Files
openteam/server/internal/usage/usage_test.go
T
Sakurasan 8c12207478 perf: 目录缓存与流式正文快路径,请求热路径开销减半
按性能分析逐项落地,实测对比(16 并发、mock 上游零延迟、SQLite):

- 每请求 SQL 10 → 2:模型/渠道目录(模型行、绑定、渠道行)进程内缓存 10s,
  运行期状态(冷却、健康)仍每次实时判定;管理端写操作经中间件、
  健康状态变化经回调立即失效。新增 TestProxyRequestSQLBudget 钉住预算。
- 端到端 p50 1.3–1.6ms → 0.66–0.70ms,RPS 3.5–8.9k → 16–18k。
- 流式正文累积:字符串 += 改 strings.Builder(原为 O(n²)),
  逐行 JSON 提取改字节快路径,并加廉价的子串预过滤、拿到输出 usage 后停累积。
  500 块:949µs/7.9MB 分配 → 83µs/136KB。
- 请求体定点改写:模型映射从整包 unmarshal/marshal 改为顶层字段替换
  (100KB:585µs → 29µs),stream_options 注入同理;requestText 改为
  只在「上游没报用量」时按需提取,亲和键改用原始体前缀。
- 渠道满载背压:新增 Acquire 有界排队(OT_PROXY_CHANNEL_ACQUIRE_WAIT,
  默认 150ms);排队仍满时返回 503 + Retry-After 而不是 502。
  实测渠道上限 4、16 并发:无排队 81 次 503;排队 150ms 后 400/400 成功。
- 记账按批聚合:同批同用户一次扣款、流水批量插入、日表按 (user,model,日期)
  聚合,替代每行 5 条语句;幂等仍靠 ref_id 唯一索引兜底。
- gorm 开启 PrepareStmt(SQL 解析此前占 CPU 15%);tiktoken 编码器按模型复用。
- 管理端用量列表不再返回原始请求/响应体(可能几十 MB),
  改为 has_raw 标记 + GET /admin/usage/:id 按需拉取,前端弹窗打开时取。

验证:go vet、go test ./...、go test -race(proxy/channel/usage/tokenizer)、
vue-tsc、eslint、vite build 全绿;本地起 mock 上游实测列表/详情接口与
管理页弹窗按需加载。
2026-10-30 14:14:21 +08:00

221 lines
6.1 KiB
Go

