JVM学习笔记之GC日志分析

最新推荐文章于 2024-09-19 17:51:37 发布
最新推荐文章于 2024-09-19 17:51:37 发布
阅读量1k 收藏 4
点赞数
分类专栏： JVM
版权声明：本文为博主原创文章，遵循 CC 4.0 BY-SA 版权协议，转载请附上原文出处链接和本声明。
本文链接：https://blog.csdn.net/qq_37475168/article/details/115563628
版权

目录

背景

GC日志参数

常用参数

其他参数

GC日志格式

日志分类

Minor GC

Full GC

Minor GC日志解析

Full GC日志解析

GC日志分析工具

GCEasy

其他

结语

背景

这是JVM学习笔记的最后一篇文章，分析一下GC日志，在JVM学习笔记之堆也分析了一次日志，但这次更加详细，不过如果对JVM堆的结构(年轻代、伊甸园区、老年代、元空间等)不熟悉的话，还是建议看看那篇文章。关于java的垃圾回收和垃圾回收器，请参见文章JVM学习之垃圾回收和垃圾回收器

GC日志参数

在文章JVM学习笔记之运行时参数中提到了一些和GC相关的参数，这里摘录如下：

常用参数

-verbose:gc输出gc日志信息，默认到标准输出

-XX:+PrintGC表示打开简化的GC日志，和上面的指令等同

-XX:+PrintGCDetails在发生垃圾回收时打印详细的GC日志，并在进程退出时输出当前内存各区域的分配情况

-XX:+PrintGCTimeStamps输出GC发生的时间戳，需要和-XX:+PrintGCDetails配合使用

-XX:+PrintGCDateStamps输出GC发生的时间戳，以日期的格式，需要和-XX:+PrintGCDetails配合使用

-XX:+PrintHeapAtGC在每一次的GC前和后，都打印堆信息，可以和-XX:+PrintGCDetails、-XX:+PrintGCDateStamps、-XX:+PrintGCTimeStamps配合使用

-Xloggc:<file>把GC日志写入到一个文件中

其他参数

-XX:+TraceClassLoading监控类的加载

-XX:+PrintGCApplicationStoppedTime打印GC时线程停顿时间

-XX:+PrintGCApplicationConcurrentTime垃圾收集之前打印打印应用未中断的执行时间

-XX:+PrintReferenceGC记录回收了多少种不同类型的引用

-XX:+PrintTenuringDistribution每次MinorGC后打印出当前使用的幸存者区中对象的年龄分布

-XX:+UseGCLogFileRotation启用GC日志文件的自动转储

-XX:NumberOfGCLogFiles=1控制GC日志文件的循环数量

-XX:GCLogFileSize=1M控制GC日志文件大小为1m

GC日志格式

日志分类

Minor GC

示例日志如下：格式为[GC(GC原因) [年轻代: 回收前大小->回收后大小(年轻代总大小)] 回收前java堆大小->回收后java堆大小(java堆总大小), GC用时] [Times: MinorGC用户耗时, MinorGC系统耗时, MinorGC实际耗时]

[GC (Allocation Failure) [PSYoungGen: 15620K->2040K(18432K)] 15620K->13630K(59392K), 0.0033045 secs] [Times: user=0.08 sys=0.08, real=0.05 secs]

三个时间的说明：

    user: 执行用户态代码的时间，也就是执行GC的CPU时间

    sys: 在内核态消耗的时间，也就是在内核执行系统调用或者等待系统事件所使用的CPU时间

    real: 程序从开始到结束用的时间，也就是GC的时间，等于(或接近于)(用户时间 + 系统时间) / GC线程数；如果real > user + sys，则说明可能IO负载过重或者CPU不够用

Full GC

示例日志如下，格式为[GC(GC原因) [年轻代: 回收前大小->回收后大小(年轻代总大小)] [老年代: 回收前大小->回收后大小(老年代总大小)] 回收前java堆大小->回收后java堆大小(java堆总大小), [元空间: 回收前大小->回收后大小(元空间总大小)] GC用时] [Times: MinorGC用户耗时, MinorGC系统耗时, MinorGC实际耗时]

