{"level":"warn","ts":"2026-05-04T21:16:13.766381Z","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-04T21:16:13.766523Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.25: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.25:2380","--initial-cluster=instance=https://199.204.45.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.25: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-04T21:16:13.766631Z","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-04T21:16:13.766667Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-05-04T21:16:13.7667Z","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-04T21:16:13.767688Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"]} {"level":"info","ts":"2026-05-04T21:16:13.767844Z","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.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.25: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-04T21:16:13.772883Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.701691ms"} {"level":"info","ts":"2026-05-04T21:16:13.780587Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ba52c55cf4193752","cluster-id":"4468ed52294bc271"} {"level":"info","ts":"2026-05-04T21:16:13.780706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=()"} {"level":"info","ts":"2026-05-04T21:16:13.780735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 0"} {"level":"info","ts":"2026-05-04T21:16:13.780746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ba52c55cf4193752 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-04T21:16:13.780756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 1"} {"level":"info","ts":"2026-05-04T21:16:13.780818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"warn","ts":"2026-05-04T21:16:13.789832Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-04T21:16:13.79306Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-04T21:16:13.794395Z","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-04T21:16:13.796625Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ba52c55cf4193752","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-04T21:16:13.796926Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ba52c55cf4193752","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-04T21:16:13.797275Z","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-04T21:16:13.79738Z","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-04T21:16:13.797445Z","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-04T21:16:13.798143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"info","ts":"2026-05-04T21:16:13.798325Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","added-peer-id":"ba52c55cf4193752","added-peer-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-05-04T21:16:13.799514Z","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-04T21:16:13.799688Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ba52c55cf4193752","initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-04T21:16:13.799719Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-04T21:16:13.799814Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-05-04T21:16:13.799828Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-05-04T21:16:14.381344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 is starting a new election at term 1"} {"level":"info","ts":"2026-05-04T21:16:14.381408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-04T21:16:14.381456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgPreVoteResp from ba52c55cf4193752 at term 1"} {"level":"info","ts":"2026-05-04T21:16:14.381475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became candidate at term 2"} {"level":"info","ts":"2026-05-04T21:16:14.381486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgVoteResp from ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-05-04T21:16:14.381502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became leader at term 2"} {"level":"info","ts":"2026-05-04T21:16:14.381516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ba52c55cf4193752 elected leader ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-05-04T21:16:14.382442Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T21:16:14.383493Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-04T21:16:14.383481Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ba52c55cf4193752","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.25:2379]}","request-path":"/0/members/ba52c55cf4193752/attributes","cluster-id":"4468ed52294bc271","publish-timeout":"7s"} {"level":"info","ts":"2026-05-04T21:16:14.383949Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T21:16:14.38417Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T21:16:14.38422Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-04T21:16:14.386504Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-04T21:16:14.388657Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-04T21:16:14.388686Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-04T21:16:14.399933Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-04T21:16:14.40156Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.25:2379"} {"level":"info","ts":"2026-05-04T21:16:42.23996Z","caller":"traceutil/trace.go:171","msg":"trace[664557805] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"284.319762ms","start":"2026-05-04T21:16:41.955612Z","end":"2026-05-04T21:16:42.239932Z","steps":["trace[664557805] 'process raft request' (duration: 284.107658ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:16:42.239935Z","caller":"traceutil/trace.go:171","msg":"trace[1628022380] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:485; }","duration":"281.092043ms","start":"2026-05-04T21:16:41.95881Z","end":"2026-05-04T21:16:42.239902Z","steps":["trace[1628022380] 'read index received' (duration: 280.896669ms)","trace[1628022380] 'applied index is now lower than readState.Index' (duration: 193.594µs)"],"step_count":2} {"level":"warn","ts":"2026-05-04T21:16:42.240084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.244365ms","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-04T21:16:42.240135Z","caller":"traceutil/trace.go:171","msg":"trace[542200402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:472; }","duration":"281.334756ms","start":"2026-05-04T21:16:41.958785Z","end":"2026-05-04T21:16:42.24012Z","steps":["trace[542200402] 'agreement among raft nodes before linearized reading' (duration: 281.231445ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:16:42.48161Z","caller":"traceutil/trace.go:171","msg":"trace[1565914358] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"166.85873ms","start":"2026-05-04T21:16:42.314731Z","end":"2026-05-04T21:16:42.481589Z","steps":["trace[1565914358] 'read index received' (duration: 166.679217ms)","trace[1565914358] 'applied index is now lower than readState.Index' (duration: 178.603µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:16:42.481715Z","caller":"traceutil/trace.go:171","msg":"trace[1271089217] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"237.243657ms","start":"2026-05-04T21:16:42.244445Z","end":"2026-05-04T21:16:42.481688Z","steps":["trace[1271089217] 'process raft request' (duration: 237.008983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:42.481723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.973872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:42.481803Z","caller":"traceutil/trace.go:171","msg":"trace[737480336] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:473; }","duration":"167.101015ms","start":"2026-05-04T21:16:42.31469Z","end":"2026-05-04T21:16:42.481791Z","steps":["trace[737480336] 'agreement among raft nodes before linearized reading' (duration: 166.989873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:45.711379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.914687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:45.711468Z","caller":"traceutil/trace.go:171","msg":"trace[85018943] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:597; }","duration":"381.051049ms","start":"2026-05-04T21:16:45.330397Z","end":"2026-05-04T21:16:45.711448Z","steps":["trace[85018943] 'range keys from in-memory index tree' (duration: 380.846986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:45.711511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:45.330384Z","time spent":"381.11821ms","remote":"127.0.0.1:55736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-05-04T21:16:45.717654Z","caller":"traceutil/trace.go:171","msg":"trace[1076782207] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"386.50359ms","start":"2026-05-04T21:16:45.331126Z","end":"2026-05-04T21:16:45.71763Z","steps":["trace[1076782207] 'process raft request' (duration: 385.317418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:45.718373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:45.331102Z","time spent":"386.644352ms","remote":"127.0.0.1:55736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T21:16:45.797995Z","caller":"traceutil/trace.go:171","msg":"trace[441395392] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"265.413962ms","start":"2026-05-04T21:16:45.532557Z","end":"2026-05-04T21:16:45.797971Z","steps":["trace[441395392] 'process raft request' (duration: 265.388851ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:16:45.800014Z","caller":"traceutil/trace.go:171","msg":"trace[301110240] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"407.892467ms","start":"2026-05-04T21:16:45.392114Z","end":"2026-05-04T21:16:45.800007Z","steps":["trace[301110240] 'process raft request' (duration: 405.731117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:45.800066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:45.392093Z","time spent":"407.934118ms","remote":"127.0.0.1:55736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-04T21:16:46.335293Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422295119288512,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-04T21:16:46.547542Z","caller":"traceutil/trace.go:171","msg":"trace[1682808449] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"815.076542ms","start":"2026-05-04T21:16:45.732431Z","end":"2026-05-04T21:16:46.547507Z","steps":["trace[1682808449] 'process raft request' (duration: 808.619673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:46.547703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:45.732414Z","time spent":"815.216114ms","remote":"127.0.0.1:55736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-04T21:16:46.548125Z","caller":"traceutil/trace.go:171","msg":"trace[297064563] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"794.814416ms","start":"2026-05-04T21:16:45.753291Z","end":"2026-05-04T21:16:46.548106Z","steps":["trace[297064563] 'process raft request' (duration: 794.172533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:46.548259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:45.753271Z","time spent":"794.927688ms","remote":"127.0.0.1:55736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T21:16:46.553198Z","caller":"traceutil/trace.go:171","msg":"trace[677110625] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"620.521568ms","start":"2026-05-04T21:16:45.932656Z","end":"2026-05-04T21:16:46.553177Z","steps":["trace[677110625] 'process raft request' (duration: 620.339205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:46.553263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:45.932629Z","time spent":"620.60648ms","remote":"127.0.0.1:55736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-04T21:16:46.553452Z","caller":"traceutil/trace.go:171","msg":"trace[1482983653] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"599.652661ms","start":"2026-05-04T21:16:45.953782Z","end":"2026-05-04T21:16:46.553435Z","steps":["trace[1482983653] 'process raft request' (duration: 599.386425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:46.553565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:45.953761Z","time spent":"599.744432ms","remote":"127.0.0.1:55736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T21:16:46.553783Z","caller":"traceutil/trace.go:171","msg":"trace[1202011823] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:619; }","duration":"718.669372ms","start":"2026-05-04T21:16:45.835089Z","end":"2026-05-04T21:16:46.553758Z","steps":["trace[1202011823] 'read index received' (duration: 706.031547ms)","trace[1202011823] 'applied index is now lower than readState.Index' (duration: 12.636715ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:16:46.553853Z","caller":"traceutil/trace.go:171","msg":"trace[2125238667] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"222.488113ms","start":"2026-05-04T21:16:46.331335Z","end":"2026-05-04T21:16:46.553823Z","steps":["trace[2125238667] 'process raft request' (duration: 222.2966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:46.553923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.824725ms","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-04T21:16:46.55396Z","caller":"traceutil/trace.go:171","msg":"trace[2062544273] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:607; }","duration":"718.894466ms","start":"2026-05-04T21:16:45.835056Z","end":"2026-05-04T21:16:46.553951Z","steps":["trace[2062544273] 'agreement among raft nodes before linearized reading' (duration: 718.774294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:46.553983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:45.83503Z","time spent":"718.948327ms","remote":"127.0.0.1:55736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29593,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-05-04T21:16:46.554065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.559659ms","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-04T21:16:46.554089Z","caller":"traceutil/trace.go:171","msg":"trace[890402131] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:607; }","duration":"296.611431ms","start":"2026-05-04T21:16:46.257471Z","end":"2026-05-04T21:16:46.554082Z","steps":["trace[890402131] 'agreement among raft nodes before linearized reading' (duration: 296.57144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:46.554096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.33866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-2sdcj\" ","response":"range_response_count:1 size:4884"} {"level":"info","ts":"2026-05-04T21:16:46.5539Z","caller":"traceutil/trace.go:171","msg":"trace[805406102] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"156.345625ms","start":"2026-05-04T21:16:46.397473Z","end":"2026-05-04T21:16:46.553818Z","steps":["trace[805406102] 'process raft request' (duration: 156.230103ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:16:46.554132Z","caller":"traceutil/trace.go:171","msg":"trace[1824384895] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-2sdcj; range_end:; response_count:1; response_revision:607; }","duration":"694.40756ms","start":"2026-05-04T21:16:45.859714Z","end":"2026-05-04T21:16:46.554121Z","steps":["trace[1824384895] 'agreement among raft nodes before linearized reading' (duration: 694.258928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:46.55426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:45.859702Z","time spent":"694.550074ms","remote":"127.0.0.1:55848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4907,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-2sdcj\" "} {"level":"info","ts":"2026-05-04T21:16:46.553777Z","caller":"traceutil/trace.go:171","msg":"trace[247723131] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"419.510944ms","start":"2026-05-04T21:16:46.134248Z","end":"2026-05-04T21:16:46.553759Z","steps":["trace[247723131] 'process raft request' (duration: 419.157728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:46.554638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:46.134224Z","time spent":"420.37765ms","remote":"127.0.0.1:55736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-04T21:16:50.103957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.737133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:50.104039Z","caller":"traceutil/trace.go:171","msg":"trace[1607419373] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:654; }","duration":"232.834455ms","start":"2026-05-04T21:16:49.871179Z","end":"2026-05-04T21:16:50.104013Z","steps":["trace[1607419373] 'range keys from in-memory index tree' (duration: 232.701763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:50.104043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.305084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:50.104103Z","caller":"traceutil/trace.go:171","msg":"trace[1746694632] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:654; }","duration":"247.374775ms","start":"2026-05-04T21:16:49.856703Z","end":"2026-05-04T21:16:50.104077Z","steps":["trace[1746694632] 'range keys from in-memory index tree' (duration: 247.251313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:50.104189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.537781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:50.104208Z","caller":"traceutil/trace.go:171","msg":"trace[1841434509] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:654; }","duration":"232.568751ms","start":"2026-05-04T21:16:49.871634Z","end":"2026-05-04T21:16:50.104203Z","steps":["trace[1841434509] 'range keys from in-memory index tree' (duration: 232.512041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:50.103951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.718953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:50.104371Z","caller":"traceutil/trace.go:171","msg":"trace[206869179] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:654; }","duration":"233.198442ms","start":"2026-05-04T21:16:49.871166Z","end":"2026-05-04T21:16:50.104365Z","steps":["trace[206869179] 'count revisions from in-memory index tree' (duration: 232.660482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:50.104367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.447917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:50.104399Z","caller":"traceutil/trace.go:171","msg":"trace[1783851444] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:654; }","duration":"247.495478ms","start":"2026-05-04T21:16:49.856894Z","end":"2026-05-04T21:16:50.104389Z","steps":["trace[1783851444] 'count revisions from in-memory index tree' (duration: 247.414747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:50.104546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.491901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:50.104591Z","caller":"traceutil/trace.go:171","msg":"trace[19931686] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:654; }","duration":"245.537952ms","start":"2026-05-04T21:16:49.859044Z","end":"2026-05-04T21:16:50.104582Z","steps":["trace[19931686] 'range keys from in-memory index tree' (duration: 245.43569ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:16:50.108526Z","caller":"traceutil/trace.go:171","msg":"trace[2145528319] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"188.565784ms","start":"2026-05-04T21:16:49.919908Z","end":"2026-05-04T21:16:50.108474Z","steps":["trace[2145528319] 'process raft request' (duration: 186.370752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.017748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.841258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T21:16:53.017757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.616108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-sj6gx\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-04T21:16:53.017817Z","caller":"traceutil/trace.go:171","msg":"trace[171580710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:679; }","duration":"265.958941ms","start":"2026-05-04T21:16:52.751843Z","end":"2026-05-04T21:16:53.017802Z","steps":["trace[171580710] 'range keys from in-memory index tree' (duration: 265.769957ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:16:53.017825Z","caller":"traceutil/trace.go:171","msg":"trace[1539046524] range","detail":"{range_begin:/registry/pods/kube-system/cilium-sj6gx; range_end:; response_count:1; response_revision:679; }","duration":"142.725691ms","start":"2026-05-04T21:16:52.875084Z","end":"2026-05-04T21:16:53.017809Z","steps":["trace[1539046524] 'range keys from in-memory index tree' (duration: 142.442765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.017914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.956446ms","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-04T21:16:53.017983Z","caller":"traceutil/trace.go:171","msg":"trace[935044911] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:679; }","duration":"417.068348ms","start":"2026-05-04T21:16:52.600894Z","end":"2026-05-04T21:16:53.017963Z","steps":["trace[935044911] 'range keys from in-memory index tree' (duration: 416.792934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.018017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:52.60087Z","time spent":"417.13989ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-04T21:16:53.018037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.212173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-04T21:16:53.018058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.349382ms","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-04T21:16:53.018069Z","caller":"traceutil/trace.go:171","msg":"trace[525151967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:679; }","duration":"198.273454ms","start":"2026-05-04T21:16:52.819787Z","end":"2026-05-04T21:16:53.018061Z","steps":["trace[525151967] 'range keys from in-memory index tree' (duration: 198.108911ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:16:53.018092Z","caller":"traceutil/trace.go:171","msg":"trace[345811433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:679; }","duration":"411.424284ms","start":"2026-05-04T21:16:52.606658Z","end":"2026-05-04T21:16:53.018083Z","steps":["trace[345811433] 'range keys from in-memory index tree' (duration: 411.24944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.018123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:52.606639Z","time spent":"411.475464ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-04T21:16:53.351902Z","caller":"traceutil/trace.go:171","msg":"trace[1894304271] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"328.564374ms","start":"2026-05-04T21:16:53.023302Z","end":"2026-05-04T21:16:53.351867Z","steps":["trace[1894304271] 'process raft request' (duration: 319.411265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.352142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:53.023283Z","time spent":"328.726547ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T21:16:53.352212Z","caller":"traceutil/trace.go:171","msg":"trace[1623522733] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"328.781879ms","start":"2026-05-04T21:16:53.023394Z","end":"2026-05-04T21:16:53.352176Z","steps":["trace[1623522733] 'process raft request' (duration: 328.407612ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:16:53.352303Z","caller":"traceutil/trace.go:171","msg":"trace[506659186] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:700; }","duration":"171.376414ms","start":"2026-05-04T21:16:53.180903Z","end":"2026-05-04T21:16:53.35228Z","steps":["trace[506659186] 'read index received' (duration: 162.01326ms)","trace[506659186] 'applied index is now lower than readState.Index' (duration: 9.362374ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T21:16:53.352381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:53.023384Z","time spent":"328.901341ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:16:53.352476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.551228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-04T21:16:53.352503Z","caller":"traceutil/trace.go:171","msg":"trace[139266867] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:684; }","duration":"171.633289ms","start":"2026-05-04T21:16:53.180859Z","end":"2026-05-04T21:16:53.352492Z","steps":["trace[139266867] 'agreement among raft nodes before linearized reading' (duration: 171.497326ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:16:53.352527Z","caller":"traceutil/trace.go:171","msg":"trace[1040925837] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"329.066934ms","start":"2026-05-04T21:16:53.02345Z","end":"2026-05-04T21:16:53.352517Z","steps":["trace[1040925837] 'process raft request' (duration: 328.607825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.352579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:53.023443Z","time spent":"329.106824ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T21:16:53.352693Z","caller":"traceutil/trace.go:171","msg":"trace[1083609674] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"323.121384ms","start":"2026-05-04T21:16:53.029563Z","end":"2026-05-04T21:16:53.352684Z","steps":["trace[1083609674] 'process raft request' (duration: 322.609224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.352738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:53.029545Z","time spent":"323.168234ms","remote":"127.0.0.1:55848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:16:53.850453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.537182ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-04T21:16:53.850711Z","caller":"traceutil/trace.go:171","msg":"trace[1368681902] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:704; }","duration":"431.464736ms","start":"2026-05-04T21:16:53.419229Z","end":"2026-05-04T21:16:53.850694Z","steps":["trace[1368681902] 'read index received' (duration: 23.381805ms)","trace[1368681902] 'applied index is now lower than readState.Index' (duration: 408.081801ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T21:16:53.850841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.740802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.850899Z","caller":"traceutil/trace.go:171","msg":"trace[1647654386] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:685; }","duration":"430.915645ms","start":"2026-05-04T21:16:53.419974Z","end":"2026-05-04T21:16:53.85089Z","steps":["trace[1647654386] 'agreement among raft nodes before linearized reading' (duration: 430.820533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.850973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:53.419963Z","time spent":"430.965197ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 "} {"level":"warn","ts":"2026-05-04T21:16:53.850986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.461734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T21:16:53.851055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.883063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.851081Z","caller":"traceutil/trace.go:171","msg":"trace[84549287] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:685; }","duration":"221.934154ms","start":"2026-05-04T21:16:53.629139Z","end":"2026-05-04T21:16:53.851073Z","steps":["trace[84549287] 'agreement among raft nodes before linearized reading' (duration: 221.904043ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:16:53.851072Z","caller":"traceutil/trace.go:171","msg":"trace[1631977642] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:685; }","duration":"313.582416ms","start":"2026-05-04T21:16:53.537468Z","end":"2026-05-04T21:16:53.851051Z","steps":["trace[1631977642] 'agreement among raft nodes before linearized reading' (duration: 313.368371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.851121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:53.537461Z","time spent":"313.649547ms","remote":"127.0.0.1:40886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-04T21:16:53.851201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.866991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.851222Z","caller":"traceutil/trace.go:171","msg":"trace[1073449299] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:685; }","duration":"264.899482ms","start":"2026-05-04T21:16:53.586317Z","end":"2026-05-04T21:16:53.851216Z","steps":["trace[1073449299] 'agreement among raft nodes before linearized reading' (duration: 264.869181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.851271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.788019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.851341Z","caller":"traceutil/trace.go:171","msg":"trace[1438200598] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:685; }","duration":"313.877942ms","start":"2026-05-04T21:16:53.537453Z","end":"2026-05-04T21:16:53.851331Z","steps":["trace[1438200598] 'agreement among raft nodes before linearized reading' (duration: 313.78113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.851362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.483473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.8514Z","caller":"traceutil/trace.go:171","msg":"trace[1189614170] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:685; }","duration":"286.537114ms","start":"2026-05-04T21:16:53.564854Z","end":"2026-05-04T21:16:53.851391Z","steps":["trace[1189614170] 'agreement among raft nodes before linearized reading' (duration: 286.476083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.851409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:53.537435Z","time spent":"313.959003ms","remote":"127.0.0.1:40886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-04T21:16:53.851467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.9803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.851489Z","caller":"traceutil/trace.go:171","msg":"trace[2065734877] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:685; }","duration":"155.02241ms","start":"2026-05-04T21:16:53.696461Z","end":"2026-05-04T21:16:53.851483Z","steps":["trace[2065734877] 'agreement among raft nodes before linearized reading' (duration: 154.98911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.851534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.92366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T21:16:53.851579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.789729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.851599Z","caller":"traceutil/trace.go:171","msg":"trace[1855137588] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:685; }","duration":"344.009051ms","start":"2026-05-04T21:16:53.50758Z","end":"2026-05-04T21:16:53.851589Z","steps":["trace[1855137588] 'agreement among raft nodes before linearized reading' (duration: 343.92654ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:16:53.851619Z","caller":"traceutil/trace.go:171","msg":"trace[969330060] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:685; }","duration":"286.83218ms","start":"2026-05-04T21:16:53.564776Z","end":"2026-05-04T21:16:53.851608Z","steps":["trace[969330060] 'agreement among raft nodes before linearized reading' (duration: 286.769818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.851663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:53.507572Z","time spent":"344.051452ms","remote":"127.0.0.1:40872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-04T21:16:53.851807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.313328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.851862Z","caller":"traceutil/trace.go:171","msg":"trace[1885414410] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:685; }","duration":"287.374849ms","start":"2026-05-04T21:16:53.564461Z","end":"2026-05-04T21:16:53.851836Z","steps":["trace[1885414410] 'agreement among raft nodes before linearized reading' (duration: 287.318858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.851905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.536801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T21:16:53.851012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.234409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.851951Z","caller":"traceutil/trace.go:171","msg":"trace[2124537417] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:685; }","duration":"344.637802ms","start":"2026-05-04T21:16:53.507297Z","end":"2026-05-04T21:16:53.851935Z","steps":["trace[2124537417] 'agreement among raft nodes before linearized reading' (duration: 344.551141ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:16:53.851999Z","caller":"traceutil/trace.go:171","msg":"trace[1714015833] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:685; }","duration":"266.241016ms","start":"2026-05-04T21:16:53.585747Z","end":"2026-05-04T21:16:53.851988Z","steps":["trace[1714015833] 'agreement among raft nodes before linearized reading' (duration: 265.239328ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.852016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:53.507273Z","time spent":"344.732245ms","remote":"127.0.0.1:40872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-04T21:16:53.851992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.066516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.852126Z","caller":"traceutil/trace.go:171","msg":"trace[1866818695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:685; }","duration":"102.22051ms","start":"2026-05-04T21:16:53.749894Z","end":"2026-05-04T21:16:53.852115Z","steps":["trace[1866818695] 'agreement among raft nodes before linearized reading' (duration: 102.073157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.852222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.910367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.852284Z","caller":"traceutil/trace.go:171","msg":"trace[450636267] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:685; }","duration":"344.975908ms","start":"2026-05-04T21:16:53.507298Z","end":"2026-05-04T21:16:53.852274Z","steps":["trace[450636267] 'agreement among raft nodes before linearized reading' (duration: 344.897397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.852349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:53.507285Z","time spent":"345.052652ms","remote":"127.0.0.1:40872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-04T21:16:53.852442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.923797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.852479Z","caller":"traceutil/trace.go:171","msg":"trace[118723095] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:685; }","duration":"155.981949ms","start":"2026-05-04T21:16:53.696488Z","end":"2026-05-04T21:16:53.852469Z","steps":["trace[118723095] 'agreement among raft nodes before linearized reading' (duration: 155.910238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.852589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.737434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.852657Z","caller":"traceutil/trace.go:171","msg":"trace[261526223] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:685; }","duration":"401.816735ms","start":"2026-05-04T21:16:53.450825Z","end":"2026-05-04T21:16:53.852641Z","steps":["trace[261526223] 'agreement among raft nodes before linearized reading' (duration: 401.735624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.852719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:53.450817Z","time spent":"401.866936ms","remote":"127.0.0.1:40858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-04T21:16:53.852917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.667787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T21:16:53.852932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.346075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.852957Z","caller":"traceutil/trace.go:171","msg":"trace[329106721] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:685; }","duration":"201.738038ms","start":"2026-05-04T21:16:53.65121Z","end":"2026-05-04T21:16:53.852948Z","steps":["trace[329106721] 'agreement among raft nodes before linearized reading' (duration: 201.675946ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:16:53.852963Z","caller":"traceutil/trace.go:171","msg":"trace[1187176345] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:685; }","duration":"402.407566ms","start":"2026-05-04T21:16:53.450547Z","end":"2026-05-04T21:16:53.852955Z","steps":["trace[1187176345] 'agreement among raft nodes before linearized reading' (duration: 402.355515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.853027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:53.450517Z","time spent":"402.468687ms","remote":"127.0.0.1:40858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-04T21:16:53.853096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.150847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.853132Z","caller":"traceutil/trace.go:171","msg":"trace[1059939867] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:685; }","duration":"180.206259ms","start":"2026-05-04T21:16:53.672916Z","end":"2026-05-04T21:16:53.853122Z","steps":["trace[1059939867] 'agreement among raft nodes before linearized reading' (duration: 180.154998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.853213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.65143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T21:16:53.853272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.078484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.853277Z","caller":"traceutil/trace.go:171","msg":"trace[2581278] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:685; }","duration":"402.722322ms","start":"2026-05-04T21:16:53.450522Z","end":"2026-05-04T21:16:53.853244Z","steps":["trace[2581278] 'agreement among raft nodes before linearized reading' (duration: 402.63631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.853342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.184125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.853374Z","caller":"traceutil/trace.go:171","msg":"trace[1814442415] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:685; }","duration":"181.246817ms","start":"2026-05-04T21:16:53.67212Z","end":"2026-05-04T21:16:53.853367Z","steps":["trace[1814442415] 'agreement among raft nodes before linearized reading' (duration: 181.201116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.853345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:53.450502Z","time spent":"402.830603ms","remote":"127.0.0.1:40858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-04T21:16:53.851316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.472862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.853571Z","caller":"traceutil/trace.go:171","msg":"trace[920045953] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:685; }","duration":"267.730404ms","start":"2026-05-04T21:16:53.585828Z","end":"2026-05-04T21:16:53.853558Z","steps":["trace[920045953] 'agreement among raft nodes before linearized reading' (duration: 265.475033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.85379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.217876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T21:16:53.852377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.303936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.854026Z","caller":"traceutil/trace.go:171","msg":"trace[1896584231] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:685; }","duration":"156.962927ms","start":"2026-05-04T21:16:53.697052Z","end":"2026-05-04T21:16:53.854015Z","steps":["trace[1896584231] 'agreement among raft nodes before linearized reading' (duration: 155.270035ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:16:53.853835Z","caller":"traceutil/trace.go:171","msg":"trace[1963755713] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:685; }","duration":"203.278517ms","start":"2026-05-04T21:16:53.650546Z","end":"2026-05-04T21:16:53.853825Z","steps":["trace[1963755713] 'agreement among raft nodes before linearized reading' (duration: 203.205876ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:16:53.853306Z","caller":"traceutil/trace.go:171","msg":"trace[458664587] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:685; }","duration":"181.113235ms","start":"2026-05-04T21:16:53.672184Z","end":"2026-05-04T21:16:53.853297Z","steps":["trace[458664587] 'agreement among raft nodes before linearized reading' (duration: 181.064404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.854355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.665784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.854399Z","caller":"traceutil/trace.go:171","msg":"trace[1930728678] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:685; }","duration":"203.759736ms","start":"2026-05-04T21:16:53.650629Z","end":"2026-05-04T21:16:53.854389Z","steps":["trace[1930728678] 'agreement among raft nodes before linearized reading' (duration: 203.668145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.855795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.242043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.855826Z","caller":"traceutil/trace.go:171","msg":"trace[430033219] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:685; }","duration":"226.295305ms","start":"2026-05-04T21:16:53.629524Z","end":"2026-05-04T21:16:53.85582Z","steps":["trace[430033219] 'agreement among raft nodes before linearized reading' (duration: 226.245824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.854698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.598069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.856195Z","caller":"traceutil/trace.go:171","msg":"trace[2132229511] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:685; }","duration":"436.982608ms","start":"2026-05-04T21:16:53.4192Z","end":"2026-05-04T21:16:53.856182Z","steps":["trace[2132229511] 'agreement among raft nodes before linearized reading' (duration: 431.582348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.856276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:53.419187Z","time spent":"437.0749ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 "} {"level":"warn","ts":"2026-05-04T21:16:53.856115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.925476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.857427Z","caller":"traceutil/trace.go:171","msg":"trace[789133326] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:685; }","duration":"228.23785ms","start":"2026-05-04T21:16:53.629163Z","end":"2026-05-04T21:16:53.857401Z","steps":["trace[789133326] 'agreement among raft nodes before linearized reading' (duration: 226.926806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.856085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.603729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:16:53.857549Z","caller":"traceutil/trace.go:171","msg":"trace[253367010] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:685; }","duration":"438.350693ms","start":"2026-05-04T21:16:53.419191Z","end":"2026-05-04T21:16:53.857542Z","steps":["trace[253367010] 'agreement among raft nodes before linearized reading' (duration: 431.612778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.857579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:53.419172Z","time spent":"438.397195ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-05-04T21:16:53.856134Z","caller":"traceutil/trace.go:171","msg":"trace[688703225] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"492.935598ms","start":"2026-05-04T21:16:53.357791Z","end":"2026-05-04T21:16:53.850726Z","steps":["trace[688703225] 'process raft request' (duration: 492.746104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:16:53.859522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:16:53.357763Z","time spent":"501.69352ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:17:11.527303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.302291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-04T21:17:11.527356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.890813ms","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-04T21:17:11.527389Z","caller":"traceutil/trace.go:171","msg":"trace[1589186714] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:800; }","duration":"101.464984ms","start":"2026-05-04T21:17:11.425904Z","end":"2026-05-04T21:17:11.527369Z","steps":["trace[1589186714] 'range keys from in-memory index tree' (duration: 101.132228ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:17:11.527441Z","caller":"traceutil/trace.go:171","msg":"trace[1149180543] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:800; }","duration":"298.026196ms","start":"2026-05-04T21:17:11.229394Z","end":"2026-05-04T21:17:11.52742Z","steps":["trace[1149180543] 'range keys from in-memory index tree' (duration: 297.69089ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:17:11.80847Z","caller":"traceutil/trace.go:171","msg":"trace[1144320312] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"276.669499ms","start":"2026-05-04T21:17:11.531776Z","end":"2026-05-04T21:17:11.808446Z","steps":["trace[1144320312] 'process raft request' (duration: 276.527707ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:17:11.813583Z","caller":"traceutil/trace.go:171","msg":"trace[1339960224] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"281.759735ms","start":"2026-05-04T21:17:11.531813Z","end":"2026-05-04T21:17:11.813573Z","steps":["trace[1339960224] 'process raft request' (duration: 281.675013ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:17:11.813771Z","caller":"traceutil/trace.go:171","msg":"trace[189278302] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"281.327826ms","start":"2026-05-04T21:17:11.532433Z","end":"2026-05-04T21:17:11.81376Z","steps":["trace[189278302] 'process raft request' (duration: 281.116052ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:17:11.813952Z","caller":"traceutil/trace.go:171","msg":"trace[819442747] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"281.274405ms","start":"2026-05-04T21:17:11.532654Z","end":"2026-05-04T21:17:11.813928Z","steps":["trace[819442747] 'process raft request' (duration: 281.057911ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:17:45.841314Z","caller":"traceutil/trace.go:171","msg":"trace[836847047] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"669.28202ms","start":"2026-05-04T21:17:45.17198Z","end":"2026-05-04T21:17:45.841262Z","steps":["trace[836847047] 'process raft request' (duration: 669.091956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:17:45.841465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:17:45.17196Z","time spent":"669.437402ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":532,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T21:17:46.380259Z","caller":"traceutil/trace.go:171","msg":"trace[1549894332] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"340.858156ms","start":"2026-05-04T21:17:46.039376Z","end":"2026-05-04T21:17:46.380235Z","steps":["trace[1549894332] 'process raft request' (duration: 340.712523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:17:46.380429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:17:46.039364Z","time spent":"340.984458ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T21:17:46.381898Z","caller":"traceutil/trace.go:171","msg":"trace[1194171266] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1051; }","duration":"302.935239ms","start":"2026-05-04T21:17:46.078949Z","end":"2026-05-04T21:17:46.381885Z","steps":["trace[1194171266] 'read index received' (duration: 301.196177ms)","trace[1194171266] 'applied index is now lower than readState.Index' (duration: 1.738132ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:17:46.381983Z","caller":"traceutil/trace.go:171","msg":"trace[145356373] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"342.440935ms","start":"2026-05-04T21:17:46.039515Z","end":"2026-05-04T21:17:46.381956Z","steps":["trace[145356373] 'process raft request' (duration: 342.308662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:17:46.382035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.082802ms","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-04T21:17:46.382061Z","caller":"traceutil/trace.go:171","msg":"trace[1865960643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1018; }","duration":"303.147272ms","start":"2026-05-04T21:17:46.078906Z","end":"2026-05-04T21:17:46.382054Z","steps":["trace[1865960643] 'agreement among raft nodes before linearized reading' (duration: 303.043461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:17:46.382083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:17:46.078885Z","time spent":"303.192964ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-04T21:17:46.382111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:17:46.039507Z","time spent":"342.534446ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:17:46.382185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.782867ms","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-04T21:17:46.382227Z","caller":"traceutil/trace.go:171","msg":"trace[167258020] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1018; }","duration":"302.838917ms","start":"2026-05-04T21:17:46.079377Z","end":"2026-05-04T21:17:46.382216Z","steps":["trace[167258020] 'agreement among raft nodes before linearized reading' (duration: 302.728115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:17:46.382258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:17:46.07937Z","time spent":"302.880578ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-04T21:17:46.382309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.487342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-04T21:17:46.382423Z","caller":"traceutil/trace.go:171","msg":"trace[1358718360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1018; }","duration":"163.643866ms","start":"2026-05-04T21:17:46.21875Z","end":"2026-05-04T21:17:46.382394Z","steps":["trace[1358718360] 'agreement among raft nodes before linearized reading' (duration: 163.371691ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:17:50.641665Z","caller":"traceutil/trace.go:171","msg":"trace[1812007367] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1065; }","duration":"246.394186ms","start":"2026-05-04T21:17:50.395243Z","end":"2026-05-04T21:17:50.641637Z","steps":["trace[1812007367] 'read index received' (duration: 246.205872ms)","trace[1812007367] 'applied index is now lower than readState.Index' (duration: 187.094µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:17:50.641715Z","caller":"traceutil/trace.go:171","msg":"trace[59889846] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"784.284552ms","start":"2026-05-04T21:17:49.857408Z","end":"2026-05-04T21:17:50.641692Z","steps":["trace[59889846] 'process raft request' (duration: 784.058367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:17:50.641871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:17:49.85739Z","time spent":"784.379074ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:17:50.641957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.33822ms","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-04T21:17:50.641957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.150465ms","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-04T21:17:50.64201Z","caller":"traceutil/trace.go:171","msg":"trace[580645040] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1030; }","duration":"228.406731ms","start":"2026-05-04T21:17:50.413588Z","end":"2026-05-04T21:17:50.641995Z","steps":["trace[580645040] 'agreement among raft nodes before linearized reading' (duration: 228.234458ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:17:50.642013Z","caller":"traceutil/trace.go:171","msg":"trace[1068085108] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1030; }","duration":"244.227366ms","start":"2026-05-04T21:17:50.397772Z","end":"2026-05-04T21:17:50.642Z","steps":["trace[1068085108] 'agreement among raft nodes before linearized reading' (duration: 244.079314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:17:50.641994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.740555ms","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-04T21:17:50.642071Z","caller":"traceutil/trace.go:171","msg":"trace[1056613877] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1030; }","duration":"245.871136ms","start":"2026-05-04T21:17:50.396182Z","end":"2026-05-04T21:17:50.642053Z","steps":["trace[1056613877] 'agreement among raft nodes before linearized reading' (duration: 245.635722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:17:50.642065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.807255ms","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-04T21:17:50.64214Z","caller":"traceutil/trace.go:171","msg":"trace[1733058806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1030; }","duration":"246.893396ms","start":"2026-05-04T21:17:50.395227Z","end":"2026-05-04T21:17:50.64212Z","steps":["trace[1733058806] 'agreement among raft nodes before linearized reading' (duration: 246.51362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:17:50.6422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.931593ms","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-04T21:17:50.642246Z","caller":"traceutil/trace.go:171","msg":"trace[1692567105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1030; }","duration":"228.003253ms","start":"2026-05-04T21:17:50.414232Z","end":"2026-05-04T21:17:50.642235Z","steps":["trace[1692567105] 'agreement among raft nodes before linearized reading' (duration: 227.878221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:11.252411Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422295119289646,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-04T21:18:11.482116Z","caller":"traceutil/trace.go:171","msg":"trace[1503556192] linearizableReadLoop","detail":"{readStateIndex:1136; appliedIndex:1135; }","duration":"729.706445ms","start":"2026-05-04T21:18:10.752374Z","end":"2026-05-04T21:18:11.48208Z","steps":["trace[1503556192] 'read index received' (duration: 729.454591ms)","trace[1503556192] 'applied index is now lower than readState.Index' (duration: 250.694µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:18:11.482371Z","caller":"traceutil/trace.go:171","msg":"trace[1000621830] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"755.652507ms","start":"2026-05-04T21:18:10.726693Z","end":"2026-05-04T21:18:11.482345Z","steps":["trace[1000621830] 'process raft request' (duration: 755.221099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:11.482495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.111162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T21:18:11.482504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:10.726662Z","time spent":"755.759509ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T21:18:11.482555Z","caller":"traceutil/trace.go:171","msg":"trace[379758526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1096; }","duration":"730.208774ms","start":"2026-05-04T21:18:10.752331Z","end":"2026-05-04T21:18:11.48254Z","steps":["trace[379758526] 'agreement among raft nodes before linearized reading' (duration: 730.108732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:11.482703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:10.752312Z","time spent":"730.376188ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-04T21:18:11.482821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.795261ms","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-04T21:18:11.482873Z","caller":"traceutil/trace.go:171","msg":"trace[311565077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1096; }","duration":"646.871932ms","start":"2026-05-04T21:18:10.835987Z","end":"2026-05-04T21:18:11.482859Z","steps":["trace[311565077] 'agreement among raft nodes before linearized reading' (duration: 646.693989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:11.482863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.865518ms","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-04T21:18:11.482921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.744553ms","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-04T21:18:11.482915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:10.835978Z","time spent":"646.926624ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-04T21:18:11.482953Z","caller":"traceutil/trace.go:171","msg":"trace[430192434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1096; }","duration":"650.99876ms","start":"2026-05-04T21:18:10.831931Z","end":"2026-05-04T21:18:11.48293Z","steps":["trace[430192434] 'agreement among raft nodes before linearized reading' (duration: 650.718064ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:11.482968Z","caller":"traceutil/trace.go:171","msg":"trace[65704014] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1096; }","duration":"652.821264ms","start":"2026-05-04T21:18:10.830134Z","end":"2026-05-04T21:18:11.482955Z","steps":["trace[65704014] 'agreement among raft nodes before linearized reading' (duration: 652.674031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:11.482958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.178037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-04T21:18:11.482951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.372357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-04T21:18:11.483007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:10.83012Z","time spent":"652.876005ms","remote":"127.0.0.1:55932","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-04T21:18:11.483008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:10.831923Z","time spent":"651.075431ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-04T21:18:11.483016Z","caller":"traceutil/trace.go:171","msg":"trace[2048671877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1096; }","duration":"692.265829ms","start":"2026-05-04T21:18:10.790738Z","end":"2026-05-04T21:18:11.483003Z","steps":["trace[2048671877] 'agreement among raft nodes before linearized reading' (duration: 691.82967ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:11.483045Z","caller":"traceutil/trace.go:171","msg":"trace[1237704454] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1096; }","duration":"651.52818ms","start":"2026-05-04T21:18:10.831489Z","end":"2026-05-04T21:18:11.483018Z","steps":["trace[1237704454] 'agreement among raft nodes before linearized reading' (duration: 651.237445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:11.483064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:10.790727Z","time spent":"692.32833ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-04T21:18:11.483096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:10.831467Z","time spent":"651.619411ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-04T21:18:11.483146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.085856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:18:11.4832Z","caller":"traceutil/trace.go:171","msg":"trace[1918177593] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1096; }","duration":"546.178898ms","start":"2026-05-04T21:18:10.937007Z","end":"2026-05-04T21:18:11.483185Z","steps":["trace[1918177593] 'agreement among raft nodes before linearized reading' (duration: 546.071326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:11.483243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:10.936989Z","time spent":"546.239189ms","remote":"127.0.0.1:41062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-05-04T21:18:11.61173Z","caller":"traceutil/trace.go:171","msg":"trace[1122849062] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"123.198934ms","start":"2026-05-04T21:18:11.488503Z","end":"2026-05-04T21:18:11.611702Z","steps":["trace[1122849062] 'process raft request' (duration: 104.018517ms)","trace[1122849062] 'compare' (duration: 19.049765ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:18:11.658343Z","caller":"traceutil/trace.go:171","msg":"trace[825632498] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"168.915415ms","start":"2026-05-04T21:18:11.489394Z","end":"2026-05-04T21:18:11.658309Z","steps":["trace[825632498] 'process raft request' (duration: 168.813194ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:11.658394Z","caller":"traceutil/trace.go:171","msg":"trace[603299318] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"168.956786ms","start":"2026-05-04T21:18:11.489394Z","end":"2026-05-04T21:18:11.658351Z","steps":["trace[603299318] 'process raft request' (duration: 168.62073ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:11.658713Z","caller":"traceutil/trace.go:171","msg":"trace[372229843] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"168.429735ms","start":"2026-05-04T21:18:11.490263Z","end":"2026-05-04T21:18:11.658693Z","steps":["trace[372229843] 'process raft request' (duration: 168.246142ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:11.658756Z","caller":"traceutil/trace.go:171","msg":"trace[997855707] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"169.22185ms","start":"2026-05-04T21:18:11.489484Z","end":"2026-05-04T21:18:11.658706Z","steps":["trace[997855707] 'process raft request' (duration: 168.776853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:14.352107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.274671971s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-04T21:18:14.352726Z","caller":"traceutil/trace.go:171","msg":"trace[1950533608] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1142; }","duration":"919.391996ms","start":"2026-05-04T21:18:13.433299Z","end":"2026-05-04T21:18:14.352691Z","steps":["trace[1950533608] 'read index received' (duration: 66.811µs)","trace[1950533608] 'applied index is now lower than readState.Index' (duration: 919.323415ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T21:18:14.352888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.568929ms","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-04T21:18:14.352924Z","caller":"traceutil/trace.go:171","msg":"trace[1640391305] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1102; }","duration":"919.666361ms","start":"2026-05-04T21:18:13.433248Z","end":"2026-05-04T21:18:14.352914Z","steps":["trace[1640391305] 'agreement among raft nodes before linearized reading' (duration: 919.548959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:14.352961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:13.433217Z","time spent":"919.734802ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-05-04T21:18:14.374561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.744128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T21:18:14.374653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.618408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-04T21:18:14.374645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.375193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T21:18:14.374744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.021968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:18:14.37477Z","caller":"traceutil/trace.go:171","msg":"trace[295264230] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1103; }","duration":"623.08698ms","start":"2026-05-04T21:18:13.751676Z","end":"2026-05-04T21:18:14.374763Z","steps":["trace[295264230] 'agreement among raft nodes before linearized reading' (duration: 623.048759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:14.374786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.458278ms","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-04T21:18:14.374804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:13.75165Z","time spent":"623.146552ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-04T21:18:14.374814Z","caller":"traceutil/trace.go:171","msg":"trace[683847653] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1103; }","duration":"759.555911ms","start":"2026-05-04T21:18:13.615249Z","end":"2026-05-04T21:18:14.374805Z","steps":["trace[683847653] 'agreement among raft nodes before linearized reading' (duration: 759.466769ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:14.374804Z","caller":"traceutil/trace.go:171","msg":"trace[1700960611] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"885.26022ms","start":"2026-05-04T21:18:13.489514Z","end":"2026-05-04T21:18:14.374774Z","steps":["trace[1700960611] 'process raft request' (duration: 884.783032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:14.374824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.383582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-04T21:18:14.374896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:13.615228Z","time spent":"759.610001ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-04T21:18:14.374903Z","caller":"traceutil/trace.go:171","msg":"trace[817776724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1103; }","duration":"712.521625ms","start":"2026-05-04T21:18:13.66236Z","end":"2026-05-04T21:18:14.374881Z","steps":["trace[817776724] 'agreement among raft nodes before linearized reading' (duration: 712.315941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:14.374945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:13.662335Z","time spent":"712.602467ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-04T21:18:14.374938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:13.489486Z","time spent":"885.372493ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:18:14.374948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.605597ms","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-04T21:18:14.375034Z","caller":"traceutil/trace.go:171","msg":"trace[2144070576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1103; }","duration":"712.743339ms","start":"2026-05-04T21:18:13.662271Z","end":"2026-05-04T21:18:14.375014Z","steps":["trace[2144070576] 'agreement among raft nodes before linearized reading' (duration: 712.459184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:14.375094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.430284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-04T21:18:14.375081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:13.662255Z","time spent":"712.81654ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-04T21:18:14.375133Z","caller":"traceutil/trace.go:171","msg":"trace[977521419] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1103; }","duration":"712.490384ms","start":"2026-05-04T21:18:13.662632Z","end":"2026-05-04T21:18:14.375123Z","steps":["trace[977521419] 'agreement among raft nodes before linearized reading' (duration: 712.274091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:14.375191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:13.66262Z","time spent":"712.562255ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-04T21:18:14.375369Z","caller":"traceutil/trace.go:171","msg":"trace[1489727972] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1103; }","duration":"140.543336ms","start":"2026-05-04T21:18:14.234192Z","end":"2026-05-04T21:18:14.374735Z","steps":["trace[1489727972] 'agreement among raft nodes before linearized reading' (duration: 140.348623ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:14.374687Z","caller":"traceutil/trace.go:171","msg":"trace[27851112] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1103; }","duration":"936.936762ms","start":"2026-05-04T21:18:13.437728Z","end":"2026-05-04T21:18:14.374665Z","steps":["trace[27851112] 'agreement among raft nodes before linearized reading' (duration: 936.738529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:14.375473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:13.437705Z","time spent":"937.731908ms","remote":"127.0.0.1:40924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-04T21:18:14.374702Z","caller":"traceutil/trace.go:171","msg":"trace[103036387] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1103; }","duration":"711.68722ms","start":"2026-05-04T21:18:13.663002Z","end":"2026-05-04T21:18:14.374689Z","steps":["trace[103036387] 'agreement among raft nodes before linearized reading' (duration: 711.495536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:14.375623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:13.662995Z","time spent":"712.612427ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-04T21:18:15.130455Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422295119289665,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-04T21:18:15.631557Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422295119289665,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-04T21:18:16.13238Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422295119289665,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-04T21:18:16.63319Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422295119289665,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-04T21:18:16.752524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00043001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-04T21:18:16.752648Z","caller":"traceutil/trace.go:171","msg":"trace[2093041042] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000621423s","start":"2026-05-04T21:18:14.752003Z","end":"2026-05-04T21:18:16.752625Z","steps":["trace[2093041042] 'agreement among raft nodes before linearized reading' (duration: 2.00045418s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:16.752711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:14.751985Z","time spent":"2.000710985s","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/04 21:18:16 WARNING: [core] [Server #5] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-04T21:18:17.133806Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422295119289665,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-04T21:18:17.461804Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.080791192s","expected-duration":"1s"} {"level":"info","ts":"2026-05-04T21:18:17.462371Z","caller":"traceutil/trace.go:171","msg":"trace[641477766] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"3.081417484s","start":"2026-05-04T21:18:14.380922Z","end":"2026-05-04T21:18:17.462339Z","steps":["trace[641477766] 'process raft request' (duration: 3.08120268s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:17.462564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:14.380904Z","time spent":"3.081560087s","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:18:17.740037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.309168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T21:18:17.740324Z","caller":"traceutil/trace.go:171","msg":"trace[702107572] linearizableReadLoop","detail":"{readStateIndex:1150; appliedIndex:1145; }","duration":"3.110632218s","start":"2026-05-04T21:18:14.629674Z","end":"2026-05-04T21:18:17.740307Z","steps":["trace[702107572] 'read index received' (duration: 2.832655813s)","trace[702107572] 'applied index is now lower than readState.Index' (duration: 277.975355ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:18:17.740371Z","caller":"traceutil/trace.go:171","msg":"trace[194748020] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"3.35892394s","start":"2026-05-04T21:18:14.381409Z","end":"2026-05-04T21:18:17.740333Z","steps":["trace[194748020] 'process raft request' (duration: 3.358746877s)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:17.740422Z","caller":"traceutil/trace.go:171","msg":"trace[902236206] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"3.358781498s","start":"2026-05-04T21:18:14.381629Z","end":"2026-05-04T21:18:17.740411Z","steps":["trace[902236206] 'process raft request' (duration: 3.358582834s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:17.740481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:14.381621Z","time spent":"3.358827558s","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:18:17.740534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:14.381391Z","time spent":"3.359054922s","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:18:17.740717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.895819758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-04T21:18:17.740699Z","caller":"traceutil/trace.go:171","msg":"trace[282234224] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"3.358624405s","start":"2026-05-04T21:18:14.382053Z","end":"2026-05-04T21:18:17.740677Z","steps":["trace[282234224] 'process raft request' (duration: 3.358188807s)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:17.740751Z","caller":"traceutil/trace.go:171","msg":"trace[1266629356] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1109; }","duration":"2.895853639s","start":"2026-05-04T21:18:14.844884Z","end":"2026-05-04T21:18:17.740738Z","steps":["trace[1266629356] 'agreement among raft nodes before linearized reading' (duration: 2.895771217s)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:17.74077Z","caller":"traceutil/trace.go:171","msg":"trace[1689889895] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"3.359662883s","start":"2026-05-04T21:18:14.381093Z","end":"2026-05-04T21:18:17.740756Z","steps":["trace[1689889895] 'process raft request' (duration: 3.14353586s)","trace[1689889895] 'compare' (duration: 215.172725ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T21:18:17.740788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:14.844812Z","time spent":"2.895965911s","remote":"127.0.0.1:40850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-05-04T21:18:17.740767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.111045955s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T21:18:17.740844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.103791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:18:17.741504Z","caller":"traceutil/trace.go:171","msg":"trace[67025235] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1109; }","duration":"405.830185ms","start":"2026-05-04T21:18:17.335644Z","end":"2026-05-04T21:18:17.741474Z","steps":["trace[67025235] 'agreement among raft nodes before linearized reading' (duration: 405.08108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:17.741545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:14.381079Z","time spent":"3.359733685s","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:18:17.741609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:17.335619Z","time spent":"405.952478ms","remote":"127.0.0.1:56028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-05-04T21:18:17.741866Z","caller":"traceutil/trace.go:171","msg":"trace[1359353087] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1109; }","duration":"3.112213157s","start":"2026-05-04T21:18:14.629618Z","end":"2026-05-04T21:18:17.741831Z","steps":["trace[1359353087] 'agreement among raft nodes before linearized reading' (duration: 3.110965144s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:17.741406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:14.382047Z","time spent":"3.358705316s","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:18:17.741967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:14.629593Z","time spent":"3.112338559s","remote":"127.0.0.1:55960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-05-04T21:18:17.74245Z","caller":"traceutil/trace.go:171","msg":"trace[422285707] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"1.361372114s","start":"2026-05-04T21:18:16.381046Z","end":"2026-05-04T21:18:17.742418Z","steps":["trace[422285707] 'process raft request' (duration: 1.359222954s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:17.742555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:16.381013Z","time spent":"1.361470525s","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:18:17.742847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.090052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:18:17.742891Z","caller":"traceutil/trace.go:171","msg":"trace[1614903838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1109; }","duration":"990.194214ms","start":"2026-05-04T21:18:16.752681Z","end":"2026-05-04T21:18:17.742875Z","steps":["trace[1614903838] 'agreement among raft nodes before linearized reading' (duration: 990.107802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:17.742933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:16.752653Z","time spent":"990.271866ms","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-04T21:18:17.744317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.18609546s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:18:17.744465Z","caller":"traceutil/trace.go:171","msg":"trace[1191915852] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1109; }","duration":"1.186280393s","start":"2026-05-04T21:18:16.558141Z","end":"2026-05-04T21:18:17.744421Z","steps":["trace[1191915852] 'agreement among raft nodes before linearized reading' (duration: 1.184819566s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:17.744521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:16.558118Z","time spent":"1.186389916s","remote":"127.0.0.1:40996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-04T21:18:18.376047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.415731ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-05-04T21:18:18.376257Z","caller":"traceutil/trace.go:171","msg":"trace[896793135] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1150; }","duration":"623.780571ms","start":"2026-05-04T21:18:17.752453Z","end":"2026-05-04T21:18:18.376233Z","steps":["trace[896793135] 'read index received' (duration: 473.010174ms)","trace[896793135] 'applied index is now lower than readState.Index' (duration: 150.768467ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T21:18:18.376381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:17.751147Z","time spent":"625.227009ms","remote":"127.0.0.1:55754","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-04T21:18:18.376775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.827869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-04T21:18:18.376934Z","caller":"traceutil/trace.go:171","msg":"trace[845211874] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:1109; }","duration":"475.055103ms","start":"2026-05-04T21:18:17.901852Z","end":"2026-05-04T21:18:18.376907Z","steps":["trace[845211874] 'agreement among raft nodes before linearized reading' (duration: 474.678417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:18.377013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:17.901825Z","time spent":"475.170907ms","remote":"127.0.0.1:55716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.25\" "} {"level":"warn","ts":"2026-05-04T21:18:18.377049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.535798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:18:18.377169Z","caller":"traceutil/trace.go:171","msg":"trace[1561485483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1109; }","duration":"624.730831ms","start":"2026-05-04T21:18:17.752409Z","end":"2026-05-04T21:18:18.37714Z","steps":["trace[1561485483] 'agreement among raft nodes before linearized reading' (duration: 624.518907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:18.377231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:17.752392Z","time spent":"624.825053ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-04T21:18:18.377464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.736836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:18:18.377584Z","caller":"traceutil/trace.go:171","msg":"trace[1498123650] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1109; }","duration":"215.94782ms","start":"2026-05-04T21:18:18.161607Z","end":"2026-05-04T21:18:18.377554Z","steps":["trace[1498123650] 'agreement among raft nodes before linearized reading' (duration: 215.717116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:18.879705Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422295119289677,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-04T21:18:19.169147Z","caller":"traceutil/trace.go:171","msg":"trace[1048544397] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"790.829092ms","start":"2026-05-04T21:18:18.378283Z","end":"2026-05-04T21:18:19.169112Z","steps":["trace[1048544397] 'process raft request' (duration: 790.601938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:19.169816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:18.378257Z","time spent":"791.472775ms","remote":"127.0.0.1:55754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-04T21:18:19.836921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:18.379052Z","time spent":"1.457860579s","remote":"127.0.0.1:55716","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-04T21:18:19.837126Z","caller":"traceutil/trace.go:171","msg":"trace[1336966724] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1151; }","duration":"1.458300838s","start":"2026-05-04T21:18:18.378796Z","end":"2026-05-04T21:18:19.837097Z","steps":["trace[1336966724] 'read index received' (duration: 790.133349ms)","trace[1336966724] 'applied index is now lower than readState.Index' (duration: 668.166099ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:18:19.837251Z","caller":"traceutil/trace.go:171","msg":"trace[519891549] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"1.451381699s","start":"2026-05-04T21:18:18.385859Z","end":"2026-05-04T21:18:19.83724Z","steps":["trace[519891549] 'process raft request' (duration: 1.450999502s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:19.837556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:18.385847Z","time spent":"1.451439141s","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:18:19.837589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.458764817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:18:19.837642Z","caller":"traceutil/trace.go:171","msg":"trace[908847204] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1111; }","duration":"1.458855468s","start":"2026-05-04T21:18:18.37877Z","end":"2026-05-04T21:18:19.837626Z","steps":["trace[908847204] 'agreement among raft nodes before linearized reading' (duration: 1.458543473s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:19.837683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:18.378759Z","time spent":"1.458917809s","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-04T21:18:19.849166Z","caller":"traceutil/trace.go:171","msg":"trace[1580478065] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"673.221783ms","start":"2026-05-04T21:18:19.175922Z","end":"2026-05-04T21:18:19.849144Z","steps":["trace[1580478065] 'process raft request' (duration: 673.03124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:19.849288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:19.175894Z","time spent":"673.333425ms","remote":"127.0.0.1:55754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-04T21:18:19.84966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.819954ms","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-04T21:18:19.849694Z","caller":"traceutil/trace.go:171","msg":"trace[579048133] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1113; }","duration":"101.899006ms","start":"2026-05-04T21:18:19.747787Z","end":"2026-05-04T21:18:19.849686Z","steps":["trace[579048133] 'agreement among raft nodes before linearized reading' (duration: 101.800174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:19.849955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.962261ms","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-04T21:18:19.850103Z","caller":"traceutil/trace.go:171","msg":"trace[533865682] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1113; }","duration":"382.064932ms","start":"2026-05-04T21:18:19.467928Z","end":"2026-05-04T21:18:19.849993Z","steps":["trace[533865682] 'agreement among raft nodes before linearized reading' (duration: 381.712605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:19.850153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:19.467906Z","time spent":"382.235476ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-04T21:18:19.850433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.158642ms","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-04T21:18:19.850556Z","caller":"traceutil/trace.go:171","msg":"trace[916067938] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1113; }","duration":"102.339765ms","start":"2026-05-04T21:18:19.748193Z","end":"2026-05-04T21:18:19.850533Z","steps":["trace[916067938] 'agreement among raft nodes before linearized reading' (duration: 101.896677ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:20.387705Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986422295119289692,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-04T21:18:20.472747Z","caller":"traceutil/trace.go:171","msg":"trace[2011308767] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"618.068645ms","start":"2026-05-04T21:18:19.85465Z","end":"2026-05-04T21:18:20.472719Z","steps":["trace[2011308767] 'process raft request' (duration: 617.981643ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:20.47277Z","caller":"traceutil/trace.go:171","msg":"trace[1300627913] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"618.33081ms","start":"2026-05-04T21:18:19.854399Z","end":"2026-05-04T21:18:20.47273Z","steps":["trace[1300627913] 'process raft request' (duration: 618.065505ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:20.472984Z","caller":"traceutil/trace.go:171","msg":"trace[509845172] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"617.600708ms","start":"2026-05-04T21:18:19.855365Z","end":"2026-05-04T21:18:20.472966Z","steps":["trace[509845172] 'process raft request' (duration: 617.317082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:20.473078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:19.85464Z","time spent":"618.196528ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:18:20.473088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:19.855356Z","time spent":"617.68245ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T21:18:20.473296Z","caller":"traceutil/trace.go:171","msg":"trace[774948538] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"617.821061ms","start":"2026-05-04T21:18:19.855429Z","end":"2026-05-04T21:18:20.47325Z","steps":["trace[774948538] 'process raft request' (duration: 617.464934ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:20.473361Z","caller":"traceutil/trace.go:171","msg":"trace[1743703113] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1157; }","duration":"585.695573ms","start":"2026-05-04T21:18:19.887651Z","end":"2026-05-04T21:18:20.473347Z","steps":["trace[1743703113] 'read index received' (duration: 584.776636ms)","trace[1743703113] 'applied index is now lower than readState.Index' (duration: 917.847µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:18:20.473403Z","caller":"traceutil/trace.go:171","msg":"trace[1592584403] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"617.464724ms","start":"2026-05-04T21:18:19.855926Z","end":"2026-05-04T21:18:20.47339Z","steps":["trace[1592584403] 'process raft request' (duration: 617.216589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:20.473472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:19.855918Z","time spent":"617.516886ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:18:20.473471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:19.855406Z","time spent":"617.966165ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:18:20.473548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.859109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-05-04T21:18:20.473479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.823375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:18:20.473586Z","caller":"traceutil/trace.go:171","msg":"trace[168543770] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1119; }","duration":"583.916249ms","start":"2026-05-04T21:18:19.889658Z","end":"2026-05-04T21:18:20.473575Z","steps":["trace[168543770] 'agreement among raft nodes before linearized reading' (duration: 583.852178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:20.473619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:19.889651Z","time spent":"583.961521ms","remote":"127.0.0.1:55824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-05-04T21:18:20.473637Z","caller":"traceutil/trace.go:171","msg":"trace[1083244436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1119; }","duration":"585.956858ms","start":"2026-05-04T21:18:19.887646Z","end":"2026-05-04T21:18:20.473603Z","steps":["trace[1083244436] 'agreement among raft nodes before linearized reading' (duration: 585.799075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:20.473664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:19.854368Z","time spent":"618.516963ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:18:20.473717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:19.887598Z","time spent":"586.09819ms","remote":"127.0.0.1:55672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-04T21:18:23.571208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.607431ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-04T21:18:24.055273Z","caller":"traceutil/trace.go:171","msg":"trace[2137955227] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"183.826982ms","start":"2026-05-04T21:18:23.871414Z","end":"2026-05-04T21:18:24.055241Z","steps":["trace[2137955227] 'process raft request' (duration: 183.623668ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:34.267612Z","caller":"traceutil/trace.go:171","msg":"trace[1167009875] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"175.536851ms","start":"2026-05-04T21:18:34.092043Z","end":"2026-05-04T21:18:34.26758Z","steps":["trace[1167009875] 'process raft request' (duration: 175.365818ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:34.76518Z","caller":"traceutil/trace.go:171","msg":"trace[1290757370] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"131.373914ms","start":"2026-05-04T21:18:34.633776Z","end":"2026-05-04T21:18:34.76515Z","steps":["trace[1290757370] 'process raft request' (duration: 93.211558ms)","trace[1290757370] 'compare' (duration: 37.85446ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:18:37.035852Z","caller":"traceutil/trace.go:171","msg":"trace[201719360] linearizableReadLoop","detail":"{readStateIndex:1265; appliedIndex:1264; }","duration":"418.08716ms","start":"2026-05-04T21:18:36.617734Z","end":"2026-05-04T21:18:37.035821Z","steps":["trace[201719360] 'read index received' (duration: 417.805425ms)","trace[201719360] 'applied index is now lower than readState.Index' (duration: 280.535µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:18:37.035984Z","caller":"traceutil/trace.go:171","msg":"trace[1113755734] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"762.320834ms","start":"2026-05-04T21:18:36.273615Z","end":"2026-05-04T21:18:37.035936Z","steps":["trace[1113755734] 'process raft request' (duration: 762.028869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:37.036168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.990055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T21:18:37.036136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.390855ms","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-04T21:18:37.036193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:36.273576Z","time spent":"762.502058ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T21:18:37.03622Z","caller":"traceutil/trace.go:171","msg":"trace[372530865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1218; }","duration":"284.083987ms","start":"2026-05-04T21:18:36.75212Z","end":"2026-05-04T21:18:37.036204Z","steps":["trace[372530865] 'agreement among raft nodes before linearized reading' (duration: 283.995265ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:37.036223Z","caller":"traceutil/trace.go:171","msg":"trace[1592799419] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1218; }","duration":"418.542738ms","start":"2026-05-04T21:18:36.617668Z","end":"2026-05-04T21:18:37.03621Z","steps":["trace[1592799419] 'agreement among raft nodes before linearized reading' (duration: 418.324384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:37.036291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:36.617647Z","time spent":"418.6099ms","remote":"127.0.0.1:55932","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-04T21:18:37.036293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.869854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-04T21:18:37.036283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.412738ms","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-04T21:18:37.036356Z","caller":"traceutil/trace.go:171","msg":"trace[1132124457] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1218; }","duration":"266.966785ms","start":"2026-05-04T21:18:36.76937Z","end":"2026-05-04T21:18:37.036336Z","steps":["trace[1132124457] 'agreement among raft nodes before linearized reading' (duration: 266.776682ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:37.036368Z","caller":"traceutil/trace.go:171","msg":"trace[414016122] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1218; }","duration":"400.516081ms","start":"2026-05-04T21:18:36.635837Z","end":"2026-05-04T21:18:37.036353Z","steps":["trace[414016122] 'agreement among raft nodes before linearized reading' (duration: 400.282216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:37.036399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.248734ms","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-04T21:18:37.036415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:36.63583Z","time spent":"400.570661ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-04T21:18:37.036446Z","caller":"traceutil/trace.go:171","msg":"trace[860951034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1218; }","duration":"376.301835ms","start":"2026-05-04T21:18:36.660128Z","end":"2026-05-04T21:18:37.036429Z","steps":["trace[860951034] 'agreement among raft nodes before linearized reading' (duration: 376.192283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:37.036488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:36.66012Z","time spent":"376.354716ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-04T21:18:37.036546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.217525ms","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-04T21:18:37.036578Z","caller":"traceutil/trace.go:171","msg":"trace[893945301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1218; }","duration":"376.263605ms","start":"2026-05-04T21:18:36.660305Z","end":"2026-05-04T21:18:37.036568Z","steps":["trace[893945301] 'agreement among raft nodes before linearized reading' (duration: 376.166583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:37.036604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:36.6603Z","time spent":"376.297736ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-04T21:18:37.574546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.165014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T21:18:37.574636Z","caller":"traceutil/trace.go:171","msg":"trace[1844512713] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"532.003877ms","start":"2026-05-04T21:18:37.042618Z","end":"2026-05-04T21:18:37.574622Z","steps":["trace[1844512713] 'process raft request' (duration: 339.700371ms)","trace[1844512713] 'compare' (duration: 192.0208ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T21:18:37.57467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:37.042601Z","time spent":"532.053957ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T21:18:37.645104Z","caller":"traceutil/trace.go:171","msg":"trace[2004791705] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"602.228502ms","start":"2026-05-04T21:18:37.042847Z","end":"2026-05-04T21:18:37.645076Z","steps":["trace[2004791705] 'process raft request' (duration: 602.151141ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:37.645218Z","caller":"traceutil/trace.go:171","msg":"trace[1629368359] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"602.322855ms","start":"2026-05-04T21:18:37.042886Z","end":"2026-05-04T21:18:37.645209Z","steps":["trace[1629368359] 'process raft request' (duration: 602.150091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:37.645275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:37.042839Z","time spent":"602.352495ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T21:18:37.645105Z","caller":"traceutil/trace.go:171","msg":"trace[348651621] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"602.320374ms","start":"2026-05-04T21:18:37.042748Z","end":"2026-05-04T21:18:37.645068Z","steps":["trace[348651621] 'process raft request' (duration: 602.049689ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:37.64513Z","caller":"traceutil/trace.go:171","msg":"trace[1666834039] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"602.287104ms","start":"2026-05-04T21:18:37.042818Z","end":"2026-05-04T21:18:37.645105Z","steps":["trace[1666834039] 'process raft request' (duration: 602.143151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:37.64557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:37.042722Z","time spent":"602.711882ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:18:37.645618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:37.042805Z","time spent":"602.779793ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:18:37.645351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:37.042877Z","time spent":"602.382225ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:18:43.845299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.488319ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T21:18:43.845447Z","caller":"traceutil/trace.go:171","msg":"trace[984896624] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"164.930343ms","start":"2026-05-04T21:18:43.68049Z","end":"2026-05-04T21:18:43.84542Z","steps":["trace[984896624] 'process raft request' (duration: 20.194988ms)","trace[984896624] 'compare' (duration: 144.322416ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:18:43.87325Z","caller":"traceutil/trace.go:171","msg":"trace[616896968] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"191.213505ms","start":"2026-05-04T21:18:43.682009Z","end":"2026-05-04T21:18:43.873223Z","steps":["trace[616896968] 'process raft request' (duration: 191.083523ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:43.873437Z","caller":"traceutil/trace.go:171","msg":"trace[787029146] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"191.38575ms","start":"2026-05-04T21:18:43.682037Z","end":"2026-05-04T21:18:43.873423Z","steps":["trace[787029146] 'process raft request' (duration: 191.160835ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:43.873625Z","caller":"traceutil/trace.go:171","msg":"trace[294983057] linearizableReadLoop","detail":"{readStateIndex:1293; appliedIndex:1290; }","duration":"120.845506ms","start":"2026-05-04T21:18:43.752771Z","end":"2026-05-04T21:18:43.873617Z","steps":["trace[294983057] 'read index received' (duration: 120.244054ms)","trace[294983057] 'applied index is now lower than readState.Index' (duration: 601.052µs)"],"step_count":2} {"level":"warn","ts":"2026-05-04T21:18:43.873698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.909018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:18:43.87372Z","caller":"traceutil/trace.go:171","msg":"trace[1952324827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1244; }","duration":"120.993999ms","start":"2026-05-04T21:18:43.75272Z","end":"2026-05-04T21:18:43.873714Z","steps":["trace[1952324827] 'agreement among raft nodes before linearized reading' (duration: 120.928688ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:43.87374Z","caller":"traceutil/trace.go:171","msg":"trace[1832533445] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"190.761797ms","start":"2026-05-04T21:18:43.682969Z","end":"2026-05-04T21:18:43.873731Z","steps":["trace[1832533445] 'process raft request' (duration: 190.404701ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:49.901968Z","caller":"traceutil/trace.go:171","msg":"trace[1414185280] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1308; }","duration":"149.184067ms","start":"2026-05-04T21:18:49.752763Z","end":"2026-05-04T21:18:49.901947Z","steps":["trace[1414185280] 'read index received' (duration: 149.017304ms)","trace[1414185280] 'applied index is now lower than readState.Index' (duration: 166.063µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:18:49.902185Z","caller":"traceutil/trace.go:171","msg":"trace[1315159739] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"207.932289ms","start":"2026-05-04T21:18:49.694244Z","end":"2026-05-04T21:18:49.902176Z","steps":["trace[1315159739] 'process raft request' (duration: 207.592733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:49.902347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.570175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:18:49.902375Z","caller":"traceutil/trace.go:171","msg":"trace[216826734] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1259; }","duration":"149.631276ms","start":"2026-05-04T21:18:49.752736Z","end":"2026-05-04T21:18:49.902368Z","steps":["trace[216826734] 'agreement among raft nodes before linearized reading' (duration: 149.571895ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:52.185283Z","caller":"traceutil/trace.go:171","msg":"trace[1068509561] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"212.7991ms","start":"2026-05-04T21:18:51.972442Z","end":"2026-05-04T21:18:52.185241Z","steps":["trace[1068509561] 'process raft request' (duration: 142.781577ms)","trace[1068509561] 'compare' (duration: 69.755488ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:18:52.264775Z","caller":"traceutil/trace.go:171","msg":"trace[1236432125] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1330; }","duration":"265.278615ms","start":"2026-05-04T21:18:51.999475Z","end":"2026-05-04T21:18:52.264753Z","steps":["trace[1236432125] 'read index received' (duration: 115.738991ms)","trace[1236432125] 'applied index is now lower than readState.Index' (duration: 149.538924ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T21:18:52.264996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.488579ms","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-04T21:18:52.265029Z","caller":"traceutil/trace.go:171","msg":"trace[170969528] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1282; }","duration":"265.57444ms","start":"2026-05-04T21:18:51.999447Z","end":"2026-05-04T21:18:52.265021Z","steps":["trace[170969528] 'agreement among raft nodes before linearized reading' (duration: 265.419967ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:52.264962Z","caller":"traceutil/trace.go:171","msg":"trace[1842139949] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"286.88414ms","start":"2026-05-04T21:18:51.977918Z","end":"2026-05-04T21:18:52.264803Z","steps":["trace[1842139949] 'process raft request' (duration: 286.724647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:52.26509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.502129ms","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-04T21:18:52.265277Z","caller":"traceutil/trace.go:171","msg":"trace[1731112084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1282; }","duration":"265.828945ms","start":"2026-05-04T21:18:51.99943Z","end":"2026-05-04T21:18:52.265259Z","steps":["trace[1731112084] 'agreement among raft nodes before linearized reading' (duration: 265.468329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:52.26509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.273132ms","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-04T21:18:52.265407Z","caller":"traceutil/trace.go:171","msg":"trace[11685828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1282; }","duration":"259.621948ms","start":"2026-05-04T21:18:52.005766Z","end":"2026-05-04T21:18:52.265387Z","steps":["trace[11685828] 'agreement among raft nodes before linearized reading' (duration: 259.162489ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:52.610994Z","caller":"traceutil/trace.go:171","msg":"trace[531897114] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"342.213317ms","start":"2026-05-04T21:18:52.268751Z","end":"2026-05-04T21:18:52.610964Z","steps":["trace[531897114] 'process raft request' (duration: 342.050744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:52.611166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:52.268731Z","time spent":"342.343869ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:18:53.230156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.558849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T21:18:53.230275Z","caller":"traceutil/trace.go:171","msg":"trace[1895847516] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1332; }","duration":"960.621333ms","start":"2026-05-04T21:18:52.269641Z","end":"2026-05-04T21:18:53.230263Z","steps":["trace[1895847516] 'read index received' (duration: 341.126317ms)","trace[1895847516] 'applied index is now lower than readState.Index' (duration: 619.494436ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:18:53.230404Z","caller":"traceutil/trace.go:171","msg":"trace[706968254] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"961.563089ms","start":"2026-05-04T21:18:52.268835Z","end":"2026-05-04T21:18:53.230398Z","steps":["trace[706968254] 'process raft request' (duration: 542.696585ms)","trace[706968254] 'compare' (duration: 418.466077ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T21:18:53.230441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:52.268807Z","time spent":"961.614512ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T21:18:53.230551Z","caller":"traceutil/trace.go:171","msg":"trace[367267131] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"960.503641ms","start":"2026-05-04T21:18:52.270043Z","end":"2026-05-04T21:18:53.230547Z","steps":["trace[367267131] 'process raft request' (duration: 960.189404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:53.230575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:52.270036Z","time spent":"960.526451ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:18:53.230699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.066982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-04T21:18:53.23072Z","caller":"traceutil/trace.go:171","msg":"trace[563333565] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1285; }","duration":"961.116112ms","start":"2026-05-04T21:18:52.269599Z","end":"2026-05-04T21:18:53.230715Z","steps":["trace[563333565] 'agreement among raft nodes before linearized reading' (duration: 961.065891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:53.230735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:52.269587Z","time spent":"961.145153ms","remote":"127.0.0.1:56030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-04T21:18:53.769442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.763339ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-04T21:18:53.769639Z","caller":"traceutil/trace.go:171","msg":"trace[661273916] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1335; }","duration":"539.321774ms","start":"2026-05-04T21:18:53.23029Z","end":"2026-05-04T21:18:53.769611Z","steps":["trace[661273916] 'read index received' (duration: 5.509304ms)","trace[661273916] 'applied index is now lower than readState.Index' (duration: 533.8108ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:18:53.769696Z","caller":"traceutil/trace.go:171","msg":"trace[1864888616] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"534.216658ms","start":"2026-05-04T21:18:53.235462Z","end":"2026-05-04T21:18:53.769679Z","steps":["trace[1864888616] 'process raft request' (duration: 534.075505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:53.769732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018522639s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T21:18:53.769768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:53.235442Z","time spent":"534.281458ms","remote":"127.0.0.1:56030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T21:18:53.769793Z","caller":"traceutil/trace.go:171","msg":"trace[1464911357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1286; }","duration":"1.01858967s","start":"2026-05-04T21:18:52.751177Z","end":"2026-05-04T21:18:53.769767Z","steps":["trace[1464911357] 'agreement among raft nodes before linearized reading' (duration: 1.018457948s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:53.769826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:52.751155Z","time spent":"1.018665022s","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-04T21:18:53.769974Z","caller":"traceutil/trace.go:171","msg":"trace[685405670] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"467.047858ms","start":"2026-05-04T21:18:53.30292Z","end":"2026-05-04T21:18:53.769968Z","steps":["trace[685405670] 'process raft request' (duration: 466.913265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:53.769993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.152458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T21:18:53.770005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:53.302907Z","time spent":"467.079129ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T21:18:53.77002Z","caller":"traceutil/trace.go:171","msg":"trace[1986782980] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1288; }","duration":"164.24759ms","start":"2026-05-04T21:18:53.605765Z","end":"2026-05-04T21:18:53.770013Z","steps":["trace[1986782980] 'agreement among raft nodes before linearized reading' (duration: 164.151838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:53.770009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.424482962s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-7vb8k\" ","response":"range_response_count:1 size:11057"} {"level":"info","ts":"2026-05-04T21:18:53.770099Z","caller":"traceutil/trace.go:171","msg":"trace[154570523] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-7vb8k; range_end:; response_count:1; response_revision:1287; }","duration":"1.424630734s","start":"2026-05-04T21:18:52.345446Z","end":"2026-05-04T21:18:53.770077Z","steps":["trace[154570523] 'agreement among raft nodes before linearized reading' (duration: 1.424242317s)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:53.770091Z","caller":"traceutil/trace.go:171","msg":"trace[23907169] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"490.167261ms","start":"2026-05-04T21:18:53.27988Z","end":"2026-05-04T21:18:53.770047Z","steps":["trace[23907169] 'process raft request' (duration: 489.760834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:53.770146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:52.345428Z","time spent":"1.424709126s","remote":"127.0.0.1:55848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11080,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-7vb8k\" "} {"level":"warn","ts":"2026-05-04T21:18:53.770286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:53.279854Z","time spent":"490.328924ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T21:18:53.924051Z","caller":"traceutil/trace.go:171","msg":"trace[1705251185] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"141.321331ms","start":"2026-05-04T21:18:53.782696Z","end":"2026-05-04T21:18:53.924018Z","steps":["trace[1705251185] 'process raft request' (duration: 89.56688ms)","trace[1705251185] 'compare' (duration: 51.125878ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:18:54.097966Z","caller":"traceutil/trace.go:171","msg":"trace[2087007057] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"166.332849ms","start":"2026-05-04T21:18:53.931603Z","end":"2026-05-04T21:18:54.097936Z","steps":["trace[2087007057] 'process raft request' (duration: 102.3699ms)","trace[2087007057] 'compare' (duration: 63.827177ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:18:54.177712Z","caller":"traceutil/trace.go:171","msg":"trace[884923023] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"245.869401ms","start":"2026-05-04T21:18:53.931819Z","end":"2026-05-04T21:18:54.177689Z","steps":["trace[884923023] 'process raft request' (duration: 245.729808ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:54.177894Z","caller":"traceutil/trace.go:171","msg":"trace[611540300] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1340; }","duration":"206.216637ms","start":"2026-05-04T21:18:53.971639Z","end":"2026-05-04T21:18:54.177855Z","steps":["trace[611540300] 'read index received' (duration: 62.447331ms)","trace[611540300] 'applied index is now lower than readState.Index' (duration: 143.767436ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:18:54.177988Z","caller":"traceutil/trace.go:171","msg":"trace[147227228] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"240.773875ms","start":"2026-05-04T21:18:53.937206Z","end":"2026-05-04T21:18:54.17798Z","steps":["trace[147227228] 'process raft request' (duration: 240.447189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:54.178218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.555073ms","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-04T21:18:54.178263Z","caller":"traceutil/trace.go:171","msg":"trace[694412294] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1292; }","duration":"206.656275ms","start":"2026-05-04T21:18:53.971595Z","end":"2026-05-04T21:18:54.178252Z","steps":["trace[694412294] 'agreement among raft nodes before linearized reading' (duration: 206.37584ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:54.299211Z","caller":"traceutil/trace.go:171","msg":"trace[477678336] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1343; }","duration":"111.086753ms","start":"2026-05-04T21:18:54.188094Z","end":"2026-05-04T21:18:54.29918Z","steps":["trace[477678336] 'read index received' (duration: 110.958271ms)","trace[477678336] 'applied index is now lower than readState.Index' (duration: 127.252µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:18:54.299306Z","caller":"traceutil/trace.go:171","msg":"trace[564349840] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"116.501214ms","start":"2026-05-04T21:18:54.182782Z","end":"2026-05-04T21:18:54.299283Z","steps":["trace[564349840] 'process raft request' (duration: 116.031866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:54.299523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.40142ms","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-04T21:18:54.299639Z","caller":"traceutil/trace.go:171","msg":"trace[1111619713] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1293; }","duration":"111.544662ms","start":"2026-05-04T21:18:54.188069Z","end":"2026-05-04T21:18:54.299614Z","steps":["trace[1111619713] 'agreement among raft nodes before linearized reading' (duration: 111.299738ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:54.480773Z","caller":"traceutil/trace.go:171","msg":"trace[1499154235] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"177.853836ms","start":"2026-05-04T21:18:54.302892Z","end":"2026-05-04T21:18:54.480746Z","steps":["trace[1499154235] 'process raft request' (duration: 177.786614ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:54.480853Z","caller":"traceutil/trace.go:171","msg":"trace[1242198095] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"193.261184ms","start":"2026-05-04T21:18:54.287553Z","end":"2026-05-04T21:18:54.480814Z","steps":["trace[1242198095] 'process raft request' (duration: 107.196589ms)","trace[1242198095] 'compare' (duration: 85.751138ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T21:18:55.555128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.799094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:18:55.555261Z","caller":"traceutil/trace.go:171","msg":"trace[409648287] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1296; }","duration":"314.014788ms","start":"2026-05-04T21:18:55.241216Z","end":"2026-05-04T21:18:55.555231Z","steps":["trace[409648287] 'count revisions from in-memory index tree' (duration: 313.648462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:55.555326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:55.241194Z","time spent":"314.110799ms","remote":"127.0.0.1:47854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-04T21:18:55.555473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.268366ms","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-04T21:18:55.555541Z","caller":"traceutil/trace.go:171","msg":"trace[1940757575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1296; }","duration":"320.370127ms","start":"2026-05-04T21:18:55.235155Z","end":"2026-05-04T21:18:55.555525Z","steps":["trace[1940757575] 'range keys from in-memory index tree' (duration: 320.137003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:55.555569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:55.235147Z","time spent":"320.415549ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-04T21:18:55.55574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.5201ms","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-04T21:18:55.555854Z","caller":"traceutil/trace.go:171","msg":"trace[44533460] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1296; }","duration":"321.154622ms","start":"2026-05-04T21:18:55.234672Z","end":"2026-05-04T21:18:55.555827Z","steps":["trace[44533460] 'range keys from in-memory index tree' (duration: 320.318707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:55.556037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:55.234652Z","time spent":"321.367726ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-04T21:18:56.120046Z","caller":"traceutil/trace.go:171","msg":"trace[716925838] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"560.49232ms","start":"2026-05-04T21:18:55.559529Z","end":"2026-05-04T21:18:56.120021Z","steps":["trace[716925838] 'process raft request' (duration: 560.329057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:56.120208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:55.559509Z","time spent":"560.606842ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T21:18:56.447229Z","caller":"traceutil/trace.go:171","msg":"trace[1904023195] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"790.409321ms","start":"2026-05-04T21:18:55.656792Z","end":"2026-05-04T21:18:56.447202Z","steps":["trace[1904023195] 'process raft request' (duration: 790.31316ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:56.447265Z","caller":"traceutil/trace.go:171","msg":"trace[854823034] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"887.151496ms","start":"2026-05-04T21:18:55.560074Z","end":"2026-05-04T21:18:56.447226Z","steps":["trace[854823034] 'process raft request' (duration: 886.82901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:56.44737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:55.656769Z","time spent":"790.534504ms","remote":"127.0.0.1:55754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-04T21:18:56.447368Z","caller":"traceutil/trace.go:171","msg":"trace[1994836517] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1347; }","duration":"716.918633ms","start":"2026-05-04T21:18:55.730425Z","end":"2026-05-04T21:18:56.447344Z","steps":["trace[1994836517] 'read index received' (duration: 389.502233ms)","trace[1994836517] 'applied index is now lower than readState.Index' (duration: 327.41266ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:18:56.447417Z","caller":"traceutil/trace.go:171","msg":"trace[898328833] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"671.208406ms","start":"2026-05-04T21:18:55.776171Z","end":"2026-05-04T21:18:56.447379Z","steps":["trace[898328833] 'process raft request' (duration: 670.978371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:56.447442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:55.560068Z","time spent":"887.281198ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:18:56.447599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:55.776141Z","time spent":"671.360619ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:18:56.447599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.808336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-04T21:18:56.447594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.152837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:18:56.447677Z","caller":"traceutil/trace.go:171","msg":"trace[123945690] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1300; }","duration":"704.926368ms","start":"2026-05-04T21:18:55.742729Z","end":"2026-05-04T21:18:56.447656Z","steps":["trace[123945690] 'agreement among raft nodes before linearized reading' (duration: 704.806475ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:56.447682Z","caller":"traceutil/trace.go:171","msg":"trace[891201523] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1300; }","duration":"717.247589ms","start":"2026-05-04T21:18:55.730419Z","end":"2026-05-04T21:18:56.447667Z","steps":["trace[891201523] 'agreement among raft nodes before linearized reading' (duration: 717.057395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:56.447745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.192321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-04T21:18:56.447746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:55.73036Z","time spent":"717.371082ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-04T21:18:56.447769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.064793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T21:18:56.447744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:55.742713Z","time spent":"705.01497ms","remote":"127.0.0.1:41104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-05-04T21:18:56.447789Z","caller":"traceutil/trace.go:171","msg":"trace[1206067419] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1300; }","duration":"666.283423ms","start":"2026-05-04T21:18:55.781495Z","end":"2026-05-04T21:18:56.447778Z","steps":["trace[1206067419] 'agreement among raft nodes before linearized reading' (duration: 666.215162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:56.447768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.008799ms","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-04T21:18:56.447813Z","caller":"traceutil/trace.go:171","msg":"trace[1067713330] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1300; }","duration":"695.139364ms","start":"2026-05-04T21:18:55.75266Z","end":"2026-05-04T21:18:56.4478Z","steps":["trace[1067713330] 'agreement among raft nodes before linearized reading' (duration: 695.077563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:56.447828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:55.781485Z","time spent":"666.334344ms","remote":"127.0.0.1:47828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-04T21:18:56.447859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:55.752645Z","time spent":"695.207725ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-04T21:18:56.447856Z","caller":"traceutil/trace.go:171","msg":"trace[1042282385] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1300; }","duration":"144.132843ms","start":"2026-05-04T21:18:56.303706Z","end":"2026-05-04T21:18:56.447839Z","steps":["trace[1042282385] 'agreement among raft nodes before linearized reading' (duration: 143.944189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:56.447617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.571656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:18:56.447955Z","caller":"traceutil/trace.go:171","msg":"trace[780549767] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1300; }","duration":"531.952725ms","start":"2026-05-04T21:18:55.915988Z","end":"2026-05-04T21:18:56.44794Z","steps":["trace[780549767] 'agreement among raft nodes before linearized reading' (duration: 531.572587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:56.448012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:55.915971Z","time spent":"532.024886ms","remote":"127.0.0.1:55912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-05-04T21:18:56.750413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.067028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T21:18:56.75074Z","caller":"traceutil/trace.go:171","msg":"trace[2021439638] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"211.286062ms","start":"2026-05-04T21:18:56.539419Z","end":"2026-05-04T21:18:56.750705Z","steps":["trace[2021439638] 'process raft request' (duration: 104.831525ms)","trace[2021439638] 'compare' (duration: 105.847475ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:18:56.7508Z","caller":"traceutil/trace.go:171","msg":"trace[1889741327] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1355; }","duration":"125.092766ms","start":"2026-05-04T21:18:56.625686Z","end":"2026-05-04T21:18:56.750779Z","steps":["trace[1889741327] 'read index received' (duration: 18.499607ms)","trace[1889741327] 'applied index is now lower than readState.Index' (duration: 106.591979ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:18:56.750921Z","caller":"traceutil/trace.go:171","msg":"trace[1795172481] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"211.433224ms","start":"2026-05-04T21:18:56.539476Z","end":"2026-05-04T21:18:56.75091Z","steps":["trace[1795172481] 'process raft request' (duration: 211.080418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:56.751161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.456153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-04T21:18:56.751243Z","caller":"traceutil/trace.go:171","msg":"trace[236226688] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1307; }","duration":"125.557644ms","start":"2026-05-04T21:18:56.62564Z","end":"2026-05-04T21:18:56.751197Z","steps":["trace[236226688] 'agreement among raft nodes before linearized reading' (duration: 125.437922ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:56.751463Z","caller":"traceutil/trace.go:171","msg":"trace[1020665722] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"209.313034ms","start":"2026-05-04T21:18:56.54214Z","end":"2026-05-04T21:18:56.751453Z","steps":["trace[1020665722] 'process raft request' (duration: 208.53123ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:58.283244Z","caller":"traceutil/trace.go:171","msg":"trace[1747273176] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1359; }","duration":"158.886359ms","start":"2026-05-04T21:18:58.124149Z","end":"2026-05-04T21:18:58.283035Z","steps":["trace[1747273176] 'read index received' (duration: 158.3589ms)","trace[1747273176] 'applied index is now lower than readState.Index' (duration: 526.079µs)"],"step_count":2} {"level":"warn","ts":"2026-05-04T21:18:58.283459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:57.903483Z","time spent":"379.968095ms","remote":"127.0.0.1:55716","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-04T21:18:58.283513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.349458ms","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-04T21:18:58.28355Z","caller":"traceutil/trace.go:171","msg":"trace[1190019429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1308; }","duration":"159.458381ms","start":"2026-05-04T21:18:58.124082Z","end":"2026-05-04T21:18:58.28354Z","steps":["trace[1190019429] 'agreement among raft nodes before linearized reading' (duration: 159.234186ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:58.54461Z","caller":"traceutil/trace.go:171","msg":"trace[1536671133] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"259.856853ms","start":"2026-05-04T21:18:58.284719Z","end":"2026-05-04T21:18:58.544575Z","steps":["trace[1536671133] 'process raft request' (duration: 246.444391ms)","trace[1536671133] 'compare' (duration: 13.248529ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:18:58.763382Z","caller":"traceutil/trace.go:171","msg":"trace[868229880] linearizableReadLoop","detail":"{readStateIndex:1362; appliedIndex:1360; }","duration":"310.884959ms","start":"2026-05-04T21:18:58.452477Z","end":"2026-05-04T21:18:58.763362Z","steps":["trace[868229880] 'read index received' (duration: 78.701985ms)","trace[868229880] 'applied index is now lower than readState.Index' (duration: 232.182304ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:18:58.763408Z","caller":"traceutil/trace.go:171","msg":"trace[504099809] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"475.504836ms","start":"2026-05-04T21:18:58.287865Z","end":"2026-05-04T21:18:58.76337Z","steps":["trace[504099809] 'process raft request' (duration: 379.2104ms)","trace[504099809] 'compare' (duration: 96.126903ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T21:18:58.763532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:58.28784Z","time spent":"475.625468ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:18:58.763591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.089083ms","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-04T21:18:58.763519Z","caller":"traceutil/trace.go:171","msg":"trace[1481737325] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"309.400792ms","start":"2026-05-04T21:18:58.454071Z","end":"2026-05-04T21:18:58.763471Z","steps":["trace[1481737325] 'process raft request' (duration: 309.254429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:58.763677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.225285ms","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-04T21:18:58.763697Z","caller":"traceutil/trace.go:171","msg":"trace[428300451] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1311; }","duration":"224.282926ms","start":"2026-05-04T21:18:58.539409Z","end":"2026-05-04T21:18:58.763692Z","steps":["trace[428300451] 'agreement among raft nodes before linearized reading' (duration: 224.227105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:58.763791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.323866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-05-04T21:18:58.763819Z","caller":"traceutil/trace.go:171","msg":"trace[1204650926] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1311; }","duration":"217.366377ms","start":"2026-05-04T21:18:58.546445Z","end":"2026-05-04T21:18:58.763811Z","steps":["trace[1204650926] 'agreement among raft nodes before linearized reading' (duration: 217.314386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:58.763791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:58.454052Z","time spent":"309.601795ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-04T21:18:58.763615Z","caller":"traceutil/trace.go:171","msg":"trace[228648814] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1311; }","duration":"311.175285ms","start":"2026-05-04T21:18:58.452433Z","end":"2026-05-04T21:18:58.763608Z","steps":["trace[228648814] 'agreement among raft nodes before linearized reading' (duration: 311.046372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:58.76395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:18:58.45241Z","time spent":"311.529673ms","remote":"127.0.0.1:55932","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-04T21:18:59.03853Z","caller":"traceutil/trace.go:171","msg":"trace[2083879350] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"270.699167ms","start":"2026-05-04T21:18:58.767804Z","end":"2026-05-04T21:18:59.038503Z","steps":["trace[2083879350] 'process raft request' (duration: 269.796729ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:59.038836Z","caller":"traceutil/trace.go:171","msg":"trace[1425876313] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1363; }","duration":"242.919316ms","start":"2026-05-04T21:18:58.795871Z","end":"2026-05-04T21:18:59.03879Z","steps":["trace[1425876313] 'read index received' (duration: 39.116474ms)","trace[1425876313] 'applied index is now lower than readState.Index' (duration: 203.801562ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:18:59.038995Z","caller":"traceutil/trace.go:171","msg":"trace[1363033920] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"269.916442ms","start":"2026-05-04T21:18:58.76904Z","end":"2026-05-04T21:18:59.038957Z","steps":["trace[1363033920] 'process raft request' (duration: 269.422213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:18:59.039047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.16007ms","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-04T21:18:59.03908Z","caller":"traceutil/trace.go:171","msg":"trace[1960109153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1314; }","duration":"243.249202ms","start":"2026-05-04T21:18:58.795821Z","end":"2026-05-04T21:18:59.03907Z","steps":["trace[1960109153] 'agreement among raft nodes before linearized reading' (duration: 243.090349ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:18:59.153473Z","caller":"traceutil/trace.go:171","msg":"trace[966850396] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"109.993263ms","start":"2026-05-04T21:18:59.043403Z","end":"2026-05-04T21:18:59.153396Z","steps":["trace[966850396] 'process raft request' (duration: 97.730233ms)","trace[966850396] 'compare' (duration: 12.138458ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:19:05.631382Z","caller":"traceutil/trace.go:171","msg":"trace[1378167865] linearizableReadLoop","detail":"{readStateIndex:1423; appliedIndex:1422; }","duration":"226.079489ms","start":"2026-05-04T21:19:05.405273Z","end":"2026-05-04T21:19:05.631353Z","steps":["trace[1378167865] 'read index received' (duration: 225.814564ms)","trace[1378167865] 'applied index is now lower than readState.Index' (duration: 263.785µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:19:05.631492Z","caller":"traceutil/trace.go:171","msg":"trace[946505563] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"235.68021ms","start":"2026-05-04T21:19:05.395772Z","end":"2026-05-04T21:19:05.631452Z","steps":["trace[946505563] 'process raft request' (duration: 235.376093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:19:05.631548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.247153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-04T21:19:05.631837Z","caller":"traceutil/trace.go:171","msg":"trace[1147844092] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1370; }","duration":"226.606129ms","start":"2026-05-04T21:19:05.405211Z","end":"2026-05-04T21:19:05.631817Z","steps":["trace[1147844092] 'agreement among raft nodes before linearized reading' (duration: 226.253973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:19:06.878442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.415909ms","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-04T21:19:06.878536Z","caller":"traceutil/trace.go:171","msg":"trace[1656013444] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1370; }","duration":"559.569352ms","start":"2026-05-04T21:19:06.318948Z","end":"2026-05-04T21:19:06.878517Z","steps":["trace[1656013444] 'range keys from in-memory index tree' (duration: 559.220796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:19:06.878579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:19:06.318925Z","time spent":"559.645954ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-04T21:19:06.878678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.089673ms","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-04T21:19:06.878765Z","caller":"traceutil/trace.go:171","msg":"trace[1739647026] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1370; }","duration":"143.251575ms","start":"2026-05-04T21:19:06.735493Z","end":"2026-05-04T21:19:06.878744Z","steps":["trace[1739647026] 'range keys from in-memory index tree' (duration: 142.886489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:19:06.878752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.154591ms","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":"info","ts":"2026-05-04T21:19:06.878861Z","caller":"traceutil/trace.go:171","msg":"trace[809587487] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1370; }","duration":"113.294434ms","start":"2026-05-04T21:19:06.765541Z","end":"2026-05-04T21:19:06.878836Z","steps":["trace[809587487] 'range keys from in-memory index tree' (duration: 112.945447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:19:06.878813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.126482583s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:19:06.878935Z","caller":"traceutil/trace.go:171","msg":"trace[1642268813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1370; }","duration":"1.126665526s","start":"2026-05-04T21:19:05.752249Z","end":"2026-05-04T21:19:06.878915Z","steps":["trace[1642268813] 'range keys from in-memory index tree' (duration: 1.126384871s)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:19:06.878977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:19:05.752227Z","time spent":"1.126742698s","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-04T21:19:07.127609Z","caller":"traceutil/trace.go:171","msg":"trace[1827176399] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"210.340844ms","start":"2026-05-04T21:19:06.91723Z","end":"2026-05-04T21:19:07.127571Z","steps":["trace[1827176399] 'process raft request' (duration: 210.11374ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:19:07.131941Z","caller":"traceutil/trace.go:171","msg":"trace[1886980854] linearizableReadLoop","detail":"{readStateIndex:1429; appliedIndex:1427; }","duration":"165.841048ms","start":"2026-05-04T21:19:06.966075Z","end":"2026-05-04T21:19:07.131916Z","steps":["trace[1886980854] 'read index received' (duration: 161.199182ms)","trace[1886980854] 'applied index is now lower than readState.Index' (duration: 4.641196ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:19:07.132031Z","caller":"traceutil/trace.go:171","msg":"trace[1198110339] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"167.99898ms","start":"2026-05-04T21:19:06.964024Z","end":"2026-05-04T21:19:07.132023Z","steps":["trace[1198110339] 'process raft request' (duration: 167.763666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:19:07.132239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.151855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:19:07.132288Z","caller":"traceutil/trace.go:171","msg":"trace[945529020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1376; }","duration":"166.252446ms","start":"2026-05-04T21:19:06.966019Z","end":"2026-05-04T21:19:07.132271Z","steps":["trace[945529020] 'agreement among raft nodes before linearized reading' (duration: 166.177425ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:19:09.132222Z","caller":"traceutil/trace.go:171","msg":"trace[89725375] linearizableReadLoop","detail":"{readStateIndex:1435; appliedIndex:1434; }","duration":"168.474059ms","start":"2026-05-04T21:19:08.96372Z","end":"2026-05-04T21:19:09.132194Z","steps":["trace[89725375] 'read index received' (duration: 168.136973ms)","trace[89725375] 'applied index is now lower than readState.Index' (duration: 335.976µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:19:09.132291Z","caller":"traceutil/trace.go:171","msg":"trace[1808644060] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"214.780508ms","start":"2026-05-04T21:19:08.917471Z","end":"2026-05-04T21:19:09.132251Z","steps":["trace[1808644060] 'process raft request' (duration: 214.366951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:19:09.132415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.602309ms","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-04T21:19:09.132453Z","caller":"traceutil/trace.go:171","msg":"trace[655512039] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1381; }","duration":"122.717481ms","start":"2026-05-04T21:19:09.009722Z","end":"2026-05-04T21:19:09.13244Z","steps":["trace[655512039] 'agreement among raft nodes before linearized reading' (duration: 122.62731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:19:09.132485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.734984ms","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-04T21:19:09.132553Z","caller":"traceutil/trace.go:171","msg":"trace[1880668678] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1381; }","duration":"168.857967ms","start":"2026-05-04T21:19:08.96368Z","end":"2026-05-04T21:19:09.132538Z","steps":["trace[1880668678] 'agreement among raft nodes before linearized reading' (duration: 168.637133ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:19:09.250378Z","caller":"traceutil/trace.go:171","msg":"trace[1450226935] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"113.827794ms","start":"2026-05-04T21:19:09.136522Z","end":"2026-05-04T21:19:09.25035Z","steps":["trace[1450226935] 'process raft request' (duration: 65.832254ms)","trace[1450226935] 'compare' (duration: 47.828187ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:19:09.250634Z","caller":"traceutil/trace.go:171","msg":"trace[1331784685] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"113.917516ms","start":"2026-05-04T21:19:09.136635Z","end":"2026-05-04T21:19:09.250553Z","steps":["trace[1331784685] 'process raft request' (duration: 113.673721ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:19:09.250889Z","caller":"traceutil/trace.go:171","msg":"trace[2046846392] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"111.265887ms","start":"2026-05-04T21:19:09.139608Z","end":"2026-05-04T21:19:09.250874Z","steps":["trace[2046846392] 'process raft request' (duration: 110.857598ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:19:09.250867Z","caller":"traceutil/trace.go:171","msg":"trace[2130311270] linearizableReadLoop","detail":"{readStateIndex:1438; appliedIndex:1435; }","duration":"108.78236ms","start":"2026-05-04T21:19:09.142018Z","end":"2026-05-04T21:19:09.2508Z","steps":["trace[2130311270] 'read index received' (duration: 60.319031ms)","trace[2130311270] 'applied index is now lower than readState.Index' (duration: 48.461969ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T21:19:09.25108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.044485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-04T21:19:09.251131Z","caller":"traceutil/trace.go:171","msg":"trace[2130525406] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1384; }","duration":"109.113046ms","start":"2026-05-04T21:19:09.142007Z","end":"2026-05-04T21:19:09.25112Z","steps":["trace[2130525406] 'agreement among raft nodes before linearized reading' (duration: 108.954333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:19:09.25132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.742759ms","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-04T21:19:09.251909Z","caller":"traceutil/trace.go:171","msg":"trace[1458015788] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1384; }","duration":"108.847371ms","start":"2026-05-04T21:19:09.142509Z","end":"2026-05-04T21:19:09.251356Z","steps":["trace[1458015788] 'agreement among raft nodes before linearized reading' (duration: 108.572676ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:19:09.365866Z","caller":"traceutil/trace.go:171","msg":"trace[1233615574] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"108.609947ms","start":"2026-05-04T21:19:09.257232Z","end":"2026-05-04T21:19:09.365842Z","steps":["trace[1233615574] 'process raft request' (duration: 94.32503ms)","trace[1233615574] 'compare' (duration: 13.986511ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:19:09.365865Z","caller":"traceutil/trace.go:171","msg":"trace[1986000812] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"108.036455ms","start":"2026-05-04T21:19:09.257799Z","end":"2026-05-04T21:19:09.365835Z","steps":["trace[1986000812] 'process raft request' (duration: 107.977434ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:19:09.365906Z","caller":"traceutil/trace.go:171","msg":"trace[1789613821] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"108.23066ms","start":"2026-05-04T21:19:09.257644Z","end":"2026-05-04T21:19:09.365874Z","steps":["trace[1789613821] 'process raft request' (duration: 108.077287ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:19:32.160831Z","caller":"traceutil/trace.go:171","msg":"trace[711290244] transaction","detail":"{read_only:false; response_revision:1492; number_of_response:1; }","duration":"150.04473ms","start":"2026-05-04T21:19:32.010751Z","end":"2026-05-04T21:19:32.160795Z","steps":["trace[711290244] 'process raft request' (duration: 139.055553ms)","trace[711290244] 'compare' (duration: 10.870044ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T21:19:39.798016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.829497ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T21:19:39.798326Z","caller":"traceutil/trace.go:171","msg":"trace[1460740716] transaction","detail":"{read_only:false; response_revision:1526; number_of_response:1; }","duration":"233.948136ms","start":"2026-05-04T21:19:39.564343Z","end":"2026-05-04T21:19:39.798292Z","steps":["trace[1460740716] 'process raft request' (duration: 101.741342ms)","trace[1460740716] 'compare' (duration: 131.707565ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:19:39.798319Z","caller":"traceutil/trace.go:171","msg":"trace[1504841817] transaction","detail":"{read_only:false; response_revision:1527; number_of_response:1; }","duration":"232.698393ms","start":"2026-05-04T21:19:39.56559Z","end":"2026-05-04T21:19:39.798288Z","steps":["trace[1504841817] 'process raft request' (duration: 232.54926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:19:43.824252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.295715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T21:19:43.824416Z","caller":"traceutil/trace.go:171","msg":"trace[837338373] transaction","detail":"{read_only:false; response_revision:1542; number_of_response:1; }","duration":"246.595575ms","start":"2026-05-04T21:19:43.577798Z","end":"2026-05-04T21:19:43.824393Z","steps":["trace[837338373] 'process raft request' (duration: 130.076954ms)","trace[837338373] 'compare' (duration: 116.080012ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:19:43.824527Z","caller":"traceutil/trace.go:171","msg":"trace[837195762] transaction","detail":"{read_only:false; response_revision:1543; number_of_response:1; }","duration":"246.164936ms","start":"2026-05-04T21:19:43.578355Z","end":"2026-05-04T21:19:43.82452Z","steps":["trace[837195762] 'process raft request' (duration: 245.990863ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:19:50.056194Z","caller":"traceutil/trace.go:171","msg":"trace[207063074] linearizableReadLoop","detail":"{readStateIndex:1633; appliedIndex:1631; }","duration":"196.666875ms","start":"2026-05-04T21:19:49.859502Z","end":"2026-05-04T21:19:50.056169Z","steps":["trace[207063074] 'read index received' (duration: 18.745842ms)","trace[207063074] 'applied index is now lower than readState.Index' (duration: 177.920183ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:19:50.056272Z","caller":"traceutil/trace.go:171","msg":"trace[1947210966] transaction","detail":"{read_only:false; response_revision:1571; number_of_response:1; }","duration":"203.317711ms","start":"2026-05-04T21:19:49.852927Z","end":"2026-05-04T21:19:50.056244Z","steps":["trace[1947210966] 'process raft request' (duration: 203.077476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:19:50.056518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.617318ms","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-04T21:19:50.056487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.969931ms","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-04T21:19:50.056578Z","caller":"traceutil/trace.go:171","msg":"trace[1204094464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1571; }","duration":"186.716359ms","start":"2026-05-04T21:19:49.869846Z","end":"2026-05-04T21:19:50.056563Z","steps":["trace[1204094464] 'agreement among raft nodes before linearized reading' (duration: 186.501595ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:19:50.056592Z","caller":"traceutil/trace.go:171","msg":"trace[1077608029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1571; }","duration":"197.109144ms","start":"2026-05-04T21:19:49.859467Z","end":"2026-05-04T21:19:50.056576Z","steps":["trace[1077608029] 'agreement among raft nodes before linearized reading' (duration: 196.86994ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:19:50.196041Z","caller":"traceutil/trace.go:171","msg":"trace[733713809] transaction","detail":"{read_only:false; response_revision:1573; number_of_response:1; }","duration":"135.336342ms","start":"2026-05-04T21:19:50.06068Z","end":"2026-05-04T21:19:50.196017Z","steps":["trace[733713809] 'process raft request' (duration: 76.277322ms)","trace[733713809] 'compare' (duration: 58.956358ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:20:07.654409Z","caller":"traceutil/trace.go:171","msg":"trace[1178615406] transaction","detail":"{read_only:false; response_revision:1650; number_of_response:1; }","duration":"187.83414ms","start":"2026-05-04T21:20:07.466533Z","end":"2026-05-04T21:20:07.654367Z","steps":["trace[1178615406] 'process raft request' (duration: 187.676598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:20:08.308998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.475462ms","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-04T21:20:08.309075Z","caller":"traceutil/trace.go:171","msg":"trace[383533235] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1653; }","duration":"113.600545ms","start":"2026-05-04T21:20:08.195455Z","end":"2026-05-04T21:20:08.309056Z","steps":["trace[383533235] 'range keys from in-memory index tree' (duration: 113.146797ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:20:09.784269Z","caller":"traceutil/trace.go:171","msg":"trace[422085043] transaction","detail":"{read_only:false; response_revision:1659; number_of_response:1; }","duration":"125.730873ms","start":"2026-05-04T21:20:09.658512Z","end":"2026-05-04T21:20:09.784243Z","steps":["trace[422085043] 'process raft request' (duration: 48.748716ms)","trace[422085043] 'compare' (duration: 76.683961ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T21:20:22.496356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.768843ms","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":"info","ts":"2026-05-04T21:20:22.496435Z","caller":"traceutil/trace.go:171","msg":"trace[1756244671] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1716; }","duration":"142.881455ms","start":"2026-05-04T21:20:22.353537Z","end":"2026-05-04T21:20:22.496418Z","steps":["trace[1756244671] 'range keys from in-memory index tree' (duration: 142.650901ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:20:22.496749Z","caller":"traceutil/trace.go:171","msg":"trace[1707958386] transaction","detail":"{read_only:false; response_revision:1717; number_of_response:1; }","duration":"109.404447ms","start":"2026-05-04T21:20:22.387332Z","end":"2026-05-04T21:20:22.496736Z","steps":["trace[1707958386] 'process raft request' (duration: 52.001798ms)","trace[1707958386] 'compare' (duration: 56.90526ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:20:22.696562Z","caller":"traceutil/trace.go:171","msg":"trace[997943581] transaction","detail":"{read_only:false; response_revision:1718; number_of_response:1; }","duration":"196.507044ms","start":"2026-05-04T21:20:22.500031Z","end":"2026-05-04T21:20:22.696538Z","steps":["trace[997943581] 'process raft request' (duration: 196.359691ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:20:22.721446Z","caller":"traceutil/trace.go:171","msg":"trace[1958392106] linearizableReadLoop","detail":"{readStateIndex:1787; appliedIndex:1785; }","duration":"126.247024ms","start":"2026-05-04T21:20:22.595184Z","end":"2026-05-04T21:20:22.721431Z","steps":["trace[1958392106] 'read index received' (duration: 101.201603ms)","trace[1958392106] 'applied index is now lower than readState.Index' (duration: 25.044601ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:20:22.721552Z","caller":"traceutil/trace.go:171","msg":"trace[347586393] transaction","detail":"{read_only:false; response_revision:1719; number_of_response:1; }","duration":"221.298128ms","start":"2026-05-04T21:20:22.500231Z","end":"2026-05-04T21:20:22.721529Z","steps":["trace[347586393] 'process raft request' (duration: 221.119405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:20:22.721979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.496738ms","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-04T21:20:22.722012Z","caller":"traceutil/trace.go:171","msg":"trace[1607749103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1719; }","duration":"126.854305ms","start":"2026-05-04T21:20:22.595148Z","end":"2026-05-04T21:20:22.722002Z","steps":["trace[1607749103] 'agreement among raft nodes before linearized reading' (duration: 126.369586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:20:22.721991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.941787ms","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-04T21:20:22.722078Z","caller":"traceutil/trace.go:171","msg":"trace[1452555843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1719; }","duration":"110.083569ms","start":"2026-05-04T21:20:22.611976Z","end":"2026-05-04T21:20:22.72206Z","steps":["trace[1452555843] 'agreement among raft nodes before linearized reading' (duration: 109.704322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:20:23.480395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.028006ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-04T21:20:23.480637Z","caller":"traceutil/trace.go:171","msg":"trace[833369615] linearizableReadLoop","detail":"{readStateIndex:1790; appliedIndex:1787; }","duration":"728.176105ms","start":"2026-05-04T21:20:22.752444Z","end":"2026-05-04T21:20:23.48062Z","steps":["trace[833369615] 'read index received' (duration: 32.933269ms)","trace[833369615] 'applied index is now lower than readState.Index' (duration: 695.241836ms)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:20:23.480707Z","caller":"traceutil/trace.go:171","msg":"trace[323096888] transaction","detail":"{read_only:false; response_revision:1722; number_of_response:1; }","duration":"754.502959ms","start":"2026-05-04T21:20:22.726195Z","end":"2026-05-04T21:20:23.480698Z","steps":["trace[323096888] 'process raft request' (duration: 754.308316ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:20:23.480712Z","caller":"traceutil/trace.go:171","msg":"trace[357186522] transaction","detail":"{read_only:false; response_revision:1721; number_of_response:1; }","duration":"754.49751ms","start":"2026-05-04T21:20:22.726177Z","end":"2026-05-04T21:20:23.480675Z","steps":["trace[357186522] 'process raft request' (duration: 203.100727ms)","trace[357186522] 'compare' (duration: 550.512676ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T21:20:23.480775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:20:22.726186Z","time spent":"754.55215ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:20:23.480863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:20:22.726157Z","time spent":"754.626462ms","remote":"127.0.0.1:55932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-04T21:20:23.595188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.717897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-04T21:20:23.595219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.585494ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-04T21:20:23.595268Z","caller":"traceutil/trace.go:171","msg":"trace[1870186481] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1722; }","duration":"842.831389ms","start":"2026-05-04T21:20:22.752416Z","end":"2026-05-04T21:20:23.595247Z","steps":["trace[1870186481] 'agreement among raft nodes before linearized reading' (duration: 728.261696ms)","trace[1870186481] 'range keys from in-memory index tree' (duration: 114.456781ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T21:20:23.595322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-04T21:20:22.7524Z","time spent":"842.91333ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-04T21:20:27.603759Z","caller":"traceutil/trace.go:171","msg":"trace[896431405] transaction","detail":"{read_only:false; response_revision:1740; number_of_response:1; }","duration":"104.996373ms","start":"2026-05-04T21:20:27.498742Z","end":"2026-05-04T21:20:27.603738Z","steps":["trace[896431405] 'process raft request' (duration: 104.728737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:20:44.219744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.096943ms","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-04T21:20:44.219857Z","caller":"traceutil/trace.go:171","msg":"trace[116765655] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1815; }","duration":"159.247516ms","start":"2026-05-04T21:20:44.060592Z","end":"2026-05-04T21:20:44.219839Z","steps":["trace[116765655] 'range keys from in-memory index tree' (duration: 158.97743ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:21:28.575177Z","caller":"traceutil/trace.go:171","msg":"trace[682187797] linearizableReadLoop","detail":"{readStateIndex:2103; appliedIndex:2102; }","duration":"107.180935ms","start":"2026-05-04T21:21:28.467973Z","end":"2026-05-04T21:21:28.575154Z","steps":["trace[682187797] 'read index received' (duration: 106.941161ms)","trace[682187797] 'applied index is now lower than readState.Index' (duration: 238.724µs)"],"step_count":2} {"level":"info","ts":"2026-05-04T21:21:28.575259Z","caller":"traceutil/trace.go:171","msg":"trace[1150438188] transaction","detail":"{read_only:false; response_revision:2021; number_of_response:1; }","duration":"128.369045ms","start":"2026-05-04T21:21:28.446866Z","end":"2026-05-04T21:21:28.575235Z","steps":["trace[1150438188] 'process raft request' (duration: 128.124449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:21:28.575377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.38557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-04T21:21:28.575459Z","caller":"traceutil/trace.go:171","msg":"trace[2131119860] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2021; }","duration":"107.503872ms","start":"2026-05-04T21:21:28.467941Z","end":"2026-05-04T21:21:28.575445Z","steps":["trace[2131119860] 'agreement among raft nodes before linearized reading' (duration: 107.306089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:21:30.590051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.356562ms","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-04T21:21:30.590153Z","caller":"traceutil/trace.go:171","msg":"trace[932077482] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2028; }","duration":"206.525245ms","start":"2026-05-04T21:21:30.38361Z","end":"2026-05-04T21:21:30.590135Z","steps":["trace[932077482] 'count revisions from in-memory index tree' (duration: 206.265181ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:21:30.858684Z","caller":"traceutil/trace.go:171","msg":"trace[1252237444] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"264.575206ms","start":"2026-05-04T21:21:30.594088Z","end":"2026-05-04T21:21:30.858663Z","steps":["trace[1252237444] 'process raft request' (duration: 264.415293ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:21:30.8637Z","caller":"traceutil/trace.go:171","msg":"trace[260845957] linearizableReadLoop","detail":"{readStateIndex:2112; appliedIndex:2110; }","duration":"181.378011ms","start":"2026-05-04T21:21:30.682298Z","end":"2026-05-04T21:21:30.863676Z","steps":["trace[260845957] 'read index received' (duration: 176.269904ms)","trace[260845957] 'applied index is now lower than readState.Index' (duration: 5.107077ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T21:21:30.864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.689717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-04T21:21:30.864059Z","caller":"traceutil/trace.go:171","msg":"trace[511169322] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2030; }","duration":"181.800509ms","start":"2026-05-04T21:21:30.682242Z","end":"2026-05-04T21:21:30.864042Z","steps":["trace[511169322] 'agreement among raft nodes before linearized reading' (duration: 181.698877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:21:30.86413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.084948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-04T21:21:30.864173Z","caller":"traceutil/trace.go:171","msg":"trace[1906222601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2030; }","duration":"112.160619ms","start":"2026-05-04T21:21:30.752Z","end":"2026-05-04T21:21:30.864161Z","steps":["trace[1906222601] 'agreement among raft nodes before linearized reading' (duration: 112.085787ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:21:30.864243Z","caller":"traceutil/trace.go:171","msg":"trace[2120855382] transaction","detail":"{read_only:false; response_revision:2030; number_of_response:1; }","duration":"269.933866ms","start":"2026-05-04T21:21:30.594298Z","end":"2026-05-04T21:21:30.864231Z","steps":["trace[2120855382] 'process raft request' (duration: 269.261454ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:22:08.617561Z","caller":"traceutil/trace.go:171","msg":"trace[1206446139] linearizableReadLoop","detail":"{readStateIndex:2319; appliedIndex:2318; }","duration":"177.436454ms","start":"2026-05-04T21:22:08.440099Z","end":"2026-05-04T21:22:08.617536Z","steps":["trace[1206446139] 'read index received' (duration: 177.123158ms)","trace[1206446139] 'applied index is now lower than readState.Index' (duration: 312.106µs)"],"step_count":2} {"level":"warn","ts":"2026-05-04T21:22:08.617707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.587228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-04T21:22:08.617778Z","caller":"traceutil/trace.go:171","msg":"trace[681641409] transaction","detail":"{read_only:false; response_revision:2228; number_of_response:1; }","duration":"203.365996ms","start":"2026-05-04T21:22:08.414258Z","end":"2026-05-04T21:22:08.617624Z","steps":["trace[681641409] 'process raft request' (duration: 203.07199ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:22:08.617755Z","caller":"traceutil/trace.go:171","msg":"trace[674346474] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:2228; }","duration":"177.689849ms","start":"2026-05-04T21:22:08.440044Z","end":"2026-05-04T21:22:08.617734Z","steps":["trace[674346474] 'agreement among raft nodes before linearized reading' (duration: 177.602528ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:22:09.171218Z","caller":"traceutil/trace.go:171","msg":"trace[1047414464] linearizableReadLoop","detail":"{readStateIndex:2320; appliedIndex:2319; }","duration":"121.732292ms","start":"2026-05-04T21:22:09.049466Z","end":"2026-05-04T21:22:09.171198Z","steps":["trace[1047414464] 'read index received' (duration: 121.559729ms)","trace[1047414464] 'applied index is now lower than readState.Index' (duration: 171.653µs)"],"step_count":2} {"level":"warn","ts":"2026-05-04T21:22:09.171465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.972616ms","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-04T21:22:09.17148Z","caller":"traceutil/trace.go:171","msg":"trace[52929858] transaction","detail":"{read_only:false; response_revision:2229; number_of_response:1; }","duration":"126.760276ms","start":"2026-05-04T21:22:09.044687Z","end":"2026-05-04T21:22:09.171447Z","steps":["trace[52929858] 'process raft request' (duration: 126.39263ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:22:09.171513Z","caller":"traceutil/trace.go:171","msg":"trace[1519735457] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2229; }","duration":"122.046427ms","start":"2026-05-04T21:22:09.049451Z","end":"2026-05-04T21:22:09.171497Z","steps":["trace[1519735457] 'agreement among raft nodes before linearized reading' (duration: 121.854844ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:22:12.23372Z","caller":"traceutil/trace.go:171","msg":"trace[1113552198] transaction","detail":"{read_only:false; response_revision:2246; number_of_response:1; }","duration":"103.993276ms","start":"2026-05-04T21:22:12.129576Z","end":"2026-05-04T21:22:12.23357Z","steps":["trace[1113552198] 'process raft request' (duration: 103.782022ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:22:12.376111Z","caller":"traceutil/trace.go:171","msg":"trace[378104966] transaction","detail":"{read_only:false; response_revision:2248; number_of_response:1; }","duration":"137.965589ms","start":"2026-05-04T21:22:12.238114Z","end":"2026-05-04T21:22:12.376079Z","steps":["trace[378104966] 'process raft request' (duration: 102.834845ms)","trace[378104966] 'compare' (duration: 35.005322ms)"],"step_count":2} {"level":"warn","ts":"2026-05-04T21:22:12.669077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.16761ms","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-04T21:22:12.669183Z","caller":"traceutil/trace.go:171","msg":"trace[1134955891] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2248; }","duration":"140.330283ms","start":"2026-05-04T21:22:12.52883Z","end":"2026-05-04T21:22:12.66916Z","steps":["trace[1134955891] 'range keys from in-memory index tree' (duration: 139.975266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:22:18.966697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.879214ms","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-04T21:22:18.966783Z","caller":"traceutil/trace.go:171","msg":"trace[874949459] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2287; }","duration":"103.999606ms","start":"2026-05-04T21:22:18.862764Z","end":"2026-05-04T21:22:18.966763Z","steps":["trace[874949459] 'count revisions from in-memory index tree' (duration: 103.819113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-04T21:22:18.967075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.740998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" limit:500 ","response":"range_response_count:70 size:67642"} {"level":"info","ts":"2026-05-04T21:22:18.967161Z","caller":"traceutil/trace.go:171","msg":"trace[837359256] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:70; response_revision:2287; }","duration":"106.908351ms","start":"2026-05-04T21:22:18.860235Z","end":"2026-05-04T21:22:18.967143Z","steps":["trace[837359256] 'range keys from in-memory index tree' (duration: 106.31395ms)"],"step_count":1} {"level":"info","ts":"2026-05-04T21:22:45.693037Z","caller":"traceutil/trace.go:171","msg":"trace[145794908] transaction","detail":"{read_only:false; response_revision:2410; number_of_response:1; }","duration":"134.255951ms","start":"2026-05-04T21:22:45.558752Z","end":"2026-05-04T21:22:45.693008Z","steps":["trace[145794908] 'process raft request' (duration: 113.065372ms)","trace[145794908] 'compare' (duration: 20.657789ms)"],"step_count":2}