go gc信息如何查看

已于 2024-09-01 22:59:08 修改
阅读量865 收藏 18
点赞数 17
分类专栏： golang实战笔记 Golang 文章标签： golang 开发语言 gc
于 2024-08-24 11:34:02 首次发布
版权声明：本文为博主原创文章，遵循 CC 4.0 BY-SA 版权协议，转载请附上原文出处链接和本声明。
本文链接：https://blog.csdn.net/lanyang123456/article/details/141496975
版权

目录

在Go语言中，可以通过设置环境变量和使用运行时包中的调试功能来打印垃圾回收（GC）的信息。

1.几种常见方法

1.1.设置环境变量 GODEBUG打印GC日志

通过设置 GODEBUG 环境变量来启用GC的详细日志。
具体来说，设置 GODEBUG=gctrace=1 可以打印GC的详细信息。

示例
在运行Go程序时，设置环境变量：

GODEBUG=gctrace=1 go run your_program.go

或者在代码中设置环境变量：

package main

import (
    "os"
    "runtime"
)

func main() {
    // 设置环境变量
    os.Setenv("GODEBUG", "gctrace=1")

    // 触发GC以查看日志
    runtime.GC()

    // 你的程序逻辑
}

1.2.使用 debug.ReadGCStats

可以使用 runtime/debug 包中的 SetGCPercent 函数来调整GC的频率，并通过 PrintGCStats 函数打印GC的统计信息。
示例

package main

import (
    "fmt"
    "runtime/debug"
    "time"
)

func main() {
    // 设置GC的频率
    debug.SetGCPercent(100)

    // 启动一个goroutine定期打印GC统计信息
    go func() {
        var stats debug.GCStats
        for {
            time.Sleep(10 * time.Second)
            debug.ReadGCStats(&stats)
            fmt.Printf("GC Stats: %+v\n", stats)
        }
    }()

    // 模拟一些内存分配
    for i := 0; i < 10; i++ {
        _ = make([]byte, 10<<20) // 10 MB
        time.Sleep(1 * time.Second)
    }
}

output:

GC Stats: {
LastGC:2024-08-24 11:41:57.930373 +0800 CST 
NumGC:10 
PauseTotal:657.732µs 
Pause:[38.41µs 79.84µs 41.56µs 44.338µs 45.675µs 75.914µs 98.979µs 94.937µs 107.504µs 30.575µs] PauseEnd:[2024-08-24 11:41:57.930373 +0800 CST 2024-08-24 11:41:56.928724 +0800 CST 2024-08-24 11:41:55.926835 +0800 CST 2024-08-24 11:41:54.924951 +0800 CST 2024-08-24 11:41:53.922546 +0800 CST 2024-08-24 11:41:52.920353 +0800 CST 2024-08-24 11:41:51.917256 +0800 CST 2024-08-24 11:41:50.914207 +0800 CST 2024-08-24 11:41:49.912043 +0800 CST 2024-08-24 11:41:48.90515 +0800 CST] 
PauseQuantiles:[]}

1.3.使用 runtime.ReadMemStats

可以使用 runtime 包中的 ReadMemStats 函数来获取GC的统计信息，并手动打印这些信息。
示例

package main

import (
    "fmt"
    "runtime"
    "time"
)

func main() {
    // 启动一个goroutine定期打印GC统计信息
    go func() {
        var m runtime.MemStats
        for {
            time.Sleep(10 * time.Second)
            runtime.ReadMemStats(&m)
            fmt.Printf("Alloc = %v MiB", m.Alloc/1024/1024)
            fmt.Printf("\tTotalAlloc = %v MiB", m.TotalAlloc/1024/1024)
            fmt.Printf("\tSys = %v MiB", m.Sys/1024/1024)
            fmt.Printf("\tNumGC = %v\n", m.NumGC)
        }
    }()

    // 模拟一些内存分配
    for i := 0; i < 10; i++ {
        _ = make([]byte, 10<<20) // 10 MB
        time.Sleep(1 * time.Second)
    }
}

output:

Alloc = 0 MiB	TotalAlloc = 100 MiB	Sys = 20 MiB	NumGC = 10

小结

  • 设置环境变量 GODEBUG：通过设置 GODEBUG=gctrace=1 可以启用GC的详细日志。
  • 使用 runtime/debug 包：可以使用 SetGCPercent 和 ReadGCStats 函数来调整GC频率和打印GC统计信息。
  • 使用 runtime 包：可以使用 ReadMemStats 函数来获取和打印GC的统计信息。

