{"level":"warn","ts":"2026-07-21T20:57:40.647027Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T20:57:40.647121Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248: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.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248: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-07-21T20:57:40.647212Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T20:57:40.647224Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-07-21T20:57:40.647246Z","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-07-21T20:57:40.648192Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-07-21T20:57:40.64834Z","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.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248: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-07-21T20:57:40.651432Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.745551ms"} {"level":"info","ts":"2026-07-21T20:57:40.665567Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-07-21T20:57:40.665868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-07-21T20:57:40.665985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-07-21T20:57:40.666205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-21T20:57:40.666215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-07-21T20:57:40.666375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-07-21T20:57:40.67009Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-21T20:57:40.672025Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-21T20:57:40.673708Z","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-07-21T20:57:40.675795Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-21T20:57:40.676569Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-21T20:57:40.676702Z","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-07-21T20:57:40.676763Z","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-07-21T20:57:40.676777Z","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-07-21T20:57:40.679343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-07-21T20:57:40.679727Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-07-21T20:57:40.680924Z","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-07-21T20:57:40.681058Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-07-21T20:57:40.681093Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-07-21T20:57:40.681405Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-21T20:57:40.681452Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-21T20:57:41.66673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-07-21T20:57:41.666797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-21T20:57:41.666816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-07-21T20:57:41.666829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-07-21T20:57:41.666836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-07-21T20:57:41.666851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-07-21T20:57:41.66686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-07-21T20:57:41.66765Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-07-21T20:57:41.66766Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T20:57:41.667724Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T20:57:41.667742Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:57:41.667788Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-21T20:57:41.667855Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-21T20:57:41.668521Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:57:41.668584Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:57:41.668606Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T20:57:41.671028Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-21T20:57:41.672587Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-07-21T20:58:07.610815Z","caller":"traceutil/trace.go:171","msg":"trace[1530786306] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"319.285203ms","start":"2026-07-21T20:58:07.291508Z","end":"2026-07-21T20:58:07.610793Z","steps":["trace[1530786306] 'process raft request' (duration: 319.160579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:07.611341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:07.291494Z","time spent":"319.485078ms","remote":"127.0.0.1:37460","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-07-21T20:58:07.911162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.558113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-21T20:58:07.91131Z","caller":"traceutil/trace.go:171","msg":"trace[625076519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:424; }","duration":"166.752557ms","start":"2026-07-21T20:58:07.744538Z","end":"2026-07-21T20:58:07.91129Z","steps":["trace[625076519] 'range keys from in-memory index tree' (duration: 166.399128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:07.911625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.8668ms","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-07-21T20:58:07.911772Z","caller":"traceutil/trace.go:171","msg":"trace[1368576983] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:424; }","duration":"205.050405ms","start":"2026-07-21T20:58:07.706697Z","end":"2026-07-21T20:58:07.911747Z","steps":["trace[1368576983] 'range keys from in-memory index tree' (duration: 204.747747ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:58:09.89363Z","caller":"traceutil/trace.go:171","msg":"trace[2097948258] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"273.38286ms","start":"2026-07-21T20:58:09.620223Z","end":"2026-07-21T20:58:09.893606Z","steps":["trace[2097948258] 'process raft request' (duration: 273.213236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:20.916606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.799576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T20:58:20.916626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.743984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:58:20.916679Z","caller":"traceutil/trace.go:171","msg":"trace[1433219806] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:589; }","duration":"228.050231ms","start":"2026-07-21T20:58:20.688614Z","end":"2026-07-21T20:58:20.916664Z","steps":["trace[1433219806] 'range keys from in-memory index tree' (duration: 227.612441ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:58:20.9167Z","caller":"traceutil/trace.go:171","msg":"trace[1502201644] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:589; }","duration":"227.866948ms","start":"2026-07-21T20:58:20.688814Z","end":"2026-07-21T20:58:20.916681Z","steps":["trace[1502201644] 'range keys from in-memory index tree' (duration: 227.676712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:20.916806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.844952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:58:20.917351Z","caller":"traceutil/trace.go:171","msg":"trace[1824755794] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:589; }","duration":"227.450367ms","start":"2026-07-21T20:58:20.689883Z","end":"2026-07-21T20:58:20.917334Z","steps":["trace[1824755794] 'count revisions from in-memory index tree' (duration: 226.736439ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:58:20.91754Z","caller":"traceutil/trace.go:171","msg":"trace[162893066] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"226.233747ms","start":"2026-07-21T20:58:20.691296Z","end":"2026-07-21T20:58:20.91753Z","steps":["trace[162893066] 'process raft request' (duration: 221.239419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:21.643256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.785194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:58:21.643356Z","caller":"traceutil/trace.go:171","msg":"trace[1867545065] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"472.511184ms","start":"2026-07-21T20:58:21.17083Z","end":"2026-07-21T20:58:21.643341Z","steps":["trace[1867545065] 'read index received' (duration: 116.394142ms)","trace[1867545065] 'applied index is now lower than readState.Index' (duration: 356.116022ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:58:21.643391Z","caller":"traceutil/trace.go:171","msg":"trace[775776055] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"497.520223ms","start":"2026-07-21T20:58:21.145839Z","end":"2026-07-21T20:58:21.643359Z","steps":["trace[775776055] 'process raft request' (duration: 141.443802ms)","trace[775776055] 'compare' (duration: 355.64356ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:58:21.643505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.66333ms","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":"warn","ts":"2026-07-21T20:58:21.643519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:21.145814Z","time spent":"497.660186ms","remote":"127.0.0.1:37534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T20:58:21.643538Z","caller":"traceutil/trace.go:171","msg":"trace[1749383257] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:596; }","duration":"472.724151ms","start":"2026-07-21T20:58:21.170806Z","end":"2026-07-21T20:58:21.64353Z","steps":["trace[1749383257] 'agreement among raft nodes before linearized reading' (duration: 472.594367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:21.64357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:21.170791Z","time spent":"472.770571ms","remote":"127.0.0.1:45292","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-07-21T20:58:21.652672Z","caller":"traceutil/trace.go:171","msg":"trace[740744182] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"362.899396ms","start":"2026-07-21T20:58:21.289756Z","end":"2026-07-21T20:58:21.652655Z","steps":["trace[740744182] 'process raft request' (duration: 362.810714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:21.652771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:21.289733Z","time spent":"362.990448ms","remote":"127.0.0.1:37236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:58:21.654876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.920924ms","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":"warn","ts":"2026-07-21T20:58:21.654875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.357034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:58:21.654923Z","caller":"traceutil/trace.go:171","msg":"trace[1497304061] 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:598; }","duration":"310.004115ms","start":"2026-07-21T20:58:21.344908Z","end":"2026-07-21T20:58:21.654912Z","steps":["trace[1497304061] 'agreement among raft nodes before linearized reading' (duration: 309.916073ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:58:21.654936Z","caller":"traceutil/trace.go:171","msg":"trace[1977071165] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:598; }","duration":"366.498868ms","start":"2026-07-21T20:58:21.288424Z","end":"2026-07-21T20:58:21.654923Z","steps":["trace[1977071165] 'agreement among raft nodes before linearized reading' (duration: 366.379255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:21.65495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:21.344892Z","time spent":"310.050427ms","remote":"127.0.0.1:45352","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-07-21T20:58:21.654975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.779181ms","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":"warn","ts":"2026-07-21T20:58:21.655024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.853312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:58:21.655051Z","caller":"traceutil/trace.go:171","msg":"trace[602926408] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:598; }","duration":"368.17857ms","start":"2026-07-21T20:58:21.286865Z","end":"2026-07-21T20:58:21.655044Z","steps":["trace[602926408] 'agreement among raft nodes before linearized reading' (duration: 368.130029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:21.655061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.378516ms","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":"warn","ts":"2026-07-21T20:58:21.655098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.401576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:58:21.655127Z","caller":"traceutil/trace.go:171","msg":"trace[2035571348] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:598; }","duration":"368.458797ms","start":"2026-07-21T20:58:21.286662Z","end":"2026-07-21T20:58:21.655121Z","steps":["trace[2035571348] 'agreement among raft nodes before linearized reading' (duration: 368.419706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:21.655139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.489069ms","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-07-21T20:58:21.655171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.959455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:58:21.655171Z","caller":"traceutil/trace.go:171","msg":"trace[965962099] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"363.605444ms","start":"2026-07-21T20:58:21.291554Z","end":"2026-07-21T20:58:21.65516Z","steps":["trace[965962099] 'process raft request' (duration: 363.111072ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:58:21.655189Z","caller":"traceutil/trace.go:171","msg":"trace[801680132] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:598; }","duration":"408.000378ms","start":"2026-07-21T20:58:21.247184Z","end":"2026-07-21T20:58:21.655184Z","steps":["trace[801680132] 'agreement among raft nodes before linearized reading' (duration: 407.974017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:21.655212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:21.247153Z","time spent":"408.053379ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-21T20:58:21.655211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.052122ms","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-07-21T20:58:21.655237Z","caller":"traceutil/trace.go:171","msg":"trace[197224264] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:598; }","duration":"349.104223ms","start":"2026-07-21T20:58:21.306124Z","end":"2026-07-21T20:58:21.655228Z","steps":["trace[197224264] 'agreement among raft nodes before linearized reading' (duration: 349.069102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:21.655275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:21.306085Z","time spent":"349.181285ms","remote":"127.0.0.1:45338","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-07-21T20:58:21.655316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.360694ms","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-07-21T20:58:21.65535Z","caller":"traceutil/trace.go:171","msg":"trace[1203358640] 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:598; }","duration":"310.413205ms","start":"2026-07-21T20:58:21.344928Z","end":"2026-07-21T20:58:21.655341Z","steps":["trace[1203358640] 'agreement among raft nodes before linearized reading' (duration: 310.369214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:21.655383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:21.344918Z","time spent":"310.455947ms","remote":"127.0.0.1:45352","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-07-21T20:58:21.65539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.207323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T20:58:21.654939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.259361ms","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-07-21T20:58:21.65544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.022126ms","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-07-21T20:58:21.655439Z","caller":"traceutil/trace.go:171","msg":"trace[2090530612] 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:598; }","duration":"310.783566ms","start":"2026-07-21T20:58:21.344646Z","end":"2026-07-21T20:58:21.655429Z","steps":["trace[2090530612] 'agreement among raft nodes before linearized reading' (duration: 310.270732ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:58:21.655464Z","caller":"traceutil/trace.go:171","msg":"trace[1535288391] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:598; }","duration":"458.060036ms","start":"2026-07-21T20:58:21.197397Z","end":"2026-07-21T20:58:21.655457Z","steps":["trace[1535288391] 'agreement among raft nodes before linearized reading' (duration: 458.029475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:21.655474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:21.344628Z","time spent":"310.836636ms","remote":"127.0.0.1:45352","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-07-21T20:58:21.655488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:21.19739Z","time spent":"458.093137ms","remote":"127.0.0.1:45304","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-07-21T20:58:21.65524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:21.291535Z","time spent":"363.662655ms","remote":"127.0.0.1:37236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4165,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:58:21.655283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.658295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T20:58:21.655611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.513668ms","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-07-21T20:58:21.655633Z","caller":"traceutil/trace.go:171","msg":"trace[845343272] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:598; }","duration":"458.550288ms","start":"2026-07-21T20:58:21.197077Z","end":"2026-07-21T20:58:21.655627Z","steps":["trace[845343272] 'agreement among raft nodes before linearized reading' (duration: 458.510227ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:58:21.655635Z","caller":"traceutil/trace.go:171","msg":"trace[1334338751] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:598; }","duration":"167.070466ms","start":"2026-07-21T20:58:21.488553Z","end":"2026-07-21T20:58:21.655623Z","steps":["trace[1334338751] 'agreement among raft nodes before linearized reading' (duration: 166.707546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:21.655144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:21.286645Z","time spent":"368.494609ms","remote":"127.0.0.1:45330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-21T20:58:21.655684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.62289ms","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-07-21T20:58:21.655705Z","caller":"traceutil/trace.go:171","msg":"trace[2065981229] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:598; }","duration":"458.662841ms","start":"2026-07-21T20:58:21.197038Z","end":"2026-07-21T20:58:21.655701Z","steps":["trace[2065981229] 'agreement among raft nodes before linearized reading' (duration: 458.63297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:21.655722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:21.197028Z","time spent":"458.689162ms","remote":"127.0.0.1:45304","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":"info","ts":"2026-07-21T20:58:21.655171Z","caller":"traceutil/trace.go:171","msg":"trace[1785049902] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:598; }","duration":"332.53873ms","start":"2026-07-21T20:58:21.322625Z","end":"2026-07-21T20:58:21.655163Z","steps":["trace[1785049902] 'agreement among raft nodes before linearized reading' (duration: 332.493989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:21.655785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:21.322611Z","time spent":"333.161277ms","remote":"127.0.0.1:45350","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-07-21T20:58:21.655069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:21.286749Z","time spent":"368.315464ms","remote":"127.0.0.1:45330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-07-21T20:58:21.655837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.848789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:58:21.655872Z","caller":"traceutil/trace.go:171","msg":"trace[422551963] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:598; }","duration":"484.939811ms","start":"2026-07-21T20:58:21.170922Z","end":"2026-07-21T20:58:21.655862Z","steps":["trace[422551963] 'agreement among raft nodes before linearized reading' (duration: 484.89105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:21.655902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:21.170899Z","time spent":"484.995053ms","remote":"127.0.0.1:45292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 "} {"level":"warn","ts":"2026-07-21T20:58:21.654977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:21.288394Z","time spent":"366.57578ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 "} {"level":"info","ts":"2026-07-21T20:58:21.655094Z","caller":"traceutil/trace.go:171","msg":"trace[1416444140] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:598; }","duration":"332.441658ms","start":"2026-07-21T20:58:21.322642Z","end":"2026-07-21T20:58:21.655084Z","steps":["trace[1416444140] 'agreement among raft nodes before linearized reading' (duration: 332.400567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:21.655979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:21.322622Z","time spent":"333.345492ms","remote":"127.0.0.1:45350","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-07-21T20:58:21.656036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.176398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:58:21.656066Z","caller":"traceutil/trace.go:171","msg":"trace[1178249083] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:598; }","duration":"485.20899ms","start":"2026-07-21T20:58:21.170849Z","end":"2026-07-21T20:58:21.656058Z","steps":["trace[1178249083] 'agreement among raft nodes before linearized reading' (duration: 485.159998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:21.656098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:21.17084Z","time spent":"485.250131ms","remote":"127.0.0.1:45292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 "} {"level":"info","ts":"2026-07-21T20:58:21.65502Z","caller":"traceutil/trace.go:171","msg":"trace[1299348988] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:598; }","duration":"331.820362ms","start":"2026-07-21T20:58:21.323186Z","end":"2026-07-21T20:58:21.655007Z","steps":["trace[1299348988] 'agreement among raft nodes before linearized reading' (duration: 331.770331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:21.656238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:21.323167Z","time spent":"333.049534ms","remote":"127.0.0.1:45338","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-07-21T20:58:21.654886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.727404ms","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-07-21T20:58:21.656341Z","caller":"traceutil/trace.go:171","msg":"trace[1365211713] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:598; }","duration":"350.216471ms","start":"2026-07-21T20:58:21.306116Z","end":"2026-07-21T20:58:21.656332Z","steps":["trace[1365211713] 'agreement among raft nodes before linearized reading' (duration: 348.685552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:21.656372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:21.306099Z","time spent":"350.260063ms","remote":"127.0.0.1:45338","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-07-21T20:58:21.65566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:21.197071Z","time spent":"458.581839ms","remote":"127.0.0.1:45304","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":"info","ts":"2026-07-21T20:58:21.655415Z","caller":"traceutil/trace.go:171","msg":"trace[1248030143] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:598; }","duration":"408.258535ms","start":"2026-07-21T20:58:21.247149Z","end":"2026-07-21T20:58:21.655407Z","steps":["trace[1248030143] 'agreement among raft nodes before linearized reading' (duration: 408.220774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:21.657207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:21.247131Z","time spent":"410.06001ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"info","ts":"2026-07-21T20:58:28.400363Z","caller":"traceutil/trace.go:171","msg":"trace[1709019018] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"273.404061ms","start":"2026-07-21T20:58:28.126935Z","end":"2026-07-21T20:58:28.400339Z","steps":["trace[1709019018] 'read index received' (duration: 273.289749ms)","trace[1709019018] 'applied index is now lower than readState.Index' (duration: 113.342µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:58:28.400516Z","caller":"traceutil/trace.go:171","msg":"trace[1489654753] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"400.644929ms","start":"2026-07-21T20:58:27.999833Z","end":"2026-07-21T20:58:28.400478Z","steps":["trace[1489654753] 'process raft request' (duration: 400.348012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:28.400606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.640427ms","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-07-21T20:58:28.400678Z","caller":"traceutil/trace.go:171","msg":"trace[1882412538] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:655; }","duration":"273.766389ms","start":"2026-07-21T20:58:28.126894Z","end":"2026-07-21T20:58:28.40066Z","steps":["trace[1882412538] 'agreement among raft nodes before linearized reading' (duration: 273.558354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:28.400681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:27.999809Z","time spent":"400.787973ms","remote":"127.0.0.1:37460","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-07-21T20:58:28.400762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.516464ms","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-07-21T20:58:28.400855Z","caller":"traceutil/trace.go:171","msg":"trace[572836044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:655; }","duration":"266.653298ms","start":"2026-07-21T20:58:28.134179Z","end":"2026-07-21T20:58:28.400832Z","steps":["trace[572836044] 'agreement among raft nodes before linearized reading' (duration: 266.36863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:29.642718Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874242277031867,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T20:58:29.783146Z","caller":"traceutil/trace.go:171","msg":"trace[408956035] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:685; }","duration":"640.720139ms","start":"2026-07-21T20:58:29.142401Z","end":"2026-07-21T20:58:29.783121Z","steps":["trace[408956035] 'read index received' (duration: 640.561055ms)","trace[408956035] 'applied index is now lower than readState.Index' (duration: 157.874µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:58:29.783266Z","caller":"traceutil/trace.go:171","msg":"trace[1189739254] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"762.122028ms","start":"2026-07-21T20:58:29.021114Z","end":"2026-07-21T20:58:29.783236Z","steps":["trace[1189739254] 'process raft request' (duration: 761.852591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:29.783311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.887633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:58:29.783379Z","caller":"traceutil/trace.go:171","msg":"trace[1274976973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"640.974485ms","start":"2026-07-21T20:58:29.142388Z","end":"2026-07-21T20:58:29.783362Z","steps":["trace[1274976973] 'agreement among raft nodes before linearized reading' (duration: 640.855572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:29.783423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:29.021097Z","time spent":"762.241611ms","remote":"127.0.0.1:37460","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-07-21T20:58:29.783434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:29.142331Z","time spent":"641.090978ms","remote":"127.0.0.1:37192","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-07-21T20:58:30.561275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.92439ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-21T20:58:30.561377Z","caller":"traceutil/trace.go:171","msg":"trace[1044974156] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:686; }","duration":"667.001921ms","start":"2026-07-21T20:58:29.894357Z","end":"2026-07-21T20:58:30.561359Z","steps":["trace[1044974156] 'read index received' (duration: 221.966677ms)","trace[1044974156] 'applied index is now lower than readState.Index' (duration: 445.033824ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:58:30.561657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.270247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T20:58:30.561815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.980428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-21T20:58:30.561815Z","caller":"traceutil/trace.go:171","msg":"trace[623477838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"667.467062ms","start":"2026-07-21T20:58:29.89432Z","end":"2026-07-21T20:58:30.561787Z","steps":["trace[623477838] 'agreement among raft nodes before linearized reading' (duration: 667.227056ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:58:30.561867Z","caller":"traceutil/trace.go:171","msg":"trace[2049781840] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:660; }","duration":"157.109121ms","start":"2026-07-21T20:58:30.404743Z","end":"2026-07-21T20:58:30.561853Z","steps":["trace[2049781840] 'agreement among raft nodes before linearized reading' (duration: 156.848504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:30.562006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:29.894304Z","time spent":"667.642466ms","remote":"127.0.0.1:37206","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-07-21T20:58:34.52135Z","caller":"traceutil/trace.go:171","msg":"trace[883131475] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"112.932034ms","start":"2026-07-21T20:58:34.408399Z","end":"2026-07-21T20:58:34.521331Z","steps":["trace[883131475] 'process raft request' (duration: 99.653154ms)","trace[883131475] 'compare' (duration: 13.108825ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:58:47.112205Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874242277032094,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T20:58:47.435481Z","caller":"traceutil/trace.go:171","msg":"trace[1698079714] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:806; }","duration":"824.166382ms","start":"2026-07-21T20:58:46.611289Z","end":"2026-07-21T20:58:47.435456Z","steps":["trace[1698079714] 'read index received' (duration: 824.039799ms)","trace[1698079714] 'applied index is now lower than readState.Index' (duration: 125.013µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:58:47.435601Z","caller":"traceutil/trace.go:171","msg":"trace[1740948661] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"929.06156ms","start":"2026-07-21T20:58:46.506518Z","end":"2026-07-21T20:58:47.435579Z","steps":["trace[1740948661] 'process raft request' (duration: 928.754692ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:47.435762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:46.506498Z","time spent":"929.158434ms","remote":"127.0.0.1:37390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:58:47.435792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.486191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:58:47.435887Z","caller":"traceutil/trace.go:171","msg":"trace[704193772] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:775; }","duration":"824.613524ms","start":"2026-07-21T20:58:46.611256Z","end":"2026-07-21T20:58:47.43587Z","steps":["trace[704193772] 'agreement among raft nodes before linearized reading' (duration: 824.322326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:47.436023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:46.611239Z","time spent":"824.731356ms","remote":"127.0.0.1:37482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-21T20:58:47.578174Z","caller":"traceutil/trace.go:171","msg":"trace[1979795630] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"300.786781ms","start":"2026-07-21T20:58:47.277363Z","end":"2026-07-21T20:58:47.57815Z","steps":["trace[1979795630] 'process raft request' (duration: 300.633677ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:58:47.57821Z","caller":"traceutil/trace.go:171","msg":"trace[1720659575] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:807; }","duration":"142.627902ms","start":"2026-07-21T20:58:47.435553Z","end":"2026-07-21T20:58:47.578181Z","steps":["trace[1720659575] 'read index received' (duration: 142.342296ms)","trace[1720659575] 'applied index is now lower than readState.Index' (duration: 284.026µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:58:47.578316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:47.277346Z","time spent":"300.901723ms","remote":"127.0.0.1:37378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5052,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:58:47.578486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.910254ms","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-07-21T20:58:47.578529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.682354ms","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-07-21T20:58:47.578619Z","caller":"traceutil/trace.go:171","msg":"trace[21010619] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:776; }","duration":"914.818178ms","start":"2026-07-21T20:58:46.663784Z","end":"2026-07-21T20:58:47.578602Z","steps":["trace[21010619] 'agreement among raft nodes before linearized reading' (duration: 914.612852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:47.578668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:46.663749Z","time spent":"914.90937ms","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-21T20:58:47.578538Z","caller":"traceutil/trace.go:171","msg":"trace[1146874217] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:776; }","duration":"923.007677ms","start":"2026-07-21T20:58:46.655519Z","end":"2026-07-21T20:58:47.578526Z","steps":["trace[1146874217] 'agreement among raft nodes before linearized reading' (duration: 922.800111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:47.578812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.642821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-21T20:58:47.578743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.616096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-21T20:58:47.578828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:46.655503Z","time spent":"923.308105ms","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T20:58:47.578845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.332306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:58:47.578897Z","caller":"traceutil/trace.go:171","msg":"trace[1028863800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:776; }","duration":"920.773679ms","start":"2026-07-21T20:58:46.658109Z","end":"2026-07-21T20:58:47.578883Z","steps":["trace[1028863800] 'agreement among raft nodes before linearized reading' (duration: 920.536984ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:58:47.578876Z","caller":"traceutil/trace.go:171","msg":"trace[98917627] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:776; }","duration":"158.771874ms","start":"2026-07-21T20:58:47.420091Z","end":"2026-07-21T20:58:47.578863Z","steps":["trace[98917627] 'agreement among raft nodes before linearized reading' (duration: 158.499337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:47.578956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:46.658104Z","time spent":"920.839071ms","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-21T20:58:47.578953Z","caller":"traceutil/trace.go:171","msg":"trace[1687250903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:776; }","duration":"683.457039ms","start":"2026-07-21T20:58:46.89547Z","end":"2026-07-21T20:58:47.578927Z","steps":["trace[1687250903] 'agreement among raft nodes before linearized reading' (duration: 683.234664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:47.579031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:46.895415Z","time spent":"683.601044ms","remote":"127.0.0.1:37192","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-07-21T20:58:47.579162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.134234ms","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-07-21T20:58:47.579234Z","caller":"traceutil/trace.go:171","msg":"trace[649117988] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:776; }","duration":"920.211325ms","start":"2026-07-21T20:58:46.659009Z","end":"2026-07-21T20:58:47.57922Z","steps":["trace[649117988] 'agreement among raft nodes before linearized reading' (duration: 919.59103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:58:47.579297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:58:46.659004Z","time spent":"920.282837ms","remote":"127.0.0.1:37460","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-07-21T20:58:47.688912Z","caller":"traceutil/trace.go:171","msg":"trace[775500892] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"104.300253ms","start":"2026-07-21T20:58:47.584588Z","end":"2026-07-21T20:58:47.688888Z","steps":["trace[775500892] 'process raft request' (duration: 97.353005ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:58:47.689024Z","caller":"traceutil/trace.go:171","msg":"trace[2054866167] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"104.352844ms","start":"2026-07-21T20:58:47.584648Z","end":"2026-07-21T20:58:47.689001Z","steps":["trace[2054866167] 'process raft request' (duration: 104.17821ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:58:47.689207Z","caller":"traceutil/trace.go:171","msg":"trace[301363619] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"104.357964ms","start":"2026-07-21T20:58:47.584603Z","end":"2026-07-21T20:58:47.688961Z","steps":["trace[301363619] 'process raft request' (duration: 104.1862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:02.243737Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874242277032347,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T20:59:02.744627Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874242277032347,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T20:59:03.245014Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874242277032347,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T20:59:03.746153Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874242277032347,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T20:59:03.78349Z","caller":"traceutil/trace.go:171","msg":"trace[962836525] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"2.130223483s","start":"2026-07-21T20:59:01.653247Z","end":"2026-07-21T20:59:03.783471Z","steps":["trace[962836525] 'process raft request' (duration: 2.130029088s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:03.783692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:01.653232Z","time spent":"2.130361377s","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:59:03.931203Z","caller":"traceutil/trace.go:171","msg":"trace[1277803253] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:941; }","duration":"2.187492805s","start":"2026-07-21T20:59:01.743689Z","end":"2026-07-21T20:59:03.931181Z","steps":["trace[1277803253] 'read index received' (duration: 2.039737612s)","trace[1277803253] 'applied index is now lower than readState.Index' (duration: 147.754323ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:59:03.931312Z","caller":"traceutil/trace.go:171","msg":"trace[1459012811] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"1.328805065s","start":"2026-07-21T20:59:02.602496Z","end":"2026-07-21T20:59:03.931301Z","steps":["trace[1459012811] 'process raft request' (duration: 1.328602519s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:03.931403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:02.602473Z","time spent":"1.328862017s","remote":"127.0.0.1:37460","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-07-21T20:59:03.931517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.481811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T20:59:03.931466Z","caller":"traceutil/trace.go:171","msg":"trace[93544713] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"2.049523686s","start":"2026-07-21T20:59:01.881894Z","end":"2026-07-21T20:59:03.931418Z","steps":["trace[93544713] 'process raft request' (duration: 2.049083764s)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:59:03.931546Z","caller":"traceutil/trace.go:171","msg":"trace[1696515658] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:909; }","duration":"694.511892ms","start":"2026-07-21T20:59:03.237027Z","end":"2026-07-21T20:59:03.931538Z","steps":["trace[1696515658] 'agreement among raft nodes before linearized reading' (duration: 694.465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:03.931582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:03.236931Z","time spent":"694.644885ms","remote":"127.0.0.1:37436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":1,"response size":30,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-07-21T20:59:03.93156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036538062s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:59:03.931641Z","caller":"traceutil/trace.go:171","msg":"trace[655824103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:909; }","duration":"1.036677146s","start":"2026-07-21T20:59:02.894944Z","end":"2026-07-21T20:59:03.931621Z","steps":["trace[655824103] 'agreement among raft nodes before linearized reading' (duration: 1.036531782s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:03.931631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.187926706s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-21T20:59:03.931647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:01.881843Z","time spent":"2.049733281s","remote":"127.0.0.1:37270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":811,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T20:59:03.93169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:02.89492Z","time spent":"1.036760028s","remote":"127.0.0.1:37192","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-07-21T20:59:03.931716Z","caller":"traceutil/trace.go:171","msg":"trace[689877993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:909; }","duration":"2.18804977s","start":"2026-07-21T20:59:01.74365Z","end":"2026-07-21T20:59:03.931699Z","steps":["trace[689877993] 'agreement among raft nodes before linearized reading' (duration: 2.187836584s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:03.931758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:01.743629Z","time spent":"2.188120861s","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T20:59:03.93178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.036812026s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-lqdc5\" ","response":"range_response_count:1 size:5674"} {"level":"info","ts":"2026-07-21T20:59:03.931819Z","caller":"traceutil/trace.go:171","msg":"trace[720620430] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-lqdc5; range_end:; response_count:1; response_revision:909; }","duration":"2.036850927s","start":"2026-07-21T20:59:01.894956Z","end":"2026-07-21T20:59:03.931807Z","steps":["trace[720620430] 'agreement among raft nodes before linearized reading' (duration: 2.036737294s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:03.931847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:01.894884Z","time spent":"2.03695784s","remote":"127.0.0.1:37390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":5697,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-lqdc5\" "} {"level":"warn","ts":"2026-07-21T20:59:03.931848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.976007482s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T20:59:03.931874Z","caller":"traceutil/trace.go:171","msg":"trace[843129771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:909; }","duration":"1.976054994s","start":"2026-07-21T20:59:01.955812Z","end":"2026-07-21T20:59:03.931867Z","steps":["trace[843129771] 'agreement among raft nodes before linearized reading' (duration: 1.975975092s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:03.931904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:01.9558Z","time spent":"1.976098434s","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T20:59:03.931925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.183167963s","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-07-21T20:59:03.931961Z","caller":"traceutil/trace.go:171","msg":"trace[1865673224] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:909; }","duration":"2.183224286s","start":"2026-07-21T20:59:01.748726Z","end":"2026-07-21T20:59:03.931951Z","steps":["trace[1865673224] 'agreement among raft nodes before linearized reading' (duration: 2.183124592s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:03.931993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:01.748716Z","time spent":"2.183270627s","remote":"127.0.0.1:37460","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-07-21T20:59:03.932427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.187915376s","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-07-21T20:59:03.932508Z","caller":"traceutil/trace.go:171","msg":"trace[1640940735] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:909; }","duration":"2.1880661s","start":"2026-07-21T20:59:01.744424Z","end":"2026-07-21T20:59:03.93249Z","steps":["trace[1640940735] 'agreement among raft nodes before linearized reading' (duration: 2.187019933s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:03.932551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:01.744402Z","time spent":"2.188137931s","remote":"127.0.0.1:37460","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-07-21T20:59:04.046005Z","caller":"traceutil/trace.go:171","msg":"trace[342348709] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"107.539913ms","start":"2026-07-21T20:59:03.938439Z","end":"2026-07-21T20:59:04.045979Z","steps":["trace[342348709] 'process raft request' (duration: 106.912746ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:59:04.046503Z","caller":"traceutil/trace.go:171","msg":"trace[665764115] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"107.992245ms","start":"2026-07-21T20:59:03.938478Z","end":"2026-07-21T20:59:04.046471Z","steps":["trace[665764115] 'process raft request' (duration: 107.78707ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:59:04.046504Z","caller":"traceutil/trace.go:171","msg":"trace[1156596513] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"108.013855ms","start":"2026-07-21T20:59:03.938455Z","end":"2026-07-21T20:59:04.046469Z","steps":["trace[1156596513] 'process raft request' (duration: 107.392268ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:59:04.047825Z","caller":"traceutil/trace.go:171","msg":"trace[1038498355] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:944; }","duration":"106.112736ms","start":"2026-07-21T20:59:03.941692Z","end":"2026-07-21T20:59:04.047804Z","steps":["trace[1038498355] 'read index received' (duration: 31.057764ms)","trace[1038498355] 'applied index is now lower than readState.Index' (duration: 75.053682ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:59:04.047928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.220759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:59:04.047956Z","caller":"traceutil/trace.go:171","msg":"trace[1936102214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:914; }","duration":"106.287691ms","start":"2026-07-21T20:59:03.941661Z","end":"2026-07-21T20:59:04.047949Z","steps":["trace[1936102214] 'agreement among raft nodes before linearized reading' (duration: 106.223869ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:59:04.048123Z","caller":"traceutil/trace.go:171","msg":"trace[1384988421] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"108.187239ms","start":"2026-07-21T20:59:03.939919Z","end":"2026-07-21T20:59:04.048106Z","steps":["trace[1384988421] 'process raft request' (duration: 106.476265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:06.478588Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874242277032390,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T20:59:06.642544Z","caller":"traceutil/trace.go:171","msg":"trace[1639063104] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"664.848613ms","start":"2026-07-21T20:59:05.977671Z","end":"2026-07-21T20:59:06.64252Z","steps":["trace[1639063104] 'read index received' (duration: 664.571726ms)","trace[1639063104] 'applied index is now lower than readState.Index' (duration: 275.767µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:59:06.642904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.208552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-21T20:59:06.642949Z","caller":"traceutil/trace.go:171","msg":"trace[259980018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:927; }","duration":"665.315746ms","start":"2026-07-21T20:59:05.977622Z","end":"2026-07-21T20:59:06.642937Z","steps":["trace[259980018] 'agreement among raft nodes before linearized reading' (duration: 665.119941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:06.643026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:05.97759Z","time spent":"665.424719ms","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T20:59:06.64313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.514235ms","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-07-21T20:59:06.64322Z","caller":"traceutil/trace.go:171","msg":"trace[1553151095] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:927; }","duration":"589.636797ms","start":"2026-07-21T20:59:06.053559Z","end":"2026-07-21T20:59:06.643196Z","steps":["trace[1553151095] 'agreement among raft nodes before linearized reading' (duration: 589.397882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:06.64328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:06.053549Z","time spent":"589.71012ms","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-21T20:59:06.643392Z","caller":"traceutil/trace.go:171","msg":"trace[1669852913] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"705.561177ms","start":"2026-07-21T20:59:05.937669Z","end":"2026-07-21T20:59:06.64323Z","steps":["trace[1669852913] 'process raft request' (duration: 704.670974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:06.643501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.473384ms","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-07-21T20:59:06.643579Z","caller":"traceutil/trace.go:171","msg":"trace[320984746] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:927; }","duration":"589.571556ms","start":"2026-07-21T20:59:06.053995Z","end":"2026-07-21T20:59:06.643567Z","steps":["trace[320984746] 'agreement among raft nodes before linearized reading' (duration: 589.418323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:06.643655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:06.053985Z","time spent":"589.655798ms","remote":"127.0.0.1:37460","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-07-21T20:59:06.643745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.562683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T20:59:06.643798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:05.93765Z","time spent":"705.871736ms","remote":"127.0.0.1:37460","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-07-21T20:59:06.643835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.150301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T20:59:06.644183Z","caller":"traceutil/trace.go:171","msg":"trace[2079153988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:927; }","duration":"590.532752ms","start":"2026-07-21T20:59:06.053615Z","end":"2026-07-21T20:59:06.644148Z","steps":["trace[2079153988] 'agreement among raft nodes before linearized reading' (duration: 589.996738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:06.644232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:06.053589Z","time spent":"590.630164ms","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-21T20:59:06.644409Z","caller":"traceutil/trace.go:171","msg":"trace[962224517] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:927; }","duration":"163.900911ms","start":"2026-07-21T20:59:06.480047Z","end":"2026-07-21T20:59:06.643947Z","steps":["trace[962224517] 'agreement among raft nodes before linearized reading' (duration: 163.558412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:07.272306Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874242277032393,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T20:59:07.678901Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.026246137s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T20:59:07.681937Z","caller":"traceutil/trace.go:171","msg":"trace[742018624] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"1.032867228s","start":"2026-07-21T20:59:06.649049Z","end":"2026-07-21T20:59:07.681916Z","steps":["trace[742018624] 'process raft request' (duration: 1.032736365s)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:59:07.681931Z","caller":"traceutil/trace.go:171","msg":"trace[1640860271] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"1.032872227s","start":"2026-07-21T20:59:06.649028Z","end":"2026-07-21T20:59:07.6819Z","steps":["trace[1640860271] 'process raft request' (duration: 1.030061165s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:07.682072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:06.649029Z","time spent":"1.03298024s","remote":"127.0.0.1:37460","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-07-21T20:59:07.682094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.611854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:59:07.68213Z","caller":"traceutil/trace.go:171","msg":"trace[548703448] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:931; }","duration":"910.693397ms","start":"2026-07-21T20:59:06.771425Z","end":"2026-07-21T20:59:07.682118Z","steps":["trace[548703448] 'agreement among raft nodes before linearized reading' (duration: 910.553693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:07.682123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:06.648998Z","time spent":"1.033042513s","remote":"127.0.0.1:37460","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-07-21T20:59:07.682154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:06.771407Z","time spent":"910.739667ms","remote":"127.0.0.1:37428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-07-21T20:59:07.681933Z","caller":"traceutil/trace.go:171","msg":"trace[308094626] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:965; }","duration":"910.448671ms","start":"2026-07-21T20:59:06.77146Z","end":"2026-07-21T20:59:07.681908Z","steps":["trace[308094626] 'read index received' (duration: 907.580787ms)","trace[308094626] 'applied index is now lower than readState.Index' (duration: 2.866984ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:59:07.682297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.325073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:59:07.682313Z","caller":"traceutil/trace.go:171","msg":"trace[1758113129] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"1.03298591s","start":"2026-07-21T20:59:06.649318Z","end":"2026-07-21T20:59:07.682304Z","steps":["trace[1758113129] 'process raft request' (duration: 1.03255735s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:07.682384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:06.64931Z","time spent":"1.033026391s","remote":"127.0.0.1:37460","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-07-21T20:59:07.68232Z","caller":"traceutil/trace.go:171","msg":"trace[1181951298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:931; }","duration":"787.374624ms","start":"2026-07-21T20:59:06.894938Z","end":"2026-07-21T20:59:07.682312Z","steps":["trace[1181951298] 'agreement among raft nodes before linearized reading' (duration: 787.335074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:07.68245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:06.894922Z","time spent":"787.522969ms","remote":"127.0.0.1:37192","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-07-21T20:59:19.845956Z","caller":"traceutil/trace.go:171","msg":"trace[2135731535] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"103.987821ms","start":"2026-07-21T20:59:19.741947Z","end":"2026-07-21T20:59:19.845935Z","steps":["trace[2135731535] 'process raft request' (duration: 103.831406ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:59:19.873373Z","caller":"traceutil/trace.go:171","msg":"trace[1960189854] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"130.806765ms","start":"2026-07-21T20:59:19.742544Z","end":"2026-07-21T20:59:19.873351Z","steps":["trace[1960189854] 'process raft request' (duration: 124.885131ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:59:19.873669Z","caller":"traceutil/trace.go:171","msg":"trace[1497313305] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"130.678981ms","start":"2026-07-21T20:59:19.742967Z","end":"2026-07-21T20:59:19.873646Z","steps":["trace[1497313305] 'process raft request' (duration: 130.347213ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:20.114058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.286435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T20:59:20.114178Z","caller":"traceutil/trace.go:171","msg":"trace[1957964393] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:972; }","duration":"117.445989ms","start":"2026-07-21T20:59:19.996691Z","end":"2026-07-21T20:59:20.114137Z","steps":["trace[1957964393] 'count revisions from in-memory index tree' (duration: 117.194864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:24.748869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.166128ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:59:24.749019Z","caller":"traceutil/trace.go:171","msg":"trace[2036128125] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1025; }","duration":"830.793152ms","start":"2026-07-21T20:59:23.918211Z","end":"2026-07-21T20:59:24.749004Z","steps":["trace[2036128125] 'read index received' (duration: 321.320786ms)","trace[2036128125] 'applied index is now lower than readState.Index' (duration: 509.471596ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:59:24.749097Z","caller":"traceutil/trace.go:171","msg":"trace[385041200] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"847.012861ms","start":"2026-07-21T20:59:23.902075Z","end":"2026-07-21T20:59:24.749088Z","steps":["trace[385041200] 'process raft request' (duration: 846.877198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:24.749152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:23.902068Z","time spent":"847.054742ms","remote":"127.0.0.1:37460","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-07-21T20:59:24.749242Z","caller":"traceutil/trace.go:171","msg":"trace[1944746188] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"847.828732ms","start":"2026-07-21T20:59:23.901373Z","end":"2026-07-21T20:59:24.749202Z","steps":["trace[1944746188] 'process raft request' (duration: 338.078199ms)","trace[1944746188] 'compare' (duration: 509.020504ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:59:24.749346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:23.901359Z","time spent":"847.931525ms","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:59:24.749382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.174712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-21T20:59:24.749409Z","caller":"traceutil/trace.go:171","msg":"trace[1530335182] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:987; }","duration":"831.226333ms","start":"2026-07-21T20:59:23.918174Z","end":"2026-07-21T20:59:24.7494Z","steps":["trace[1530335182] 'agreement among raft nodes before linearized reading' (duration: 831.12345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:24.749433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:23.91816Z","time spent":"831.267314ms","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T20:59:24.749596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.6827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-21T20:59:24.749664Z","caller":"traceutil/trace.go:171","msg":"trace[1458378710] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:987; }","duration":"291.781932ms","start":"2026-07-21T20:59:24.457865Z","end":"2026-07-21T20:59:24.749647Z","steps":["trace[1458378710] 'agreement among raft nodes before linearized reading' (duration: 291.576677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:25.394167Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874242277032554,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T20:59:26.178386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.230522672s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:59:26.178521Z","caller":"traceutil/trace.go:171","msg":"trace[1245561923] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:989; }","duration":"1.230654556s","start":"2026-07-21T20:59:24.947834Z","end":"2026-07-21T20:59:26.178489Z","steps":["trace[1245561923] 'range keys from in-memory index tree' (duration: 1.230508222s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:26.179298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.030987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T20:59:26.179525Z","caller":"traceutil/trace.go:171","msg":"trace[341756070] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1030; }","duration":"1.285565267s","start":"2026-07-21T20:59:24.893941Z","end":"2026-07-21T20:59:26.179507Z","steps":["trace[341756070] 'read index received' (duration: 668.166431ms)","trace[341756070] 'applied index is now lower than readState.Index' (duration: 617.397866ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:59:26.179598Z","caller":"traceutil/trace.go:171","msg":"trace[752936002] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"1.337979624s","start":"2026-07-21T20:59:24.841608Z","end":"2026-07-21T20:59:26.179587Z","steps":["trace[752936002] 'process raft request' (duration: 720.520355ms)","trace[752936002] 'compare' (duration: 616.422721ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:59:26.179677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:24.841578Z","time spent":"1.338045636s","remote":"127.0.0.1:37222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T20:59:26.179695Z","caller":"traceutil/trace.go:171","msg":"trace[491006212] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"1.33318424s","start":"2026-07-21T20:59:24.84649Z","end":"2026-07-21T20:59:26.179674Z","steps":["trace[491006212] 'process raft request' (duration: 1.332926992s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:26.179819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:24.846465Z","time spent":"1.333276522s","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:59:26.179848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.285891225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:59:26.179916Z","caller":"traceutil/trace.go:171","msg":"trace[1876139855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"1.286007188s","start":"2026-07-21T20:59:24.893891Z","end":"2026-07-21T20:59:26.179898Z","steps":["trace[1876139855] 'agreement among raft nodes before linearized reading' (duration: 1.285792103s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:26.179975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:24.893867Z","time spent":"1.286096571s","remote":"127.0.0.1:37192","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-07-21T20:59:26.186623Z","caller":"traceutil/trace.go:171","msg":"trace[1560174996] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"549.028819ms","start":"2026-07-21T20:59:25.637584Z","end":"2026-07-21T20:59:26.186613Z","steps":["trace[1560174996] 'process raft request' (duration: 548.874014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:26.186694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:25.63755Z","time spent":"549.105511ms","remote":"127.0.0.1:37460","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-07-21T20:59:26.187017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.622182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T20:59:26.187044Z","caller":"traceutil/trace.go:171","msg":"trace[1025088275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:992; }","duration":"285.676392ms","start":"2026-07-21T20:59:25.901361Z","end":"2026-07-21T20:59:26.187037Z","steps":["trace[1025088275] 'agreement among raft nodes before linearized reading' (duration: 285.60279ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:59:54.732833Z","caller":"traceutil/trace.go:171","msg":"trace[2107294724] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1128; }","duration":"324.335122ms","start":"2026-07-21T20:59:54.408474Z","end":"2026-07-21T20:59:54.732809Z","steps":["trace[2107294724] 'read index received' (duration: 324.171748ms)","trace[2107294724] 'applied index is now lower than readState.Index' (duration: 162.514µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:59:54.732986Z","caller":"traceutil/trace.go:171","msg":"trace[75858268] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"404.348984ms","start":"2026-07-21T20:59:54.328607Z","end":"2026-07-21T20:59:54.732956Z","steps":["trace[75858268] 'process raft request' (duration: 404.061406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:54.733095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.668101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T20:59:54.733135Z","caller":"traceutil/trace.go:171","msg":"trace[30002481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1083; }","duration":"324.715792ms","start":"2026-07-21T20:59:54.408411Z","end":"2026-07-21T20:59:54.733127Z","steps":["trace[30002481] 'agreement among raft nodes before linearized reading' (duration: 324.549068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:54.733135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:54.328588Z","time spent":"404.450936ms","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:59:54.733158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:54.408355Z","time spent":"324.798865ms","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T20:59:54.733202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.936088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-21T20:59:54.733353Z","caller":"traceutil/trace.go:171","msg":"trace[1001544453] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:1083; }","duration":"274.051191ms","start":"2026-07-21T20:59:54.4592Z","end":"2026-07-21T20:59:54.733252Z","steps":["trace[1001544453] 'agreement among raft nodes before linearized reading' (duration: 273.773184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:55.069582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.743045ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:59:55.069706Z","caller":"traceutil/trace.go:171","msg":"trace[1790318200] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1083; }","duration":"122.8795ms","start":"2026-07-21T20:59:54.946809Z","end":"2026-07-21T20:59:55.069689Z","steps":["trace[1790318200] 'range keys from in-memory index tree' (duration: 122.727615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:55.069825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.269806ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-07-21T20:59:55.069907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:54.73443Z","time spent":"335.471271ms","remote":"127.0.0.1:37222","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-21T20:59:55.11141Z","caller":"traceutil/trace.go:171","msg":"trace[1985498736] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"372.724164ms","start":"2026-07-21T20:59:54.738655Z","end":"2026-07-21T20:59:55.11138Z","steps":["trace[1985498736] 'process raft request' (duration: 372.374995ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:59:55.111505Z","caller":"traceutil/trace.go:171","msg":"trace[1331271109] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1129; }","duration":"328.230193ms","start":"2026-07-21T20:59:54.783257Z","end":"2026-07-21T20:59:55.111487Z","steps":["trace[1331271109] 'read index received' (duration: 172.288419ms)","trace[1331271109] 'applied index is now lower than readState.Index' (duration: 155.940624ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T20:59:55.111532Z","caller":"traceutil/trace.go:171","msg":"trace[611575373] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"180.064129ms","start":"2026-07-21T20:59:54.931444Z","end":"2026-07-21T20:59:55.111508Z","steps":["trace[611575373] 'process raft request' (duration: 179.886905ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:55.111581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:54.738631Z","time spent":"372.860088ms","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T20:59:55.111633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.321465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:59:55.111761Z","caller":"traceutil/trace.go:171","msg":"trace[1991315722] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1085; }","duration":"328.544622ms","start":"2026-07-21T20:59:54.783193Z","end":"2026-07-21T20:59:55.111737Z","steps":["trace[1991315722] 'agreement among raft nodes before linearized reading' (duration: 328.339786ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:55.11183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:54.783167Z","time spent":"328.645144ms","remote":"127.0.0.1:45190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-07-21T20:59:55.12265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.256247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T20:59:55.122726Z","caller":"traceutil/trace.go:171","msg":"trace[1706063874] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1086; }","duration":"228.37884ms","start":"2026-07-21T20:59:54.894329Z","end":"2026-07-21T20:59:55.122708Z","steps":["trace[1706063874] 'agreement among raft nodes before linearized reading' (duration: 228.215746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:55.122955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.873804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-21T20:59:55.123006Z","caller":"traceutil/trace.go:171","msg":"trace[1391530709] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1086; }","duration":"172.968196ms","start":"2026-07-21T20:59:54.950026Z","end":"2026-07-21T20:59:55.122994Z","steps":["trace[1391530709] 'agreement among raft nodes before linearized reading' (duration: 172.792131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:55.123189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.763341ms","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-07-21T20:59:55.123244Z","caller":"traceutil/trace.go:171","msg":"trace[829238551] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1086; }","duration":"206.858182ms","start":"2026-07-21T20:59:54.916372Z","end":"2026-07-21T20:59:55.12323Z","steps":["trace[829238551] 'agreement among raft nodes before linearized reading' (duration: 206.505693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:55.123535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.847878ms","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-07-21T20:59:55.123726Z","caller":"traceutil/trace.go:171","msg":"trace[1080868755] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1086; }","duration":"212.914011ms","start":"2026-07-21T20:59:54.910669Z","end":"2026-07-21T20:59:55.123583Z","steps":["trace[1080868755] 'agreement among raft nodes before linearized reading' (duration: 212.564541ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T20:59:57.615983Z","caller":"traceutil/trace.go:171","msg":"trace[1527051743] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"465.774367ms","start":"2026-07-21T20:59:57.150179Z","end":"2026-07-21T20:59:57.615954Z","steps":["trace[1527051743] 'process raft request' (duration: 421.980058ms)","trace[1527051743] 'compare' (duration: 43.688745ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T20:59:57.61613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:57.150158Z","time spent":"465.896689ms","remote":"127.0.0.1:37460","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-07-21T20:59:57.656073Z","caller":"traceutil/trace.go:171","msg":"trace[773373088] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"505.031726ms","start":"2026-07-21T20:59:57.151015Z","end":"2026-07-21T20:59:57.656047Z","steps":["trace[773373088] 'process raft request' (duration: 504.871272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T20:59:57.656346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T20:59:57.150999Z","time spent":"505.217361ms","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:00:06.908794Z","caller":"traceutil/trace.go:171","msg":"trace[2065828318] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"107.925935ms","start":"2026-07-21T21:00:06.80082Z","end":"2026-07-21T21:00:06.908746Z","steps":["trace[2065828318] 'process raft request' (duration: 107.632198ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:11.487045Z","caller":"traceutil/trace.go:171","msg":"trace[1725022286] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"124.427334ms","start":"2026-07-21T21:00:11.362581Z","end":"2026-07-21T21:00:11.487008Z","steps":["trace[1725022286] 'process raft request' (duration: 79.751783ms)","trace[1725022286] 'compare' (duration: 44.336852ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:00:11.487345Z","caller":"traceutil/trace.go:171","msg":"trace[1020326802] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"124.320021ms","start":"2026-07-21T21:00:11.363008Z","end":"2026-07-21T21:00:11.487328Z","steps":["trace[1020326802] 'process raft request' (duration: 123.913351ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:11.487688Z","caller":"traceutil/trace.go:171","msg":"trace[852817995] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"120.899363ms","start":"2026-07-21T21:00:11.366755Z","end":"2026-07-21T21:00:11.487654Z","steps":["trace[852817995] 'process raft request' (duration: 120.234286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:13.510691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.455591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-21T21:00:13.510704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.385553ms","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-07-21T21:00:13.510779Z","caller":"traceutil/trace.go:171","msg":"trace[434023339] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1195; }","duration":"277.583744ms","start":"2026-07-21T21:00:13.233175Z","end":"2026-07-21T21:00:13.510759Z","steps":["trace[434023339] 'range keys from in-memory index tree' (duration: 277.267367ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:13.510786Z","caller":"traceutil/trace.go:171","msg":"trace[1476043351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1195; }","duration":"276.499366ms","start":"2026-07-21T21:00:13.234274Z","end":"2026-07-21T21:00:13.510774Z","steps":["trace[1476043351] 'range keys from in-memory index tree' (duration: 276.27309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:13.510765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.672497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:00:13.510839Z","caller":"traceutil/trace.go:171","msg":"trace[991291347] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1195; }","duration":"247.76201ms","start":"2026-07-21T21:00:13.26306Z","end":"2026-07-21T21:00:13.510822Z","steps":["trace[991291347] 'count revisions from in-memory index tree' (duration: 247.624185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:16.021252Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874242277033078,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T21:00:16.111574Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.109791075s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T21:00:16.111983Z","caller":"traceutil/trace.go:171","msg":"trace[486919029] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"1.110279317s","start":"2026-07-21T21:00:15.001679Z","end":"2026-07-21T21:00:16.111959Z","steps":["trace[486919029] 'process raft request' (duration: 1.110108753s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:16.112116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:15.001657Z","time spent":"1.11039213s","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:00:17.008162Z","caller":"traceutil/trace.go:171","msg":"trace[858192152] linearizableReadLoop","detail":"{readStateIndex:1255; appliedIndex:1253; }","duration":"1.487889612s","start":"2026-07-21T21:00:15.520228Z","end":"2026-07-21T21:00:17.008117Z","steps":["trace[858192152] 'read index received' (duration: 591.561746ms)","trace[858192152] 'applied index is now lower than readState.Index' (duration: 896.326726ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:00:17.008271Z","caller":"traceutil/trace.go:171","msg":"trace[421429772] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"1.795116957s","start":"2026-07-21T21:00:15.213121Z","end":"2026-07-21T21:00:17.008238Z","steps":["trace[421429772] 'process raft request' (duration: 1.793394493s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:17.008373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.488126658s","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-07-21T21:00:17.008409Z","caller":"traceutil/trace.go:171","msg":"trace[1629996915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1202; }","duration":"1.488198709s","start":"2026-07-21T21:00:15.5202Z","end":"2026-07-21T21:00:17.008398Z","steps":["trace[1629996915] 'agreement among raft nodes before linearized reading' (duration: 1.488053006s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:17.008464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:15.520185Z","time spent":"1.488269471s","remote":"127.0.0.1:37460","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-07-21T21:00:17.008477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:15.213102Z","time spent":"1.79524456s","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:00:17.00864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.488316783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-21T21:00:17.008702Z","caller":"traceutil/trace.go:171","msg":"trace[1117993560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1202; }","duration":"1.488446166s","start":"2026-07-21T21:00:15.520241Z","end":"2026-07-21T21:00:17.008688Z","steps":["trace[1117993560] 'agreement among raft nodes before linearized reading' (duration: 1.488258432s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:17.008727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:15.520219Z","time spent":"1.488502658s","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T21:00:17.008815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.114327963s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:00:17.008849Z","caller":"traceutil/trace.go:171","msg":"trace[144597201] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1202; }","duration":"1.114386244s","start":"2026-07-21T21:00:15.894454Z","end":"2026-07-21T21:00:17.00884Z","steps":["trace[144597201] 'agreement among raft nodes before linearized reading' (duration: 1.114330343s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:17.008867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.290352458s","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-07-21T21:00:17.008886Z","caller":"traceutil/trace.go:171","msg":"trace[1210236726] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1202; }","duration":"1.290394829s","start":"2026-07-21T21:00:15.718485Z","end":"2026-07-21T21:00:17.00888Z","steps":["trace[1210236726] 'agreement among raft nodes before linearized reading' (duration: 1.290344898s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:17.008903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:15.718469Z","time spent":"1.290430519s","remote":"127.0.0.1:37460","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-07-21T21:00:17.008882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.286919099s","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-07-21T21:00:17.008944Z","caller":"traceutil/trace.go:171","msg":"trace[1427078919] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1202; }","duration":"1.287019271s","start":"2026-07-21T21:00:15.721909Z","end":"2026-07-21T21:00:17.008928Z","steps":["trace[1427078919] 'agreement among raft nodes before linearized reading' (duration: 1.286827936s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:17.008877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:15.894438Z","time spent":"1.114432346s","remote":"127.0.0.1:37192","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-07-21T21:00:17.008986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:15.721885Z","time spent":"1.287090122s","remote":"127.0.0.1:37460","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-07-21T21:00:23.233269Z","caller":"traceutil/trace.go:171","msg":"trace[291685170] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"141.807856ms","start":"2026-07-21T21:00:23.091444Z","end":"2026-07-21T21:00:23.233252Z","steps":["trace[291685170] 'process raft request' (duration: 141.685883ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:23.387689Z","caller":"traceutil/trace.go:171","msg":"trace[901176778] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"294.607207ms","start":"2026-07-21T21:00:23.093055Z","end":"2026-07-21T21:00:23.387662Z","steps":["trace[901176778] 'process raft request' (duration: 294.504694ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:23.387702Z","caller":"traceutil/trace.go:171","msg":"trace[681211865] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"295.303415ms","start":"2026-07-21T21:00:23.092365Z","end":"2026-07-21T21:00:23.387668Z","steps":["trace[681211865] 'process raft request' (duration: 294.198197ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:23.387865Z","caller":"traceutil/trace.go:171","msg":"trace[1401252597] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"294.348831ms","start":"2026-07-21T21:00:23.093491Z","end":"2026-07-21T21:00:23.38784Z","steps":["trace[1401252597] 'process raft request' (duration: 294.141575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:30.396989Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874242277033193,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T21:00:30.897981Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874242277033193,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T21:00:31.398455Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874242277033193,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T21:00:31.896217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.0003034s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-21T21:00:31.896332Z","caller":"traceutil/trace.go:171","msg":"trace[62266477] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000463914s","start":"2026-07-21T21:00:29.89585Z","end":"2026-07-21T21:00:31.896314Z","steps":["trace[62266477] 'agreement among raft nodes before linearized reading' (duration: 2.00033116s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:31.896402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:29.895831Z","time spent":"2.000554256s","remote":"127.0.0.1:37192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/21 21:00:31 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-21T21:00:31.899508Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874242277033193,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T21:00:32.197333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.378304694s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T21:00:32.197414Z","caller":"traceutil/trace.go:171","msg":"trace[1706100460] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"2.641919986s","start":"2026-07-21T21:00:29.555479Z","end":"2026-07-21T21:00:32.197399Z","steps":["trace[1706100460] 'process raft request' (duration: 263.489639ms)","trace[1706100460] 'compare' (duration: 2.377669947s)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:00:32.197456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:29.555463Z","time spent":"2.641973777s","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:00:32.200063Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.381043035s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T21:00:32.20048Z","caller":"traceutil/trace.go:171","msg":"trace[2109732846] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"2.626771572s","start":"2026-07-21T21:00:29.573686Z","end":"2026-07-21T21:00:32.200458Z","steps":["trace[2109732846] 'process raft request' (duration: 2.626636599s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:32.200621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:29.573672Z","time spent":"2.626864875s","remote":"127.0.0.1:37460","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-07-21T21:00:32.440495Z","caller":"traceutil/trace.go:171","msg":"trace[1632096824] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"1.363999971s","start":"2026-07-21T21:00:31.076476Z","end":"2026-07-21T21:00:32.440476Z","steps":["trace[1632096824] 'process raft request' (duration: 1.363687304s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:32.440602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:31.076453Z","time spent":"1.364091713s","remote":"127.0.0.1:37460","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-07-21T21:00:32.440527Z","caller":"traceutil/trace.go:171","msg":"trace[1135427492] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1302; }","duration":"2.544593165s","start":"2026-07-21T21:00:29.895887Z","end":"2026-07-21T21:00:32.44048Z","steps":["trace[1135427492] 'read index received' (duration: 2.306268692s)","trace[1135427492] 'applied index is now lower than readState.Index' (duration: 238.322413ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:00:32.441476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165237816s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-21T21:00:32.441494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.492892672s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:00:32.441526Z","caller":"traceutil/trace.go:171","msg":"trace[1004184686] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1248; }","duration":"1.492966314s","start":"2026-07-21T21:00:30.948551Z","end":"2026-07-21T21:00:32.441517Z","steps":["trace[1004184686] 'agreement among raft nodes before linearized reading' (duration: 1.492913273s)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:32.441528Z","caller":"traceutil/trace.go:171","msg":"trace[49784309] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1248; }","duration":"1.165324108s","start":"2026-07-21T21:00:31.276191Z","end":"2026-07-21T21:00:32.441515Z","steps":["trace[49784309] 'agreement among raft nodes before linearized reading' (duration: 1.165161513s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:32.441551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:30.948524Z","time spent":"1.493020585s","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:00:32.441611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070261317s","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":"warn","ts":"2026-07-21T21:00:32.441638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.731578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:00:32.441658Z","caller":"traceutil/trace.go:171","msg":"trace[1118979107] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1248; }","duration":"1.070342109s","start":"2026-07-21T21:00:31.371306Z","end":"2026-07-21T21:00:32.441648Z","steps":["trace[1118979107] 'agreement among raft nodes before linearized reading' (duration: 1.070277188s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:32.441694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:31.37128Z","time spent":"1.070405451s","remote":"127.0.0.1:45158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:00:32.441562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:31.276171Z","time spent":"1.16538336s","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-21T21:00:32.441752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.192056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:00:32.441788Z","caller":"traceutil/trace.go:171","msg":"trace[1344311765] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1248; }","duration":"598.266278ms","start":"2026-07-21T21:00:31.843511Z","end":"2026-07-21T21:00:32.441777Z","steps":["trace[1344311765] 'agreement among raft nodes before linearized reading' (duration: 598.215777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:32.441526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.683129ms","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-07-21T21:00:32.44183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:31.843488Z","time spent":"598.33291ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-07-21T21:00:32.441862Z","caller":"traceutil/trace.go:171","msg":"trace[504701795] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1248; }","duration":"886.086609ms","start":"2026-07-21T21:00:31.555766Z","end":"2026-07-21T21:00:32.441853Z","steps":["trace[504701795] 'agreement among raft nodes before linearized reading' (duration: 885.569746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:32.441913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:31.555742Z","time spent":"886.161611ms","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-21T21:00:32.4417Z","caller":"traceutil/trace.go:171","msg":"trace[1058009395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1248; }","duration":"544.83974ms","start":"2026-07-21T21:00:31.896843Z","end":"2026-07-21T21:00:32.441683Z","steps":["trace[1058009395] 'agreement among raft nodes before linearized reading' (duration: 544.766788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:32.442081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:31.896818Z","time spent":"545.204399ms","remote":"127.0.0.1:37206","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-07-21T21:00:32.443088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.205699308s","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-07-21T21:00:32.443146Z","caller":"traceutil/trace.go:171","msg":"trace[394783006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1248; }","duration":"2.205786651s","start":"2026-07-21T21:00:30.237345Z","end":"2026-07-21T21:00:32.443132Z","steps":["trace[394783006] 'agreement among raft nodes before linearized reading' (duration: 2.2038473s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:32.443186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:30.237329Z","time spent":"2.205843592s","remote":"127.0.0.1:37460","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-07-21T21:00:34.1207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.504435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-07-21T21:00:34.120771Z","caller":"traceutil/trace.go:171","msg":"trace[998694327] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1254; }","duration":"229.608808ms","start":"2026-07-21T21:00:33.891145Z","end":"2026-07-21T21:00:34.120754Z","steps":["trace[998694327] 'range keys from in-memory index tree' (duration: 229.370172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:34.121134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.756046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:00:34.121224Z","caller":"traceutil/trace.go:171","msg":"trace[530813282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1254; }","duration":"228.878609ms","start":"2026-07-21T21:00:33.892326Z","end":"2026-07-21T21:00:34.121204Z","steps":["trace[530813282] 'range keys from in-memory index tree' (duration: 228.699084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:34.960569Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874242277033212,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T21:00:35.026596Z","caller":"traceutil/trace.go:171","msg":"trace[486734778] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"820.68389ms","start":"2026-07-21T21:00:34.205892Z","end":"2026-07-21T21:00:35.026576Z","steps":["trace[486734778] 'process raft request' (duration: 820.561187ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:35.026727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:34.205875Z","time spent":"820.783882ms","remote":"127.0.0.1:37460","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-07-21T21:00:35.049597Z","caller":"traceutil/trace.go:171","msg":"trace[1406844431] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"842.24682ms","start":"2026-07-21T21:00:34.207331Z","end":"2026-07-21T21:00:35.049578Z","steps":["trace[1406844431] 'process raft request' (duration: 841.958843ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:35.049612Z","caller":"traceutil/trace.go:171","msg":"trace[1665075209] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1310; }","duration":"592.884139ms","start":"2026-07-21T21:00:34.456704Z","end":"2026-07-21T21:00:35.049588Z","steps":["trace[1665075209] 'read index received' (duration: 569.81545ms)","trace[1665075209] 'applied index is now lower than readState.Index' (duration: 23.067619ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:00:35.049694Z","caller":"traceutil/trace.go:171","msg":"trace[1737570547] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"804.203292ms","start":"2026-07-21T21:00:34.245475Z","end":"2026-07-21T21:00:35.049678Z","steps":["trace[1737570547] 'process raft request' (duration: 803.988366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:35.049731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:34.207321Z","time spent":"842.331413ms","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:00:35.049752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:34.245452Z","time spent":"804.278644ms","remote":"127.0.0.1:37270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T21:00:35.049721Z","caller":"traceutil/trace.go:171","msg":"trace[1658078434] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"602.801977ms","start":"2026-07-21T21:00:34.446878Z","end":"2026-07-21T21:00:35.04968Z","steps":["trace[1658078434] 'process raft request' (duration: 602.661353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:35.049811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.088504ms","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-07-21T21:00:35.049846Z","caller":"traceutil/trace.go:171","msg":"trace[126890620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1258; }","duration":"593.141446ms","start":"2026-07-21T21:00:34.456697Z","end":"2026-07-21T21:00:35.049839Z","steps":["trace[126890620] 'agreement among raft nodes before linearized reading' (duration: 593.008532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:35.049872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:34.456642Z","time spent":"593.224448ms","remote":"127.0.0.1:37460","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-07-21T21:00:35.049893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:34.446851Z","time spent":"602.956041ms","remote":"127.0.0.1:37460","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-07-21T21:00:35.049938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.645279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T21:00:35.049906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.130826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-21T21:00:35.049975Z","caller":"traceutil/trace.go:171","msg":"trace[1685365701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1258; }","duration":"154.70456ms","start":"2026-07-21T21:00:34.895254Z","end":"2026-07-21T21:00:35.049959Z","steps":["trace[1685365701] 'agreement among raft nodes before linearized reading' (duration: 154.66865ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:35.049993Z","caller":"traceutil/trace.go:171","msg":"trace[629635174] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1258; }","duration":"593.239989ms","start":"2026-07-21T21:00:34.456747Z","end":"2026-07-21T21:00:35.049987Z","steps":["trace[629635174] 'agreement among raft nodes before linearized reading' (duration: 593.084854ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:35.050002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.344222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-21T21:00:35.050017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:34.456739Z","time spent":"593.27112ms","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-21T21:00:35.050022Z","caller":"traceutil/trace.go:171","msg":"trace[676956052] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:1258; }","duration":"588.402464ms","start":"2026-07-21T21:00:34.461614Z","end":"2026-07-21T21:00:35.050017Z","steps":["trace[676956052] 'agreement among raft nodes before linearized reading' (duration: 588.341562ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:35.050046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:34.461592Z","time spent":"588.448334ms","remote":"127.0.0.1:37222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"warn","ts":"2026-07-21T21:00:35.05014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.236109ms","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-07-21T21:00:35.050185Z","caller":"traceutil/trace.go:171","msg":"trace[1705742442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1258; }","duration":"593.369513ms","start":"2026-07-21T21:00:34.456804Z","end":"2026-07-21T21:00:35.050174Z","steps":["trace[1705742442] 'agreement among raft nodes before linearized reading' (duration: 593.233659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:35.050222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:34.456738Z","time spent":"593.477015ms","remote":"127.0.0.1:37460","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-07-21T21:00:35.240382Z","caller":"traceutil/trace.go:171","msg":"trace[350848690] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"178.798917ms","start":"2026-07-21T21:00:35.061556Z","end":"2026-07-21T21:00:35.240355Z","steps":["trace[350848690] 'process raft request' (duration: 83.549401ms)","trace[350848690] 'compare' (duration: 95.130182ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:00:37.274846Z","caller":"traceutil/trace.go:171","msg":"trace[438556644] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"129.388484ms","start":"2026-07-21T21:00:37.145432Z","end":"2026-07-21T21:00:37.27482Z","steps":["trace[438556644] 'process raft request' (duration: 39.722412ms)","trace[438556644] 'compare' (duration: 89.510247ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:00:37.775909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.124946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T21:00:37.776286Z","caller":"traceutil/trace.go:171","msg":"trace[1624695523] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"629.993284ms","start":"2026-07-21T21:00:37.146281Z","end":"2026-07-21T21:00:37.776274Z","steps":["trace[1624695523] 'process raft request' (duration: 629.723657ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:37.776107Z","caller":"traceutil/trace.go:171","msg":"trace[1326519517] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"629.960824ms","start":"2026-07-21T21:00:37.146127Z","end":"2026-07-21T21:00:37.776088Z","steps":["trace[1326519517] 'process raft request' (duration: 510.5676ms)","trace[1326519517] 'compare' (duration: 118.837109ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:00:37.776359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:37.146275Z","time spent":"630.050906ms","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:00:37.776413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:37.146116Z","time spent":"630.2403ms","remote":"127.0.0.1:37460","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-07-21T21:00:37.776509Z","caller":"traceutil/trace.go:171","msg":"trace[1932884483] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"630.156389ms","start":"2026-07-21T21:00:37.146324Z","end":"2026-07-21T21:00:37.776481Z","steps":["trace[1932884483] 'process raft request' (duration: 629.903152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:37.776644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:37.146319Z","time spent":"630.251421ms","remote":"127.0.0.1:37460","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-07-21T21:00:38.171487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.815569ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T21:00:38.171563Z","caller":"traceutil/trace.go:171","msg":"trace[1316905851] linearizableReadLoop","detail":"{readStateIndex:1328; appliedIndex:1327; }","duration":"276.870075ms","start":"2026-07-21T21:00:37.894681Z","end":"2026-07-21T21:00:38.171552Z","steps":["trace[1316905851] 'read index received' (duration: 157.871082ms)","trace[1316905851] 'applied index is now lower than readState.Index' (duration: 118.997963ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:00:38.171624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.941378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:00:38.171643Z","caller":"traceutil/trace.go:171","msg":"trace[1779699576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1271; }","duration":"276.99215ms","start":"2026-07-21T21:00:37.894644Z","end":"2026-07-21T21:00:38.171636Z","steps":["trace[1779699576] 'agreement among raft nodes before linearized reading' (duration: 276.938597ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:38.171763Z","caller":"traceutil/trace.go:171","msg":"trace[2123824795] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"611.785471ms","start":"2026-07-21T21:00:37.559947Z","end":"2026-07-21T21:00:38.171733Z","steps":["trace[2123824795] 'process raft request' (duration: 492.659254ms)","trace[2123824795] 'compare' (duration: 118.687185ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:00:38.171911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:37.559918Z","time spent":"611.906184ms","remote":"127.0.0.1:37460","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-07-21T21:00:39.16585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.874868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T21:00:39.165959Z","caller":"traceutil/trace.go:171","msg":"trace[1559263695] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1271; }","duration":"733.004031ms","start":"2026-07-21T21:00:38.432934Z","end":"2026-07-21T21:00:39.165938Z","steps":["trace[1559263695] 'count revisions from in-memory index tree' (duration: 732.762865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:39.166009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:38.432868Z","time spent":"733.126434ms","remote":"127.0.0.1:37748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":107,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:00:39.165907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.991853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:00:39.166336Z","caller":"traceutil/trace.go:171","msg":"trace[2134617194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1271; }","duration":"271.447834ms","start":"2026-07-21T21:00:38.894876Z","end":"2026-07-21T21:00:39.166324Z","steps":["trace[2134617194] 'range keys from in-memory index tree' (duration: 270.892341ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:39.52201Z","caller":"traceutil/trace.go:171","msg":"trace[1243744518] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1329; }","duration":"243.116248ms","start":"2026-07-21T21:00:39.278874Z","end":"2026-07-21T21:00:39.521991Z","steps":["trace[1243744518] 'read index received' (duration: 187.412351ms)","trace[1243744518] 'applied index is now lower than readState.Index' (duration: 55.703307ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:00:39.522171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.284044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-21T21:00:39.522203Z","caller":"traceutil/trace.go:171","msg":"trace[2050623222] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1273; }","duration":"243.329835ms","start":"2026-07-21T21:00:39.278865Z","end":"2026-07-21T21:00:39.522194Z","steps":["trace[2050623222] 'agreement among raft nodes before linearized reading' (duration: 243.196971ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:39.522563Z","caller":"traceutil/trace.go:171","msg":"trace[713335503] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"351.070194ms","start":"2026-07-21T21:00:39.171473Z","end":"2026-07-21T21:00:39.522543Z","steps":["trace[713335503] 'process raft request' (duration: 294.959186ms)","trace[713335503] 'compare' (duration: 55.278366ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:00:39.522656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:39.17145Z","time spent":"351.148896ms","remote":"127.0.0.1:37460","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-07-21T21:00:40.281136Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874242277033249,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T21:00:40.782098Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874242277033249,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T21:00:40.850818Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.321711472s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T21:00:40.851111Z","caller":"traceutil/trace.go:171","msg":"trace[166938571] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"1.323827997s","start":"2026-07-21T21:00:39.527267Z","end":"2026-07-21T21:00:40.851095Z","steps":["trace[166938571] 'process raft request' (duration: 1.323720565s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:40.8512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:39.527251Z","time spent":"1.32389474s","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:00:40.900965Z","caller":"traceutil/trace.go:171","msg":"trace[507861557] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"1.08540198s","start":"2026-07-21T21:00:39.815534Z","end":"2026-07-21T21:00:40.900936Z","steps":["trace[507861557] 'process raft request' (duration: 1.085197015s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:40.901091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:39.815508Z","time spent":"1.085520453s","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:00:40.905989Z","caller":"traceutil/trace.go:171","msg":"trace[1200457434] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1330; }","duration":"1.12581795s","start":"2026-07-21T21:00:39.780151Z","end":"2026-07-21T21:00:40.905969Z","steps":["trace[1200457434] 'read index received' (duration: 1.070866512s)","trace[1200457434] 'applied index is now lower than readState.Index' (duration: 54.950348ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:00:40.906237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.126068288s","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-07-21T21:00:40.906277Z","caller":"traceutil/trace.go:171","msg":"trace[336182647] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1275; }","duration":"1.12616462s","start":"2026-07-21T21:00:39.780103Z","end":"2026-07-21T21:00:40.906268Z","steps":["trace[336182647] 'agreement among raft nodes before linearized reading' (duration: 1.125989815s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:40.906337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:39.780081Z","time spent":"1.126245732s","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-21T21:00:40.90668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013399548s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:00:40.906719Z","caller":"traceutil/trace.go:171","msg":"trace[1581963323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1275; }","duration":"1.01345943s","start":"2026-07-21T21:00:39.893249Z","end":"2026-07-21T21:00:40.906709Z","steps":["trace[1581963323] 'agreement among raft nodes before linearized reading' (duration: 1.013404869s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:40.906748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:39.893232Z","time spent":"1.013510861s","remote":"127.0.0.1:37206","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-07-21T21:00:40.907071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.342255ms","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-07-21T21:00:40.907113Z","caller":"traceutil/trace.go:171","msg":"trace[705679618] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1275; }","duration":"727.418906ms","start":"2026-07-21T21:00:40.179684Z","end":"2026-07-21T21:00:40.907103Z","steps":["trace[705679618] 'agreement among raft nodes before linearized reading' (duration: 727.351274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:40.907149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:40.179663Z","time spent":"727.477628ms","remote":"127.0.0.1:37330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:00:40.907884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.126782016s","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-07-21T21:00:40.906508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.126255863s","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-07-21T21:00:40.907927Z","caller":"traceutil/trace.go:171","msg":"trace[1703647119] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1275; }","duration":"1.126863398s","start":"2026-07-21T21:00:39.781053Z","end":"2026-07-21T21:00:40.907916Z","steps":["trace[1703647119] 'agreement among raft nodes before linearized reading' (duration: 1.126755095s)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:40.908075Z","caller":"traceutil/trace.go:171","msg":"trace[174251570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1275; }","duration":"1.127874414s","start":"2026-07-21T21:00:39.780183Z","end":"2026-07-21T21:00:40.908058Z","steps":["trace[174251570] 'agreement among raft nodes before linearized reading' (duration: 1.12614499s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:40.908169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:39.781031Z","time spent":"1.127123245s","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-21T21:00:40.90831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:39.780148Z","time spent":"1.128024398s","remote":"127.0.0.1:37460","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-07-21T21:00:40.908483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.126787266s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T21:00:40.908524Z","caller":"traceutil/trace.go:171","msg":"trace[2092197810] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1275; }","duration":"1.126846157s","start":"2026-07-21T21:00:39.781668Z","end":"2026-07-21T21:00:40.908514Z","steps":["trace[2092197810] 'agreement among raft nodes before linearized reading' (duration: 1.125787649s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:40.908558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:39.78166Z","time spent":"1.126888068s","remote":"127.0.0.1:37270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":107,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-07-21T21:00:41.818556Z","caller":"traceutil/trace.go:171","msg":"trace[1754899474] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"171.263372ms","start":"2026-07-21T21:00:41.647265Z","end":"2026-07-21T21:00:41.818528Z","steps":["trace[1754899474] 'process raft request' (duration: 170.956513ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:42.391232Z","caller":"traceutil/trace.go:171","msg":"trace[2120445700] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"105.03763ms","start":"2026-07-21T21:00:42.28617Z","end":"2026-07-21T21:00:42.391208Z","steps":["trace[2120445700] 'process raft request' (duration: 104.886166ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:43.069173Z","caller":"traceutil/trace.go:171","msg":"trace[1050439381] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"140.241165ms","start":"2026-07-21T21:00:42.928909Z","end":"2026-07-21T21:00:43.06915Z","steps":["trace[1050439381] 'process raft request' (duration: 140.215584ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:43.069472Z","caller":"traceutil/trace.go:171","msg":"trace[1392524646] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"141.033245ms","start":"2026-07-21T21:00:42.928408Z","end":"2026-07-21T21:00:43.069441Z","steps":["trace[1392524646] 'process raft request' (duration: 140.686687ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:43.069575Z","caller":"traceutil/trace.go:171","msg":"trace[1298834387] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"144.699771ms","start":"2026-07-21T21:00:42.924859Z","end":"2026-07-21T21:00:43.069559Z","steps":["trace[1298834387] 'process raft request' (duration: 69.536387ms)","trace[1298834387] 'compare' (duration: 74.597129ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:00:43.454041Z","caller":"traceutil/trace.go:171","msg":"trace[1218983657] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1357; }","duration":"233.785316ms","start":"2026-07-21T21:00:43.220236Z","end":"2026-07-21T21:00:43.454022Z","steps":["trace[1218983657] 'read index received' (duration: 232.404201ms)","trace[1218983657] 'applied index is now lower than readState.Index' (duration: 1.380075ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:00:43.454136Z","caller":"traceutil/trace.go:171","msg":"trace[1421691697] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"256.915197ms","start":"2026-07-21T21:00:43.197208Z","end":"2026-07-21T21:00:43.454123Z","steps":["trace[1421691697] 'process raft request' (duration: 255.4881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:43.454215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.979155ms","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-07-21T21:00:43.454284Z","caller":"traceutil/trace.go:171","msg":"trace[2054856289] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1300; }","duration":"183.090419ms","start":"2026-07-21T21:00:43.271177Z","end":"2026-07-21T21:00:43.454267Z","steps":["trace[2054856289] 'agreement among raft nodes before linearized reading' (duration: 182.963245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:43.454304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.045333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:00:43.454376Z","caller":"traceutil/trace.go:171","msg":"trace[984313885] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1300; }","duration":"234.166686ms","start":"2026-07-21T21:00:43.220193Z","end":"2026-07-21T21:00:43.45436Z","steps":["trace[984313885] 'agreement among raft nodes before linearized reading' (duration: 233.95036ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:44.726994Z","caller":"traceutil/trace.go:171","msg":"trace[1281206269] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"136.122217ms","start":"2026-07-21T21:00:44.590833Z","end":"2026-07-21T21:00:44.726955Z","steps":["trace[1281206269] 'process raft request' (duration: 95.928823ms)","trace[1281206269] 'compare' (duration: 39.902637ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:00:45.0492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.455142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-21T21:00:45.049286Z","caller":"traceutil/trace.go:171","msg":"trace[441169142] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1309; }","duration":"172.597045ms","start":"2026-07-21T21:00:44.876673Z","end":"2026-07-21T21:00:45.04927Z","steps":["trace[441169142] 'range keys from in-memory index tree' (duration: 172.282837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:45.049388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.725295ms","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-07-21T21:00:45.049487Z","caller":"traceutil/trace.go:171","msg":"trace[1984632982] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1309; }","duration":"108.8686ms","start":"2026-07-21T21:00:44.940595Z","end":"2026-07-21T21:00:45.049464Z","steps":["trace[1984632982] 'count revisions from in-memory index tree' (duration: 108.642893ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:45.049503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.124625ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:00:45.049591Z","caller":"traceutil/trace.go:171","msg":"trace[1189781884] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1309; }","duration":"102.217727ms","start":"2026-07-21T21:00:44.947358Z","end":"2026-07-21T21:00:45.049576Z","steps":["trace[1189781884] 'range keys from in-memory index tree' (duration: 102.112234ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:45.230298Z","caller":"traceutil/trace.go:171","msg":"trace[2131578632] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"101.555869ms","start":"2026-07-21T21:00:45.128708Z","end":"2026-07-21T21:00:45.230264Z","steps":["trace[2131578632] 'process raft request' (duration: 100.839111ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:45.35475Z","caller":"traceutil/trace.go:171","msg":"trace[2050492452] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"212.801811ms","start":"2026-07-21T21:00:45.141924Z","end":"2026-07-21T21:00:45.354726Z","steps":["trace[2050492452] 'process raft request' (duration: 212.75273ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:45.354836Z","caller":"traceutil/trace.go:171","msg":"trace[483738454] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"215.973864ms","start":"2026-07-21T21:00:45.138812Z","end":"2026-07-21T21:00:45.354786Z","steps":["trace[483738454] 'process raft request' (duration: 200.513862ms)","trace[483738454] 'compare' (duration: 15.209454ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:00:47.873466Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874242277033343,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T21:00:47.991029Z","caller":"traceutil/trace.go:171","msg":"trace[88971416] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"628.391601ms","start":"2026-07-21T21:00:47.362611Z","end":"2026-07-21T21:00:47.991003Z","steps":["trace[88971416] 'process raft request' (duration: 628.194276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:47.99115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:47.362596Z","time spent":"628.497434ms","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:00:49.072278Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.081569391s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-21T21:00:49.277553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.989158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T21:00:49.277839Z","caller":"traceutil/trace.go:171","msg":"trace[259933331] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"824.92055ms","start":"2026-07-21T21:00:48.452899Z","end":"2026-07-21T21:00:49.27782Z","steps":["trace[259933331] 'process raft request' (duration: 824.838158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:49.277928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:48.452867Z","time spent":"825.022452ms","remote":"127.0.0.1:37460","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-07-21T21:00:49.278361Z","caller":"traceutil/trace.go:171","msg":"trace[1808992556] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"1.915539636s","start":"2026-07-21T21:00:47.3628Z","end":"2026-07-21T21:00:49.27834Z","steps":["trace[1808992556] 'process raft request' (duration: 1.709687896s)","trace[1808992556] 'compare' (duration: 204.69185ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:00:49.278459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:47.362783Z","time spent":"1.915631088s","remote":"127.0.0.1:37460","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-07-21T21:00:49.278652Z","caller":"traceutil/trace.go:171","msg":"trace[1508763202] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"1.72364747s","start":"2026-07-21T21:00:47.55499Z","end":"2026-07-21T21:00:49.278637Z","steps":["trace[1508763202] 'process raft request' (duration: 1.722677414s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:49.278744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:47.554965Z","time spent":"1.723717332s","remote":"127.0.0.1:37460","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-07-21T21:00:49.278871Z","caller":"traceutil/trace.go:171","msg":"trace[2065858013] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1376; }","duration":"1.905463985s","start":"2026-07-21T21:00:47.373391Z","end":"2026-07-21T21:00:49.278855Z","steps":["trace[2065858013] 'read index received' (duration: 617.379597ms)","trace[2065858013] 'applied index is now lower than readState.Index' (duration: 1.288082898s)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:00:49.27905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.464736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T21:00:49.279059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.905647399s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:00:49.279108Z","caller":"traceutil/trace.go:171","msg":"trace[1383730321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1321; }","duration":"136.567459ms","start":"2026-07-21T21:00:49.142525Z","end":"2026-07-21T21:00:49.279093Z","steps":["trace[1383730321] 'agreement among raft nodes before linearized reading' (duration: 136.459636ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:49.27912Z","caller":"traceutil/trace.go:171","msg":"trace[565206169] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1321; }","duration":"1.905763872s","start":"2026-07-21T21:00:47.373338Z","end":"2026-07-21T21:00:49.279102Z","steps":["trace[565206169] 'agreement among raft nodes before linearized reading' (duration: 1.905629579s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:49.279133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.635323853s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-21T21:00:49.279159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:47.373312Z","time spent":"1.905836734s","remote":"127.0.0.1:37520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-07-21T21:00:49.279194Z","caller":"traceutil/trace.go:171","msg":"trace[1023622793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1321; }","duration":"1.635427376s","start":"2026-07-21T21:00:47.643752Z","end":"2026-07-21T21:00:49.279179Z","steps":["trace[1023622793] 'agreement among raft nodes before linearized reading' (duration: 1.635235141s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:49.279208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14465549s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-21T21:00:49.279227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:47.643737Z","time spent":"1.635484037s","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-21T21:00:49.279248Z","caller":"traceutil/trace.go:171","msg":"trace[89668202] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1321; }","duration":"1.144733932s","start":"2026-07-21T21:00:48.134501Z","end":"2026-07-21T21:00:49.279235Z","steps":["trace[89668202] 'agreement among raft nodes before linearized reading' (duration: 1.144659351s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:49.27926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.386820174s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:00:49.279312Z","caller":"traceutil/trace.go:171","msg":"trace[928871908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1321; }","duration":"1.386908917s","start":"2026-07-21T21:00:47.892389Z","end":"2026-07-21T21:00:49.279298Z","steps":["trace[928871908] 'agreement among raft nodes before linearized reading' (duration: 1.386833145s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:49.279307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:48.134477Z","time spent":"1.144809565s","remote":"127.0.0.1:37714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-07-21T21:00:49.279338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:47.892371Z","time spent":"1.386962219s","remote":"127.0.0.1:37192","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-07-21T21:00:51.389371Z","caller":"traceutil/trace.go:171","msg":"trace[297576260] linearizableReadLoop","detail":"{readStateIndex:1396; appliedIndex:1395; }","duration":"105.600014ms","start":"2026-07-21T21:00:51.283749Z","end":"2026-07-21T21:00:51.389349Z","steps":["trace[297576260] 'read index received' (duration: 105.412929ms)","trace[297576260] 'applied index is now lower than readState.Index' (duration: 186.205µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:00:51.389605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.8321ms","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-07-21T21:00:51.389641Z","caller":"traceutil/trace.go:171","msg":"trace[1593544275] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1336; }","duration":"105.902872ms","start":"2026-07-21T21:00:51.283727Z","end":"2026-07-21T21:00:51.38963Z","steps":["trace[1593544275] 'agreement among raft nodes before linearized reading' (duration: 105.748108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:51.389683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.96896ms","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-07-21T21:00:51.389805Z","caller":"traceutil/trace.go:171","msg":"trace[15662879] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1336; }","duration":"102.131114ms","start":"2026-07-21T21:00:51.28765Z","end":"2026-07-21T21:00:51.389781Z","steps":["trace[15662879] 'agreement among raft nodes before linearized reading' (duration: 101.854778ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:51.390372Z","caller":"traceutil/trace.go:171","msg":"trace[755114346] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"106.809657ms","start":"2026-07-21T21:00:51.283545Z","end":"2026-07-21T21:00:51.390355Z","steps":["trace[755114346] 'process raft request' (duration: 105.637346ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:53.645829Z","caller":"traceutil/trace.go:171","msg":"trace[1515747001] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1408; }","duration":"245.582082ms","start":"2026-07-21T21:00:53.400229Z","end":"2026-07-21T21:00:53.645811Z","steps":["trace[1515747001] 'read index received' (duration: 245.436488ms)","trace[1515747001] 'applied index is now lower than readState.Index' (duration: 144.734µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:00:53.645918Z","caller":"traceutil/trace.go:171","msg":"trace[36274219] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"248.90782ms","start":"2026-07-21T21:00:53.396984Z","end":"2026-07-21T21:00:53.645892Z","steps":["trace[36274219] 'process raft request' (duration: 248.709044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:53.646416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.737157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-21T21:00:53.646466Z","caller":"traceutil/trace.go:171","msg":"trace[1961994103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1349; }","duration":"240.85881ms","start":"2026-07-21T21:00:53.405596Z","end":"2026-07-21T21:00:53.646455Z","steps":["trace[1961994103] 'agreement among raft nodes before linearized reading' (duration: 240.695586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:53.646454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.948262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T21:00:53.646518Z","caller":"traceutil/trace.go:171","msg":"trace[2116429220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1349; }","duration":"241.046355ms","start":"2026-07-21T21:00:53.405454Z","end":"2026-07-21T21:00:53.6465Z","steps":["trace[2116429220] 'agreement among raft nodes before linearized reading' (duration: 240.879211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:53.646029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.789088ms","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-07-21T21:00:53.646666Z","caller":"traceutil/trace.go:171","msg":"trace[901981960] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1349; }","duration":"246.478806ms","start":"2026-07-21T21:00:53.400176Z","end":"2026-07-21T21:00:53.646654Z","steps":["trace[901981960] 'agreement among raft nodes before linearized reading' (duration: 245.725726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:53.646217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.790499ms","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-07-21T21:00:53.647114Z","caller":"traceutil/trace.go:171","msg":"trace[2125087112] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1349; }","duration":"241.702072ms","start":"2026-07-21T21:00:53.405395Z","end":"2026-07-21T21:00:53.647097Z","steps":["trace[2125087112] 'agreement among raft nodes before linearized reading' (duration: 240.773488ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:56.799584Z","caller":"traceutil/trace.go:171","msg":"trace[1991661064] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"395.631563ms","start":"2026-07-21T21:00:56.403928Z","end":"2026-07-21T21:00:56.79956Z","steps":["trace[1991661064] 'process raft request' (duration: 395.489259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:00:56.799747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:00:56.403912Z","time spent":"395.744846ms","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T21:00:59.458603Z","caller":"traceutil/trace.go:171","msg":"trace[355914527] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"168.718122ms","start":"2026-07-21T21:00:59.289866Z","end":"2026-07-21T21:00:59.458584Z","steps":["trace[355914527] 'process raft request' (duration: 168.614509ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:59.649932Z","caller":"traceutil/trace.go:171","msg":"trace[1000547078] linearizableReadLoop","detail":"{readStateIndex:1454; appliedIndex:1453; }","duration":"100.192572ms","start":"2026-07-21T21:00:59.54972Z","end":"2026-07-21T21:00:59.649913Z","steps":["trace[1000547078] 'read index received' (duration: 96.788983ms)","trace[1000547078] 'applied index is now lower than readState.Index' (duration: 3.402718ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:00:59.650123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.401858ms","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-07-21T21:00:59.65017Z","caller":"traceutil/trace.go:171","msg":"trace[968444683] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1393; }","duration":"100.50443ms","start":"2026-07-21T21:00:59.54965Z","end":"2026-07-21T21:00:59.650155Z","steps":["trace[968444683] 'agreement among raft nodes before linearized reading' (duration: 100.353417ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:00:59.650244Z","caller":"traceutil/trace.go:171","msg":"trace[891868637] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"174.900772ms","start":"2026-07-21T21:00:59.475164Z","end":"2026-07-21T21:00:59.650065Z","steps":["trace[891868637] 'process raft request' (duration: 171.33702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:00.088301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.21986ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-21T21:01:01.842791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.414686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T21:01:01.84287Z","caller":"traceutil/trace.go:171","msg":"trace[17010092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1407; }","duration":"103.525649ms","start":"2026-07-21T21:01:01.739327Z","end":"2026-07-21T21:01:01.842853Z","steps":["trace[17010092] 'range keys from in-memory index tree' (duration: 103.236442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:13.965489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.799412ms","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-07-21T21:01:13.965582Z","caller":"traceutil/trace.go:171","msg":"trace[785429908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1458; }","duration":"109.923924ms","start":"2026-07-21T21:01:13.855637Z","end":"2026-07-21T21:01:13.965561Z","steps":["trace[785429908] 'range keys from in-memory index tree' (duration: 109.641537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:13.965844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.467609ms","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-07-21T21:01:13.965912Z","caller":"traceutil/trace.go:171","msg":"trace[1149109600] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1458; }","duration":"110.536141ms","start":"2026-07-21T21:01:13.855331Z","end":"2026-07-21T21:01:13.965867Z","steps":["trace[1149109600] 'range keys from in-memory index tree' (duration: 110.356375ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:14.189714Z","caller":"traceutil/trace.go:171","msg":"trace[568605957] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"220.888588ms","start":"2026-07-21T21:01:13.968793Z","end":"2026-07-21T21:01:14.189682Z","steps":["trace[568605957] 'process raft request' (duration: 220.703583ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:14.369039Z","caller":"traceutil/trace.go:171","msg":"trace[51528341] linearizableReadLoop","detail":"{readStateIndex:1525; appliedIndex:1522; }","duration":"355.132863ms","start":"2026-07-21T21:01:14.013887Z","end":"2026-07-21T21:01:14.36902Z","steps":["trace[51528341] 'read index received' (duration: 175.627082ms)","trace[51528341] 'applied index is now lower than readState.Index' (duration: 179.504971ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:01:14.369122Z","caller":"traceutil/trace.go:171","msg":"trace[1257376529] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"399.995078ms","start":"2026-07-21T21:01:13.969061Z","end":"2026-07-21T21:01:14.369056Z","steps":["trace[1257376529] 'process raft request' (duration: 325.036411ms)","trace[1257376529] 'compare' (duration: 74.779643ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:01:14.369213Z","caller":"traceutil/trace.go:171","msg":"trace[246040779] transaction","detail":"{read_only:false; response_revision:1461; number_of_response:1; }","duration":"398.381736ms","start":"2026-07-21T21:01:13.97077Z","end":"2026-07-21T21:01:14.369152Z","steps":["trace[246040779] 'process raft request' (duration: 398.213952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:14.369319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.39921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-21T21:01:14.369394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:13.969044Z","time spent":"400.198973ms","remote":"127.0.0.1:37460","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-07-21T21:01:14.369415Z","caller":"traceutil/trace.go:171","msg":"trace[1790818013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1461; }","duration":"355.551884ms","start":"2026-07-21T21:01:14.013842Z","end":"2026-07-21T21:01:14.369394Z","steps":["trace[1790818013] 'agreement among raft nodes before linearized reading' (duration: 355.270717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:14.369443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:13.970763Z","time spent":"398.558442ms","remote":"127.0.0.1:37460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T21:01:14.369467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T21:01:14.013811Z","time spent":"355.647107ms","remote":"127.0.0.1:37460","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-07-21T21:01:14.590818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.612822ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T21:01:14.590997Z","caller":"traceutil/trace.go:171","msg":"trace[1463696005] linearizableReadLoop","detail":"{readStateIndex:1527; appliedIndex:1525; }","duration":"127.722366ms","start":"2026-07-21T21:01:14.463258Z","end":"2026-07-21T21:01:14.590981Z","steps":["trace[1463696005] 'read index received' (duration: 16.843208ms)","trace[1463696005] 'applied index is now lower than readState.Index' (duration: 110.878098ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:01:14.591109Z","caller":"traceutil/trace.go:171","msg":"trace[913457191] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"216.538423ms","start":"2026-07-21T21:01:14.37453Z","end":"2026-07-21T21:01:14.591068Z","steps":["trace[913457191] 'process raft request' (duration: 216.391099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:14.591169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.912011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-21T21:01:14.591199Z","caller":"traceutil/trace.go:171","msg":"trace[1092143227] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:1463; }","duration":"127.987673ms","start":"2026-07-21T21:01:14.463201Z","end":"2026-07-21T21:01:14.591189Z","steps":["trace[1092143227] 'agreement among raft nodes before linearized reading' (duration: 127.86821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:01:14.591561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.966867ms","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:525"} {"level":"info","ts":"2026-07-21T21:01:14.591719Z","caller":"traceutil/trace.go:171","msg":"trace[497450558] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"216.776649ms","start":"2026-07-21T21:01:14.374375Z","end":"2026-07-21T21:01:14.591152Z","steps":["trace[497450558] 'process raft request' (duration: 105.715125ms)","trace[497450558] 'compare' (duration: 110.49637ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:01:14.592354Z","caller":"traceutil/trace.go:171","msg":"trace[1187047252] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1463; }","duration":"107.259315ms","start":"2026-07-21T21:01:14.484408Z","end":"2026-07-21T21:01:14.591667Z","steps":["trace[1187047252] 'agreement among raft nodes before linearized reading' (duration: 106.885416ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:01:18.551773Z","caller":"traceutil/trace.go:171","msg":"trace[37306330] transaction","detail":"{read_only:false; response_revision:1480; number_of_response:1; }","duration":"161.801093ms","start":"2026-07-21T21:01:18.389949Z","end":"2026-07-21T21:01:18.55175Z","steps":["trace[37306330] 'process raft request' (duration: 107.827631ms)","trace[37306330] 'compare' (duration: 53.885428ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:01:36.739474Z","caller":"traceutil/trace.go:171","msg":"trace[1238046425] transaction","detail":"{read_only:false; response_revision:1565; number_of_response:1; }","duration":"100.4693ms","start":"2026-07-21T21:01:36.638976Z","end":"2026-07-21T21:01:36.739446Z","steps":["trace[1238046425] 'process raft request' (duration: 100.257233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:02:14.651968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.848915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T21:02:14.652703Z","caller":"traceutil/trace.go:171","msg":"trace[1658568665] transaction","detail":"{read_only:false; response_revision:1737; number_of_response:1; }","duration":"124.708253ms","start":"2026-07-21T21:02:14.527974Z","end":"2026-07-21T21:02:14.652683Z","steps":["trace[1658568665] 'process raft request' (duration: 124.58541ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:02:14.652686Z","caller":"traceutil/trace.go:171","msg":"trace[750526635] transaction","detail":"{read_only:false; response_revision:1736; number_of_response:1; }","duration":"126.541101ms","start":"2026-07-21T21:02:14.526103Z","end":"2026-07-21T21:02:14.652644Z","steps":["trace[750526635] 'process raft request' (duration: 16.937128ms)","trace[750526635] 'compare' (duration: 108.444113ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:02:51.158183Z","caller":"traceutil/trace.go:171","msg":"trace[175942875] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"278.024517ms","start":"2026-07-21T21:02:50.880123Z","end":"2026-07-21T21:02:51.158147Z","steps":["trace[175942875] 'process raft request' (duration: 277.858832ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:02:51.158637Z","caller":"traceutil/trace.go:171","msg":"trace[539409349] linearizableReadLoop","detail":"{readStateIndex:1989; appliedIndex:1989; }","duration":"162.460649ms","start":"2026-07-21T21:02:50.996138Z","end":"2026-07-21T21:02:51.158599Z","steps":["trace[539409349] 'read index received' (duration: 162.447869ms)","trace[539409349] 'applied index is now lower than readState.Index' (duration: 10.11µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:02:51.158797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.632675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:02:51.158889Z","caller":"traceutil/trace.go:171","msg":"trace[1555555749] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1905; }","duration":"162.771738ms","start":"2026-07-21T21:02:50.996096Z","end":"2026-07-21T21:02:51.158868Z","steps":["trace[1555555749] 'agreement among raft nodes before linearized reading' (duration: 162.627433ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:03:02.483649Z","caller":"traceutil/trace.go:171","msg":"trace[833221713] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"101.497274ms","start":"2026-07-21T21:03:02.382128Z","end":"2026-07-21T21:03:02.483625Z","steps":["trace[833221713] 'process raft request' (duration: 101.262728ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:03:13.632975Z","caller":"traceutil/trace.go:171","msg":"trace[1590737797] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"134.739319ms","start":"2026-07-21T21:03:13.498063Z","end":"2026-07-21T21:03:13.632802Z","steps":["trace[1590737797] 'process raft request' (duration: 84.86608ms)","trace[1590737797] 'compare' (duration: 49.674614ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T21:03:14.626506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.661003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T21:03:14.626597Z","caller":"traceutil/trace.go:171","msg":"trace[1978668991] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"151.203039ms","start":"2026-07-21T21:03:14.475379Z","end":"2026-07-21T21:03:14.626582Z","steps":["trace[1978668991] 'process raft request' (duration: 48.420652ms)","trace[1978668991] 'compare' (duration: 102.086879ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:03:20.40898Z","caller":"traceutil/trace.go:171","msg":"trace[1793281037] linearizableReadLoop","detail":"{readStateIndex:2131; appliedIndex:2130; }","duration":"112.778037ms","start":"2026-07-21T21:03:20.296181Z","end":"2026-07-21T21:03:20.40896Z","steps":["trace[1793281037] 'read index received' (duration: 112.548981ms)","trace[1793281037] 'applied index is now lower than readState.Index' (duration: 228.236µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:03:20.409071Z","caller":"traceutil/trace.go:171","msg":"trace[1507790447] transaction","detail":"{read_only:false; response_revision:2041; number_of_response:1; }","duration":"135.1705ms","start":"2026-07-21T21:03:20.273868Z","end":"2026-07-21T21:03:20.409039Z","steps":["trace[1507790447] 'process raft request' (duration: 134.964205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:03:20.409217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.014053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T21:03:20.409325Z","caller":"traceutil/trace.go:171","msg":"trace[1750025000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2041; }","duration":"113.178108ms","start":"2026-07-21T21:03:20.296123Z","end":"2026-07-21T21:03:20.409301Z","steps":["trace[1750025000] 'agreement among raft nodes before linearized reading' (duration: 113.007523ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:04:13.876754Z","caller":"traceutil/trace.go:171","msg":"trace[2120805149] transaction","detail":"{read_only:false; response_revision:2284; number_of_response:1; }","duration":"131.292575ms","start":"2026-07-21T21:04:13.74541Z","end":"2026-07-21T21:04:13.876703Z","steps":["trace[2120805149] 'process raft request' (duration: 130.976777ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:04:41.669033Z","caller":"traceutil/trace.go:171","msg":"trace[364677021] transaction","detail":"{read_only:false; response_revision:2410; number_of_response:1; }","duration":"280.029587ms","start":"2026-07-21T21:04:41.388839Z","end":"2026-07-21T21:04:41.668869Z","steps":["trace[364677021] 'process raft request' (duration: 279.78759ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:04:42.84371Z","caller":"traceutil/trace.go:171","msg":"trace[1367234895] linearizableReadLoop","detail":"{readStateIndex:2522; appliedIndex:2521; }","duration":"129.712643ms","start":"2026-07-21T21:04:42.713961Z","end":"2026-07-21T21:04:42.843673Z","steps":["trace[1367234895] 'read index received' (duration: 129.419105ms)","trace[1367234895] 'applied index is now lower than readState.Index' (duration: 292.408µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:04:42.84379Z","caller":"traceutil/trace.go:171","msg":"trace[153036812] transaction","detail":"{read_only:false; response_revision:2416; number_of_response:1; }","duration":"149.256154ms","start":"2026-07-21T21:04:42.694502Z","end":"2026-07-21T21:04:42.843758Z","steps":["trace[153036812] 'process raft request' (duration: 149.009068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T21:04:42.843987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.038641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-07-21T21:04:42.844031Z","caller":"traceutil/trace.go:171","msg":"trace[1775065409] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2416; }","duration":"130.147995ms","start":"2026-07-21T21:04:42.71387Z","end":"2026-07-21T21:04:42.844018Z","steps":["trace[1775065409] 'agreement among raft nodes before linearized reading' (duration: 129.95088ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:04:42.975117Z","caller":"traceutil/trace.go:171","msg":"trace[1251694330] transaction","detail":"{read_only:false; response_revision:2417; number_of_response:1; }","duration":"126.530041ms","start":"2026-07-21T21:04:42.848544Z","end":"2026-07-21T21:04:42.975074Z","steps":["trace[1251694330] 'process raft request' (duration: 58.515ms)","trace[1251694330] 'compare' (duration: 67.713012ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T21:04:48.389565Z","caller":"traceutil/trace.go:171","msg":"trace[247182628] transaction","detail":"{read_only:false; response_revision:2444; number_of_response:1; }","duration":"101.732622ms","start":"2026-07-21T21:04:48.287801Z","end":"2026-07-21T21:04:48.389534Z","steps":["trace[247182628] 'process raft request' (duration: 101.571448ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T21:05:17.051979Z","caller":"traceutil/trace.go:171","msg":"trace[1783316543] transaction","detail":"{read_only:false; response_revision:2603; number_of_response:1; }","duration":"101.126805ms","start":"2026-07-21T21:05:16.95083Z","end":"2026-07-21T21:05:17.051957Z","steps":["trace[1783316543] 'process raft request' (duration: 100.991202ms)"],"step_count":1}