go tool pprof -http=:8080 cpu.out
go test -bench=. -cpuprofile=cpu.out
curl -o cpu.out localhost:6060/debug/pprof/profile

接口偶发多花六十毫秒,pprof 连续采了三分钟,火焰图上完全找不到这一段,热点全被平均到正常请求里。先别急着动参数,把报错原文和时间点记下来,顺序反了容易白折腾。

原因不复杂,默认按一百赫兹采样,一次只有十毫秒粒度,短时抖动被大量正常请求稀释,函数本身耗时又不足以在堆栈里冒头,汇总视图自然看不出来。

「pprof 采样间隔」确认了原因之后,改动尽量只碰那一处,顺手改的东西后面最容易出问题。改动写完就同步给同事,下一个人踩同一个坑,成本还是你的。

验证用真实数据走一遍,测试数据太干净,边界情况根本碰不到。能分两步做的操作就别合成一步,中间看一眼返回值,出问题好判断。

处理上按这个顺序:把采样频率提高再复现问题;只对出问题的那段时间抓取;配合 trace 看单次请求时间线而不是看汇总。

改完只确认一件事:同一段耗时在 trace 和火焰图里都能指到具体函数。

作者 9823189