通过这些方法，你可以方便地获取和打印GC的日志信息，以便进行性能调优和问题排查。

2.GC日志打印示例

以下是一个完整的示例，展示了如何在运行Go程序时通过设置环境变量来打印垃圾回收（GC）日志。这个示例程序还会模拟一些内存分配，以便触发垃圾回收。

示例代码
首先，创建一个Go程序文件，例如 main.go，并将以下代码粘贴到文件中：

package main

import (
    "fmt"
    "runtime"
    "time"
)

func main() {
    // 启动一个goroutine定期打印GC统计信息
    go func() {
        var m runtime.MemStats
        for {
            time.Sleep(10 * time.Second)
            runtime.ReadMemStats(&m)
            fmt.Printf("Alloc = %v MiB", m.Alloc/1024/1024)
            fmt.Printf("\tTotalAlloc = %v MiB", m.TotalAlloc/1024/1024)
            fmt.Printf("\tSys = %v MiB", m.Sys/1024/1024)
            fmt.Printf("\tNumGC = %v\n", m.NumGC)
        }
    }()

    // 模拟一些内存分配以触发GC
    for i := 0; i < 10; i++ {
        _ = make([]byte, 10<<20) // 10 MB
        time.Sleep(1 * time.Second)
    }
}

运行示例
在终端中运行以下命令来设置环境变量并运行Go程序：

GODEBUG=gctrace=1 go run main.go

解释

  • 设置环境变量：通过在运行程序时设置 GODEBUG=gctrace=1 环境变量，启用GC的详细日志。
  • 定期打印GC统计信息：程序启动一个goroutine，每10秒钟打印一次GC的统计信息，包括已分配的内存、总分配的内存、系统内存和GC的次数。
  • 模拟内存分配：在主goroutine中，每秒钟分配10MB的内存，共分配10次，以触发垃圾回收。

GC日志示例
运行程序后，会在终端中看到类似以下的GC日志输出：


gc 1 @0.065s 0%: 0.010+2.7+0.003 ms clock, 0.10+0.16/2.9/2.1+0.034 ms cpu, 4->4->0 MB, 5 MB goal, 10 P
gc 2 @0.125s 0%: 0.027+0.47+0.003 ms clock, 0.27+0.097/1.0/0.43+0.033 ms cpu, 4->4->0 MB, 5 MB goal, 10 P
gc 3 @0.180s 0%: 0.031+1.0+0.10 ms clock, 0.31+0.75/0.80/1.0+1.0 ms cpu, 4->4->0 MB, 5 MB goal, 10 P
gc 4 @0.399s 1%: 0.76+2.6+4.3 ms clock, 7.6+2.0/1.8/0+43 ms cpu, 4->4->1 MB, 5 MB goal, 10 P
gc 5 @0.477s 1%: 0.25+2.6+0.004 ms clock, 2.5+0.38/1.0/2.0+0.042 ms cpu, 4->4->1 MB, 5 MB goal, 10 P
gc 6 @0.491s 1%: 0.092+9.8+0.003 ms clock, 0.92+0/10/1.9+0.033 ms cpu, 4->4->0 MB, 5 MB goal, 10 P
gc 7 @0.518s 1%: 0.093+0.62+0.004 ms clock, 0.93+0.19/1.0/0.23+0.049 ms cpu, 4->4->0 MB, 5 MB goal, 10 P
gc 8 @0.528s 1%: 0.043+0.98+0.004 ms clock, 0.43+0.13/0.95/0.62+0.047 ms cpu, 4->4->1 MB, 5 MB goal, 10 P
# command-line-arguments
gc 1 @0.018s 1%: 0.008+1.8+0.025 ms clock, 0.083+0.13/2.6/1.9+0.25 ms cpu, 4->4->2 MB, 5 MB goal, 10 P
gc 2 @0.033s 1%: 0.011+1.0+0.046 ms clock, 0.11+0.58/1.7/2.6+0.46 ms cpu, 5->5->4 MB, 6 MB goal, 10 P
# command-line-arguments
gc 1 @0.003s 8%: 0.028+4.2+0.059 ms clock, 0.28+0.16/5.9/2.8+0.59 ms cpu, 4->6->5 MB, 5 MB goal, 10 P
gc 2 @0.024s 4%: 0.007+3.0+0.069 ms clock, 0.076+0.042/3.1/1.9+0.69 ms cpu, 9->9->8 MB, 10 MB goal, 10 P
gc 3 @0.134s 1%: 0.019+1.7+0.065 ms clock, 0.19+0.98/2.3/2.0+0.65 ms cpu, 16->16->9 MB, 17 MB goal, 10 P
gc 9 @1.225s 0%: 0.26+1.0+0.19 ms clock, 2.6+0.40/1.2/1.0+1.9 ms cpu, 4->4->0 MB, 5 MB goal, 10 P
gc 1 @0.001s 6%: 0.036+1.3+0.064 ms clock, 0.36+0/0.77/1.1+0.64 ms cpu, 10->10->10 MB, 11 MB goal, 10 P
gc 2 @1.004s 0%: 0.072+1.2+0.023 ms clock, 0.72+0/1.3/0+0.23 ms cpu, 20->20->0 MB, 21 MB goal, 10 P
gc 3 @2.011s 0%: 0.036+0.12+0.003 ms clock, 0.36+0/0.19/0.031+0.035 ms cpu, 10->10->0 MB, 11 MB goal, 10 P
gc 4 @3.015s 0%: 0.11+0.78+0.010 ms clock, 1.1+0/1.0/0.007+0.10 ms cpu, 10->10->0 MB, 11 MB goal, 10 P
gc 5 @4.020s 0%: 0.13+1.1+0.031 ms clock, 1.3+0/0.38/0+0.31 ms cpu, 10->10->0 MB, 11 MB goal, 10 P
gc 6 @5.022s 0%: 0.10+0.54+0.007 ms clock, 1.0+0/0.65/0+0.079 ms cpu, 10->10->0 MB, 11 MB goal, 10 P
gc 7 @6.024s 0%: 0.13+0.65+0.008 ms clock, 1.3+0/0.75/0+0.081 ms cpu, 10->10->0 MB, 11 MB goal, 10 P
gc 8 @7.027s 0%: 0.094+0.73+0.008 ms clock, 0.94+0/0.83/0+0.082 ms cpu, 10->10->0 MB, 11 MB goal, 10 P
gc 9 @8.029s 0%: 0.14+0.68+0.008 ms clock, 1.4+0/0.84/0+0.081 ms cpu, 10->10->0 MB, 11 MB goal, 10 P
gc 10 @9.031s 0%: 0.076+0.43+0.008 ms clock, 0.76+0/0.59/0+0.084 ms cpu, 10->10->0 MB, 11 MB goal, 10 P
Alloc = 0 MiB	TotalAlloc = 100 MiB	Sys = 32 MiB	NumGC = 10

