{"level":"warn","ts":"2026-04-10T04:14:49.167005Z","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:14:49.167091Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.19: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.19:2380","--initial-cluster=instance=https://199.204.45.19:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.19:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.19: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:14:49.167157Z","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:14:49.16717Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-04-10T04:14:49.167191Z","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:14:49.168362Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"]} {"level":"info","ts":"2026-04-10T04:14:49.168442Z","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.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.19: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:14:49.174317Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.669875ms"} {"level":"info","ts":"2026-04-10T04:14:49.183225Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a847c2e9c7010db6","cluster-id":"2b11a68e27507b05"} {"level":"info","ts":"2026-04-10T04:14:49.1833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=()"} {"level":"info","ts":"2026-04-10T04:14:49.183326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 0"} {"level":"info","ts":"2026-04-10T04:14:49.183339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a847c2e9c7010db6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-10T04:14:49.183348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became follower at term 1"} {"level":"info","ts":"2026-04-10T04:14:49.183384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"warn","ts":"2026-04-10T04:14:49.188754Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-10T04:14:49.190155Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-10T04:14:49.192587Z","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:14:49.196357Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a847c2e9c7010db6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-10T04:14:49.197605Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a847c2e9c7010db6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-10T04:14:49.197753Z","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:14:49.198508Z","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:14:49.198564Z","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:14:49.199382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 switched to configuration voters=(12125874831040253366)"} {"level":"info","ts":"2026-04-10T04:14:49.199529Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","added-peer-id":"a847c2e9c7010db6","added-peer-peer-urls":["https://199.204.45.19:2380"]} {"level":"info","ts":"2026-04-10T04:14:49.206542Z","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:14:49.206671Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-04-10T04:14:49.206704Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.19:2380"} {"level":"info","ts":"2026-04-10T04:14:49.206977Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a847c2e9c7010db6","initial-advertise-peer-urls":["https://199.204.45.19:2380"],"listen-peer-urls":["https://199.204.45.19:2380"],"advertise-client-urls":["https://199.204.45.19:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.19:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-10T04:14:49.207016Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-10T04:14:49.484288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 is starting a new election at term 1"} {"level":"info","ts":"2026-04-10T04:14:49.484341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-10T04:14:49.484363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgPreVoteResp from a847c2e9c7010db6 at term 1"} {"level":"info","ts":"2026-04-10T04:14:49.48438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became candidate at term 2"} {"level":"info","ts":"2026-04-10T04:14:49.484388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 received MsgVoteResp from a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-04-10T04:14:49.4844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a847c2e9c7010db6 became leader at term 2"} {"level":"info","ts":"2026-04-10T04:14:49.48441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a847c2e9c7010db6 elected leader a847c2e9c7010db6 at term 2"} {"level":"info","ts":"2026-04-10T04:14:49.485232Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T04:14:49.486969Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a847c2e9c7010db6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.19:2379]}","request-path":"/0/members/a847c2e9c7010db6/attributes","cluster-id":"2b11a68e27507b05","publish-timeout":"7s"} {"level":"info","ts":"2026-04-10T04:14:49.487084Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-10T04:14:49.487136Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-10T04:14:49.489203Z","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:14:49.490374Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-10T04:14:49.490476Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-10T04:14:49.491008Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2b11a68e27507b05","local-member-id":"a847c2e9c7010db6","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T04:14:49.491167Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T04:14:49.491237Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-10T04:14:49.491817Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.19:2379"} {"level":"info","ts":"2026-04-10T04:14:53.910955Z","caller":"traceutil/trace.go:171","msg":"trace[802221383] linearizableReadLoop","detail":"{readStateIndex:218; appliedIndex:216; }","duration":"167.119652ms","start":"2026-04-10T04:14:53.743806Z","end":"2026-04-10T04:14:53.910925Z","steps":["trace[802221383] 'read index received' (duration: 90.555867ms)","trace[802221383] 'applied index is now lower than readState.Index' (duration: 76.562945ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:14:53.91112Z","caller":"traceutil/trace.go:171","msg":"trace[354691334] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"172.638806ms","start":"2026-04-10T04:14:53.738431Z","end":"2026-04-10T04:14:53.91107Z","steps":["trace[354691334] 'process raft request' (duration: 172.3644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:14:53.911169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.270636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3708"} {"level":"info","ts":"2026-04-10T04:14:53.911392Z","caller":"traceutil/trace.go:171","msg":"trace[1948350765] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:212; }","duration":"145.535071ms","start":"2026-04-10T04:14:53.765843Z","end":"2026-04-10T04:14:53.911378Z","steps":["trace[1948350765] 'agreement among raft nodes before linearized reading' (duration: 145.257066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:14:53.911296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.446379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:14:53.911592Z","caller":"traceutil/trace.go:171","msg":"trace[1436801840] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:0; response_revision:212; }","duration":"167.763374ms","start":"2026-04-10T04:14:53.743772Z","end":"2026-04-10T04:14:53.911535Z","steps":["trace[1436801840] 'agreement among raft nodes before linearized reading' (duration: 167.332197ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:14:54.11239Z","caller":"traceutil/trace.go:171","msg":"trace[931391266] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"192.629027ms","start":"2026-04-10T04:14:53.919724Z","end":"2026-04-10T04:14:54.112353Z","steps":["trace[931391266] 'process raft request' (duration: 192.436164ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:14:54.168058Z","caller":"traceutil/trace.go:171","msg":"trace[1828106117] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"184.805142ms","start":"2026-04-10T04:14:53.983234Z","end":"2026-04-10T04:14:54.168039Z","steps":["trace[1828106117] 'process raft request' (duration: 184.395255ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:14:54.414369Z","caller":"traceutil/trace.go:171","msg":"trace[1644566974] linearizableReadLoop","detail":"{readStateIndex:223; appliedIndex:222; }","duration":"198.540628ms","start":"2026-04-10T04:14:54.215795Z","end":"2026-04-10T04:14:54.414336Z","steps":["trace[1644566974] 'read index received' (duration: 185.695939ms)","trace[1644566974] 'applied index is now lower than readState.Index' (duration: 12.843649ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:14:54.414436Z","caller":"traceutil/trace.go:171","msg":"trace[600084796] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"200.406482ms","start":"2026-04-10T04:14:54.213982Z","end":"2026-04-10T04:14:54.414388Z","steps":["trace[600084796] 'process raft request' (duration: 187.576384ms)","trace[600084796] 'compare' (duration: 12.596254ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:14:54.414724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.874164ms","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:14:54.414845Z","caller":"traceutil/trace.go:171","msg":"trace[709732233] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:217; }","duration":"199.079617ms","start":"2026-04-10T04:14:54.21575Z","end":"2026-04-10T04:14:54.41483Z","steps":["trace[709732233] 'agreement among raft nodes before linearized reading' (duration: 198.7045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:12.980843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.102724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.19\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-10T04:15:12.98098Z","caller":"traceutil/trace.go:171","msg":"trace[1311151004] range","detail":"{range_begin:/registry/masterleases/199.204.45.19; range_end:; response_count:1; response_revision:418; }","duration":"310.250778ms","start":"2026-04-10T04:15:12.670676Z","end":"2026-04-10T04:15:12.980926Z","steps":["trace[1311151004] 'range keys from in-memory index tree' (duration: 309.969501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:12.981022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:12.67066Z","time spent":"310.352609ms","remote":"127.0.0.1:58962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.19\" "} {"level":"warn","ts":"2026-04-10T04:15:12.981008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.853896ms","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:15:12.981114Z","caller":"traceutil/trace.go:171","msg":"trace[1901108943] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:418; }","duration":"981.012709ms","start":"2026-04-10T04:15:12.000078Z","end":"2026-04-10T04:15:12.98109Z","steps":["trace[1901108943] 'range keys from in-memory index tree' (duration: 980.747064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:12.98116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:12.000054Z","time spent":"981.097211ms","remote":"127.0.0.1:59224","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:15:13.503099Z","caller":"traceutil/trace.go:171","msg":"trace[1049871191] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"154.551087ms","start":"2026-04-10T04:15:13.348515Z","end":"2026-04-10T04:15:13.503066Z","steps":["trace[1049871191] 'process raft request' (duration: 154.338644ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:15.284794Z","caller":"traceutil/trace.go:171","msg":"trace[1871938070] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:437; }","duration":"111.970315ms","start":"2026-04-10T04:15:15.1728Z","end":"2026-04-10T04:15:15.284771Z","steps":["trace[1871938070] 'read index received' (duration: 97.331573ms)","trace[1871938070] 'applied index is now lower than readState.Index' (duration: 14.637922ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:15:15.284832Z","caller":"traceutil/trace.go:171","msg":"trace[1183718274] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"139.898785ms","start":"2026-04-10T04:15:15.144895Z","end":"2026-04-10T04:15:15.284794Z","steps":["trace[1183718274] 'process raft request' (duration: 125.236433ms)","trace[1183718274] 'compare' (duration: 14.50229ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:15:15.285054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.205519ms","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:15:15.28519Z","caller":"traceutil/trace.go:171","msg":"trace[1588339518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:426; }","duration":"112.411883ms","start":"2026-04-10T04:15:15.172752Z","end":"2026-04-10T04:15:15.285164Z","steps":["trace[1588339518] 'agreement among raft nodes before linearized reading' (duration: 112.097797ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:17.453297Z","caller":"traceutil/trace.go:171","msg":"trace[1584482331] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:442; }","duration":"158.540562ms","start":"2026-04-10T04:15:17.294698Z","end":"2026-04-10T04:15:17.453239Z","steps":["trace[1584482331] 'read index received' (duration: 158.292187ms)","trace[1584482331] 'applied index is now lower than readState.Index' (duration: 243.744µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:15:17.453525Z","caller":"traceutil/trace.go:171","msg":"trace[1867687448] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"160.361147ms","start":"2026-04-10T04:15:17.293109Z","end":"2026-04-10T04:15:17.45347Z","steps":["trace[1867687448] 'process raft request' (duration: 159.922079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:17.454238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.750666ms","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:15:17.454328Z","caller":"traceutil/trace.go:171","msg":"trace[493553015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:430; }","duration":"159.675465ms","start":"2026-04-10T04:15:17.294636Z","end":"2026-04-10T04:15:17.454312Z","steps":["trace[493553015] 'agreement among raft nodes before linearized reading' (duration: 158.757357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:17.862053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.619215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T04:15:17.862419Z","caller":"traceutil/trace.go:171","msg":"trace[2059702114] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:443; }","duration":"347.331128ms","start":"2026-04-10T04:15:17.515053Z","end":"2026-04-10T04:15:17.862384Z","steps":["trace[2059702114] 'read index received' (duration: 72.752775ms)","trace[2059702114] 'applied index is now lower than readState.Index' (duration: 274.575733ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:15:17.862608Z","caller":"traceutil/trace.go:171","msg":"trace[2051368803] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"402.576887ms","start":"2026-04-10T04:15:17.459995Z","end":"2026-04-10T04:15:17.862572Z","steps":["trace[2051368803] 'process raft request' (duration: 127.835631ms)","trace[2051368803] 'compare' (duration: 273.485732ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:15:17.862815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:17.459969Z","time spent":"402.709569ms","remote":"127.0.0.1:59224","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:15:17.866528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.432235ms","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:15:17.866611Z","caller":"traceutil/trace.go:171","msg":"trace[933988040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:431; }","duration":"351.547527ms","start":"2026-04-10T04:15:17.515044Z","end":"2026-04-10T04:15:17.866592Z","steps":["trace[933988040] 'agreement among raft nodes before linearized reading' (duration: 347.488871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:17.866651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:17.514989Z","time spent":"351.654389ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-10T04:15:19.358232Z","caller":"traceutil/trace.go:171","msg":"trace[1654308828] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"189.057571ms","start":"2026-04-10T04:15:19.169143Z","end":"2026-04-10T04:15:19.3582Z","steps":["trace[1654308828] 'process raft request' (duration: 188.855868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:19.613041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.875184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-10T04:15:19.613177Z","caller":"traceutil/trace.go:171","msg":"trace[955898984] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:433; }","duration":"155.071917ms","start":"2026-04-10T04:15:19.458076Z","end":"2026-04-10T04:15:19.613148Z","steps":["trace[955898984] 'range keys from in-memory index tree' (duration: 154.726451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:19.613044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.200726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/cilium-config-agent\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:19.613276Z","caller":"traceutil/trace.go:171","msg":"trace[106092774] range","detail":"{range_begin:/registry/roles/kube-system/cilium-config-agent; range_end:; response_count:0; response_revision:433; }","duration":"238.487172ms","start":"2026-04-10T04:15:19.374762Z","end":"2026-04-10T04:15:19.613249Z","steps":["trace[106092774] 'range keys from in-memory index tree' (duration: 238.105394ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:20.337304Z","caller":"traceutil/trace.go:171","msg":"trace[219575099] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:481; }","duration":"212.917315ms","start":"2026-04-10T04:15:20.124348Z","end":"2026-04-10T04:15:20.337265Z","steps":["trace[219575099] 'read index received' (duration: 188.713714ms)","trace[219575099] 'applied index is now lower than readState.Index' (duration: 24.201891ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:15:20.337528Z","caller":"traceutil/trace.go:171","msg":"trace[1627024398] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"245.903939ms","start":"2026-04-10T04:15:20.091589Z","end":"2026-04-10T04:15:20.337493Z","steps":["trace[1627024398] 'process raft request' (duration: 221.423653ms)","trace[1627024398] 'compare' (duration: 23.922005ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:15:20.339304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.158018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"warn","ts":"2026-04-10T04:15:20.339426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.041714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329948"} {"level":"info","ts":"2026-04-10T04:15:20.339493Z","caller":"traceutil/trace.go:171","msg":"trace[2031067363] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:468; }","duration":"215.149197ms","start":"2026-04-10T04:15:20.124316Z","end":"2026-04-10T04:15:20.339465Z","steps":["trace[2031067363] 'agreement among raft nodes before linearized reading' (duration: 213.17948ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:20.33954Z","caller":"traceutil/trace.go:171","msg":"trace[2042001686] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:468; }","duration":"185.459224ms","start":"2026-04-10T04:15:20.153999Z","end":"2026-04-10T04:15:20.339458Z","steps":["trace[2042001686] 'agreement among raft nodes before linearized reading' (duration: 185.031666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:20.339335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.220199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-04-10T04:15:20.340025Z","caller":"traceutil/trace.go:171","msg":"trace[884434638] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:468; }","duration":"185.974574ms","start":"2026-04-10T04:15:20.154025Z","end":"2026-04-10T04:15:20.34Z","steps":["trace[884434638] 'agreement among raft nodes before linearized reading' (duration: 185.126458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:21.308608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.750238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:21.308729Z","caller":"traceutil/trace.go:171","msg":"trace[350551968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:468; }","duration":"403.938141ms","start":"2026-04-10T04:15:20.904765Z","end":"2026-04-10T04:15:21.308703Z","steps":["trace[350551968] 'range keys from in-memory index tree' (duration: 403.647887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:21.308777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:20.904737Z","time spent":"404.028273ms","remote":"127.0.0.1:58952","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:15:21.309111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.664615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329948"} {"level":"info","ts":"2026-04-10T04:15:21.309174Z","caller":"traceutil/trace.go:171","msg":"trace[170080381] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:468; }","duration":"847.782228ms","start":"2026-04-10T04:15:20.461378Z","end":"2026-04-10T04:15:21.30916Z","steps":["trace[170080381] 'range keys from in-memory index tree' (duration: 846.994633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:21.309224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:20.461347Z","time spent":"847.865139ms","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329971,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-04-10T04:15:22.842563Z","caller":"traceutil/trace.go:171","msg":"trace[1883329901] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"134.505105ms","start":"2026-04-10T04:15:22.708022Z","end":"2026-04-10T04:15:22.842527Z","steps":["trace[1883329901] 'read index received' (duration: 134.370522ms)","trace[1883329901] 'applied index is now lower than readState.Index' (duration: 132.953µs)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:15:22.842772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.705408ms","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:15:22.842894Z","caller":"traceutil/trace.go:171","msg":"trace[979272353] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:475; }","duration":"134.899532ms","start":"2026-04-10T04:15:22.707965Z","end":"2026-04-10T04:15:22.842864Z","steps":["trace[979272353] 'agreement among raft nodes before linearized reading' (duration: 134.692168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:23.264061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.723488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T04:15:23.264675Z","caller":"traceutil/trace.go:171","msg":"trace[1382746623] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:490; }","duration":"406.130193ms","start":"2026-04-10T04:15:22.858521Z","end":"2026-04-10T04:15:23.264651Z","steps":["trace[1382746623] 'read index received' (duration: 204.745434ms)","trace[1382746623] 'applied index is now lower than readState.Index' (duration: 201.383199ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:15:23.264767Z","caller":"traceutil/trace.go:171","msg":"trace[1797756143] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"420.788905ms","start":"2026-04-10T04:15:22.84392Z","end":"2026-04-10T04:15:23.264709Z","steps":["trace[1797756143] 'process raft request' (duration: 219.316124ms)","trace[1797756143] 'compare' (duration: 200.527064ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:15:23.265151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:22.843893Z","time spent":"420.97143ms","remote":"127.0.0.1:58962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:15:23.265146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.590682ms","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:15:23.265253Z","caller":"traceutil/trace.go:171","msg":"trace[2061045380] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:476; }","duration":"406.743205ms","start":"2026-04-10T04:15:22.858484Z","end":"2026-04-10T04:15:23.265227Z","steps":["trace[2061045380] 'agreement among raft nodes before linearized reading' (duration: 406.258345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:23.265221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.233558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T04:15:23.265317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:22.858473Z","time spent":"406.832727ms","remote":"127.0.0.1:59018","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":"info","ts":"2026-04-10T04:15:23.265373Z","caller":"traceutil/trace.go:171","msg":"trace[953414999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:476; }","duration":"360.454053ms","start":"2026-04-10T04:15:22.904892Z","end":"2026-04-10T04:15:23.265346Z","steps":["trace[953414999] 'agreement among raft nodes before linearized reading' (duration: 360.212678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:23.265744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:22.904872Z","time spent":"360.85645ms","remote":"127.0.0.1:58952","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:15:24.954151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.870486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10870"} {"level":"info","ts":"2026-04-10T04:15:24.954293Z","caller":"traceutil/trace.go:171","msg":"trace[1228210154] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:551; }","duration":"176.045728ms","start":"2026-04-10T04:15:24.778197Z","end":"2026-04-10T04:15:24.954242Z","steps":["trace[1228210154] 'range keys from in-memory index tree' (duration: 175.666132ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:25.104944Z","caller":"traceutil/trace.go:171","msg":"trace[1657377764] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"139.188612ms","start":"2026-04-10T04:15:24.965723Z","end":"2026-04-10T04:15:25.104912Z","steps":["trace[1657377764] 'read index received' (duration: 138.768704ms)","trace[1657377764] 'applied index is now lower than readState.Index' (duration: 418.738µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:15:25.104985Z","caller":"traceutil/trace.go:171","msg":"trace[1761553935] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"156.886222ms","start":"2026-04-10T04:15:24.948063Z","end":"2026-04-10T04:15:25.104949Z","steps":["trace[1761553935] 'process raft request' (duration: 156.419193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:25.105202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.449248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:59631"} {"level":"info","ts":"2026-04-10T04:15:25.105246Z","caller":"traceutil/trace.go:171","msg":"trace[540033666] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:552; }","duration":"139.533129ms","start":"2026-04-10T04:15:24.965699Z","end":"2026-04-10T04:15:25.105232Z","steps":["trace[540033666] 'agreement among raft nodes before linearized reading' (duration: 139.339385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:25.480763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.657405ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T04:15:25.480901Z","caller":"traceutil/trace.go:171","msg":"trace[804015479] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"454.771619ms","start":"2026-04-10T04:15:25.026094Z","end":"2026-04-10T04:15:25.480866Z","steps":["trace[804015479] 'process raft request' (duration: 332.92528ms)","trace[804015479] 'compare' (duration: 121.514123ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:15:25.48098Z","caller":"traceutil/trace.go:171","msg":"trace[1198715001] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"322.199539ms","start":"2026-04-10T04:15:25.158761Z","end":"2026-04-10T04:15:25.48096Z","steps":["trace[1198715001] 'process raft request' (duration: 322.119858ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:25.481001Z","caller":"traceutil/trace.go:171","msg":"trace[535657307] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:568; }","duration":"354.259097ms","start":"2026-04-10T04:15:25.126695Z","end":"2026-04-10T04:15:25.480954Z","steps":["trace[535657307] 'read index received' (duration: 232.227905ms)","trace[535657307] 'applied index is now lower than readState.Index' (duration: 122.028572ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:15:25.481065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:25.158735Z","time spent":"322.296432ms","remote":"127.0.0.1:58978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:15:25.481126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:25.026062Z","time spent":"454.966103ms","remote":"127.0.0.1:58978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:15:25.481143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.45058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-04-10T04:15:25.481267Z","caller":"traceutil/trace.go:171","msg":"trace[698487158] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:554; }","duration":"354.626575ms","start":"2026-04-10T04:15:25.126633Z","end":"2026-04-10T04:15:25.481259Z","steps":["trace[698487158] 'agreement among raft nodes before linearized reading' (duration: 354.39193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:25.481285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:25.126613Z","time spent":"354.667635ms","remote":"127.0.0.1:58978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":80726,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-04-10T04:15:25.652183Z","caller":"traceutil/trace.go:171","msg":"trace[1123428724] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"112.838741ms","start":"2026-04-10T04:15:25.539323Z","end":"2026-04-10T04:15:25.652162Z","steps":["trace[1123428724] 'process raft request' (duration: 112.147148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.058291Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150296666924022,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-10T04:15:30.48608Z","caller":"traceutil/trace.go:171","msg":"trace[723557271] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"928.731315ms","start":"2026-04-10T04:15:29.557319Z","end":"2026-04-10T04:15:30.48605Z","steps":["trace[723557271] 'read index received' (duration: 927.829758ms)","trace[723557271] 'applied index is now lower than readState.Index' (duration: 900.637µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:15:30.48624Z","caller":"traceutil/trace.go:171","msg":"trace[604124364] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"933.801059ms","start":"2026-04-10T04:15:29.552386Z","end":"2026-04-10T04:15:30.486187Z","steps":["trace[604124364] 'process raft request' (duration: 933.100407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.486516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.145402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:30.486667Z","caller":"traceutil/trace.go:171","msg":"trace[1362686474] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:578; }","duration":"929.327706ms","start":"2026-04-10T04:15:29.557315Z","end":"2026-04-10T04:15:30.486642Z","steps":["trace[1362686474] 'agreement among raft nodes before linearized reading' (duration: 929.067431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.486723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.557267Z","time spent":"929.442499ms","remote":"127.0.0.1:49798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-10T04:15:30.48673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.90912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:30.486834Z","caller":"traceutil/trace.go:171","msg":"trace[474319673] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:578; }","duration":"768.096564ms","start":"2026-04-10T04:15:29.71872Z","end":"2026-04-10T04:15:30.486817Z","steps":["trace[474319673] 'agreement among raft nodes before linearized reading' (duration: 767.821368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.486869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.595335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:30.486911Z","caller":"traceutil/trace.go:171","msg":"trace[416176349] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:578; }","duration":"745.645616ms","start":"2026-04-10T04:15:29.741252Z","end":"2026-04-10T04:15:30.486898Z","steps":["trace[416176349] 'agreement among raft nodes before linearized reading' (duration: 745.552714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.486915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.718708Z","time spent":"768.188666ms","remote":"127.0.0.1:49828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-10T04:15:30.486946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.741249Z","time spent":"745.688647ms","remote":"127.0.0.1:49836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-10T04:15:30.487872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.782449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:30.487945Z","caller":"traceutil/trace.go:171","msg":"trace[385782303] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:578; }","duration":"731.826768ms","start":"2026-04-10T04:15:29.756097Z","end":"2026-04-10T04:15:30.487924Z","steps":["trace[385782303] 'agreement among raft nodes before linearized reading' (duration: 730.723298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.486738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.552362Z","time spent":"934.157945ms","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:15:30.488058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.690551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:30.488116Z","caller":"traceutil/trace.go:171","msg":"trace[1246293668] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:578; }","duration":"930.751252ms","start":"2026-04-10T04:15:29.557348Z","end":"2026-04-10T04:15:30.488099Z","steps":["trace[1246293668] 'agreement among raft nodes before linearized reading' (duration: 929.697143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.488162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.557346Z","time spent":"930.794423ms","remote":"127.0.0.1:49798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 "} {"level":"warn","ts":"2026-04-10T04:15:30.48851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.227035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:30.488607Z","caller":"traceutil/trace.go:171","msg":"trace[207519532] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:578; }","duration":"747.331807ms","start":"2026-04-10T04:15:29.741238Z","end":"2026-04-10T04:15:30.48857Z","steps":["trace[207519532] 'agreement among raft nodes before linearized reading' (duration: 746.987151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.488659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.741217Z","time spent":"747.428779ms","remote":"127.0.0.1:49836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-04-10T04:15:30.488963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.635553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:30.489008Z","caller":"traceutil/trace.go:171","msg":"trace[902825734] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:578; }","duration":"769.683354ms","start":"2026-04-10T04:15:29.7193Z","end":"2026-04-10T04:15:30.488983Z","steps":["trace[902825734] 'agreement among raft nodes before linearized reading' (duration: 769.46065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.489042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.719286Z","time spent":"769.746495ms","remote":"127.0.0.1:49828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-04-10T04:15:30.489201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.392367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T04:15:30.48919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.327847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:30.489263Z","caller":"traceutil/trace.go:171","msg":"trace[209762515] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:578; }","duration":"733.456509ms","start":"2026-04-10T04:15:29.755778Z","end":"2026-04-10T04:15:30.489234Z","steps":["trace[209762515] 'agreement among raft nodes before linearized reading' (duration: 733.380098ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:30.489281Z","caller":"traceutil/trace.go:171","msg":"trace[160545234] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:578; }","duration":"733.439588ms","start":"2026-04-10T04:15:29.755811Z","end":"2026-04-10T04:15:30.489251Z","steps":["trace[160545234] 'agreement among raft nodes before linearized reading' (duration: 733.294136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.489306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.525508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T04:15:30.489302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.75577Z","time spent":"733.52039ms","remote":"127.0.0.1:49838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-04-10T04:15:30.489323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.755799Z","time spent":"733.51504ms","remote":"127.0.0.1:49838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-04-10T04:15:30.489359Z","caller":"traceutil/trace.go:171","msg":"trace[1676117367] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:578; }","duration":"770.58595ms","start":"2026-04-10T04:15:29.718748Z","end":"2026-04-10T04:15:30.489333Z","steps":["trace[1676117367] 'agreement among raft nodes before linearized reading' (duration: 770.525939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.489394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.718735Z","time spent":"770.649991ms","remote":"127.0.0.1:49828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-04-10T04:15:30.488009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.756088Z","time spent":"731.900759ms","remote":"127.0.0.1:49836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"warn","ts":"2026-04-10T04:15:30.489867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.590419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jx2l7\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-04-10T04:15:30.489862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.54151ms","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:15:30.489901Z","caller":"traceutil/trace.go:171","msg":"trace[1868105107] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jx2l7; range_end:; response_count:1; response_revision:578; }","duration":"466.647631ms","start":"2026-04-10T04:15:30.023233Z","end":"2026-04-10T04:15:30.489881Z","steps":["trace[1868105107] 'agreement among raft nodes before linearized reading' (duration: 466.513178ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:30.489911Z","caller":"traceutil/trace.go:171","msg":"trace[1880660825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"552.605541ms","start":"2026-04-10T04:15:29.937293Z","end":"2026-04-10T04:15:30.489899Z","steps":["trace[1880660825] 'agreement among raft nodes before linearized reading' (duration: 552.440348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.48992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:30.023217Z","time spent":"466.697961ms","remote":"127.0.0.1:59136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-jx2l7\" "} {"level":"warn","ts":"2026-04-10T04:15:30.489943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.203041ms","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:15:30.489952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.937282Z","time spent":"552.653742ms","remote":"127.0.0.1:59224","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:15:30.489998Z","caller":"traceutil/trace.go:171","msg":"trace[1286658657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"540.279301ms","start":"2026-04-10T04:15:29.949691Z","end":"2026-04-10T04:15:30.489971Z","steps":["trace[1286658657] 'agreement among raft nodes before linearized reading' (duration: 540.105888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.490034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.949666Z","time spent":"540.358613ms","remote":"127.0.0.1:59224","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:15:30.490236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.543136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:30.490303Z","caller":"traceutil/trace.go:171","msg":"trace[1275187961] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:578; }","duration":"830.610138ms","start":"2026-04-10T04:15:29.65966Z","end":"2026-04-10T04:15:30.49027Z","steps":["trace[1275187961] 'agreement among raft nodes before linearized reading' (duration: 830.539847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.490328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.659654Z","time spent":"830.667329ms","remote":"127.0.0.1:49814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-10T04:15:30.490348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.342555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T04:15:30.490355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.118784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:30.490415Z","caller":"traceutil/trace.go:171","msg":"trace[1081399656] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:578; }","duration":"793.402876ms","start":"2026-04-10T04:15:29.696983Z","end":"2026-04-10T04:15:30.490386Z","steps":["trace[1081399656] 'agreement among raft nodes before linearized reading' (duration: 793.251203ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:30.490444Z","caller":"traceutil/trace.go:171","msg":"trace[1810817572] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:578; }","duration":"877.216056ms","start":"2026-04-10T04:15:29.613207Z","end":"2026-04-10T04:15:30.490423Z","steps":["trace[1810817572] 'agreement among raft nodes before linearized reading' (duration: 877.084563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.490458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.696971Z","time spent":"793.474837ms","remote":"127.0.0.1:49824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-10T04:15:30.490478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.925955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:30.490535Z","caller":"traceutil/trace.go:171","msg":"trace[434960320] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:578; }","duration":"808.028117ms","start":"2026-04-10T04:15:29.682496Z","end":"2026-04-10T04:15:30.490525Z","steps":["trace[434960320] 'agreement among raft nodes before linearized reading' (duration: 807.954116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.490522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.613185Z","time spent":"877.292127ms","remote":"127.0.0.1:49804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-10T04:15:30.491029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.13354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:30.491063Z","caller":"traceutil/trace.go:171","msg":"trace[1915663689] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:578; }","duration":"793.214042ms","start":"2026-04-10T04:15:29.697839Z","end":"2026-04-10T04:15:30.491053Z","steps":["trace[1915663689] 'agreement among raft nodes before linearized reading' (duration: 793.152561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.491098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.697817Z","time spent":"793.266203ms","remote":"127.0.0.1:49824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-10T04:15:30.491297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.20333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:30.49132Z","caller":"traceutil/trace.go:171","msg":"trace[751314232] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:578; }","duration":"794.285492ms","start":"2026-04-10T04:15:29.697025Z","end":"2026-04-10T04:15:30.491311Z","steps":["trace[751314232] 'agreement among raft nodes before linearized reading' (duration: 794.16676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.491346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.696997Z","time spent":"794.342643ms","remote":"127.0.0.1:49824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-10T04:15:30.491588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.101063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:30.491662Z","caller":"traceutil/trace.go:171","msg":"trace[1781942606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"587.192514ms","start":"2026-04-10T04:15:29.904448Z","end":"2026-04-10T04:15:30.49164Z","steps":["trace[1781942606] 'agreement among raft nodes before linearized reading' (duration: 586.96266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.491669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.496405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:30.491706Z","caller":"traceutil/trace.go:171","msg":"trace[1704724380] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:578; }","duration":"809.559036ms","start":"2026-04-10T04:15:29.682131Z","end":"2026-04-10T04:15:30.49169Z","steps":["trace[1704724380] 'agreement among raft nodes before linearized reading' (duration: 809.505815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.491706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.904435Z","time spent":"587.251975ms","remote":"127.0.0.1:58952","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:15:30.491728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.682113Z","time spent":"809.608737ms","remote":"127.0.0.1:49822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 "} {"level":"warn","ts":"2026-04-10T04:15:30.491866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.360312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:30.491894Z","caller":"traceutil/trace.go:171","msg":"trace[436591649] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:578; }","duration":"809.413533ms","start":"2026-04-10T04:15:29.682473Z","end":"2026-04-10T04:15:30.491887Z","steps":["trace[436591649] 'agreement among raft nodes before linearized reading' (duration: 809.370402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.491915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.68246Z","time spent":"809.448354ms","remote":"127.0.0.1:49822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 "} {"level":"warn","ts":"2026-04-10T04:15:30.492174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.797618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:30.492205Z","caller":"traceutil/trace.go:171","msg":"trace[876247107] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:578; }","duration":"934.827578ms","start":"2026-04-10T04:15:29.557362Z","end":"2026-04-10T04:15:30.49219Z","steps":["trace[876247107] 'agreement among raft nodes before linearized reading' (duration: 934.784177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.492225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.55736Z","time spent":"934.859059ms","remote":"127.0.0.1:49798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-10T04:15:30.492388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.39628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T04:15:30.492417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.37482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:30.492442Z","caller":"traceutil/trace.go:171","msg":"trace[1084290330] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:578; }","duration":"833.468412ms","start":"2026-04-10T04:15:29.658956Z","end":"2026-04-10T04:15:30.492425Z","steps":["trace[1084290330] 'agreement among raft nodes before linearized reading' (duration: 833.390391ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:30.492466Z","caller":"traceutil/trace.go:171","msg":"trace[2120574650] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:578; }","duration":"833.454942ms","start":"2026-04-10T04:15:29.658986Z","end":"2026-04-10T04:15:30.492441Z","steps":["trace[2120574650] 'agreement among raft nodes before linearized reading' (duration: 833.36316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.492488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.658949Z","time spent":"833.519863ms","remote":"127.0.0.1:49814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-10T04:15:30.492492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.658964Z","time spent":"833.520342ms","remote":"127.0.0.1:49814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-10T04:15:30.495849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.285776ms","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:15:30.495892Z","caller":"traceutil/trace.go:171","msg":"trace[2122814988] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"921.352458ms","start":"2026-04-10T04:15:29.574522Z","end":"2026-04-10T04:15:30.495875Z","steps":["trace[2122814988] 'agreement among raft nodes before linearized reading' (duration: 917.545397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.495919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.574506Z","time spent":"921.406959ms","remote":"127.0.0.1:59224","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:15:30.496324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.340065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-10T04:15:30.496361Z","caller":"traceutil/trace.go:171","msg":"trace[58098407] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"734.379245ms","start":"2026-04-10T04:15:29.761966Z","end":"2026-04-10T04:15:30.496345Z","steps":["trace[58098407] 'agreement among raft nodes before linearized reading' (duration: 734.202322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.496381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.761958Z","time spent":"734.418456ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-10T04:15:30.497076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.994368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:30.497118Z","caller":"traceutil/trace.go:171","msg":"trace[641325647] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:578; }","duration":"883.990651ms","start":"2026-04-10T04:15:29.613116Z","end":"2026-04-10T04:15:30.497107Z","steps":["trace[641325647] 'agreement among raft nodes before linearized reading' (duration: 878.980977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.497099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.768769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:30.497249Z","caller":"traceutil/trace.go:171","msg":"trace[103151284] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:578; }","duration":"882.982513ms","start":"2026-04-10T04:15:29.614237Z","end":"2026-04-10T04:15:30.49722Z","steps":["trace[103151284] 'agreement among raft nodes before linearized reading' (duration: 881.130229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:30.497255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.682474Z","time spent":"808.077108ms","remote":"127.0.0.1:49822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-04-10T04:15:30.497292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.614216Z","time spent":"883.066404ms","remote":"127.0.0.1:49804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"warn","ts":"2026-04-10T04:15:30.497158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:29.6131Z","time spent":"884.042053ms","remote":"127.0.0.1:49804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 "} {"level":"info","ts":"2026-04-10T04:15:30.630428Z","caller":"traceutil/trace.go:171","msg":"trace[926516975] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"132.014858ms","start":"2026-04-10T04:15:30.498374Z","end":"2026-04-10T04:15:30.630388Z","steps":["trace[926516975] 'read index received' (duration: 101.935218ms)","trace[926516975] 'applied index is now lower than readState.Index' (duration: 30.07834ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:15:30.630603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.192421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:30.630646Z","caller":"traceutil/trace.go:171","msg":"trace[1286505689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"132.280474ms","start":"2026-04-10T04:15:30.498353Z","end":"2026-04-10T04:15:30.630633Z","steps":["trace[1286505689] 'agreement among raft nodes before linearized reading' (duration: 132.176712ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:30.630818Z","caller":"traceutil/trace.go:171","msg":"trace[1979461658] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"132.342304ms","start":"2026-04-10T04:15:30.498431Z","end":"2026-04-10T04:15:30.630773Z","steps":["trace[1979461658] 'process raft request' (duration: 101.881497ms)","trace[1979461658] 'compare' (duration: 29.693083ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:15:31.374575Z","caller":"traceutil/trace.go:171","msg":"trace[326706220] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"873.905005ms","start":"2026-04-10T04:15:30.500633Z","end":"2026-04-10T04:15:31.374538Z","steps":["trace[326706220] 'process raft request' (duration: 873.521987ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:31.374635Z","caller":"traceutil/trace.go:171","msg":"trace[1697210807] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"823.403453ms","start":"2026-04-10T04:15:30.551203Z","end":"2026-04-10T04:15:31.374607Z","steps":["trace[1697210807] 'process raft request' (duration: 823.356082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:31.374788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:30.500482Z","time spent":"874.19621ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:15:31.374911Z","caller":"traceutil/trace.go:171","msg":"trace[1571179674] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"872.340144ms","start":"2026-04-10T04:15:30.502502Z","end":"2026-04-10T04:15:31.374842Z","steps":["trace[1571179674] 'process raft request' (duration: 871.905406ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:31.375008Z","caller":"traceutil/trace.go:171","msg":"trace[86237459] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"871.605061ms","start":"2026-04-10T04:15:30.503394Z","end":"2026-04-10T04:15:31.374999Z","steps":["trace[86237459] 'process raft request' (duration: 871.064281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:31.375126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:30.503387Z","time spent":"871.659482ms","remote":"127.0.0.1:59224","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:15:31.375137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:30.502491Z","time spent":"872.503008ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:15:31.374933Z","caller":"traceutil/trace.go:171","msg":"trace[762371752] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"868.502943ms","start":"2026-04-10T04:15:30.506412Z","end":"2026-04-10T04:15:31.374915Z","steps":["trace[762371752] 'process raft request' (duration: 868.082065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:31.375446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:30.506393Z","time spent":"869.000461ms","remote":"127.0.0.1:59136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:15:31.374931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:30.551176Z","time spent":"823.675908ms","remote":"127.0.0.1:59296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-10T04:15:32.40559Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150296666924033,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T04:15:32.906605Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150296666924033,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T04:15:33.406885Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150296666924033,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T04:15:33.863651Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.082905217s","expected-duration":"1s"} {"level":"info","ts":"2026-04-10T04:15:33.864785Z","caller":"traceutil/trace.go:171","msg":"trace[1508886282] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"2.085521206s","start":"2026-04-10T04:15:31.779232Z","end":"2026-04-10T04:15:33.864753Z","steps":["trace[1508886282] 'process raft request' (duration: 2.084968766s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:33.864992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:31.779203Z","time spent":"2.085678818s","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:15:33.868733Z","caller":"traceutil/trace.go:171","msg":"trace[790581399] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:601; }","duration":"1.964119096s","start":"2026-04-10T04:15:31.904584Z","end":"2026-04-10T04:15:33.868703Z","steps":["trace[790581399] 'read index received' (duration: 1.959383858s)","trace[790581399] 'applied index is now lower than readState.Index' (duration: 4.734288ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:15:33.868878Z","caller":"traceutil/trace.go:171","msg":"trace[38164064] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.689536793s","start":"2026-04-10T04:15:32.179321Z","end":"2026-04-10T04:15:33.868858Z","steps":["trace[38164064] 'process raft request' (duration: 1.689250818s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:33.868994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:32.179294Z","time spent":"1.689646985s","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:15:33.869172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233958068s","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:15:33.869227Z","caller":"traceutil/trace.go:171","msg":"trace[2065573091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"1.23407047s","start":"2026-04-10T04:15:32.635142Z","end":"2026-04-10T04:15:33.869212Z","steps":["trace[2065573091] 'agreement among raft nodes before linearized reading' (duration: 1.233906177s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:33.869262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:32.635113Z","time spent":"1.234141662s","remote":"127.0.0.1:59224","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:15:33.869375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.964798628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:33.86942Z","caller":"traceutil/trace.go:171","msg":"trace[89379378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"1.96488801s","start":"2026-04-10T04:15:31.904521Z","end":"2026-04-10T04:15:33.869409Z","steps":["trace[89379378] 'agreement among raft nodes before linearized reading' (duration: 1.964818808s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:33.869454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:31.904498Z","time spent":"1.964949282s","remote":"127.0.0.1:58952","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:15:33.869502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.468094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-10T04:15:33.869544Z","caller":"traceutil/trace.go:171","msg":"trace[2097621540] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"490.510415ms","start":"2026-04-10T04:15:33.379024Z","end":"2026-04-10T04:15:33.869534Z","steps":["trace[2097621540] 'agreement among raft nodes before linearized reading' (duration: 490.403463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:33.869577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:33.378952Z","time spent":"490.618407ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-10T04:15:33.869675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.14757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-10T04:15:33.869745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.201081ms","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:15:33.869758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.197810417s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.19\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-10T04:15:33.869778Z","caller":"traceutil/trace.go:171","msg":"trace[757160306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"489.234191ms","start":"2026-04-10T04:15:33.380534Z","end":"2026-04-10T04:15:33.869768Z","steps":["trace[757160306] 'agreement among raft nodes before linearized reading' (duration: 489.141679ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:33.86979Z","caller":"traceutil/trace.go:171","msg":"trace[29476838] range","detail":"{range_begin:/registry/masterleases/199.204.45.19; range_end:; response_count:1; response_revision:586; }","duration":"1.197882127s","start":"2026-04-10T04:15:32.671896Z","end":"2026-04-10T04:15:33.869778Z","steps":["trace[29476838] 'agreement among raft nodes before linearized reading' (duration: 1.197819566s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:33.869807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:33.380467Z","time spent":"489.331123ms","remote":"127.0.0.1:59224","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:15:33.869825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:32.671876Z","time spent":"1.197940339s","remote":"127.0.0.1:58962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.19\" "} {"level":"info","ts":"2026-04-10T04:15:33.869887Z","caller":"traceutil/trace.go:171","msg":"trace[1503763174] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"489.292682ms","start":"2026-04-10T04:15:33.380461Z","end":"2026-04-10T04:15:33.869754Z","steps":["trace[1503763174] 'agreement among raft nodes before linearized reading' (duration: 488.630629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:33.870591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:33.380446Z","time spent":"490.086077ms","remote":"127.0.0.1:59224","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:15:34.589564Z","caller":"traceutil/trace.go:171","msg":"trace[322177266] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"234.773772ms","start":"2026-04-10T04:15:34.354755Z","end":"2026-04-10T04:15:34.589529Z","steps":["trace[322177266] 'process raft request' (duration: 233.118292ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:34.742744Z","caller":"traceutil/trace.go:171","msg":"trace[1926900887] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"105.426343ms","start":"2026-04-10T04:15:34.636632Z","end":"2026-04-10T04:15:34.742058Z","steps":["trace[1926900887] 'process raft request' (duration: 59.979127ms)","trace[1926900887] 'compare' (duration: 45.277693ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:15:35.148188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.167507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:35.148327Z","caller":"traceutil/trace.go:171","msg":"trace[254411526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"244.373831ms","start":"2026-04-10T04:15:34.903909Z","end":"2026-04-10T04:15:35.148283Z","steps":["trace[254411526] 'range keys from in-memory index tree' (duration: 244.054546ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:35.437138Z","caller":"traceutil/trace.go:171","msg":"trace[123978447] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"161.189492ms","start":"2026-04-10T04:15:35.275891Z","end":"2026-04-10T04:15:35.43708Z","steps":["trace[123978447] 'process raft request' (duration: 151.699005ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:35.649636Z","caller":"traceutil/trace.go:171","msg":"trace[386251011] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"178.243169ms","start":"2026-04-10T04:15:35.471342Z","end":"2026-04-10T04:15:35.649585Z","steps":["trace[386251011] 'process raft request' (duration: 102.203304ms)","trace[386251011] 'compare' (duration: 75.553647ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:15:35.6497Z","caller":"traceutil/trace.go:171","msg":"trace[1244052202] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"178.104047ms","start":"2026-04-10T04:15:35.471564Z","end":"2026-04-10T04:15:35.649668Z","steps":["trace[1244052202] 'process raft request' (duration: 177.935444ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:35.649935Z","caller":"traceutil/trace.go:171","msg":"trace[934919823] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"177.970394ms","start":"2026-04-10T04:15:35.471935Z","end":"2026-04-10T04:15:35.649906Z","steps":["trace[934919823] 'process raft request' (duration: 177.612478ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:35.650002Z","caller":"traceutil/trace.go:171","msg":"trace[1066212724] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"175.868494ms","start":"2026-04-10T04:15:35.474113Z","end":"2026-04-10T04:15:35.649982Z","steps":["trace[1066212724] 'process raft request' (duration: 175.771723ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:37.457975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.615979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:37.458093Z","caller":"traceutil/trace.go:171","msg":"trace[1416910728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"553.810414ms","start":"2026-04-10T04:15:36.904257Z","end":"2026-04-10T04:15:37.458067Z","steps":["trace[1416910728] 'range keys from in-memory index tree' (duration: 553.513758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:37.458157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:36.904236Z","time spent":"553.911445ms","remote":"127.0.0.1:58948","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:15:37.45869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.995356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jx2l7\" ","response":"range_response_count:1 size:17520"} {"level":"info","ts":"2026-04-10T04:15:37.458734Z","caller":"traceutil/trace.go:171","msg":"trace[1786754930] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jx2l7; range_end:; response_count:1; response_revision:614; }","duration":"395.076907ms","start":"2026-04-10T04:15:37.063645Z","end":"2026-04-10T04:15:37.458722Z","steps":["trace[1786754930] 'range keys from in-memory index tree' (duration: 394.071048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:37.458765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:37.063623Z","time spent":"395.134908ms","remote":"127.0.0.1:59136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17543,"request content":"key:\"/registry/pods/kube-system/cilium-jx2l7\" "} {"level":"info","ts":"2026-04-10T04:15:38.297155Z","caller":"traceutil/trace.go:171","msg":"trace[944744897] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"320.858665ms","start":"2026-04-10T04:15:37.976269Z","end":"2026-04-10T04:15:38.297127Z","steps":["trace[944744897] 'process raft request' (duration: 280.977222ms)","trace[944744897] 'compare' (duration: 39.728451ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:15:38.297319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:37.976254Z","time spent":"320.982107ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:15:38.358762Z","caller":"traceutil/trace.go:171","msg":"trace[754063476] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:634; }","duration":"245.199876ms","start":"2026-04-10T04:15:38.113534Z","end":"2026-04-10T04:15:38.358734Z","steps":["trace[754063476] 'read index received' (duration: 143.896269ms)","trace[754063476] 'applied index is now lower than readState.Index' (duration: 101.302507ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:15:38.35907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.468521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jx2l7\" ","response":"range_response_count:1 size:17704"} {"level":"info","ts":"2026-04-10T04:15:38.359138Z","caller":"traceutil/trace.go:171","msg":"trace[864986453] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jx2l7; range_end:; response_count:1; response_revision:620; }","duration":"245.662335ms","start":"2026-04-10T04:15:38.113461Z","end":"2026-04-10T04:15:38.359123Z","steps":["trace[864986453] 'agreement among raft nodes before linearized reading' (duration: 245.38571ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:38.359226Z","caller":"traceutil/trace.go:171","msg":"trace[1349947977] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"382.226278ms","start":"2026-04-10T04:15:37.976976Z","end":"2026-04-10T04:15:38.359202Z","steps":["trace[1349947977] 'process raft request' (duration: 381.649638ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:38.359247Z","caller":"traceutil/trace.go:171","msg":"trace[1717895818] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"382.266199ms","start":"2026-04-10T04:15:37.976905Z","end":"2026-04-10T04:15:38.359171Z","steps":["trace[1717895818] 'process raft request' (duration: 381.597217ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:38.359433Z","caller":"traceutil/trace.go:171","msg":"trace[1667719128] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"244.609504ms","start":"2026-04-10T04:15:38.114811Z","end":"2026-04-10T04:15:38.35942Z","steps":["trace[1667719128] 'process raft request' (duration: 243.881911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:38.359638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:37.976878Z","time spent":"382.561264ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:15:38.359364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:37.976949Z","time spent":"382.33695ms","remote":"127.0.0.1:59224","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:15:38.537285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.171053ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-10T04:15:38.578067Z","caller":"traceutil/trace.go:171","msg":"trace[459912655] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"205.71901ms","start":"2026-04-10T04:15:38.372324Z","end":"2026-04-10T04:15:38.578043Z","steps":["trace[459912655] 'process raft request' (duration: 205.522897ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:39.161372Z","caller":"traceutil/trace.go:171","msg":"trace[676797209] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"185.605976ms","start":"2026-04-10T04:15:38.975738Z","end":"2026-04-10T04:15:39.161344Z","steps":["trace[676797209] 'process raft request' (duration: 185.487614ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:39.371277Z","caller":"traceutil/trace.go:171","msg":"trace[2015852166] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"204.872414ms","start":"2026-04-10T04:15:39.166368Z","end":"2026-04-10T04:15:39.37124Z","steps":["trace[2015852166] 'process raft request' (duration: 113.565914ms)","trace[2015852166] 'compare' (duration: 91.103727ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:15:40.989168Z","caller":"traceutil/trace.go:171","msg":"trace[1462548175] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"180.227116ms","start":"2026-04-10T04:15:40.808913Z","end":"2026-04-10T04:15:40.98914Z","steps":["trace[1462548175] 'process raft request' (duration: 180.071753ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:41.757781Z","caller":"traceutil/trace.go:171","msg":"trace[1556219473] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"145.341026ms","start":"2026-04-10T04:15:41.612407Z","end":"2026-04-10T04:15:41.757748Z","steps":["trace[1556219473] 'process raft request' (duration: 145.191113ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:49.975753Z","caller":"traceutil/trace.go:171","msg":"trace[1914804439] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"111.969454ms","start":"2026-04-10T04:15:49.863754Z","end":"2026-04-10T04:15:49.975724Z","steps":["trace[1914804439] 'process raft request' (duration: 111.791641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:52.276558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.496498ms","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:15:52.276644Z","caller":"traceutil/trace.go:171","msg":"trace[763781322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:756; }","duration":"195.383444ms","start":"2026-04-10T04:15:52.081241Z","end":"2026-04-10T04:15:52.276624Z","steps":["trace[763781322] 'range keys from in-memory index tree' (duration: 194.242804ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:52.47611Z","caller":"traceutil/trace.go:171","msg":"trace[1919692954] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"193.547782ms","start":"2026-04-10T04:15:52.282538Z","end":"2026-04-10T04:15:52.476086Z","steps":["trace[1919692954] 'process raft request' (duration: 193.358988ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:52.476676Z","caller":"traceutil/trace.go:171","msg":"trace[407590555] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:779; }","duration":"109.184222ms","start":"2026-04-10T04:15:52.367338Z","end":"2026-04-10T04:15:52.476522Z","steps":["trace[407590555] 'read index received' (duration: 109.178862ms)","trace[407590555] 'applied index is now lower than readState.Index' (duration: 4.17µs)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:15:54.492904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.125521761s","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:15:54.493001Z","caller":"traceutil/trace.go:171","msg":"trace[2016207950] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:757; }","duration":"2.125690925s","start":"2026-04-10T04:15:52.367286Z","end":"2026-04-10T04:15:54.492977Z","steps":["trace[2016207950] 'agreement among raft nodes before linearized reading' (duration: 109.356156ms)","trace[2016207950] 'range keys from in-memory index tree' (duration: 2.016054064s)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:15:54.493044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.062220897s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-10T04:15:54.493132Z","caller":"traceutil/trace.go:171","msg":"trace[134111854] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:757; }","duration":"2.06234691s","start":"2026-04-10T04:15:52.430761Z","end":"2026-04-10T04:15:54.493108Z","steps":["trace[134111854] 'agreement among raft nodes before linearized reading' (duration: 46.017731ms)","trace[134111854] 'range keys from in-memory index tree' (duration: 2.016074355s)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:15:54.493182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:52.430742Z","time spent":"2.062430311s","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-10T04:15:54.493047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:52.367257Z","time spent":"2.125781816s","remote":"127.0.0.1:59224","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:15:54.493531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.062993992s","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:15:54.493614Z","caller":"traceutil/trace.go:171","msg":"trace[1036221902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:757; }","duration":"2.063116705s","start":"2026-04-10T04:15:52.430479Z","end":"2026-04-10T04:15:54.493596Z","steps":["trace[1036221902] 'agreement among raft nodes before linearized reading' (duration: 46.283666ms)","trace[1036221902] 'range keys from in-memory index tree' (duration: 2.016494012s)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:15:54.493798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:52.430456Z","time spent":"2.063325878s","remote":"127.0.0.1:59224","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:15:54.494152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.013380204s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-mc8hp\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-10T04:15:54.494286Z","caller":"traceutil/trace.go:171","msg":"trace[1790779987] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-mc8hp; range_end:; response_count:1; response_revision:757; }","duration":"2.013495766s","start":"2026-04-10T04:15:52.480728Z","end":"2026-04-10T04:15:54.494224Z","steps":["trace[1790779987] 'range keys from in-memory index tree' (duration: 2.013279062s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:54.494466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:52.48071Z","time spent":"2.01371861s","remote":"127.0.0.1:59136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-mc8hp\" "} {"level":"warn","ts":"2026-04-10T04:15:54.494766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.821494824s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.19\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-10T04:15:54.495296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.590496673s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:54.495387Z","caller":"traceutil/trace.go:171","msg":"trace[1971393858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:757; }","duration":"1.590636706s","start":"2026-04-10T04:15:52.90473Z","end":"2026-04-10T04:15:54.495367Z","steps":["trace[1971393858] 'range keys from in-memory index tree' (duration: 1.590372701s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:54.495427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:52.904707Z","time spent":"1.590711847s","remote":"127.0.0.1:58952","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:15:54.494848Z","caller":"traceutil/trace.go:171","msg":"trace[590243670] range","detail":"{range_begin:/registry/masterleases/199.204.45.19; range_end:; response_count:1; response_revision:757; }","duration":"1.821571056s","start":"2026-04-10T04:15:52.67326Z","end":"2026-04-10T04:15:54.494831Z","steps":["trace[590243670] 'range keys from in-memory index tree' (duration: 1.821373901s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:54.495853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:52.673191Z","time spent":"1.822642446s","remote":"127.0.0.1:58962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.19\" "} {"level":"warn","ts":"2026-04-10T04:15:54.496399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.910835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:54.496444Z","caller":"traceutil/trace.go:171","msg":"trace[2112928212] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:757; }","duration":"821.962186ms","start":"2026-04-10T04:15:53.674469Z","end":"2026-04-10T04:15:54.496431Z","steps":["trace[2112928212] 'count revisions from in-memory index tree' (duration: 821.822104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:54.49648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:53.67434Z","time spent":"822.12916ms","remote":"127.0.0.1:59058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-10T04:15:54.496818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.370035ms","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:15:54.496865Z","caller":"traceutil/trace.go:171","msg":"trace[865739102] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:757; }","duration":"466.458706ms","start":"2026-04-10T04:15:54.030394Z","end":"2026-04-10T04:15:54.496853Z","steps":["trace[865739102] 'range keys from in-memory index tree' (duration: 466.212292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:54.496904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:54.030368Z","time spent":"466.522017ms","remote":"127.0.0.1:59224","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:15:54.497457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.570533ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T04:15:54.497916Z","caller":"traceutil/trace.go:171","msg":"trace[1947085044] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"246.059083ms","start":"2026-04-10T04:15:54.251829Z","end":"2026-04-10T04:15:54.497888Z","steps":["trace[1947085044] 'process raft request' (duration: 115.98814ms)","trace[1947085044] 'compare' (duration: 124.646171ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:15:55.666443Z","caller":"traceutil/trace.go:171","msg":"trace[1215402215] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"165.370353ms","start":"2026-04-10T04:15:55.501048Z","end":"2026-04-10T04:15:55.666418Z","steps":["trace[1215402215] 'process raft request' (duration: 165.18916ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:55.667735Z","caller":"traceutil/trace.go:171","msg":"trace[809314118] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"139.510629ms","start":"2026-04-10T04:15:55.528209Z","end":"2026-04-10T04:15:55.66772Z","steps":["trace[809314118] 'process raft request' (duration: 139.410168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:57.99013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.048302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-10T04:15:57.990347Z","caller":"traceutil/trace.go:171","msg":"trace[1500965233] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"220.023876ms","start":"2026-04-10T04:15:57.770294Z","end":"2026-04-10T04:15:57.990318Z","steps":["trace[1500965233] 'process raft request' (duration: 88.711569ms)","trace[1500965233] 'compare' (duration: 130.89426ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:15:58.115603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.347466ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-10T04:15:58.115933Z","caller":"traceutil/trace.go:171","msg":"trace[1543657326] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:810; }","duration":"211.303853ms","start":"2026-04-10T04:15:57.904553Z","end":"2026-04-10T04:15:58.115857Z","steps":["trace[1543657326] 'read index received' (duration: 39.43µs)","trace[1543657326] 'applied index is now lower than readState.Index' (duration: 211.258793ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:15:58.116042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.450666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:15:58.116129Z","caller":"traceutil/trace.go:171","msg":"trace[1700391109] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"119.284821ms","start":"2026-04-10T04:15:57.996821Z","end":"2026-04-10T04:15:58.116106Z","steps":["trace[1700391109] 'process raft request' (duration: 119.072268ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:58.116161Z","caller":"traceutil/trace.go:171","msg":"trace[207651258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"211.641959ms","start":"2026-04-10T04:15:57.904497Z","end":"2026-04-10T04:15:58.116139Z","steps":["trace[207651258] 'agreement among raft nodes before linearized reading' (duration: 211.446975ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:58.155301Z","caller":"traceutil/trace.go:171","msg":"trace[274898151] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"158.281741ms","start":"2026-04-10T04:15:57.996993Z","end":"2026-04-10T04:15:58.155274Z","steps":["trace[274898151] 'process raft request' (duration: 157.885694ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:58.642589Z","caller":"traceutil/trace.go:171","msg":"trace[955787748] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:814; }","duration":"105.77401ms","start":"2026-04-10T04:15:58.536787Z","end":"2026-04-10T04:15:58.642561Z","steps":["trace[955787748] 'read index received' (duration: 105.442054ms)","trace[955787748] 'applied index is now lower than readState.Index' (duration: 330.786µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:15:58.642657Z","caller":"traceutil/trace.go:171","msg":"trace[1675479432] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"481.019959ms","start":"2026-04-10T04:15:58.161589Z","end":"2026-04-10T04:15:58.642609Z","steps":["trace[1675479432] 'process raft request' (duration: 480.802715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:58.642804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:58.161545Z","time spent":"481.166471ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":50466,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:15:58.642933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.142676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-10T04:15:58.643011Z","caller":"traceutil/trace.go:171","msg":"trace[1304745577] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:790; }","duration":"106.287869ms","start":"2026-04-10T04:15:58.536702Z","end":"2026-04-10T04:15:58.64299Z","steps":["trace[1304745577] 'agreement among raft nodes before linearized reading' (duration: 105.985203ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:58.791927Z","caller":"traceutil/trace.go:171","msg":"trace[891191226] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"144.684167ms","start":"2026-04-10T04:15:58.64722Z","end":"2026-04-10T04:15:58.791904Z","steps":["trace[891191226] 'process raft request' (duration: 107.122254ms)","trace[891191226] 'compare' (duration: 37.363608ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:15:58.792319Z","caller":"traceutil/trace.go:171","msg":"trace[1393527236] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"145.078174ms","start":"2026-04-10T04:15:58.647225Z","end":"2026-04-10T04:15:58.792303Z","steps":["trace[1393527236] 'process raft request' (duration: 144.621166ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:58.792383Z","caller":"traceutil/trace.go:171","msg":"trace[1206145559] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"144.455502ms","start":"2026-04-10T04:15:58.647904Z","end":"2026-04-10T04:15:58.792359Z","steps":["trace[1206145559] 'process raft request' (duration: 144.236618ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:58.792602Z","caller":"traceutil/trace.go:171","msg":"trace[999102274] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"144.600914ms","start":"2026-04-10T04:15:58.64799Z","end":"2026-04-10T04:15:58.792591Z","steps":["trace[999102274] 'process raft request' (duration: 144.270558ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:58.792791Z","caller":"traceutil/trace.go:171","msg":"trace[2004877594] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"144.553274ms","start":"2026-04-10T04:15:58.648201Z","end":"2026-04-10T04:15:58.792755Z","steps":["trace[2004877594] 'process raft request' (duration: 144.33063ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:58.974556Z","caller":"traceutil/trace.go:171","msg":"trace[1400365354] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"267.894461ms","start":"2026-04-10T04:15:58.706596Z","end":"2026-04-10T04:15:58.974491Z","steps":["trace[1400365354] 'process raft request' (duration: 267.710117ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:58.974595Z","caller":"traceutil/trace.go:171","msg":"trace[1794898728] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"268.079714ms","start":"2026-04-10T04:15:58.706488Z","end":"2026-04-10T04:15:58.974568Z","steps":["trace[1794898728] 'process raft request' (duration: 267.728868ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:58.975707Z","caller":"traceutil/trace.go:171","msg":"trace[1458522545] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"270.818586ms","start":"2026-04-10T04:15:58.704861Z","end":"2026-04-10T04:15:58.975679Z","steps":["trace[1458522545] 'process raft request' (duration: 189.014476ms)","trace[1458522545] 'compare' (duration: 80.19289ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:15:59.098475Z","caller":"traceutil/trace.go:171","msg":"trace[235898692] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"115.46413ms","start":"2026-04-10T04:15:58.982986Z","end":"2026-04-10T04:15:59.09845Z","steps":["trace[235898692] 'process raft request' (duration: 95.70325ms)","trace[235898692] 'compare' (duration: 19.651977ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:15:59.354851Z","caller":"traceutil/trace.go:171","msg":"trace[1720081185] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"248.161043ms","start":"2026-04-10T04:15:59.106666Z","end":"2026-04-10T04:15:59.354827Z","steps":["trace[1720081185] 'process raft request' (duration: 248.059061ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:15:59.355171Z","caller":"traceutil/trace.go:171","msg":"trace[514203090] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"249.12606ms","start":"2026-04-10T04:15:59.105983Z","end":"2026-04-10T04:15:59.355109Z","steps":["trace[514203090] 'process raft request' (duration: 248.093601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:59.651415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.19832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-10T04:15:59.651534Z","caller":"traceutil/trace.go:171","msg":"trace[275856718] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"292.370289ms","start":"2026-04-10T04:15:59.359135Z","end":"2026-04-10T04:15:59.651506Z","steps":["trace[275856718] 'process raft request' (duration: 141.992726ms)","trace[275856718] 'compare' (duration: 149.807302ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:15:59.694561Z","caller":"traceutil/trace.go:171","msg":"trace[1576642630] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"331.947369ms","start":"2026-04-10T04:15:59.362576Z","end":"2026-04-10T04:15:59.694523Z","steps":["trace[1576642630] 'process raft request' (duration: 331.638644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:59.694835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:59.362554Z","time spent":"332.118863ms","remote":"127.0.0.1:59424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5099,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:15:59.694952Z","caller":"traceutil/trace.go:171","msg":"trace[899484745] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"332.351118ms","start":"2026-04-10T04:15:59.362576Z","end":"2026-04-10T04:15:59.694927Z","steps":["trace[899484745] 'process raft request' (duration: 331.875438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:59.695114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:59.362552Z","time spent":"332.46866ms","remote":"127.0.0.1:59136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:15:59.697157Z","caller":"traceutil/trace.go:171","msg":"trace[2043650350] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:828; }","duration":"334.292694ms","start":"2026-04-10T04:15:59.362837Z","end":"2026-04-10T04:15:59.69713Z","steps":["trace[2043650350] 'read index received' (duration: 138.4145ms)","trace[2043650350] 'applied index is now lower than readState.Index' (duration: 195.876434ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:15:59.69749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.62537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:5266"} {"level":"info","ts":"2026-04-10T04:15:59.697537Z","caller":"traceutil/trace.go:171","msg":"trace[980613764] range","detail":"{range_begin:/registry/deployments/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:807; }","duration":"334.753762ms","start":"2026-04-10T04:15:59.362769Z","end":"2026-04-10T04:15:59.697522Z","steps":["trace[980613764] 'agreement among raft nodes before linearized reading' (duration: 334.539398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:59.697573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:59.36276Z","time spent":"334.803173ms","remote":"127.0.0.1:59392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":5289,"request content":"key:\"/registry/deployments/envoy-gateway-system/envoy-gateway\" "} {"level":"info","ts":"2026-04-10T04:15:59.697752Z","caller":"traceutil/trace.go:171","msg":"trace[586649004] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"332.686583ms","start":"2026-04-10T04:15:59.364949Z","end":"2026-04-10T04:15:59.697636Z","steps":["trace[586649004] 'process raft request' (duration: 330.563444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:15:59.698276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:15:59.364937Z","time spent":"332.935069ms","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036298,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:16:31.522817Z","caller":"traceutil/trace.go:171","msg":"trace[2108430512] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1014; }","duration":"409.069563ms","start":"2026-04-10T04:16:31.113709Z","end":"2026-04-10T04:16:31.522778Z","steps":["trace[2108430512] 'read index received' (duration: 408.928169ms)","trace[2108430512] 'applied index is now lower than readState.Index' (duration: 139.424µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:16:31.522927Z","caller":"traceutil/trace.go:171","msg":"trace[664554249] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"409.529042ms","start":"2026-04-10T04:16:31.113357Z","end":"2026-04-10T04:16:31.522886Z","steps":["trace[664554249] 'process raft request' (duration: 409.163874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:31.523113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:16:31.113322Z","time spent":"409.692864ms","remote":"127.0.0.1:59224","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:16:31.523199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.439909ms","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:16:31.523253Z","caller":"traceutil/trace.go:171","msg":"trace[1863458669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:983; }","duration":"409.547962ms","start":"2026-04-10T04:16:31.113691Z","end":"2026-04-10T04:16:31.523239Z","steps":["trace[1863458669] 'agreement among raft nodes before linearized reading' (duration: 409.304258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:31.523305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:16:31.113681Z","time spent":"409.614763ms","remote":"127.0.0.1:59224","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:16:32.023957Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150296666924850,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-10T04:16:32.203091Z","caller":"traceutil/trace.go:171","msg":"trace[2032474685] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"1.084409786s","start":"2026-04-10T04:16:31.118658Z","end":"2026-04-10T04:16:32.203068Z","steps":["trace[2032474685] 'process raft request' (duration: 1.084357245s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:32.203247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:16:31.118642Z","time spent":"1.084517539s","remote":"127.0.0.1:59224","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:16:32.203229Z","caller":"traceutil/trace.go:171","msg":"trace[549888929] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"1.085912014s","start":"2026-04-10T04:16:31.117296Z","end":"2026-04-10T04:16:32.203208Z","steps":["trace[549888929] 'process raft request' (duration: 1.085525826s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:32.20346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:16:31.117269Z","time spent":"1.086157329s","remote":"127.0.0.1:59224","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:16:33.636551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211037544s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-10T04:16:33.636631Z","caller":"traceutil/trace.go:171","msg":"trace[437320641] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1015; }","duration":"2.113661269s","start":"2026-04-10T04:16:31.522951Z","end":"2026-04-10T04:16:33.636612Z","steps":["trace[437320641] 'read index received' (duration: 679.885468ms)","trace[437320641] 'applied index is now lower than readState.Index' (duration: 1.433774711s)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:16:33.63669Z","caller":"traceutil/trace.go:171","msg":"trace[1957116518] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"2.108184616s","start":"2026-04-10T04:16:31.528489Z","end":"2026-04-10T04:16:33.636674Z","steps":["trace[1957116518] 'process raft request' (duration: 896.943538ms)","trace[1957116518] 'compare' (duration: 1.210957373s)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:16:33.636736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:16:31.528462Z","time spent":"2.108244598s","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:16:33.636888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.523057678s","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:16:33.63697Z","caller":"traceutil/trace.go:171","msg":"trace[847931677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:986; }","duration":"2.523177481s","start":"2026-04-10T04:16:31.113773Z","end":"2026-04-10T04:16:33.63695Z","steps":["trace[847931677] 'agreement among raft nodes before linearized reading' (duration: 2.522962746s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:33.637006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:16:31.113758Z","time spent":"2.523242322s","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-10T04:16:33.690438Z","caller":"traceutil/trace.go:171","msg":"trace[614956944] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"867.822424ms","start":"2026-04-10T04:16:32.8226Z","end":"2026-04-10T04:16:33.690423Z","steps":["trace[614956944] 'process raft request' (duration: 867.730673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:33.690542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:16:32.822578Z","time spent":"867.909416ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:16:33.690525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.959230482s","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:16:33.690597Z","caller":"traceutil/trace.go:171","msg":"trace[553435262] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:987; }","duration":"1.959314043s","start":"2026-04-10T04:16:31.731265Z","end":"2026-04-10T04:16:33.690579Z","steps":["trace[553435262] 'agreement among raft nodes before linearized reading' (duration: 1.9591669s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:33.690659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:16:31.731194Z","time spent":"1.959443225s","remote":"127.0.0.1:59284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-04-10T04:16:33.69078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.258031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T04:16:33.690803Z","caller":"traceutil/trace.go:171","msg":"trace[774408826] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:987; }","duration":"684.324302ms","start":"2026-04-10T04:16:33.006474Z","end":"2026-04-10T04:16:33.690798Z","steps":["trace[774408826] 'agreement among raft nodes before linearized reading' (duration: 684.289451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:33.690818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:16:33.006452Z","time spent":"684.362672ms","remote":"127.0.0.1:49838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-10T04:16:33.690879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.145354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:16:33.690917Z","caller":"traceutil/trace.go:171","msg":"trace[1600740158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"787.206366ms","start":"2026-04-10T04:16:32.9037Z","end":"2026-04-10T04:16:33.690906Z","steps":["trace[1600740158] 'agreement among raft nodes before linearized reading' (duration: 787.151385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:33.690947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.192533ms","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:16:33.690945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:16:32.903688Z","time spent":"787.251107ms","remote":"127.0.0.1:58952","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:16:33.690985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.389367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:16:33.691Z","caller":"traceutil/trace.go:171","msg":"trace[1055326401] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:987; }","duration":"603.419758ms","start":"2026-04-10T04:16:33.087576Z","end":"2026-04-10T04:16:33.690996Z","steps":["trace[1055326401] 'agreement among raft nodes before linearized reading' (duration: 603.397407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:33.691041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:16:33.087563Z","time spent":"603.474359ms","remote":"127.0.0.1:49766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-04-10T04:16:33.690965Z","caller":"traceutil/trace.go:171","msg":"trace[2021675071] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:987; }","duration":"163.250203ms","start":"2026-04-10T04:16:33.527711Z","end":"2026-04-10T04:16:33.690961Z","steps":["trace[2021675071] 'agreement among raft nodes before linearized reading' (duration: 163.172231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:33.691525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015580688s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.19\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-10T04:16:33.691787Z","caller":"traceutil/trace.go:171","msg":"trace[1863564452] range","detail":"{range_begin:/registry/masterleases/199.204.45.19; range_end:; response_count:1; response_revision:987; }","duration":"1.015907454s","start":"2026-04-10T04:16:32.675851Z","end":"2026-04-10T04:16:33.691759Z","steps":["trace[1863564452] 'agreement among raft nodes before linearized reading' (duration: 1.015407354s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:33.691938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:16:32.675806Z","time spent":"1.016116088s","remote":"127.0.0.1:58962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.19\" "} {"level":"info","ts":"2026-04-10T04:16:40.464947Z","caller":"traceutil/trace.go:171","msg":"trace[374783775] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1045; }","duration":"140.781614ms","start":"2026-04-10T04:16:40.32414Z","end":"2026-04-10T04:16:40.464922Z","steps":["trace[374783775] 'read index received' (duration: 110.835724ms)","trace[374783775] 'applied index is now lower than readState.Index' (duration: 29.94489ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:16:40.465122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.949326ms","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:16:40.465164Z","caller":"traceutil/trace.go:171","msg":"trace[377570750] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1012; }","duration":"141.058238ms","start":"2026-04-10T04:16:40.324094Z","end":"2026-04-10T04:16:40.465152Z","steps":["trace[377570750] 'agreement among raft nodes before linearized reading' (duration: 140.941906ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:16:40.465178Z","caller":"traceutil/trace.go:171","msg":"trace[638370050] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"223.4558ms","start":"2026-04-10T04:16:40.241672Z","end":"2026-04-10T04:16:40.465128Z","steps":["trace[638370050] 'process raft request' (duration: 193.217065ms)","trace[638370050] 'compare' (duration: 29.92718ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:16:41.116898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.441264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:16:41.116988Z","caller":"traceutil/trace.go:171","msg":"trace[225069717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1012; }","duration":"212.604787ms","start":"2026-04-10T04:16:40.904363Z","end":"2026-04-10T04:16:41.116968Z","steps":["trace[225069717] 'range keys from in-memory index tree' (duration: 212.350963ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:56.334139Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150296666925056,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T04:16:56.834928Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150296666925056,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T04:16:57.190138Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.360098249s","expected-duration":"1s"} {"level":"info","ts":"2026-04-10T04:16:57.190552Z","caller":"traceutil/trace.go:171","msg":"trace[1875010772] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"1.360608458s","start":"2026-04-10T04:16:55.829923Z","end":"2026-04-10T04:16:57.190531Z","steps":["trace[1875010772] 'process raft request' (duration: 1.360477256s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:57.190687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:16:55.829895Z","time spent":"1.36072382s","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:16:57.210654Z","caller":"traceutil/trace.go:171","msg":"trace[624766257] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1095; }","duration":"1.377108064s","start":"2026-04-10T04:16:55.833522Z","end":"2026-04-10T04:16:57.210631Z","steps":["trace[624766257] 'read index received' (duration: 1.356811537s)","trace[624766257] 'applied index is now lower than readState.Index' (duration: 20.295257ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:16:57.210682Z","caller":"traceutil/trace.go:171","msg":"trace[1289472161] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"1.377146495s","start":"2026-04-10T04:16:55.833514Z","end":"2026-04-10T04:16:57.210661Z","steps":["trace[1289472161] 'process raft request' (duration: 1.37688921s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:57.21088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.377350738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-10T04:16:57.210921Z","caller":"traceutil/trace.go:171","msg":"trace[597374224] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1061; }","duration":"1.37745072s","start":"2026-04-10T04:16:55.833458Z","end":"2026-04-10T04:16:57.210909Z","steps":["trace[597374224] 'agreement among raft nodes before linearized reading' (duration: 1.377295217s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:57.210958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:16:55.833426Z","time spent":"1.377525033s","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-10T04:16:57.210977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.375139639s","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:16:57.21101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.575534ms","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:16:57.211049Z","caller":"traceutil/trace.go:171","msg":"trace[1175178938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1061; }","duration":"1.3752416s","start":"2026-04-10T04:16:55.835795Z","end":"2026-04-10T04:16:57.211037Z","steps":["trace[1175178938] 'agreement among raft nodes before linearized reading' (duration: 1.375029715s)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:16:57.211056Z","caller":"traceutil/trace.go:171","msg":"trace[269691881] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1061; }","duration":"643.666936ms","start":"2026-04-10T04:16:56.567377Z","end":"2026-04-10T04:16:57.211044Z","steps":["trace[269691881] 'agreement among raft nodes before linearized reading' (duration: 643.547893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:57.211088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:16:55.835787Z","time spent":"1.375291551s","remote":"127.0.0.1:59224","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:16:57.211136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.064237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:16:57.211171Z","caller":"traceutil/trace.go:171","msg":"trace[764776985] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1061; }","duration":"250.138418ms","start":"2026-04-10T04:16:56.961022Z","end":"2026-04-10T04:16:57.211161Z","steps":["trace[764776985] 'agreement among raft nodes before linearized reading' (duration: 250.085068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:57.211277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.366104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-10T04:16:57.211088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:16:56.56735Z","time spent":"643.729878ms","remote":"127.0.0.1:59224","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:16:57.211383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306656372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:16:57.211412Z","caller":"traceutil/trace.go:171","msg":"trace[1577499307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1061; }","duration":"1.306707714s","start":"2026-04-10T04:16:55.904696Z","end":"2026-04-10T04:16:57.211403Z","steps":["trace[1577499307] 'agreement among raft nodes before linearized reading' (duration: 1.306622272s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:57.211436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:16:55.904678Z","time spent":"1.306752534s","remote":"127.0.0.1:58952","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:16:57.211491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:16:55.833481Z","time spent":"1.377368019s","remote":"127.0.0.1:59224","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:16:57.21075Z","caller":"traceutil/trace.go:171","msg":"trace[2110813637] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"1.290944s","start":"2026-04-10T04:16:55.919758Z","end":"2026-04-10T04:16:57.210702Z","steps":["trace[2110813637] 'process raft request' (duration: 1.290812768s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:57.211637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:16:55.919734Z","time spent":"1.291839277s","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:16:57.211322Z","caller":"traceutil/trace.go:171","msg":"trace[1386218317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1061; }","duration":"800.408695ms","start":"2026-04-10T04:16:56.410902Z","end":"2026-04-10T04:16:57.21131Z","steps":["trace[1386218317] 'agreement among raft nodes before linearized reading' (duration: 800.314323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:57.211746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:16:56.41084Z","time spent":"800.864924ms","remote":"127.0.0.1:59224","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:16:57.341021Z","caller":"traceutil/trace.go:171","msg":"trace[466866331] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"124.520279ms","start":"2026-04-10T04:16:57.216482Z","end":"2026-04-10T04:16:57.341003Z","steps":["trace[466866331] 'process raft request' (duration: 124.472418ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:16:57.341084Z","caller":"traceutil/trace.go:171","msg":"trace[725776919] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"124.794363ms","start":"2026-04-10T04:16:57.216254Z","end":"2026-04-10T04:16:57.341048Z","steps":["trace[725776919] 'process raft request' (duration: 124.59115ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:16:57.341174Z","caller":"traceutil/trace.go:171","msg":"trace[1344804738] linearizableReadLoop","detail":"{readStateIndex:1102; appliedIndex:1098; }","duration":"118.541428ms","start":"2026-04-10T04:16:57.222624Z","end":"2026-04-10T04:16:57.341166Z","steps":["trace[1344804738] 'read index received' (duration: 27.36586ms)","trace[1344804738] 'applied index is now lower than readState.Index' (duration: 91.174778ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:16:57.341299Z","caller":"traceutil/trace.go:171","msg":"trace[1859433147] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"124.55434ms","start":"2026-04-10T04:16:57.216676Z","end":"2026-04-10T04:16:57.34123Z","steps":["trace[1859433147] 'process raft request' (duration: 124.302934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:57.341962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.319572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:16:57.34201Z","caller":"traceutil/trace.go:171","msg":"trace[1093703815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1065; }","duration":"119.400863ms","start":"2026-04-10T04:16:57.222598Z","end":"2026-04-10T04:16:57.341999Z","steps":["trace[1093703815] 'agreement among raft nodes before linearized reading' (duration: 118.614419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:59.085976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038910037s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-10T04:16:59.086071Z","caller":"traceutil/trace.go:171","msg":"trace[1477544355] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1103; }","duration":"683.148642ms","start":"2026-04-10T04:16:58.402902Z","end":"2026-04-10T04:16:59.08605Z","steps":["trace[1477544355] 'read index received' (duration: 30.43µs)","trace[1477544355] 'applied index is now lower than readState.Index' (duration: 683.116752ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:16:59.086184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.279265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:16:59.086217Z","caller":"traceutil/trace.go:171","msg":"trace[1470693202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1066; }","duration":"683.352545ms","start":"2026-04-10T04:16:58.402855Z","end":"2026-04-10T04:16:59.086208Z","steps":["trace[1470693202] 'agreement among raft nodes before linearized reading' (duration: 683.287164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:59.086279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:16:58.402828Z","time spent":"683.444188ms","remote":"127.0.0.1:58952","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:16:59.086515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.598299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:16:59.086556Z","caller":"traceutil/trace.go:171","msg":"trace[340397559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1066; }","duration":"183.862963ms","start":"2026-04-10T04:16:58.902684Z","end":"2026-04-10T04:16:59.086547Z","steps":["trace[340397559] 'agreement among raft nodes before linearized reading' (duration: 183.808363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:59.086779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.801054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T04:16:59.086899Z","caller":"traceutil/trace.go:171","msg":"trace[950605617] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1066; }","duration":"124.975587ms","start":"2026-04-10T04:16:58.961903Z","end":"2026-04-10T04:16:59.086879Z","steps":["trace[950605617] 'agreement among raft nodes before linearized reading' (duration: 124.779094ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:16:59.449663Z","caller":"traceutil/trace.go:171","msg":"trace[247830157] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1104; }","duration":"233.748933ms","start":"2026-04-10T04:16:59.21589Z","end":"2026-04-10T04:16:59.449639Z","steps":["trace[247830157] 'read index received' (duration: 233.533899ms)","trace[247830157] 'applied index is now lower than readState.Index' (duration: 213.764µs)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:16:59.449943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.0376ms","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:16:59.449795Z","caller":"traceutil/trace.go:171","msg":"trace[125784548] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"254.004071ms","start":"2026-04-10T04:16:59.195755Z","end":"2026-04-10T04:16:59.449759Z","steps":["trace[125784548] 'process raft request' (duration: 253.722165ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:16:59.450001Z","caller":"traceutil/trace.go:171","msg":"trace[194751937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1067; }","duration":"234.137481ms","start":"2026-04-10T04:16:59.215849Z","end":"2026-04-10T04:16:59.449987Z","steps":["trace[194751937] 'agreement among raft nodes before linearized reading' (duration: 233.964818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:59.450156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.415249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-10T04:16:59.45024Z","caller":"traceutil/trace.go:171","msg":"trace[72157603] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1067; }","duration":"195.548022ms","start":"2026-04-10T04:16:59.254672Z","end":"2026-04-10T04:16:59.45022Z","steps":["trace[72157603] 'agreement among raft nodes before linearized reading' (duration: 195.304788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:59.450547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.175519ms","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:16:59.4506Z","caller":"traceutil/trace.go:171","msg":"trace[379606239] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1067; }","duration":"104.268032ms","start":"2026-04-10T04:16:59.34632Z","end":"2026-04-10T04:16:59.450588Z","steps":["trace[379606239] 'agreement among raft nodes before linearized reading' (duration: 103.884555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:59.450731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.349263ms","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:16:59.450862Z","caller":"traceutil/trace.go:171","msg":"trace[354049463] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1067; }","duration":"104.525576ms","start":"2026-04-10T04:16:59.346297Z","end":"2026-04-10T04:16:59.450823Z","steps":["trace[354049463] 'agreement among raft nodes before linearized reading' (duration: 104.137519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:59.450868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.62846ms","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:16:59.451011Z","caller":"traceutil/trace.go:171","msg":"trace[1222980470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1067; }","duration":"103.829044ms","start":"2026-04-10T04:16:59.34716Z","end":"2026-04-10T04:16:59.450989Z","steps":["trace[1222980470] 'agreement among raft nodes before linearized reading' (duration: 103.377676ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:16:59.607009Z","caller":"traceutil/trace.go:171","msg":"trace[477029036] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"151.046463ms","start":"2026-04-10T04:16:59.455936Z","end":"2026-04-10T04:16:59.606982Z","steps":["trace[477029036] 'process raft request' (duration: 150.85034ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:16:59.607537Z","caller":"traceutil/trace.go:171","msg":"trace[634132077] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"151.490621ms","start":"2026-04-10T04:16:59.456018Z","end":"2026-04-10T04:16:59.607509Z","steps":["trace[634132077] 'process raft request' (duration: 150.925111ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:16:59.607633Z","caller":"traceutil/trace.go:171","msg":"trace[1921182033] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"151.559712ms","start":"2026-04-10T04:16:59.456061Z","end":"2026-04-10T04:16:59.607621Z","steps":["trace[1921182033] 'process raft request' (duration: 151.321508ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:16:59.607793Z","caller":"traceutil/trace.go:171","msg":"trace[1913973424] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1105; }","duration":"136.382319ms","start":"2026-04-10T04:16:59.471387Z","end":"2026-04-10T04:16:59.60777Z","steps":["trace[1913973424] 'read index received' (duration: 15.050541ms)","trace[1913973424] 'applied index is now lower than readState.Index' (duration: 121.330488ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:16:59.607957Z","caller":"traceutil/trace.go:171","msg":"trace[1690782048] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"150.745757ms","start":"2026-04-10T04:16:59.4572Z","end":"2026-04-10T04:16:59.607946Z","steps":["trace[1690782048] 'process raft request' (duration: 150.37907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:59.60803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.608755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T04:16:59.608064Z","caller":"traceutil/trace.go:171","msg":"trace[1445937139] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1072; }","duration":"136.740947ms","start":"2026-04-10T04:16:59.471313Z","end":"2026-04-10T04:16:59.608054Z","steps":["trace[1445937139] 'agreement among raft nodes before linearized reading' (duration: 136.554382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:16:59.845848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.232685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:16:59.845946Z","caller":"traceutil/trace.go:171","msg":"trace[1730620365] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1072; }","duration":"108.404288ms","start":"2026-04-10T04:16:59.737522Z","end":"2026-04-10T04:16:59.845926Z","steps":["trace[1730620365] 'count revisions from in-memory index tree' (duration: 108.131784ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:01.605279Z","caller":"traceutil/trace.go:171","msg":"trace[188013631] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"110.846604ms","start":"2026-04-10T04:17:01.4944Z","end":"2026-04-10T04:17:01.605246Z","steps":["trace[188013631] 'process raft request' (duration: 100.355089ms)","trace[188013631] 'compare' (duration: 10.259101ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:17:01.833679Z","caller":"traceutil/trace.go:171","msg":"trace[1905080566] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"218.169963ms","start":"2026-04-10T04:17:01.615479Z","end":"2026-04-10T04:17:01.833649Z","steps":["trace[1905080566] 'process raft request' (duration: 186.712438ms)","trace[1905080566] 'compare' (duration: 30.961546ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:17:01.846144Z","caller":"traceutil/trace.go:171","msg":"trace[675874412] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"230.376699ms","start":"2026-04-10T04:17:01.61575Z","end":"2026-04-10T04:17:01.846127Z","steps":["trace[675874412] 'process raft request' (duration: 230.293998ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:01.846294Z","caller":"traceutil/trace.go:171","msg":"trace[1666745017] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"230.546863ms","start":"2026-04-10T04:17:01.615736Z","end":"2026-04-10T04:17:01.846283Z","steps":["trace[1666745017] 'process raft request' (duration: 230.225337ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:01.846445Z","caller":"traceutil/trace.go:171","msg":"trace[612576963] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1112; }","duration":"145.814225ms","start":"2026-04-10T04:17:01.700608Z","end":"2026-04-10T04:17:01.846422Z","steps":["trace[612576963] 'read index received' (duration: 101.604512ms)","trace[612576963] 'applied index is now lower than readState.Index' (duration: 44.207993ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:17:01.846554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.920258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T04:17:01.846519Z","caller":"traceutil/trace.go:171","msg":"trace[1302156904] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"229.628966ms","start":"2026-04-10T04:17:01.616862Z","end":"2026-04-10T04:17:01.846491Z","steps":["trace[1302156904] 'process raft request' (duration: 229.218779ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:01.846596Z","caller":"traceutil/trace.go:171","msg":"trace[1979248339] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1078; }","duration":"145.994118ms","start":"2026-04-10T04:17:01.700577Z","end":"2026-04-10T04:17:01.846571Z","steps":["trace[1979248339] 'agreement among raft nodes before linearized reading' (duration: 145.918337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:14.15621Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150296666925291,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-10T04:17:14.239241Z","caller":"traceutil/trace.go:171","msg":"trace[1987141318] linearizableReadLoop","detail":"{readStateIndex:1208; appliedIndex:1207; }","duration":"583.944664ms","start":"2026-04-10T04:17:13.655281Z","end":"2026-04-10T04:17:14.239226Z","steps":["trace[1987141318] 'read index received' (duration: 583.803671ms)","trace[1987141318] 'applied index is now lower than readState.Index' (duration: 140.363µs)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:17:14.239342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.505208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:17:14.23936Z","caller":"traceutil/trace.go:171","msg":"trace[785493668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1165; }","duration":"335.576209ms","start":"2026-04-10T04:17:13.903778Z","end":"2026-04-10T04:17:14.239354Z","steps":["trace[785493668] 'agreement among raft nodes before linearized reading' (duration: 335.529167ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:14.239334Z","caller":"traceutil/trace.go:171","msg":"trace[440733595] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"667.024531ms","start":"2026-04-10T04:17:13.572256Z","end":"2026-04-10T04:17:14.239281Z","steps":["trace[440733595] 'process raft request' (duration: 666.870418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:14.239459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.589918ms","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:17:14.239474Z","caller":"traceutil/trace.go:171","msg":"trace[138944586] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1165; }","duration":"285.628719ms","start":"2026-04-10T04:17:13.953842Z","end":"2026-04-10T04:17:14.239471Z","steps":["trace[138944586] 'agreement among raft nodes before linearized reading' (duration: 285.555328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:14.239491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.19841ms","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:17:14.239552Z","caller":"traceutil/trace.go:171","msg":"trace[1375673513] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1165; }","duration":"584.305151ms","start":"2026-04-10T04:17:13.65523Z","end":"2026-04-10T04:17:14.239535Z","steps":["trace[1375673513] 'agreement among raft nodes before linearized reading' (duration: 584.076516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:14.239562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.955784ms","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:17:14.239578Z","caller":"traceutil/trace.go:171","msg":"trace[715486550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1165; }","duration":"286.988014ms","start":"2026-04-10T04:17:13.952586Z","end":"2026-04-10T04:17:14.239574Z","steps":["trace[715486550] 'agreement among raft nodes before linearized reading' (duration: 286.945463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:14.239587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:13.655204Z","time spent":"584.375583ms","remote":"127.0.0.1:59224","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:17:14.239618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.752446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-10T04:17:14.239652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.642226ms","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:17:14.239647Z","caller":"traceutil/trace.go:171","msg":"trace[1486196380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1165; }","duration":"289.800936ms","start":"2026-04-10T04:17:13.949837Z","end":"2026-04-10T04:17:14.239638Z","steps":["trace[1486196380] 'agreement among raft nodes before linearized reading' (duration: 289.704274ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:14.239666Z","caller":"traceutil/trace.go:171","msg":"trace[1604132858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1165; }","duration":"331.676737ms","start":"2026-04-10T04:17:13.907985Z","end":"2026-04-10T04:17:14.239662Z","steps":["trace[1604132858] 'agreement among raft nodes before linearized reading' (duration: 331.626446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:14.239678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:13.90797Z","time spent":"331.705817ms","remote":"127.0.0.1:59224","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:17:14.239498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:13.572237Z","time spent":"667.175475ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:17:14.239382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:13.903749Z","time spent":"335.628401ms","remote":"127.0.0.1:58952","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:17:15.018545Z","caller":"traceutil/trace.go:171","msg":"trace[1540440067] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"773.790731ms","start":"2026-04-10T04:17:14.244736Z","end":"2026-04-10T04:17:15.018527Z","steps":["trace[1540440067] 'process raft request' (duration: 770.618251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:15.018688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:14.244716Z","time spent":"773.886822ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:17:15.018786Z","caller":"traceutil/trace.go:171","msg":"trace[60222278] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"773.958373ms","start":"2026-04-10T04:17:14.244806Z","end":"2026-04-10T04:17:15.018765Z","steps":["trace[60222278] 'process raft request' (duration: 773.690528ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:15.018837Z","caller":"traceutil/trace.go:171","msg":"trace[1474169298] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"773.691018ms","start":"2026-04-10T04:17:14.245128Z","end":"2026-04-10T04:17:15.018819Z","steps":["trace[1474169298] 'process raft request' (duration: 773.622387ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:15.018843Z","caller":"traceutil/trace.go:171","msg":"trace[729577870] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"773.712478ms","start":"2026-04-10T04:17:14.245099Z","end":"2026-04-10T04:17:15.018811Z","steps":["trace[729577870] 'process raft request' (duration: 773.568505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:15.018898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:14.244776Z","time spent":"774.053515ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:17:15.018967Z","caller":"traceutil/trace.go:171","msg":"trace[122502187] linearizableReadLoop","detail":"{readStateIndex:1213; appliedIndex:1208; }","duration":"114.992221ms","start":"2026-04-10T04:17:14.903953Z","end":"2026-04-10T04:17:15.018945Z","steps":["trace[122502187] 'read index received' (duration: 111.426015ms)","trace[122502187] 'applied index is now lower than readState.Index' (duration: 3.564706ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:17:15.018994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:14.24509Z","time spent":"773.82539ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:17:15.019017Z","caller":"traceutil/trace.go:171","msg":"trace[93515446] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"773.066646ms","start":"2026-04-10T04:17:14.24593Z","end":"2026-04-10T04:17:15.018997Z","steps":["trace[93515446] 'process raft request' (duration: 772.840632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:15.019075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:14.245922Z","time spent":"773.124477ms","remote":"127.0.0.1:59224","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:17:15.019175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:14.245114Z","time spent":"773.786179ms","remote":"127.0.0.1:59224","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:17:15.019724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.115644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:17:15.019806Z","caller":"traceutil/trace.go:171","msg":"trace[1978204593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1170; }","duration":"115.887298ms","start":"2026-04-10T04:17:14.903903Z","end":"2026-04-10T04:17:15.01979Z","steps":["trace[1978204593] 'agreement among raft nodes before linearized reading' (duration: 115.128434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:27.30236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.095373ms","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:17:27.302477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.238536ms","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:17:27.302511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.914577ms","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:17:27.302506Z","caller":"traceutil/trace.go:171","msg":"trace[911688929] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1205; }","duration":"137.276607ms","start":"2026-04-10T04:17:27.165207Z","end":"2026-04-10T04:17:27.302484Z","steps":["trace[911688929] 'range keys from in-memory index tree' (duration: 136.958711ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:27.302612Z","caller":"traceutil/trace.go:171","msg":"trace[649554684] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1205; }","duration":"139.062891ms","start":"2026-04-10T04:17:27.163529Z","end":"2026-04-10T04:17:27.302591Z","steps":["trace[649554684] 'range keys from in-memory index tree' (duration: 138.680993ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:27.302559Z","caller":"traceutil/trace.go:171","msg":"trace[1120560802] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1205; }","duration":"137.390889ms","start":"2026-04-10T04:17:27.165149Z","end":"2026-04-10T04:17:27.30254Z","steps":["trace[1120560802] 'range keys from in-memory index tree' (duration: 136.988041ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:27.466139Z","caller":"traceutil/trace.go:171","msg":"trace[244169910] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"158.39984ms","start":"2026-04-10T04:17:27.307713Z","end":"2026-04-10T04:17:27.466113Z","steps":["trace[244169910] 'process raft request' (duration: 112.486346ms)","trace[244169910] 'compare' (duration: 45.184571ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:17:27.466361Z","caller":"traceutil/trace.go:171","msg":"trace[64420628] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"157.491232ms","start":"2026-04-10T04:17:27.308859Z","end":"2026-04-10T04:17:27.46635Z","steps":["trace[64420628] 'process raft request' (duration: 156.654547ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:27.466529Z","caller":"traceutil/trace.go:171","msg":"trace[1334803094] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"156.299141ms","start":"2026-04-10T04:17:27.31022Z","end":"2026-04-10T04:17:27.46652Z","steps":["trace[1334803094] 'process raft request' (duration: 155.336443ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:29.252618Z","caller":"traceutil/trace.go:171","msg":"trace[462876937] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"115.818076ms","start":"2026-04-10T04:17:29.136766Z","end":"2026-04-10T04:17:29.252584Z","steps":["trace[462876937] 'process raft request' (duration: 115.619042ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:30.367591Z","caller":"traceutil/trace.go:171","msg":"trace[1356345647] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"255.161312ms","start":"2026-04-10T04:17:30.112391Z","end":"2026-04-10T04:17:30.367552Z","steps":["trace[1356345647] 'process raft request' (duration: 254.922897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:31.555801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.575834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:17:31.555902Z","caller":"traceutil/trace.go:171","msg":"trace[1569298766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1238; }","duration":"650.793299ms","start":"2026-04-10T04:17:30.905088Z","end":"2026-04-10T04:17:31.555881Z","steps":["trace[1569298766] 'range keys from in-memory index tree' (duration: 650.414021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:31.55594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:30.905055Z","time spent":"650.878211ms","remote":"127.0.0.1:58952","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:17:31.556198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.561928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-10T04:17:31.556287Z","caller":"traceutil/trace.go:171","msg":"trace[673784272] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1238; }","duration":"161.711942ms","start":"2026-04-10T04:17:31.394554Z","end":"2026-04-10T04:17:31.556265Z","steps":["trace[673784272] 'range keys from in-memory index tree' (duration: 161.398805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:31.556904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.040874ms","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:17:31.556933Z","caller":"traceutil/trace.go:171","msg":"trace[857460696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1238; }","duration":"295.128836ms","start":"2026-04-10T04:17:31.261796Z","end":"2026-04-10T04:17:31.556924Z","steps":["trace[857460696] 'range keys from in-memory index tree' (duration: 294.866471ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:31.8111Z","caller":"traceutil/trace.go:171","msg":"trace[115390731] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"249.702831ms","start":"2026-04-10T04:17:31.561375Z","end":"2026-04-10T04:17:31.811078Z","steps":["trace[115390731] 'process raft request' (duration: 249.544097ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:31.969721Z","caller":"traceutil/trace.go:171","msg":"trace[140869643] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"406.584942ms","start":"2026-04-10T04:17:31.563108Z","end":"2026-04-10T04:17:31.969693Z","steps":["trace[140869643] 'process raft request' (duration: 406.424309ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:31.969735Z","caller":"traceutil/trace.go:171","msg":"trace[903672802] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"407.921317ms","start":"2026-04-10T04:17:31.561748Z","end":"2026-04-10T04:17:31.96967Z","steps":["trace[903672802] 'process raft request' (duration: 407.751214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:31.969864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:31.563086Z","time spent":"406.703514ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:17:31.969823Z","caller":"traceutil/trace.go:171","msg":"trace[1253528059] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"408.277003ms","start":"2026-04-10T04:17:31.561487Z","end":"2026-04-10T04:17:31.969765Z","steps":["trace[1253528059] 'process raft request' (duration: 407.976698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:31.96994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:31.561737Z","time spent":"408.09368ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:17:31.97016Z","caller":"traceutil/trace.go:171","msg":"trace[311447821] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"408.701761ms","start":"2026-04-10T04:17:31.561441Z","end":"2026-04-10T04:17:31.970142Z","steps":["trace[311447821] 'process raft request' (duration: 407.890837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:31.970238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:31.561413Z","time spent":"408.790172ms","remote":"127.0.0.1:59224","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:17:31.970555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:31.561461Z","time spent":"408.487657ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:17:32.969108Z","caller":"traceutil/trace.go:171","msg":"trace[488721746] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"125.830923ms","start":"2026-04-10T04:17:32.843251Z","end":"2026-04-10T04:17:32.969082Z","steps":["trace[488721746] 'process raft request' (duration: 28.927218ms)","trace[488721746] 'compare' (duration: 96.457446ms)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:17:34.120359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.442601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:17:34.12044Z","caller":"traceutil/trace.go:171","msg":"trace[1046215249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1246; }","duration":"216.599734ms","start":"2026-04-10T04:17:33.903822Z","end":"2026-04-10T04:17:34.120421Z","steps":["trace[1046215249] 'range keys from in-memory index tree' (duration: 216.286388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:34.120496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.326517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-10T04:17:34.120573Z","caller":"traceutil/trace.go:171","msg":"trace[121622526] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1246; }","duration":"304.45305ms","start":"2026-04-10T04:17:33.816098Z","end":"2026-04-10T04:17:34.120552Z","steps":["trace[121622526] 'range keys from in-memory index tree' (duration: 304.093093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:34.120624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:33.816076Z","time spent":"304.535011ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-10T04:17:34.12061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.741475ms","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:17:34.120723Z","caller":"traceutil/trace.go:171","msg":"trace[1368816228] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1246; }","duration":"144.896628ms","start":"2026-04-10T04:17:33.975803Z","end":"2026-04-10T04:17:34.1207Z","steps":["trace[1368816228] 'range keys from in-memory index tree' (duration: 144.552291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:34.120757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.10112ms","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:17:34.120803Z","caller":"traceutil/trace.go:171","msg":"trace[1279114276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1246; }","duration":"146.184402ms","start":"2026-04-10T04:17:33.974603Z","end":"2026-04-10T04:17:34.120788Z","steps":["trace[1279114276] 'range keys from in-memory index tree' (duration: 145.995658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:34.120843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.474377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-10T04:17:34.12089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.477429ms","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:17:34.120906Z","caller":"traceutil/trace.go:171","msg":"trace[2023380445] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1246; }","duration":"146.60115ms","start":"2026-04-10T04:17:33.97429Z","end":"2026-04-10T04:17:34.120891Z","steps":["trace[2023380445] 'range keys from in-memory index tree' (duration: 146.275794ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:34.120927Z","caller":"traceutil/trace.go:171","msg":"trace[718363497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1246; }","duration":"145.51446ms","start":"2026-04-10T04:17:33.975402Z","end":"2026-04-10T04:17:34.120916Z","steps":["trace[718363497] 'range keys from in-memory index tree' (duration: 145.333737ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:34.495041Z","caller":"traceutil/trace.go:171","msg":"trace[65019862] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"369.52819ms","start":"2026-04-10T04:17:34.125474Z","end":"2026-04-10T04:17:34.495002Z","steps":["trace[65019862] 'process raft request' (duration: 369.252645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:34.495294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:34.125449Z","time spent":"369.718305ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:17:34.836073Z","caller":"traceutil/trace.go:171","msg":"trace[1963082342] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"710.555931ms","start":"2026-04-10T04:17:34.125492Z","end":"2026-04-10T04:17:34.836048Z","steps":["trace[1963082342] 'process raft request' (duration: 710.350848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:34.836227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:34.125473Z","time spent":"710.673993ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:17:34.836423Z","caller":"traceutil/trace.go:171","msg":"trace[1535913910] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"710.904548ms","start":"2026-04-10T04:17:34.125493Z","end":"2026-04-10T04:17:34.836398Z","steps":["trace[1535913910] 'process raft request' (duration: 710.503041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:34.836586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:34.125484Z","time spent":"711.009789ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:17:34.83683Z","caller":"traceutil/trace.go:171","msg":"trace[1022549378] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"711.00823ms","start":"2026-04-10T04:17:34.125811Z","end":"2026-04-10T04:17:34.836819Z","steps":["trace[1022549378] 'process raft request' (duration: 710.5073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:34.836899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:34.125793Z","time spent":"711.070502ms","remote":"127.0.0.1:59224","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:17:34.837152Z","caller":"traceutil/trace.go:171","msg":"trace[1231303684] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"711.106142ms","start":"2026-04-10T04:17:34.126035Z","end":"2026-04-10T04:17:34.837142Z","steps":["trace[1231303684] 'process raft request' (duration: 710.745766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:34.837217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:34.126025Z","time spent":"711.160814ms","remote":"127.0.0.1:59224","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:17:34.837193Z","caller":"traceutil/trace.go:171","msg":"trace[1627319232] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"420.930078ms","start":"2026-04-10T04:17:34.416237Z","end":"2026-04-10T04:17:34.837167Z","steps":["trace[1627319232] 'process raft request' (duration: 420.839137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:34.837386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:34.416205Z","time spent":"421.058791ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:17:37.036334Z","caller":"traceutil/trace.go:171","msg":"trace[951260139] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"531.754141ms","start":"2026-04-10T04:17:36.504551Z","end":"2026-04-10T04:17:37.036305Z","steps":["trace[951260139] 'process raft request' (duration: 531.564688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:37.036492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:36.504529Z","time spent":"531.879195ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:17:37.03672Z","caller":"traceutil/trace.go:171","msg":"trace[911268438] linearizableReadLoop","detail":"{readStateIndex:1306; appliedIndex:1305; }","duration":"195.081813ms","start":"2026-04-10T04:17:36.841623Z","end":"2026-04-10T04:17:37.036704Z","steps":["trace[911268438] 'read index received' (duration: 194.40345ms)","trace[911268438] 'applied index is now lower than readState.Index' (duration: 676.353µs)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:17:37.03693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.302667ms","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:17:37.036971Z","caller":"traceutil/trace.go:171","msg":"trace[144310994] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1259; }","duration":"195.382759ms","start":"2026-04-10T04:17:36.841578Z","end":"2026-04-10T04:17:37.036961Z","steps":["trace[144310994] 'agreement among raft nodes before linearized reading' (duration: 195.212876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:37.044634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.867229ms","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:17:37.044626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.370344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T04:17:37.044662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.866079ms","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:17:37.044684Z","caller":"traceutil/trace.go:171","msg":"trace[2128865874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1260; }","duration":"201.954941ms","start":"2026-04-10T04:17:36.842716Z","end":"2026-04-10T04:17:37.044671Z","steps":["trace[2128865874] 'agreement among raft nodes before linearized reading' (duration: 201.852819ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:37.044709Z","caller":"traceutil/trace.go:171","msg":"trace[876076903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1260; }","duration":"140.494236ms","start":"2026-04-10T04:17:36.904191Z","end":"2026-04-10T04:17:37.044685Z","steps":["trace[876076903] 'agreement among raft nodes before linearized reading' (duration: 140.337213ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:37.044884Z","caller":"traceutil/trace.go:171","msg":"trace[168136297] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"200.935603ms","start":"2026-04-10T04:17:36.843937Z","end":"2026-04-10T04:17:37.044873Z","steps":["trace[168136297] 'process raft request' (duration: 200.448353ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:37.044719Z","caller":"traceutil/trace.go:171","msg":"trace[1057117479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1260; }","duration":"201.957001ms","start":"2026-04-10T04:17:36.842749Z","end":"2026-04-10T04:17:37.044706Z","steps":["trace[1057117479] 'agreement among raft nodes before linearized reading' (duration: 201.796858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:37.045189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.382868ms","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:17:37.045237Z","caller":"traceutil/trace.go:171","msg":"trace[319763350] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1260; }","duration":"203.455239ms","start":"2026-04-10T04:17:36.841771Z","end":"2026-04-10T04:17:37.045226Z","steps":["trace[319763350] 'agreement among raft nodes before linearized reading' (duration: 202.687194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:37.510582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.428985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:17:37.510664Z","caller":"traceutil/trace.go:171","msg":"trace[2066625071] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1264; }","duration":"227.602868ms","start":"2026-04-10T04:17:37.283043Z","end":"2026-04-10T04:17:37.510646Z","steps":["trace[2066625071] 'count revisions from in-memory index tree' (duration: 227.330544ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:39.170568Z","caller":"traceutil/trace.go:171","msg":"trace[1690275733] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"124.320626ms","start":"2026-04-10T04:17:39.046209Z","end":"2026-04-10T04:17:39.17053Z","steps":["trace[1690275733] 'process raft request' (duration: 124.03018ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:39.171869Z","caller":"traceutil/trace.go:171","msg":"trace[1235750198] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1321; }","duration":"107.777047ms","start":"2026-04-10T04:17:39.064066Z","end":"2026-04-10T04:17:39.171843Z","steps":["trace[1235750198] 'read index received' (duration: 106.238349ms)","trace[1235750198] 'applied index is now lower than readState.Index' (duration: 1.537608ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:17:39.172207Z","caller":"traceutil/trace.go:171","msg":"trace[1613792398] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"121.473533ms","start":"2026-04-10T04:17:39.050631Z","end":"2026-04-10T04:17:39.172105Z","steps":["trace[1613792398] 'process raft request' (duration: 121.084226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:39.172418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.322687ms","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:17:39.172484Z","caller":"traceutil/trace.go:171","msg":"trace[818952703] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1275; }","duration":"108.437099ms","start":"2026-04-10T04:17:39.064032Z","end":"2026-04-10T04:17:39.172469Z","steps":["trace[818952703] 'agreement among raft nodes before linearized reading' (duration: 108.184405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:45.816706Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150296666925616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T04:17:46.317835Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150296666925616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T04:17:46.819688Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150296666925616,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T04:17:47.248001Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.93424865s","expected-duration":"1s"} {"level":"info","ts":"2026-04-10T04:17:47.24862Z","caller":"traceutil/trace.go:171","msg":"trace[1243548464] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1373; }","duration":"1.932766742s","start":"2026-04-10T04:17:45.315826Z","end":"2026-04-10T04:17:47.248592Z","steps":["trace[1243548464] 'read index received' (duration: 1.932346194s)","trace[1243548464] 'applied index is now lower than readState.Index' (duration: 419.648µs)"],"step_count":2} {"level":"warn","ts":"2026-04-10T04:17:47.248893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.933035916s","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:17:47.248856Z","caller":"traceutil/trace.go:171","msg":"trace[448697269] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"1.935110255s","start":"2026-04-10T04:17:45.313636Z","end":"2026-04-10T04:17:47.248746Z","steps":["trace[448697269] 'process raft request' (duration: 1.934737819s)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:47.24895Z","caller":"traceutil/trace.go:171","msg":"trace[785593008] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1325; }","duration":"1.93315671s","start":"2026-04-10T04:17:45.315778Z","end":"2026-04-10T04:17:47.248935Z","steps":["trace[785593008] 'agreement among raft nodes before linearized reading' (duration: 1.932930825s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:47.248996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:45.315757Z","time spent":"1.933228201s","remote":"127.0.0.1:59224","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:17:47.249147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:45.313613Z","time spent":"1.935370942s","remote":"127.0.0.1:59224","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:17:47.249179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.572642167s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T04:17:47.249229Z","caller":"traceutil/trace.go:171","msg":"trace[1885817890] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1325; }","duration":"1.572703628s","start":"2026-04-10T04:17:45.676513Z","end":"2026-04-10T04:17:47.249216Z","steps":["trace[1885817890] 'agreement among raft nodes before linearized reading' (duration: 1.572613326s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:47.24922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.453637161s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T04:17:47.249319Z","caller":"traceutil/trace.go:171","msg":"trace[365164645] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1325; }","duration":"1.453809823s","start":"2026-04-10T04:17:45.795486Z","end":"2026-04-10T04:17:47.249296Z","steps":["trace[365164645] 'agreement among raft nodes before linearized reading' (duration: 1.453589979s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:47.249354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.524697463s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-04-10T04:17:47.24937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:45.795464Z","time spent":"1.453895855s","remote":"127.0.0.1:59038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-04-10T04:17:47.249389Z","caller":"traceutil/trace.go:171","msg":"trace[686423883] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1325; }","duration":"1.524757814s","start":"2026-04-10T04:17:45.724622Z","end":"2026-04-10T04:17:47.24938Z","steps":["trace[686423883] 'agreement among raft nodes before linearized reading' (duration: 1.524666322s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:47.249417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:45.724611Z","time spent":"1.524800555s","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-10T04:17:47.249554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.931719953s","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:17:47.249579Z","caller":"traceutil/trace.go:171","msg":"trace[450869469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1325; }","duration":"1.931755994s","start":"2026-04-10T04:17:45.317814Z","end":"2026-04-10T04:17:47.24957Z","steps":["trace[450869469] 'agreement among raft nodes before linearized reading' (duration: 1.931673183s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:47.249577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.781138598s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-04-10T04:17:47.249603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:45.317808Z","time spent":"1.931789355s","remote":"127.0.0.1:59224","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:17:47.249265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:45.676443Z","time spent":"1.5728131s","remote":"127.0.0.1:59342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-04-10T04:17:47.249638Z","caller":"traceutil/trace.go:171","msg":"trace[425328872] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1325; }","duration":"1.78125055s","start":"2026-04-10T04:17:45.468372Z","end":"2026-04-10T04:17:47.249623Z","steps":["trace[425328872] 'agreement among raft nodes before linearized reading' (duration: 1.781016156s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:47.249677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:45.468346Z","time spent":"1.781323362s","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-10T04:17:47.249713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.472825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-10T04:17:47.249762Z","caller":"traceutil/trace.go:171","msg":"trace[1199460629] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1325; }","duration":"743.559316ms","start":"2026-04-10T04:17:46.506186Z","end":"2026-04-10T04:17:47.249745Z","steps":["trace[1199460629] 'agreement among raft nodes before linearized reading' (duration: 743.438484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:47.249799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:46.506167Z","time spent":"743.622368ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-10T04:17:47.24983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.645849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:17:47.249861Z","caller":"traceutil/trace.go:171","msg":"trace[198283496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1325; }","duration":"343.71154ms","start":"2026-04-10T04:17:46.90614Z","end":"2026-04-10T04:17:47.249852Z","steps":["trace[198283496] 'agreement among raft nodes before linearized reading' (duration: 343.661659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:47.249893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:46.906119Z","time spent":"343.763671ms","remote":"127.0.0.1:58952","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:17:47.249916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.539961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-10T04:17:47.250017Z","caller":"traceutil/trace.go:171","msg":"trace[1272753357] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1325; }","duration":"173.699725ms","start":"2026-04-10T04:17:47.076291Z","end":"2026-04-10T04:17:47.249991Z","steps":["trace[1272753357] 'agreement among raft nodes before linearized reading' (duration: 173.46111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:47.250396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.934348613s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-10T04:17:47.250462Z","caller":"traceutil/trace.go:171","msg":"trace[702638259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1325; }","duration":"1.934458834s","start":"2026-04-10T04:17:45.315988Z","end":"2026-04-10T04:17:47.250446Z","steps":["trace[702638259] 'agreement among raft nodes before linearized reading' (duration: 1.933081678s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:47.250508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:45.31597Z","time spent":"1.934528826s","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-10T04:17:47.765789Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150296666925620,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T04:17:48.266294Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150296666925620,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T04:17:48.767482Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":988150296666925620,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-10T04:17:49.239023Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.985085086s","expected-duration":"1s"} {"level":"info","ts":"2026-04-10T04:17:49.242972Z","caller":"traceutil/trace.go:171","msg":"trace[2081656636] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"1.989120642s","start":"2026-04-10T04:17:47.253829Z","end":"2026-04-10T04:17:49.242949Z","steps":["trace[2081656636] 'process raft request' (duration: 1.986586084s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:49.24312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:47.253804Z","time spent":"1.989221903s","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:17:49.248637Z","caller":"traceutil/trace.go:171","msg":"trace[1967509688] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"1.991949964s","start":"2026-04-10T04:17:47.256665Z","end":"2026-04-10T04:17:49.248615Z","steps":["trace[1967509688] 'process raft request' (duration: 1.991820762s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:49.24875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:47.256623Z","time spent":"1.992063187s","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:17:49.248893Z","caller":"traceutil/trace.go:171","msg":"trace[1735811830] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"1.991068877s","start":"2026-04-10T04:17:47.257812Z","end":"2026-04-10T04:17:49.248881Z","steps":["trace[1735811830] 'process raft request' (duration: 1.991011256s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:49.248955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:47.257791Z","time spent":"1.991132329s","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:17:49.248944Z","caller":"traceutil/trace.go:171","msg":"trace[1612611063] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"1.99121431s","start":"2026-04-10T04:17:47.257704Z","end":"2026-04-10T04:17:49.248918Z","steps":["trace[1612611063] 'process raft request' (duration: 1.991069587s)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:49.249085Z","caller":"traceutil/trace.go:171","msg":"trace[1327217013] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"1.991243251s","start":"2026-04-10T04:17:47.25783Z","end":"2026-04-10T04:17:49.249073Z","steps":["trace[1327217013] 'process raft request' (duration: 1.991021857s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:49.249115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:47.257693Z","time spent":"1.991323991s","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-10T04:17:49.249147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:47.257813Z","time spent":"1.991298701s","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:17:49.249172Z","caller":"traceutil/trace.go:171","msg":"trace[1961603] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"1.990217631s","start":"2026-04-10T04:17:47.258903Z","end":"2026-04-10T04:17:49.24912Z","steps":["trace[1961603] 'process raft request' (duration: 1.990037638s)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:17:49.249236Z","caller":"traceutil/trace.go:171","msg":"trace[1686248042] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"1.273099497s","start":"2026-04-10T04:17:47.976125Z","end":"2026-04-10T04:17:49.249225Z","steps":["trace[1686248042] 'process raft request' (duration: 1.272871943s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:49.249298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:47.976091Z","time spent":"1.273173539s","remote":"127.0.0.1:59224","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:17:49.249352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:47.258894Z","time spent":"1.990367034s","remote":"127.0.0.1:59224","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:17:49.249463Z","caller":"traceutil/trace.go:171","msg":"trace[481576634] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"1.992358232s","start":"2026-04-10T04:17:47.256499Z","end":"2026-04-10T04:17:49.248857Z","steps":["trace[481576634] 'process raft request' (duration: 1.992082697s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:49.24955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:47.256468Z","time spent":"1.993045433s","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-10T04:17:49.258019Z","caller":"traceutil/trace.go:171","msg":"trace[690059879] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1374; }","duration":"1.993019973s","start":"2026-04-10T04:17:47.264982Z","end":"2026-04-10T04:17:49.258002Z","steps":["trace[690059879] 'read index received' (duration: 1.974287455s)","trace[690059879] 'applied index is now lower than readState.Index' (duration: 18.731558ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:17:49.258075Z","caller":"traceutil/trace.go:171","msg":"trace[2022364961] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"258.32229ms","start":"2026-04-10T04:17:48.999707Z","end":"2026-04-10T04:17:49.258029Z","steps":["trace[2022364961] 'process raft request' (duration: 258.203888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:49.258168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.657616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-10T04:17:49.258227Z","caller":"traceutil/trace.go:171","msg":"trace[503109472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1334; }","duration":"854.763188ms","start":"2026-04-10T04:17:48.403455Z","end":"2026-04-10T04:17:49.258218Z","steps":["trace[503109472] 'agreement among raft nodes before linearized reading' (duration: 854.676217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:49.258277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:48.403433Z","time spent":"854.837189ms","remote":"127.0.0.1:58952","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:17:49.258201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.993209158s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-10T04:17:49.258368Z","caller":"traceutil/trace.go:171","msg":"trace[442281834] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1334; }","duration":"1.993379751s","start":"2026-04-10T04:17:47.264977Z","end":"2026-04-10T04:17:49.258356Z","steps":["trace[442281834] 'agreement among raft nodes before linearized reading' (duration: 1.993099176s)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:49.258389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.586164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-10T04:17:49.25841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:47.264945Z","time spent":"1.993455392s","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-10T04:17:49.258424Z","caller":"traceutil/trace.go:171","msg":"trace[998932081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1334; }","duration":"354.661825ms","start":"2026-04-10T04:17:48.903753Z","end":"2026-04-10T04:17:49.258415Z","steps":["trace[998932081] 'agreement among raft nodes before linearized reading' (duration: 354.593023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:49.258449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:48.903732Z","time spent":"354.711406ms","remote":"127.0.0.1:58948","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:17:49.258527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.281907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-10T04:17:49.258576Z","caller":"traceutil/trace.go:171","msg":"trace[302451800] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1334; }","duration":"666.406079ms","start":"2026-04-10T04:17:48.592156Z","end":"2026-04-10T04:17:49.258562Z","steps":["trace[302451800] 'agreement among raft nodes before linearized reading' (duration: 666.293097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:17:49.258608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-10T04:17:48.592127Z","time spent":"666.472331ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-10T04:18:29.51989Z","caller":"traceutil/trace.go:171","msg":"trace[656703369] transaction","detail":"{read_only:false; response_revision:1513; number_of_response:1; }","duration":"164.066343ms","start":"2026-04-10T04:18:29.355799Z","end":"2026-04-10T04:18:29.519866Z","steps":["trace[656703369] 'process raft request' (duration: 76.188733ms)","trace[656703369] 'compare' (duration: 87.069366ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:18:45.761731Z","caller":"traceutil/trace.go:171","msg":"trace[321226188] transaction","detail":"{read_only:false; response_revision:1590; number_of_response:1; }","duration":"113.470985ms","start":"2026-04-10T04:18:45.648226Z","end":"2026-04-10T04:18:45.761697Z","steps":["trace[321226188] 'process raft request' (duration: 36.673542ms)","trace[321226188] 'compare' (duration: 76.63999ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:18:52.087802Z","caller":"traceutil/trace.go:171","msg":"trace[1583565795] transaction","detail":"{read_only:false; response_revision:1622; number_of_response:1; }","duration":"138.778065ms","start":"2026-04-10T04:18:51.948991Z","end":"2026-04-10T04:18:52.087769Z","steps":["trace[1583565795] 'process raft request' (duration: 70.974497ms)","trace[1583565795] 'compare' (duration: 67.672386ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:18:56.043061Z","caller":"traceutil/trace.go:171","msg":"trace[1293296573] transaction","detail":"{read_only:false; response_revision:1639; number_of_response:1; }","duration":"104.849075ms","start":"2026-04-10T04:18:55.93817Z","end":"2026-04-10T04:18:56.043019Z","steps":["trace[1293296573] 'process raft request' (duration: 21.243353ms)","trace[1293296573] 'compare' (duration: 83.302065ms)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:19:32.244669Z","caller":"traceutil/trace.go:171","msg":"trace[909510386] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"138.872295ms","start":"2026-04-10T04:19:32.105769Z","end":"2026-04-10T04:19:32.244641Z","steps":["trace[909510386] 'process raft request' (duration: 138.651891ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:19:32.24469Z","caller":"traceutil/trace.go:171","msg":"trace[1314298246] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"138.825524ms","start":"2026-04-10T04:19:32.105838Z","end":"2026-04-10T04:19:32.244664Z","steps":["trace[1314298246] 'process raft request' (duration: 138.713352ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:19:40.519936Z","caller":"traceutil/trace.go:171","msg":"trace[1614682913] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"121.796119ms","start":"2026-04-10T04:19:40.398113Z","end":"2026-04-10T04:19:40.519909Z","steps":["trace[1614682913] 'process raft request' (duration: 121.692267ms)"],"step_count":1} {"level":"info","ts":"2026-04-10T04:20:54.778146Z","caller":"traceutil/trace.go:171","msg":"trace[1696294463] linearizableReadLoop","detail":"{readStateIndex:2292; appliedIndex:2291; }","duration":"105.276262ms","start":"2026-04-10T04:20:54.672846Z","end":"2026-04-10T04:20:54.778122Z","steps":["trace[1696294463] 'read index received' (duration: 105.122809ms)","trace[1696294463] 'applied index is now lower than readState.Index' (duration: 152.013µs)"],"step_count":2} {"level":"info","ts":"2026-04-10T04:20:54.77832Z","caller":"traceutil/trace.go:171","msg":"trace[849929206] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"134.025225ms","start":"2026-04-10T04:20:54.644282Z","end":"2026-04-10T04:20:54.778307Z","steps":["trace[849929206] 'process raft request' (duration: 133.695789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-10T04:20:54.778653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.798253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-10T04:20:54.778709Z","caller":"traceutil/trace.go:171","msg":"trace[1514033348] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2204; }","duration":"105.916185ms","start":"2026-04-10T04:20:54.672779Z","end":"2026-04-10T04:20:54.778695Z","steps":["trace[1514033348] 'agreement among raft nodes before linearized reading' (duration: 105.784633ms)"],"step_count":1}