[Full GC (Ergonomics) [PSYoungGen: 1730K->0K(18432K)] [ParOldGen: 38565K->40005K(40960K)] 40295K->40005K(59392K), [Metaspace: 3746K->3746K(1056768K)], 0.0115976 secs] [Times: user=0.14 sys=0.02, real=0.01 secs]

Minor GC日志解析

以这一行日志为例：

2021-02-05T19:40:48.862+0800: [GC (Allocation Failure) [PSYoungGen: 13922K->2035K(18432K)] 13922K->11752K(59392K), 0.0719394 secs] [Times: user=0.00 sys=0.00, real=0.07 secs]

2021-02-05T19:40:48.862+0800表示GC发生的时间，GC表示发生了GC(Minor GC)，Allocation Failure表示GC发生的原因——内存空间不够导致的分配失败，PSYoungGen表示年轻代发生的是并行GC，13922K->2035K(18432K)表示此区域GC前占13922K，GC后是2035K，总大小为18432K(伊甸园 + 一个幸存者区)，13922K->11752K(59392K)表示堆空间GC前大小为13922K，GC后为11752K，总大小为59392K(老年代 + 9 * 年轻代 / 10)，年轻代少了11887K，java堆只少了2170K，说明有相当一部分对象(9617K)到了老年代；0.0719394 secs表示此次GC用了0.0719394秒，user=0.00 sys=0.00, real=0.07 secs分别表示用户态时间0s，内核态0s，总时间0.07s

Full GC日志解析

以这一行日志为例：

2021-02-05T19:40:57.474+0800: [Full GC (Allocation Failure) [PSYoungGen: 14572K->14572K(18432K)] [ParOldGen: 40864K->40810K(40960K)] 55436K->55382K(59392K), [Metaspace: 3745K->3745K(1056768K)], 0.0112977 secs] [Times: user=0.16 sys=0.00, real=0.01 secs]

2021-02-05T19:40:57.474+0800表示GC发生的时间，Full GC表示发生了Full GC，Allocation Failure表示GC发生的原因——内存空间不够导致的分配失败，以年轻代部分为例：PSYoungGen表示年轻代发生的是并行GC，14572K->14572K(18432K)表示此区域GC前占14572K，GC后也是14572K(这是OOM前最后一次GC，所以没有回收什么东西)，总大小为18432K(伊甸园 + 一个幸存者区)，55436K->55382K(59392K)表示堆空间GC前大小为55436K，GC后为55382K，总大小为59392K(老年代 + 9 * 年轻代 / 10)；0.0112977 secs表示此次GC用了0.0112977秒，user=0.16 sys=0.00, real=0.01 secs分别表示用户态时间0.16s，内核态0s，总时间0.01s

GC日志分析工具

GCEasy

这是一款在线的GC日志分析工具，地址：https://gceasy.io/，在主界面中上传我们的日志文件(获取方式参见文章JVM学习笔记之命令行监控工具的jmap命令)，然后点击Analyze即可：

完成后就到了结果页面：

比如有内存大小，年轻代、老年代、元空间、总大小，这里的年轻代还是只包括伊甸园区 + 一个幸存者区，各个分区的总大小和峰值占用值：

吞吐率、延迟，可见Parallel回收器在高吞吐、低延迟方面做得不错;

交互图，三角表示GC发生的事件

里面年轻代标签的内容如下：

老年代内容如下，明显可以看到发生一次Minor GC后，很多对象都从年轻代跑到了老年代：

下面是一些统计信息：

其他

GCViewer是一款离线、免费开源的GC日志分析器，可以从github上直接下载，当成jar包运行

GChisto也是GC日志分析工具，需要从svn上下载，但已经没人维护了

惠普机器上的日志文件可以用HPjmeter分析

结语

JVM学习笔记到此结束，有问题欢迎在评论区讨论