这些GC日志是Go运行时在垃圾回收过程中生成的详细信息。每一行日志记录了一个GC周期的相关信息。

以下是对这些日志的详细解释：
每一行GC日志的格式如下：

gc N @T S%: M1+M2+M3 ms clock, C1+C2/C3/C4+C5 ms cpu, A->B->C MB, D MB goal, P P

字段解释

  • gc N：表示这是第N次垃圾回收。
  • @T：表示垃圾回收发生的时间点，单位是秒（s），从程序启动开始计算。
  • S%：表示垃圾回收器在总CPU时间中所占的百分比。
  • M1+M2+M3 ms clock：表示垃圾回收的三个阶段（标记、清理、其他）所花费的总时间，单位是毫秒（ms）。
    • C1+C2/C3/C4+C5 ms cpu：表示垃圾回收的各个阶段在CPU上所花费的时间，单位是毫秒（ms）。
    • C1：STW（Stop-The-World）标记开始时间。
    • C2：并发标记时间。
    • C3：并发清理时间。
    • C4：并发清理结束时间。
    • C5：STW标记结束时间。
  • A->B->C MB：表示垃圾回收前、垃圾回收后和垃圾回收过程中分配的内存量，单位是MB。
  • D MB goal：表示垃圾回收的目标内存量，单位是MB。
  • P P：表示参与垃圾回收的处理器数量。

着重提下时钟时间和CPU时间:
在垃圾回收（GC）日志中，这两个时间可以提供不同的性能视角：

  • 时钟时间可以帮助我们了解GC操作对应用程序响应时间的影响，特别是在停顿时间敏感的应用中非常重要。
  • CPU时间可以帮助我们评估GC算法的效率，了解GC操作占用了多少CPU资源，从而对GC算法和配置进行优化。
时钟时间，也称为墙钟时间或实际时间（Wall-Clock Time），是指从某个操作开始到结束所经过的真实世界时间。它包括所有等待时间和执行时间，比如等待磁盘I/O、网络通信延迟、操作系统调度其他进程的时间等。在多任务操作系统中，程序可能会被操作系统挂起，以便其他程序也能运行。时钟时间会计算这期间的等待时间。

