Go 使用 defer 计算函数耗时为什么总是很少？

这是一个新手问题，可能很容易忽略。

今天想计算程序的耗时时间，为了方便使用defer来计算函数的总的执行时间。但是耗时总是很少。后来经过如下实验发下了问题：

logsParam := "[test]"
startTime := time.Now()
defer logs.Warning("%s end-cost Seconds:%vs ", logsParam, time.Since(startTime).Seconds())
time.Sleep(2 * time.Second)

logs.Warning("%s end-cost-2 Seconds:%vs ", logsParam, time.Since(startTime).Seconds())

输出：

2020/08/21 14:55:45.888 [W] [proc.go:203]  [test] end-cost-2 Seconds:2.000612215s 
2020/08/21 14:55:45.888 [W] [proc.go:203]  [test] end-cost Seconds:1.45e-07s 

使用以上的defer形式来计算耗时确实有问题。后来找到原因了：defer 调用的函数参数的值 defer 被定义时就确定了。所以耗时参数在defer定义的那块就确定了，所以耗时就很少了。计算耗时的方法可以改成这样：

logsParam := "[test]"
startTime := time.Now()
defer func(time1 time.Time) {
    logs.Warning("%s end-cost Seconds:%v\n", logsParam, time.Since(time1))
}(startTime)
time.Sleep(2 * time.Second)

logs.Warning("%s end-cost-2 Seconds:%vs ", logsParam, time.Since(startTime).Seconds())

输出：

2020/08/21 15:27:31.594 [W] [proc.go:203]  [test] end-cost-2 Seconds:2.000604603s 
2020/08/21 15:27:31.594 [W] [main.go:58]  [test] end-cost Seconds:2.000729234s

圆满完成~~~

原文作者：TTWP_8

原文链接：https://www.jianshu.com/p/d3b7eac81679