package usage
import (
"fmt"
"os"
"testing"
"time"
"github.com/openteam/server/internal/store"
"gorm.io/gorm"
)
func TestCloseDrainsQueue(t *testing.T) {
db := openTestDB(t)
u := seedUser(t, db, 100)
rec := NewRecorder(db)
// 快速灌入超过一批的记录(batchSize=32),大部分会留在 channel 里
for i := 0; i < 100; i++ {
rec.Record(&store.UsageLog{
TraceID: fmt.Sprintf("t%d", i), UserID: u, ModelName: "m1",
Status: store.UsageStatusError, ErrorCode: strPtr("test"), // error 状态不触发扣费分支
CreatedAt: time.Now().UTC(),
})
}
rec.Close()
var n int64
db.Table("usage_logs").Count(&n)
if n != 100 {
t.Fatalf("Close 应排空队列全部落库,实际 %d/100", n)
}
}
func TestRecordAfterCloseDoesNotPanic(t *testing.T) {
db := openTestDB(t)
u := seedUser(t, db, 100)
rec := NewRecorder(db)
rec.Close()
// Shutdown 超时后被强断的在途请求仍可能调 Record:不允许 send-on-closed panic
done := make(chan struct{})
go func() {
defer close(done)
rec.Record(&store.UsageLog{
TraceID: "late", UserID: u, ModelName: "m1",
Status: store.UsageStatusError, ErrorCode: strPtr("late"),
CreatedAt: time.Now().UTC(),
})
}()
select {
case <-done:
case <-time.After(5 * time.Second):
t.Fatal("Record 在 Close 之后调用发生阻塞(疑似 panic 被吞)")
}
var n int64
db.Table("usage_logs").Where("trace_id = ?", "late").Count(&n)
if n != 1 {
t.Fatalf("Close 后的 Record 应同步落库,实际 %d 条", n)
}
}
func TestCloseIsIdempotent(t *testing.T) {
db := openTestDB(t)
seedUser(t, db, 0)
rec := NewRecorder(db)
rec.Close()
rec.Close() // serve.go 的 defer Shutdown 与显式 Close 双调用
}
func TestBillingDeductsBalanceAtomically(t *testing.T) {
db := openTestDB(t)
u := seedUser(t, db, 1)
m := seedModel(t, db)
rec := NewRecorder(db)
rec.Record(&store.UsageLog{
TraceID: "bill", UserID: u, ModelID: m, ModelName: "m1",
InputTokens: 1000, OutputTokens: 1000,
InputPrice: 0.15, OutputPrice: 0.60,
Cost: 0.15*1000/1e6 + 0.60*1000/1e6,
Status: store.UsageStatusSuccess,
LatencyMS: 5,
CreatedAt: time.Now().UTC(),
})
rec.Close()
var user store.User
if err := db.First(&user, u).Error; err != nil {
t.Fatal(err)
}
want := 1 - (0.15*1000/1e6 + 0.60*1000/1e6)
if diff := user.Balance - want; diff > 1e-9 || diff < -1e-9 {
t.Fatalf("余额应扣到 %.10f,实际 %.10f", want, user.Balance)
}
var bl store.BalanceLog
if err := db.Where("user_id = ?", u).First(&bl).Error; err != nil {
t.Fatalf("余额流水应落库: %v", err)
}
if bl.Change >= 0 {
t.Fatalf("流水应为负数扣费,实际 %v", bl.Change)
}
}
// ---------------------------------------------------------------------------
// helpers
func openTestDB(t *testing.T) *gorm.DB {
t.Helper()
dsn := "/tmp/usage_test_" + t.Name() + "_" + time.Now().Format("150405.000000000") + ".db"
db, err := store.Open("sqlite", dsn)
if err != nil {
t.Fatalf("open db: %v", err)
}
t.Cleanup(func() {
sqlDB, _ := db.DB()
sqlDB.Close()
os.Remove(dsn)
})
return db
}
func seedUser(t *testing.T, db *gorm.DB, balance float64) uint64 {
t.Helper()
u := store.User{Username: "u" + fmt.Sprint(time.Now().UnixNano()), Email: "u@x.com",
PasswordHash: "x", Role: store.RoleUser, Balance: balance, Status: store.UserStatusActive}
if err := db.Create(&u).Error; err != nil {
t.Fatal(err)
}
return u.ID
}
func seedModel(t *testing.T, db *gorm.DB) uint64 {
t.Helper()
m := store.Model{Name: "m1", InputPrice: 0.15, OutputPrice: 0.60, Enabled: true}
if err := db.Create(&m).Error; err != nil {
t.Fatal(err)
}
return m.ID
}
func strPtr(s string) *string { return &s }
// 同一 trace 重复记账(重试/重复 flush 场景)只扣一次款:流水 ref_id 有唯一索引,
// 但扣款本身没有,billUser 必须先查再扣。
func TestBillingRefIDImpotency(t *testing.T) {
db := openTestDB(t)
u := seedUser(t, db, 5)
rec := NewRecorder(db)
mk := func(trace string) *store.UsageLog {
return &store.UsageLog{TraceID: trace, UserID: u, ModelName: "m1",
Status: store.UsageStatusSuccess, Cost: 1, CreatedAt: time.Now().UTC()}
}
rec.Record(mk("dup"))
rec.Record(mk("dup"))
rec.Close()
var user store.User
if err := db.First(&user, u).Error; err != nil {
t.Fatal(err)
}
if user.Balance != 4 {
t.Errorf("balance = %v, want 4 (重复 trace 不重复扣款)", user.Balance)
}
var n int64
db.Model(&store.BalanceLog{}).Where("user_id = ?", u).Count(&n)
if n != 1 {
t.Errorf("balance logs = %d, want 1", n)
}
}
// 一批同用户请求:一次扣款、一条日聚合(而不是逐行 upsert)。
func TestBillingAggregatesBatch(t *testing.T) {
db := openTestDB(t)
u := seedUser(t, db, 100)
m := seedModel(t, db)
rec := NewRecorder(db)
const n = 32
for i := 0; i < n; i++ {
rec.Record(&store.UsageLog{
TraceID: fmt.Sprintf("agg-%d", i), UserID: u, ModelID: m, ModelName: "m1",
InputTokens: 10, OutputTokens: 5, Cost: 0.01,
Status: store.UsageStatusSuccess, CreatedAt: time.Now().UTC(),
})
}
rec.Close()
var user store.User
if err := db.First(&user, u).Error; err != nil {
t.Fatal(err)
}
if want := 100 - 0.01*n; user.Balance-want > 1e-9 || user.Balance-want < -1e-9 {
t.Fatalf("余额 = %v,期望 %v", user.Balance, want)
}
var ledgers int64
db.Model(&store.BalanceLog{}).Where("user_id = ?", u).Count(&ledgers)
if ledgers != n {
t.Fatalf("流水条数 = %d,期望 %d(每请求一条明细)", ledgers, n)
}
var daily []store.UsageDaily
db.Where("user_id = ?", u).Find(&daily)
if len(daily) != 1 {
t.Fatalf("日聚合应只有 1 行,实际 %d", len(daily))
}
if daily[0].Requests != n {
t.Fatalf("日聚合 requests = %d,期望 %d", daily[0].Requests, n)
}
if d := daily[0].Cost - 0.01*n; d > 1e-9 || d < -1e-9 {
t.Fatalf("日聚合 cost = %v,期望 %v", daily[0].Cost, 0.01*n)
}
// 流水里的 balance_after 应与扣款后余额一致(批内累计)
var last store.BalanceLog
db.Where("user_id = ?", u).Order("id DESC").First(&last)
if last.BalanceAfter-user.Balance > 1e-9 || user.Balance-last.BalanceAfter > 1e-9 {
t.Fatalf("最后一条流水 balance_after = %v,实际余额 %v", last.BalanceAfter, user.Balance)
}
}