go语言提供了强大的时间处理能力，结合defer关键字，可以方便地实现函数执行时间的测量。以下是一种常用的方法：
package main
import (
"log"
"time"
)
// trace 函数记录函数开始执行的时间
func trace(s string) (string, time.Time) {
log.Println("START:", s)
return s, time.Now()
}
// un 函数计算函数执行的耗时，并打印日志
func un(s string, startTime time.Time) {
endTime := time.Now()
elapsed := endTime.Sub(startTime)
log.Printf(" END: %s, Elapsed time: %v\n", s, elapsed)
log.Printf(" END: %s, Elapsed time in milliseconds: %dms\n", s, elapsed.Milliseconds())
}
// 示例函数
func someFunction() {
defer un(trace("someFunction")) // 使用defer调用un函数，确保在函数退出时执行
// 模拟一些耗时操作
time.Sleep(100 * time.Millisecond)
log.Println("Doing some work...")
time.Sleep(200 * time.Millisecond)
}
func main() {
someFunction()
}代码解释:
- trace(s string) (string, time.Time): 这个函数用于记录函数开始执行的时间。它接收一个字符串s作为函数名或标识符，打印一条日志信息，并返回该字符串和当前时间。
- un(s string, startTime time.Time): 这个函数用于计算函数执行的耗时。它接收函数名s和开始时间startTime作为参数，计算结束时间与开始时间的差值，并打印包含函数名和耗时的日志信息。这里使用 elapsed.Milliseconds() 将耗时转换为毫秒。
- someFunction(): 这是一个示例函数，用于演示如何使用trace和un函数来测量函数的执行时间。defer un(trace("someFunction"))这行代码是关键。trace("someFunction")会在someFunction函数开始时执行，记录开始时间。defer un(...)则保证了un函数会在someFunction函数执行完毕后执行，无论someFunction函数是否发生panic。
- main(): 主函数，调用someFunction()来执行示例。
运行结果示例:
2023/10/27 10:00:00 START: someFunction 2023/10/27 10:00:00 Doing some work... 2023/10/27 10:00:00 END: someFunction, Elapsed time: 300.000000ms 2023/10/27 10:00:00 END: someFunction, Elapsed time in milliseconds: 300ms
注意事项:
- 精度问题: 虽然代码中使用了time.Now()和time.Sub()来获取和计算时间，但是由于受到系统时钟的精度限制，测量结果可能存在一定的误差。如果需要更高的精度，可以考虑使用更高级的时间测量方法。
- 日志输出: 示例代码中使用了log.Println()和log.Printf()函数进行日志输出。这些日志输出操作本身也会消耗一定的时间，可能会对测量结果产生影响。如果需要非常精确的测量结果，可以考虑移除日志输出操作。
- 并发环境: 在并发环境中，多个goroutine可能会同时调用trace和un函数，导致日志输出混乱。为了解决这个问题，可以使用互斥锁来保护日志输出操作。
总结:
立即学习“go语言免费学习笔记（深入）”；
通过利用Go语言的defer关键字和time包，我们可以方便地实现函数执行时间的测量。这种方法简单易用，适用于大多数场景。但是，在需要高精度测量或并发环境下，需要注意精度问题和日志输出问题，并采取相应的措施来解决。 这种方案可以很方便的嵌入到你的代码中，用于性能分析和调试。 请注意，实际的函数执行时间会受到多种因素的影响，例如CPU负载、内存访问速度等。因此，多次运行并取平均值可以获得更准确的结果。