CPU时间是指CPU实际花费在程序执行上的时间，不包括等待时间，如I/O操作等待、进程被操作系统挂起的时间等。CPU时间可以进一步细分为用户态CPU时间和内核态CPU时间：
用户态CPU时间：程序在用户态执行的时间，不包括系统调用。
内核态CPU时间：程序因系统调用而在内核态执行的时间。

区别
- 度量范围：时钟时间包括程序执行的全部时间（包括CPU执行时间和等待时间），而CPU时间仅包括CPU处理指令的时间。
- 影响因素：时钟时间受到系统负载、I/O等待和外部资源等待的影响，而CPU时间主要受程序的计算复杂度影响。

用途：时钟时间用于衡量从用户角度看到的响应时间，适合用于性能评估和基准测试；CPU时间用于分析程序的计算效率，适合用于性能优化。

示例解释
以下是对示例日志的详细解释：

gc 1 @0.065s 0%: 0.010+2.7+0.003 ms clock, 0.10+0.16/2.9/2.1+0.034 ms cpu, 4->4->0 MB, 5 MB goal, 10 P
* gc 1：这是第1次垃圾回收。
* @0.065s：垃圾回收发生在程序启动后的0.065秒。
* **0%**：垃圾回收器在总CPU时间中所占的百分比为0%。
* 0.010+2.7+0.003 ms clock：垃圾回收的三个阶段（标记、清理、其他）所花费的总时间分别是0.010ms、2.7ms和0.003ms。
* 0.10+0.16/2.9/2.1+0.034 ms cpu：垃圾回收的各个阶段在CPU上所花费的时间分别是0.10ms、0.16ms、2.9ms、2.1ms和0.034ms。
* 4->4->0 MB：垃圾回收前、垃圾回收后和垃圾回收过程中分配的内存量分别是4MB、4MB和0MB。
* 5 MB goal：垃圾回收的目标内存量是5MB。
* 10 P：参与垃圾回收的处理器数量是10个。

3.参考

gc-guide
runtime

