如何在 Go 中实现慢查询日志记录和告警-Golang-PHP中文网

database/sql 的 query 和 exec 不暴露执行耗时，因标准库抽象屏蔽了驱动细节，返回值无耗时字段，必须在调用链路中手动包裹计时逻辑或通过 context 超时机制拦截。

如何在 go 中实现慢查询日志记录和告警

为什么 `database/sql` 的 `Query` 和 `Exec` 本身不暴露执行耗时

Go 标准库的 database/sql 抽象了驱动层，所有查询都走统一的 Stmt.Exec 或 DB.Query 接口，但这些方法不返回执行时间，也不提供钩子。你拿到的 *sql.Rows 或 error 里没有耗时字段——这意味着不能靠“事后检查返回值”来判断慢查询。

真正能拦截和计时的位置只有两个：SQL 构造完成、准备执行前；以及执行完成、结果返回后。所以必须在调用链路中手动包裹。

最轻量做法：对每个关键查询显式加 time.Now() 和 time.Since()
更可持续的做法：封装一个带超时和日志的 DB 代理，比如用结构体嵌套 *sql.DB，重写 Query/Exec 方法
不要试图修改底层驱动（如 mysql 或 pgx）的源码——维护成本高，且升级即失效

用 `context.WithTimeout` 拦截并记录超时查询

很多慢查询本质是卡在数据库响应上，而非 Go 层逻辑。这时靠 time.Since() 只能知道“总耗时”，但无法区分是网络延迟、锁等待还是 SQL 本身低效。用 context.WithTimeout 能让查询在指定时间后主动中断，并留下明确信号。

示例：对一条可能慢的 SELECT 加 2 秒超时，并记录日志

ctx, cancel := context.WithTimeout(context.Background(), 2*time.Second)
defer cancel()
rows, err := db.QueryContext(ctx, "SELECT * FROM orders WHERE user_id = ?", userID)
if err != nil {
    if errors.Is(err, context.DeadlineExceeded) {
        log.Printf("SLOW QUERY ALERT: SELECT orders by user_id=%d took >2s", userID)
    }
    return err
}

QueryContext 和 ExecContext 是标准接口，所有现代驱动（mysql, pgx, sqlite3）都支持
注意：超时会触发 context.DeadlineExceeded，但某些旧版驱动（如老 go-sql-driver/mysql）可能返回 driver.ErrBadConn 或直接 panic，建议升级到 v1.7+
别把超时设得太激进——网络抖动或临时锁竞争可能导致误报；生产环境建议从 5s 开始观察，再逐步下调

如何统一注入慢查询日志而不侵入业务代码

如果项目已有上百处 db.Query，逐个加 time.Since() 不现实。可行方案是用中间件式包装：定义一个 LoggingDB 结构体，持有原始 *sql.DB，并在其方法中统一埋点。

Alibabacloud Sdk Client Initialization For Golang

初始化并管理阿里云SDK Go 客户端，涵盖 sync.Once 单例、goroutine 安全、endpoint 与 region 配置、VPC 端点及调试。

下载

关键点不是“记录所有查询”，而是“只记录超过阈值的查询”。否则日志爆炸，反而掩盖真正问题。

type LoggingDB struct {
    db        *sql.DB
    slowThreshold time.Duration
}
<p>func (l <em>LoggingDB) Query(query string, args ...interface{}) (</em>sql.Rows, error) {
start := time.Now()
rows, err := l.db.Query(query, args...)
elapsed := time.Since(start)
if elapsed > l.slowThreshold {
log.Printf("SLOW QUERY (%v): %s | args: %v", elapsed, query, args)
}
return rows, err
}
</p>

注意：不能只看 elapsed，要结合 err == nil 判断是否真执行成功——失败查询也可能很慢（比如死锁重试），也该告警
参数 args 直接打印可能泄露敏感数据（如密码、token），上线前务必脱敏，例如用 log.Printf("... | args: %v", redactArgs(args))
这个包装不兼容 QueryRowContext 等 Context 版本，如需完整支持，得补全全部 8 个公开方法（Query, QueryRow, Exec, 各自的 Context 版本）

告警怎么发才不会被运维拉黑

日志写文件或 stdout 是第一步，但真正有用的是“可行动的告警”。直接每条慢查询都发钉钉/邮件？不出三天就会被静音。

合理做法是聚合 + 降噪：

用内存 map 或简单 LRU cache 统计 60 秒内同一 SQL 模板的慢查询次数（用 sqlparser 或正则提取模板，如把 WHERE id = 123 归一为 WHERE id = ?）
只在 60 秒内同模板慢查询 ≥ 5 次时，才触发告警，并附上最近 3 条完整参数和耗时
告警消息里必须包含可定位信息：服务名、主机 IP、SQL 模板、平均耗时、采样 trace_id（如果有分布式追踪）
避免使用 log.Fatal 或 panic 来“强调严重性”——它会让进程退出，比慢查询本身危害更大