{"level":"warn","ts":"2026-04-06T17:08:43.634241Z","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-06T17:08:43.634351Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-06T17:08:43.63443Z","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-06T17:08:43.634451Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-04-06T17:08:43.634481Z","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-06T17:08:43.635604Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-04-06T17:08:43.635706Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-06T17:08:43.640723Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.570042ms"} {"level":"info","ts":"2026-04-06T17:08:43.647614Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-04-06T17:08:43.647744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-04-06T17:08:43.647793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-04-06T17:08:43.647806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-06T17:08:43.647819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-04-06T17:08:43.647872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-04-06T17:08:43.654602Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-06T17:08:43.658336Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-06T17:08:43.660021Z","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-06T17:08:43.662851Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-06T17:08:43.663167Z","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-06T17:08:43.663249Z","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-06T17:08:43.663262Z","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-06T17:08:43.663385Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-06T17:08:43.664387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-04-06T17:08:43.664584Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-04-06T17:08:43.668599Z","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-06T17:08:43.668748Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-04-06T17:08:43.668851Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-04-06T17:08:43.668997Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-06T17:08:43.669047Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-06T17:08:44.54909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-04-06T17:08:44.549196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-06T17:08:44.549223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-04-06T17:08:44.549243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-04-06T17:08:44.549254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-04-06T17:08:44.549277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-04-06T17:08:44.54929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-04-06T17:08:44.550579Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-04-06T17:08:44.550685Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-06T17:08:44.550636Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T17:08:44.550654Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-06T17:08:44.550999Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-06T17:08:44.551094Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-06T17:08:44.551792Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T17:08:44.551974Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T17:08:44.552017Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T17:08:44.552943Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-04-06T17:08:44.554943Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-06T17:08:47.530763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.282008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:08:47.530964Z","caller":"traceutil/trace.go:171","msg":"trace[385007084] range","detail":"{range_begin:/registry/clusterrolebindings/system:kube-controller-manager; range_end:; response_count:0; response_revision:145; }","duration":"235.530217ms","start":"2026-04-06T17:08:47.295409Z","end":"2026-04-06T17:08:47.530939Z","steps":["trace[385007084] 'range keys from in-memory index tree' (duration: 235.209816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:48.461277Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125953864015282545,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-06T17:08:48.539761Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.004858892s","expected-duration":"1s"} {"level":"info","ts":"2026-04-06T17:08:48.540115Z","caller":"traceutil/trace.go:171","msg":"trace[1438587667] linearizableReadLoop","detail":"{readStateIndex:149; appliedIndex:148; }","duration":"579.563352ms","start":"2026-04-06T17:08:47.960523Z","end":"2026-04-06T17:08:48.540087Z","steps":["trace[1438587667] 'read index received' (duration: 579.426587ms)","trace[1438587667] 'applied index is now lower than readState.Index' (duration: 135.875µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:08:48.540203Z","caller":"traceutil/trace.go:171","msg":"trace[63476713] transaction","detail":"{read_only:false; response_revision:146; number_of_response:1; }","duration":"1.005431712s","start":"2026-04-06T17:08:47.534752Z","end":"2026-04-06T17:08:48.540183Z","steps":["trace[63476713] 'process raft request' (duration: 1.005207684s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:48.540306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.765329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:08:48.540369Z","caller":"traceutil/trace.go:171","msg":"trace[599175459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:146; }","duration":"579.872323ms","start":"2026-04-06T17:08:47.96047Z","end":"2026-04-06T17:08:48.540342Z","steps":["trace[599175459] 'agreement among raft nodes before linearized reading' (duration: 579.757609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:48.540402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:08:47.960447Z","time spent":"579.946746ms","remote":"127.0.0.1:57032","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-06T17:08:48.540501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.013177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-06T17:08:48.540521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.022617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-06T17:08:48.54061Z","caller":"traceutil/trace.go:171","msg":"trace[1063465296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:146; }","duration":"557.184402ms","start":"2026-04-06T17:08:47.9834Z","end":"2026-04-06T17:08:48.540584Z","steps":["trace[1063465296] 'agreement among raft nodes before linearized reading' (duration: 556.923733ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:08:48.540618Z","caller":"traceutil/trace.go:171","msg":"trace[449900589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:146; }","duration":"557.165582ms","start":"2026-04-06T17:08:47.983429Z","end":"2026-04-06T17:08:48.540595Z","steps":["trace[449900589] 'agreement among raft nodes before linearized reading' (duration: 556.950174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:48.540664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:08:47.983379Z","time spent":"557.274046ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-06T17:08:48.540667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:08:47.983408Z","time spent":"557.248265ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-06T17:08:48.540725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:08:47.534687Z","time spent":"1.005563108s","remote":"127.0.0.1:57396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":730,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T17:08:49.869729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.981879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:08:49.869936Z","caller":"traceutil/trace.go:171","msg":"trace[643314077] transaction","detail":"{read_only:false; response_revision:147; number_of_response:1; }","duration":"1.324511257s","start":"2026-04-06T17:08:48.545392Z","end":"2026-04-06T17:08:49.869903Z","steps":["trace[643314077] 'process raft request' (duration: 456.920626ms)","trace[643314077] 'compare' (duration: 866.851195ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:08:49.87014Z","caller":"traceutil/trace.go:171","msg":"trace[2108657221] transaction","detail":"{read_only:false; response_revision:148; number_of_response:1; }","duration":"1.323861214s","start":"2026-04-06T17:08:48.546257Z","end":"2026-04-06T17:08:49.870118Z","steps":["trace[2108657221] 'process raft request' (duration: 1.323579574s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:49.870273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:08:48.546239Z","time spent":"1.323963117s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:08:49.870269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:08:48.545372Z","time spent":"1.324682653s","remote":"127.0.0.1:57316","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-06T17:08:49.870491Z","caller":"traceutil/trace.go:171","msg":"trace[1947154066] transaction","detail":"{read_only:false; response_revision:149; number_of_response:1; }","duration":"1.323062335s","start":"2026-04-06T17:08:48.547401Z","end":"2026-04-06T17:08:49.870463Z","steps":["trace[1947154066] 'process raft request' (duration: 1.3226304s)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:08:49.870577Z","caller":"traceutil/trace.go:171","msg":"trace[1067907607] transaction","detail":"{read_only:false; response_revision:150; number_of_response:1; }","duration":"948.019867ms","start":"2026-04-06T17:08:48.922546Z","end":"2026-04-06T17:08:49.870566Z","steps":["trace[1067907607] 'process raft request' (duration: 947.876252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:49.870627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:08:48.922526Z","time spent":"948.08042ms","remote":"127.0.0.1:57242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T17:08:49.870626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:08:48.547382Z","time spent":"1.323164629s","remote":"127.0.0.1:57396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":659,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T17:08:49.870502Z","caller":"traceutil/trace.go:171","msg":"trace[971677229] linearizableReadLoop","detail":"{readStateIndex:153; appliedIndex:149; }","duration":"909.498619ms","start":"2026-04-06T17:08:48.960986Z","end":"2026-04-06T17:08:49.870485Z","steps":["trace[971677229] 'read index received' (duration: 41.304967ms)","trace[971677229] 'applied index is now lower than readState.Index' (duration: 868.192872ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:08:49.870762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.780218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:08:49.870824Z","caller":"traceutil/trace.go:171","msg":"trace[643620316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:150; }","duration":"909.865571ms","start":"2026-04-06T17:08:48.960932Z","end":"2026-04-06T17:08:49.870797Z","steps":["trace[643620316] 'agreement among raft nodes before linearized reading' (duration: 909.602152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:49.870864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.170591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:08:49.870948Z","caller":"traceutil/trace.go:171","msg":"trace[254629871] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:150; }","duration":"720.297176ms","start":"2026-04-06T17:08:49.150626Z","end":"2026-04-06T17:08:49.870923Z","steps":["trace[254629871] 'agreement among raft nodes before linearized reading' (duration: 720.13589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:49.870922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:08:48.960907Z","time spent":"910.004326ms","remote":"127.0.0.1:57032","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-06T17:08:49.871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:08:49.150571Z","time spent":"720.419ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-06T17:08:50.237004Z","caller":"traceutil/trace.go:171","msg":"trace[1935082180] linearizableReadLoop","detail":"{readStateIndex:154; appliedIndex:153; }","duration":"362.90021ms","start":"2026-04-06T17:08:49.874078Z","end":"2026-04-06T17:08:50.236979Z","steps":["trace[1935082180] 'read index received' (duration: 355.185108ms)","trace[1935082180] 'applied index is now lower than readState.Index' (duration: 7.713622ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:08:50.237226Z","caller":"traceutil/trace.go:171","msg":"trace[2144429628] transaction","detail":"{read_only:false; response_revision:151; number_of_response:1; }","duration":"363.911295ms","start":"2026-04-06T17:08:49.873272Z","end":"2026-04-06T17:08:50.237183Z","steps":["trace[2144429628] 'process raft request' (duration: 356.008456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:50.23732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:08:49.873254Z","time spent":"364.006969ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T17:08:50.237415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.745596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:08:50.237578Z","caller":"traceutil/trace.go:171","msg":"trace[1967351266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:151; }","duration":"275.960613ms","start":"2026-04-06T17:08:49.96156Z","end":"2026-04-06T17:08:50.237521Z","steps":["trace[1967351266] 'agreement among raft nodes before linearized reading' (duration: 275.677863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:50.237729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.638896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:08:50.237779Z","caller":"traceutil/trace.go:171","msg":"trace[931799782] range","detail":"{range_begin:/registry/clusterrolebindings/system:kube-scheduler; range_end:; response_count:0; response_revision:151; }","duration":"363.726689ms","start":"2026-04-06T17:08:49.87404Z","end":"2026-04-06T17:08:50.237766Z","steps":["trace[931799782] 'agreement among raft nodes before linearized reading' (duration: 363.629585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:50.237864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:08:49.874024Z","time spent":"363.829163ms","remote":"127.0.0.1:57396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/system:kube-scheduler\" "} {"level":"info","ts":"2026-04-06T17:08:55.485516Z","caller":"traceutil/trace.go:171","msg":"trace[23329215] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"128.266724ms","start":"2026-04-06T17:08:55.357217Z","end":"2026-04-06T17:08:55.485483Z","steps":["trace[23329215] 'process raft request' (duration: 86.955397ms)","trace[23329215] 'compare' (duration: 41.075509ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:08:55.898227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.750801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:08:55.898558Z","caller":"traceutil/trace.go:171","msg":"trace[252297246] linearizableReadLoop","detail":"{readStateIndex:317; appliedIndex:316; }","duration":"145.039915ms","start":"2026-04-06T17:08:55.753502Z","end":"2026-04-06T17:08:55.898542Z","steps":["trace[252297246] 'read index received' (duration: 36.922552ms)","trace[252297246] 'applied index is now lower than readState.Index' (duration: 108.114563ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:08:55.898501Z","caller":"traceutil/trace.go:171","msg":"trace[1342572421] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"148.774678ms","start":"2026-04-06T17:08:55.749694Z","end":"2026-04-06T17:08:55.898469Z","steps":["trace[1342572421] 'process raft request' (duration: 40.718346ms)","trace[1342572421] 'compare' (duration: 107.586935ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:08:55.898667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.283783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6960"} {"level":"info","ts":"2026-04-06T17:08:55.898689Z","caller":"traceutil/trace.go:171","msg":"trace[1846591733] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:306; }","duration":"179.337545ms","start":"2026-04-06T17:08:55.719344Z","end":"2026-04-06T17:08:55.898682Z","steps":["trace[1846591733] 'agreement among raft nodes before linearized reading' (duration: 179.272403ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:08:55.898682Z","caller":"traceutil/trace.go:171","msg":"trace[2125627924] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"141.060175ms","start":"2026-04-06T17:08:55.757589Z","end":"2026-04-06T17:08:55.89865Z","steps":["trace[2125627924] 'process raft request' (duration: 140.9041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:55.899109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.528527ms","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-06T17:08:55.899143Z","caller":"traceutil/trace.go:171","msg":"trace[2001964540] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:307; }","duration":"142.583699ms","start":"2026-04-06T17:08:55.756551Z","end":"2026-04-06T17:08:55.899135Z","steps":["trace[2001964540] 'agreement among raft nodes before linearized reading' (duration: 142.524777ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:08:55.899505Z","caller":"traceutil/trace.go:171","msg":"trace[963789241] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"139.991158ms","start":"2026-04-06T17:08:55.759499Z","end":"2026-04-06T17:08:55.89949Z","steps":["trace[963789241] 'process raft request' (duration: 139.535522ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:08:56.179651Z","caller":"traceutil/trace.go:171","msg":"trace[1443905699] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:319; }","duration":"274.380198ms","start":"2026-04-06T17:08:55.90525Z","end":"2026-04-06T17:08:56.17963Z","steps":["trace[1443905699] 'read index received' (duration: 55.684174ms)","trace[1443905699] 'applied index is now lower than readState.Index' (duration: 218.695014ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:08:56.179715Z","caller":"traceutil/trace.go:171","msg":"trace[2134498387] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"276.283585ms","start":"2026-04-06T17:08:55.903404Z","end":"2026-04-06T17:08:56.179687Z","steps":["trace[2134498387] 'process raft request' (duration: 257.328776ms)","trace[2134498387] 'compare' (duration: 18.724731ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:08:56.179742Z","caller":"traceutil/trace.go:171","msg":"trace[2029155896] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"272.241992ms","start":"2026-04-06T17:08:55.90749Z","end":"2026-04-06T17:08:56.179732Z","steps":["trace[2029155896] 'process raft request' (duration: 272.089477ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:08:56.179696Z","caller":"traceutil/trace.go:171","msg":"trace[1448975854] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"275.215457ms","start":"2026-04-06T17:08:55.904451Z","end":"2026-04-06T17:08:56.179666Z","steps":["trace[1448975854] 'process raft request' (duration: 275.090223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:56.17986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.591755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-04-06T17:08:56.179937Z","caller":"traceutil/trace.go:171","msg":"trace[713179687] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:311; }","duration":"274.699269ms","start":"2026-04-06T17:08:55.905224Z","end":"2026-04-06T17:08:56.179923Z","steps":["trace[713179687] 'agreement among raft nodes before linearized reading' (duration: 274.529023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:56.180043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.635076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-06T17:08:56.180073Z","caller":"traceutil/trace.go:171","msg":"trace[1860419082] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:311; }","duration":"122.706158ms","start":"2026-04-06T17:08:56.05736Z","end":"2026-04-06T17:08:56.180066Z","steps":["trace[1860419082] 'agreement among raft nodes before linearized reading' (duration: 122.665337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:56.180169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.018913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-06T17:08:56.180193Z","caller":"traceutil/trace.go:171","msg":"trace[1151623020] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:311; }","duration":"173.071954ms","start":"2026-04-06T17:08:56.007116Z","end":"2026-04-06T17:08:56.180188Z","steps":["trace[1151623020] 'agreement among raft nodes before linearized reading' (duration: 173.029623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:56.180291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.056253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-06T17:08:56.180314Z","caller":"traceutil/trace.go:171","msg":"trace[1119112549] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:311; }","duration":"222.145146ms","start":"2026-04-06T17:08:55.958163Z","end":"2026-04-06T17:08:56.180308Z","steps":["trace[1119112549] 'agreement among raft nodes before linearized reading' (duration: 222.085193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:56.17987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.02185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-06T17:08:56.180542Z","caller":"traceutil/trace.go:171","msg":"trace[1025287615] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:311; }","duration":"273.708384ms","start":"2026-04-06T17:08:55.906826Z","end":"2026-04-06T17:08:56.180535Z","steps":["trace[1025287615] 'agreement among raft nodes before linearized reading' (duration: 273.018259ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:08:57.495905Z","caller":"traceutil/trace.go:171","msg":"trace[152569431] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"125.855319ms","start":"2026-04-06T17:08:57.370017Z","end":"2026-04-06T17:08:57.495872Z","steps":["trace[152569431] 'process raft request' (duration: 125.684233ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:09:23.416213Z","caller":"traceutil/trace.go:171","msg":"trace[1655807070] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"154.893359ms","start":"2026-04-06T17:09:23.261293Z","end":"2026-04-06T17:09:23.416186Z","steps":["trace[1655807070] 'process raft request' (duration: 154.192897ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:09:23.427236Z","caller":"traceutil/trace.go:171","msg":"trace[783276452] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"158.07226ms","start":"2026-04-06T17:09:23.269117Z","end":"2026-04-06T17:09:23.42719Z","steps":["trace[783276452] 'process raft request' (duration: 157.937896ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:09:23.427503Z","caller":"traceutil/trace.go:171","msg":"trace[1277132027] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"139.727929ms","start":"2026-04-06T17:09:23.287718Z","end":"2026-04-06T17:09:23.427446Z","steps":["trace[1277132027] 'process raft request' (duration: 139.387908ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:09:23.427616Z","caller":"traceutil/trace.go:171","msg":"trace[2123112934] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"130.222049ms","start":"2026-04-06T17:09:23.297382Z","end":"2026-04-06T17:09:23.427604Z","steps":["trace[2123112934] 'process raft request' (duration: 129.860498ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:09:23.427777Z","caller":"traceutil/trace.go:171","msg":"trace[2122741954] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"111.056742ms","start":"2026-04-06T17:09:23.316709Z","end":"2026-04-06T17:09:23.427766Z","steps":["trace[2122741954] 'process raft request' (duration: 110.826465ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:09:23.427892Z","caller":"traceutil/trace.go:171","msg":"trace[841652891] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:506; }","duration":"148.444775ms","start":"2026-04-06T17:09:23.279419Z","end":"2026-04-06T17:09:23.427864Z","steps":["trace[841652891] 'read index received' (duration: 136.112075ms)","trace[841652891] 'applied index is now lower than readState.Index' (duration: 12.33136ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:09:23.427964Z","caller":"traceutil/trace.go:171","msg":"trace[592928920] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"124.842348ms","start":"2026-04-06T17:09:23.30311Z","end":"2026-04-06T17:09:23.427952Z","steps":["trace[592928920] 'process raft request' (duration: 124.367803ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:09:23.428033Z","caller":"traceutil/trace.go:171","msg":"trace[548565496] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"111.120964ms","start":"2026-04-06T17:09:23.316869Z","end":"2026-04-06T17:09:23.42799Z","steps":["trace[548565496] 'process raft request' (duration: 110.718292ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:09:23.427961Z","caller":"traceutil/trace.go:171","msg":"trace[425683980] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"104.797444ms","start":"2026-04-06T17:09:23.323097Z","end":"2026-04-06T17:09:23.427895Z","steps":["trace[425683980] 'process raft request' (duration: 104.563617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:23.428261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.818957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:09:23.428339Z","caller":"traceutil/trace.go:171","msg":"trace[1556813497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:499; }","duration":"148.943411ms","start":"2026-04-06T17:09:23.279383Z","end":"2026-04-06T17:09:23.428326Z","steps":["trace[1556813497] 'agreement among raft nodes before linearized reading' (duration: 148.770445ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:09:23.428549Z","caller":"traceutil/trace.go:171","msg":"trace[29228369] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"166.266769ms","start":"2026-04-06T17:09:23.26227Z","end":"2026-04-06T17:09:23.428537Z","steps":["trace[29228369] 'process raft request' (duration: 164.643257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:24.242256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.419816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:09:24.242434Z","caller":"traceutil/trace.go:171","msg":"trace[300949315] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"817.382892ms","start":"2026-04-06T17:09:23.425033Z","end":"2026-04-06T17:09:24.242416Z","steps":["trace[300949315] 'process raft request' (duration: 817.31843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:24.242577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:23.425023Z","time spent":"817.490205ms","remote":"127.0.0.1:57592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T17:09:24.242561Z","caller":"traceutil/trace.go:171","msg":"trace[2057732195] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"817.687012ms","start":"2026-04-06T17:09:23.4248Z","end":"2026-04-06T17:09:24.242487Z","steps":["trace[2057732195] 'process raft request' (duration: 163.966146ms)","trace[2057732195] 'compare' (duration: 653.277492ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:09:24.242718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:23.424766Z","time spent":"817.873908ms","remote":"127.0.0.1:57592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T17:09:24.26616Z","caller":"traceutil/trace.go:171","msg":"trace[2134097571] transaction","detail":"{read_only:false; number_of_response:1; response_revision:502; }","duration":"828.102912ms","start":"2026-04-06T17:09:23.43801Z","end":"2026-04-06T17:09:24.266113Z","steps":["trace[2134097571] 'process raft request' (duration: 827.938186ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:09:24.266155Z","caller":"traceutil/trace.go:171","msg":"trace[844854088] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"836.865828ms","start":"2026-04-06T17:09:23.429238Z","end":"2026-04-06T17:09:24.266104Z","steps":["trace[844854088] 'process raft request' (duration: 836.561789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:24.266326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:23.437984Z","time spent":"828.253176ms","remote":"127.0.0.1:57070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10528,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:09:24.266393Z","caller":"traceutil/trace.go:171","msg":"trace[228553364] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"821.698229ms","start":"2026-04-06T17:09:23.444655Z","end":"2026-04-06T17:09:24.266353Z","steps":["trace[228553364] 'process raft request' (duration: 821.349128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:24.266426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:23.429223Z","time spent":"837.061065ms","remote":"127.0.0.1:57070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10479,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:09:24.266462Z","caller":"traceutil/trace.go:171","msg":"trace[1834718328] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"818.682374ms","start":"2026-04-06T17:09:23.447715Z","end":"2026-04-06T17:09:24.266398Z","steps":["trace[1834718328] 'process raft request' (duration: 818.542179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:24.26788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:23.447694Z","time spent":"819.926492ms","remote":"127.0.0.1:57070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:09:24.268286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:23.444644Z","time spent":"821.799782ms","remote":"127.0.0.1:57592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T17:09:24.268934Z","caller":"traceutil/trace.go:171","msg":"trace[1574227283] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"818.060464ms","start":"2026-04-06T17:09:23.448434Z","end":"2026-04-06T17:09:24.266494Z","steps":["trace[1574227283] 'process raft request' (duration: 817.897088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:24.269013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:23.448426Z","time spent":"820.555912ms","remote":"127.0.0.1:57592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T17:09:24.266512Z","caller":"traceutil/trace.go:171","msg":"trace[808356684] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"809.079959ms","start":"2026-04-06T17:09:23.45741Z","end":"2026-04-06T17:09:24.26649Z","steps":["trace[808356684] 'process raft request' (duration: 808.949925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:24.269859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:23.457391Z","time spent":"812.377774ms","remote":"127.0.0.1:57592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T17:09:24.271133Z","caller":"traceutil/trace.go:171","msg":"trace[627097592] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"811.782245ms","start":"2026-04-06T17:09:23.459194Z","end":"2026-04-06T17:09:24.270976Z","steps":["trace[627097592] 'process raft request' (duration: 807.219791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:24.271233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:23.459173Z","time spent":"811.993831ms","remote":"127.0.0.1:57070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:09:24.279722Z","caller":"traceutil/trace.go:171","msg":"trace[2030191465] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:517; }","duration":"849.275801ms","start":"2026-04-06T17:09:23.43039Z","end":"2026-04-06T17:09:24.279666Z","steps":["trace[2030191465] 'read index received' (duration: 158.432331ms)","trace[2030191465] 'applied index is now lower than readState.Index' (duration: 690.8425ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:09:24.279912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.490338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:09:24.279969Z","caller":"traceutil/trace.go:171","msg":"trace[9651525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"849.667513ms","start":"2026-04-06T17:09:23.43029Z","end":"2026-04-06T17:09:24.279958Z","steps":["trace[9651525] 'agreement among raft nodes before linearized reading' (duration: 849.508948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:24.280022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:23.430278Z","time spent":"849.735965ms","remote":"127.0.0.1:57034","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-06T17:09:24.280314Z","caller":"traceutil/trace.go:171","msg":"trace[1199573098] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"820.123329ms","start":"2026-04-06T17:09:23.460181Z","end":"2026-04-06T17:09:24.280304Z","steps":["trace[1199573098] 'process raft request' (duration: 812.944802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:24.280411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:23.460167Z","time spent":"820.17427ms","remote":"127.0.0.1:57070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:09:24.280485Z","caller":"traceutil/trace.go:171","msg":"trace[842082505] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"816.547826ms","start":"2026-04-06T17:09:23.463928Z","end":"2026-04-06T17:09:24.280476Z","steps":["trace[842082505] 'process raft request' (duration: 815.538014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:24.280519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:23.463915Z","time spent":"816.583756ms","remote":"127.0.0.1:57070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:09:24.2806Z","caller":"traceutil/trace.go:171","msg":"trace[575072622] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"795.065216ms","start":"2026-04-06T17:09:23.485527Z","end":"2026-04-06T17:09:24.280592Z","steps":["trace[575072622] 'process raft request' (duration: 794.062115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:24.28066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:23.485498Z","time spent":"795.122418ms","remote":"127.0.0.1:57070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T17:09:26.587196Z","caller":"traceutil/trace.go:171","msg":"trace[1692352277] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:590; }","duration":"185.931974ms","start":"2026-04-06T17:09:26.401238Z","end":"2026-04-06T17:09:26.58717Z","steps":["trace[1692352277] 'read index received' (duration: 185.721687ms)","trace[1692352277] 'applied index is now lower than readState.Index' (duration: 208.977µs)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:09:26.587473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.860667ms","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-06T17:09:26.587327Z","caller":"traceutil/trace.go:171","msg":"trace[941809341] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"187.652298ms","start":"2026-04-06T17:09:26.399634Z","end":"2026-04-06T17:09:26.587286Z","steps":["trace[941809341] 'process raft request' (duration: 187.377859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:26.58759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.326716ms","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-06T17:09:26.58783Z","caller":"traceutil/trace.go:171","msg":"trace[411620020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:568; }","duration":"186.604355ms","start":"2026-04-06T17:09:26.401205Z","end":"2026-04-06T17:09:26.58781Z","steps":["trace[411620020] 'agreement among raft nodes before linearized reading' (duration: 186.098789ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:09:26.587555Z","caller":"traceutil/trace.go:171","msg":"trace[1333191992] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:568; }","duration":"182.983611ms","start":"2026-04-06T17:09:26.404554Z","end":"2026-04-06T17:09:26.587538Z","steps":["trace[1333191992] 'agreement among raft nodes before linearized reading' (duration: 182.794985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:26.588122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.924836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:09:26.58819Z","caller":"traceutil/trace.go:171","msg":"trace[2093580804] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:568; }","duration":"120.005709ms","start":"2026-04-06T17:09:26.468168Z","end":"2026-04-06T17:09:26.588174Z","steps":["trace[2093580804] 'agreement among raft nodes before linearized reading' (duration: 119.912936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:26.588392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.222536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:09:26.588454Z","caller":"traceutil/trace.go:171","msg":"trace[1617876390] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:568; }","duration":"120.315078ms","start":"2026-04-06T17:09:26.468122Z","end":"2026-04-06T17:09:26.588437Z","steps":["trace[1617876390] 'agreement among raft nodes before linearized reading' (duration: 120.221305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:26.588635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.953928ms","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-06T17:09:26.588703Z","caller":"traceutil/trace.go:171","msg":"trace[1358969460] 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:568; }","duration":"168.053371ms","start":"2026-04-06T17:09:26.420633Z","end":"2026-04-06T17:09:26.588686Z","steps":["trace[1358969460] 'agreement among raft nodes before linearized reading' (duration: 167.958118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:26.588922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.876747ms","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-06T17:09:26.588985Z","caller":"traceutil/trace.go:171","msg":"trace[1686651085] 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:568; }","duration":"168.9686ms","start":"2026-04-06T17:09:26.420001Z","end":"2026-04-06T17:09:26.588969Z","steps":["trace[1686651085] 'agreement among raft nodes before linearized reading' (duration: 168.877087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:26.589035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.837206ms","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-06T17:09:26.58914Z","caller":"traceutil/trace.go:171","msg":"trace[394670373] 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:568; }","duration":"169.047832ms","start":"2026-04-06T17:09:26.420069Z","end":"2026-04-06T17:09:26.589117Z","steps":["trace[394670373] 'agreement among raft nodes before linearized reading' (duration: 168.898148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:26.895267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.431778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:09:26.895492Z","caller":"traceutil/trace.go:171","msg":"trace[510691290] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"300.599943ms","start":"2026-04-06T17:09:26.59488Z","end":"2026-04-06T17:09:26.89548Z","steps":["trace[510691290] 'process raft request' (duration: 300.470529ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:09:26.895507Z","caller":"traceutil/trace.go:171","msg":"trace[356523660] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:591; }","duration":"248.540435ms","start":"2026-04-06T17:09:26.646952Z","end":"2026-04-06T17:09:26.895492Z","steps":["trace[356523660] 'read index received' (duration: 109.817527ms)","trace[356523660] 'applied index is now lower than readState.Index' (duration: 138.722088ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:09:26.895536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:26.594865Z","time spent":"300.653606ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:09:26.895633Z","caller":"traceutil/trace.go:171","msg":"trace[500697710] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"301.10989ms","start":"2026-04-06T17:09:26.594503Z","end":"2026-04-06T17:09:26.895613Z","steps":["trace[500697710] 'process raft request' (duration: 162.257788ms)","trace[500697710] 'compare' (duration: 138.348736ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:09:26.895666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:26.594488Z","time spent":"301.162602ms","remote":"127.0.0.1:57316","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-06T17:09:26.895698Z","caller":"traceutil/trace.go:171","msg":"trace[2067030871] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"299.20163ms","start":"2026-04-06T17:09:26.596487Z","end":"2026-04-06T17:09:26.895688Z","steps":["trace[2067030871] 'process raft request' (duration: 298.936702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:26.895827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.883346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:09:26.895865Z","caller":"traceutil/trace.go:171","msg":"trace[1157092647] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:571; }","duration":"248.941588ms","start":"2026-04-06T17:09:26.646903Z","end":"2026-04-06T17:09:26.895845Z","steps":["trace[1157092647] 'agreement among raft nodes before linearized reading' (duration: 248.895466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:26.895989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.829954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:09:26.896025Z","caller":"traceutil/trace.go:171","msg":"trace[1449825558] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:571; }","duration":"248.885405ms","start":"2026-04-06T17:09:26.647134Z","end":"2026-04-06T17:09:26.89602Z","steps":["trace[1449825558] 'agreement among raft nodes before linearized reading' (duration: 248.839244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:26.896274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.58045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:09:26.896303Z","caller":"traceutil/trace.go:171","msg":"trace[1015347938] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:571; }","duration":"123.638932ms","start":"2026-04-06T17:09:26.772652Z","end":"2026-04-06T17:09:26.896291Z","steps":["trace[1015347938] 'agreement among raft nodes before linearized reading' (duration: 123.592921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:26.896396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.235101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:09:26.896432Z","caller":"traceutil/trace.go:171","msg":"trace[1690107412] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:571; }","duration":"124.295493ms","start":"2026-04-06T17:09:26.772131Z","end":"2026-04-06T17:09:26.896427Z","steps":["trace[1690107412] 'agreement among raft nodes before linearized reading' (duration: 124.250412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:26.896523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.384836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:09:26.896543Z","caller":"traceutil/trace.go:171","msg":"trace[1923649222] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:571; }","duration":"124.430138ms","start":"2026-04-06T17:09:26.772108Z","end":"2026-04-06T17:09:26.896538Z","steps":["trace[1923649222] 'agreement among raft nodes before linearized reading' (duration: 124.400177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:26.896621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.204078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:09:26.896644Z","caller":"traceutil/trace.go:171","msg":"trace[1081639913] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:571; }","duration":"163.224799ms","start":"2026-04-06T17:09:26.733411Z","end":"2026-04-06T17:09:26.896636Z","steps":["trace[1081639913] 'agreement among raft nodes before linearized reading' (duration: 163.195988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:26.89675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.370683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:09:26.896769Z","caller":"traceutil/trace.go:171","msg":"trace[434567752] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:571; }","duration":"163.412835ms","start":"2026-04-06T17:09:26.733352Z","end":"2026-04-06T17:09:26.896765Z","steps":["trace[434567752] 'agreement among raft nodes before linearized reading' (duration: 163.366413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:26.896851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.12156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:09:26.896871Z","caller":"traceutil/trace.go:171","msg":"trace[1016456728] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:571; }","duration":"247.156071ms","start":"2026-04-06T17:09:26.64971Z","end":"2026-04-06T17:09:26.896866Z","steps":["trace[1016456728] 'agreement among raft nodes before linearized reading' (duration: 247.12488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:27.207024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.408654ms","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":"warn","ts":"2026-04-06T17:09:27.207098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.997201ms","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-06T17:09:27.207114Z","caller":"traceutil/trace.go:171","msg":"trace[836444126] 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:571; }","duration":"135.546567ms","start":"2026-04-06T17:09:27.071549Z","end":"2026-04-06T17:09:27.207095Z","steps":["trace[836444126] 'count revisions from in-memory index tree' (duration: 135.344871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:27.207109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.712323ms","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-06T17:09:27.207144Z","caller":"traceutil/trace.go:171","msg":"trace[1568025075] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:571; }","duration":"149.080533ms","start":"2026-04-06T17:09:27.058048Z","end":"2026-04-06T17:09:27.207128Z","steps":["trace[1568025075] 'count revisions from in-memory index tree' (duration: 148.876867ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:09:27.207186Z","caller":"traceutil/trace.go:171","msg":"trace[403585093] 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:571; }","duration":"135.825707ms","start":"2026-04-06T17:09:27.071343Z","end":"2026-04-06T17:09:27.207168Z","steps":["trace[403585093] 'range keys from in-memory index tree' (duration: 135.64111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:27.207046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.014461ms","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-06T17:09:27.207358Z","caller":"traceutil/trace.go:171","msg":"trace[960437499] 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:571; }","duration":"135.371242ms","start":"2026-04-06T17:09:27.071974Z","end":"2026-04-06T17:09:27.207345Z","steps":["trace[960437499] 'range keys from in-memory index tree' (duration: 134.926238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:27.207361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.421054ms","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-06T17:09:27.207391Z","caller":"traceutil/trace.go:171","msg":"trace[1665274352] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:571; }","duration":"149.467915ms","start":"2026-04-06T17:09:27.057913Z","end":"2026-04-06T17:09:27.207381Z","steps":["trace[1665274352] 'range keys from in-memory index tree' (duration: 149.369182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:27.207046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.620708ms","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":"info","ts":"2026-04-06T17:09:27.207443Z","caller":"traceutil/trace.go:171","msg":"trace[2098068404] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:571; }","duration":"148.049981ms","start":"2026-04-06T17:09:27.059386Z","end":"2026-04-06T17:09:27.207436Z","steps":["trace[2098068404] 'range keys from in-memory index tree' (duration: 147.578887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:27.207448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.283159ms","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-06T17:09:27.20757Z","caller":"traceutil/trace.go:171","msg":"trace[1345764957] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:571; }","duration":"162.426044ms","start":"2026-04-06T17:09:27.045128Z","end":"2026-04-06T17:09:27.207554Z","steps":["trace[1345764957] 'range keys from in-memory index tree' (duration: 162.187326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:27.207174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.960919ms","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-06T17:09:27.207774Z","caller":"traceutil/trace.go:171","msg":"trace[1770223865] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:571; }","duration":"162.573178ms","start":"2026-04-06T17:09:27.04518Z","end":"2026-04-06T17:09:27.207754Z","steps":["trace[1770223865] 'count revisions from in-memory index tree' (duration: 161.896897ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:09:28.448016Z","caller":"traceutil/trace.go:171","msg":"trace[490840389] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"170.955352ms","start":"2026-04-06T17:09:28.277033Z","end":"2026-04-06T17:09:28.447988Z","steps":["trace[490840389] 'read index received' (duration: 170.225589ms)","trace[490840389] 'applied index is now lower than readState.Index' (duration: 728.953µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:09:28.448059Z","caller":"traceutil/trace.go:171","msg":"trace[979742989] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"243.247518ms","start":"2026-04-06T17:09:28.204767Z","end":"2026-04-06T17:09:28.448014Z","steps":["trace[979742989] 'process raft request' (duration: 242.825005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:28.448186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.114287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:09:28.44823Z","caller":"traceutil/trace.go:171","msg":"trace[1640270842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"171.22696ms","start":"2026-04-06T17:09:28.27699Z","end":"2026-04-06T17:09:28.448217Z","steps":["trace[1640270842] 'agreement among raft nodes before linearized reading' (duration: 171.106477ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:09:31.223074Z","caller":"traceutil/trace.go:171","msg":"trace[1437373018] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"152.014396ms","start":"2026-04-06T17:09:31.071034Z","end":"2026-04-06T17:09:31.223049Z","steps":["trace[1437373018] 'process raft request' (duration: 151.855521ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:09:31.66968Z","caller":"traceutil/trace.go:171","msg":"trace[480583316] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"391.874417ms","start":"2026-04-06T17:09:31.277771Z","end":"2026-04-06T17:09:31.669645Z","steps":["trace[480583316] 'read index received' (duration: 390.955939ms)","trace[480583316] 'applied index is now lower than readState.Index' (duration: 916.748µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:09:31.669775Z","caller":"traceutil/trace.go:171","msg":"trace[2091066032] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"528.999225ms","start":"2026-04-06T17:09:31.140703Z","end":"2026-04-06T17:09:31.669703Z","steps":["trace[2091066032] 'process raft request' (duration: 528.285343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:31.669922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.505262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6l927\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-06T17:09:31.669966Z","caller":"traceutil/trace.go:171","msg":"trace[622915361] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6l927; range_end:; response_count:1; response_revision:597; }","duration":"240.577444ms","start":"2026-04-06T17:09:31.429374Z","end":"2026-04-06T17:09:31.669952Z","steps":["trace[622915361] 'agreement among raft nodes before linearized reading' (duration: 240.516732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:31.669861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.066583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-06T17:09:31.670074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:31.140673Z","time spent":"529.249003ms","remote":"127.0.0.1:57122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:09:31.671278Z","caller":"traceutil/trace.go:171","msg":"trace[939417534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"392.397454ms","start":"2026-04-06T17:09:31.277734Z","end":"2026-04-06T17:09:31.670131Z","steps":["trace[939417534] 'agreement among raft nodes before linearized reading' (duration: 392.071114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:31.671414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:31.277712Z","time spent":"393.679674ms","remote":"127.0.0.1:57032","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-06T17:09:33.140526Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125953864015283497,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-06T17:09:33.243862Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.55733769s","expected-duration":"1s"} {"level":"info","ts":"2026-04-06T17:09:33.24451Z","caller":"traceutil/trace.go:171","msg":"trace[547263925] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.558100135s","start":"2026-04-06T17:09:31.686377Z","end":"2026-04-06T17:09:33.244477Z","steps":["trace[547263925] 'process raft request' (duration: 1.557850267s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:33.254541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:31.686348Z","time spent":"1.568045708s","remote":"127.0.0.1:57242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:09:33.416221Z","caller":"traceutil/trace.go:171","msg":"trace[289929055] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:621; }","duration":"776.294591ms","start":"2026-04-06T17:09:32.639899Z","end":"2026-04-06T17:09:33.416194Z","steps":["trace[289929055] 'read index received' (duration: 604.256494ms)","trace[289929055] 'applied index is now lower than readState.Index' (duration: 172.036947ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:09:33.416382Z","caller":"traceutil/trace.go:171","msg":"trace[1167937404] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"1.644491744s","start":"2026-04-06T17:09:31.77182Z","end":"2026-04-06T17:09:33.416312Z","steps":["trace[1167937404] 'process raft request' (duration: 1.595979667s)","trace[1167937404] 'compare' (duration: 47.717012ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:09:33.416516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.60834ms","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-06T17:09:33.416767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:31.771761Z","time spent":"1.644944709s","remote":"127.0.0.1:57396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T17:09:33.416854Z","caller":"traceutil/trace.go:171","msg":"trace[1403794012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:600; }","duration":"776.932ms","start":"2026-04-06T17:09:32.639876Z","end":"2026-04-06T17:09:33.416808Z","steps":["trace[1403794012] 'agreement among raft nodes before linearized reading' (duration: 776.453095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:33.416944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:32.639807Z","time spent":"777.096016ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-06T17:09:33.416362Z","caller":"traceutil/trace.go:171","msg":"trace[716428275] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"513.762245ms","start":"2026-04-06T17:09:32.902537Z","end":"2026-04-06T17:09:33.416299Z","steps":["trace[716428275] 'process raft request' (duration: 513.553388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:33.417186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.239487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:09:33.417221Z","caller":"traceutil/trace.go:171","msg":"trace[530883208] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"141.272988ms","start":"2026-04-06T17:09:33.275936Z","end":"2026-04-06T17:09:33.417209Z","steps":["trace[530883208] 'agreement among raft nodes before linearized reading' (duration: 141.218986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:33.417219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.639289ms","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-06T17:09:33.417266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.12216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-06T17:09:33.417263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:32.902508Z","time spent":"514.655283ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:09:33.417306Z","caller":"traceutil/trace.go:171","msg":"trace[1586482666] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:600; }","duration":"449.198692ms","start":"2026-04-06T17:09:32.968095Z","end":"2026-04-06T17:09:33.417294Z","steps":["trace[1586482666] 'agreement among raft nodes before linearized reading' (duration: 449.039107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:33.417336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:32.968071Z","time spent":"449.258924ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-06T17:09:33.41788Z","caller":"traceutil/trace.go:171","msg":"trace[1788402145] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:600; }","duration":"464.786483ms","start":"2026-04-06T17:09:32.952498Z","end":"2026-04-06T17:09:33.417284Z","steps":["trace[1788402145] 'agreement among raft nodes before linearized reading' (duration: 464.483873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:33.417987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:32.952473Z","time spent":"465.495825ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-06T17:09:33.41828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.414652ms","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-06T17:09:33.418381Z","caller":"traceutil/trace.go:171","msg":"trace[1729964889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:600; }","duration":"452.351431ms","start":"2026-04-06T17:09:32.966001Z","end":"2026-04-06T17:09:33.418352Z","steps":["trace[1729964889] 'agreement among raft nodes before linearized reading' (duration: 451.236636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:33.418429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:32.965985Z","time spent":"452.432524ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-06T17:09:49.85743Z","caller":"traceutil/trace.go:171","msg":"trace[1072599216] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:794; }","duration":"327.911514ms","start":"2026-04-06T17:09:49.529485Z","end":"2026-04-06T17:09:49.857397Z","steps":["trace[1072599216] 'read index received' (duration: 327.713947ms)","trace[1072599216] 'applied index is now lower than readState.Index' (duration: 196.156µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:09:49.857547Z","caller":"traceutil/trace.go:171","msg":"trace[600202512] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"539.817676ms","start":"2026-04-06T17:09:49.317705Z","end":"2026-04-06T17:09:49.857523Z","steps":["trace[600202512] 'process raft request' (duration: 539.461884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:49.857702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:49.317687Z","time spent":"539.909198ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:09:49.857713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.218134ms","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-06T17:09:49.857782Z","caller":"traceutil/trace.go:171","msg":"trace[1466566878] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:767; }","duration":"328.326137ms","start":"2026-04-06T17:09:49.529439Z","end":"2026-04-06T17:09:49.857765Z","steps":["trace[1466566878] 'agreement among raft nodes before linearized reading' (duration: 328.127411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:49.857872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:49.529417Z","time spent":"328.409589ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-06T17:09:49.952641Z","caller":"traceutil/trace.go:171","msg":"trace[1152564182] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"367.631065ms","start":"2026-04-06T17:09:49.584968Z","end":"2026-04-06T17:09:49.952599Z","steps":["trace[1152564182] 'process raft request' (duration: 366.301013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:49.952743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.689832ms","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-06T17:09:49.952826Z","caller":"traceutil/trace.go:171","msg":"trace[223811305] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:768; }","duration":"418.805185ms","start":"2026-04-06T17:09:49.534Z","end":"2026-04-06T17:09:49.952805Z","steps":["trace[223811305] 'agreement among raft nodes before linearized reading' (duration: 418.525157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:49.952843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:49.584944Z","time spent":"367.793219ms","remote":"127.0.0.1:57282","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-06T17:09:49.952884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.638659ms","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-06T17:09:49.952959Z","caller":"traceutil/trace.go:171","msg":"trace[1928375775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:768; }","duration":"370.722242ms","start":"2026-04-06T17:09:49.58222Z","end":"2026-04-06T17:09:49.952942Z","steps":["trace[1928375775] 'agreement among raft nodes before linearized reading' (duration: 370.465094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:49.953004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.792955ms","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-06T17:09:49.953052Z","caller":"traceutil/trace.go:171","msg":"trace[1852346806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:768; }","duration":"370.861657ms","start":"2026-04-06T17:09:49.582179Z","end":"2026-04-06T17:09:49.953041Z","steps":["trace[1852346806] 'agreement among raft nodes before linearized reading' (duration: 370.746043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:49.953136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:49.582163Z","time spent":"370.958789ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-06T17:09:49.953008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:49.582217Z","time spent":"370.779864ms","remote":"127.0.0.1:57316","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-06T17:09:49.952887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:49.533977Z","time spent":"418.899839ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-06T17:10:24.693205Z","caller":"traceutil/trace.go:171","msg":"trace[875545743] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"273.734458ms","start":"2026-04-06T17:10:24.419435Z","end":"2026-04-06T17:10:24.69317Z","steps":["trace[875545743] 'process raft request' (duration: 273.500011ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:10:25.40464Z","caller":"traceutil/trace.go:171","msg":"trace[2051424387] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1021; }","duration":"448.997536ms","start":"2026-04-06T17:10:24.955614Z","end":"2026-04-06T17:10:25.404611Z","steps":["trace[2051424387] 'read index received' (duration: 448.846391ms)","trace[2051424387] 'applied index is now lower than readState.Index' (duration: 149.345µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:10:25.404707Z","caller":"traceutil/trace.go:171","msg":"trace[209435971] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"856.63576ms","start":"2026-04-06T17:10:24.548047Z","end":"2026-04-06T17:10:25.404683Z","steps":["trace[209435971] 'process raft request' (duration: 856.402823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:25.404816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.191312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:10:25.404865Z","caller":"traceutil/trace.go:171","msg":"trace[365935970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:985; }","duration":"449.314596ms","start":"2026-04-06T17:10:24.955536Z","end":"2026-04-06T17:10:25.40485Z","steps":["trace[365935970] 'agreement among raft nodes before linearized reading' (duration: 449.219893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:25.404884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:24.548016Z","time spent":"856.783565ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:10:25.404885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.136103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:10:25.404961Z","caller":"traceutil/trace.go:171","msg":"trace[1306416993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:985; }","duration":"127.263707ms","start":"2026-04-06T17:10:25.277679Z","end":"2026-04-06T17:10:25.404942Z","steps":["trace[1306416993] 'agreement among raft nodes before linearized reading' (duration: 127.117903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:25.404905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:24.955503Z","time spent":"449.391498ms","remote":"127.0.0.1:57032","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-06T17:10:26.596061Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125953864015284306,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-06T17:10:27.096922Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125953864015284306,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-06T17:10:27.09944Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.323672105s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-06T17:10:27.600763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.028713ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-06T17:10:27.600915Z","caller":"traceutil/trace.go:171","msg":"trace[1890565156] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1022; }","duration":"1.505470347s","start":"2026-04-06T17:10:26.09542Z","end":"2026-04-06T17:10:27.60089Z","steps":["trace[1890565156] 'read index received' (duration: 1.004219627s)","trace[1890565156] 'applied index is now lower than readState.Index' (duration: 501.24854ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:10:27.601198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.505760366s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-06T17:10:27.601265Z","caller":"traceutil/trace.go:171","msg":"trace[674901259] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:985; }","duration":"1.50586467s","start":"2026-04-06T17:10:26.095386Z","end":"2026-04-06T17:10:27.60125Z","steps":["trace[674901259] 'agreement among raft nodes before linearized reading' (duration: 1.505599091s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:27.601308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:26.095372Z","time spent":"1.505925461s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-06T17:10:27.604353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.505029534s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-06T17:10:27.60444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.459557902s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-06T17:10:27.604469Z","caller":"traceutil/trace.go:171","msg":"trace[1392512127] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:986; }","duration":"1.505199909s","start":"2026-04-06T17:10:26.099239Z","end":"2026-04-06T17:10:27.604438Z","steps":["trace[1392512127] 'agreement among raft nodes before linearized reading' (duration: 1.505014153s)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:10:27.604489Z","caller":"traceutil/trace.go:171","msg":"trace[107878327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:986; }","duration":"1.459621683s","start":"2026-04-06T17:10:26.144854Z","end":"2026-04-06T17:10:27.604476Z","steps":["trace[107878327] 'agreement among raft nodes before linearized reading' (duration: 1.459463618s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:27.604528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:26.144846Z","time spent":"1.459674736s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-06T17:10:27.60454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:26.099214Z","time spent":"1.505308453s","remote":"127.0.0.1:57570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-06T17:10:27.604564Z","caller":"traceutil/trace.go:171","msg":"trace[730927631] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"193.231763ms","start":"2026-04-06T17:10:27.411298Z","end":"2026-04-06T17:10:27.60453Z","steps":["trace[730927631] 'process raft request' (duration: 192.762918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:27.604717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.326365099s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:10:27.60475Z","caller":"traceutil/trace.go:171","msg":"trace[1213904138] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:986; }","duration":"1.326451162s","start":"2026-04-06T17:10:26.278289Z","end":"2026-04-06T17:10:27.60474Z","steps":["trace[1213904138] 'agreement among raft nodes before linearized reading' (duration: 1.32640004s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:27.604778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:26.278242Z","time spent":"1.326530534s","remote":"127.0.0.1:57032","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-06T17:10:27.604952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.460938515s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-06T17:10:27.605007Z","caller":"traceutil/trace.go:171","msg":"trace[825019459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:986; }","duration":"1.461004128s","start":"2026-04-06T17:10:26.14399Z","end":"2026-04-06T17:10:27.604994Z","steps":["trace[825019459] 'agreement among raft nodes before linearized reading' (duration: 1.460821282s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:27.605042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:26.143984Z","time spent":"1.461050199s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-06T17:10:27.605195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.463870448s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-06T17:10:27.605303Z","caller":"traceutil/trace.go:171","msg":"trace[600809848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:986; }","duration":"1.464023103s","start":"2026-04-06T17:10:26.141265Z","end":"2026-04-06T17:10:27.605289Z","steps":["trace[600809848] 'agreement among raft nodes before linearized reading' (duration: 1.463763785s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:27.605338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:26.141247Z","time spent":"1.464081244s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-06T17:10:27.605424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.463633321s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-06T17:10:27.605467Z","caller":"traceutil/trace.go:171","msg":"trace[852577718] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:986; }","duration":"1.463713813s","start":"2026-04-06T17:10:26.141741Z","end":"2026-04-06T17:10:27.605455Z","steps":["trace[852577718] 'agreement among raft nodes before linearized reading' (duration: 1.463596019s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:27.605498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:26.141722Z","time spent":"1.463768734s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-06T17:10:30.987322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.917681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:10:30.987398Z","caller":"traceutil/trace.go:171","msg":"trace[1669451146] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:999; }","duration":"108.058135ms","start":"2026-04-06T17:10:30.879323Z","end":"2026-04-06T17:10:30.987381Z","steps":["trace[1669451146] 'count revisions from in-memory index tree' (duration: 107.818737ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:10:37.7686Z","caller":"traceutil/trace.go:171","msg":"trace[1353281833] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"116.720184ms","start":"2026-04-06T17:10:37.651857Z","end":"2026-04-06T17:10:37.768577Z","steps":["trace[1353281833] 'process raft request' (duration: 116.529628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:52.381384Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125953864015284514,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-06T17:10:52.428417Z","caller":"traceutil/trace.go:171","msg":"trace[1659692530] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1108; }","duration":"547.58341ms","start":"2026-04-06T17:10:51.880809Z","end":"2026-04-06T17:10:52.428393Z","steps":["trace[1659692530] 'read index received' (duration: 547.398054ms)","trace[1659692530] 'applied index is now lower than readState.Index' (duration: 184.326µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:10:52.42855Z","caller":"traceutil/trace.go:171","msg":"trace[1923835952] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"576.09916ms","start":"2026-04-06T17:10:51.85244Z","end":"2026-04-06T17:10:52.428539Z","steps":["trace[1923835952] 'process raft request' (duration: 575.800531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:52.428767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:51.852416Z","time spent":"576.166072ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:10:52.42903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.318692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-06T17:10:52.429048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.220349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-06T17:10:52.429144Z","caller":"traceutil/trace.go:171","msg":"trace[307720397] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1066; }","duration":"239.477098ms","start":"2026-04-06T17:10:52.18965Z","end":"2026-04-06T17:10:52.429127Z","steps":["trace[307720397] 'agreement among raft nodes before linearized reading' (duration: 239.308342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:52.429169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.7934ms","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-06T17:10:52.429188Z","caller":"traceutil/trace.go:171","msg":"trace[1167686796] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1066; }","duration":"548.400535ms","start":"2026-04-06T17:10:51.880767Z","end":"2026-04-06T17:10:52.429167Z","steps":["trace[1167686796] 'agreement among raft nodes before linearized reading' (duration: 548.166918ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:10:52.429261Z","caller":"traceutil/trace.go:171","msg":"trace[1588866651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1066; }","duration":"510.907974ms","start":"2026-04-06T17:10:51.918324Z","end":"2026-04-06T17:10:52.429232Z","steps":["trace[1588866651] 'agreement among raft nodes before linearized reading' (duration: 510.690947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:52.429245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.612987ms","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-06T17:10:52.429325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:51.918283Z","time spent":"511.028697ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-06T17:10:52.429299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:51.880748Z","time spent":"548.518328ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-06T17:10:52.429342Z","caller":"traceutil/trace.go:171","msg":"trace[1374056069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1066; }","duration":"512.73795ms","start":"2026-04-06T17:10:51.916579Z","end":"2026-04-06T17:10:52.429317Z","steps":["trace[1374056069] 'agreement among raft nodes before linearized reading' (duration: 512.4181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:52.429398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:51.91657Z","time spent":"512.820454ms","remote":"127.0.0.1:57316","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-06T17:10:52.429052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.08508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:10:52.429461Z","caller":"traceutil/trace.go:171","msg":"trace[620941257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1066; }","duration":"152.551514ms","start":"2026-04-06T17:10:52.276893Z","end":"2026-04-06T17:10:52.429445Z","steps":["trace[620941257] 'agreement among raft nodes before linearized reading' (duration: 152.017998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:52.429583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.001464ms","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-06T17:10:52.429631Z","caller":"traceutil/trace.go:171","msg":"trace[348660419] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1066; }","duration":"517.070066ms","start":"2026-04-06T17:10:51.912547Z","end":"2026-04-06T17:10:52.429617Z","steps":["trace[348660419] 'agreement among raft nodes before linearized reading' (duration: 516.207139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:52.429625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.859871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-06T17:10:52.429826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:51.912524Z","time spent":"517.295323ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-06T17:10:52.429844Z","caller":"traceutil/trace.go:171","msg":"trace[2122499604] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1066; }","duration":"444.106198ms","start":"2026-04-06T17:10:51.985725Z","end":"2026-04-06T17:10:52.429832Z","steps":["trace[2122499604] 'agreement among raft nodes before linearized reading' (duration: 443.839929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:52.43Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:51.985711Z","time spent":"444.249743ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-06T17:10:52.430013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.709589ms","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-06T17:10:52.430113Z","caller":"traceutil/trace.go:171","msg":"trace[544249246] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1066; }","duration":"511.943286ms","start":"2026-04-06T17:10:51.918152Z","end":"2026-04-06T17:10:52.430095Z","steps":["trace[544249246] 'agreement among raft nodes before linearized reading' (duration: 510.661596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:52.430157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:51.918132Z","time spent":"512.012278ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-06T17:10:52.890485Z","caller":"traceutil/trace.go:171","msg":"trace[1404915834] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"456.566658ms","start":"2026-04-06T17:10:52.433891Z","end":"2026-04-06T17:10:52.890458Z","steps":["trace[1404915834] 'process raft request' (duration: 370.027216ms)","trace[1404915834] 'compare' (duration: 86.420049ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:10:52.890633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:52.433869Z","time spent":"456.685201ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:10:52.931245Z","caller":"traceutil/trace.go:171","msg":"trace[714797005] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"496.129723ms","start":"2026-04-06T17:10:52.435089Z","end":"2026-04-06T17:10:52.931219Z","steps":["trace[714797005] 'process raft request' (duration: 495.980519ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:10:52.931402Z","caller":"traceutil/trace.go:171","msg":"trace[1081262812] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"496.277698ms","start":"2026-04-06T17:10:52.435103Z","end":"2026-04-06T17:10:52.931381Z","steps":["trace[1081262812] 'process raft request' (duration: 495.987799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:52.931411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:52.435076Z","time spent":"496.245728ms","remote":"127.0.0.1:57316","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-06T17:10:52.931505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:52.43509Z","time spent":"496.35563ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:10:52.931552Z","caller":"traceutil/trace.go:171","msg":"trace[1010628405] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"496.905307ms","start":"2026-04-06T17:10:52.434634Z","end":"2026-04-06T17:10:52.931539Z","steps":["trace[1010628405] 'process raft request' (duration: 496.325209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:52.931629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:52.43461Z","time spent":"496.97443ms","remote":"127.0.0.1:57316","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-06T17:10:52.931241Z","caller":"traceutil/trace.go:171","msg":"trace[1152643979] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"496.508996ms","start":"2026-04-06T17:10:52.434691Z","end":"2026-04-06T17:10:52.9312Z","steps":["trace[1152643979] 'process raft request' (duration: 496.357801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:52.931718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:52.434677Z","time spent":"497.005091ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:10:55.395143Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125953864015284528,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-06T17:10:55.5242Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.08840279s","expected-duration":"1s"} {"level":"info","ts":"2026-04-06T17:10:55.524504Z","caller":"traceutil/trace.go:171","msg":"trace[2092398026] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"1.088847264s","start":"2026-04-06T17:10:54.435635Z","end":"2026-04-06T17:10:55.524482Z","steps":["trace[2092398026] 'process raft request' (duration: 1.08871501s)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:10:55.524521Z","caller":"traceutil/trace.go:171","msg":"trace[2144328886] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1115; }","duration":"629.561861ms","start":"2026-04-06T17:10:54.894928Z","end":"2026-04-06T17:10:55.52449Z","steps":["trace[2144328886] 'read index received' (duration: 629.546221ms)","trace[2144328886] 'applied index is now lower than readState.Index' (duration: 12.9µs)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:10:55.524812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.893241ms","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-06T17:10:55.524846Z","caller":"traceutil/trace.go:171","msg":"trace[43929769] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1072; }","duration":"629.977284ms","start":"2026-04-06T17:10:54.894861Z","end":"2026-04-06T17:10:55.524838Z","steps":["trace[43929769] 'agreement among raft nodes before linearized reading' (duration: 629.785858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:55.524865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:54.894833Z","time spent":"630.027895ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-06T17:10:55.525203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:54.435593Z","time spent":"1.088994928s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:10:55.974627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.758979ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:10:55.974907Z","caller":"traceutil/trace.go:171","msg":"trace[1408088116] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"549.408376ms","start":"2026-04-06T17:10:55.42546Z","end":"2026-04-06T17:10:55.974868Z","steps":["trace[1408088116] 'process raft request' (duration: 381.313657ms)","trace[1408088116] 'compare' (duration: 167.609594ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:10:55.97498Z","caller":"traceutil/trace.go:171","msg":"trace[1051519601] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1115; }","duration":"450.317461ms","start":"2026-04-06T17:10:55.524653Z","end":"2026-04-06T17:10:55.974971Z","steps":["trace[1051519601] 'read index received' (duration: 282.13522ms)","trace[1051519601] 'applied index is now lower than readState.Index' (duration: 168.181191ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:10:55.975056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:55.425438Z","time spent":"549.540871ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:10:55.975187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040163311s","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-06T17:10:55.975223Z","caller":"traceutil/trace.go:171","msg":"trace[604210883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1074; }","duration":"1.040246474s","start":"2026-04-06T17:10:54.934967Z","end":"2026-04-06T17:10:55.975213Z","steps":["trace[604210883] 'agreement among raft nodes before linearized reading' (duration: 1.040060378s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:55.975284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:54.934952Z","time spent":"1.040324387s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-06T17:10:55.975502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040390489s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-06T17:10:55.975577Z","caller":"traceutil/trace.go:171","msg":"trace[1379220024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1074; }","duration":"1.040520413s","start":"2026-04-06T17:10:54.935038Z","end":"2026-04-06T17:10:55.975559Z","steps":["trace[1379220024] 'agreement among raft nodes before linearized reading' (duration: 1.040257985s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:55.97561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040556984s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-06T17:10:55.975625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:54.935022Z","time spent":"1.040594305s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-06T17:10:55.975641Z","caller":"traceutil/trace.go:171","msg":"trace[392684395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1074; }","duration":"1.040616806s","start":"2026-04-06T17:10:54.935015Z","end":"2026-04-06T17:10:55.975632Z","steps":["trace[392684395] 'agreement among raft nodes before linearized reading' (duration: 1.040507963s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:55.975693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:54.934998Z","time spent":"1.040688978s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-06T17:10:55.975865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040838463s","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-06T17:10:55.975896Z","caller":"traceutil/trace.go:171","msg":"trace[1512149203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1074; }","duration":"1.040871644s","start":"2026-04-06T17:10:54.935015Z","end":"2026-04-06T17:10:55.975886Z","steps":["trace[1512149203] 'agreement among raft nodes before linearized reading' (duration: 1.040764961s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:55.975955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:54.935003Z","time spent":"1.040944136s","remote":"127.0.0.1:57316","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-06T17:10:55.975986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.740228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:10:55.976019Z","caller":"traceutil/trace.go:171","msg":"trace[1797921141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1074; }","duration":"697.808741ms","start":"2026-04-06T17:10:55.2782Z","end":"2026-04-06T17:10:55.976008Z","steps":["trace[1797921141] 'agreement among raft nodes before linearized reading' (duration: 697.761279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:55.976045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:55.278177Z","time spent":"697.861992ms","remote":"127.0.0.1:57032","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-06T17:10:55.974941Z","caller":"traceutil/trace.go:171","msg":"trace[1421863314] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"446.707688ms","start":"2026-04-06T17:10:55.528194Z","end":"2026-04-06T17:10:55.974901Z","steps":["trace[1421863314] 'process raft request' (duration: 446.609785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:55.976128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:55.528177Z","time spent":"447.894906ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:10:56.114Z","caller":"traceutil/trace.go:171","msg":"trace[218116961] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"133.394046ms","start":"2026-04-06T17:10:55.980591Z","end":"2026-04-06T17:10:56.113985Z","steps":["trace[218116961] 'process raft request' (duration: 129.710901ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:10:56.113994Z","caller":"traceutil/trace.go:171","msg":"trace[1563031742] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"132.042713ms","start":"2026-04-06T17:10:55.981924Z","end":"2026-04-06T17:10:56.113967Z","steps":["trace[1563031742] 'process raft request' (duration: 132.004052ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:10:56.114054Z","caller":"traceutil/trace.go:171","msg":"trace[1018684110] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"132.327112ms","start":"2026-04-06T17:10:55.981699Z","end":"2026-04-06T17:10:56.114027Z","steps":["trace[1018684110] 'process raft request' (duration: 132.193278ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:10:57.975473Z","caller":"traceutil/trace.go:171","msg":"trace[723415227] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1122; }","duration":"208.054487ms","start":"2026-04-06T17:10:57.767402Z","end":"2026-04-06T17:10:57.975456Z","steps":["trace[723415227] 'read index received' (duration: 207.876381ms)","trace[723415227] 'applied index is now lower than readState.Index' (duration: 177.436µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:10:57.975615Z","caller":"traceutil/trace.go:171","msg":"trace[737990132] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"436.670866ms","start":"2026-04-06T17:10:57.538935Z","end":"2026-04-06T17:10:57.975606Z","steps":["trace[737990132] 'process raft request' (duration: 436.393278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:57.975679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.631591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-06T17:10:57.97572Z","caller":"traceutil/trace.go:171","msg":"trace[284440201] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1079; }","duration":"164.725584ms","start":"2026-04-06T17:10:57.810984Z","end":"2026-04-06T17:10:57.97571Z","steps":["trace[284440201] 'agreement among raft nodes before linearized reading' (duration: 164.642291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:57.9757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:57.538917Z","time spent":"436.732499ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:10:57.975863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.46572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:10:57.975888Z","caller":"traceutil/trace.go:171","msg":"trace[715949286] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1079; }","duration":"208.491291ms","start":"2026-04-06T17:10:57.76739Z","end":"2026-04-06T17:10:57.975881Z","steps":["trace[715949286] 'agreement among raft nodes before linearized reading' (duration: 208.45059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:58.688203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.367024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:10:58.688296Z","caller":"traceutil/trace.go:171","msg":"trace[1360805971] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1123; }","duration":"666.508914ms","start":"2026-04-06T17:10:58.021771Z","end":"2026-04-06T17:10:58.68828Z","steps":["trace[1360805971] 'read index received' (duration: 486.468652ms)","trace[1360805971] 'applied index is now lower than readState.Index' (duration: 180.039282ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:10:58.688402Z","caller":"traceutil/trace.go:171","msg":"trace[1173268852] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"703.894691ms","start":"2026-04-06T17:10:57.984497Z","end":"2026-04-06T17:10:58.688391Z","steps":["trace[1173268852] 'process raft request' (duration: 522.252469ms)","trace[1173268852] 'compare' (duration: 181.24165ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:10:58.688457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:57.984477Z","time spent":"703.950354ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:10:58.688654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.94317ms","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-06T17:10:58.688738Z","caller":"traceutil/trace.go:171","msg":"trace[597230309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1080; }","duration":"571.065054ms","start":"2026-04-06T17:10:58.11765Z","end":"2026-04-06T17:10:58.688715Z","steps":["trace[597230309] 'agreement among raft nodes before linearized reading' (duration: 570.776125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:58.688789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:58.11763Z","time spent":"571.149457ms","remote":"127.0.0.1:57316","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-06T17:10:58.688825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.404146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:10:58.68886Z","caller":"traceutil/trace.go:171","msg":"trace[1071680609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1080; }","duration":"410.473859ms","start":"2026-04-06T17:10:58.278375Z","end":"2026-04-06T17:10:58.688849Z","steps":["trace[1071680609] 'agreement among raft nodes before linearized reading' (duration: 410.399186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:58.688888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:58.278354Z","time spent":"410.52878ms","remote":"127.0.0.1:57032","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-06T17:10:58.689105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.901569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-06T17:10:58.68917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.381971ms","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-06T17:10:58.689152Z","caller":"traceutil/trace.go:171","msg":"trace[130252846] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1080; }","duration":"571.013592ms","start":"2026-04-06T17:10:58.118124Z","end":"2026-04-06T17:10:58.689138Z","steps":["trace[130252846] 'agreement among raft nodes before linearized reading' (duration: 570.853387ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:10:58.689213Z","caller":"traceutil/trace.go:171","msg":"trace[1233111209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1080; }","duration":"667.457764ms","start":"2026-04-06T17:10:58.021736Z","end":"2026-04-06T17:10:58.689193Z","steps":["trace[1233111209] 'agreement among raft nodes before linearized reading' (duration: 667.289328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:58.68922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:58.118103Z","time spent":"571.103705ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-06T17:10:58.689242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:58.02172Z","time spent":"667.514985ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-06T17:10:58.689438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.383853ms","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-06T17:10:58.689469Z","caller":"traceutil/trace.go:171","msg":"trace[949858067] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1080; }","duration":"571.433585ms","start":"2026-04-06T17:10:58.118026Z","end":"2026-04-06T17:10:58.68946Z","steps":["trace[949858067] 'agreement among raft nodes before linearized reading' (duration: 571.346922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:58.689494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:58.118017Z","time spent":"571.471466ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-06T17:10:58.996805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.693506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:10:58.997385Z","caller":"traceutil/trace.go:171","msg":"trace[820010935] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"296.340324ms","start":"2026-04-06T17:10:58.701026Z","end":"2026-04-06T17:10:58.997366Z","steps":["trace[820010935] 'process raft request' (duration: 152.987608ms)","trace[820010935] 'compare' (duration: 142.403767ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:10:58.997506Z","caller":"traceutil/trace.go:171","msg":"trace[2081212861] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"296.142218ms","start":"2026-04-06T17:10:58.701267Z","end":"2026-04-06T17:10:58.997409Z","steps":["trace[2081212861] 'process raft request' (duration: 296.013454ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:10:58.997527Z","caller":"traceutil/trace.go:171","msg":"trace[1030353662] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1125; }","duration":"230.761066ms","start":"2026-04-06T17:10:58.766733Z","end":"2026-04-06T17:10:58.997494Z","steps":["trace[1030353662] 'read index received' (duration: 87.253365ms)","trace[1030353662] 'applied index is now lower than readState.Index' (duration: 143.505171ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:10:58.997625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.8856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-06T17:10:58.997654Z","caller":"traceutil/trace.go:171","msg":"trace[254710998] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1084; }","duration":"230.972673ms","start":"2026-04-06T17:10:58.766674Z","end":"2026-04-06T17:10:58.997647Z","steps":["trace[254710998] 'agreement among raft nodes before linearized reading' (duration: 230.913161ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:10:58.997613Z","caller":"traceutil/trace.go:171","msg":"trace[1351096010] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"296.239271ms","start":"2026-04-06T17:10:58.701338Z","end":"2026-04-06T17:10:58.997578Z","steps":["trace[1351096010] 'process raft request' (duration: 296.032535ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:04.095083Z","caller":"traceutil/trace.go:171","msg":"trace[1447414852] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"104.418841ms","start":"2026-04-06T17:11:03.990639Z","end":"2026-04-06T17:11:04.095058Z","steps":["trace[1447414852] 'process raft request' (duration: 104.260366ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:08.50312Z","caller":"traceutil/trace.go:171","msg":"trace[1993021588] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"121.488483ms","start":"2026-04-06T17:11:08.381606Z","end":"2026-04-06T17:11:08.503095Z","steps":["trace[1993021588] 'process raft request' (duration: 56.431522ms)","trace[1993021588] 'compare' (duration: 64.895886ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:11:11.050001Z","caller":"traceutil/trace.go:171","msg":"trace[875849812] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1219; }","duration":"276.125573ms","start":"2026-04-06T17:11:10.77385Z","end":"2026-04-06T17:11:11.049976Z","steps":["trace[875849812] 'read index received' (duration: 275.985398ms)","trace[875849812] 'applied index is now lower than readState.Index' (duration: 139.035µs)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:11:11.050119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:10.670789Z","time spent":"379.325596ms","remote":"127.0.0.1:57058","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-06T17:11:11.050498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.646379ms","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-06T17:11:11.050539Z","caller":"traceutil/trace.go:171","msg":"trace[2011822122] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1170; }","duration":"276.758603ms","start":"2026-04-06T17:11:10.773769Z","end":"2026-04-06T17:11:11.050528Z","steps":["trace[2011822122] 'agreement among raft nodes before linearized reading' (duration: 276.620229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:11.050753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.099229ms","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-06T17:11:11.05079Z","caller":"traceutil/trace.go:171","msg":"trace[623413736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1170; }","duration":"265.188822ms","start":"2026-04-06T17:11:10.785592Z","end":"2026-04-06T17:11:11.05078Z","steps":["trace[623413736] 'agreement among raft nodes before linearized reading' (duration: 265.103919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:11.334808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.222935ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:11:11.334922Z","caller":"traceutil/trace.go:171","msg":"trace[1366610220] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"282.998377ms","start":"2026-04-06T17:11:11.051903Z","end":"2026-04-06T17:11:11.334901Z","steps":["trace[1366610220] 'process raft request' (duration: 113.606838ms)","trace[1366610220] 'compare' (duration: 169.108091ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:11:11.427604Z","caller":"traceutil/trace.go:171","msg":"trace[625004964] linearizableReadLoop","detail":"{readStateIndex:1223; appliedIndex:1220; }","duration":"352.165158ms","start":"2026-04-06T17:11:11.075423Z","end":"2026-04-06T17:11:11.427588Z","steps":["trace[625004964] 'read index received' (duration: 90.090564ms)","trace[625004964] 'applied index is now lower than readState.Index' (duration: 262.073894ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:11:11.427689Z","caller":"traceutil/trace.go:171","msg":"trace[1478997723] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"372.42693ms","start":"2026-04-06T17:11:11.055222Z","end":"2026-04-06T17:11:11.427649Z","steps":["trace[1478997723] 'process raft request' (duration: 372.225953ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:11.427724Z","caller":"traceutil/trace.go:171","msg":"trace[1905817825] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"372.443601ms","start":"2026-04-06T17:11:11.05525Z","end":"2026-04-06T17:11:11.427694Z","steps":["trace[1905817825] 'process raft request' (duration: 372.314597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:11.427768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.327333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-06T17:11:11.427787Z","caller":"traceutil/trace.go:171","msg":"trace[569799252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1173; }","duration":"352.386274ms","start":"2026-04-06T17:11:11.075395Z","end":"2026-04-06T17:11:11.427781Z","steps":["trace[569799252] 'agreement among raft nodes before linearized reading' (duration: 352.287551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:11.427807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:11.075382Z","time spent":"352.419905ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-06T17:11:11.427859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:11.055236Z","time spent":"372.552604ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:11:11.427871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:11.055211Z","time spent":"372.557954ms","remote":"127.0.0.1:57316","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-06T17:11:11.427954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.125458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-06T17:11:11.42803Z","caller":"traceutil/trace.go:171","msg":"trace[1473066791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1173; }","duration":"329.223711ms","start":"2026-04-06T17:11:11.098789Z","end":"2026-04-06T17:11:11.428013Z","steps":["trace[1473066791] 'agreement among raft nodes before linearized reading' (duration: 329.048816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:11.428069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:11.098781Z","time spent":"329.280524ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-06T17:11:11.428095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.741227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:11:11.428164Z","caller":"traceutil/trace.go:171","msg":"trace[398704956] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1173; }","duration":"166.83004ms","start":"2026-04-06T17:11:11.26131Z","end":"2026-04-06T17:11:11.42814Z","steps":["trace[398704956] 'agreement among raft nodes before linearized reading' (duration: 166.721427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:11.427985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.697915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-06T17:11:11.428198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.364306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-06T17:11:11.428224Z","caller":"traceutil/trace.go:171","msg":"trace[173468850] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1173; }","duration":"329.396256ms","start":"2026-04-06T17:11:11.098818Z","end":"2026-04-06T17:11:11.428214Z","steps":["trace[173468850] 'agreement among raft nodes before linearized reading' (duration: 329.343335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:11.428254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:11.098814Z","time spent":"329.433117ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-06T17:11:11.428217Z","caller":"traceutil/trace.go:171","msg":"trace[44558771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1173; }","duration":"149.956013ms","start":"2026-04-06T17:11:11.278251Z","end":"2026-04-06T17:11:11.428207Z","steps":["trace[44558771] 'agreement among raft nodes before linearized reading' (duration: 149.701785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:13.95428Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125953864015284763,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-06T17:11:14.454634Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125953864015284763,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-06T17:11:14.955128Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125953864015284763,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-06T17:11:15.198131Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.762737777s","expected-duration":"1s"} {"level":"info","ts":"2026-04-06T17:11:15.198528Z","caller":"traceutil/trace.go:171","msg":"trace[1457318534] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"1.763210741s","start":"2026-04-06T17:11:13.435294Z","end":"2026-04-06T17:11:15.198504Z","steps":["trace[1457318534] 'process raft request' (duration: 1.763068207s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:15.198706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:13.435274Z","time spent":"1.763339346s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:11:15.199393Z","caller":"traceutil/trace.go:171","msg":"trace[2130571121] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"1.763943434s","start":"2026-04-06T17:11:13.435432Z","end":"2026-04-06T17:11:15.199375Z","steps":["trace[2130571121] 'process raft request' (duration: 1.763760909s)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:15.199428Z","caller":"traceutil/trace.go:171","msg":"trace[1809154002] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"1.048994878s","start":"2026-04-06T17:11:14.150409Z","end":"2026-04-06T17:11:15.199404Z","steps":["trace[1809154002] 'process raft request' (duration: 1.048922456s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:15.199491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:13.435421Z","time spent":"1.764022867s","remote":"127.0.0.1:57316","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-06T17:11:15.199547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:14.150393Z","time spent":"1.049087962s","remote":"127.0.0.1:57316","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-06T17:11:15.199391Z","caller":"traceutil/trace.go:171","msg":"trace[1074895615] linearizableReadLoop","detail":"{readStateIndex:1230; appliedIndex:1228; }","duration":"1.746126628s","start":"2026-04-06T17:11:13.453242Z","end":"2026-04-06T17:11:15.199368Z","steps":["trace[1074895615] 'read index received' (duration: 1.745471408s)","trace[1074895615] 'applied index is now lower than readState.Index' (duration: 654.37µs)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:11:15.199679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.633510422s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-06T17:11:15.199697Z","caller":"traceutil/trace.go:171","msg":"trace[1661383647] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1180; }","duration":"1.633565023s","start":"2026-04-06T17:11:13.566127Z","end":"2026-04-06T17:11:15.199692Z","steps":["trace[1661383647] 'agreement among raft nodes before linearized reading' (duration: 1.633518562s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:15.199711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:13.566106Z","time spent":"1.633601394s","remote":"127.0.0.1:57214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-06T17:11:15.199715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.736991143s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-06T17:11:15.199751Z","caller":"traceutil/trace.go:171","msg":"trace[95537305] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1180; }","duration":"1.737062165s","start":"2026-04-06T17:11:13.462678Z","end":"2026-04-06T17:11:15.19974Z","steps":["trace[95537305] 'agreement among raft nodes before linearized reading' (duration: 1.736959182s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:15.199785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:13.462661Z","time spent":"1.737116467s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-06T17:11:15.199804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.736620111s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-06T17:11:15.199868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.916968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:11:15.199885Z","caller":"traceutil/trace.go:171","msg":"trace[620107759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1180; }","duration":"243.974609ms","start":"2026-04-06T17:11:14.955904Z","end":"2026-04-06T17:11:15.199878Z","steps":["trace[620107759] 'agreement among raft nodes before linearized reading' (duration: 243.948308ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:15.199878Z","caller":"traceutil/trace.go:171","msg":"trace[62598970] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1180; }","duration":"1.736744165s","start":"2026-04-06T17:11:13.463116Z","end":"2026-04-06T17:11:15.19986Z","steps":["trace[62598970] 'agreement among raft nodes before linearized reading' (duration: 1.736549919s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:15.199919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:13.46308Z","time spent":"1.736830688s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-06T17:11:15.200037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.737066625s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-06T17:11:15.20007Z","caller":"traceutil/trace.go:171","msg":"trace[1756665042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1180; }","duration":"1.737106287s","start":"2026-04-06T17:11:13.462954Z","end":"2026-04-06T17:11:15.20006Z","steps":["trace[1756665042] 'agreement among raft nodes before linearized reading' (duration: 1.737020374s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:15.200097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:13.462949Z","time spent":"1.737141838s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-06T17:11:15.199556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.746294843s","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":"info","ts":"2026-04-06T17:11:15.200361Z","caller":"traceutil/trace.go:171","msg":"trace[1329344170] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1180; }","duration":"1.747173441s","start":"2026-04-06T17:11:13.453177Z","end":"2026-04-06T17:11:15.20035Z","steps":["trace[1329344170] 'agreement among raft nodes before linearized reading' (duration: 1.746250782s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:15.200395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:13.453149Z","time spent":"1.747234633s","remote":"127.0.0.1:52508","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":"info","ts":"2026-04-06T17:11:27.47517Z","caller":"traceutil/trace.go:171","msg":"trace[142118236] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1270; }","duration":"227.882637ms","start":"2026-04-06T17:11:27.247264Z","end":"2026-04-06T17:11:27.475147Z","steps":["trace[142118236] 'read index received' (duration: 227.836425ms)","trace[142118236] 'applied index is now lower than readState.Index' (duration: 44.902µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:11:27.475279Z","caller":"traceutil/trace.go:171","msg":"trace[1964997417] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"241.596365ms","start":"2026-04-06T17:11:27.233654Z","end":"2026-04-06T17:11:27.475251Z","steps":["trace[1964997417] 'process raft request' (duration: 241.349217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:27.475422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.141305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-06T17:11:27.47628Z","caller":"traceutil/trace.go:171","msg":"trace[1533909656] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1218; }","duration":"229.026302ms","start":"2026-04-06T17:11:27.247222Z","end":"2026-04-06T17:11:27.476248Z","steps":["trace[1533909656] 'agreement among raft nodes before linearized reading' (duration: 228.038881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:27.476421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.106311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-04-06T17:11:27.476441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.738323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-06T17:11:27.476494Z","caller":"traceutil/trace.go:171","msg":"trace[879252804] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1218; }","duration":"212.826656ms","start":"2026-04-06T17:11:27.263657Z","end":"2026-04-06T17:11:27.476484Z","steps":["trace[879252804] 'agreement among raft nodes before linearized reading' (duration: 212.664091ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:27.47649Z","caller":"traceutil/trace.go:171","msg":"trace[551342065] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1218; }","duration":"210.209955ms","start":"2026-04-06T17:11:27.266264Z","end":"2026-04-06T17:11:27.476474Z","steps":["trace[551342065] 'agreement among raft nodes before linearized reading' (duration: 210.010459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:27.476896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.678027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-06T17:11:27.477066Z","caller":"traceutil/trace.go:171","msg":"trace[1148375322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1218; }","duration":"224.869083ms","start":"2026-04-06T17:11:27.252179Z","end":"2026-04-06T17:11:27.477048Z","steps":["trace[1148375322] 'agreement among raft nodes before linearized reading' (duration: 224.227923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:27.478239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.880404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-06T17:11:27.478372Z","caller":"traceutil/trace.go:171","msg":"trace[1645848448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1218; }","duration":"228.019971ms","start":"2026-04-06T17:11:27.250337Z","end":"2026-04-06T17:11:27.478357Z","steps":["trace[1645848448] 'agreement among raft nodes before linearized reading' (duration: 225.76776ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:27.645186Z","caller":"traceutil/trace.go:171","msg":"trace[49226124] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"162.467244ms","start":"2026-04-06T17:11:27.48269Z","end":"2026-04-06T17:11:27.645157Z","steps":["trace[49226124] 'process raft request' (duration: 162.416482ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:27.645382Z","caller":"traceutil/trace.go:171","msg":"trace[1311959081] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"164.743184ms","start":"2026-04-06T17:11:27.480623Z","end":"2026-04-06T17:11:27.645366Z","steps":["trace[1311959081] 'process raft request' (duration: 164.364213ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:27.645446Z","caller":"traceutil/trace.go:171","msg":"trace[1918744081] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"163.458185ms","start":"2026-04-06T17:11:27.481948Z","end":"2026-04-06T17:11:27.645406Z","steps":["trace[1918744081] 'process raft request' (duration: 163.083053ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:27.64549Z","caller":"traceutil/trace.go:171","msg":"trace[934911814] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"164.90856ms","start":"2026-04-06T17:11:27.480565Z","end":"2026-04-06T17:11:27.645474Z","steps":["trace[934911814] 'process raft request' (duration: 162.849615ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:27.645722Z","caller":"traceutil/trace.go:171","msg":"trace[824462555] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"164.634661ms","start":"2026-04-06T17:11:27.480589Z","end":"2026-04-06T17:11:27.645224Z","steps":["trace[824462555] 'process raft request' (duration: 164.268889ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:30.130453Z","caller":"traceutil/trace.go:171","msg":"trace[1828935520] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1284; }","duration":"480.704812ms","start":"2026-04-06T17:11:29.649729Z","end":"2026-04-06T17:11:30.130434Z","steps":["trace[1828935520] 'read index received' (duration: 480.62749ms)","trace[1828935520] 'applied index is now lower than readState.Index' (duration: 76.482µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:11:30.130485Z","caller":"traceutil/trace.go:171","msg":"trace[521922681] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"644.675512ms","start":"2026-04-06T17:11:29.485786Z","end":"2026-04-06T17:11:30.130462Z","steps":["trace[521922681] 'process raft request' (duration: 644.522857ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:30.130597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:29.485768Z","time spent":"644.764855ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:11:30.130664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.914648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-06T17:11:30.130685Z","caller":"traceutil/trace.go:171","msg":"trace[964873286] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1232; }","duration":"480.983191ms","start":"2026-04-06T17:11:29.649696Z","end":"2026-04-06T17:11:30.130679Z","steps":["trace[964873286] 'agreement among raft nodes before linearized reading' (duration: 480.838596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:30.130705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:29.649679Z","time spent":"481.021391ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-06T17:11:30.699902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.877805ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:11:30.700026Z","caller":"traceutil/trace.go:171","msg":"trace[2132867233] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"565.408627ms","start":"2026-04-06T17:11:30.134604Z","end":"2026-04-06T17:11:30.700013Z","steps":["trace[2132867233] 'process raft request' (duration: 565.369796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:30.700069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:30.134581Z","time spent":"565.466198ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:11:30.700037Z","caller":"traceutil/trace.go:171","msg":"trace[1124297608] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"641.41004ms","start":"2026-04-06T17:11:30.058584Z","end":"2026-04-06T17:11:30.699994Z","steps":["trace[1124297608] 'process raft request' (duration: 512.38264ms)","trace[1124297608] 'compare' (duration: 128.773272ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:11:30.700075Z","caller":"traceutil/trace.go:171","msg":"trace[792394924] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1285; }","duration":"569.537786ms","start":"2026-04-06T17:11:30.130512Z","end":"2026-04-06T17:11:30.70005Z","steps":["trace[792394924] 'read index received' (duration: 440.468026ms)","trace[792394924] 'applied index is now lower than readState.Index' (duration: 129.06741ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:11:30.700211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049187615s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-06T17:11:30.700231Z","caller":"traceutil/trace.go:171","msg":"trace[536741281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1234; }","duration":"1.049246117s","start":"2026-04-06T17:11:29.650979Z","end":"2026-04-06T17:11:30.700225Z","steps":["trace[536741281] 'agreement among raft nodes before linearized reading' (duration: 1.049164544s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:30.700249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:29.650958Z","time spent":"1.049287487s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-06T17:11:30.700232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:30.058558Z","time spent":"641.569235ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:11:30.70029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049628838s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-06T17:11:30.700326Z","caller":"traceutil/trace.go:171","msg":"trace[304739714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1234; }","duration":"1.049708061s","start":"2026-04-06T17:11:29.650607Z","end":"2026-04-06T17:11:30.700315Z","steps":["trace[304739714] 'agreement among raft nodes before linearized reading' (duration: 1.049561316s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:30.700331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.912578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-06T17:11:30.700358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:29.65059Z","time spent":"1.049760283s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-06T17:11:30.700376Z","caller":"traceutil/trace.go:171","msg":"trace[659464919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1234; }","duration":"423.992261ms","start":"2026-04-06T17:11:30.276369Z","end":"2026-04-06T17:11:30.700362Z","steps":["trace[659464919] 'agreement among raft nodes before linearized reading' (duration: 423.926429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:30.700386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048463952s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-06T17:11:30.700407Z","caller":"traceutil/trace.go:171","msg":"trace[478604406] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1234; }","duration":"1.048503713s","start":"2026-04-06T17:11:29.651897Z","end":"2026-04-06T17:11:30.7004Z","steps":["trace[478604406] 'agreement among raft nodes before linearized reading' (duration: 1.048447931s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:30.700407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:30.276349Z","time spent":"424.051412ms","remote":"127.0.0.1:57032","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-06T17:11:30.700423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:29.651889Z","time spent":"1.048530744s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-06T17:11:30.700629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0506306s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-06T17:11:30.700647Z","caller":"traceutil/trace.go:171","msg":"trace[1322397284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1234; }","duration":"1.050664441s","start":"2026-04-06T17:11:29.649977Z","end":"2026-04-06T17:11:30.700642Z","steps":["trace[1322397284] 'agreement among raft nodes before linearized reading' (duration: 1.050611509s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:30.700664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:29.649972Z","time spent":"1.050687461s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-06T17:11:30.970381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.680462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:11:30.970486Z","caller":"traceutil/trace.go:171","msg":"trace[1157909774] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"257.909944ms","start":"2026-04-06T17:11:30.712558Z","end":"2026-04-06T17:11:30.970468Z","steps":["trace[1157909774] 'process raft request' (duration: 118.036606ms)","trace[1157909774] 'compare' (duration: 139.564568ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:11:32.148903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.712648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:11:32.149191Z","caller":"traceutil/trace.go:171","msg":"trace[23651010] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1240; }","duration":"152.13231ms","start":"2026-04-06T17:11:31.997044Z","end":"2026-04-06T17:11:32.149176Z","steps":["trace[23651010] 'count revisions from in-memory index tree' (duration: 151.621995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:32.776785Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125953864015284911,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-06T17:11:32.836164Z","caller":"traceutil/trace.go:171","msg":"trace[798156778] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1294; }","duration":"559.420029ms","start":"2026-04-06T17:11:32.276726Z","end":"2026-04-06T17:11:32.836146Z","steps":["trace[798156778] 'read index received' (duration: 559.207102ms)","trace[798156778] 'applied index is now lower than readState.Index' (duration: 212.077µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:11:32.836263Z","caller":"traceutil/trace.go:171","msg":"trace[1038719125] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"700.085112ms","start":"2026-04-06T17:11:32.136167Z","end":"2026-04-06T17:11:32.836252Z","steps":["trace[1038719125] 'process raft request' (duration: 699.820044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:32.836355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:32.136145Z","time spent":"700.139564ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:11:32.836704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.958966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:11:32.836776Z","caller":"traceutil/trace.go:171","msg":"trace[889679686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1241; }","duration":"560.0739ms","start":"2026-04-06T17:11:32.276683Z","end":"2026-04-06T17:11:32.836756Z","steps":["trace[889679686] 'agreement among raft nodes before linearized reading' (duration: 559.940786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:32.836819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:32.276629Z","time spent":"560.182133ms","remote":"127.0.0.1:57034","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-06T17:11:32.837283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.863638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-06T17:11:32.837369Z","caller":"traceutil/trace.go:171","msg":"trace[1290922363] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1241; }","duration":"132.012532ms","start":"2026-04-06T17:11:32.705335Z","end":"2026-04-06T17:11:32.837348Z","steps":["trace[1290922363] 'agreement among raft nodes before linearized reading' (duration: 131.140245ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:33.426224Z","caller":"traceutil/trace.go:171","msg":"trace[1367641756] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"584.925677ms","start":"2026-04-06T17:11:32.841278Z","end":"2026-04-06T17:11:33.426203Z","steps":["trace[1367641756] 'process raft request' (duration: 576.742622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:33.426533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:32.84125Z","time spent":"585.032301ms","remote":"127.0.0.1:57316","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-06T17:11:34.724086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.76501ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:11:34.724243Z","caller":"traceutil/trace.go:171","msg":"trace[811773592] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"1.882654483s","start":"2026-04-06T17:11:32.84157Z","end":"2026-04-06T17:11:34.724225Z","steps":["trace[811773592] 'process raft request' (duration: 1.371653986s)","trace[811773592] 'compare' (duration: 510.385428ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:11:34.724301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:32.84155Z","time spent":"1.882731255s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:11:34.724569Z","caller":"traceutil/trace.go:171","msg":"trace[1128370101] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"1.882604771s","start":"2026-04-06T17:11:32.841937Z","end":"2026-04-06T17:11:34.724542Z","steps":["trace[1128370101] 'process raft request' (duration: 1.88225044s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:34.724729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:32.84191Z","time spent":"1.882729605s","remote":"127.0.0.1:57316","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-06T17:11:34.724871Z","caller":"traceutil/trace.go:171","msg":"trace[641068649] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"1.882846459s","start":"2026-04-06T17:11:32.842004Z","end":"2026-04-06T17:11:34.724851Z","steps":["trace[641068649] 'process raft request' (duration: 1.882452447s)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:34.724949Z","caller":"traceutil/trace.go:171","msg":"trace[506642049] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1295; }","duration":"1.44609346s","start":"2026-04-06T17:11:33.278845Z","end":"2026-04-06T17:11:34.724939Z","steps":["trace[506642049] 'read index received' (duration: 139.201167ms)","trace[506642049] 'applied index is now lower than readState.Index' (duration: 1.306891543s)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:11:34.72499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:32.841982Z","time spent":"1.882946922s","remote":"127.0.0.1:57316","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-06T17:11:34.725021Z","caller":"traceutil/trace.go:171","msg":"trace[13817259] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"1.88223316s","start":"2026-04-06T17:11:32.842778Z","end":"2026-04-06T17:11:34.725011Z","steps":["trace[13817259] 'process raft request' (duration: 1.882004663s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:34.725056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.446208093s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:11:34.725107Z","caller":"traceutil/trace.go:171","msg":"trace[235748372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1246; }","duration":"1.446285256s","start":"2026-04-06T17:11:33.278815Z","end":"2026-04-06T17:11:34.7251Z","steps":["trace[235748372] 'agreement among raft nodes before linearized reading' (duration: 1.446194853s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:34.725127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:33.278801Z","time spent":"1.446321956s","remote":"127.0.0.1:57032","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-06T17:11:34.725301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.6491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:11:34.725328Z","caller":"traceutil/trace.go:171","msg":"trace[255079237] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1246; }","duration":"724.739313ms","start":"2026-04-06T17:11:34.000581Z","end":"2026-04-06T17:11:34.72532Z","steps":["trace[255079237] 'agreement among raft nodes before linearized reading' (duration: 724.694661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:34.725346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:34.000546Z","time spent":"724.795564ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-06T17:11:34.725067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:32.842733Z","time spent":"1.882310902s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:11:34.72545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.671081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-06T17:11:34.725469Z","caller":"traceutil/trace.go:171","msg":"trace[1527560226] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1246; }","duration":"301.689181ms","start":"2026-04-06T17:11:34.423774Z","end":"2026-04-06T17:11:34.725463Z","steps":["trace[1527560226] 'agreement among raft nodes before linearized reading' (duration: 301.65042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:34.725479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.242271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-06T17:11:34.725486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:34.423721Z","time spent":"301.760664ms","remote":"127.0.0.1:57370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-04-06T17:11:34.725505Z","caller":"traceutil/trace.go:171","msg":"trace[1633679008] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1246; }","duration":"483.272902ms","start":"2026-04-06T17:11:34.242223Z","end":"2026-04-06T17:11:34.725496Z","steps":["trace[1633679008] 'agreement among raft nodes before linearized reading' (duration: 483.229131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:34.725532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:34.242203Z","time spent":"483.323734ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-06T17:11:35.011518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.145028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:11:35.011811Z","caller":"traceutil/trace.go:171","msg":"trace[697817547] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"169.381666ms","start":"2026-04-06T17:11:34.842402Z","end":"2026-04-06T17:11:35.011783Z","steps":["trace[697817547] 'process raft request' (duration: 32.861926ms)","trace[697817547] 'compare' (duration: 135.711035ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:11:35.539814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.794684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-06T17:11:35.539945Z","caller":"traceutil/trace.go:171","msg":"trace[1181445174] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1247; }","duration":"235.97277ms","start":"2026-04-06T17:11:35.303956Z","end":"2026-04-06T17:11:35.539929Z","steps":["trace[1181445174] 'count revisions from in-memory index tree' (duration: 235.676081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:35.540008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.236275ms","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-06T17:11:35.540086Z","caller":"traceutil/trace.go:171","msg":"trace[108668115] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1247; }","duration":"109.367439ms","start":"2026-04-06T17:11:35.430701Z","end":"2026-04-06T17:11:35.540068Z","steps":["trace[108668115] 'range keys from in-memory index tree' (duration: 109.055079ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:35.79602Z","caller":"traceutil/trace.go:171","msg":"trace[900656075] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"252.276844ms","start":"2026-04-06T17:11:35.543719Z","end":"2026-04-06T17:11:35.795996Z","steps":["trace[900656075] 'process raft request' (duration: 252.121099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:37.905536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.375445034s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-06T17:11:37.905658Z","caller":"traceutil/trace.go:171","msg":"trace[1164328376] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1302; }","duration":"1.629268776s","start":"2026-04-06T17:11:36.276368Z","end":"2026-04-06T17:11:37.905637Z","steps":["trace[1164328376] 'read index received' (duration: 253.689188ms)","trace[1164328376] 'applied index is now lower than readState.Index' (duration: 1.375577458s)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:11:37.905938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.629563076s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:11:37.905979Z","caller":"traceutil/trace.go:171","msg":"trace[1741327340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1248; }","duration":"1.629649239s","start":"2026-04-06T17:11:36.276319Z","end":"2026-04-06T17:11:37.905968Z","steps":["trace[1741327340] 'agreement among raft nodes before linearized reading' (duration: 1.629568096s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:37.906027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:36.276298Z","time spent":"1.62972066s","remote":"127.0.0.1:57032","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-06T17:11:37.965113Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.198705204s","expected-duration":"1s"} {"level":"info","ts":"2026-04-06T17:11:37.965446Z","caller":"traceutil/trace.go:171","msg":"trace[1204649254] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"1.199136428s","start":"2026-04-06T17:11:36.76629Z","end":"2026-04-06T17:11:37.965427Z","steps":["trace[1204649254] 'process raft request' (duration: 1.198974893s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:37.965571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:36.766271Z","time spent":"1.19923599s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:11:38.557437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.332179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:11:38.557561Z","caller":"traceutil/trace.go:171","msg":"trace[669079402] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1303; }","duration":"651.829918ms","start":"2026-04-06T17:11:37.90571Z","end":"2026-04-06T17:11:38.55754Z","steps":["trace[669079402] 'read index received' (duration: 59.693434ms)","trace[669079402] 'applied index is now lower than readState.Index' (duration: 592.134894ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:11:38.557812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.496774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-06T17:11:38.557841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.829923206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-06T17:11:38.557872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.828312386s","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-06T17:11:38.557883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.828356017s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-06T17:11:38.5579Z","caller":"traceutil/trace.go:171","msg":"trace[1063469379] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1250; }","duration":"870.627378ms","start":"2026-04-06T17:11:37.687255Z","end":"2026-04-06T17:11:38.557882Z","steps":["trace[1063469379] 'agreement among raft nodes before linearized reading' (duration: 870.467223ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:38.557876Z","caller":"traceutil/trace.go:171","msg":"trace[1955269654] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"1.541222216s","start":"2026-04-06T17:11:37.016618Z","end":"2026-04-06T17:11:38.55784Z","steps":["trace[1955269654] 'process raft request' (duration: 1.229363791s)","trace[1955269654] 'compare' (duration: 310.985288ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:11:38.557923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.974941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:11:38.557933Z","caller":"traceutil/trace.go:171","msg":"trace[350677774] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1250; }","duration":"1.8284296s","start":"2026-04-06T17:11:36.72949Z","end":"2026-04-06T17:11:38.557919Z","steps":["trace[350677774] 'agreement among raft nodes before linearized reading' (duration: 1.828235064s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:38.55795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:37.687241Z","time spent":"870.69813ms","remote":"127.0.0.1:57110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-04-06T17:11:38.557993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:36.72948Z","time spent":"1.828504372s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-06T17:11:38.557992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102301526s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-06T17:11:38.557999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.830006238s","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-06T17:11:38.557993Z","caller":"traceutil/trace.go:171","msg":"trace[1411180194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1250; }","duration":"651.073735ms","start":"2026-04-06T17:11:37.906901Z","end":"2026-04-06T17:11:38.557974Z","steps":["trace[1411180194] 'agreement among raft nodes before linearized reading' (duration: 650.983903ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:38.558034Z","caller":"traceutil/trace.go:171","msg":"trace[1889667932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1250; }","duration":"1.830092831s","start":"2026-04-06T17:11:36.727933Z","end":"2026-04-06T17:11:38.558025Z","steps":["trace[1889667932] 'agreement among raft nodes before linearized reading' (duration: 1.830003198s)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:38.558034Z","caller":"traceutil/trace.go:171","msg":"trace[644075075] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1250; }","duration":"1.102383748s","start":"2026-04-06T17:11:37.455638Z","end":"2026-04-06T17:11:38.558022Z","steps":["trace[644075075] 'agreement among raft nodes before linearized reading' (duration: 1.102320256s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:38.558033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:37.016595Z","time spent":"1.54135757s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:11:38.558059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:37.906883Z","time spent":"651.160518ms","remote":"127.0.0.1:57034","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-06T17:11:38.558078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:37.455066Z","time spent":"1.102999347s","remote":"127.0.0.1:57260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-04-06T17:11:38.558068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:36.727891Z","time spent":"1.830169963s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-06T17:11:38.55793Z","caller":"traceutil/trace.go:171","msg":"trace[2062452771] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1250; }","duration":"1.828397469s","start":"2026-04-06T17:11:36.729515Z","end":"2026-04-06T17:11:38.557913Z","steps":["trace[2062452771] 'agreement among raft nodes before linearized reading' (duration: 1.828156421s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:38.55818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:36.729503Z","time spent":"1.828662376s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-06T17:11:38.557909Z","caller":"traceutil/trace.go:171","msg":"trace[1977476176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1250; }","duration":"1.83004541s","start":"2026-04-06T17:11:36.727846Z","end":"2026-04-06T17:11:38.557892Z","steps":["trace[1977476176] 'agreement among raft nodes before linearized reading' (duration: 1.829852314s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:38.558258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:36.727828Z","time spent":"1.830420441s","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-06T17:11:38.558204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.545324ms","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-06T17:11:38.55843Z","caller":"traceutil/trace.go:171","msg":"trace[862401514] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1250; }","duration":"758.126012ms","start":"2026-04-06T17:11:37.800287Z","end":"2026-04-06T17:11:38.558413Z","steps":["trace[862401514] 'agreement among raft nodes before linearized reading' (duration: 757.41618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:38.563932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:37.800268Z","time spent":"763.647743ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-06T17:11:38.878144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.918515ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:11:38.878342Z","caller":"traceutil/trace.go:171","msg":"trace[56362487] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"315.882218ms","start":"2026-04-06T17:11:38.562425Z","end":"2026-04-06T17:11:38.878307Z","steps":["trace[56362487] 'process raft request' (duration: 171.704445ms)","trace[56362487] 'compare' (duration: 143.693808ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:11:38.878432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:38.562404Z","time spent":"315.982072ms","remote":"127.0.0.1:57316","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-06T17:11:38.908454Z","caller":"traceutil/trace.go:171","msg":"trace[1116395651] linearizableReadLoop","detail":"{readStateIndex:1308; appliedIndex:1305; }","duration":"273.769727ms","start":"2026-04-06T17:11:38.63466Z","end":"2026-04-06T17:11:38.90843Z","steps":["trace[1116395651] 'read index received' (duration: 99.37362ms)","trace[1116395651] 'applied index is now lower than readState.Index' (duration: 174.395087ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:11:38.908644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.958843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:11:38.908641Z","caller":"traceutil/trace.go:171","msg":"trace[1829777887] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"344.887154ms","start":"2026-04-06T17:11:38.563728Z","end":"2026-04-06T17:11:38.908615Z","steps":["trace[1829777887] 'process raft request' (duration: 344.593275ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:38.908678Z","caller":"traceutil/trace.go:171","msg":"trace[524758253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1255; }","duration":"274.030535ms","start":"2026-04-06T17:11:38.634636Z","end":"2026-04-06T17:11:38.908667Z","steps":["trace[524758253] 'agreement among raft nodes before linearized reading' (duration: 273.896541ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:38.908718Z","caller":"traceutil/trace.go:171","msg":"trace[214352730] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"272.444016ms","start":"2026-04-06T17:11:38.636255Z","end":"2026-04-06T17:11:38.908699Z","steps":["trace[214352730] 'process raft request' (duration: 272.104856ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:38.908772Z","caller":"traceutil/trace.go:171","msg":"trace[858527699] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"345.876615ms","start":"2026-04-06T17:11:38.562875Z","end":"2026-04-06T17:11:38.908751Z","steps":["trace[858527699] 'process raft request' (duration: 345.301807ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:38.908838Z","caller":"traceutil/trace.go:171","msg":"trace[440661858] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"272.414166ms","start":"2026-04-06T17:11:38.636375Z","end":"2026-04-06T17:11:38.908789Z","steps":["trace[440661858] 'process raft request' (duration: 272.017014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:38.908807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:38.563709Z","time spent":"344.990207ms","remote":"127.0.0.1:57316","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-06T17:11:38.908881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:38.562863Z","time spent":"345.956368ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:11:40.771456Z","caller":"traceutil/trace.go:171","msg":"trace[1936421395] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1328; }","duration":"100.546826ms","start":"2026-04-06T17:11:40.670885Z","end":"2026-04-06T17:11:40.771432Z","steps":["trace[1936421395] 'read index received' (duration: 100.388641ms)","trace[1936421395] 'applied index is now lower than readState.Index' (duration: 157.245µs)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:11:40.771634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.740903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-06T17:11:40.771674Z","caller":"traceutil/trace.go:171","msg":"trace[1285358678] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:1274; }","duration":"100.833006ms","start":"2026-04-06T17:11:40.670831Z","end":"2026-04-06T17:11:40.771664Z","steps":["trace[1285358678] 'agreement among raft nodes before linearized reading' (duration: 100.693531ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:40.771723Z","caller":"traceutil/trace.go:171","msg":"trace[969740693] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"207.48533ms","start":"2026-04-06T17:11:40.564224Z","end":"2026-04-06T17:11:40.77171Z","steps":["trace[969740693] 'process raft request' (duration: 207.048826ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:45.125864Z","caller":"traceutil/trace.go:171","msg":"trace[280594544] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"342.430079ms","start":"2026-04-06T17:11:44.783402Z","end":"2026-04-06T17:11:45.125832Z","steps":["trace[280594544] 'process raft request' (duration: 342.227943ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:45.125793Z","caller":"traceutil/trace.go:171","msg":"trace[793735526] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1357; }","duration":"226.083175ms","start":"2026-04-06T17:11:44.899688Z","end":"2026-04-06T17:11:45.125771Z","steps":["trace[793735526] 'read index received' (duration: 225.868588ms)","trace[793735526] 'applied index is now lower than readState.Index' (duration: 213.767µs)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:11:45.126045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.339313ms","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-06T17:11:45.126092Z","caller":"traceutil/trace.go:171","msg":"trace[1117890816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1302; }","duration":"226.441036ms","start":"2026-04-06T17:11:44.899639Z","end":"2026-04-06T17:11:45.12608Z","steps":["trace[1117890816] 'agreement among raft nodes before linearized reading' (duration: 226.277791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:45.126012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:44.783374Z","time spent":"342.566403ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:11:45.126303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.015901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-06T17:11:45.126348Z","caller":"traceutil/trace.go:171","msg":"trace[2138687436] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1302; }","duration":"168.088214ms","start":"2026-04-06T17:11:44.958249Z","end":"2026-04-06T17:11:45.126337Z","steps":["trace[2138687436] 'agreement among raft nodes before linearized reading' (duration: 167.947999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:45.126326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.873915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-06T17:11:45.126366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.032662ms","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-06T17:11:45.126405Z","caller":"traceutil/trace.go:171","msg":"trace[890705442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1302; }","duration":"177.964358ms","start":"2026-04-06T17:11:44.948418Z","end":"2026-04-06T17:11:45.126383Z","steps":["trace[890705442] 'agreement among raft nodes before linearized reading' (duration: 177.747221ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:45.12645Z","caller":"traceutil/trace.go:171","msg":"trace[707222138] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1302; }","duration":"168.150545ms","start":"2026-04-06T17:11:44.958281Z","end":"2026-04-06T17:11:45.126432Z","steps":["trace[707222138] 'agreement among raft nodes before linearized reading' (duration: 167.898538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:45.126558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.598049ms","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-06T17:11:45.126639Z","caller":"traceutil/trace.go:171","msg":"trace[89989024] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1302; }","duration":"168.726513ms","start":"2026-04-06T17:11:44.957899Z","end":"2026-04-06T17:11:45.126625Z","steps":["trace[89989024] 'agreement among raft nodes before linearized reading' (duration: 168.417224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:45.965654Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125953864015285026,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-06T17:11:46.105578Z","caller":"traceutil/trace.go:171","msg":"trace[553751509] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"975.489697ms","start":"2026-04-06T17:11:45.130063Z","end":"2026-04-06T17:11:46.105553Z","steps":["trace[553751509] 'process raft request' (duration: 972.737642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:46.106467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:45.130038Z","time spent":"975.704604ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:11:46.118459Z","caller":"traceutil/trace.go:171","msg":"trace[1909562249] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"987.166488ms","start":"2026-04-06T17:11:45.131271Z","end":"2026-04-06T17:11:46.118437Z","steps":["trace[1909562249] 'process raft request' (duration: 986.801516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:46.118645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:45.131254Z","time spent":"987.261901ms","remote":"127.0.0.1:57316","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-06T17:11:46.118646Z","caller":"traceutil/trace.go:171","msg":"trace[1474810564] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"987.211709ms","start":"2026-04-06T17:11:45.131418Z","end":"2026-04-06T17:11:46.11863Z","steps":["trace[1474810564] 'process raft request' (duration: 987.031664ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:46.118692Z","caller":"traceutil/trace.go:171","msg":"trace[749991567] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"986.767306ms","start":"2026-04-06T17:11:45.131897Z","end":"2026-04-06T17:11:46.118665Z","steps":["trace[749991567] 'process raft request' (duration: 986.60257ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:46.118811Z","caller":"traceutil/trace.go:171","msg":"trace[557060579] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"987.391794ms","start":"2026-04-06T17:11:45.131392Z","end":"2026-04-06T17:11:46.118783Z","steps":["trace[557060579] 'process raft request' (duration: 986.825107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:46.118874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:45.131873Z","time spent":"986.90694ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:11:46.118941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:45.131366Z","time spent":"987.516288ms","remote":"127.0.0.1:57316","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-06T17:11:46.119001Z","caller":"traceutil/trace.go:171","msg":"trace[896542346] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"982.987119ms","start":"2026-04-06T17:11:45.136003Z","end":"2026-04-06T17:11:46.118991Z","steps":["trace[896542346] 'process raft request' (duration: 982.525534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:46.119062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:45.135969Z","time spent":"983.062671ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":456,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:11:46.119641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:45.131392Z","time spent":"987.356433ms","remote":"127.0.0.1:57316","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-06T17:11:46.124315Z","caller":"traceutil/trace.go:171","msg":"trace[220712537] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1358; }","duration":"659.004689ms","start":"2026-04-06T17:11:45.46527Z","end":"2026-04-06T17:11:46.124275Z","steps":["trace[220712537] 'read index received' (duration: 637.545526ms)","trace[220712537] 'applied index is now lower than readState.Index' (duration: 21.457083ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:11:46.124565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.250137ms","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":"warn","ts":"2026-04-06T17:11:46.124579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.927581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-04-06T17:11:46.124611Z","caller":"traceutil/trace.go:171","msg":"trace[1041963606] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1308; }","duration":"659.37382ms","start":"2026-04-06T17:11:45.465225Z","end":"2026-04-06T17:11:46.124599Z","steps":["trace[1041963606] 'agreement among raft nodes before linearized reading' (duration: 659.245746ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:11:46.124635Z","caller":"traceutil/trace.go:171","msg":"trace[738701606] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1308; }","duration":"217.023694ms","start":"2026-04-06T17:11:45.907596Z","end":"2026-04-06T17:11:46.12462Z","steps":["trace[738701606] 'agreement among raft nodes before linearized reading' (duration: 216.9044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:11:46.124659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:11:45.465204Z","time spent":"659.444763ms","remote":"127.0.0.1:57356","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":"warn","ts":"2026-04-06T17:11:46.124925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.478647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:44092"} {"level":"info","ts":"2026-04-06T17:11:46.124966Z","caller":"traceutil/trace.go:171","msg":"trace[1852108252] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1308; }","duration":"215.55723ms","start":"2026-04-06T17:11:45.909397Z","end":"2026-04-06T17:11:46.124955Z","steps":["trace[1852108252] 'agreement among raft nodes before linearized reading' (duration: 215.425636ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:12:50.612198Z","caller":"traceutil/trace.go:171","msg":"trace[1540798165] transaction","detail":"{read_only:false; response_revision:1617; number_of_response:1; }","duration":"159.779862ms","start":"2026-04-06T17:12:50.452392Z","end":"2026-04-06T17:12:50.612172Z","steps":["trace[1540798165] 'process raft request' (duration: 159.496743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:12:50.61226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.194253ms","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-06T17:12:50.612471Z","caller":"traceutil/trace.go:171","msg":"trace[1387166579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1618; }","duration":"159.423539ms","start":"2026-04-06T17:12:50.45303Z","end":"2026-04-06T17:12:50.612454Z","steps":["trace[1387166579] 'agreement among raft nodes before linearized reading' (duration: 159.12258ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:12:50.612304Z","caller":"traceutil/trace.go:171","msg":"trace[432748723] transaction","detail":"{read_only:false; response_revision:1618; number_of_response:1; }","duration":"159.75136ms","start":"2026-04-06T17:12:50.452541Z","end":"2026-04-06T17:12:50.612292Z","steps":["trace[432748723] 'process raft request' (duration: 159.578405ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:13:01.581315Z","caller":"traceutil/trace.go:171","msg":"trace[1999060953] transaction","detail":"{read_only:false; response_revision:1670; number_of_response:1; }","duration":"153.655746ms","start":"2026-04-06T17:13:01.42764Z","end":"2026-04-06T17:13:01.581296Z","steps":["trace[1999060953] 'process raft request' (duration: 153.542133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:13:05.817099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.064314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-06T17:13:05.817174Z","caller":"traceutil/trace.go:171","msg":"trace[1933197158] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1687; }","duration":"179.206239ms","start":"2026-04-06T17:13:05.637951Z","end":"2026-04-06T17:13:05.817157Z","steps":["trace[1933197158] 'range keys from in-memory index tree' (duration: 178.828807ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:13:05.97426Z","caller":"traceutil/trace.go:171","msg":"trace[1779217780] transaction","detail":"{read_only:false; response_revision:1688; number_of_response:1; }","duration":"152.079446ms","start":"2026-04-06T17:13:05.822152Z","end":"2026-04-06T17:13:05.974232Z","steps":["trace[1779217780] 'process raft request' (duration: 151.919161ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:13:08.92523Z","caller":"traceutil/trace.go:171","msg":"trace[2048925032] transaction","detail":"{read_only:false; response_revision:1701; number_of_response:1; }","duration":"160.282478ms","start":"2026-04-06T17:13:08.764917Z","end":"2026-04-06T17:13:08.925199Z","steps":["trace[2048925032] 'process raft request' (duration: 131.172522ms)","trace[2048925032] 'compare' (duration: 28.61917ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:13:08.925626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.898366ms","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-06T17:13:08.925699Z","caller":"traceutil/trace.go:171","msg":"trace[1028748507] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1701; }","duration":"138.015529ms","start":"2026-04-06T17:13:08.787668Z","end":"2026-04-06T17:13:08.925683Z","steps":["trace[1028748507] 'agreement among raft nodes before linearized reading' (duration: 137.814873ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:13:08.925992Z","caller":"traceutil/trace.go:171","msg":"trace[26908375] linearizableReadLoop","detail":"{readStateIndex:1774; appliedIndex:1773; }","duration":"137.178052ms","start":"2026-04-06T17:13:08.787716Z","end":"2026-04-06T17:13:08.924894Z","steps":["trace[26908375] 'read index received' (duration: 108.296914ms)","trace[26908375] 'applied index is now lower than readState.Index' (duration: 28.880148ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:13:08.926218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.084234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-06T17:13:08.926261Z","caller":"traceutil/trace.go:171","msg":"trace[618786764] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1701; }","duration":"117.205297ms","start":"2026-04-06T17:13:08.809045Z","end":"2026-04-06T17:13:08.92625Z","steps":["trace[618786764] 'agreement among raft nodes before linearized reading' (duration: 117.068553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:13:08.926318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.390202ms","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-06T17:13:08.926385Z","caller":"traceutil/trace.go:171","msg":"trace[422312886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1701; }","duration":"116.475734ms","start":"2026-04-06T17:13:08.809893Z","end":"2026-04-06T17:13:08.926369Z","steps":["trace[422312886] 'agreement among raft nodes before linearized reading' (duration: 116.274108ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:13:09.229852Z","caller":"traceutil/trace.go:171","msg":"trace[1526343218] transaction","detail":"{read_only:false; response_revision:1703; number_of_response:1; }","duration":"300.31583ms","start":"2026-04-06T17:13:08.929508Z","end":"2026-04-06T17:13:09.229823Z","steps":["trace[1526343218] 'process raft request' (duration: 300.103384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:13:09.230113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:13:08.929487Z","time spent":"300.539848ms","remote":"127.0.0.1:57316","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-06T17:13:09.230161Z","caller":"traceutil/trace.go:171","msg":"trace[51120485] transaction","detail":"{read_only:false; response_revision:1704; number_of_response:1; }","duration":"299.812514ms","start":"2026-04-06T17:13:08.930319Z","end":"2026-04-06T17:13:09.230132Z","steps":["trace[51120485] 'process raft request' (duration: 299.449543ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:13:26.889927Z","caller":"traceutil/trace.go:171","msg":"trace[633278553] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"153.105209ms","start":"2026-04-06T17:13:26.73679Z","end":"2026-04-06T17:13:26.889895Z","steps":["trace[633278553] 'process raft request' (duration: 152.945684ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:13:31.603219Z","caller":"traceutil/trace.go:171","msg":"trace[1816130028] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"124.205801ms","start":"2026-04-06T17:13:31.478988Z","end":"2026-04-06T17:13:31.603194Z","steps":["trace[1816130028] 'process raft request' (duration: 124.027525ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:15:03.35963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.604614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-04-06T17:15:03.359717Z","caller":"traceutil/trace.go:171","msg":"trace[1049916414] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2249; }","duration":"156.726077ms","start":"2026-04-06T17:15:03.202971Z","end":"2026-04-06T17:15:03.359697Z","steps":["trace[1049916414] 'range keys from in-memory index tree' (duration: 156.47481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:15:12.16796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.9893ms","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-06T17:15:12.16803Z","caller":"traceutil/trace.go:171","msg":"trace[1430324383] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2293; }","duration":"198.061793ms","start":"2026-04-06T17:15:11.969951Z","end":"2026-04-06T17:15:12.168013Z","steps":["trace[1430324383] 'range keys from in-memory index tree' (duration: 197.794034ms)"],"step_count":1}