{"level":"warn","ts":"2026-05-29T14:03:00.097798Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T14:03:00.098005Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-05-29T14:03:00.098103Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T14:03:00.09813Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-05-29T14:03:00.098175Z","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-05-29T14:03:00.098904Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-05-29T14:03:00.099021Z","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.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-05-29T14:03:00.597372Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"498.075541ms"} {"level":"info","ts":"2026-05-29T14:03:01.45382Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-05-29T14:03:01.453972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T14:03:01.454003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-05-29T14:03:01.454238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T14:03:01.454259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-05-29T14:03:01.454373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-05-29T14:03:01.460974Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T14:03:01.462943Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T14:03:01.464332Z","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-05-29T14:03:01.468514Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T14:03:01.468856Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T14:03:01.46976Z","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-05-29T14:03:01.469904Z","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-05-29T14:03:01.469949Z","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-05-29T14:03:01.472165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-05-29T14:03:01.472697Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-05-29T14:03:01.477465Z","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-05-29T14:03:01.477731Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T14:03:01.477773Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T14:03:01.477924Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-05-29T14:03:01.477946Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-05-29T14:03:01.854697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T14:03:01.854799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T14:03:01.854871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-05-29T14:03:01.854895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-05-29T14:03:01.854906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-05-29T14:03:01.854933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-05-29T14:03:01.854951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-05-29T14:03:01.875654Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T14:03:01.875791Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T14:03:01.87583Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T14:03:01.875882Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T14:03:01.876047Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T14:03:01.876264Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T14:03:01.876848Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T14:03:01.876922Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T14:03:01.876942Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T14:03:01.87755Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T14:03:01.878392Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"warn","ts":"2026-05-29T14:03:27.656158Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398341022823731716,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T14:03:27.81132Z","caller":"traceutil/trace.go:171","msg":"trace[1168922745] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:479; }","duration":"655.487916ms","start":"2026-05-29T14:03:27.155804Z","end":"2026-05-29T14:03:27.811292Z","steps":["trace[1168922745] 'read index received' (duration: 655.275741ms)","trace[1168922745] 'applied index is now lower than readState.Index' (duration: 211.145µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:03:27.811454Z","caller":"traceutil/trace.go:171","msg":"trace[1559351505] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"698.028315ms","start":"2026-05-29T14:03:27.113415Z","end":"2026-05-29T14:03:27.811443Z","steps":["trace[1559351505] 'process raft request' (duration: 697.732469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:27.811754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.917865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:03:27.811884Z","caller":"traceutil/trace.go:171","msg":"trace[904245994] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:467; }","duration":"656.14701ms","start":"2026-05-29T14:03:27.155717Z","end":"2026-05-29T14:03:27.811864Z","steps":["trace[904245994] 'agreement among raft nodes before linearized reading' (duration: 655.951655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:27.811727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.70592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-29T14:03:27.811964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:27.155697Z","time spent":"656.254942ms","remote":"127.0.0.1:33646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T14:03:27.812033Z","caller":"traceutil/trace.go:171","msg":"trace[1056726579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:467; }","duration":"344.063558ms","start":"2026-05-29T14:03:27.467954Z","end":"2026-05-29T14:03:27.812018Z","steps":["trace[1056726579] 'agreement among raft nodes before linearized reading' (duration: 343.69497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:27.812074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:27.467935Z","time spent":"344.127639ms","remote":"127.0.0.1:33944","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-05-29T14:03:27.812305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:27.11339Z","time spent":"698.20484ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:03:37.973032Z","caller":"traceutil/trace.go:171","msg":"trace[1883089626] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"107.556873ms","start":"2026-05-29T14:03:37.865456Z","end":"2026-05-29T14:03:37.973013Z","steps":["trace[1883089626] 'process raft request' (duration: 107.455271ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:38.032104Z","caller":"traceutil/trace.go:171","msg":"trace[1763973470] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"139.772003ms","start":"2026-05-29T14:03:37.892307Z","end":"2026-05-29T14:03:38.032079Z","steps":["trace[1763973470] 'process raft request' (duration: 139.557729ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:41.018269Z","caller":"traceutil/trace.go:171","msg":"trace[545079633] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"154.162904ms","start":"2026-05-29T14:03:40.864078Z","end":"2026-05-29T14:03:41.018241Z","steps":["trace[545079633] 'process raft request' (duration: 153.946899ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:41.050403Z","caller":"traceutil/trace.go:171","msg":"trace[949757323] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"103.704937ms","start":"2026-05-29T14:03:40.946653Z","end":"2026-05-29T14:03:41.050357Z","steps":["trace[949757323] 'process raft request' (duration: 97.118869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:41.819878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:41.459545Z","time spent":"360.32451ms","remote":"127.0.0.1:49054","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-29T14:03:41.819983Z","caller":"traceutil/trace.go:171","msg":"trace[1867258447] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"395.786613ms","start":"2026-05-29T14:03:41.424177Z","end":"2026-05-29T14:03:41.819963Z","steps":["trace[1867258447] 'read index received' (duration: 390.824213ms)","trace[1867258447] 'applied index is now lower than readState.Index' (duration: 4.9619ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:03:41.820295Z","caller":"traceutil/trace.go:171","msg":"trace[1525111578] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"412.590349ms","start":"2026-05-29T14:03:41.407637Z","end":"2026-05-29T14:03:41.820227Z","steps":["trace[1525111578] 'process raft request' (duration: 407.317262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:41.820666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:41.407614Z","time spent":"412.850144ms","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4059,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:03:41.821056Z","caller":"traceutil/trace.go:171","msg":"trace[885465459] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"243.326885ms","start":"2026-05-29T14:03:41.577689Z","end":"2026-05-29T14:03:41.821016Z","steps":["trace[885465459] 'process raft request' (duration: 242.750662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:41.821572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.329118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-05-29T14:03:41.821771Z","caller":"traceutil/trace.go:171","msg":"trace[1750340967] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:629; }","duration":"397.605354ms","start":"2026-05-29T14:03:41.424136Z","end":"2026-05-29T14:03:41.821742Z","steps":["trace[1750340967] 'agreement among raft nodes before linearized reading' (duration: 396.181672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:41.821837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:41.424115Z","time spent":"397.709746ms","remote":"127.0.0.1:33716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29592,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-05-29T14:03:41.821437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.712754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:03:41.822136Z","caller":"traceutil/trace.go:171","msg":"trace[1801953371] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:631; }","duration":"248.477581ms","start":"2026-05-29T14:03:41.573631Z","end":"2026-05-29T14:03:41.822109Z","steps":["trace[1801953371] 'agreement among raft nodes before linearized reading' (duration: 247.295584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:48.535355Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398341022823732179,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T14:03:48.593308Z","caller":"traceutil/trace.go:171","msg":"trace[1732892795] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"721.47957ms","start":"2026-05-29T14:03:47.871771Z","end":"2026-05-29T14:03:48.593251Z","steps":["trace[1732892795] 'process raft request' (duration: 719.413614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:48.59359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:47.871728Z","time spent":"721.724336ms","remote":"127.0.0.1:33758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:03:48.695181Z","caller":"traceutil/trace.go:171","msg":"trace[1396642003] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:711; }","duration":"660.492458ms","start":"2026-05-29T14:03:48.034625Z","end":"2026-05-29T14:03:48.695117Z","steps":["trace[1396642003] 'read index received' (duration: 548.946405ms)","trace[1396642003] 'applied index is now lower than readState.Index' (duration: 111.544253ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:03:48.695295Z","caller":"traceutil/trace.go:171","msg":"trace[1629261525] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"553.039458ms","start":"2026-05-29T14:03:48.142209Z","end":"2026-05-29T14:03:48.695248Z","steps":["trace[1629261525] 'process raft request' (duration: 486.637324ms)","trace[1629261525] 'compare' (duration: 66.127617ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:03:48.695436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.792045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zt24w\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-29T14:03:48.695486Z","caller":"traceutil/trace.go:171","msg":"trace[133338239] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zt24w; range_end:; response_count:1; response_revision:692; }","duration":"660.919978ms","start":"2026-05-29T14:03:48.034553Z","end":"2026-05-29T14:03:48.695473Z","steps":["trace[133338239] 'agreement among raft nodes before linearized reading' (duration: 660.737814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:48.695478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:48.142183Z","time spent":"553.19316ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:03:48.695565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:48.034517Z","time spent":"661.03791ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-zt24w\" "} {"level":"info","ts":"2026-05-29T14:03:48.871848Z","caller":"traceutil/trace.go:171","msg":"trace[1827298151] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:713; }","duration":"176.538214ms","start":"2026-05-29T14:03:48.695266Z","end":"2026-05-29T14:03:48.871804Z","steps":["trace[1827298151] 'read index received' (duration: 124.868769ms)","trace[1827298151] 'applied index is now lower than readState.Index' (duration: 51.667315ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:03:48.872053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.511302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T14:03:48.872067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.595579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T14:03:48.872167Z","caller":"traceutil/trace.go:171","msg":"trace[2108383603] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:693; }","duration":"211.737462ms","start":"2026-05-29T14:03:48.660406Z","end":"2026-05-29T14:03:48.872143Z","steps":["trace[2108383603] 'agreement among raft nodes before linearized reading' (duration: 211.411475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:48.872241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.50161ms","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-05-29T14:03:48.872279Z","caller":"traceutil/trace.go:171","msg":"trace[1773704627] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:693; }","duration":"759.619143ms","start":"2026-05-29T14:03:48.112651Z","end":"2026-05-29T14:03:48.87227Z","steps":["trace[1773704627] 'agreement among raft nodes before linearized reading' (duration: 759.471079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:48.872321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:48.112628Z","time spent":"759.683954ms","remote":"127.0.0.1:33944","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":"info","ts":"2026-05-29T14:03:48.872548Z","caller":"traceutil/trace.go:171","msg":"trace[1688227531] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"209.051591ms","start":"2026-05-29T14:03:48.663475Z","end":"2026-05-29T14:03:48.872527Z","steps":["trace[1688227531] 'process raft request' (duration: 156.531487ms)","trace[1688227531] 'compare' (duration: 51.361288ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:03:48.872099Z","caller":"traceutil/trace.go:171","msg":"trace[828138219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:693; }","duration":"717.604494ms","start":"2026-05-29T14:03:48.154483Z","end":"2026-05-29T14:03:48.872087Z","steps":["trace[828138219] 'agreement among raft nodes before linearized reading' (duration: 717.498451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:48.872751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:48.154471Z","time spent":"718.267108ms","remote":"127.0.0.1:33662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T14:03:48.872953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.064751ms","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-05-29T14:03:48.873104Z","caller":"traceutil/trace.go:171","msg":"trace[1789958746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:693; }","duration":"832.329007ms","start":"2026-05-29T14:03:48.040704Z","end":"2026-05-29T14:03:48.873033Z","steps":["trace[1789958746] 'agreement among raft nodes before linearized reading' (duration: 831.749115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:48.873457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:48.040676Z","time spent":"832.764736ms","remote":"127.0.0.1:33944","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":"info","ts":"2026-05-29T14:03:49.344338Z","caller":"traceutil/trace.go:171","msg":"trace[699960802] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:714; }","duration":"472.360914ms","start":"2026-05-29T14:03:48.871945Z","end":"2026-05-29T14:03:49.344306Z","steps":["trace[699960802] 'read index received' (duration: 472.228231ms)","trace[699960802] 'applied index is now lower than readState.Index' (duration: 131.273µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:03:49.344531Z","caller":"traceutil/trace.go:171","msg":"trace[1352738187] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"632.759598ms","start":"2026-05-29T14:03:48.711735Z","end":"2026-05-29T14:03:49.344495Z","steps":["trace[1352738187] 'process raft request' (duration: 632.38113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:49.344718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:48.711702Z","time spent":"632.914382ms","remote":"127.0.0.1:33852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:03:49.34491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.762514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T14:03:49.345008Z","caller":"traceutil/trace.go:171","msg":"trace[251803919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:694; }","duration":"575.930388ms","start":"2026-05-29T14:03:48.769052Z","end":"2026-05-29T14:03:49.344982Z","steps":["trace[251803919] 'agreement among raft nodes before linearized reading' (duration: 575.518889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:49.345072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:48.769036Z","time spent":"576.021211ms","remote":"127.0.0.1:33944","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-05-29T14:03:49.396877Z","caller":"traceutil/trace.go:171","msg":"trace[222644562] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"518.290851ms","start":"2026-05-29T14:03:48.878554Z","end":"2026-05-29T14:03:49.396844Z","steps":["trace[222644562] 'process raft request' (duration: 518.047815ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:49.39698Z","caller":"traceutil/trace.go:171","msg":"trace[1387257103] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"518.191009ms","start":"2026-05-29T14:03:48.878761Z","end":"2026-05-29T14:03:49.396952Z","steps":["trace[1387257103] 'process raft request' (duration: 517.980864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:49.397044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:48.878516Z","time spent":"518.439994ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:03:49.397102Z","caller":"traceutil/trace.go:171","msg":"trace[674389431] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"515.904208ms","start":"2026-05-29T14:03:48.881175Z","end":"2026-05-29T14:03:49.39708Z","steps":["trace[674389431] 'process raft request' (duration: 515.646912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:49.397131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:48.878754Z","time spent":"518.295991ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:03:49.397197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:48.881159Z","time spent":"515.989569ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:03:49.397369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.347574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T14:03:49.397421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.012355ms","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-05-29T14:03:49.397462Z","caller":"traceutil/trace.go:171","msg":"trace[1358540109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:697; }","duration":"242.488828ms","start":"2026-05-29T14:03:49.15495Z","end":"2026-05-29T14:03:49.397439Z","steps":["trace[1358540109] 'agreement among raft nodes before linearized reading' (duration: 242.335794ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:03:49.397472Z","caller":"traceutil/trace.go:171","msg":"trace[1153158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:697; }","duration":"275.120067ms","start":"2026-05-29T14:03:49.122339Z","end":"2026-05-29T14:03:49.397459Z","steps":["trace[1153158] 'agreement among raft nodes before linearized reading' (duration: 274.973024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:49.39733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.258522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-29T14:03:49.397837Z","caller":"traceutil/trace.go:171","msg":"trace[1678440607] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:697; }","duration":"519.569048ms","start":"2026-05-29T14:03:48.878039Z","end":"2026-05-29T14:03:49.397608Z","steps":["trace[1678440607] 'agreement among raft nodes before linearized reading' (duration: 519.244161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:49.397889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:48.878017Z","time spent":"519.859875ms","remote":"127.0.0.1:33868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-29T14:03:50.362769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.760852ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T14:03:50.362923Z","caller":"traceutil/trace.go:171","msg":"trace[414436580] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"1.012969244s","start":"2026-05-29T14:03:49.34991Z","end":"2026-05-29T14:03:50.362879Z","steps":["trace[414436580] 'process raft request' (duration: 219.845514ms)","trace[414436580] 'compare' (duration: 792.538057ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:03:50.363034Z","caller":"traceutil/trace.go:171","msg":"trace[1345627238] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"961.417641ms","start":"2026-05-29T14:03:49.401597Z","end":"2026-05-29T14:03:50.363014Z","steps":["trace[1345627238] 'process raft request' (duration: 961.287668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:50.363105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:49.401581Z","time spent":"961.491752ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:03:50.363178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:49.349886Z","time spent":"1.013163478s","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:03:50.363295Z","caller":"traceutil/trace.go:171","msg":"trace[74478537] linearizableReadLoop","detail":"{readStateIndex:719; appliedIndex:718; }","duration":"963.648181ms","start":"2026-05-29T14:03:49.399636Z","end":"2026-05-29T14:03:50.363284Z","steps":["trace[74478537] 'read index received' (duration: 170.130242ms)","trace[74478537] 'applied index is now lower than readState.Index' (duration: 793.516979ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:03:50.363585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.935747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:03:50.363671Z","caller":"traceutil/trace.go:171","msg":"trace[2107195521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:699; }","duration":"964.08075ms","start":"2026-05-29T14:03:49.399571Z","end":"2026-05-29T14:03:50.363652Z","steps":["trace[2107195521] 'agreement among raft nodes before linearized reading' (duration: 963.895906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:50.363732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:49.399539Z","time spent":"964.185383ms","remote":"127.0.0.1:33662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T14:03:50.813915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.186695ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-29T14:03:50.814119Z","caller":"traceutil/trace.go:171","msg":"trace[71864776] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:720; }","duration":"450.769852ms","start":"2026-05-29T14:03:50.363325Z","end":"2026-05-29T14:03:50.814095Z","steps":["trace[71864776] 'read index received' (duration: 339.391423ms)","trace[71864776] 'applied index is now lower than readState.Index' (duration: 111.376299ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:03:50.8143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.412623233s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-29T14:03:50.814391Z","caller":"traceutil/trace.go:171","msg":"trace[779242712] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:699; }","duration":"1.412742515s","start":"2026-05-29T14:03:49.401636Z","end":"2026-05-29T14:03:50.814378Z","steps":["trace[779242712] 'agreement among raft nodes before linearized reading' (duration: 1.412570632s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:50.814443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:49.40163Z","time spent":"1.412801386s","remote":"127.0.0.1:33868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-29T14:03:50.814592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.064778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:03:50.814687Z","caller":"traceutil/trace.go:171","msg":"trace[1289932297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:699; }","duration":"442.229862ms","start":"2026-05-29T14:03:50.372437Z","end":"2026-05-29T14:03:50.814667Z","steps":["trace[1289932297] 'agreement among raft nodes before linearized reading' (duration: 441.826323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:03:50.814831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:03:50.3724Z","time spent":"442.417766ms","remote":"127.0.0.1:33646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T14:04:03.331926Z","caller":"traceutil/trace.go:171","msg":"trace[23906764] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"187.10185ms","start":"2026-05-29T14:04:03.144802Z","end":"2026-05-29T14:04:03.331904Z","steps":["trace[23906764] 'process raft request' (duration: 186.977188ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:04:06.283221Z","caller":"traceutil/trace.go:171","msg":"trace[2064600882] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"120.834679ms","start":"2026-05-29T14:04:06.162352Z","end":"2026-05-29T14:04:06.283186Z","steps":["trace[2064600882] 'process raft request' (duration: 120.566704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:06.662912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.005916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T14:04:06.66303Z","caller":"traceutil/trace.go:171","msg":"trace[323658711] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:817; }","duration":"277.222461ms","start":"2026-05-29T14:04:06.385781Z","end":"2026-05-29T14:04:06.663003Z","steps":["trace[323658711] 'count revisions from in-memory index tree' (duration: 276.837442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:06.663567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.564332ms","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-05-29T14:04:06.663685Z","caller":"traceutil/trace.go:171","msg":"trace[41743501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:817; }","duration":"225.783387ms","start":"2026-05-29T14:04:06.437873Z","end":"2026-05-29T14:04:06.663656Z","steps":["trace[41743501] 'range keys from in-memory index tree' (duration: 225.102322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:06.663905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.772237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T14:04:06.664139Z","caller":"traceutil/trace.go:171","msg":"trace[1005190426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:817; }","duration":"226.091063ms","start":"2026-05-29T14:04:06.437991Z","end":"2026-05-29T14:04:06.664082Z","steps":["trace[1005190426] 'range keys from in-memory index tree' (duration: 224.799985ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:04:41.825252Z","caller":"traceutil/trace.go:171","msg":"trace[134785515] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1066; }","duration":"140.049818ms","start":"2026-05-29T14:04:41.685177Z","end":"2026-05-29T14:04:41.825227Z","steps":["trace[134785515] 'read index received' (duration: 139.910295ms)","trace[134785515] 'applied index is now lower than readState.Index' (duration: 138.403µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:04:41.825661Z","caller":"traceutil/trace.go:171","msg":"trace[742513325] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"144.22517ms","start":"2026-05-29T14:04:41.681412Z","end":"2026-05-29T14:04:41.825637Z","steps":["trace[742513325] 'process raft request' (duration: 143.631597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:41.825764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T14:04:41.825822Z","caller":"traceutil/trace.go:171","msg":"trace[68748750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1031; }","duration":"139.848922ms","start":"2026-05-29T14:04:41.685958Z","end":"2026-05-29T14:04:41.825807Z","steps":["trace[68748750] 'agreement among raft nodes before linearized reading' (duration: 139.690609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:41.825963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.764354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T14:04:41.825987Z","caller":"traceutil/trace.go:171","msg":"trace[216517499] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1031; }","duration":"140.820195ms","start":"2026-05-29T14:04:41.685161Z","end":"2026-05-29T14:04:41.825981Z","steps":["trace[216517499] 'agreement among raft nodes before linearized reading' (duration: 140.738813ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:04:42.464666Z","caller":"traceutil/trace.go:171","msg":"trace[1060003488] linearizableReadLoop","detail":"{readStateIndex:1070; appliedIndex:1069; }","duration":"492.731858ms","start":"2026-05-29T14:04:41.971916Z","end":"2026-05-29T14:04:42.464648Z","steps":["trace[1060003488] 'read index received' (duration: 447.697684ms)","trace[1060003488] 'applied index is now lower than readState.Index' (duration: 45.033304ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:04:42.464701Z","caller":"traceutil/trace.go:171","msg":"trace[1226504488] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"494.944037ms","start":"2026-05-29T14:04:41.969724Z","end":"2026-05-29T14:04:42.464668Z","steps":["trace[1226504488] 'process raft request' (duration: 449.871053ms)","trace[1226504488] 'compare' (duration: 44.836538ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:04:42.46479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.860971ms","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-05-29T14:04:42.464817Z","caller":"traceutil/trace.go:171","msg":"trace[488943567] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1034; }","duration":"492.918272ms","start":"2026-05-29T14:04:41.971888Z","end":"2026-05-29T14:04:42.464807Z","steps":["trace[488943567] 'agreement among raft nodes before linearized reading' (duration: 492.858871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:42.46481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.658778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T14:04:42.464835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:04:41.971877Z","time spent":"492.953233ms","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-05-29T14:04:42.464839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:04:41.969699Z","time spent":"495.065751ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:04:42.464851Z","caller":"traceutil/trace.go:171","msg":"trace[2011761549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1034; }","duration":"311.73222ms","start":"2026-05-29T14:04:42.153106Z","end":"2026-05-29T14:04:42.464838Z","steps":["trace[2011761549] 'agreement among raft nodes before linearized reading' (duration: 311.671029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:42.465017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:04:42.153072Z","time spent":"311.940644ms","remote":"127.0.0.1:33646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T14:04:43.407336Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398341022823732920,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T14:04:43.467061Z","caller":"traceutil/trace.go:171","msg":"trace[671006098] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"581.37469ms","start":"2026-05-29T14:04:42.885668Z","end":"2026-05-29T14:04:43.467043Z","steps":["trace[671006098] 'process raft request' (duration: 581.271588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:43.467157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:04:42.885651Z","time spent":"581.454223ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:04:44.04833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.18875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T14:04:44.048461Z","caller":"traceutil/trace.go:171","msg":"trace[1402680337] linearizableReadLoop","detail":"{readStateIndex:1072; appliedIndex:1070; }","duration":"1.141620282s","start":"2026-05-29T14:04:42.906818Z","end":"2026-05-29T14:04:44.048438Z","steps":["trace[1402680337] 'read index received' (duration: 560.179599ms)","trace[1402680337] 'applied index is now lower than readState.Index' (duration: 581.438793ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:04:44.048773Z","caller":"traceutil/trace.go:171","msg":"trace[2013095588] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"1.163053589s","start":"2026-05-29T14:04:42.885701Z","end":"2026-05-29T14:04:44.048755Z","steps":["trace[2013095588] 'process raft request' (duration: 624.299397ms)","trace[2013095588] 'compare' (duration: 537.957065ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:04:44.048858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:04:42.885682Z","time spent":"1.163130591s","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:04:44.048966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.142134163s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T14:04:44.049012Z","caller":"traceutil/trace.go:171","msg":"trace[1052331155] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1036; }","duration":"1.142202036s","start":"2026-05-29T14:04:42.906793Z","end":"2026-05-29T14:04:44.048995Z","steps":["trace[1052331155] 'agreement among raft nodes before linearized reading' (duration: 1.142101103s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:44.04903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.929943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-29T14:04:44.049053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:04:42.906785Z","time spent":"1.142256157s","remote":"127.0.0.1:34020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-05-29T14:04:44.049063Z","caller":"traceutil/trace.go:171","msg":"trace[31125162] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1036; }","duration":"218.975424ms","start":"2026-05-29T14:04:43.830079Z","end":"2026-05-29T14:04:44.049054Z","steps":["trace[31125162] 'agreement among raft nodes before linearized reading' (duration: 218.873562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:44.048837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.214875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T14:04:44.049082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.668502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T14:04:44.049128Z","caller":"traceutil/trace.go:171","msg":"trace[915842751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1036; }","duration":"894.564682ms","start":"2026-05-29T14:04:43.154546Z","end":"2026-05-29T14:04:44.049111Z","steps":["trace[915842751] 'agreement among raft nodes before linearized reading' (duration: 894.231775ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:04:44.049155Z","caller":"traceutil/trace.go:171","msg":"trace[1923687815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1036; }","duration":"189.776595ms","start":"2026-05-29T14:04:43.859359Z","end":"2026-05-29T14:04:44.049136Z","steps":["trace[1923687815] 'agreement among raft nodes before linearized reading' (duration: 189.58332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:44.049181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:04:43.154528Z","time spent":"894.637884ms","remote":"127.0.0.1:33646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T14:04:44.04924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.723644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:04:44.049276Z","caller":"traceutil/trace.go:171","msg":"trace[686622349] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1036; }","duration":"229.757014ms","start":"2026-05-29T14:04:43.819506Z","end":"2026-05-29T14:04:44.049263Z","steps":["trace[686622349] 'agreement among raft nodes before linearized reading' (duration: 229.706863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:04:44.049287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.049728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T14:04:44.049316Z","caller":"traceutil/trace.go:171","msg":"trace[1051817325] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1036; }","duration":"189.096408ms","start":"2026-05-29T14:04:43.860213Z","end":"2026-05-29T14:04:44.049309Z","steps":["trace[1051817325] 'agreement among raft nodes before linearized reading' (duration: 189.035217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:06.127867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.325791ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T14:05:06.127947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.775988ms","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-05-29T14:05:06.127964Z","caller":"traceutil/trace.go:171","msg":"trace[1917699808] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1107; }","duration":"329.438483ms","start":"2026-05-29T14:05:05.79851Z","end":"2026-05-29T14:05:06.127949Z","steps":["trace[1917699808] 'range keys from in-memory index tree' (duration: 329.312271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:06.127944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.201634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:05:06.127996Z","caller":"traceutil/trace.go:171","msg":"trace[546994534] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1107; }","duration":"518.8707ms","start":"2026-05-29T14:05:05.609111Z","end":"2026-05-29T14:05:06.127982Z","steps":["trace[546994534] 'range keys from in-memory index tree' (duration: 518.532023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:06.128036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:05.609056Z","time spent":"518.970703ms","remote":"127.0.0.1:33944","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-05-29T14:05:06.12804Z","caller":"traceutil/trace.go:171","msg":"trace[72485908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1107; }","duration":"973.341137ms","start":"2026-05-29T14:05:05.154665Z","end":"2026-05-29T14:05:06.128006Z","steps":["trace[72485908] 'range keys from in-memory index tree' (duration: 973.081141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:06.128106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:05.154636Z","time spent":"973.45587ms","remote":"127.0.0.1:33662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T14:05:06.128349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.547598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T14:05:06.128585Z","caller":"traceutil/trace.go:171","msg":"trace[677659351] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"455.50321ms","start":"2026-05-29T14:05:05.673053Z","end":"2026-05-29T14:05:06.128556Z","steps":["trace[677659351] 'process raft request' (duration: 142.591634ms)","trace[677659351] 'compare' (duration: 312.389554ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:05:06.128673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:05.673025Z","time spent":"455.608161ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:05:08.31327Z","caller":"traceutil/trace.go:171","msg":"trace[1192470456] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"156.707239ms","start":"2026-05-29T14:05:08.156536Z","end":"2026-05-29T14:05:08.313244Z","steps":["trace[1192470456] 'process raft request' (duration: 156.556135ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:05:08.313403Z","caller":"traceutil/trace.go:171","msg":"trace[1450404028] linearizableReadLoop","detail":"{readStateIndex:1156; appliedIndex:1155; }","duration":"147.900612ms","start":"2026-05-29T14:05:08.16547Z","end":"2026-05-29T14:05:08.313371Z","steps":["trace[1450404028] 'read index received' (duration: 147.587706ms)","trace[1450404028] 'applied index is now lower than readState.Index' (duration: 309.906µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:05:08.313594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.104007ms","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-05-29T14:05:08.313641Z","caller":"traceutil/trace.go:171","msg":"trace[1133171853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1115; }","duration":"148.187359ms","start":"2026-05-29T14:05:08.165443Z","end":"2026-05-29T14:05:08.31363Z","steps":["trace[1133171853] 'agreement among raft nodes before linearized reading' (duration: 148.044186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:08.313635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.730096ms","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-05-29T14:05:08.313712Z","caller":"traceutil/trace.go:171","msg":"trace[626571157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1115; }","duration":"106.876229ms","start":"2026-05-29T14:05:08.206823Z","end":"2026-05-29T14:05:08.3137Z","steps":["trace[626571157] 'agreement among raft nodes before linearized reading' (duration: 106.701755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:08.313782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.916419ms","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-05-29T14:05:08.313808Z","caller":"traceutil/trace.go:171","msg":"trace[1498850494] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1115; }","duration":"110.964171ms","start":"2026-05-29T14:05:08.202836Z","end":"2026-05-29T14:05:08.3138Z","steps":["trace[1498850494] 'agreement among raft nodes before linearized reading' (duration: 110.894499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:08.314186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.337999ms","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-05-29T14:05:08.314248Z","caller":"traceutil/trace.go:171","msg":"trace[2053849224] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1115; }","duration":"111.441481ms","start":"2026-05-29T14:05:08.202792Z","end":"2026-05-29T14:05:08.314234Z","steps":["trace[2053849224] 'agreement among raft nodes before linearized reading' (duration: 110.728946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:08.572186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.489539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T14:05:08.572361Z","caller":"traceutil/trace.go:171","msg":"trace[1864947128] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"254.779411ms","start":"2026-05-29T14:05:08.317557Z","end":"2026-05-29T14:05:08.572337Z","steps":["trace[1864947128] 'process raft request' (duration: 140.044778ms)","trace[1864947128] 'compare' (duration: 114.357215ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:05:09.204687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.240924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T14:05:09.205233Z","caller":"traceutil/trace.go:171","msg":"trace[1922764059] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"887.337582ms","start":"2026-05-29T14:05:08.317741Z","end":"2026-05-29T14:05:09.205079Z","steps":["trace[1922764059] 'process raft request' (duration: 648.392343ms)","trace[1922764059] 'compare' (duration: 237.677501ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:05:09.205341Z","caller":"traceutil/trace.go:171","msg":"trace[1034717830] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"887.517236ms","start":"2026-05-29T14:05:08.317782Z","end":"2026-05-29T14:05:09.2053Z","steps":["trace[1034717830] 'process raft request' (duration: 887.055046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:09.20591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:08.317768Z","time spent":"888.086328ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:05:09.205461Z","caller":"traceutil/trace.go:171","msg":"trace[1273172614] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"887.471665ms","start":"2026-05-29T14:05:08.317962Z","end":"2026-05-29T14:05:09.205434Z","steps":["trace[1273172614] 'process raft request' (duration: 887.141848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:09.206214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:08.317926Z","time spent":"888.252382ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:05:09.206649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:08.317722Z","time spent":"887.965425ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:05:09.265503Z","caller":"traceutil/trace.go:171","msg":"trace[1449677443] linearizableReadLoop","detail":"{readStateIndex:1161; appliedIndex:1157; }","duration":"636.979779ms","start":"2026-05-29T14:05:08.628498Z","end":"2026-05-29T14:05:09.265478Z","steps":["trace[1449677443] 'read index received' (duration: 337.711447ms)","trace[1449677443] 'applied index is now lower than readState.Index' (duration: 299.267352ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:05:09.26557Z","caller":"traceutil/trace.go:171","msg":"trace[894635621] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"661.905754ms","start":"2026-05-29T14:05:08.60363Z","end":"2026-05-29T14:05:09.265536Z","steps":["trace[894635621] 'process raft request' (duration: 661.71039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:09.265664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.150963ms","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":"info","ts":"2026-05-29T14:05:09.265711Z","caller":"traceutil/trace.go:171","msg":"trace[884199763] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1120; }","duration":"637.255246ms","start":"2026-05-29T14:05:08.628441Z","end":"2026-05-29T14:05:09.265696Z","steps":["trace[884199763] 'agreement among raft nodes before linearized reading' (duration: 637.162204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:09.265732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:08.603557Z","time spent":"662.082569ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:05:09.265796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.380833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:05:09.265832Z","caller":"traceutil/trace.go:171","msg":"trace[145080970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1120; }","duration":"108.441835ms","start":"2026-05-29T14:05:09.157379Z","end":"2026-05-29T14:05:09.265821Z","steps":["trace[145080970] 'agreement among raft nodes before linearized reading' (duration: 108.396604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:09.265745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:08.628422Z","time spent":"637.313777ms","remote":"127.0.0.1:34206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-29T14:05:10.536046Z","caller":"traceutil/trace.go:171","msg":"trace[396607748] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1161; }","duration":"227.367166ms","start":"2026-05-29T14:05:10.308648Z","end":"2026-05-29T14:05:10.536015Z","steps":["trace[396607748] 'read index received' (duration: 144.982638ms)","trace[396607748] 'applied index is now lower than readState.Index' (duration: 82.382718ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:05:10.536218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.537279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:05:10.536322Z","caller":"traceutil/trace.go:171","msg":"trace[1917779739] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1120; }","duration":"227.709753ms","start":"2026-05-29T14:05:10.308592Z","end":"2026-05-29T14:05:10.536301Z","steps":["trace[1917779739] 'agreement among raft nodes before linearized reading' (duration: 227.516819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:10.575885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.746902ms","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-05-29T14:05:10.575969Z","caller":"traceutil/trace.go:171","msg":"trace[1653462996] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1121; }","duration":"258.844304ms","start":"2026-05-29T14:05:10.317106Z","end":"2026-05-29T14:05:10.575951Z","steps":["trace[1653462996] 'agreement among raft nodes before linearized reading' (duration: 258.63854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:11.372159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.308252ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T14:05:11.372288Z","caller":"traceutil/trace.go:171","msg":"trace[707920698] linearizableReadLoop","detail":"{readStateIndex:1165; appliedIndex:1164; }","duration":"490.568117ms","start":"2026-05-29T14:05:10.881701Z","end":"2026-05-29T14:05:11.372269Z","steps":["trace[707920698] 'read index received' (duration: 107.063µs)","trace[707920698] 'applied index is now lower than readState.Index' (duration: 490.459354ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:05:11.372384Z","caller":"traceutil/trace.go:171","msg":"trace[1807119411] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"790.385401ms","start":"2026-05-29T14:05:10.581952Z","end":"2026-05-29T14:05:11.372338Z","steps":["trace[1807119411] 'process raft request' (duration: 57.792972ms)","trace[1807119411] 'compare' (duration: 732.011385ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:05:11.372468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.763422ms","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-05-29T14:05:11.372622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:10.581922Z","time spent":"790.585905ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:05:11.372719Z","caller":"traceutil/trace.go:171","msg":"trace[1263531669] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1123; }","duration":"491.045388ms","start":"2026-05-29T14:05:10.881617Z","end":"2026-05-29T14:05:11.372662Z","steps":["trace[1263531669] 'agreement among raft nodes before linearized reading' (duration: 490.733351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:11.372815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:10.881586Z","time spent":"491.211172ms","remote":"127.0.0.1:34068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-05-29T14:05:11.447244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.018178ms","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-05-29T14:05:11.447282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.623806ms","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-05-29T14:05:11.447309Z","caller":"traceutil/trace.go:171","msg":"trace[489893063] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1124; }","duration":"236.086339ms","start":"2026-05-29T14:05:11.211208Z","end":"2026-05-29T14:05:11.447294Z","steps":["trace[489893063] 'agreement among raft nodes before linearized reading' (duration: 235.902315ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:05:11.447337Z","caller":"traceutil/trace.go:171","msg":"trace[429481139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1124; }","duration":"234.686867ms","start":"2026-05-29T14:05:11.212635Z","end":"2026-05-29T14:05:11.447322Z","steps":["trace[429481139] 'agreement among raft nodes before linearized reading' (duration: 234.470142ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:05:11.447443Z","caller":"traceutil/trace.go:171","msg":"trace[2053973667] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"175.221038ms","start":"2026-05-29T14:05:11.27212Z","end":"2026-05-29T14:05:11.447341Z","steps":["trace[2053973667] 'process raft request' (duration: 174.8195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:11.44758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.867201ms","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-05-29T14:05:11.447693Z","caller":"traceutil/trace.go:171","msg":"trace[1134727363] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1124; }","duration":"234.999225ms","start":"2026-05-29T14:05:11.212684Z","end":"2026-05-29T14:05:11.447683Z","steps":["trace[1134727363] 'agreement among raft nodes before linearized reading' (duration: 234.84472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:11.447897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.474853ms","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":"info","ts":"2026-05-29T14:05:11.447954Z","caller":"traceutil/trace.go:171","msg":"trace[1299588213] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1124; }","duration":"316.592646ms","start":"2026-05-29T14:05:11.131346Z","end":"2026-05-29T14:05:11.447938Z","steps":["trace[1299588213] 'agreement among raft nodes before linearized reading' (duration: 316.512035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:11.44799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:11.13131Z","time spent":"316.670487ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-05-29T14:05:11.448077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.41553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:05:11.44814Z","caller":"traceutil/trace.go:171","msg":"trace[22339973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1124; }","duration":"292.501332ms","start":"2026-05-29T14:05:11.15562Z","end":"2026-05-29T14:05:11.448121Z","steps":["trace[22339973] 'agreement among raft nodes before linearized reading' (duration: 292.418611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:11.71926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.699779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T14:05:11.719686Z","caller":"traceutil/trace.go:171","msg":"trace[2069885583] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"265.852841ms","start":"2026-05-29T14:05:11.453795Z","end":"2026-05-29T14:05:11.719648Z","steps":["trace[2069885583] 'process raft request' (duration: 265.649946ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:05:11.719691Z","caller":"traceutil/trace.go:171","msg":"trace[820085204] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"266.606548ms","start":"2026-05-29T14:05:11.453027Z","end":"2026-05-29T14:05:11.719634Z","steps":["trace[820085204] 'process raft request' (duration: 58.414077ms)","trace[820085204] 'compare' (duration: 207.488675ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:05:11.719907Z","caller":"traceutil/trace.go:171","msg":"trace[802528544] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"265.877411ms","start":"2026-05-29T14:05:11.454006Z","end":"2026-05-29T14:05:11.719883Z","steps":["trace[802528544] 'process raft request' (duration: 265.534473ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:05:31.851415Z","caller":"traceutil/trace.go:171","msg":"trace[345709529] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"240.040938ms","start":"2026-05-29T14:05:31.611344Z","end":"2026-05-29T14:05:31.851385Z","steps":["trace[345709529] 'process raft request' (duration: 239.839123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:32.771107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.245923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T14:05:32.771227Z","caller":"traceutil/trace.go:171","msg":"trace[1315510352] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1287; }","duration":"898.687363ms","start":"2026-05-29T14:05:31.872516Z","end":"2026-05-29T14:05:32.771204Z","steps":["trace[1315510352] 'read index received' (duration: 247.222586ms)","trace[1315510352] 'applied index is now lower than readState.Index' (duration: 651.463317ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:05:32.771331Z","caller":"traceutil/trace.go:171","msg":"trace[1591611186] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"1.157395735s","start":"2026-05-29T14:05:31.613925Z","end":"2026-05-29T14:05:32.771321Z","steps":["trace[1591611186] 'process raft request' (duration: 505.820956ms)","trace[1591611186] 'compare' (duration: 650.576707ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:05:32.771386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:31.613885Z","time spent":"1.157474887s","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:05:32.771808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.598725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:05:32.771914Z","caller":"traceutil/trace.go:171","msg":"trace[1373053908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1240; }","duration":"616.759868ms","start":"2026-05-29T14:05:32.155117Z","end":"2026-05-29T14:05:32.771877Z","steps":["trace[1373053908] 'agreement among raft nodes before linearized reading' (duration: 616.593294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:32.771939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.241341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T14:05:32.771985Z","caller":"traceutil/trace.go:171","msg":"trace[752776027] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1240; }","duration":"869.292552ms","start":"2026-05-29T14:05:31.902681Z","end":"2026-05-29T14:05:32.771974Z","steps":["trace[752776027] 'agreement among raft nodes before linearized reading' (duration: 869.17979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:32.771921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.277172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T14:05:32.772131Z","caller":"traceutil/trace.go:171","msg":"trace[632756084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1240; }","duration":"869.542958ms","start":"2026-05-29T14:05:31.902532Z","end":"2026-05-29T14:05:32.772075Z","steps":["trace[632756084] 'agreement among raft nodes before linearized reading' (duration: 869.101418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:32.771989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:32.15509Z","time spent":"616.871841ms","remote":"127.0.0.1:33646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T14:05:32.772215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:31.902498Z","time spent":"869.677211ms","remote":"127.0.0.1:33944","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-05-29T14:05:32.772018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:31.902664Z","time spent":"869.347654ms","remote":"127.0.0.1:33944","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-05-29T14:05:32.773362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.127553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T14:05:32.773432Z","caller":"traceutil/trace.go:171","msg":"trace[1074646440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1240; }","duration":"900.977675ms","start":"2026-05-29T14:05:31.872438Z","end":"2026-05-29T14:05:32.773416Z","steps":["trace[1074646440] 'agreement among raft nodes before linearized reading' (duration: 899.008651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:32.773477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:31.872398Z","time spent":"901.067467ms","remote":"127.0.0.1:33944","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-05-29T14:05:45.306631Z","caller":"traceutil/trace.go:171","msg":"trace[1148697954] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1333; }","duration":"149.225671ms","start":"2026-05-29T14:05:45.157374Z","end":"2026-05-29T14:05:45.3066Z","steps":["trace[1148697954] 'read index received' (duration: 147.907152ms)","trace[1148697954] 'applied index is now lower than readState.Index' (duration: 1.316719ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:05:45.306802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.401095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:05:45.306838Z","caller":"traceutil/trace.go:171","msg":"trace[7805877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1283; }","duration":"149.520928ms","start":"2026-05-29T14:05:45.157306Z","end":"2026-05-29T14:05:45.306827Z","steps":["trace[7805877] 'agreement among raft nodes before linearized reading' (duration: 149.419716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:51.511234Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398341022823733595,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T14:05:51.672697Z","caller":"traceutil/trace.go:171","msg":"trace[541184533] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"752.891849ms","start":"2026-05-29T14:05:50.919782Z","end":"2026-05-29T14:05:51.672674Z","steps":["trace[541184533] 'process raft request' (duration: 752.689123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:51.672817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:50.919772Z","time spent":"752.97593ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:05:51.901529Z","caller":"traceutil/trace.go:171","msg":"trace[895741545] linearizableReadLoop","detail":"{readStateIndex:1373; appliedIndex:1370; }","duration":"891.362111ms","start":"2026-05-29T14:05:51.010126Z","end":"2026-05-29T14:05:51.901488Z","steps":["trace[895741545] 'read index received' (duration: 662.414601ms)","trace[895741545] 'applied index is now lower than readState.Index' (duration: 228.946ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:05:51.901603Z","caller":"traceutil/trace.go:171","msg":"trace[910671470] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"693.293996ms","start":"2026-05-29T14:05:51.208269Z","end":"2026-05-29T14:05:51.901563Z","steps":["trace[910671470] 'process raft request' (duration: 693.084621ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:05:51.90176Z","caller":"traceutil/trace.go:171","msg":"trace[1950467195] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"977.073493ms","start":"2026-05-29T14:05:50.924649Z","end":"2026-05-29T14:05:51.901723Z","steps":["trace[1950467195] 'process raft request' (duration: 965.621029ms)","trace[1950467195] 'compare' (duration: 10.610156ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:05:51.901848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:51.208241Z","time spent":"693.474909ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:05:51.901881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.720559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T14:05:51.901933Z","caller":"traceutil/trace.go:171","msg":"trace[1087215642] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1321; }","duration":"891.832011ms","start":"2026-05-29T14:05:51.010082Z","end":"2026-05-29T14:05:51.901914Z","steps":["trace[1087215642] 'agreement among raft nodes before linearized reading' (duration: 891.587766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:51.901936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:50.924623Z","time spent":"977.224576ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:05:51.901973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:51.010062Z","time spent":"891.901893ms","remote":"127.0.0.1:33944","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-05-29T14:05:51.902155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.78047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T14:05:51.902182Z","caller":"traceutil/trace.go:171","msg":"trace[1869791036] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1321; }","duration":"526.852452ms","start":"2026-05-29T14:05:51.375321Z","end":"2026-05-29T14:05:51.902173Z","steps":["trace[1869791036] 'agreement among raft nodes before linearized reading' (duration: 526.792571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:51.902211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:51.375301Z","time spent":"526.902444ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":5,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-05-29T14:05:51.902211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.952187ms","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-05-29T14:05:51.902254Z","caller":"traceutil/trace.go:171","msg":"trace[1892167376] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1321; }","duration":"884.024338ms","start":"2026-05-29T14:05:51.018216Z","end":"2026-05-29T14:05:51.902241Z","steps":["trace[1892167376] 'agreement among raft nodes before linearized reading' (duration: 883.882954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:51.902284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:51.018203Z","time spent":"884.07548ms","remote":"127.0.0.1:33944","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-05-29T14:05:51.902409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.197178ms","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-05-29T14:05:51.902433Z","caller":"traceutil/trace.go:171","msg":"trace[2000107229] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1321; }","duration":"882.26839ms","start":"2026-05-29T14:05:51.020157Z","end":"2026-05-29T14:05:51.902425Z","steps":["trace[2000107229] 'agreement among raft nodes before linearized reading' (duration: 882.195368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:51.902458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:05:51.020132Z","time spent":"882.319601ms","remote":"127.0.0.1:33944","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-05-29T14:05:54.073019Z","caller":"traceutil/trace.go:171","msg":"trace[444440698] linearizableReadLoop","detail":"{readStateIndex:1386; appliedIndex:1385; }","duration":"118.569101ms","start":"2026-05-29T14:05:53.954428Z","end":"2026-05-29T14:05:54.072997Z","steps":["trace[444440698] 'read index received' (duration: 118.54457ms)","trace[444440698] 'applied index is now lower than readState.Index' (duration: 23.651µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:05:54.073122Z","caller":"traceutil/trace.go:171","msg":"trace[1976516346] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"163.13598ms","start":"2026-05-29T14:05:53.909973Z","end":"2026-05-29T14:05:54.073109Z","steps":["trace[1976516346] 'process raft request' (duration: 162.898375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:54.073299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.859258ms","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-05-29T14:05:54.073326Z","caller":"traceutil/trace.go:171","msg":"trace[1321502943] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1334; }","duration":"118.93108ms","start":"2026-05-29T14:05:53.954387Z","end":"2026-05-29T14:05:54.073318Z","steps":["trace[1321502943] 'agreement among raft nodes before linearized reading' (duration: 118.815346ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:05:54.228086Z","caller":"traceutil/trace.go:171","msg":"trace[1074306087] linearizableReadLoop","detail":"{readStateIndex:1387; appliedIndex:1386; }","duration":"154.97605ms","start":"2026-05-29T14:05:54.073083Z","end":"2026-05-29T14:05:54.228059Z","steps":["trace[1074306087] 'read index received' (duration: 137.217925ms)","trace[1074306087] 'applied index is now lower than readState.Index' (duration: 17.757175ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:05:54.228359Z","caller":"traceutil/trace.go:171","msg":"trace[1252201649] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"246.903579ms","start":"2026-05-29T14:05:53.981441Z","end":"2026-05-29T14:05:54.228344Z","steps":["trace[1252201649] 'process raft request' (duration: 228.849928ms)","trace[1252201649] 'compare' (duration: 17.664662ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:05:54.228671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.094916ms","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-05-29T14:05:54.228715Z","caller":"traceutil/trace.go:171","msg":"trace[75032824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1335; }","duration":"248.169048ms","start":"2026-05-29T14:05:53.980536Z","end":"2026-05-29T14:05:54.228705Z","steps":["trace[75032824] 'agreement among raft nodes before linearized reading' (duration: 248.002634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:05:54.228891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.899686ms","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-05-29T14:05:54.228928Z","caller":"traceutil/trace.go:171","msg":"trace[169912660] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1335; }","duration":"249.953936ms","start":"2026-05-29T14:05:53.978966Z","end":"2026-05-29T14:05:54.22892Z","steps":["trace[169912660] 'agreement among raft nodes before linearized reading' (duration: 249.866364ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:05:54.236887Z","caller":"traceutil/trace.go:171","msg":"trace[1086796641] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"157.994306ms","start":"2026-05-29T14:05:54.07888Z","end":"2026-05-29T14:05:54.236875Z","steps":["trace[1086796641] 'process raft request' (duration: 157.777532ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:05:55.240228Z","caller":"traceutil/trace.go:171","msg":"trace[2052728780] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"133.426721ms","start":"2026-05-29T14:05:55.106775Z","end":"2026-05-29T14:05:55.240201Z","steps":["trace[2052728780] 'process raft request' (duration: 82.836099ms)","trace[2052728780] 'compare' (duration: 50.453019ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:06:02.810877Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2398341022823733746,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T14:06:03.284802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000598165s","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-05-29T14:06:03.284946Z","caller":"traceutil/trace.go:171","msg":"trace[1835959547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1397; }","duration":"1.00082097s","start":"2026-05-29T14:06:02.284097Z","end":"2026-05-29T14:06:03.284918Z","steps":["trace[1835959547] 'range keys from in-memory index tree' (duration: 1.00031329s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:06:03.285006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:06:02.284067Z","time spent":"1.000927054s","remote":"127.0.0.1:33944","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":"info","ts":"2026-05-29T14:06:03.284976Z","caller":"traceutil/trace.go:171","msg":"trace[1223951601] linearizableReadLoop","detail":"{readStateIndex:1452; appliedIndex:1451; }","duration":"974.65827ms","start":"2026-05-29T14:06:02.31028Z","end":"2026-05-29T14:06:03.284938Z","steps":["trace[1223951601] 'read index received' (duration: 883.302132ms)","trace[1223951601] 'applied index is now lower than readState.Index' (duration: 91.354288ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:06:03.285212Z","caller":"traceutil/trace.go:171","msg":"trace[108619008] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"999.291807ms","start":"2026-05-29T14:06:02.285843Z","end":"2026-05-29T14:06:03.285134Z","steps":["trace[108619008] 'process raft request' (duration: 907.804036ms)","trace[108619008] 'compare' (duration: 91.130983ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:06:03.285397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.08994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T14:06:03.285459Z","caller":"traceutil/trace.go:171","msg":"trace[882501916] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1398; }","duration":"975.213272ms","start":"2026-05-29T14:06:02.31023Z","end":"2026-05-29T14:06:03.285444Z","steps":["trace[882501916] 'agreement among raft nodes before linearized reading' (duration: 974.995017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:06:03.285452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.826486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-29T14:06:03.285514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:06:02.310215Z","time spent":"975.285704ms","remote":"127.0.0.1:33944","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-05-29T14:06:03.285567Z","caller":"traceutil/trace.go:171","msg":"trace[1484330414] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1398; }","duration":"944.00845ms","start":"2026-05-29T14:06:02.341529Z","end":"2026-05-29T14:06:03.285537Z","steps":["trace[1484330414] 'agreement among raft nodes before linearized reading' (duration: 943.726474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:06:03.285624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:06:02.341504Z","time spent":"944.110443ms","remote":"127.0.0.1:33944","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-05-29T14:06:03.285715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.440979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-05-29T14:06:03.285409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:06:02.285814Z","time spent":"999.501822ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:06:03.285766Z","caller":"traceutil/trace.go:171","msg":"trace[1053633272] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1398; }","duration":"739.597684ms","start":"2026-05-29T14:06:02.546156Z","end":"2026-05-29T14:06:03.285754Z","steps":["trace[1053633272] 'agreement among raft nodes before linearized reading' (duration: 739.468221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:06:03.285807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:06:02.546122Z","time spent":"739.677515ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-29T14:06:03.285854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.615419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:06:03.28589Z","caller":"traceutil/trace.go:171","msg":"trace[679652114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1398; }","duration":"130.797533ms","start":"2026-05-29T14:06:03.155082Z","end":"2026-05-29T14:06:03.28588Z","steps":["trace[679652114] 'agreement among raft nodes before linearized reading' (duration: 130.743112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:06:03.285867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.589825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-29T14:06:03.28603Z","caller":"traceutil/trace.go:171","msg":"trace[1156328661] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1398; }","duration":"448.864171ms","start":"2026-05-29T14:06:02.837144Z","end":"2026-05-29T14:06:03.286009Z","steps":["trace[1156328661] 'agreement among raft nodes before linearized reading' (duration: 448.380741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:06:03.286098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:06:02.837116Z","time spent":"448.967224ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-29T14:06:03.835703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.365393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T14:06:03.835871Z","caller":"traceutil/trace.go:171","msg":"trace[52499304] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"544.065334ms","start":"2026-05-29T14:06:03.291777Z","end":"2026-05-29T14:06:03.835842Z","steps":["trace[52499304] 'process raft request' (duration: 395.427635ms)","trace[52499304] 'compare' (duration: 148.124988ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T14:06:03.83595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:06:03.291743Z","time spent":"544.174436ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:06:03.888989Z","caller":"traceutil/trace.go:171","msg":"trace[197732351] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"596.690933ms","start":"2026-05-29T14:06:03.292256Z","end":"2026-05-29T14:06:03.888947Z","steps":["trace[197732351] 'process raft request' (duration: 596.351485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:06:03.889402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:06:03.292242Z","time spent":"596.871866ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:06:03.889444Z","caller":"traceutil/trace.go:171","msg":"trace[129728293] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"597.154413ms","start":"2026-05-29T14:06:03.29227Z","end":"2026-05-29T14:06:03.889424Z","steps":["trace[129728293] 'process raft request' (duration: 596.542719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:06:03.889545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:06:03.292244Z","time spent":"597.250856ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T14:06:03.889902Z","caller":"traceutil/trace.go:171","msg":"trace[194013456] linearizableReadLoop","detail":"{readStateIndex:1457; appliedIndex:1452; }","duration":"529.231825ms","start":"2026-05-29T14:06:03.360639Z","end":"2026-05-29T14:06:03.889871Z","steps":["trace[194013456] 'read index received' (duration: 326.695219ms)","trace[194013456] 'applied index is now lower than readState.Index' (duration: 202.535106ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:06:03.890058Z","caller":"traceutil/trace.go:171","msg":"trace[192142004] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"596.737504ms","start":"2026-05-29T14:06:03.293307Z","end":"2026-05-29T14:06:03.890045Z","steps":["trace[192142004] 'process raft request' (duration: 596.417407ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T14:06:03.889988Z","caller":"traceutil/trace.go:171","msg":"trace[1234635069] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"597.197824ms","start":"2026-05-29T14:06:03.292717Z","end":"2026-05-29T14:06:03.889915Z","steps":["trace[1234635069] 'process raft request' (duration: 596.612001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:06:03.890192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:06:03.293294Z","time spent":"596.794605ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:06:03.890273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.546422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-29T14:06:03.890327Z","caller":"traceutil/trace.go:171","msg":"trace[2112713922] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1403; }","duration":"529.646364ms","start":"2026-05-29T14:06:03.360664Z","end":"2026-05-29T14:06:03.890311Z","steps":["trace[2112713922] 'agreement among raft nodes before linearized reading' (duration: 529.554392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:06:03.890351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:06:03.292709Z","time spent":"597.465079ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T14:06:03.890394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:06:03.360633Z","time spent":"529.737766ms","remote":"127.0.0.1:33720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":140,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-05-29T14:06:03.890597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.16949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T14:06:03.890646Z","caller":"traceutil/trace.go:171","msg":"trace[1632602296] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1403; }","duration":"418.277612ms","start":"2026-05-29T14:06:03.472357Z","end":"2026-05-29T14:06:03.890635Z","steps":["trace[1632602296] 'agreement among raft nodes before linearized reading' (duration: 418.196831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:06:03.890679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:06:03.472334Z","time spent":"418.337753ms","remote":"127.0.0.1:34050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":29,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-29T14:06:03.890695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.310506ms","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:532"} {"level":"info","ts":"2026-05-29T14:06:03.890732Z","caller":"traceutil/trace.go:171","msg":"trace[1909474635] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1403; }","duration":"478.346336ms","start":"2026-05-29T14:06:03.412374Z","end":"2026-05-29T14:06:03.890721Z","steps":["trace[1909474635] 'agreement among raft nodes before linearized reading' (duration: 478.197953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:06:03.890762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:06:03.412301Z","time spent":"478.454798ms","remote":"127.0.0.1:33944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-29T14:06:03.89096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.319959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:104018"} {"level":"warn","ts":"2026-05-29T14:06:03.890988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.953716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-29T14:06:03.891026Z","caller":"traceutil/trace.go:171","msg":"trace[904056750] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1403; }","duration":"505.027058ms","start":"2026-05-29T14:06:03.385983Z","end":"2026-05-29T14:06:03.89101Z","steps":["trace[904056750] 'agreement among raft nodes before linearized reading' (duration: 504.850264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:06:03.891053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:06:03.385961Z","time spent":"505.084839ms","remote":"127.0.0.1:33730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":161,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-05-29T14:06:03.89099Z","caller":"traceutil/trace.go:171","msg":"trace[1382226124] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1403; }","duration":"530.35024ms","start":"2026-05-29T14:06:03.360631Z","end":"2026-05-29T14:06:03.890981Z","steps":["trace[1382226124] 'agreement among raft nodes before linearized reading' (duration: 530.184316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:06:03.891255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T14:06:03.360592Z","time spent":"530.652216ms","remote":"127.0.0.1:33718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":104040,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-05-29T14:09:59.32463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.846045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T14:09:59.324782Z","caller":"traceutil/trace.go:171","msg":"trace[1494501944] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2500; }","duration":"175.07704ms","start":"2026-05-29T14:09:59.149681Z","end":"2026-05-29T14:09:59.324758Z","steps":["trace[1494501944] 'range keys from in-memory index tree' (duration: 161.772893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:09:59.324775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.119102ms","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-05-29T14:09:59.324907Z","caller":"traceutil/trace.go:171","msg":"trace[2108801414] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2500; }","duration":"175.364947ms","start":"2026-05-29T14:09:59.149513Z","end":"2026-05-29T14:09:59.324877Z","steps":["trace[2108801414] 'range keys from in-memory index tree' (duration: 161.944826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:09:59.324907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.611709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T14:09:59.325081Z","caller":"traceutil/trace.go:171","msg":"trace[255180720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2500; }","duration":"165.814855ms","start":"2026-05-29T14:09:59.159209Z","end":"2026-05-29T14:09:59.325024Z","steps":["trace[255180720] 'range keys from in-memory index tree' (duration: 162.312725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T14:09:59.324878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.744837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T14:09:59.325338Z","caller":"traceutil/trace.go:171","msg":"trace[860987048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2500; }","duration":"181.274889ms","start":"2026-05-29T14:09:59.144046Z","end":"2026-05-29T14:09:59.325321Z","steps":["trace[860987048] 'agreement among raft nodes before linearized reading' (duration: 18.450832ms)","trace[860987048] 'range keys from in-memory index tree' (duration: 162.12754ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T14:09:59.331696Z","caller":"traceutil/trace.go:171","msg":"trace[1328122554] transaction","detail":"{read_only:false; response_revision:2501; number_of_response:1; }","duration":"164.487365ms","start":"2026-05-29T14:09:59.167184Z","end":"2026-05-29T14:09:59.331671Z","steps":["trace[1328122554] 'process raft request' (duration: 164.176038ms)"],"step_count":1}