{"level":"warn","ts":"2026-04-27T16:04:40.393629Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T16:04:40.393804Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-27T16:04:40.393921Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T16:04:40.393943Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-27T16:04:40.39399Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-27T16:04:40.395009Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-04-27T16:04:40.39512Z","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.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-27T16:04:40.399394Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.992484ms"} {"level":"info","ts":"2026-04-27T16:04:40.408557Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-04-27T16:04:40.40865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T16:04:40.408676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-04-27T16:04:40.408691Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T16:04:40.408704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-04-27T16:04:40.408754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-04-27T16:04:40.418165Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T16:04:40.419322Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T16:04:40.42051Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-27T16:04:40.422809Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T16:04:40.423317Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T16:04:40.423306Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-27T16:04:40.423444Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-27T16:04:40.423462Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-27T16:04:40.437063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-04-27T16:04:40.437378Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-04-27T16:04:40.439286Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-27T16:04:40.439919Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T16:04:40.439968Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T16:04:40.440302Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-27T16:04:40.441218Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-04-27T16:04:41.014529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T16:04:41.014592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T16:04:41.014612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-04-27T16:04:41.014627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-04-27T16:04:41.014636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-27T16:04:41.014667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-04-27T16:04:41.014676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-04-27T16:04:41.018822Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T16:04:41.018873Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T16:04:41.019283Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T16:04:41.01954Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T16:04:41.021834Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T16:04:41.021989Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T16:04:41.022035Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T16:04:41.023325Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T16:04:41.023941Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-04-27T16:04:41.03018Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T16:04:41.030348Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-04-27T16:04:51.151652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.449964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T16:04:51.152155Z","caller":"traceutil/trace.go:171","msg":"trace[982628095] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:302; }","duration":"193.006169ms","start":"2026-04-27T16:04:50.959112Z","end":"2026-04-27T16:04:51.152118Z","steps":["trace[982628095] 'range keys from in-memory index tree' (duration: 192.324721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:04:51.151824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.197188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-27T16:04:51.152476Z","caller":"traceutil/trace.go:171","msg":"trace[1693591270] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:302; }","duration":"243.911357ms","start":"2026-04-27T16:04:50.908526Z","end":"2026-04-27T16:04:51.152437Z","steps":["trace[1693591270] 'range keys from in-memory index tree' (duration: 243.055185ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:04:56.778991Z","caller":"traceutil/trace.go:171","msg":"trace[1340033683] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:386; }","duration":"106.57933ms","start":"2026-04-27T16:04:56.672373Z","end":"2026-04-27T16:04:56.778952Z","steps":["trace[1340033683] 'read index received' (duration: 105.585005ms)","trace[1340033683] 'applied index is now lower than readState.Index' (duration: 992.915µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:04:56.77903Z","caller":"traceutil/trace.go:171","msg":"trace[1816372734] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"257.787835ms","start":"2026-04-27T16:04:56.521216Z","end":"2026-04-27T16:04:56.779004Z","steps":["trace[1816372734] 'process raft request' (duration: 256.658417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:04:56.779133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.737964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-27T16:04:56.779164Z","caller":"traceutil/trace.go:171","msg":"trace[914744172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:375; }","duration":"106.860418ms","start":"2026-04-27T16:04:56.672293Z","end":"2026-04-27T16:04:56.779154Z","steps":["trace[914744172] 'agreement among raft nodes before linearized reading' (duration: 106.770185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:04:56.779273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.834987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-27T16:04:56.779375Z","caller":"traceutil/trace.go:171","msg":"trace[98443484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:375; }","duration":"106.985561ms","start":"2026-04-27T16:04:56.672364Z","end":"2026-04-27T16:04:56.77935Z","steps":["trace[98443484] 'agreement among raft nodes before linearized reading' (duration: 106.779805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:04:57.375766Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410015590374236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T16:04:57.876983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410015590374236,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T16:04:58.284988Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.500287016s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T16:04:58.372855Z","caller":"traceutil/trace.go:171","msg":"trace[1277805952] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"1.588237933s","start":"2026-04-27T16:04:56.78457Z","end":"2026-04-27T16:04:58.372808Z","steps":["trace[1277805952] 'process raft request' (duration: 1.500747357s)","trace[1277805952] 'compare' (duration: 87.210608ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:04:58.374371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:04:56.78454Z","time spent":"1.589078015s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:04:58.391956Z","caller":"traceutil/trace.go:171","msg":"trace[1434005846] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:387; }","duration":"1.516566426s","start":"2026-04-27T16:04:56.875365Z","end":"2026-04-27T16:04:58.391931Z","steps":["trace[1434005846] 'read index received' (duration: 1.409979456s)","trace[1434005846] 'applied index is now lower than readState.Index' (duration: 106.58601ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:04:58.392069Z","caller":"traceutil/trace.go:171","msg":"trace[1983723547] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"1.606392553s","start":"2026-04-27T16:04:56.785649Z","end":"2026-04-27T16:04:58.392041Z","steps":["trace[1983723547] 'process raft request' (duration: 1.606181818s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:04:58.392184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.490352258s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"warn","ts":"2026-04-27T16:04:58.392217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:04:56.78563Z","time spent":"1.606521455s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:04:58.392247Z","caller":"traceutil/trace.go:171","msg":"trace[1644000020] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:377; }","duration":"1.49047403s","start":"2026-04-27T16:04:56.901761Z","end":"2026-04-27T16:04:58.392235Z","steps":["trace[1644000020] 'agreement among raft nodes before linearized reading' (duration: 1.490295815s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:04:58.392286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:04:56.901727Z","time spent":"1.490550563s","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6987,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-04-27T16:04:58.392383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.516948307s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T16:04:58.392531Z","caller":"traceutil/trace.go:171","msg":"trace[54062715] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:377; }","duration":"1.517152072s","start":"2026-04-27T16:04:56.875343Z","end":"2026-04-27T16:04:58.392495Z","steps":["trace[54062715] 'agreement among raft nodes before linearized reading' (duration: 1.51670879s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:04:58.392606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:04:56.875165Z","time spent":"1.517427728s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T16:04:58.392924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.998445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:04:58.393087Z","caller":"traceutil/trace.go:171","msg":"trace[286667592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:377; }","duration":"679.177319ms","start":"2026-04-27T16:04:57.713873Z","end":"2026-04-27T16:04:58.39305Z","steps":["trace[286667592] 'agreement among raft nodes before linearized reading' (duration: 678.915092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:04:58.39322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:04:57.713795Z","time spent":"679.410635ms","remote":"127.0.0.1:41716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:04:58.392976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121595077s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-27T16:04:58.3937Z","caller":"traceutil/trace.go:171","msg":"trace[1448294037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:377; }","duration":"1.122388329s","start":"2026-04-27T16:04:57.271297Z","end":"2026-04-27T16:04:58.393686Z","steps":["trace[1448294037] 'agreement among raft nodes before linearized reading' (duration: 1.12163769s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:04:58.393841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:04:57.271269Z","time spent":"1.122554823s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T16:04:59.179307Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410015590374241,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T16:04:59.447424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.180409ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:04:59.447586Z","caller":"traceutil/trace.go:171","msg":"trace[725489858] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"1.049393017s","start":"2026-04-27T16:04:58.398169Z","end":"2026-04-27T16:04:59.447562Z","steps":["trace[725489858] 'process raft request' (duration: 257.767976ms)","trace[725489858] 'compare' (duration: 791.029536ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:04:59.44766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:04:58.398153Z","time spent":"1.049473759s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:04:59.789667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.93179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:04:59.790045Z","caller":"traceutil/trace.go:171","msg":"trace[1794563708] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"1.391721653s","start":"2026-04-27T16:04:58.39828Z","end":"2026-04-27T16:04:59.790002Z","steps":["trace[1794563708] 'process raft request' (duration: 1.211339751s)","trace[1794563708] 'compare' (duration: 179.740196ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:04:59.790155Z","caller":"traceutil/trace.go:171","msg":"trace[1809032657] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"1.387325299s","start":"2026-04-27T16:04:58.402715Z","end":"2026-04-27T16:04:59.790041Z","steps":["trace[1809032657] 'process raft request' (duration: 1.387115114s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:04:59.790263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:04:58.398232Z","time spent":"1.391921469s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:04:59.790392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:04:58.402703Z","time spent":"1.387555206s","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:04:59.790421Z","caller":"traceutil/trace.go:171","msg":"trace[1751226295] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:390; }","duration":"1.111745792s","start":"2026-04-27T16:04:58.678664Z","end":"2026-04-27T16:04:59.790409Z","steps":["trace[1751226295] 'read index received' (duration: 937.963871ms)","trace[1751226295] 'applied index is now lower than readState.Index' (duration: 173.780891ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:04:59.793555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.114867803s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-04-27T16:04:59.793705Z","caller":"traceutil/trace.go:171","msg":"trace[1999774156] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:380; }","duration":"1.115077688s","start":"2026-04-27T16:04:58.678609Z","end":"2026-04-27T16:04:59.793686Z","steps":["trace[1999774156] 'agreement among raft nodes before linearized reading' (duration: 1.111894167s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:04:59.793754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:04:58.678584Z","time spent":"1.1151616s","remote":"127.0.0.1:41866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4391,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-27T16:05:01.901163Z","caller":"traceutil/trace.go:171","msg":"trace[417520200] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"101.272823ms","start":"2026-04-27T16:05:01.79986Z","end":"2026-04-27T16:05:01.901133Z","steps":["trace[417520200] 'process raft request' (duration: 101.091637ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:15.682553Z","caller":"traceutil/trace.go:171","msg":"trace[133518560] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"153.6372ms","start":"2026-04-27T16:05:15.528875Z","end":"2026-04-27T16:05:15.682512Z","steps":["trace[133518560] 'process raft request' (duration: 153.381273ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:16.041327Z","caller":"traceutil/trace.go:171","msg":"trace[575460350] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"449.45317ms","start":"2026-04-27T16:05:15.59181Z","end":"2026-04-27T16:05:16.041263Z","steps":["trace[575460350] 'process raft request' (duration: 444.546023ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:16.041375Z","caller":"traceutil/trace.go:171","msg":"trace[344404152] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"445.035035ms","start":"2026-04-27T16:05:15.596321Z","end":"2026-04-27T16:05:16.041356Z","steps":["trace[344404152] 'process raft request' (duration: 444.85457ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:16.041463Z","caller":"traceutil/trace.go:171","msg":"trace[644842417] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"439.237005ms","start":"2026-04-27T16:05:15.602176Z","end":"2026-04-27T16:05:16.041413Z","steps":["trace[644842417] 'process raft request' (duration: 439.04194ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:16.041672Z","caller":"traceutil/trace.go:171","msg":"trace[1663035148] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"435.041347ms","start":"2026-04-27T16:05:15.60662Z","end":"2026-04-27T16:05:16.041661Z","steps":["trace[1663035148] 'process raft request' (duration: 434.739529ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:16.041605Z","caller":"traceutil/trace.go:171","msg":"trace[103199397] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"438.962309ms","start":"2026-04-27T16:05:15.602482Z","end":"2026-04-27T16:05:16.041445Z","steps":["trace[103199397] 'process raft request' (duration: 438.826666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:16.041748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:15.602143Z","time spent":"439.467862ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:05:16.041548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:15.591777Z","time spent":"449.675276ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T16:05:16.041919Z","caller":"traceutil/trace.go:171","msg":"trace[1306464846] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"432.83642ms","start":"2026-04-27T16:05:15.609045Z","end":"2026-04-27T16:05:16.041881Z","steps":["trace[1306464846] 'process raft request' (duration: 432.515702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:16.041724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:15.60658Z","time spent":"435.122339ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:05:16.042011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:15.602477Z","time spent":"439.36451ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T16:05:16.042133Z","caller":"traceutil/trace.go:171","msg":"trace[2007377631] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"425.647793ms","start":"2026-04-27T16:05:15.616476Z","end":"2026-04-27T16:05:16.042124Z","steps":["trace[2007377631] 'process raft request' (duration: 425.423608ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:16.042074Z","caller":"traceutil/trace.go:171","msg":"trace[273938287] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"432.555183ms","start":"2026-04-27T16:05:15.609501Z","end":"2026-04-27T16:05:16.042056Z","steps":["trace[273938287] 'process raft request' (duration: 432.302317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:16.041692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:15.59631Z","time spent":"445.225589ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:05:16.042178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:15.616465Z","time spent":"425.693574ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:05:16.042265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:15.609456Z","time spent":"432.711657ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T16:05:16.042337Z","caller":"traceutil/trace.go:171","msg":"trace[910887638] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"400.868622ms","start":"2026-04-27T16:05:15.641411Z","end":"2026-04-27T16:05:16.042279Z","steps":["trace[910887638] 'process raft request' (duration: 400.642766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:16.042439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:15.609012Z","time spent":"432.942932ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:05:16.042519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:15.641351Z","time spent":"401.076257ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T16:05:16.042619Z","caller":"traceutil/trace.go:171","msg":"trace[1029733301] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"389.402445ms","start":"2026-04-27T16:05:15.653028Z","end":"2026-04-27T16:05:16.042431Z","steps":["trace[1029733301] 'process raft request' (duration: 389.221401ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:16.042651Z","caller":"traceutil/trace.go:171","msg":"trace[1902448821] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"377.23175ms","start":"2026-04-27T16:05:15.665395Z","end":"2026-04-27T16:05:16.042627Z","steps":["trace[1902448821] 'process raft request' (duration: 376.994814ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:16.04263Z","caller":"traceutil/trace.go:171","msg":"trace[570630591] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:497; }","duration":"329.074322ms","start":"2026-04-27T16:05:15.71353Z","end":"2026-04-27T16:05:16.042605Z","steps":["trace[570630591] 'read index received' (duration: 322.844201ms)","trace[570630591] 'applied index is now lower than readState.Index' (duration: 6.228691ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:05:16.042679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:15.65299Z","time spent":"389.665021ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:05:16.042707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:15.665359Z","time spent":"377.322152ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:05:16.042982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.447143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:05:16.043091Z","caller":"traceutil/trace.go:171","msg":"trace[48662482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:492; }","duration":"329.625217ms","start":"2026-04-27T16:05:15.713449Z","end":"2026-04-27T16:05:16.043074Z","steps":["trace[48662482] 'agreement among raft nodes before linearized reading' (duration: 329.335479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:16.043163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:15.713413Z","time spent":"329.73729ms","remote":"127.0.0.1:41716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T16:05:16.053204Z","caller":"traceutil/trace.go:171","msg":"trace[1781391945] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"257.369746ms","start":"2026-04-27T16:05:15.795805Z","end":"2026-04-27T16:05:16.053175Z","steps":["trace[1781391945] 'process raft request' (duration: 256.953266ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:17.729879Z","caller":"traceutil/trace.go:171","msg":"trace[739017220] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"372.393184ms","start":"2026-04-27T16:05:17.357457Z","end":"2026-04-27T16:05:17.72985Z","steps":["trace[739017220] 'read index received' (duration: 372.034596ms)","trace[739017220] 'applied index is now lower than readState.Index' (duration: 357.038µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:05:17.730321Z","caller":"traceutil/trace.go:171","msg":"trace[1228874824] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"392.155747ms","start":"2026-04-27T16:05:17.338101Z","end":"2026-04-27T16:05:17.730257Z","steps":["trace[1228874824] 'process raft request' (duration: 391.248453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:17.730599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:17.338074Z","time spent":"392.386992ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:05:17.730566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.057832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31126"} {"level":"info","ts":"2026-04-27T16:05:17.730719Z","caller":"traceutil/trace.go:171","msg":"trace[909526402] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:557; }","duration":"373.294888ms","start":"2026-04-27T16:05:17.357391Z","end":"2026-04-27T16:05:17.730686Z","steps":["trace[909526402] 'agreement among raft nodes before linearized reading' (duration: 372.843017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:17.730779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:17.35736Z","time spent":"373.40721ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":31148,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/udproutes.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-04-27T16:05:17.735209Z","caller":"traceutil/trace.go:171","msg":"trace[2086578952] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"358.402802ms","start":"2026-04-27T16:05:17.376779Z","end":"2026-04-27T16:05:17.735182Z","steps":["trace[2086578952] 'process raft request' (duration: 357.668813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:17.735377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:17.376748Z","time spent":"358.543776ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:05:22.855289Z","caller":"traceutil/trace.go:171","msg":"trace[1609350166] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"452.876599ms","start":"2026-04-27T16:05:22.402369Z","end":"2026-04-27T16:05:22.855245Z","steps":["trace[1609350166] 'read index received' (duration: 452.51139ms)","trace[1609350166] 'applied index is now lower than readState.Index' (duration: 363.799µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:05:22.855401Z","caller":"traceutil/trace.go:171","msg":"trace[548407181] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"472.461813ms","start":"2026-04-27T16:05:22.382886Z","end":"2026-04-27T16:05:22.855348Z","steps":["trace[548407181] 'process raft request' (duration: 472.137186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.855603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:22.382855Z","time spent":"472.643298ms","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:05:22.855679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.526481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-27T16:05:22.855753Z","caller":"traceutil/trace.go:171","msg":"trace[103874215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:588; }","duration":"348.640104ms","start":"2026-04-27T16:05:22.507092Z","end":"2026-04-27T16:05:22.855733Z","steps":["trace[103874215] 'agreement among raft nodes before linearized reading' (duration: 348.423247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.855716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.004696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:05:22.855663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.130165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:05:22.855794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:22.507057Z","time spent":"348.728966ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T16:05:22.855801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.329766ms","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-04-27T16:05:22.855811Z","caller":"traceutil/trace.go:171","msg":"trace[1973820966] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:588; }","duration":"111.209271ms","start":"2026-04-27T16:05:22.744582Z","end":"2026-04-27T16:05:22.855791Z","steps":["trace[1973820966] 'agreement among raft nodes before linearized reading' (duration: 111.012146ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:22.85584Z","caller":"traceutil/trace.go:171","msg":"trace[363523469] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:588; }","duration":"453.488075ms","start":"2026-04-27T16:05:22.402326Z","end":"2026-04-27T16:05:22.855814Z","steps":["trace[363523469] 'agreement among raft nodes before linearized reading' (duration: 453.120865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.855911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.135877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:05:22.855947Z","caller":"traceutil/trace.go:171","msg":"trace[1117978498] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:588; }","duration":"137.172957ms","start":"2026-04-27T16:05:22.718765Z","end":"2026-04-27T16:05:22.855938Z","steps":["trace[1117978498] 'agreement among raft nodes before linearized reading' (duration: 137.118646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.856041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.946512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:05:22.856069Z","caller":"traceutil/trace.go:171","msg":"trace[437942973] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:588; }","duration":"314.988712ms","start":"2026-04-27T16:05:22.541071Z","end":"2026-04-27T16:05:22.85606Z","steps":["trace[437942973] 'agreement among raft nodes before linearized reading' (duration: 314.944331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.856113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:22.541065Z","time spent":"315.040813ms","remote":"127.0.0.1:46536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:05:22.856185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.719096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:05:22.856267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.342812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:05:22.856292Z","caller":"traceutil/trace.go:171","msg":"trace[1543087808] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:588; }","duration":"315.380423ms","start":"2026-04-27T16:05:22.540903Z","end":"2026-04-27T16:05:22.856283Z","steps":["trace[1543087808] 'agreement among raft nodes before linearized reading' (duration: 315.337161ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:22.856273Z","caller":"traceutil/trace.go:171","msg":"trace[1702527322] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:588; }","duration":"314.955501ms","start":"2026-04-27T16:05:22.541295Z","end":"2026-04-27T16:05:22.856251Z","steps":["trace[1702527322] 'agreement among raft nodes before linearized reading' (duration: 314.713715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.857156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:22.541262Z","time spent":"315.849064ms","remote":"127.0.0.1:46536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-27T16:05:22.855754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.195631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:05:22.857337Z","caller":"traceutil/trace.go:171","msg":"trace[226018341] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:588; }","duration":"112.808052ms","start":"2026-04-27T16:05:22.744518Z","end":"2026-04-27T16:05:22.857326Z","steps":["trace[226018341] 'agreement among raft nodes before linearized reading' (duration: 111.201461ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:22.855843Z","caller":"traceutil/trace.go:171","msg":"trace[1336375589] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:588; }","duration":"136.378948ms","start":"2026-04-27T16:05:22.719447Z","end":"2026-04-27T16:05:22.855826Z","steps":["trace[1336375589] 'agreement among raft nodes before linearized reading' (duration: 136.327747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.856318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:22.54086Z","time spent":"315.452304ms","remote":"127.0.0.1:46536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-27T16:05:22.855949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:22.402299Z","time spent":"453.612967ms","remote":"127.0.0.1:42154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"warn","ts":"2026-04-27T16:05:22.855998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.230291ms","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-04-27T16:05:22.857749Z","caller":"traceutil/trace.go:171","msg":"trace[162384360] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:588; }","duration":"218.994296ms","start":"2026-04-27T16:05:22.638745Z","end":"2026-04-27T16:05:22.857739Z","steps":["trace[162384360] 'agreement among raft nodes before linearized reading' (duration: 217.227161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.856313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.810585ms","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-04-27T16:05:22.857885Z","caller":"traceutil/trace.go:171","msg":"trace[1041949584] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:588; }","duration":"194.390305ms","start":"2026-04-27T16:05:22.663486Z","end":"2026-04-27T16:05:22.857877Z","steps":["trace[1041949584] 'agreement among raft nodes before linearized reading' (duration: 192.784154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.85639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.559618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-27T16:05:22.858009Z","caller":"traceutil/trace.go:171","msg":"trace[2089524208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:588; }","duration":"319.196419ms","start":"2026-04-27T16:05:22.538806Z","end":"2026-04-27T16:05:22.858003Z","steps":["trace[2089524208] 'agreement among raft nodes before linearized reading' (duration: 317.525327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.858037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:22.538797Z","time spent":"319.227719ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T16:05:22.856432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.68363ms","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-04-27T16:05:22.858134Z","caller":"traceutil/trace.go:171","msg":"trace[550712599] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:588; }","duration":"336.440434ms","start":"2026-04-27T16:05:22.521687Z","end":"2026-04-27T16:05:22.858127Z","steps":["trace[550712599] 'agreement among raft nodes before linearized reading' (duration: 334.718391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.858158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:22.521655Z","time spent":"336.495075ms","remote":"127.0.0.1:46522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:05:22.856462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.727781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:05:22.858296Z","caller":"traceutil/trace.go:171","msg":"trace[16847277] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:588; }","duration":"336.603667ms","start":"2026-04-27T16:05:22.521685Z","end":"2026-04-27T16:05:22.858289Z","steps":["trace[16847277] 'agreement among raft nodes before linearized reading' (duration: 334.758351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.85832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:22.521641Z","time spent":"336.671519ms","remote":"127.0.0.1:46522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-27T16:05:22.85566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.136568ms","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-04-27T16:05:22.858458Z","caller":"traceutil/trace.go:171","msg":"trace[180250286] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:588; }","duration":"194.938458ms","start":"2026-04-27T16:05:22.66346Z","end":"2026-04-27T16:05:22.858398Z","steps":["trace[180250286] 'agreement among raft nodes before linearized reading' (duration: 192.108377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.856556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.755428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:05:22.858733Z","caller":"traceutil/trace.go:171","msg":"trace[631748302] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:588; }","duration":"250.964844ms","start":"2026-04-27T16:05:22.607752Z","end":"2026-04-27T16:05:22.858717Z","steps":["trace[631748302] 'agreement among raft nodes before linearized reading' (duration: 248.726597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.856558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.789954ms","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-04-27T16:05:22.859052Z","caller":"traceutil/trace.go:171","msg":"trace[863908333] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:588; }","duration":"195.361338ms","start":"2026-04-27T16:05:22.663679Z","end":"2026-04-27T16:05:22.85904Z","steps":["trace[863908333] 'agreement among raft nodes before linearized reading' (duration: 192.468015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.856642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.974838ms","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-04-27T16:05:22.859395Z","caller":"traceutil/trace.go:171","msg":"trace[236595018] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:588; }","duration":"140.748918ms","start":"2026-04-27T16:05:22.718633Z","end":"2026-04-27T16:05:22.859382Z","steps":["trace[236595018] 'agreement among raft nodes before linearized reading' (duration: 137.966927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.856725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.25252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:05:22.859946Z","caller":"traceutil/trace.go:171","msg":"trace[117233519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"142.486383ms","start":"2026-04-27T16:05:22.717446Z","end":"2026-04-27T16:05:22.859932Z","steps":["trace[117233519] 'agreement among raft nodes before linearized reading' (duration: 139.197849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.856772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.623246ms","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-04-27T16:05:22.860061Z","caller":"traceutil/trace.go:171","msg":"trace[159674763] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:588; }","duration":"221.96204ms","start":"2026-04-27T16:05:22.638084Z","end":"2026-04-27T16:05:22.860046Z","steps":["trace[159674763] 'agreement among raft nodes before linearized reading' (duration: 218.494803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.856776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.845721ms","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-04-27T16:05:22.860206Z","caller":"traceutil/trace.go:171","msg":"trace[1920943589] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:588; }","duration":"222.318159ms","start":"2026-04-27T16:05:22.63788Z","end":"2026-04-27T16:05:22.860198Z","steps":["trace[1920943589] 'agreement among raft nodes before linearized reading' (duration: 218.873912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.856852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.520897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:05:22.860282Z","caller":"traceutil/trace.go:171","msg":"trace[266378442] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:588; }","duration":"305.962505ms","start":"2026-04-27T16:05:22.554306Z","end":"2026-04-27T16:05:22.860268Z","steps":["trace[266378442] 'agreement among raft nodes before linearized reading' (duration: 302.516458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.860361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:22.554295Z","time spent":"306.043526ms","remote":"127.0.0.1:46550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"warn","ts":"2026-04-27T16:05:22.856847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.261626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:05:22.860602Z","caller":"traceutil/trace.go:171","msg":"trace[1053558682] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:588; }","duration":"252.029361ms","start":"2026-04-27T16:05:22.608557Z","end":"2026-04-27T16:05:22.860586Z","steps":["trace[1053558682] 'agreement among raft nodes before linearized reading' (duration: 248.241805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.8569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.21723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:05:22.860853Z","caller":"traceutil/trace.go:171","msg":"trace[157319696] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:588; }","duration":"253.194221ms","start":"2026-04-27T16:05:22.607641Z","end":"2026-04-27T16:05:22.860835Z","steps":["trace[157319696] 'agreement among raft nodes before linearized reading' (duration: 249.231011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.856884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.05073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:05:22.861063Z","caller":"traceutil/trace.go:171","msg":"trace[1524379593] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:588; }","duration":"307.269847ms","start":"2026-04-27T16:05:22.553783Z","end":"2026-04-27T16:05:22.861053Z","steps":["trace[1524379593] 'agreement among raft nodes before linearized reading' (duration: 303.051121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.861113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:22.553768Z","time spent":"307.326979ms","remote":"127.0.0.1:46550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:05:22.856952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.170264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:05:22.861448Z","caller":"traceutil/trace.go:171","msg":"trace[605981016] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:588; }","duration":"307.691158ms","start":"2026-04-27T16:05:22.553739Z","end":"2026-04-27T16:05:22.861431Z","steps":["trace[605981016] 'agreement among raft nodes before linearized reading' (duration: 303.168384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:22.861512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:22.553725Z","time spent":"307.766161ms","remote":"127.0.0.1:46550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"warn","ts":"2026-04-27T16:05:23.738524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.581473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:05:23.738658Z","caller":"traceutil/trace.go:171","msg":"trace[1345400911] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"870.301581ms","start":"2026-04-27T16:05:22.868331Z","end":"2026-04-27T16:05:23.738632Z","steps":["trace[1345400911] 'process raft request' (duration: 199.479673ms)","trace[1345400911] 'compare' (duration: 670.213542ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:05:23.738727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:22.868319Z","time spent":"870.375052ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:05:23.768013Z","caller":"traceutil/trace.go:171","msg":"trace[2054481616] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"506.082513ms","start":"2026-04-27T16:05:23.261889Z","end":"2026-04-27T16:05:23.767971Z","steps":["trace[2054481616] 'process raft request' (duration: 498.42496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:23.768289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:23.261843Z","time spent":"506.276788ms","remote":"127.0.0.1:41776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:05:23.781006Z","caller":"traceutil/trace.go:171","msg":"trace[1350291368] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"470.813871ms","start":"2026-04-27T16:05:23.310155Z","end":"2026-04-27T16:05:23.780969Z","steps":["trace[1350291368] 'process raft request' (duration: 470.078553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:23.781258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:23.310122Z","time spent":"471.025168ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:05:24.61958Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410015590374667,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T16:05:25.120532Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410015590374667,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T16:05:25.620876Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410015590374667,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T16:05:26.121917Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410015590374667,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T16:05:26.134657Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.230828404s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T16:05:26.135217Z","caller":"traceutil/trace.go:171","msg":"trace[1901409546] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"2.01623715s","start":"2026-04-27T16:05:24.118943Z","end":"2026-04-27T16:05:26.13518Z","steps":["trace[1901409546] 'read index received' (duration: 2.015859651s)","trace[1901409546] 'applied index is now lower than readState.Index' (duration: 376.349µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:05:26.135338Z","caller":"traceutil/trace.go:171","msg":"trace[1148146933] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"2.300052624s","start":"2026-04-27T16:05:23.835245Z","end":"2026-04-27T16:05:26.135297Z","steps":["trace[1148146933] 'process raft request' (duration: 2.299718296s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:26.135502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:23.835213Z","time spent":"2.300182467s","remote":"127.0.0.1:42154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:05:26.135555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.016593949s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-27T16:05:26.135607Z","caller":"traceutil/trace.go:171","msg":"trace[1055113758] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:594; }","duration":"2.016669511s","start":"2026-04-27T16:05:24.118923Z","end":"2026-04-27T16:05:26.135593Z","steps":["trace[1055113758] 'agreement among raft nodes before linearized reading' (duration: 2.016439565s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:26.135649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:24.118832Z","time spent":"2.016806954s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T16:05:26.135848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.865535941s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-27T16:05:26.135857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.955169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-27T16:05:26.135937Z","caller":"traceutil/trace.go:171","msg":"trace[1207395427] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:594; }","duration":"1.865651573s","start":"2026-04-27T16:05:24.27023Z","end":"2026-04-27T16:05:26.135881Z","steps":["trace[1207395427] 'agreement among raft nodes before linearized reading' (duration: 1.865460618s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:26.135972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:24.2702Z","time spent":"1.865764976s","remote":"127.0.0.1:41736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.216\" "} {"level":"warn","ts":"2026-04-27T16:05:26.136071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.510878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-27T16:05:26.136079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.242074029s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-27T16:05:26.136139Z","caller":"traceutil/trace.go:171","msg":"trace[821975730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:594; }","duration":"306.629621ms","start":"2026-04-27T16:05:25.8295Z","end":"2026-04-27T16:05:26.13613Z","steps":["trace[821975730] 'agreement among raft nodes before linearized reading' (duration: 306.494148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:26.136142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.424918611s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:05:26.136189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:25.829473Z","time spent":"306.700033ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-27T16:05:26.136248Z","caller":"traceutil/trace.go:171","msg":"trace[126622439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"1.425074636s","start":"2026-04-27T16:05:24.71116Z","end":"2026-04-27T16:05:26.136235Z","steps":["trace[126622439] 'agreement among raft nodes before linearized reading' (duration: 1.424903881s)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:26.136147Z","caller":"traceutil/trace.go:171","msg":"trace[165812158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"1.242196703s","start":"2026-04-27T16:05:24.893939Z","end":"2026-04-27T16:05:26.136136Z","steps":["trace[165812158] 'agreement among raft nodes before linearized reading' (duration: 1.24206517s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:26.136317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:24.711131Z","time spent":"1.425176428s","remote":"127.0.0.1:41716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:05:26.136343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:24.893912Z","time spent":"1.242406698s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T16:05:26.135967Z","caller":"traceutil/trace.go:171","msg":"trace[257178442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:594; }","duration":"392.118183ms","start":"2026-04-27T16:05:25.743829Z","end":"2026-04-27T16:05:26.135947Z","steps":["trace[257178442] 'agreement among raft nodes before linearized reading' (duration: 391.886117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:26.136426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:25.743786Z","time spent":"392.632916ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T16:05:27.860127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.494853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:05:27.860223Z","caller":"traceutil/trace.go:171","msg":"trace[767629524] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:618; }","duration":"307.686988ms","start":"2026-04-27T16:05:27.552515Z","end":"2026-04-27T16:05:27.860202Z","steps":["trace[767629524] 'range keys from in-memory index tree' (duration: 307.38509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:27.860272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:27.552491Z","time spent":"307.77085ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T16:05:27.860438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.442507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:05:27.860525Z","caller":"traceutil/trace.go:171","msg":"trace[702525382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"149.596901ms","start":"2026-04-27T16:05:27.710907Z","end":"2026-04-27T16:05:27.860504Z","steps":["trace[702525382] 'range keys from in-memory index tree' (duration: 149.345015ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:32.486078Z","caller":"traceutil/trace.go:171","msg":"trace[1795974635] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"118.858755ms","start":"2026-04-27T16:05:32.367192Z","end":"2026-04-27T16:05:32.486051Z","steps":["trace[1795974635] 'process raft request' (duration: 118.69458ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:32.492053Z","caller":"traceutil/trace.go:171","msg":"trace[69651015] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"123.472072ms","start":"2026-04-27T16:05:32.368554Z","end":"2026-04-27T16:05:32.492027Z","steps":["trace[69651015] 'process raft request' (duration: 122.845826ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:38.47536Z","caller":"traceutil/trace.go:171","msg":"trace[815084927] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"240.987972ms","start":"2026-04-27T16:05:38.234348Z","end":"2026-04-27T16:05:38.475336Z","steps":["trace[815084927] 'read index received' (duration: 240.889369ms)","trace[815084927] 'applied index is now lower than readState.Index' (duration: 98.002µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:05:38.475452Z","caller":"traceutil/trace.go:171","msg":"trace[1190154704] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"242.972042ms","start":"2026-04-27T16:05:38.232435Z","end":"2026-04-27T16:05:38.475407Z","steps":["trace[1190154704] 'process raft request' (duration: 242.757096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:38.475591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.213588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-27T16:05:38.475649Z","caller":"traceutil/trace.go:171","msg":"trace[1351240437] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:741; }","duration":"241.312511ms","start":"2026-04-27T16:05:38.234322Z","end":"2026-04-27T16:05:38.475635Z","steps":["trace[1351240437] 'agreement among raft nodes before linearized reading' (duration: 241.104476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:38.476906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.929096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-27T16:05:38.476967Z","caller":"traceutil/trace.go:171","msg":"trace[613908846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:742; }","duration":"242.06755ms","start":"2026-04-27T16:05:38.234886Z","end":"2026-04-27T16:05:38.476954Z","steps":["trace[613908846] 'agreement among raft nodes before linearized reading' (duration: 241.815913ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:38.477289Z","caller":"traceutil/trace.go:171","msg":"trace[35093008] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"240.488059ms","start":"2026-04-27T16:05:38.23679Z","end":"2026-04-27T16:05:38.477279Z","steps":["trace[35093008] 'process raft request' (duration: 239.598907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:41.83446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.909319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T16:05:41.834603Z","caller":"traceutil/trace.go:171","msg":"trace[249346213] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:758; }","duration":"508.144375ms","start":"2026-04-27T16:05:41.326424Z","end":"2026-04-27T16:05:41.834568Z","steps":["trace[249346213] 'count revisions from in-memory index tree' (duration: 507.747865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:41.834666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:41.326392Z","time spent":"508.257798ms","remote":"127.0.0.1:41788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":13,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-04-27T16:05:42.160941Z","caller":"traceutil/trace.go:171","msg":"trace[1287112612] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:784; }","duration":"100.319516ms","start":"2026-04-27T16:05:42.060589Z","end":"2026-04-27T16:05:42.160908Z","steps":["trace[1287112612] 'read index received' (duration: 100.05959ms)","trace[1287112612] 'applied index is now lower than readState.Index' (duration: 258.806µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:05:42.161074Z","caller":"traceutil/trace.go:171","msg":"trace[1933412526] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"646.085563ms","start":"2026-04-27T16:05:41.514921Z","end":"2026-04-27T16:05:42.161007Z","steps":["trace[1933412526] 'process raft request' (duration: 645.794066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:42.16128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:41.514893Z","time spent":"646.229526ms","remote":"127.0.0.1:41894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:05:42.161338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.725716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-27T16:05:42.161439Z","caller":"traceutil/trace.go:171","msg":"trace[2086592187] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:759; }","duration":"101.006754ms","start":"2026-04-27T16:05:42.060411Z","end":"2026-04-27T16:05:42.161417Z","steps":["trace[2086592187] 'agreement among raft nodes before linearized reading' (duration: 100.625414ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:48.934556Z","caller":"traceutil/trace.go:171","msg":"trace[78962150] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"380.743895ms","start":"2026-04-27T16:05:48.553785Z","end":"2026-04-27T16:05:48.934529Z","steps":["trace[78962150] 'process raft request' (duration: 380.706904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:48.934726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:48.553768Z","time spent":"380.868947ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:05:48.934682Z","caller":"traceutil/trace.go:171","msg":"trace[1193005964] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"381.478043ms","start":"2026-04-27T16:05:48.553159Z","end":"2026-04-27T16:05:48.934637Z","steps":["trace[1193005964] 'process raft request' (duration: 381.171376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:48.93492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:48.553133Z","time spent":"381.665828ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:05:49.17715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.065376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:05:49.177358Z","caller":"traceutil/trace.go:171","msg":"trace[1792605767] linearizableReadLoop","detail":"{readStateIndex:859; appliedIndex:855; }","duration":"495.204358ms","start":"2026-04-27T16:05:48.682133Z","end":"2026-04-27T16:05:49.177338Z","steps":["trace[1792605767] 'read index received' (duration: 252.244316ms)","trace[1792605767] 'applied index is now lower than readState.Index' (duration: 242.959132ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:05:49.177371Z","caller":"traceutil/trace.go:171","msg":"trace[603134226] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"607.62931ms","start":"2026-04-27T16:05:48.569709Z","end":"2026-04-27T16:05:49.177338Z","steps":["trace[603134226] 'process raft request' (duration: 430.276897ms)","trace[603134226] 'compare' (duration: 176.911812ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:05:49.177464Z","caller":"traceutil/trace.go:171","msg":"trace[1052914020] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"527.885435ms","start":"2026-04-27T16:05:48.649557Z","end":"2026-04-27T16:05:49.177443Z","steps":["trace[1052914020] 'process raft request' (duration: 527.719871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:49.177524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:48.649483Z","time spent":"528.011177ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:05:49.17757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.418013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T16:05:49.17764Z","caller":"traceutil/trace.go:171","msg":"trace[911344067] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:831; }","duration":"495.548006ms","start":"2026-04-27T16:05:48.682074Z","end":"2026-04-27T16:05:49.177622Z","steps":["trace[911344067] 'agreement among raft nodes before linearized reading' (duration: 495.428993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:49.177616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:48.569679Z","time spent":"607.781914ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:05:49.177687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:48.682048Z","time spent":"495.624668ms","remote":"127.0.0.1:42020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:05:49.177696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.984553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5066"} {"level":"info","ts":"2026-04-27T16:05:49.17778Z","caller":"traceutil/trace.go:171","msg":"trace[2007016564] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:831; }","duration":"122.124547ms","start":"2026-04-27T16:05:49.055635Z","end":"2026-04-27T16:05:49.177759Z","steps":["trace[2007016564] 'agreement among raft nodes before linearized reading' (duration: 121.85857ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:49.369914Z","caller":"traceutil/trace.go:171","msg":"trace[1281507351] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"184.289668ms","start":"2026-04-27T16:05:49.18558Z","end":"2026-04-27T16:05:49.36987Z","steps":["trace[1281507351] 'process raft request' (duration: 143.801985ms)","trace[1281507351] 'compare' (duration: 40.297299ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:05:49.564522Z","caller":"traceutil/trace.go:171","msg":"trace[511100357] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"188.514455ms","start":"2026-04-27T16:05:49.375946Z","end":"2026-04-27T16:05:49.56446Z","steps":["trace[511100357] 'process raft request' (duration: 100.566312ms)","trace[511100357] 'compare' (duration: 87.714808ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:05:49.652309Z","caller":"traceutil/trace.go:171","msg":"trace[1557076412] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"275.781942ms","start":"2026-04-27T16:05:49.376477Z","end":"2026-04-27T16:05:49.652259Z","steps":["trace[1557076412] 'process raft request' (duration: 275.369732ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:49.652649Z","caller":"traceutil/trace.go:171","msg":"trace[761122841] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"272.92563ms","start":"2026-04-27T16:05:49.379682Z","end":"2026-04-27T16:05:49.652608Z","steps":["trace[761122841] 'process raft request' (duration: 272.484828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:49.936321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.652184ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-27T16:05:49.936621Z","caller":"traceutil/trace.go:171","msg":"trace[96723028] linearizableReadLoop","detail":"{readStateIndex:864; appliedIndex:861; }","duration":"366.404143ms","start":"2026-04-27T16:05:49.570194Z","end":"2026-04-27T16:05:49.936598Z","steps":["trace[96723028] 'read index received' (duration: 81.635774ms)","trace[96723028] 'applied index is now lower than readState.Index' (duration: 284.767329ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:05:49.93673Z","caller":"traceutil/trace.go:171","msg":"trace[1802310963] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"365.983532ms","start":"2026-04-27T16:05:49.570736Z","end":"2026-04-27T16:05:49.936719Z","steps":["trace[1802310963] 'process raft request' (duration: 365.719315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:49.936829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:49.570708Z","time spent":"366.056354ms","remote":"127.0.0.1:41766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":719,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:05:49.93717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.664663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:05:49.93721Z","caller":"traceutil/trace.go:171","msg":"trace[1876761253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:836; }","duration":"223.758585ms","start":"2026-04-27T16:05:49.713439Z","end":"2026-04-27T16:05:49.937197Z","steps":["trace[1876761253] 'agreement among raft nodes before linearized reading' (duration: 223.689634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:49.93723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.033558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-qsdnn\" ","response":"range_response_count:1 size:3871"} {"level":"info","ts":"2026-04-27T16:05:49.937263Z","caller":"traceutil/trace.go:171","msg":"trace[1999137933] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-qsdnn; range_end:; response_count:1; response_revision:836; }","duration":"367.12412ms","start":"2026-04-27T16:05:49.570129Z","end":"2026-04-27T16:05:49.937253Z","steps":["trace[1999137933] 'agreement among raft nodes before linearized reading' (duration: 366.937165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:49.937301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:49.570098Z","time spent":"367.196503ms","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":3893,"request content":"key:\"/registry/pods/kube-system/coredns-67659f764b-qsdnn\" "} {"level":"warn","ts":"2026-04-27T16:05:49.937352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.428176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-04-27T16:05:49.937376Z","caller":"traceutil/trace.go:171","msg":"trace[1939135541] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:836; }","duration":"196.500607ms","start":"2026-04-27T16:05:49.74087Z","end":"2026-04-27T16:05:49.93737Z","steps":["trace[1939135541] 'agreement among raft nodes before linearized reading' (duration: 196.453416ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:50.286099Z","caller":"traceutil/trace.go:171","msg":"trace[192332917] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"338.387554ms","start":"2026-04-27T16:05:49.947516Z","end":"2026-04-27T16:05:50.285904Z","steps":["trace[192332917] 'process raft request' (duration: 277.30083ms)","trace[192332917] 'compare' (duration: 60.598923ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:05:50.287225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:49.947504Z","time spent":"339.615205ms","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4762,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:05:50.541064Z","caller":"traceutil/trace.go:171","msg":"trace[503526040] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"241.974407ms","start":"2026-04-27T16:05:50.29906Z","end":"2026-04-27T16:05:50.541034Z","steps":["trace[503526040] 'process raft request' (duration: 241.69719ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:50.683888Z","caller":"traceutil/trace.go:171","msg":"trace[871996496] linearizableReadLoop","detail":"{readStateIndex:869; appliedIndex:868; }","duration":"114.387312ms","start":"2026-04-27T16:05:50.569471Z","end":"2026-04-27T16:05:50.683858Z","steps":["trace[871996496] 'read index received' (duration: 44.242159ms)","trace[871996496] 'applied index is now lower than readState.Index' (duration: 70.143973ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:05:50.68399Z","caller":"traceutil/trace.go:171","msg":"trace[1246831852] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"382.299035ms","start":"2026-04-27T16:05:50.301628Z","end":"2026-04-27T16:05:50.683927Z","steps":["trace[1246831852] 'process raft request' (duration: 312.194192ms)","trace[1246831852] 'compare' (duration: 69.876787ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:05:50.684245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.748421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T16:05:50.684316Z","caller":"traceutil/trace.go:171","msg":"trace[169433002] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:840; }","duration":"114.870104ms","start":"2026-04-27T16:05:50.569425Z","end":"2026-04-27T16:05:50.684295Z","steps":["trace[169433002] 'agreement among raft nodes before linearized reading' (duration: 114.550376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:50.684411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:50.301603Z","time spent":"382.589451ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:05:50.910664Z","caller":"traceutil/trace.go:171","msg":"trace[1471786616] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"220.085054ms","start":"2026-04-27T16:05:50.690541Z","end":"2026-04-27T16:05:50.910626Z","steps":["trace[1471786616] 'process raft request' (duration: 149.529291ms)","trace[1471786616] 'compare' (duration: 70.334377ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:05:51.117441Z","caller":"traceutil/trace.go:171","msg":"trace[185499326] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"173.571498ms","start":"2026-04-27T16:05:50.943837Z","end":"2026-04-27T16:05:51.117408Z","steps":["trace[185499326] 'process raft request' (duration: 104.79586ms)","trace[185499326] 'compare' (duration: 68.637225ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:05:51.122935Z","caller":"traceutil/trace.go:171","msg":"trace[1815085834] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"177.461045ms","start":"2026-04-27T16:05:50.945442Z","end":"2026-04-27T16:05:51.122903Z","steps":["trace[1815085834] 'process raft request' (duration: 177.306182ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:51.292614Z","caller":"traceutil/trace.go:171","msg":"trace[1276937610] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"105.923297ms","start":"2026-04-27T16:05:51.186663Z","end":"2026-04-27T16:05:51.292587Z","steps":["trace[1276937610] 'process raft request' (duration: 105.776634ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:53.254196Z","caller":"traceutil/trace.go:171","msg":"trace[1396701256] linearizableReadLoop","detail":"{readStateIndex:879; appliedIndex:878; }","duration":"338.287281ms","start":"2026-04-27T16:05:52.915876Z","end":"2026-04-27T16:05:53.254163Z","steps":["trace[1396701256] 'read index received' (duration: 338.085606ms)","trace[1396701256] 'applied index is now lower than readState.Index' (duration: 200.715µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:05:53.254269Z","caller":"traceutil/trace.go:171","msg":"trace[1165589678] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"355.981278ms","start":"2026-04-27T16:05:52.898236Z","end":"2026-04-27T16:05:53.254217Z","steps":["trace[1165589678] 'process raft request' (duration: 355.746423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:53.25446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:52.898214Z","time spent":"356.149903ms","remote":"127.0.0.1:41766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":802,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:05:53.254576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.657261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T16:05:53.254682Z","caller":"traceutil/trace.go:171","msg":"trace[2097111533] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:850; }","duration":"338.819795ms","start":"2026-04-27T16:05:52.915846Z","end":"2026-04-27T16:05:53.254666Z","steps":["trace[2097111533] 'agreement among raft nodes before linearized reading' (duration: 338.481726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:53.254719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.631839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:05:53.254754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:52.915833Z","time spent":"338.907687ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T16:05:53.25473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.630487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:05:53.254839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.539559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-27T16:05:53.254868Z","caller":"traceutil/trace.go:171","msg":"trace[962798262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:850; }","duration":"128.623321ms","start":"2026-04-27T16:05:53.126237Z","end":"2026-04-27T16:05:53.254861Z","steps":["trace[962798262] 'agreement among raft nodes before linearized reading' (duration: 128.546429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:53.254877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.921642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:05:53.254929Z","caller":"traceutil/trace.go:171","msg":"trace[882256854] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:850; }","duration":"244.991163ms","start":"2026-04-27T16:05:53.009913Z","end":"2026-04-27T16:05:53.254904Z","steps":["trace[882256854] 'agreement among raft nodes before linearized reading' (duration: 244.898321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:53.254984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.047695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:05:53.255014Z","caller":"traceutil/trace.go:171","msg":"trace[949606294] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:850; }","duration":"245.120806ms","start":"2026-04-27T16:05:53.009886Z","end":"2026-04-27T16:05:53.255006Z","steps":["trace[949606294] 'agreement among raft nodes before linearized reading' (duration: 245.057065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:53.255081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.383613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:05:53.255103Z","caller":"traceutil/trace.go:171","msg":"trace[90208816] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:850; }","duration":"262.417505ms","start":"2026-04-27T16:05:52.99268Z","end":"2026-04-27T16:05:53.255098Z","steps":["trace[90208816] 'agreement among raft nodes before linearized reading' (duration: 262.384834ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:53.254766Z","caller":"traceutil/trace.go:171","msg":"trace[767779013] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:850; }","duration":"262.697161ms","start":"2026-04-27T16:05:52.992053Z","end":"2026-04-27T16:05:53.25475Z","steps":["trace[767779013] 'agreement among raft nodes before linearized reading' (duration: 262.618109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:53.255267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.527125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-27T16:05:53.255299Z","caller":"traceutil/trace.go:171","msg":"trace[1851861744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:850; }","duration":"133.611098ms","start":"2026-04-27T16:05:53.12168Z","end":"2026-04-27T16:05:53.255291Z","steps":["trace[1851861744] 'agreement among raft nodes before linearized reading' (duration: 133.459305ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:53.25488Z","caller":"traceutil/trace.go:171","msg":"trace[459844205] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:850; }","duration":"326.798972ms","start":"2026-04-27T16:05:52.928062Z","end":"2026-04-27T16:05:53.254861Z","steps":["trace[459844205] 'agreement among raft nodes before linearized reading' (duration: 326.564186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:53.255355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:52.928051Z","time spent":"327.282684ms","remote":"127.0.0.1:50584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:05:53.255486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.526701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:05:53.255532Z","caller":"traceutil/trace.go:171","msg":"trace[865900110] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:850; }","duration":"276.596233ms","start":"2026-04-27T16:05:52.978924Z","end":"2026-04-27T16:05:53.25552Z","steps":["trace[865900110] 'agreement among raft nodes before linearized reading' (duration: 276.537731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:53.25553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.076399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:05:53.255637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.588372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:05:53.25571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.778868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:05:53.255712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.123739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:05:53.255662Z","caller":"traceutil/trace.go:171","msg":"trace[304729002] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:850; }","duration":"276.201592ms","start":"2026-04-27T16:05:52.979438Z","end":"2026-04-27T16:05:53.25564Z","steps":["trace[304729002] 'agreement among raft nodes before linearized reading' (duration: 276.067488ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:53.255743Z","caller":"traceutil/trace.go:171","msg":"trace[575056758] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:850; }","duration":"263.82774ms","start":"2026-04-27T16:05:52.991908Z","end":"2026-04-27T16:05:53.255736Z","steps":["trace[575056758] 'agreement among raft nodes before linearized reading' (duration: 263.773318ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:53.255767Z","caller":"traceutil/trace.go:171","msg":"trace[136332969] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:850; }","duration":"327.187222ms","start":"2026-04-27T16:05:52.928564Z","end":"2026-04-27T16:05:53.255752Z","steps":["trace[136332969] 'agreement among raft nodes before linearized reading' (duration: 327.102559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:53.255382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.175457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:05:53.255821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:52.928561Z","time spent":"327.247714ms","remote":"127.0.0.1:50584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "} {"level":"info","ts":"2026-04-27T16:05:53.255841Z","caller":"traceutil/trace.go:171","msg":"trace[912198456] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:850; }","duration":"245.63882ms","start":"2026-04-27T16:05:53.010189Z","end":"2026-04-27T16:05:53.255828Z","steps":["trace[912198456] 'agreement among raft nodes before linearized reading' (duration: 245.173428ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:53.25571Z","caller":"traceutil/trace.go:171","msg":"trace[975109634] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:850; }","duration":"276.663834ms","start":"2026-04-27T16:05:52.979026Z","end":"2026-04-27T16:05:53.25569Z","steps":["trace[975109634] 'agreement among raft nodes before linearized reading' (duration: 276.571972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:53.255927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.771426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:05:53.255957Z","caller":"traceutil/trace.go:171","msg":"trace[1872275280] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:850; }","duration":"327.811617ms","start":"2026-04-27T16:05:52.928139Z","end":"2026-04-27T16:05:53.25595Z","steps":["trace[1872275280] 'agreement among raft nodes before linearized reading' (duration: 327.765346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:05:53.255978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:05:52.928135Z","time spent":"327.837288ms","remote":"127.0.0.1:50584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "} {"level":"warn","ts":"2026-04-27T16:05:54.102751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.419254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:05:54.1029Z","caller":"traceutil/trace.go:171","msg":"trace[811367425] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"254.568085ms","start":"2026-04-27T16:05:53.848307Z","end":"2026-04-27T16:05:54.102875Z","steps":["trace[811367425] 'process raft request' (duration: 102.458579ms)","trace[811367425] 'compare' (duration: 151.666825ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:05:54.735993Z","caller":"traceutil/trace.go:171","msg":"trace[1657222233] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"104.286906ms","start":"2026-04-27T16:05:54.631682Z","end":"2026-04-27T16:05:54.735969Z","steps":["trace[1657222233] 'process raft request' (duration: 104.218535ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:05:54.736293Z","caller":"traceutil/trace.go:171","msg":"trace[1772818817] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"108.142414ms","start":"2026-04-27T16:05:54.627861Z","end":"2026-04-27T16:05:54.736004Z","steps":["trace[1772818817] 'process raft request' (duration: 100.259875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:20.094804Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410015590375440,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T16:06:21.209172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.165626ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-27T16:06:21.21005Z","caller":"traceutil/trace.go:171","msg":"trace[1564395620] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"1.582673179s","start":"2026-04-27T16:06:19.62736Z","end":"2026-04-27T16:06:21.210033Z","steps":["trace[1564395620] 'process raft request' (duration: 1.581970591s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:21.210216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:19.627332Z","time spent":"1.582795581s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:06:21.210698Z","caller":"traceutil/trace.go:171","msg":"trace[1258170242] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1012; }","duration":"1.615954539s","start":"2026-04-27T16:06:19.594013Z","end":"2026-04-27T16:06:21.209968Z","steps":["trace[1258170242] 'read index received' (duration: 767.996544ms)","trace[1258170242] 'applied index is now lower than readState.Index' (duration: 847.956165ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:06:21.21158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.498661355s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:06:21.211643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.617617512s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T16:06:21.211675Z","caller":"traceutil/trace.go:171","msg":"trace[1043454902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"1.498802539s","start":"2026-04-27T16:06:19.712856Z","end":"2026-04-27T16:06:21.211659Z","steps":["trace[1043454902] 'agreement among raft nodes before linearized reading' (duration: 1.498006119s)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:21.211702Z","caller":"traceutil/trace.go:171","msg":"trace[898320307] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:978; }","duration":"1.617717233s","start":"2026-04-27T16:06:19.593969Z","end":"2026-04-27T16:06:21.211686Z","steps":["trace[898320307] 'agreement among raft nodes before linearized reading' (duration: 1.616448851s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:21.211718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:19.712837Z","time spent":"1.49887091s","remote":"127.0.0.1:41716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:06:21.211899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.437645233s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-27T16:06:21.211937Z","caller":"traceutil/trace.go:171","msg":"trace[1815359722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:978; }","duration":"1.437692494s","start":"2026-04-27T16:06:19.774235Z","end":"2026-04-27T16:06:21.211928Z","steps":["trace[1815359722] 'agreement among raft nodes before linearized reading' (duration: 1.436675728s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:21.211963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:19.774229Z","time spent":"1.437728205s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T16:06:21.212133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.454671933s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-27T16:06:21.212173Z","caller":"traceutil/trace.go:171","msg":"trace[196772670] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:978; }","duration":"1.454794966s","start":"2026-04-27T16:06:19.757369Z","end":"2026-04-27T16:06:21.212164Z","steps":["trace[196772670] 'agreement among raft nodes before linearized reading' (duration: 1.453562974s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:21.212203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.454687084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-27T16:06:21.212244Z","caller":"traceutil/trace.go:171","msg":"trace[333419359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:978; }","duration":"1.454727384s","start":"2026-04-27T16:06:19.757507Z","end":"2026-04-27T16:06:21.212234Z","steps":["trace[333419359] 'agreement among raft nodes before linearized reading' (duration: 1.45336171s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:21.212277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:19.757458Z","time spent":"1.454813356s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T16:06:21.212383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:19.757351Z","time spent":"1.454840417s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T16:06:21.211718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.438070874s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T16:06:21.212626Z","caller":"traceutil/trace.go:171","msg":"trace[1626125889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:978; }","duration":"1.439000667s","start":"2026-04-27T16:06:19.773607Z","end":"2026-04-27T16:06:21.212608Z","steps":["trace[1626125889] 'agreement among raft nodes before linearized reading' (duration: 1.437257693s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:21.21174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:19.59395Z","time spent":"1.617780656s","remote":"127.0.0.1:42170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:06:21.212694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:19.773596Z","time spent":"1.439078859s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T16:06:21.212965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.454737014s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T16:06:21.212999Z","caller":"traceutil/trace.go:171","msg":"trace[617958533] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:978; }","duration":"1.454808256s","start":"2026-04-27T16:06:19.758181Z","end":"2026-04-27T16:06:21.212989Z","steps":["trace[617958533] 'agreement among raft nodes before linearized reading' (duration: 1.452732624s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:21.213024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:19.758167Z","time spent":"1.454852077s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T16:06:39.478853Z","caller":"traceutil/trace.go:171","msg":"trace[928618119] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"142.633595ms","start":"2026-04-27T16:06:39.336199Z","end":"2026-04-27T16:06:39.478832Z","steps":["trace[928618119] 'process raft request' (duration: 138.832237ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:39.482521Z","caller":"traceutil/trace.go:171","msg":"trace[2043831937] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"144.991945ms","start":"2026-04-27T16:06:39.337504Z","end":"2026-04-27T16:06:39.482496Z","steps":["trace[2043831937] 'process raft request' (duration: 144.954724ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:39.482615Z","caller":"traceutil/trace.go:171","msg":"trace[218002170] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"145.765575ms","start":"2026-04-27T16:06:39.336825Z","end":"2026-04-27T16:06:39.48259Z","steps":["trace[218002170] 'process raft request' (duration: 145.602621ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:39.482586Z","caller":"traceutil/trace.go:171","msg":"trace[229061681] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"146.169415ms","start":"2026-04-27T16:06:39.336404Z","end":"2026-04-27T16:06:39.482574Z","steps":["trace[229061681] 'process raft request' (duration: 145.989531ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:39.482928Z","caller":"traceutil/trace.go:171","msg":"trace[1571627769] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"146.143175ms","start":"2026-04-27T16:06:39.336406Z","end":"2026-04-27T16:06:39.482549Z","steps":["trace[1571627769] 'process raft request' (duration: 145.891339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:48.010845Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410015590375673,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T16:06:48.139625Z","caller":"traceutil/trace.go:171","msg":"trace[1862144802] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1104; }","duration":"629.18673ms","start":"2026-04-27T16:06:47.510417Z","end":"2026-04-27T16:06:48.139604Z","steps":["trace[1862144802] 'read index received' (duration: 629.014415ms)","trace[1862144802] 'applied index is now lower than readState.Index' (duration: 171.495µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:06:48.139691Z","caller":"traceutil/trace.go:171","msg":"trace[1219157773] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"776.032002ms","start":"2026-04-27T16:06:47.363615Z","end":"2026-04-27T16:06:48.139647Z","steps":["trace[1219157773] 'process raft request' (duration: 775.872687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:48.139807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.368585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T16:06:48.139833Z","caller":"traceutil/trace.go:171","msg":"trace[1884696029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1064; }","duration":"629.414216ms","start":"2026-04-27T16:06:47.51041Z","end":"2026-04-27T16:06:48.139824Z","steps":["trace[1884696029] 'agreement among raft nodes before linearized reading' (duration: 629.290963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:48.13983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:47.363585Z","time spent":"776.164586ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:06:48.139913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.301805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T16:06:48.139931Z","caller":"traceutil/trace.go:171","msg":"trace[566312159] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1064; }","duration":"624.358646ms","start":"2026-04-27T16:06:47.515567Z","end":"2026-04-27T16:06:48.139925Z","steps":["trace[566312159] 'agreement among raft nodes before linearized reading' (duration: 624.306265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:48.139946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:47.515544Z","time spent":"624.398387ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T16:06:48.140051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.239159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-27T16:06:48.140075Z","caller":"traceutil/trace.go:171","msg":"trace[1721088596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1064; }","duration":"609.28572ms","start":"2026-04-27T16:06:47.530783Z","end":"2026-04-27T16:06:48.140069Z","steps":["trace[1721088596] 'agreement among raft nodes before linearized reading' (duration: 609.215038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:48.140143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:47.530771Z","time spent":"609.363882ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T16:06:48.140134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.460969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-27T16:06:48.1402Z","caller":"traceutil/trace.go:171","msg":"trace[33217930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1064; }","duration":"608.605552ms","start":"2026-04-27T16:06:47.531574Z","end":"2026-04-27T16:06:48.14018Z","steps":["trace[33217930] 'agreement among raft nodes before linearized reading' (duration: 608.341786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:48.140267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.630777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-27T16:06:48.140297Z","caller":"traceutil/trace.go:171","msg":"trace[318077406] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1064; }","duration":"623.684578ms","start":"2026-04-27T16:06:47.516605Z","end":"2026-04-27T16:06:48.140289Z","steps":["trace[318077406] 'agreement among raft nodes before linearized reading' (duration: 623.610156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:48.140323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:47.516591Z","time spent":"623.725031ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T16:06:48.139854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:47.510277Z","time spent":"629.571619ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T16:06:48.140329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.038786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:06:48.14026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:47.53155Z","time spent":"608.696755ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T16:06:48.140375Z","caller":"traceutil/trace.go:171","msg":"trace[1801007216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1064; }","duration":"358.118118ms","start":"2026-04-27T16:06:47.782243Z","end":"2026-04-27T16:06:48.140361Z","steps":["trace[1801007216] 'agreement among raft nodes before linearized reading' (duration: 358.055465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:48.140414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:47.782226Z","time spent":"358.178209ms","remote":"127.0.0.1:41716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:06:48.46446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.836923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:06:48.464677Z","caller":"traceutil/trace.go:171","msg":"trace[667930253] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"318.902111ms","start":"2026-04-27T16:06:48.145757Z","end":"2026-04-27T16:06:48.464659Z","steps":["trace[667930253] 'process raft request' (duration: 318.813749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:48.464751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:48.145732Z","time spent":"318.984593ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:06:48.464719Z","caller":"traceutil/trace.go:171","msg":"trace[1906377681] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"318.921502ms","start":"2026-04-27T16:06:48.145763Z","end":"2026-04-27T16:06:48.464685Z","steps":["trace[1906377681] 'process raft request' (duration: 50.465324ms)","trace[1906377681] 'compare' (duration: 267.557535ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:06:48.465012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:48.145737Z","time spent":"319.211198ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:06:48.70045Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410015590375677,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T16:06:48.759379Z","caller":"traceutil/trace.go:171","msg":"trace[1136745685] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"613.290563ms","start":"2026-04-27T16:06:48.145998Z","end":"2026-04-27T16:06:48.759289Z","steps":["trace[1136745685] 'process raft request' (duration: 575.499935ms)","trace[1136745685] 'compare' (duration: 37.135791ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:06:48.759716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:48.145977Z","time spent":"613.61289ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:06:48.759602Z","caller":"traceutil/trace.go:171","msg":"trace[965939877] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"613.221271ms","start":"2026-04-27T16:06:48.146357Z","end":"2026-04-27T16:06:48.759578Z","steps":["trace[965939877] 'process raft request' (duration: 612.858712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:48.759891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:48.146347Z","time spent":"613.455777ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:06:48.759997Z","caller":"traceutil/trace.go:171","msg":"trace[35586326] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1107; }","duration":"560.49958ms","start":"2026-04-27T16:06:48.199474Z","end":"2026-04-27T16:06:48.759973Z","steps":["trace[35586326] 'read index received' (duration: 521.965373ms)","trace[35586326] 'applied index is now lower than readState.Index' (duration: 38.533047ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:06:48.760159Z","caller":"traceutil/trace.go:171","msg":"trace[111657149] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"613.718184ms","start":"2026-04-27T16:06:48.146417Z","end":"2026-04-27T16:06:48.760135Z","steps":["trace[111657149] 'process raft request' (duration: 613.107808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:48.760184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.690185ms","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-04-27T16:06:48.760218Z","caller":"traceutil/trace.go:171","msg":"trace[780299420] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1069; }","duration":"560.770337ms","start":"2026-04-27T16:06:48.199438Z","end":"2026-04-27T16:06:48.760209Z","steps":["trace[780299420] 'agreement among raft nodes before linearized reading' (duration: 560.638614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:48.760254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:48.199422Z","time spent":"560.823078ms","remote":"127.0.0.1:46388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:06:48.760265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:48.146386Z","time spent":"613.829017ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:06:50.734861Z","caller":"traceutil/trace.go:171","msg":"trace[957063350] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1112; }","duration":"265.524153ms","start":"2026-04-27T16:06:50.469301Z","end":"2026-04-27T16:06:50.734825Z","steps":["trace[957063350] 'read index received' (duration: 265.280267ms)","trace[957063350] 'applied index is now lower than readState.Index' (duration: 242.506µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:06:50.735126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.80389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T16:06:50.735156Z","caller":"traceutil/trace.go:171","msg":"trace[1199717071] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1071; }","duration":"265.899223ms","start":"2026-04-27T16:06:50.469249Z","end":"2026-04-27T16:06:50.735148Z","steps":["trace[1199717071] 'agreement among raft nodes before linearized reading' (duration: 265.745739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:50.735161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.818831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-27T16:06:50.735195Z","caller":"traceutil/trace.go:171","msg":"trace[1773948678] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1071; }","duration":"265.898813ms","start":"2026-04-27T16:06:50.469287Z","end":"2026-04-27T16:06:50.735185Z","steps":["trace[1773948678] 'agreement among raft nodes before linearized reading' (duration: 265.77576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:50.735238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.364936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:06:50.735221Z","caller":"traceutil/trace.go:171","msg":"trace[1752011228] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"563.662041ms","start":"2026-04-27T16:06:50.171496Z","end":"2026-04-27T16:06:50.735158Z","steps":["trace[1752011228] 'process raft request' (duration: 563.130077ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:50.735316Z","caller":"traceutil/trace.go:171","msg":"trace[1085782277] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1071; }","duration":"131.504409ms","start":"2026-04-27T16:06:50.603793Z","end":"2026-04-27T16:06:50.735298Z","steps":["trace[1085782277] 'agreement among raft nodes before linearized reading' (duration: 131.387587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:50.73554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:50.171463Z","time spent":"563.904767ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:06:50.916762Z","caller":"traceutil/trace.go:171","msg":"trace[1050053121] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"177.995849ms","start":"2026-04-27T16:06:50.738737Z","end":"2026-04-27T16:06:50.916732Z","steps":["trace[1050053121] 'process raft request' (duration: 175.317501ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:50.950172Z","caller":"traceutil/trace.go:171","msg":"trace[170603702] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1113; }","duration":"185.889762ms","start":"2026-04-27T16:06:50.764256Z","end":"2026-04-27T16:06:50.950145Z","steps":["trace[170603702] 'read index received' (duration: 149.795807ms)","trace[170603702] 'applied index is now lower than readState.Index' (duration: 36.092495ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:06:50.950472Z","caller":"traceutil/trace.go:171","msg":"trace[24684160] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"211.328484ms","start":"2026-04-27T16:06:50.738957Z","end":"2026-04-27T16:06:50.950286Z","steps":["trace[24684160] 'process raft request' (duration: 211.048508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:50.950585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.927854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T16:06:50.950697Z","caller":"traceutil/trace.go:171","msg":"trace[836948276] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1073; }","duration":"186.014206ms","start":"2026-04-27T16:06:50.76461Z","end":"2026-04-27T16:06:50.950624Z","steps":["trace[836948276] 'agreement among raft nodes before linearized reading' (duration: 185.84526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:50.950712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.202501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-27T16:06:50.950836Z","caller":"traceutil/trace.go:171","msg":"trace[1416556505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1073; }","duration":"186.443166ms","start":"2026-04-27T16:06:50.764362Z","end":"2026-04-27T16:06:50.950806Z","steps":["trace[1416556505] 'agreement among raft nodes before linearized reading' (duration: 186.018955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:50.951239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.978491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-27T16:06:50.951284Z","caller":"traceutil/trace.go:171","msg":"trace[465822899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1073; }","duration":"187.060703ms","start":"2026-04-27T16:06:50.764211Z","end":"2026-04-27T16:06:50.951272Z","steps":["trace[465822899] 'agreement among raft nodes before linearized reading' (duration: 186.077507ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:53.158094Z","caller":"traceutil/trace.go:171","msg":"trace[1235158744] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"233.545264ms","start":"2026-04-27T16:06:52.924513Z","end":"2026-04-27T16:06:53.158059Z","steps":["trace[1235158744] 'process raft request' (duration: 233.322657ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:53.158172Z","caller":"traceutil/trace.go:171","msg":"trace[350648327] linearizableReadLoop","detail":"{readStateIndex:1120; appliedIndex:1119; }","duration":"201.977905ms","start":"2026-04-27T16:06:52.956173Z","end":"2026-04-27T16:06:53.158151Z","steps":["trace[350648327] 'read index received' (duration: 201.595595ms)","trace[350648327] 'applied index is now lower than readState.Index' (duration: 380.32µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:06:53.158499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.406526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-27T16:06:53.158574Z","caller":"traceutil/trace.go:171","msg":"trace[260656497] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1078; }","duration":"202.531089ms","start":"2026-04-27T16:06:52.956026Z","end":"2026-04-27T16:06:53.158557Z","steps":["trace[260656497] 'agreement among raft nodes before linearized reading' (duration: 202.329274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:53.158619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.312606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-27T16:06:53.158677Z","caller":"traceutil/trace.go:171","msg":"trace[1098225356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1078; }","duration":"156.395957ms","start":"2026-04-27T16:06:53.002267Z","end":"2026-04-27T16:06:53.158663Z","steps":["trace[1098225356] 'agreement among raft nodes before linearized reading' (duration: 156.255053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:53.158668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.070928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T16:06:53.15903Z","caller":"traceutil/trace.go:171","msg":"trace[1865034264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1078; }","duration":"179.503468ms","start":"2026-04-27T16:06:52.979507Z","end":"2026-04-27T16:06:53.15901Z","steps":["trace[1865034264] 'agreement among raft nodes before linearized reading' (duration: 178.922163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:53.158892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.984132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-27T16:06:53.159471Z","caller":"traceutil/trace.go:171","msg":"trace[66040483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1078; }","duration":"157.599218ms","start":"2026-04-27T16:06:53.001857Z","end":"2026-04-27T16:06:53.159456Z","steps":["trace[66040483] 'agreement among raft nodes before linearized reading' (duration: 156.92999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:05.803027Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410015590375911,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T16:07:06.896859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.842354ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:07:06.896954Z","caller":"traceutil/trace.go:171","msg":"trace[870757185] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1168; }","duration":"792.950146ms","start":"2026-04-27T16:07:06.103991Z","end":"2026-04-27T16:07:06.896941Z","steps":["trace[870757185] 'range keys from in-memory index tree' (duration: 792.830644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:06.897006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.688070219s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-27T16:07:06.897029Z","caller":"traceutil/trace.go:171","msg":"trace[1092506449] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1168; }","duration":"1.68812132s","start":"2026-04-27T16:07:05.2089Z","end":"2026-04-27T16:07:06.897021Z","steps":["trace[1092506449] 'range keys from in-memory index tree' (duration: 1.687975756s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:06.897048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:05.208885Z","time spent":"1.688158341s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T16:07:06.897321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.036389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:07:06.897464Z","caller":"traceutil/trace.go:171","msg":"trace[2069941642] linearizableReadLoop","detail":"{readStateIndex:1216; appliedIndex:1215; }","duration":"1.595464026s","start":"2026-04-27T16:07:05.301977Z","end":"2026-04-27T16:07:06.897441Z","steps":["trace[2069941642] 'read index received' (duration: 775.054617ms)","trace[2069941642] 'applied index is now lower than readState.Index' (duration: 820.407369ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:07:06.897552Z","caller":"traceutil/trace.go:171","msg":"trace[1333722492] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"1.605651388s","start":"2026-04-27T16:07:05.291886Z","end":"2026-04-27T16:07:06.897537Z","steps":["trace[1333722492] 'process raft request' (duration: 785.231878ms)","trace[1333722492] 'compare' (duration: 819.66369ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:07:06.897617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:05.291858Z","time spent":"1.60572445s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:07:06.897996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.596017291s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T16:07:06.898038Z","caller":"traceutil/trace.go:171","msg":"trace[1783728756] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1169; }","duration":"1.596093733s","start":"2026-04-27T16:07:05.301933Z","end":"2026-04-27T16:07:06.898027Z","steps":["trace[1783728756] 'agreement among raft nodes before linearized reading' (duration: 1.595908057s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:06.89807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:05.301917Z","time spent":"1.596146414s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T16:07:07.165574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.430381686s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T16:07:07.165624Z","caller":"traceutil/trace.go:171","msg":"trace[561162339] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1170; }","duration":"1.430479239s","start":"2026-04-27T16:07:05.735134Z","end":"2026-04-27T16:07:07.165613Z","steps":["trace[561162339] 'agreement among raft nodes before linearized reading' (duration: 1.430357926s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:07.165625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.851003733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-27T16:07:07.165653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:05.73512Z","time spent":"1.43052598s","remote":"127.0.0.1:42020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:07:07.165642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.847865413s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-27T16:07:07.165666Z","caller":"traceutil/trace.go:171","msg":"trace[1181909626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1170; }","duration":"1.851061615s","start":"2026-04-27T16:07:05.314594Z","end":"2026-04-27T16:07:07.165655Z","steps":["trace[1181909626] 'agreement among raft nodes before linearized reading' (duration: 1.850949792s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:07.165697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:05.314586Z","time spent":"1.851103636s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T16:07:07.16569Z","caller":"traceutil/trace.go:171","msg":"trace[2096525832] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"344.948398ms","start":"2026-04-27T16:07:06.820729Z","end":"2026-04-27T16:07:07.165678Z","steps":["trace[2096525832] 'process raft request' (duration: 344.538357ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:07:07.165712Z","caller":"traceutil/trace.go:171","msg":"trace[1819429249] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1170; }","duration":"1.847941616s","start":"2026-04-27T16:07:05.317744Z","end":"2026-04-27T16:07:07.165686Z","steps":["trace[1819429249] 'agreement among raft nodes before linearized reading' (duration: 1.847775152s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:07.165707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.453488459s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:07:07.165763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:06.820688Z","time spent":"345.027539ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:07:07.165756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:05.317737Z","time spent":"1.848007477s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-27T16:07:07.16577Z","caller":"traceutil/trace.go:171","msg":"trace[988005318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1170; }","duration":"1.453811967s","start":"2026-04-27T16:07:05.71194Z","end":"2026-04-27T16:07:07.165752Z","steps":["trace[988005318] 'agreement among raft nodes before linearized reading' (duration: 1.453695825s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:07.16581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:05.711908Z","time spent":"1.453891309s","remote":"127.0.0.1:41716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T16:07:07.165456Z","caller":"traceutil/trace.go:171","msg":"trace[788779611] linearizableReadLoop","detail":"{readStateIndex:1217; appliedIndex:1216; }","duration":"267.936855ms","start":"2026-04-27T16:07:06.897495Z","end":"2026-04-27T16:07:07.165432Z","steps":["trace[788779611] 'read index received' (duration: 267.683427ms)","trace[788779611] 'applied index is now lower than readState.Index' (duration: 252.408µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:07:07.166137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.85866526s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-27T16:07:07.166175Z","caller":"traceutil/trace.go:171","msg":"trace[213155891] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"264.443895ms","start":"2026-04-27T16:07:06.901719Z","end":"2026-04-27T16:07:07.166163Z","steps":["trace[213155891] 'process raft request' (duration: 264.412114ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:07:07.166189Z","caller":"traceutil/trace.go:171","msg":"trace[1841473241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1170; }","duration":"1.858741632s","start":"2026-04-27T16:07:05.307434Z","end":"2026-04-27T16:07:07.166175Z","steps":["trace[1841473241] 'agreement among raft nodes before linearized reading' (duration: 1.858601607s)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:07:07.166199Z","caller":"traceutil/trace.go:171","msg":"trace[424453581] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"265.569795ms","start":"2026-04-27T16:07:06.900613Z","end":"2026-04-27T16:07:07.166183Z","steps":["trace[424453581] 'process raft request' (duration: 265.458032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:07.166232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:05.30742Z","time spent":"1.858798143s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T16:07:23.558393Z","caller":"traceutil/trace.go:171","msg":"trace[1312460514] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"326.911296ms","start":"2026-04-27T16:07:23.231455Z","end":"2026-04-27T16:07:23.558366Z","steps":["trace[1312460514] 'process raft request' (duration: 326.733342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:23.5587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:23.231419Z","time spent":"327.168301ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:07:24.29203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.892694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:07:24.292154Z","caller":"traceutil/trace.go:171","msg":"trace[1479694244] linearizableReadLoop","detail":"{readStateIndex:1274; appliedIndex:1272; }","duration":"1.020009523s","start":"2026-04-27T16:07:23.272132Z","end":"2026-04-27T16:07:24.292142Z","steps":["trace[1479694244] 'read index received' (duration: 286.102911ms)","trace[1479694244] 'applied index is now lower than readState.Index' (duration: 733.905052ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:07:24.292218Z","caller":"traceutil/trace.go:171","msg":"trace[74224020] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"1.03198182s","start":"2026-04-27T16:07:23.260226Z","end":"2026-04-27T16:07:24.292208Z","steps":["trace[74224020] 'process raft request' (duration: 409.84728ms)","trace[74224020] 'compare' (duration: 621.7121ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:07:24.292258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:23.260195Z","time spent":"1.032045872s","remote":"127.0.0.1:41766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:07:24.292373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.145315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-27T16:07:24.292424Z","caller":"traceutil/trace.go:171","msg":"trace[1595500305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1224; }","duration":"979.238438ms","start":"2026-04-27T16:07:23.313174Z","end":"2026-04-27T16:07:24.292412Z","steps":["trace[1595500305] 'agreement among raft nodes before linearized reading' (duration: 979.045933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:24.292455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:23.31315Z","time spent":"979.29795ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T16:07:24.2925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.568774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-27T16:07:24.292527Z","caller":"traceutil/trace.go:171","msg":"trace[911040281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1224; }","duration":"925.617995ms","start":"2026-04-27T16:07:23.366903Z","end":"2026-04-27T16:07:24.292521Z","steps":["trace[911040281] 'agreement among raft nodes before linearized reading' (duration: 925.546463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:24.292546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:23.366888Z","time spent":"925.652936ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T16:07:24.292533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020385713s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-27T16:07:24.29263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.856414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:07:24.292624Z","caller":"traceutil/trace.go:171","msg":"trace[67705620] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1224; }","duration":"1.020525746s","start":"2026-04-27T16:07:23.272078Z","end":"2026-04-27T16:07:24.292603Z","steps":["trace[67705620] 'agreement among raft nodes before linearized reading' (duration: 1.020344531s)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:07:24.292655Z","caller":"traceutil/trace.go:171","msg":"trace[729662987] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1224; }","duration":"913.923585ms","start":"2026-04-27T16:07:23.378724Z","end":"2026-04-27T16:07:24.292648Z","steps":["trace[729662987] 'agreement among raft nodes before linearized reading' (duration: 913.885185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:24.292657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.531917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-27T16:07:24.292674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:23.378713Z","time spent":"913.956467ms","remote":"127.0.0.1:41892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-04-27T16:07:24.292686Z","caller":"traceutil/trace.go:171","msg":"trace[1826669886] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1224; }","duration":"965.587948ms","start":"2026-04-27T16:07:23.327089Z","end":"2026-04-27T16:07:24.292677Z","steps":["trace[1826669886] 'agreement among raft nodes before linearized reading' (duration: 965.500886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:24.292673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.765746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-27T16:07:24.292721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:23.327063Z","time spent":"965.65151ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T16:07:24.292681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:23.27206Z","time spent":"1.020607728s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T16:07:24.292538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.177674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-04-27T16:07:24.292775Z","caller":"traceutil/trace.go:171","msg":"trace[875863880] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1224; }","duration":"928.896408ms","start":"2026-04-27T16:07:23.363825Z","end":"2026-04-27T16:07:24.292722Z","steps":["trace[875863880] 'agreement among raft nodes before linearized reading' (duration: 928.661123ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:07:24.292823Z","caller":"traceutil/trace.go:171","msg":"trace[731526509] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1224; }","duration":"861.495792ms","start":"2026-04-27T16:07:23.431307Z","end":"2026-04-27T16:07:24.292802Z","steps":["trace[731526509] 'agreement among raft nodes before linearized reading' (duration: 860.932288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:24.29287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:23.363801Z","time spent":"929.013431ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T16:07:24.292883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:23.431285Z","time spent":"861.585054ms","remote":"127.0.0.1:41866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5353,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-27T16:07:24.292938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.062017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:07:24.293055Z","caller":"traceutil/trace.go:171","msg":"trace[489189688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1224; }","duration":"581.320753ms","start":"2026-04-27T16:07:23.711717Z","end":"2026-04-27T16:07:24.293038Z","steps":["trace[489189688] 'agreement among raft nodes before linearized reading' (duration: 580.675406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:24.293165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:23.71169Z","time spent":"581.458097ms","remote":"127.0.0.1:41716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T16:07:24.571199Z","caller":"traceutil/trace.go:171","msg":"trace[528585019] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1282; }","duration":"193.032566ms","start":"2026-04-27T16:07:24.378149Z","end":"2026-04-27T16:07:24.571181Z","steps":["trace[528585019] 'read index received' (duration: 192.861241ms)","trace[528585019] 'applied index is now lower than readState.Index' (duration: 170.605µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:07:24.571289Z","caller":"traceutil/trace.go:171","msg":"trace[1127917031] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"210.049851ms","start":"2026-04-27T16:07:24.36123Z","end":"2026-04-27T16:07:24.57128Z","steps":["trace[1127917031] 'process raft request' (duration: 209.838215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:24.571724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.556968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:07:24.571723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.757468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-27T16:07:24.571761Z","caller":"traceutil/trace.go:171","msg":"trace[1736348486] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1232; }","duration":"193.644691ms","start":"2026-04-27T16:07:24.378108Z","end":"2026-04-27T16:07:24.571753Z","steps":["trace[1736348486] 'agreement among raft nodes before linearized reading' (duration: 193.569749ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:07:24.571797Z","caller":"traceutil/trace.go:171","msg":"trace[1511519384] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1232; }","duration":"192.86223ms","start":"2026-04-27T16:07:24.378916Z","end":"2026-04-27T16:07:24.571778Z","steps":["trace[1511519384] 'agreement among raft nodes before linearized reading' (duration: 192.671136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:25.867856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.468644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:07:25.867948Z","caller":"traceutil/trace.go:171","msg":"trace[1377651760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1234; }","duration":"155.608057ms","start":"2026-04-27T16:07:25.712318Z","end":"2026-04-27T16:07:25.867926Z","steps":["trace[1377651760] 'range keys from in-memory index tree' (duration: 155.375631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:40.952994Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831410015590376284,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T16:07:41.243067Z","caller":"traceutil/trace.go:171","msg":"trace[1617794020] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"790.608244ms","start":"2026-04-27T16:07:40.452423Z","end":"2026-04-27T16:07:41.243031Z","steps":["trace[1617794020] 'process raft request' (duration: 790.260246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:41.243281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:40.452404Z","time spent":"790.772379ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:07:41.495357Z","caller":"traceutil/trace.go:171","msg":"trace[796599703] linearizableReadLoop","detail":"{readStateIndex:1402; appliedIndex:1399; }","duration":"1.042625728s","start":"2026-04-27T16:07:40.452646Z","end":"2026-04-27T16:07:41.495272Z","steps":["trace[796599703] 'read index received' (duration: 790.033129ms)","trace[796599703] 'applied index is now lower than readState.Index' (duration: 252.591249ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:07:41.495672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042995917s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-27T16:07:41.495716Z","caller":"traceutil/trace.go:171","msg":"trace[652809380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1348; }","duration":"1.04307309s","start":"2026-04-27T16:07:40.452631Z","end":"2026-04-27T16:07:41.495704Z","steps":["trace[652809380] 'agreement among raft nodes before linearized reading' (duration: 1.042857634s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:41.495759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:40.452625Z","time spent":"1.043123991s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T16:07:41.495694Z","caller":"traceutil/trace.go:171","msg":"trace[1726088757] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"1.038624065s","start":"2026-04-27T16:07:40.456873Z","end":"2026-04-27T16:07:41.495497Z","steps":["trace[1726088757] 'process raft request' (duration: 1.037975048s)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:07:41.495048Z","caller":"traceutil/trace.go:171","msg":"trace[206534438] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"1.039260841s","start":"2026-04-27T16:07:40.45575Z","end":"2026-04-27T16:07:41.495011Z","steps":["trace[206534438] 'process raft request' (duration: 1.038795939s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:41.496024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.118352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-27T16:07:41.496107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.959165ms","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:530"} {"level":"warn","ts":"2026-04-27T16:07:41.496068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.416692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-27T16:07:41.49618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:40.455734Z","time spent":"1.040292428s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:07:41.496198Z","caller":"traceutil/trace.go:171","msg":"trace[28674002] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1348; }","duration":"829.269556ms","start":"2026-04-27T16:07:40.666802Z","end":"2026-04-27T16:07:41.496072Z","steps":["trace[28674002] 'agreement among raft nodes before linearized reading' (duration: 829.120032ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:07:41.496213Z","caller":"traceutil/trace.go:171","msg":"trace[885889422] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1348; }","duration":"826.598286ms","start":"2026-04-27T16:07:40.66958Z","end":"2026-04-27T16:07:41.496178Z","steps":["trace[885889422] 'agreement among raft nodes before linearized reading' (duration: 826.32026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:41.49618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:40.456839Z","time spent":"1.039085347s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:07:41.496278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:40.669572Z","time spent":"826.693019ms","remote":"127.0.0.1:42170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:07:41.496321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038588603s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-27T16:07:41.496145Z","caller":"traceutil/trace.go:171","msg":"trace[254908532] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1348; }","duration":"397.118559ms","start":"2026-04-27T16:07:41.099018Z","end":"2026-04-27T16:07:41.496137Z","steps":["trace[254908532] 'agreement among raft nodes before linearized reading' (duration: 396.951155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:41.4964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:41.098993Z","time spent":"397.399506ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-27T16:07:41.496256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:40.666767Z","time spent":"829.478351ms","remote":"127.0.0.1:41954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-04-27T16:07:41.496351Z","caller":"traceutil/trace.go:171","msg":"trace[1070902589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1348; }","duration":"1.038670215s","start":"2026-04-27T16:07:40.457673Z","end":"2026-04-27T16:07:41.496343Z","steps":["trace[1070902589] 'agreement among raft nodes before linearized reading' (duration: 1.0381028s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:41.496525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:40.457663Z","time spent":"1.03885732s","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T16:08:43.428146Z","caller":"traceutil/trace.go:171","msg":"trace[1002483083] transaction","detail":"{read_only:false; response_revision:1632; number_of_response:1; }","duration":"103.126248ms","start":"2026-04-27T16:08:43.324944Z","end":"2026-04-27T16:08:43.428071Z","steps":["trace[1002483083] 'process raft request' (duration: 102.966234ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:43.672379Z","caller":"traceutil/trace.go:171","msg":"trace[667937504] transaction","detail":"{read_only:false; response_revision:1633; number_of_response:1; }","duration":"117.526605ms","start":"2026-04-27T16:08:43.554826Z","end":"2026-04-27T16:08:43.672353Z","steps":["trace[667937504] 'process raft request' (duration: 117.291509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:46.275862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.218475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T16:08:46.275921Z","caller":"traceutil/trace.go:171","msg":"trace[1834448507] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1642; }","duration":"464.317998ms","start":"2026-04-27T16:08:45.811587Z","end":"2026-04-27T16:08:46.275905Z","steps":["trace[1834448507] 'range keys from in-memory index tree' (duration: 464.084422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:46.275943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:45.811565Z","time spent":"464.372309ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T16:08:46.275939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.641351ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:08:46.27606Z","caller":"traceutil/trace.go:171","msg":"trace[1539649043] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1642; }","duration":"171.774074ms","start":"2026-04-27T16:08:46.104269Z","end":"2026-04-27T16:08:46.276043Z","steps":["trace[1539649043] 'range keys from in-memory index tree' (duration: 171.624931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:46.27615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.289625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-27T16:08:46.276177Z","caller":"traceutil/trace.go:171","msg":"trace[85145807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1642; }","duration":"441.321245ms","start":"2026-04-27T16:08:45.834847Z","end":"2026-04-27T16:08:46.276168Z","steps":["trace[85145807] 'range keys from in-memory index tree' (duration: 441.236484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:46.276194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:45.834844Z","time spent":"441.346196ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T16:08:46.276294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.49046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-27T16:08:46.276308Z","caller":"traceutil/trace.go:171","msg":"trace[1837756883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1642; }","duration":"441.533022ms","start":"2026-04-27T16:08:45.834771Z","end":"2026-04-27T16:08:46.276304Z","steps":["trace[1837756883] 'range keys from in-memory index tree' (duration: 441.372747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:46.276322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:45.834754Z","time spent":"441.563523ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T16:08:46.276401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.10702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-27T16:08:46.276414Z","caller":"traceutil/trace.go:171","msg":"trace[1943869036] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1642; }","duration":"441.127731ms","start":"2026-04-27T16:08:45.835281Z","end":"2026-04-27T16:08:46.276408Z","steps":["trace[1943869036] 'range keys from in-memory index tree' (duration: 441.06725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:46.276426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:45.835274Z","time spent":"441.149402ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-27T16:08:46.276505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.644935ms","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-04-27T16:08:46.276519Z","caller":"traceutil/trace.go:171","msg":"trace[499311875] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1642; }","duration":"389.690746ms","start":"2026-04-27T16:08:45.886823Z","end":"2026-04-27T16:08:46.276514Z","steps":["trace[499311875] 'count revisions from in-memory index tree' (duration: 389.579323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:46.276534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:45.8868Z","time spent":"389.728816ms","remote":"127.0.0.1:46522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-27T16:08:46.27657Z","caller":"traceutil/trace.go:171","msg":"trace[1156359030] linearizableReadLoop","detail":"{readStateIndex:1710; appliedIndex:1709; }","duration":"380.660499ms","start":"2026-04-27T16:08:45.895897Z","end":"2026-04-27T16:08:46.276558Z","steps":["trace[1156359030] 'read index received' (duration: 336.539095ms)","trace[1156359030] 'applied index is now lower than readState.Index' (duration: 44.120224ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:08:46.276707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.810654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-27T16:08:46.27673Z","caller":"traceutil/trace.go:171","msg":"trace[709701489] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1643; }","duration":"380.877215ms","start":"2026-04-27T16:08:45.895845Z","end":"2026-04-27T16:08:46.276722Z","steps":["trace[709701489] 'agreement among raft nodes before linearized reading' (duration: 380.753332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:46.276764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:45.895793Z","time spent":"380.963587ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-04-27T16:08:46.276814Z","caller":"traceutil/trace.go:171","msg":"trace[1460494394] transaction","detail":"{read_only:false; response_revision:1643; number_of_response:1; }","duration":"382.686421ms","start":"2026-04-27T16:08:45.89412Z","end":"2026-04-27T16:08:46.276806Z","steps":["trace[1460494394] 'process raft request' (duration: 338.455204ms)","trace[1460494394] 'compare' (duration: 43.773296ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:08:46.276867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:45.894097Z","time spent":"382.735753ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:08:46.485108Z","caller":"traceutil/trace.go:171","msg":"trace[1897723772] transaction","detail":"{read_only:false; response_revision:1648; number_of_response:1; }","duration":"202.000806ms","start":"2026-04-27T16:08:46.283084Z","end":"2026-04-27T16:08:46.485085Z","steps":["trace[1897723772] 'process raft request' (duration: 201.958725ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:46.485277Z","caller":"traceutil/trace.go:171","msg":"trace[1356380645] transaction","detail":"{read_only:false; response_revision:1645; number_of_response:1; }","duration":"203.537825ms","start":"2026-04-27T16:08:46.281603Z","end":"2026-04-27T16:08:46.485141Z","steps":["trace[1356380645] 'process raft request' (duration: 156.388091ms)","trace[1356380645] 'compare' (duration: 46.848206ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:08:46.485486Z","caller":"traceutil/trace.go:171","msg":"trace[1935831653] transaction","detail":"{read_only:false; response_revision:1646; number_of_response:1; }","duration":"203.810673ms","start":"2026-04-27T16:08:46.281638Z","end":"2026-04-27T16:08:46.485448Z","steps":["trace[1935831653] 'process raft request' (duration: 203.323431ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:46.485504Z","caller":"traceutil/trace.go:171","msg":"trace[2119951239] transaction","detail":"{read_only:false; response_revision:1647; number_of_response:1; }","duration":"203.017762ms","start":"2026-04-27T16:08:46.282438Z","end":"2026-04-27T16:08:46.485456Z","steps":["trace[2119951239] 'process raft request' (duration: 202.569701ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:54.539697Z","caller":"traceutil/trace.go:171","msg":"trace[511482210] transaction","detail":"{read_only:false; response_revision:1682; number_of_response:1; }","duration":"130.275008ms","start":"2026-04-27T16:08:54.409389Z","end":"2026-04-27T16:08:54.539664Z","steps":["trace[511482210] 'process raft request' (duration: 130.082583ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:54.674718Z","caller":"traceutil/trace.go:171","msg":"trace[1073586621] transaction","detail":"{read_only:false; response_revision:1683; number_of_response:1; }","duration":"130.355961ms","start":"2026-04-27T16:08:54.544331Z","end":"2026-04-27T16:08:54.674687Z","steps":["trace[1073586621] 'process raft request' (duration: 64.158029ms)","trace[1073586621] 'compare' (duration: 65.966076ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:08:54.675078Z","caller":"traceutil/trace.go:171","msg":"trace[835808806] transaction","detail":"{read_only:false; response_revision:1686; number_of_response:1; }","duration":"130.171736ms","start":"2026-04-27T16:08:54.544872Z","end":"2026-04-27T16:08:54.675043Z","steps":["trace[835808806] 'process raft request' (duration: 130.126885ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:54.675139Z","caller":"traceutil/trace.go:171","msg":"trace[1583752384] transaction","detail":"{read_only:false; response_revision:1684; number_of_response:1; }","duration":"130.789542ms","start":"2026-04-27T16:08:54.544321Z","end":"2026-04-27T16:08:54.675111Z","steps":["trace[1583752384] 'process raft request' (duration: 130.455624ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:54.675214Z","caller":"traceutil/trace.go:171","msg":"trace[2035387640] transaction","detail":"{read_only:false; response_revision:1685; number_of_response:1; }","duration":"130.844923ms","start":"2026-04-27T16:08:54.544353Z","end":"2026-04-27T16:08:54.675198Z","steps":["trace[2035387640] 'process raft request' (duration: 130.609137ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:35.003059Z","caller":"traceutil/trace.go:171","msg":"trace[2024789876] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"116.018026ms","start":"2026-04-27T16:09:34.887013Z","end":"2026-04-27T16:09:35.003031Z","steps":["trace[2024789876] 'process raft request' (duration: 115.860351ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:35.038722Z","caller":"traceutil/trace.go:171","msg":"trace[347683628] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"149.05064ms","start":"2026-04-27T16:09:34.889652Z","end":"2026-04-27T16:09:35.038703Z","steps":["trace[347683628] 'process raft request' (duration: 149.029209ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:35.039007Z","caller":"traceutil/trace.go:171","msg":"trace[407185997] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"150.710293ms","start":"2026-04-27T16:09:34.888275Z","end":"2026-04-27T16:09:35.038986Z","steps":["trace[407185997] 'process raft request' (duration: 150.385975ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:35.039296Z","caller":"traceutil/trace.go:171","msg":"trace[270591924] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"151.661307ms","start":"2026-04-27T16:09:34.887619Z","end":"2026-04-27T16:09:35.03928Z","steps":["trace[270591924] 'process raft request' (duration: 150.936508ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:53.33897Z","caller":"traceutil/trace.go:171","msg":"trace[12181882] transaction","detail":"{read_only:false; response_revision:1951; number_of_response:1; }","duration":"130.544887ms","start":"2026-04-27T16:09:53.208405Z","end":"2026-04-27T16:09:53.33895Z","steps":["trace[12181882] 'process raft request' (duration: 130.391563ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:53.339121Z","caller":"traceutil/trace.go:171","msg":"trace[1079878871] linearizableReadLoop","detail":"{readStateIndex:2032; appliedIndex:2030; }","duration":"130.582149ms","start":"2026-04-27T16:09:53.208513Z","end":"2026-04-27T16:09:53.339095Z","steps":["trace[1079878871] 'read index received' (duration: 130.26893ms)","trace[1079878871] 'applied index is now lower than readState.Index' (duration: 311.049µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:09:53.339156Z","caller":"traceutil/trace.go:171","msg":"trace[114288913] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"130.696262ms","start":"2026-04-27T16:09:53.208427Z","end":"2026-04-27T16:09:53.339123Z","steps":["trace[114288913] 'process raft request' (duration: 130.488565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:53.339343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.813534ms","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:530"} {"level":"info","ts":"2026-04-27T16:09:53.339421Z","caller":"traceutil/trace.go:171","msg":"trace[1185794773] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1952; }","duration":"130.936287ms","start":"2026-04-27T16:09:53.208465Z","end":"2026-04-27T16:09:53.339402Z","steps":["trace[1185794773] 'agreement among raft nodes before linearized reading' (duration: 130.716112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:53.572673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.612263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:09:53.572875Z","caller":"traceutil/trace.go:171","msg":"trace[1820222604] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"227.682062ms","start":"2026-04-27T16:09:53.345143Z","end":"2026-04-27T16:09:53.572825Z","steps":["trace[1820222604] 'process raft request' (duration: 56.845854ms)","trace[1820222604] 'compare' (duration: 170.48944ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:10:57.168641Z","caller":"traceutil/trace.go:171","msg":"trace[1090648912] linearizableReadLoop","detail":"{readStateIndex:2361; appliedIndex:2360; }","duration":"147.298654ms","start":"2026-04-27T16:10:57.021322Z","end":"2026-04-27T16:10:57.168621Z","steps":["trace[1090648912] 'read index received' (duration: 147.112859ms)","trace[1090648912] 'applied index is now lower than readState.Index' (duration: 184.965µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:10:57.168748Z","caller":"traceutil/trace.go:171","msg":"trace[72961794] transaction","detail":"{read_only:false; response_revision:2267; number_of_response:1; }","duration":"245.6551ms","start":"2026-04-27T16:10:56.923062Z","end":"2026-04-27T16:10:57.168717Z","steps":["trace[72961794] 'process raft request' (duration: 245.432665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:10:57.168792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.463569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-04-27T16:10:57.16882Z","caller":"traceutil/trace.go:171","msg":"trace[45914751] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2267; }","duration":"147.528511ms","start":"2026-04-27T16:10:57.021282Z","end":"2026-04-27T16:10:57.16881Z","steps":["trace[45914751] 'agreement among raft nodes before linearized reading' (duration: 147.415527ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:11:07.286768Z","caller":"traceutil/trace.go:171","msg":"trace[4845460] transaction","detail":"{read_only:false; response_revision:2321; number_of_response:1; }","duration":"238.938317ms","start":"2026-04-27T16:11:07.047768Z","end":"2026-04-27T16:11:07.286706Z","steps":["trace[4845460] 'process raft request' (duration: 238.751931ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:11:12.967564Z","caller":"traceutil/trace.go:171","msg":"trace[3186445] transaction","detail":"{read_only:false; response_revision:2343; number_of_response:1; }","duration":"733.430074ms","start":"2026-04-27T16:11:12.234116Z","end":"2026-04-27T16:11:12.967546Z","steps":["trace[3186445] 'process raft request' (duration: 733.159637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:11:12.969784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:11:12.234103Z","time spent":"735.60338ms","remote":"127.0.0.1:41904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}