{"level":"warn","ts":"2026-04-10T04:09:08.216565Z","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-10T04:09:08.216673Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115: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.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115: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-10T04:09:08.216749Z","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-10T04:09:08.216758Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-04-10T04:09:08.216774Z","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-10T04:09:08.217513Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-04-10T04:09:08.217584Z","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.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115: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-10T04:09:08.225332Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.510554ms"} {"level":"info","ts":"2026-04-10T04:09:08.238301Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-04-10T04:09:08.238412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-04-10T04:09:08.238434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-04-10T04:09:08.238444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-10T04:09:08.238459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-04-10T04:09:08.238498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-04-10T04:09:08.256922Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-10T04:09:08.259403Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-10T04:09:08.262068Z","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-10T04:09:08.265595Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-10T04:09:08.266163Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-10T04:09:08.266168Z","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-10T04:09:08.266292Z","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-10T04:09:08.266316Z","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-10T04:09:08.26838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-04-10T04:09:08.268514Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-04-10T04:09:08.271985Z","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-10T04:09:08.272158Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-04-10T04:09:08.272201Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-04-10T04:09:08.273255Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-10T04:09:08.27329Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-10T04:09:09.139937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-04-10T04:09:09.140004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-10T04:09:09.140043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-04-10T04:09:09.140063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-04-10T04:09:09.140075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-04-10T04:09:09.140091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-04-10T04:09:09.140116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-04-10T04:09:09.141315Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T04:09:09.142149Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-04-10T04:09:09.142198Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-10T04:09:09.142675Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T04:09:09.1428Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T04:09:09.142849Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T04:09:09.142879Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-10T04:09:09.144578Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-10T04:09:09.144619Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-10T04:09:09.145787Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-04-10T04:09:09.146438Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-10T04:09:36.287479Z","caller":"traceutil/trace.go:171","msg":"trace[390325136] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"145.565237ms","start":"2026-04-10T04:09:36.141873Z","end":"2026-04-10T04:09:36.287439Z","steps":["trace[390325136] 'process raft request' (duration: 145.356571ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:36.976481Z","caller":"traceutil/trace.go:171","msg":"trace[2119314423] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:448; }","duration":"197.297032ms","start":"2026-04-10T04:09:36.779156Z","end":"2026-04-10T04:09:36.976453Z","steps":["trace[2119314423] 'read index received' (duration: 197.185659ms)","trace[2119314423] 'applied index is now lower than readState.Index' (duration: 110.143µs)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:09:36.976661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.481397ms","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-10T04:09:36.976891Z","caller":"traceutil/trace.go:171","msg":"trace[650435730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:430; }","duration":"197.746285ms","start":"2026-04-10T04:09:36.779125Z","end":"2026-04-10T04:09:36.976871Z","steps":["trace[650435730] 'agreement among raft nodes before linearized reading' (duration: 197.463247ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:36.976663Z","caller":"traceutil/trace.go:171","msg":"trace[1780828387] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"216.152539ms","start":"2026-04-10T04:09:36.760445Z","end":"2026-04-10T04:09:36.976597Z","steps":["trace[1780828387] 'process raft request' (duration: 215.869611ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:38.910635Z","caller":"traceutil/trace.go:171","msg":"trace[523817745] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"120.965993ms","start":"2026-04-10T04:09:38.789645Z","end":"2026-04-10T04:09:38.910611Z","steps":["trace[523817745] 'process raft request' (duration: 120.639634ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:39.178397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.138725ms","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-10T04:09:39.178515Z","caller":"traceutil/trace.go:171","msg":"trace[2131373133] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:435; }","duration":"100.27352ms","start":"2026-04-10T04:09:39.078224Z","end":"2026-04-10T04:09:39.178497Z","steps":["trace[2131373133] 'range keys from in-memory index tree' (duration: 100.121154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:39.179147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.842582ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-10T04:09:39.179472Z","caller":"traceutil/trace.go:171","msg":"trace[911598609] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"241.554499ms","start":"2026-04-10T04:09:38.937843Z","end":"2026-04-10T04:09:39.179398Z","steps":["trace[911598609] 'process raft request' (duration: 51.817502ms)","trace[911598609] 'compare' (duration: 188.641305ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:09:39.179546Z","caller":"traceutil/trace.go:171","msg":"trace[1607261781] linearizableReadLoop","detail":"{readStateIndex:456; appliedIndex:454; }","duration":"197.606664ms","start":"2026-04-10T04:09:38.981899Z","end":"2026-04-10T04:09:39.179505Z","steps":["trace[1607261781] 'read index received' (duration: 5.318843ms)","trace[1607261781] 'applied index is now lower than readState.Index' (duration: 192.284461ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:09:39.179864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.584963ms","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-10T04:09:39.179915Z","caller":"traceutil/trace.go:171","msg":"trace[558627155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:436; }","duration":"190.700177ms","start":"2026-04-10T04:09:38.989204Z","end":"2026-04-10T04:09:39.179904Z","steps":["trace[558627155] 'agreement among raft nodes before linearized reading' (duration: 190.49183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:39.180812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.906252ms","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-10T04:09:39.180874Z","caller":"traceutil/trace.go:171","msg":"trace[196955343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:436; }","duration":"199.010166ms","start":"2026-04-10T04:09:38.981845Z","end":"2026-04-10T04:09:39.180855Z","steps":["trace[196955343] 'agreement among raft nodes before linearized reading' (duration: 197.757289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:39.649118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.840365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T04:09:39.64935Z","caller":"traceutil/trace.go:171","msg":"trace[967039418] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"462.584425ms","start":"2026-04-10T04:09:39.186746Z","end":"2026-04-10T04:09:39.649331Z","steps":["trace[967039418] 'process raft request' (duration: 462.529994ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:39.649362Z","caller":"traceutil/trace.go:171","msg":"trace[1296489303] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"462.854563ms","start":"2026-04-10T04:09:39.186481Z","end":"2026-04-10T04:09:39.649336Z","steps":["trace[1296489303] 'process raft request' (duration: 462.73999ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:39.649387Z","caller":"traceutil/trace.go:171","msg":"trace[929731556] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"463.388729ms","start":"2026-04-10T04:09:39.185949Z","end":"2026-04-10T04:09:39.649337Z","steps":["trace[929731556] 'process raft request' (duration: 336.090589ms)","trace[929731556] 'compare' (duration: 126.687061ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:09:39.649437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:39.186728Z","time spent":"462.672348ms","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:09:39.649523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:39.18643Z","time spent":"463.017438ms","remote":"127.0.0.1:60710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6516,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T04:09:39.649598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:39.185924Z","time spent":"463.560154ms","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:09:39.844756Z","caller":"traceutil/trace.go:171","msg":"trace[1680841507] linearizableReadLoop","detail":"{readStateIndex:462; appliedIndex:461; }","duration":"112.797524ms","start":"2026-04-10T04:09:39.731935Z","end":"2026-04-10T04:09:39.844732Z","steps":["trace[1680841507] 'read index received' (duration: 59.013523ms)","trace[1680841507] 'applied index is now lower than readState.Index' (duration: 53.782521ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:09:39.844894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.943109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:09:39.84489Z","caller":"traceutil/trace.go:171","msg":"trace[308176176] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"188.729283ms","start":"2026-04-10T04:09:39.656108Z","end":"2026-04-10T04:09:39.844838Z","steps":["trace[308176176] 'process raft request' (duration: 134.828099ms)","trace[308176176] 'compare' (duration: 53.634707ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:09:39.844939Z","caller":"traceutil/trace.go:171","msg":"trace[874772179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:442; }","duration":"113.042152ms","start":"2026-04-10T04:09:39.731886Z","end":"2026-04-10T04:09:39.844928Z","steps":["trace[874772179] 'agreement among raft nodes before linearized reading' (duration: 112.931889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:40.227411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.984257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-10T04:09:40.227569Z","caller":"traceutil/trace.go:171","msg":"trace[1397737103] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"220.169552ms","start":"2026-04-10T04:09:40.007371Z","end":"2026-04-10T04:09:40.22754Z","steps":["trace[1397737103] 'process raft request' (duration: 104.939518ms)","trace[1397737103] 'compare' (duration: 114.585505ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:09:40.241902Z","caller":"traceutil/trace.go:171","msg":"trace[966750222] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"159.898167ms","start":"2026-04-10T04:09:40.081978Z","end":"2026-04-10T04:09:40.241876Z","steps":["trace[966750222] 'process raft request' (duration: 159.771503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:40.470369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.659356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-10T04:09:40.470563Z","caller":"traceutil/trace.go:171","msg":"trace[1919456362] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"235.337622ms","start":"2026-04-10T04:09:40.235207Z","end":"2026-04-10T04:09:40.470545Z","steps":["trace[1919456362] 'process raft request' (duration: 117.44702ms)","trace[1919456362] 'compare' (duration: 117.507421ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:09:40.491282Z","caller":"traceutil/trace.go:171","msg":"trace[692067087] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"243.661179ms","start":"2026-04-10T04:09:40.247587Z","end":"2026-04-10T04:09:40.491248Z","steps":["trace[692067087] 'process raft request' (duration: 243.451893ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:40.491643Z","caller":"traceutil/trace.go:171","msg":"trace[406108331] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"244.03389ms","start":"2026-04-10T04:09:40.247596Z","end":"2026-04-10T04:09:40.49163Z","steps":["trace[406108331] 'process raft request' (duration: 243.594707ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:40.492038Z","caller":"traceutil/trace.go:171","msg":"trace[791477664] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"239.801385ms","start":"2026-04-10T04:09:40.252223Z","end":"2026-04-10T04:09:40.492025Z","steps":["trace[791477664] 'process raft request' (duration: 239.251818ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:40.492234Z","caller":"traceutil/trace.go:171","msg":"trace[619421262] linearizableReadLoop","detail":"{readStateIndex:474; appliedIndex:470; }","duration":"180.478497ms","start":"2026-04-10T04:09:40.31173Z","end":"2026-04-10T04:09:40.492209Z","steps":["trace[619421262] 'read index received' (duration: 41.026295ms)","trace[619421262] 'applied index is now lower than readState.Index' (duration: 139.450772ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:09:40.492756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.009643ms","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-10T04:09:40.492808Z","caller":"traceutil/trace.go:171","msg":"trace[970150690] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:455; }","duration":"181.103065ms","start":"2026-04-10T04:09:40.311688Z","end":"2026-04-10T04:09:40.492791Z","steps":["trace[970150690] 'agreement among raft nodes before linearized reading' (duration: 180.659293ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:40.493093Z","caller":"traceutil/trace.go:171","msg":"trace[1927245055] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"175.375496ms","start":"2026-04-10T04:09:40.317698Z","end":"2026-04-10T04:09:40.493073Z","steps":["trace[1927245055] 'process raft request' (duration: 174.070767ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:40.684152Z","caller":"traceutil/trace.go:171","msg":"trace[2015932571] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"129.378264ms","start":"2026-04-10T04:09:40.554724Z","end":"2026-04-10T04:09:40.684102Z","steps":["trace[2015932571] 'process raft request' (duration: 81.335011ms)","trace[2015932571] 'compare' (duration: 47.545018ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:09:40.685962Z","caller":"traceutil/trace.go:171","msg":"trace[1695094794] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"130.204868ms","start":"2026-04-10T04:09:40.555702Z","end":"2026-04-10T04:09:40.685906Z","steps":["trace[1695094794] 'process raft request' (duration: 128.070015ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:41.782991Z","caller":"traceutil/trace.go:171","msg":"trace[2063407970] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"124.395136ms","start":"2026-04-10T04:09:41.658558Z","end":"2026-04-10T04:09:41.782953Z","steps":["trace[2063407970] 'process raft request' (duration: 124.160168ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:42.507323Z","caller":"traceutil/trace.go:171","msg":"trace[1453795169] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:494; }","duration":"775.818472ms","start":"2026-04-10T04:09:41.731475Z","end":"2026-04-10T04:09:42.507294Z","steps":["trace[1453795169] 'read index received' (duration: 51.358743ms)","trace[1453795169] 'applied index is now lower than readState.Index' (duration: 724.458439ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:09:42.507506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.033028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:09:42.507446Z","caller":"traceutil/trace.go:171","msg":"trace[1528064934] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"848.400332ms","start":"2026-04-10T04:09:41.658997Z","end":"2026-04-10T04:09:42.507397Z","steps":["trace[1528064934] 'process raft request' (duration: 796.465342ms)","trace[1528064934] 'compare' (duration: 51.437856ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:09:42.507553Z","caller":"traceutil/trace.go:171","msg":"trace[305405950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:476; }","duration":"776.093191ms","start":"2026-04-10T04:09:41.731445Z","end":"2026-04-10T04:09:42.507538Z","steps":["trace[305405950] 'agreement among raft nodes before linearized reading' (duration: 776.000398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:42.507537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.315674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T04:09:42.507595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:41.731366Z","time spent":"776.221534ms","remote":"127.0.0.1:60612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-10T04:09:42.50762Z","caller":"traceutil/trace.go:171","msg":"trace[1425658224] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:476; }","duration":"666.466847ms","start":"2026-04-10T04:09:41.841131Z","end":"2026-04-10T04:09:42.507598Z","steps":["trace[1425658224] 'agreement among raft nodes before linearized reading' (duration: 666.337103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:42.507673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:41.8411Z","time spent":"666.56375ms","remote":"127.0.0.1:60706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-04-10T04:09:42.507692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:41.658963Z","time spent":"848.603878ms","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:09:42.507921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.361706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-10T04:09:42.507957Z","caller":"traceutil/trace.go:171","msg":"trace[1269993530] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:476; }","duration":"221.398488ms","start":"2026-04-10T04:09:42.286549Z","end":"2026-04-10T04:09:42.507947Z","steps":["trace[1269993530] 'agreement among raft nodes before linearized reading' (duration: 221.326906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:42.758696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.434423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:09:42.758815Z","caller":"traceutil/trace.go:171","msg":"trace[2110523873] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:478; }","duration":"115.592278ms","start":"2026-04-10T04:09:42.643191Z","end":"2026-04-10T04:09:42.758783Z","steps":["trace[2110523873] 'range keys from in-memory index tree' (duration: 115.36434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:42.759205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.056747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-10T04:09:42.759294Z","caller":"traceutil/trace.go:171","msg":"trace[947291328] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:478; }","duration":"151.213862ms","start":"2026-04-10T04:09:42.608059Z","end":"2026-04-10T04:09:42.759273Z","steps":["trace[947291328] 'range keys from in-memory index tree' (duration: 150.913723ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:43.161557Z","caller":"traceutil/trace.go:171","msg":"trace[127710403] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"197.626807ms","start":"2026-04-10T04:09:42.963907Z","end":"2026-04-10T04:09:43.161534Z","steps":["trace[127710403] 'process raft request' (duration: 197.160123ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:43.161511Z","caller":"traceutil/trace.go:171","msg":"trace[1878057385] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"189.303954ms","start":"2026-04-10T04:09:42.972163Z","end":"2026-04-10T04:09:43.161467Z","steps":["trace[1878057385] 'process raft request' (duration: 189.17234ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:43.183241Z","caller":"traceutil/trace.go:171","msg":"trace[1691877970] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"192.336074ms","start":"2026-04-10T04:09:42.990873Z","end":"2026-04-10T04:09:43.183209Z","steps":["trace[1691877970] 'process raft request' (duration: 192.021104ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:43.183328Z","caller":"traceutil/trace.go:171","msg":"trace[998458609] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"192.351095ms","start":"2026-04-10T04:09:42.990949Z","end":"2026-04-10T04:09:43.183301Z","steps":["trace[998458609] 'process raft request' (duration: 192.16987ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:43.18347Z","caller":"traceutil/trace.go:171","msg":"trace[494633316] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"187.680023ms","start":"2026-04-10T04:09:42.995778Z","end":"2026-04-10T04:09:43.183458Z","steps":["trace[494633316] 'process raft request' (duration: 187.503558ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:43.183682Z","caller":"traceutil/trace.go:171","msg":"trace[950017468] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"192.616943ms","start":"2026-04-10T04:09:42.991048Z","end":"2026-04-10T04:09:43.183665Z","steps":["trace[950017468] 'process raft request' (duration: 192.16631ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:43.183763Z","caller":"traceutil/trace.go:171","msg":"trace[496689775] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"186.680462ms","start":"2026-04-10T04:09:42.997075Z","end":"2026-04-10T04:09:43.183755Z","steps":["trace[496689775] 'process raft request' (duration: 186.267349ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:43.183835Z","caller":"traceutil/trace.go:171","msg":"trace[1160512116] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"170.870517ms","start":"2026-04-10T04:09:43.012957Z","end":"2026-04-10T04:09:43.183827Z","steps":["trace[1160512116] 'process raft request' (duration: 170.493546ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:43.183924Z","caller":"traceutil/trace.go:171","msg":"trace[1225232330] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"172.014953ms","start":"2026-04-10T04:09:43.011901Z","end":"2026-04-10T04:09:43.183916Z","steps":["trace[1225232330] 'process raft request' (duration: 171.499977ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:43.184129Z","caller":"traceutil/trace.go:171","msg":"trace[202905364] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"142.993848ms","start":"2026-04-10T04:09:43.041125Z","end":"2026-04-10T04:09:43.184119Z","steps":["trace[202905364] 'process raft request' (duration: 142.649747ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:43.184246Z","caller":"traceutil/trace.go:171","msg":"trace[1926221905] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"166.11194ms","start":"2026-04-10T04:09:43.017998Z","end":"2026-04-10T04:09:43.18411Z","steps":["trace[1926221905] 'process raft request' (duration: 165.539292ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:43.18461Z","caller":"traceutil/trace.go:171","msg":"trace[696114700] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"154.026868ms","start":"2026-04-10T04:09:43.030566Z","end":"2026-04-10T04:09:43.184593Z","steps":["trace[696114700] 'process raft request' (duration: 153.13768ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:44.419828Z","caller":"traceutil/trace.go:171","msg":"trace[1486759702] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"544.847967ms","start":"2026-04-10T04:09:43.87494Z","end":"2026-04-10T04:09:44.419788Z","steps":["trace[1486759702] 'process raft request' (duration: 536.966791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:44.420063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:43.874915Z","time spent":"545.029922ms","remote":"127.0.0.1:60658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:09:44.423348Z","caller":"traceutil/trace.go:171","msg":"trace[264001344] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"534.684843ms","start":"2026-04-10T04:09:43.88863Z","end":"2026-04-10T04:09:44.423315Z","steps":["trace[264001344] 'process raft request' (duration: 534.402785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:44.423532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:43.8886Z","time spent":"534.824298ms","remote":"127.0.0.1:60658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98689,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:09:44.423708Z","caller":"traceutil/trace.go:171","msg":"trace[319322964] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"459.033663ms","start":"2026-04-10T04:09:43.964653Z","end":"2026-04-10T04:09:44.423687Z","steps":["trace[319322964] 'process raft request' (duration: 458.702432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:44.423826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:43.964623Z","time spent":"459.150486ms","remote":"127.0.0.1:60658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T04:09:45.070425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.562214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T04:09:45.070617Z","caller":"traceutil/trace.go:171","msg":"trace[595589885] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"315.117182ms","start":"2026-04-10T04:09:44.755456Z","end":"2026-04-10T04:09:45.070573Z","steps":["trace[595589885] 'process raft request' (duration: 182.31706ms)","trace[595589885] 'compare' (duration: 131.927524ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:09:45.070658Z","caller":"traceutil/trace.go:171","msg":"trace[2068852543] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"204.355855ms","start":"2026-04-10T04:09:44.866274Z","end":"2026-04-10T04:09:45.07063Z","steps":["trace[2068852543] 'read index received' (duration: 71.267445ms)","trace[2068852543] 'applied index is now lower than readState.Index' (duration: 133.08605ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:09:45.070815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:44.755419Z","time spent":"315.287818ms","remote":"127.0.0.1:60658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:09:45.07087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.584282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-04-10T04:09:45.070892Z","caller":"traceutil/trace.go:171","msg":"trace[1195400727] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:553; }","duration":"204.675994ms","start":"2026-04-10T04:09:44.866209Z","end":"2026-04-10T04:09:45.070885Z","steps":["trace[1195400727] 'agreement among raft nodes before linearized reading' (duration: 204.504039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:45.544195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.956645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T04:09:45.54438Z","caller":"traceutil/trace.go:171","msg":"trace[1542086066] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"381.907556ms","start":"2026-04-10T04:09:45.162405Z","end":"2026-04-10T04:09:45.544312Z","steps":["trace[1542086066] 'read index received' (duration: 76.633252ms)","trace[1542086066] 'applied index is now lower than readState.Index' (duration: 305.272994ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:09:45.544592Z","caller":"traceutil/trace.go:171","msg":"trace[787952551] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"412.372228ms","start":"2026-04-10T04:09:45.13215Z","end":"2026-04-10T04:09:45.544522Z","steps":["trace[787952551] 'process raft request' (duration: 106.928998ms)","trace[787952551] 'compare' (duration: 304.642225ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:09:45.544806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.392871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-04-10T04:09:45.544857Z","caller":"traceutil/trace.go:171","msg":"trace[1178627538] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:554; }","duration":"382.467124ms","start":"2026-04-10T04:09:45.162373Z","end":"2026-04-10T04:09:45.54484Z","steps":["trace[1178627538] 'agreement among raft nodes before linearized reading' (duration: 382.264217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:45.544901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:45.162355Z","time spent":"382.536805ms","remote":"127.0.0.1:60658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":151259,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-04-10T04:09:45.544936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:45.132121Z","time spent":"412.560284ms","remote":"127.0.0.1:60658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:09:45.604195Z","caller":"traceutil/trace.go:171","msg":"trace[1570423135] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"306.045449ms","start":"2026-04-10T04:09:45.298128Z","end":"2026-04-10T04:09:45.604173Z","steps":["trace[1570423135] 'process raft request' (duration: 305.490071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:45.604339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:45.298083Z","time spent":"306.179194ms","remote":"127.0.0.1:60658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:09:45.604542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.788976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-10T04:09:45.604691Z","caller":"traceutil/trace.go:171","msg":"trace[830559624] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:555; }","duration":"328.021373ms","start":"2026-04-10T04:09:45.276644Z","end":"2026-04-10T04:09:45.604665Z","steps":["trace[830559624] 'agreement among raft nodes before linearized reading' (duration: 327.550529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:45.604776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:45.276622Z","time spent":"328.143467ms","remote":"127.0.0.1:60878","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-10T04:09:46.110025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.516223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:74769"} {"level":"info","ts":"2026-04-10T04:09:46.11029Z","caller":"traceutil/trace.go:171","msg":"trace[1135616725] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:558; }","duration":"337.819513ms","start":"2026-04-10T04:09:45.772445Z","end":"2026-04-10T04:09:46.110264Z","steps":["trace[1135616725] 'range keys from in-memory index tree' (duration: 336.977697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:46.110345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:45.772435Z","time spent":"337.894005ms","remote":"127.0.0.1:60658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":74792,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-04-10T04:09:46.143647Z","caller":"traceutil/trace.go:171","msg":"trace[374435647] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"329.350468ms","start":"2026-04-10T04:09:45.814272Z","end":"2026-04-10T04:09:46.143623Z","steps":["trace[374435647] 'read index received' (duration: 328.957146ms)","trace[374435647] 'applied index is now lower than readState.Index' (duration: 392.542µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:09:46.144123Z","caller":"traceutil/trace.go:171","msg":"trace[474135315] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"366.853293ms","start":"2026-04-10T04:09:45.777262Z","end":"2026-04-10T04:09:46.144116Z","steps":["trace[474135315] 'process raft request' (duration: 366.015817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:46.14423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:45.777232Z","time spent":"366.918545ms","remote":"127.0.0.1:60658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208470,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:09:46.144423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.151805ms","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-10T04:09:46.144449Z","caller":"traceutil/trace.go:171","msg":"trace[2015976261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:559; }","duration":"330.199115ms","start":"2026-04-10T04:09:45.814243Z","end":"2026-04-10T04:09:46.144442Z","steps":["trace[2015976261] 'agreement among raft nodes before linearized reading' (duration: 330.084092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:46.144464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:45.81423Z","time spent":"330.230477ms","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-10T04:09:46.525467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.229809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"info","ts":"2026-04-10T04:09:46.525589Z","caller":"traceutil/trace.go:171","msg":"trace[922891106] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:560; }","duration":"234.435595ms","start":"2026-04-10T04:09:46.291123Z","end":"2026-04-10T04:09:46.525559Z","steps":["trace[922891106] 'range keys from in-memory index tree' (duration: 233.552856ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:46.992884Z","caller":"traceutil/trace.go:171","msg":"trace[1493011816] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"371.29173ms","start":"2026-04-10T04:09:46.621555Z","end":"2026-04-10T04:09:46.992846Z","steps":["trace[1493011816] 'read index received' (duration: 371.104923ms)","trace[1493011816] 'applied index is now lower than readState.Index' (duration: 184.827µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:09:46.993028Z","caller":"traceutil/trace.go:171","msg":"trace[1040443688] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"437.044902ms","start":"2026-04-10T04:09:46.555939Z","end":"2026-04-10T04:09:46.992984Z","steps":["trace[1040443688] 'process raft request' (duration: 436.670389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:46.9932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.327215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T04:09:46.993252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:46.555918Z","time spent":"437.221948ms","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:09:46.993272Z","caller":"traceutil/trace.go:171","msg":"trace[631003104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"261.453619ms","start":"2026-04-10T04:09:46.731801Z","end":"2026-04-10T04:09:46.993254Z","steps":["trace[631003104] 'agreement among raft nodes before linearized reading' (duration: 261.327365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:46.993582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.011133ms","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-10T04:09:46.993667Z","caller":"traceutil/trace.go:171","msg":"trace[452193054] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:561; }","duration":"372.185078ms","start":"2026-04-10T04:09:46.621461Z","end":"2026-04-10T04:09:46.993646Z","steps":["trace[452193054] 'agreement among raft nodes before linearized reading' (duration: 371.523778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:46.993745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:46.621418Z","time spent":"372.290252ms","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-10T04:09:47.237921Z","caller":"traceutil/trace.go:171","msg":"trace[231113805] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"239.472342ms","start":"2026-04-10T04:09:46.998419Z","end":"2026-04-10T04:09:47.237891Z","steps":["trace[231113805] 'process raft request' (duration: 192.204213ms)","trace[231113805] 'compare' (duration: 46.920607ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:09:49.607329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.092993ms","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-10T04:09:49.607408Z","caller":"traceutil/trace.go:171","msg":"trace[502304422] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:572; }","duration":"147.206956ms","start":"2026-04-10T04:09:49.460183Z","end":"2026-04-10T04:09:49.60739Z","steps":["trace[502304422] 'range keys from in-memory index tree' (duration: 146.99133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:49.607612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.342071ms","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-10T04:09:49.607647Z","caller":"traceutil/trace.go:171","msg":"trace[1468272636] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:572; }","duration":"147.388832ms","start":"2026-04-10T04:09:49.46025Z","end":"2026-04-10T04:09:49.607639Z","steps":["trace[1468272636] 'count revisions from in-memory index tree' (duration: 147.31762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:50.778599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.804619ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-10T04:09:50.77896Z","caller":"traceutil/trace.go:171","msg":"trace[103763162] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"295.532624ms","start":"2026-04-10T04:09:50.483337Z","end":"2026-04-10T04:09:50.77887Z","steps":["trace[103763162] 'process raft request' (duration: 170.370882ms)","trace[103763162] 'compare' (duration: 124.616934ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:09:51.47731Z","caller":"traceutil/trace.go:171","msg":"trace[506630515] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"178.408473ms","start":"2026-04-10T04:09:51.298857Z","end":"2026-04-10T04:09:51.477265Z","steps":["trace[506630515] 'process raft request' (duration: 173.768376ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:52.642499Z","caller":"traceutil/trace.go:171","msg":"trace[181601846] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"340.347438ms","start":"2026-04-10T04:09:52.302096Z","end":"2026-04-10T04:09:52.642444Z","steps":["trace[181601846] 'process raft request' (duration: 339.912243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:52.642793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:52.302082Z","time spent":"340.561505ms","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:09:52.64671Z","caller":"traceutil/trace.go:171","msg":"trace[177796628] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"243.761623ms","start":"2026-04-10T04:09:52.402594Z","end":"2026-04-10T04:09:52.646355Z","steps":["trace[177796628] 'process raft request' (duration: 243.314508ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:52.647557Z","caller":"traceutil/trace.go:171","msg":"trace[548625079] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:614; }","duration":"162.565424ms","start":"2026-04-10T04:09:52.484929Z","end":"2026-04-10T04:09:52.647494Z","steps":["trace[548625079] 'read index received' (duration: 156.999604ms)","trace[548625079] 'applied index is now lower than readState.Index' (duration: 5.56372ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:09:52.648095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.162985ms","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-10T04:09:52.648146Z","caller":"traceutil/trace.go:171","msg":"trace[671490503] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:591; }","duration":"163.273749ms","start":"2026-04-10T04:09:52.484855Z","end":"2026-04-10T04:09:52.648129Z","steps":["trace[671490503] 'agreement among raft nodes before linearized reading' (duration: 162.74468ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:52.648778Z","caller":"traceutil/trace.go:171","msg":"trace[56959506] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"215.100225ms","start":"2026-04-10T04:09:52.433644Z","end":"2026-04-10T04:09:52.648744Z","steps":["trace[56959506] 'process raft request' (duration: 212.563889ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:52.651416Z","caller":"traceutil/trace.go:171","msg":"trace[1354312202] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"268.662211ms","start":"2026-04-10T04:09:52.377621Z","end":"2026-04-10T04:09:52.646283Z","steps":["trace[1354312202] 'process raft request' (duration: 264.948285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:53.54625Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827831046142661704,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T04:09:54.04736Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827831046142661704,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T04:09:54.548381Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827831046142661704,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T04:09:54.82333Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.105406432s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-10T04:09:54.94349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.681101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-10T04:09:54.943658Z","caller":"traceutil/trace.go:171","msg":"trace[1733804709] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"1.898452213s","start":"2026-04-10T04:09:53.04518Z","end":"2026-04-10T04:09:54.943632Z","steps":["trace[1733804709] 'read index received' (duration: 1.778363358s)","trace[1733804709] 'applied index is now lower than readState.Index' (duration: 120.086685ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:09:54.943895Z","caller":"traceutil/trace.go:171","msg":"trace[938269468] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"2.226098029s","start":"2026-04-10T04:09:52.717782Z","end":"2026-04-10T04:09:54.94388Z","steps":["trace[938269468] 'process raft request' (duration: 2.105859338s)","trace[938269468] 'compare' (duration: 119.415633ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:09:54.943961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:52.717749Z","time spent":"2.226179631s","remote":"127.0.0.1:32884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T04:09:54.944477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.899300673s","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-10T04:09:54.944526Z","caller":"traceutil/trace.go:171","msg":"trace[108238042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"1.899400706s","start":"2026-04-10T04:09:53.04511Z","end":"2026-04-10T04:09:54.944511Z","steps":["trace[108238042] 'agreement among raft nodes before linearized reading' (duration: 1.899054563s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:54.94456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:53.045085Z","time spent":"1.899468069s","remote":"127.0.0.1:60878","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-10T04:09:54.944746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108089968s","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-10T04:09:54.94483Z","caller":"traceutil/trace.go:171","msg":"trace[1572681376] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:593; }","duration":"1.108238133s","start":"2026-04-10T04:09:53.83657Z","end":"2026-04-10T04:09:54.944808Z","steps":["trace[1572681376] 'agreement among raft nodes before linearized reading' (duration: 1.107995045s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:54.944888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:53.836543Z","time spent":"1.108336316s","remote":"127.0.0.1:60878","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-10T04:09:54.944987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.608018901s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6fr4w\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-10T04:09:54.944947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.21304239s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T04:09:54.945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.404856ms","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-10T04:09:54.945056Z","caller":"traceutil/trace.go:171","msg":"trace[820273680] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6fr4w; range_end:; response_count:1; response_revision:593; }","duration":"1.608128765s","start":"2026-04-10T04:09:53.336904Z","end":"2026-04-10T04:09:54.945033Z","steps":["trace[820273680] 'agreement among raft nodes before linearized reading' (duration: 1.60798324s)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:54.945087Z","caller":"traceutil/trace.go:171","msg":"trace[1425113079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"1.213263238s","start":"2026-04-10T04:09:53.731789Z","end":"2026-04-10T04:09:54.945052Z","steps":["trace[1425113079] 'agreement among raft nodes before linearized reading' (duration: 1.212982548s)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:54.945092Z","caller":"traceutil/trace.go:171","msg":"trace[2107692896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"296.568481ms","start":"2026-04-10T04:09:54.6485Z","end":"2026-04-10T04:09:54.945068Z","steps":["trace[2107692896] 'agreement among raft nodes before linearized reading' (duration: 296.317703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:54.945096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:53.336878Z","time spent":"1.608208648s","remote":"127.0.0.1:60796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-6fr4w\" "} {"level":"warn","ts":"2026-04-10T04:09:54.945152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:53.731759Z","time spent":"1.213382352s","remote":"127.0.0.1:60612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T04:09:54.944822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.655617661s","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-10T04:09:54.945671Z","caller":"traceutil/trace.go:171","msg":"trace[765670837] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:593; }","duration":"1.656609016s","start":"2026-04-10T04:09:53.289036Z","end":"2026-04-10T04:09:54.945645Z","steps":["trace[765670837] 'agreement among raft nodes before linearized reading' (duration: 1.655512477s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:54.945725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:53.289005Z","time spent":"1.656703809s","remote":"127.0.0.1:60878","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-10T04:09:56.958614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000338436s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-10T04:09:56.958732Z","caller":"traceutil/trace.go:171","msg":"trace[2048842535] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000483951s","start":"2026-04-10T04:09:54.958228Z","end":"2026-04-10T04:09:56.958712Z","steps":["trace[2048842535] 'agreement among raft nodes before linearized reading' (duration: 2.000351077s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:56.958779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:54.958219Z","time spent":"2.000550533s","remote":"127.0.0.1:60612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/10 04:09:56 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-10T04:09:57.434929Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.396558701s","expected-duration":"1s"} {"level":"info","ts":"2026-04-10T04:09:57.435754Z","caller":"traceutil/trace.go:171","msg":"trace[1050820875] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"2.480282964s","start":"2026-04-10T04:09:54.955438Z","end":"2026-04-10T04:09:57.435721Z","steps":["trace[1050820875] 'process raft request' (duration: 2.479754036s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:57.435955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:54.955427Z","time spent":"2.480424139s","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:09:57.436276Z","caller":"traceutil/trace.go:171","msg":"trace[1599734422] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:620; }","duration":"2.477986474s","start":"2026-04-10T04:09:54.958248Z","end":"2026-04-10T04:09:57.436235Z","steps":["trace[1599734422] 'read index received' (duration: 80.168848ms)","trace[1599734422] 'applied index is now lower than readState.Index' (duration: 2.397816376s)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:09:57.436308Z","caller":"traceutil/trace.go:171","msg":"trace[1417096023] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"2.480755841s","start":"2026-04-10T04:09:54.9555Z","end":"2026-04-10T04:09:57.436256Z","steps":["trace[1417096023] 'process raft request' (duration: 2.480133989s)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:57.436411Z","caller":"traceutil/trace.go:171","msg":"trace[1842389823] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"2.480848654s","start":"2026-04-10T04:09:54.955545Z","end":"2026-04-10T04:09:57.436394Z","steps":["trace[1842389823] 'process raft request' (duration: 2.480406018s)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:57.436436Z","caller":"traceutil/trace.go:171","msg":"trace[196489663] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"2.473566721s","start":"2026-04-10T04:09:54.962825Z","end":"2026-04-10T04:09:57.436392Z","steps":["trace[196489663] 'process raft request' (duration: 2.47326986s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:57.436536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:54.955512Z","time spent":"2.480940407s","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:09:57.436623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:54.962792Z","time spent":"2.473741718s","remote":"127.0.0.1:60796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:09:57.436891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:54.955493Z","time spent":"2.481039642s","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:09:58.771178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171780543s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T04:09:58.771406Z","caller":"traceutil/trace.go:171","msg":"trace[2051732442] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"3.382072874s","start":"2026-04-10T04:09:55.389307Z","end":"2026-04-10T04:09:58.77138Z","steps":["trace[2051732442] 'process raft request' (duration: 2.209987671s)","trace[2051732442] 'compare' (duration: 1.171532004s)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:09:58.771524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:55.389262Z","time spent":"3.38221798s","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:09:58.77157Z","caller":"traceutil/trace.go:171","msg":"trace[1730103730] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"1.335195602s","start":"2026-04-10T04:09:57.436353Z","end":"2026-04-10T04:09:58.771548Z","steps":["trace[1730103730] 'read index received' (duration: 162.743106ms)","trace[1730103730] 'applied index is now lower than readState.Index' (duration: 1.172450866s)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:09:58.850368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.008772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T04:09:58.850394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.891045658s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T04:09:58.85046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.892013397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:09:58.850521Z","caller":"traceutil/trace.go:171","msg":"trace[1205349427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"1.891211084s","start":"2026-04-10T04:09:56.959277Z","end":"2026-04-10T04:09:58.850489Z","steps":["trace[1205349427] 'agreement among raft nodes before linearized reading' (duration: 1.812404934s)","trace[1205349427] 'range keys from in-memory index tree' (duration: 78.605683ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:09:58.85058Z","caller":"traceutil/trace.go:171","msg":"trace[1517130917] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:599; }","duration":"3.892176593s","start":"2026-04-10T04:09:54.958365Z","end":"2026-04-10T04:09:58.850541Z","steps":["trace[1517130917] 'agreement among raft nodes before linearized reading' (duration: 3.813315861s)","trace[1517130917] 'range keys from in-memory index tree' (duration: 78.668605ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:09:58.850659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:54.958352Z","time spent":"3.892294058s","remote":"127.0.0.1:60858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-04-10T04:09:58.850451Z","caller":"traceutil/trace.go:171","msg":"trace[1394984258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"807.153567ms","start":"2026-04-10T04:09:58.04328Z","end":"2026-04-10T04:09:58.850433Z","steps":["trace[1394984258] 'agreement among raft nodes before linearized reading' (duration: 807.007272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:58.85079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:58.043259Z","time spent":"807.50578ms","remote":"127.0.0.1:60612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T04:09:58.850761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:56.959187Z","time spent":"1.891514366s","remote":"127.0.0.1:60624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-10T04:09:58.850969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.808091602s","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-10T04:09:58.85108Z","caller":"traceutil/trace.go:171","msg":"trace[424363860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:599; }","duration":"1.808276538s","start":"2026-04-10T04:09:57.042783Z","end":"2026-04-10T04:09:58.85106Z","steps":["trace[424363860] 'agreement among raft nodes before linearized reading' (duration: 1.728871386s)","trace[424363860] 'range keys from in-memory index tree' (duration: 78.633204ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:09:58.851183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:57.042722Z","time spent":"1.808448154s","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-10T04:09:58.980451Z","caller":"traceutil/trace.go:171","msg":"trace[898231079] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"123.154488ms","start":"2026-04-10T04:09:58.857272Z","end":"2026-04-10T04:09:58.980426Z","steps":["trace[898231079] 'process raft request' (duration: 122.983162ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:09:59.078102Z","caller":"traceutil/trace.go:171","msg":"trace[458441872] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:627; }","duration":"217.612304ms","start":"2026-04-10T04:09:58.860454Z","end":"2026-04-10T04:09:59.078066Z","steps":["trace[458441872] 'read index received' (duration: 119.913561ms)","trace[458441872] 'applied index is now lower than readState.Index' (duration: 97.697332ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:09:59.078266Z","caller":"traceutil/trace.go:171","msg":"trace[1454060482] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"220.758187ms","start":"2026-04-10T04:09:58.857471Z","end":"2026-04-10T04:09:59.078229Z","steps":["trace[1454060482] 'process raft request' (duration: 220.387194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:09:59.078344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.852382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:09:59.07839Z","caller":"traceutil/trace.go:171","msg":"trace[2121653817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"217.967167ms","start":"2026-04-10T04:09:58.860409Z","end":"2026-04-10T04:09:59.078376Z","steps":["trace[2121653817] 'agreement among raft nodes before linearized reading' (duration: 217.853393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:00.046002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.900594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6fr4w\" ","response":"range_response_count:1 size:17149"} {"level":"warn","ts":"2026-04-10T04:10:00.046141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.691638ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-10T04:10:00.046627Z","caller":"traceutil/trace.go:171","msg":"trace[1676096268] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6fr4w; range_end:; response_count:1; response_revision:602; }","duration":"693.08289ms","start":"2026-04-10T04:09:59.353004Z","end":"2026-04-10T04:10:00.046087Z","steps":["trace[1676096268] 'range keys from in-memory index tree' (duration: 692.675935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:00.046714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:59.352968Z","time spent":"693.728904ms","remote":"127.0.0.1:60796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17172,"request content":"key:\"/registry/pods/kube-system/cilium-6fr4w\" "} {"level":"info","ts":"2026-04-10T04:10:00.046794Z","caller":"traceutil/trace.go:171","msg":"trace[1430332664] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:630; }","duration":"605.401145ms","start":"2026-04-10T04:09:59.441374Z","end":"2026-04-10T04:10:00.046775Z","steps":["trace[1430332664] 'read index received' (duration: 10.99607ms)","trace[1430332664] 'applied index is now lower than readState.Index' (duration: 594.404255ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:10:00.046795Z","caller":"traceutil/trace.go:171","msg":"trace[1716290360] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"692.380855ms","start":"2026-04-10T04:09:59.354391Z","end":"2026-04-10T04:10:00.046772Z","steps":["trace[1716290360] 'process raft request' (duration: 97.962749ms)","trace[1716290360] 'compare' (duration: 593.475221ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:10:00.046874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:59.354373Z","time spent":"692.466718ms","remote":"127.0.0.1:60688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":765,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-10T04:10:00.046919Z","caller":"traceutil/trace.go:171","msg":"trace[311717888] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"680.98459ms","start":"2026-04-10T04:09:59.365916Z","end":"2026-04-10T04:10:00.046901Z","steps":["trace[311717888] 'process raft request' (duration: 680.794213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:00.046994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:59.365904Z","time spent":"681.055752ms","remote":"127.0.0.1:60858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T04:10:00.047064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.10396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:10:00.047104Z","caller":"traceutil/trace.go:171","msg":"trace[1588711327] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"316.151712ms","start":"2026-04-10T04:09:59.730942Z","end":"2026-04-10T04:10:00.047094Z","steps":["trace[1588711327] 'agreement among raft nodes before linearized reading' (duration: 316.034198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:00.04712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.167438ms","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-10T04:10:00.047143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:59.730874Z","time spent":"316.259515ms","remote":"127.0.0.1:60612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-10T04:10:00.047183Z","caller":"traceutil/trace.go:171","msg":"trace[1068926492] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:604; }","duration":"604.241642ms","start":"2026-04-10T04:09:59.44292Z","end":"2026-04-10T04:10:00.047162Z","steps":["trace[1068926492] 'agreement among raft nodes before linearized reading' (duration: 604.065215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:00.047242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:59.442913Z","time spent":"604.316774ms","remote":"127.0.0.1:60878","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-10T04:10:00.047243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.82935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-10T04:10:00.047278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.160305ms","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-10T04:10:00.047379Z","caller":"traceutil/trace.go:171","msg":"trace[248006666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"606.037338ms","start":"2026-04-10T04:09:59.4413Z","end":"2026-04-10T04:10:00.047337Z","steps":["trace[248006666] 'agreement among raft nodes before linearized reading' (duration: 605.592712ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:10:00.04738Z","caller":"traceutil/trace.go:171","msg":"trace[1173012530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:604; }","duration":"605.30194ms","start":"2026-04-10T04:09:59.44205Z","end":"2026-04-10T04:10:00.047352Z","steps":["trace[1173012530] 'agreement among raft nodes before linearized reading' (duration: 604.878245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:00.047449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:59.441276Z","time spent":"606.161361ms","remote":"127.0.0.1:60878","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-10T04:10:00.047451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:09:59.442042Z","time spent":"605.398944ms","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-10T04:10:01.768735Z","caller":"traceutil/trace.go:171","msg":"trace[149214522] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"400.083939ms","start":"2026-04-10T04:10:01.368621Z","end":"2026-04-10T04:10:01.768705Z","steps":["trace[149214522] 'read index received' (duration: 399.933452ms)","trace[149214522] 'applied index is now lower than readState.Index' (duration: 148.917µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:10:01.768875Z","caller":"traceutil/trace.go:171","msg":"trace[428465905] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"430.647858ms","start":"2026-04-10T04:10:01.338213Z","end":"2026-04-10T04:10:01.768861Z","steps":["trace[428465905] 'process raft request' (duration: 430.290124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:01.769041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:10:01.338153Z","time spent":"430.765462ms","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:10:01.769158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.526775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6fr4w\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-04-10T04:10:01.769239Z","caller":"traceutil/trace.go:171","msg":"trace[172063403] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6fr4w; range_end:; response_count:1; response_revision:621; }","duration":"400.618488ms","start":"2026-04-10T04:10:01.368603Z","end":"2026-04-10T04:10:01.769221Z","steps":["trace[172063403] 'agreement among raft nodes before linearized reading' (duration: 400.422751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:01.769278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:10:01.368028Z","time spent":"401.242512ms","remote":"127.0.0.1:60796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17547,"request content":"key:\"/registry/pods/kube-system/cilium-6fr4w\" "} {"level":"info","ts":"2026-04-10T04:10:01.771923Z","caller":"traceutil/trace.go:171","msg":"trace[1611033647] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"402.314391ms","start":"2026-04-10T04:10:01.369597Z","end":"2026-04-10T04:10:01.771912Z","steps":["trace[1611033647] 'process raft request' (duration: 402.042271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:01.772007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:10:01.369582Z","time spent":"402.374653ms","remote":"127.0.0.1:60688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":765,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T04:10:02.484052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.422812ms","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-10T04:10:02.484142Z","caller":"traceutil/trace.go:171","msg":"trace[1410666093] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"381.592029ms","start":"2026-04-10T04:10:02.10253Z","end":"2026-04-10T04:10:02.484122Z","steps":["trace[1410666093] 'range keys from in-memory index tree' (duration: 381.205044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:02.484425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.385793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-10T04:10:02.484505Z","caller":"traceutil/trace.go:171","msg":"trace[150919840] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:628; }","duration":"196.483817ms","start":"2026-04-10T04:10:02.288004Z","end":"2026-04-10T04:10:02.484488Z","steps":["trace[150919840] 'range keys from in-memory index tree' (duration: 196.239757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:02.484644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.085067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6fr4w\" ","response":"range_response_count:1 size:17790"} {"level":"info","ts":"2026-04-10T04:10:02.484722Z","caller":"traceutil/trace.go:171","msg":"trace[1075175494] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6fr4w; range_end:; response_count:1; response_revision:628; }","duration":"107.197061ms","start":"2026-04-10T04:10:02.377507Z","end":"2026-04-10T04:10:02.484704Z","steps":["trace[1075175494] 'range keys from in-memory index tree' (duration: 106.909061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:02.485101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.530008ms","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-10T04:10:02.485143Z","caller":"traceutil/trace.go:171","msg":"trace[915852626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"420.618091ms","start":"2026-04-10T04:10:02.064515Z","end":"2026-04-10T04:10:02.485134Z","steps":["trace[915852626] 'range keys from in-memory index tree' (duration: 419.527091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:02.485164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:10:02.064489Z","time spent":"420.669903ms","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-10T04:10:02.485145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.692729ms","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-10T04:10:02.485233Z","caller":"traceutil/trace.go:171","msg":"trace[1221935072] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"382.824944ms","start":"2026-04-10T04:10:02.102395Z","end":"2026-04-10T04:10:02.48522Z","steps":["trace[1221935072] 'range keys from in-memory index tree' (duration: 381.798267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:02.48531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:10:02.102501Z","time spent":"382.788753ms","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-10T04:10:02.485338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:10:02.102383Z","time spent":"382.917467ms","remote":"127.0.0.1:60878","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-10T04:10:02.503634Z","caller":"traceutil/trace.go:171","msg":"trace[1139648211] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"121.824325ms","start":"2026-04-10T04:10:02.381772Z","end":"2026-04-10T04:10:02.503596Z","steps":["trace[1139648211] 'process raft request' (duration: 121.611468ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:10:16.617252Z","caller":"traceutil/trace.go:171","msg":"trace[266186508] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:791; }","duration":"147.665553ms","start":"2026-04-10T04:10:16.469559Z","end":"2026-04-10T04:10:16.617224Z","steps":["trace[266186508] 'read index received' (duration: 147.443004ms)","trace[266186508] 'applied index is now lower than readState.Index' (duration: 221.539µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:10:16.617338Z","caller":"traceutil/trace.go:171","msg":"trace[1961768510] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"277.508202ms","start":"2026-04-10T04:10:16.3398Z","end":"2026-04-10T04:10:16.617308Z","steps":["trace[1961768510] 'process raft request' (duration: 277.267871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:16.617698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.113872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wcnn8\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-10T04:10:16.617757Z","caller":"traceutil/trace.go:171","msg":"trace[1820810851] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wcnn8; range_end:; response_count:1; response_revision:759; }","duration":"148.203126ms","start":"2026-04-10T04:10:16.46954Z","end":"2026-04-10T04:10:16.617743Z","steps":["trace[1820810851] 'agreement among raft nodes before linearized reading' (duration: 147.80758ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:10:16.723977Z","caller":"traceutil/trace.go:171","msg":"trace[1146978152] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"100.409943ms","start":"2026-04-10T04:10:16.623542Z","end":"2026-04-10T04:10:16.723952Z","steps":["trace[1146978152] 'process raft request' (duration: 60.69987ms)","trace[1146978152] 'compare' (duration: 39.541287ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:10:16.724273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.250197ms","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-10T04:10:16.724406Z","caller":"traceutil/trace.go:171","msg":"trace[90110925] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:760; }","duration":"100.412563ms","start":"2026-04-10T04:10:16.623977Z","end":"2026-04-10T04:10:16.724389Z","steps":["trace[90110925] 'agreement among raft nodes before linearized reading' (duration: 100.071089ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:10:17.040982Z","caller":"traceutil/trace.go:171","msg":"trace[1292237780] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"413.8833ms","start":"2026-04-10T04:10:16.627069Z","end":"2026-04-10T04:10:17.040953Z","steps":["trace[1292237780] 'process raft request' (duration: 398.573411ms)","trace[1292237780] 'compare' (duration: 15.102601ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:10:17.041149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:10:16.627058Z","time spent":"414.007367ms","remote":"127.0.0.1:60796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:10:17.041462Z","caller":"traceutil/trace.go:171","msg":"trace[1336937522] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:793; }","duration":"317.514113ms","start":"2026-04-10T04:10:16.723916Z","end":"2026-04-10T04:10:17.041431Z","steps":["trace[1336937522] 'read index received' (duration: 301.741235ms)","trace[1336937522] 'applied index is now lower than readState.Index' (duration: 15.771408ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:10:17.041521Z","caller":"traceutil/trace.go:171","msg":"trace[1319123480] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"413.797448ms","start":"2026-04-10T04:10:16.627676Z","end":"2026-04-10T04:10:17.041473Z","steps":["trace[1319123480] 'process raft request' (duration: 413.213504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:17.041677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.122706ms","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-10T04:10:17.041756Z","caller":"traceutil/trace.go:171","msg":"trace[1838680941] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:762; }","duration":"407.220459ms","start":"2026-04-10T04:10:16.634517Z","end":"2026-04-10T04:10:17.041737Z","steps":["trace[1838680941] 'agreement among raft nodes before linearized reading' (duration: 407.024181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:17.041775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:10:16.627668Z","time spent":"413.972365ms","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:10:17.041806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:10:16.63451Z","time spent":"407.285753ms","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-10T04:10:17.041919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.318922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:10:17.041957Z","caller":"traceutil/trace.go:171","msg":"trace[1583696493] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy; range_end:; response_count:0; response_revision:762; }","duration":"416.567962ms","start":"2026-04-10T04:10:16.625381Z","end":"2026-04-10T04:10:17.041949Z","steps":["trace[1583696493] 'agreement among raft nodes before linearized reading' (duration: 416.313351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:17.042011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:10:16.62537Z","time spent":"416.631884ms","remote":"127.0.0.1:60706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/envoy\" "} {"level":"warn","ts":"2026-04-10T04:10:17.082281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.7247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:10:17.082361Z","caller":"traceutil/trace.go:171","msg":"trace[109045715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:763; }","duration":"350.830595ms","start":"2026-04-10T04:10:16.73151Z","end":"2026-04-10T04:10:17.08234Z","steps":["trace[109045715] 'agreement among raft nodes before linearized reading' (duration: 350.695689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:17.082401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:10:16.731493Z","time spent":"350.900178ms","remote":"127.0.0.1:60612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-10T04:10:17.08285Z","caller":"traceutil/trace.go:171","msg":"trace[321986349] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"350.927079ms","start":"2026-04-10T04:10:16.731865Z","end":"2026-04-10T04:10:17.082792Z","steps":["trace[321986349] 'process raft request' (duration: 350.062163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:17.083203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:10:16.731847Z","time spent":"351.106775ms","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:10:17.250756Z","caller":"traceutil/trace.go:171","msg":"trace[271312474] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:796; }","duration":"168.638161ms","start":"2026-04-10T04:10:17.082098Z","end":"2026-04-10T04:10:17.250736Z","steps":["trace[271312474] 'read index received' (duration: 109.680858ms)","trace[271312474] 'applied index is now lower than readState.Index' (duration: 58.955893ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:10:17.250845Z","caller":"traceutil/trace.go:171","msg":"trace[1777526926] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"202.637298ms","start":"2026-04-10T04:10:17.048174Z","end":"2026-04-10T04:10:17.250812Z","steps":["trace[1777526926] 'process raft request' (duration: 202.481461ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:10:17.25094Z","caller":"traceutil/trace.go:171","msg":"trace[959645228] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"204.418231ms","start":"2026-04-10T04:10:17.046499Z","end":"2026-04-10T04:10:17.250918Z","steps":["trace[959645228] 'process raft request' (duration: 145.328963ms)","trace[959645228] 'compare' (duration: 58.682162ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:10:17.251123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.457714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-qmw6q\" ","response":"range_response_count:1 size:5047"} {"level":"warn","ts":"2026-04-10T04:10:17.251129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.782595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:10:17.251171Z","caller":"traceutil/trace.go:171","msg":"trace[1788911647] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-qmw6q; range_end:; response_count:1; response_revision:765; }","duration":"205.541877ms","start":"2026-04-10T04:10:17.045616Z","end":"2026-04-10T04:10:17.251158Z","steps":["trace[1788911647] 'agreement among raft nodes before linearized reading' (duration: 205.246675ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:10:17.251205Z","caller":"traceutil/trace.go:171","msg":"trace[1614393585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"167.9027ms","start":"2026-04-10T04:10:17.083283Z","end":"2026-04-10T04:10:17.251186Z","steps":["trace[1614393585] 'agreement among raft nodes before linearized reading' (duration: 167.776845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:17.554572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.791608ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T04:10:17.554703Z","caller":"traceutil/trace.go:171","msg":"trace[1420095338] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"295.203703ms","start":"2026-04-10T04:10:17.259473Z","end":"2026-04-10T04:10:17.554677Z","steps":["trace[1420095338] 'process raft request' (duration: 97.192105ms)","trace[1420095338] 'compare' (duration: 197.594491ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:10:17.559802Z","caller":"traceutil/trace.go:171","msg":"trace[1760794301] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"292.96857ms","start":"2026-04-10T04:10:17.266805Z","end":"2026-04-10T04:10:17.559774Z","steps":["trace[1760794301] 'process raft request' (duration: 292.904528ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:10:17.559884Z","caller":"traceutil/trace.go:171","msg":"trace[1873359115] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"295.274145ms","start":"2026-04-10T04:10:17.26457Z","end":"2026-04-10T04:10:17.559844Z","steps":["trace[1873359115] 'process raft request' (duration: 294.982012ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:10:17.766584Z","caller":"traceutil/trace.go:171","msg":"trace[1985926617] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"168.611778ms","start":"2026-04-10T04:10:17.597941Z","end":"2026-04-10T04:10:17.766553Z","steps":["trace[1985926617] 'process raft request' (duration: 71.06036ms)","trace[1985926617] 'compare' (duration: 97.315679ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:10:17.767025Z","caller":"traceutil/trace.go:171","msg":"trace[1920147182] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"169.008456ms","start":"2026-04-10T04:10:17.597982Z","end":"2026-04-10T04:10:17.76699Z","steps":["trace[1920147182] 'process raft request' (duration: 168.506125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:19.173201Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.26627994s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-10T04:10:19.316075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.029839ms","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-10T04:10:19.316262Z","caller":"traceutil/trace.go:171","msg":"trace[1330741353] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:774; }","duration":"238.243478ms","start":"2026-04-10T04:10:19.077987Z","end":"2026-04-10T04:10:19.31623Z","steps":["trace[1330741353] 'range keys from in-memory index tree' (duration: 238.004798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:19.323279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.730581ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-10T04:10:19.324322Z","caller":"traceutil/trace.go:171","msg":"trace[949930754] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:807; }","duration":"592.363138ms","start":"2026-04-10T04:10:18.73194Z","end":"2026-04-10T04:10:19.324303Z","steps":["trace[949930754] 'read index received' (duration: 441.618365ms)","trace[949930754] 'applied index is now lower than readState.Index' (duration: 150.743973ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:10:19.324402Z","caller":"traceutil/trace.go:171","msg":"trace[65370300] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"1.275342191s","start":"2026-04-10T04:10:18.049036Z","end":"2026-04-10T04:10:19.324378Z","steps":["trace[65370300] 'process raft request' (duration: 1.275145753s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:19.324509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:10:18.049005Z","time spent":"1.275440015s","remote":"127.0.0.1:60858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:10:19.324624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.673171ms","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-10T04:10:19.324692Z","caller":"traceutil/trace.go:171","msg":"trace[2141720990] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:775; }","duration":"592.774645ms","start":"2026-04-10T04:10:18.731898Z","end":"2026-04-10T04:10:19.324672Z","steps":["trace[2141720990] 'agreement among raft nodes before linearized reading' (duration: 592.513995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:19.324718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.451053ms","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-10T04:10:19.324748Z","caller":"traceutil/trace.go:171","msg":"trace[1667201819] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:775; }","duration":"236.519296ms","start":"2026-04-10T04:10:19.088219Z","end":"2026-04-10T04:10:19.324738Z","steps":["trace[1667201819] 'agreement among raft nodes before linearized reading' (duration: 236.218264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:19.32474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:10:18.731876Z","time spent":"592.849038ms","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-10T04:10:19.324703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.998377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-10T04:10:19.324634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.942759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T04:10:19.324873Z","caller":"traceutil/trace.go:171","msg":"trace[718335853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:775; }","duration":"277.206216ms","start":"2026-04-10T04:10:19.047646Z","end":"2026-04-10T04:10:19.324852Z","steps":["trace[718335853] 'agreement among raft nodes before linearized reading' (duration: 276.876252ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:10:19.324886Z","caller":"traceutil/trace.go:171","msg":"trace[1148661393] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:775; }","duration":"582.22022ms","start":"2026-04-10T04:10:18.742642Z","end":"2026-04-10T04:10:19.324862Z","steps":["trace[1148661393] 'agreement among raft nodes before linearized reading' (duration: 581.911027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:19.325234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.283115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:10:19.325282Z","caller":"traceutil/trace.go:171","msg":"trace[1242208927] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"593.027627ms","start":"2026-04-10T04:10:18.732245Z","end":"2026-04-10T04:10:19.325272Z","steps":["trace[1242208927] 'agreement among raft nodes before linearized reading' (duration: 592.16002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:10:19.32518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:10:18.742633Z","time spent":"582.522242ms","remote":"127.0.0.1:60926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-04-10T04:10:19.325358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:10:18.73224Z","time spent":"593.066318ms","remote":"127.0.0.1:60612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-10T04:11:38.067398Z","caller":"traceutil/trace.go:171","msg":"trace[1120926330] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"131.907395ms","start":"2026-04-10T04:11:37.935452Z","end":"2026-04-10T04:11:38.067359Z","steps":["trace[1120926330] 'process raft request' (duration: 131.634151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:11:38.067856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.230634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4219"} {"level":"info","ts":"2026-04-10T04:11:38.068017Z","caller":"traceutil/trace.go:171","msg":"trace[1733445613] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:1165; }","duration":"120.490018ms","start":"2026-04-10T04:11:37.947505Z","end":"2026-04-10T04:11:38.067995Z","steps":["trace[1733445613] 'agreement among raft nodes before linearized reading' (duration: 119.867236ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:11:38.135348Z","caller":"traceutil/trace.go:171","msg":"trace[1138297667] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"126.437017ms","start":"2026-04-10T04:11:38.00887Z","end":"2026-04-10T04:11:38.135307Z","steps":["trace[1138297667] 'process raft request' (duration: 126.202075ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:11:40.158767Z","caller":"traceutil/trace.go:171","msg":"trace[693046259] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"145.425764ms","start":"2026-04-10T04:11:40.013303Z","end":"2026-04-10T04:11:40.158728Z","steps":["trace[693046259] 'process raft request' (duration: 145.247454ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:11:40.276021Z","caller":"traceutil/trace.go:171","msg":"trace[187832326] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"135.047778ms","start":"2026-04-10T04:11:40.140928Z","end":"2026-04-10T04:11:40.275975Z","steps":["trace[187832326] 'process raft request' (duration: 88.653211ms)","trace[187832326] 'compare' (duration: 46.139304ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:11:40.276008Z","caller":"traceutil/trace.go:171","msg":"trace[1701950312] linearizableReadLoop","detail":"{readStateIndex:1221; appliedIndex:1220; }","duration":"117.257299ms","start":"2026-04-10T04:11:40.158691Z","end":"2026-04-10T04:11:40.275948Z","steps":["trace[1701950312] 'read index received' (duration: 70.888153ms)","trace[1701950312] 'applied index is now lower than readState.Index' (duration: 46.367515ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:11:40.276281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.784857ms","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-10T04:11:40.276322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.447401ms","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-10T04:11:40.276397Z","caller":"traceutil/trace.go:171","msg":"trace[310048108] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1171; }","duration":"123.950485ms","start":"2026-04-10T04:11:40.152415Z","end":"2026-04-10T04:11:40.276366Z","steps":["trace[310048108] 'agreement among raft nodes before linearized reading' (duration: 123.566934ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:11:40.276426Z","caller":"traceutil/trace.go:171","msg":"trace[7819013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1171; }","duration":"136.598109ms","start":"2026-04-10T04:11:40.139803Z","end":"2026-04-10T04:11:40.276401Z","steps":["trace[7819013] 'agreement among raft nodes before linearized reading' (duration: 136.286632ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:11:40.495668Z","caller":"traceutil/trace.go:171","msg":"trace[411604194] linearizableReadLoop","detail":"{readStateIndex:1222; appliedIndex:1221; }","duration":"219.376395ms","start":"2026-04-10T04:11:40.276268Z","end":"2026-04-10T04:11:40.495644Z","steps":["trace[411604194] 'read index received' (duration: 209.879474ms)","trace[411604194] 'applied index is now lower than readState.Index' (duration: 9.495751ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:11:40.495817Z","caller":"traceutil/trace.go:171","msg":"trace[37605190] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"331.898ms","start":"2026-04-10T04:11:40.163863Z","end":"2026-04-10T04:11:40.495761Z","steps":["trace[37605190] 'process raft request' (duration: 322.318615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:11:40.495898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.903557ms","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-10T04:11:40.495935Z","caller":"traceutil/trace.go:171","msg":"trace[265685169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1172; }","duration":"335.996091ms","start":"2026-04-10T04:11:40.15993Z","end":"2026-04-10T04:11:40.495926Z","steps":["trace[265685169] 'agreement among raft nodes before linearized reading' (duration: 335.814942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:11:40.495967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:11:40.159916Z","time spent":"336.042744ms","remote":"127.0.0.1:60878","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-10T04:11:40.496196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:11:40.16385Z","time spent":"332.09386ms","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:11:40.534201Z","caller":"traceutil/trace.go:171","msg":"trace[1004157083] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"251.924228ms","start":"2026-04-10T04:11:40.282248Z","end":"2026-04-10T04:11:40.534172Z","steps":["trace[1004157083] 'process raft request' (duration: 251.842154ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:11:40.53424Z","caller":"traceutil/trace.go:171","msg":"trace[192026264] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"252.489018ms","start":"2026-04-10T04:11:40.281732Z","end":"2026-04-10T04:11:40.534221Z","steps":["trace[192026264] 'process raft request' (duration: 252.193483ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:11:58.628974Z","caller":"traceutil/trace.go:171","msg":"trace[1185472323] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"264.020419ms","start":"2026-04-10T04:11:58.364922Z","end":"2026-04-10T04:11:58.628942Z","steps":["trace[1185472323] 'process raft request' (duration: 263.809988ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:11:58.730806Z","caller":"traceutil/trace.go:171","msg":"trace[1462786] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1283; }","duration":"114.715989ms","start":"2026-04-10T04:11:58.616048Z","end":"2026-04-10T04:11:58.730764Z","steps":["trace[1462786] 'read index received' (duration: 12.630405ms)","trace[1462786] 'applied index is now lower than readState.Index' (duration: 102.084124ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:11:58.731009Z","caller":"traceutil/trace.go:171","msg":"trace[1188200503] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"295.01635ms","start":"2026-04-10T04:11:58.435962Z","end":"2026-04-10T04:11:58.730979Z","steps":["trace[1188200503] 'process raft request' (duration: 253.930746ms)","trace[1188200503] 'compare' (duration: 40.658891ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:11:58.731353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.278138ms","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-10T04:11:58.731413Z","caller":"traceutil/trace.go:171","msg":"trace[757380427] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1231; }","duration":"115.410325ms","start":"2026-04-10T04:11:58.615982Z","end":"2026-04-10T04:11:58.731393Z","steps":["trace[757380427] 'agreement among raft nodes before linearized reading' (duration: 114.995314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:11:58.731568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.514944ms","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-10T04:11:58.73167Z","caller":"traceutil/trace.go:171","msg":"trace[1041192473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1231; }","duration":"109.681653ms","start":"2026-04-10T04:11:58.621966Z","end":"2026-04-10T04:11:58.731648Z","steps":["trace[1041192473] 'agreement among raft nodes before linearized reading' (duration: 109.373427ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:12:10.863125Z","caller":"traceutil/trace.go:171","msg":"trace[765097031] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1358; }","duration":"132.196025ms","start":"2026-04-10T04:12:10.730894Z","end":"2026-04-10T04:12:10.86309Z","steps":["trace[765097031] 'read index received' (duration: 131.836377ms)","trace[765097031] 'applied index is now lower than readState.Index' (duration: 358.518µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:12:10.863494Z","caller":"traceutil/trace.go:171","msg":"trace[1728118638] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"166.717021ms","start":"2026-04-10T04:12:10.696761Z","end":"2026-04-10T04:12:10.863478Z","steps":["trace[1728118638] 'process raft request' (duration: 166.100547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:12:10.863558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.64721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:12:10.863664Z","caller":"traceutil/trace.go:171","msg":"trace[1701992133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1301; }","duration":"132.807299ms","start":"2026-04-10T04:12:10.730831Z","end":"2026-04-10T04:12:10.863638Z","steps":["trace[1701992133] 'agreement among raft nodes before linearized reading' (duration: 132.648241ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:12:27.231862Z","caller":"traceutil/trace.go:171","msg":"trace[504754176] linearizableReadLoop","detail":"{readStateIndex:1428; appliedIndex:1427; }","duration":"206.671904ms","start":"2026-04-10T04:12:27.025166Z","end":"2026-04-10T04:12:27.231837Z","steps":["trace[504754176] 'read index received' (duration: 206.467083ms)","trace[504754176] 'applied index is now lower than readState.Index' (duration: 203.891µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:12:27.231989Z","caller":"traceutil/trace.go:171","msg":"trace[929664988] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"220.588631ms","start":"2026-04-10T04:12:27.011334Z","end":"2026-04-10T04:12:27.231923Z","steps":["trace[929664988] 'process raft request' (duration: 220.326258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:12:27.232062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.738124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T04:12:27.232103Z","caller":"traceutil/trace.go:171","msg":"trace[278068616] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1366; }","duration":"200.804317ms","start":"2026-04-10T04:12:27.031286Z","end":"2026-04-10T04:12:27.23209Z","steps":["trace[278068616] 'agreement among raft nodes before linearized reading' (duration: 200.701462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:12:27.232135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.919257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" limit:500 ","response":"range_response_count:1 size:5912"} {"level":"info","ts":"2026-04-10T04:12:27.232221Z","caller":"traceutil/trace.go:171","msg":"trace[17595265] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:1366; }","duration":"207.072154ms","start":"2026-04-10T04:12:27.025129Z","end":"2026-04-10T04:12:27.232201Z","steps":["trace[17595265] 'agreement among raft nodes before linearized reading' (duration: 206.825471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:12:27.232428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.409843ms","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-10T04:12:27.2325Z","caller":"traceutil/trace.go:171","msg":"trace[701808549] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1366; }","duration":"180.55161ms","start":"2026-04-10T04:12:27.051925Z","end":"2026-04-10T04:12:27.232477Z","steps":["trace[701808549] 'agreement among raft nodes before linearized reading' (duration: 180.229723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:12:27.232802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.483267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" limit:500 ","response":"range_response_count:69 size:65992"} {"level":"info","ts":"2026-04-10T04:12:27.232873Z","caller":"traceutil/trace.go:171","msg":"trace[17031527] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:69; response_revision:1366; }","duration":"199.576122ms","start":"2026-04-10T04:12:27.033279Z","end":"2026-04-10T04:12:27.232855Z","steps":["trace[17031527] 'agreement among raft nodes before linearized reading' (duration: 199.020461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:12:27.536278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.640632ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T04:12:27.536412Z","caller":"traceutil/trace.go:171","msg":"trace[1608488188] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"298.108595ms","start":"2026-04-10T04:12:27.238279Z","end":"2026-04-10T04:12:27.536388Z","steps":["trace[1608488188] 'process raft request' (duration: 115.244311ms)","trace[1608488188] 'compare' (duration: 182.311395ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:12:27.550409Z","caller":"traceutil/trace.go:171","msg":"trace[903404489] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"312.094695ms","start":"2026-04-10T04:12:27.238287Z","end":"2026-04-10T04:12:27.550381Z","steps":["trace[903404489] 'process raft request' (duration: 311.909606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:12:27.550654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:12:27.238248Z","time spent":"312.244184ms","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:12:27.550942Z","caller":"traceutil/trace.go:171","msg":"trace[194886629] linearizableReadLoop","detail":"{readStateIndex:1433; appliedIndex:1428; }","duration":"235.419547ms","start":"2026-04-10T04:12:27.315499Z","end":"2026-04-10T04:12:27.550919Z","steps":["trace[194886629] 'read index received' (duration: 38.041763ms)","trace[194886629] 'applied index is now lower than readState.Index' (duration: 197.376054ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:12:27.550937Z","caller":"traceutil/trace.go:171","msg":"trace[995840250] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"312.589343ms","start":"2026-04-10T04:12:27.238307Z","end":"2026-04-10T04:12:27.550897Z","steps":["trace[995840250] 'process raft request' (duration: 312.023512ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:12:27.550964Z","caller":"traceutil/trace.go:171","msg":"trace[551139269] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"309.661226ms","start":"2026-04-10T04:12:27.241274Z","end":"2026-04-10T04:12:27.550935Z","steps":["trace[551139269] 'process raft request' (duration: 309.293196ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:12:27.550993Z","caller":"traceutil/trace.go:171","msg":"trace[1126110784] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"309.591142ms","start":"2026-04-10T04:12:27.241391Z","end":"2026-04-10T04:12:27.550982Z","steps":["trace[1126110784] 'process raft request' (duration: 309.462086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:12:27.551068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:12:27.241378Z","time spent":"309.657765ms","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:12:27.551101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:12:27.241258Z","time spent":"309.775482ms","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:12:27.551123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:12:27.238203Z","time spent":"312.821277ms","remote":"127.0.0.1:60878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:12:27.551538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.02378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" limit:500 ","response":"range_response_count:54 size:39220"} {"level":"info","ts":"2026-04-10T04:12:27.551616Z","caller":"traceutil/trace.go:171","msg":"trace[1961253702] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:54; response_revision:1371; }","duration":"236.160477ms","start":"2026-04-10T04:12:27.315435Z","end":"2026-04-10T04:12:27.551596Z","steps":["trace[1961253702] 'agreement among raft nodes before linearized reading' (duration: 235.600977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:12:27.595407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.257634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5912"} {"level":"info","ts":"2026-04-10T04:12:27.595499Z","caller":"traceutil/trace.go:171","msg":"trace[1221550237] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1371; }","duration":"255.410572ms","start":"2026-04-10T04:12:27.340069Z","end":"2026-04-10T04:12:27.59548Z","steps":["trace[1221550237] 'agreement among raft nodes before linearized reading' (duration: 255.121296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:12:27.595794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.262905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:1 size:3548"} {"level":"info","ts":"2026-04-10T04:12:27.595856Z","caller":"traceutil/trace.go:171","msg":"trace[1140667561] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:1; response_revision:1371; }","duration":"223.350059ms","start":"2026-04-10T04:12:27.37249Z","end":"2026-04-10T04:12:27.59584Z","steps":["trace[1140667561] 'agreement among raft nodes before linearized reading' (duration: 223.242284ms)"],"step_count":1}