coder_szc
关注
专栏目录
【深入理解JVM】详解GC日志参数配置及日志分析
m0_64205605的博客
11-20 925
GCTest类的执行时，JVM参数配置： -Xms1m -Xmx1m -XX:+PrintGCDetails -XX:+PrintGCTimeStamps -XX:+PrintGCDateStamps 执行后控制台输出如下图，准备下一步详细分析GC日志。 二、GC日志参数配置 1、堆空间参数 -Xms：初始堆大小 默认物理内存的1/64(小于1GB)空余堆大小小于40%时，JVM就会增大堆直到-Xmx的最大限制 -Xmx：最大堆大小 默认物理内存的1/4(小于1GB)空余堆大小大于70%时，J
linux 输出gc日志,Tomcat输出保存JVM GC日志文件
weixin_39688170的博客
05-01 935
当系统出现问题时，分析java虚拟机GC日志可以帮助我们定位问题，一般来说，我们可以通过制定JVM参数使tomcat保存GC日志文件，具体实现如下：Windows下找到tomcat的解压目录，进入bin文件夹，修改catalina.bat，在第一行加入如下代码set "JAVA_OPTS=%JAVA_OPTS% -XX:+PrintGCDetails -XX:+PrintGCTimeStamps ...
JVM实战：JVM常用参数配置
dumuzhouguohe的博客
04-19 1260
JVM
快速解读GC日志
weixin_33912445的博客
10-18 597
本文是 Plumbr 发行的 Java垃圾收集指南 的部分内容。文中将介绍GC日志的输出格式, 以及如何解读GC日志, 从中提取有用的信息。我们通过 -XX:+UseSerialGC 选项,指定JVM使用串行垃圾收集器, 并使用下面的启动参数让 JVM 打印出详细的GC日志: -XX:+PrintGCDetails -XX:+Prin...
ELK日志分析系统
最新发布
A6985HG的博客
09-19 924
①、FilebeatFilebeat：轻量级的开源日志文件数据搜集器。通常在需要采集数据的客户端安装 Filebeat，并指定目录与日志格式，Filebeat 就能快速收集数据，并发送给 logstash 进或是直接发给Elasticsearch 存储，性能上相比运行于 JVM 上的 logstash 优势明显，是对它的替代。常应用于 EFLK 架构当中。通过 Logstash 具有基于磁盘的自适应缓冲系统，该系统将吸收传入的吞吐量，从而减轻 Elasticsearch 持续写入数据的压力。
GC日志解读
huang211630的专栏
06-26 463
GC日志根据不同的JDK有不同的日志格式，但是总得来说都是为了反馈GC前的内存使用量和GC后的内存使用量，GC所耗时的时间等，例如解读如下GC日志中的一部分，表示了普通GC和完全GC两次内存垃圾回收信息：普通GC日志：26.701:[GC 26.702:[DefNew:9073k->488k(9088k),0.0026632secs]29892k->21886k(130112k),0.0027223
分析GC日志解读
一个菜鸡的博客
04-29 1183
real time是小于sys time＋user time的，因为一般是多个线程并发的去做GC，所以real time是要小于sys＋user time的。GCEasy是一款在线的GC日志分析器，可以通过GC日志分析进行内存泄露检测、GC暂停原因分析、JVM配置建议优化等功能，大多数功能是免费的。通过图示，我们可以发现GC日志格式的规律一般都是：GC前内存占用-＞GC后内存占用（该区域内存总大小）一种是部分收集（Partial GC），一种是整堆收集（Full GC）
JVM之GC日志解读
一个菜鸡的博客
04-13 1709
一文带你GC日志怎么看，怎么使用参数输出GC日志
JVM学习笔记
04-28
### JVM学习笔记 #### JVM内存模型 (JMM) JVM内存模型主要分为以下几个部分： - **Java堆**：这是所有线程共享的一块区域，在虚拟机启动时创建。主要用于存放对象实例，几乎所有的对象实例都在这里分配内存。 - *...
jvm-study:jvm学习笔记
03-08
本学习笔记旨在全面解析JVM的工作原理，涵盖内存管理、类加载机制、垃圾收集、性能调优等多个关键领域，帮助读者从基础到深入地掌握JVM。 1. **JVM结构与运行过程** - JVM由类装载器、运行时数据区、执行引擎、...
JVM学习资料+笔记
06-30
4. GC日志分析：通过设置JVM参数输出GC日志，然后使用专门工具（如GcViewer）分析垃圾收集的效率和问题。 三、JVM优化 1. 参数调整：通过设置JVM启动参数，如-Xms、-Xmx控制堆大小，-XX:+UseG1GC选择垃圾收集器，-...
GC日志解读，这次别再说看不懂GC日志了
09-15 4630
测试环境：机器内存16G，JDK 8，12核CPU 测试用例： import java.util.Random; import java.util.concurrent.TimeUnit; import java.util.concurrent.atomic.LongAdder; /* 演示GC日志生成与解读 */ public class GCLogAnalysis { private static Random random = new Random(); public static.
GC日志分析
qq_38730338的博客
01-04 1864
GC 日志分析算是 JVM 调优中比较难的部分，今天这篇文章就来聊聊如何利用 JDK 现有的命令并且借助可视化工具如何去分析 GC 日志。
读懂 GC 日志
灵动的艺术的博客
08-26 1464
读懂 GC 日志安全点GC 打印控制可管理的 JVM 参数查看 JVM 参数调整 JVM 参数查看所有支持动态修改的 JVM 参数垃圾回收器日志解读CMS 日志解读JVM 配置示例 这个课题拖了很久了，可以说读懂 GC 日志对于一个 java 后端开发来说是必须的事情。然而读懂 GC 日志并非是一件容易的事情，首先要对 JVM 内存模型有基本的理解，熟悉常用的 JVM 垃圾回收器，其次要知晓每个参数能够产生的效果，再次要清楚不同的垃圾回收器的日志该如何去读。这些要求给 java 后端开发读懂 gc 日志带来
4种GC日志解读和分析
闫兵的博客
02-24 4590
GC日志解读和分析 如下是模拟内存溢出的Java代码 案例代码存在生产垃圾对象，当垃圾对象超过最大堆内存时，就造成内存溢出 import java.util.Random; import java.util.concurrent.TimeUnit; import java.util.concurrent.atomic.LongAdder; /* 演示GC日志生成与解读 */ public class GCLogAnalysis { private static Random random = n
GC日志详解[乐乐独记]
热门推荐
qq_41931364的博客
07-10 1万+
GC日志详解1、静态分析GC日志 1、静态分析GC日志 在程序中加上对应的参数生成GC日志在文件中 -Xloggc:./gc-%t.log -XX:+PrintGCDetails -XX:+PrintGCDateStamps -XX:+PrintGCTimeStamps -XX:+PrintGCCause -XX:+UseGCLogFileRotation -XX:NumberOfGCLogFiles=10 -XX:GCLogFileSize=100M 参数 含义 -Xloggc:./
【JVM】GC日志解读实例
魏菱延的博客
05-20 453
每一种收集器的日志形式都是由它们自身的实现所决定的，换而言之，每个收集器的日志格式都可以不一样。但虚拟机设计者为了方便用户阅读，将各个收集器的日志都维持一定的共性。例如以下GC日志片段： 2019-05-20T12:26:59.890+0800: 348146.272: [GC (Allocation Failure) 4197972K->2706428K(10781376K), 0.09...
JVM性能调优——GC日志分析
码说芯语的博客
04-13 3529
生成GC日志、Parallel垃圾收集器日志解析、G1垃圾收集器日志解析、CMS垃圾收集器日志解析、日志解析工具
评论 5
添加红包

请填写红包祝福语或标题

个

红包个数最小为10个

元

红包金额最低5元

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

抵扣说明：

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

余额充值