翔云123456
关注
专栏目录
golang GC机制
小二黑嘿嘿的博客
03-09 2277
Golang自1.5版本开始引入三色GC，多次改进，GC停顿时间降低到1ms。 一：GC的触发 GC的触发条件有以下几种： gcTriggerAlways：强制触发GC gcTriggerHeap：当前分配的内存达到一定值就触发GC gcTriggerTime：当一定时间没有执行过GC触发 gcTriggerCycle：要求启动新一轮的GC，已启动则跳过，手动触发GC的runtime.GC()会...
Go-gclog是一个go日志管理库
08-14
gclog是一个go日志管理库，基于go标准库的log创建，拥有动态变更级别、自动切分、自动删除过期日志等功能
如何查看gc日志
雪招在等开花
03-10 435
转载。
golang 如何排查和定位GC问题
weixin_34362875的博客
01-19 541
2019独角兽企业重金招聘Python工程师标准>>> ...
使用gops来排查golang进程gc问题
最新发布
Horsdy123的专栏
04-01 294
gops安装与使用
如何监控JVM GC
秋̶᭄ꦿ攻城狮࿆ྂ
10-17 1865
一般使用jstat来监控gc，如果感觉gc有问题，可以使用verbosegc参数。如果你使用springboot，可以使用springboot actuator报点 + prometheus收集 + grafana图形展示 方案。
golang的gc回收针对堆还是栈？变量内存分配在堆还是栈？
01-07
golang的垃圾回收是针对堆的（垃圾回收都是针对堆的，这里只是做一个简单的证明） 引用类型的全局变量内存分配在堆上，值类型的全局变量分配在栈上 局部变量内存分配可能在栈上也可能在堆上 堆和栈的简单说明： 1.栈...
浅谈 golang并发 GC
01-24
浅谈 Golang 并发 GC Golang 并发控制是指在 Golang 语言中，使用 Goroutine、Channel、WaitGroup 等机制来控制并发执行的过程。Golang 的并发控制机制可以确保多个 Goroutine 之间的数据安全和高效执行。 一、...
golang源码分析–gc
01-20
《深入剖析Go语言GC机制》 Go语言的垃圾回收（Garbage Collection，简称GC）机制是其内存管理的重要组成部分，它的设计目标是自动回收不再使用的内存，以避免内存泄漏，并确保程序高效运行。Go选择了经典的标记-...
Golang入门——GC机制
qq_23680847的博客
11-03 1347
Golang入门——GC机制常见GC算法引用计数标记清除分代收集Go的GCmark内存标记三色标记法STW（Stop The World）GC优化写屏障协助GCGC触发的时机内存分配量到达阈值触发GC定期触发GC手动触发GC 性能优化 常见GC算法 引用计数 每个对象维护一个计数器，当引用该对象的对象被销毁时，计数器减一，当计数器为0时回收该对象。 代表语言：Python，PHP，Swift 优点：对象可以很快被回收，不会出现内存耗尽再回收的现象。 缺点：不能很好的处理循环引用，而且维护计数器需要开销。 标
面试官：Go 如何查看 GC 信息？
木川的技术之路
10-06 453
大家好，我是木川一、GODEBUG='gctrace=1'packagemain funcmain(){ forn:=1;n<100000;n++{ _=make([]byte,1<<20) } }$GODEBUG='gctrace=1'gorunmain.go gc1@0.003s4%:0.013+1...
golang学习笔记-查看gc
知识就是生产力
04-17 3717
windows: 在cmd命令行下： 1.设置跟踪GC的临时环境变量 set GOGCTRACE=1 set GODEBUG=gctrace=1 2.将gc写入日志中 xxx.exe 2> gc.log 在生成的gc跟踪日志中查看gc的过程，下面是截取的一部分gc日志： gc 1 @0.005s 0%: 0+11+0.99 ms clock, 0+0/15/0+7.9 ms cpu, ...
记一次Golang的GC
weixin_41413372的博客
11-17 352
golang中GC 采用三色标记： 灰色：对象已被标记，但这个对象包含的子对象未标记 黑色：对象已被标记，且这个对象包含的子对象也已标记，gcmarkBits对应的位为1（该对象不会在本次GC中被清理） 白色：对象未被标记，gcmarkBits对应的位为0（该对象将会在本次GC中被清理） GC触发 阈值：默认内存扩大一倍，启动GC 定期：默认2min触发一次GC 手动：runtime.gc() GC执行两个操作
golang 打印gc信息
10-19 589
golang 打印gc信息 GODEBUG='gctrace=1' nohup ./app &
java GC 日志查看 gcviewer
热门推荐
nokiaisacat的专栏
08-04 1万+
要查看GC日志首先要知道GC的log放在哪里，使用jps命令查看当前有哪些java进程在运行，找到我们要查看的java程序的进程pid 使用命令jinfo pid 来查看这个进程对应的java 信息，可以看到大概在最下面的地方有个参数-Xloggc:，他对应的就是gc log的位置。
JVM GC原理及调优的基本思路
小楼一夜听春雨，深巷明朝卖杏花
12-29 576
和 Web 应用程序一样，Tomcat 作为一个 Java 程序也跑在 JVM 中，因此如果我们要对 Tomcat 进行调优，需要先了解 JVM 调优的原理。而对于 JVM 调优来说，主要是 JVM 垃圾收集的优化，一般来说是因为有问题才需要优化，所以对于 JVM GC 来说，如果你观察到 Tomcat 进程的 CPU 使用率比较高，并且在 GC 日志中发现 GC 次数比较频繁、GC 停顿时间长，这表明你需要对 GC 进行优化了。 在对 GC 调优的过程中，我们不仅需要知道 GC 的原理，更重要的...
JVM相关参数、命令等
第四空间
11-08 115
-Xmx 堆最大值，例子：-Xmx1g. -Xmx512m -Xms 堆最小值，例子：-Xmx1g. -Xs512m
golang垃圾回收gc
Yu的博客
08-09 427
垃圾回收概念 对不再使用的内存资源进行自动回收的行为。 出现的原因 内存管理是程序员开发应用的难题，传统的编程语言(c/c++)，程序员须小心进行管理操作，控制内存的申请及释放。稍有不慎会造成内存泄漏。 以往常用办法 1.内存泄漏检测工具。原理是静态代码扫描，通过扫描程序检测可能出现内存泄漏的代码段，然而检测工具难免有疏漏和不足，只能嗯起到辅助作用。 2.职能指针。c++中引入的自动内...
评论 1
添加红包

请填写红包祝福语或标题

个

红包个数最小为10个

元

红包金额最低5元

当前余额3.43元 前往充值 >
需支付：10.00元
成就一亿技术人!
领取后你会自动成为博主和红包主的粉丝 规则
hope_wisdom
发出的红包
实付元
使用余额支付
点击重新获取
扫码支付
钱包余额 0

抵扣说明：

1.余额是钱包充值的虚拟货币，按照1:1的比例进行支付金额的抵扣。
2.余额无法直接购买下载，可以购买VIP、付费专栏及课程。

余额充值