JVM学习笔记之GC日志分析

最新推荐文章于 2024-04-15 22:05:39 发布
最新推荐文章于 2024-04-15 22:05:39 发布
阅读量726 收藏 3
点赞数
分类专栏： 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
关注
  • 0
    点赞
  • 踩
  • 3
    收藏
    觉得还不错? 一键收藏
  • 5
    评论
专栏目录
JVM学习资料+笔记
06-30
4. GC日志分析：通过设置JVM参数输出GC日志，然后使用专门工具（如GcViewer）分析垃圾收集的效率和问题。 三、JVM优化 1. 参数调整：通过设置JVM启动参数，如-Xms、-Xmx控制堆大小，-XX:+UseG1GC选择垃圾收集器，-...
jvm-study:jvm学习笔记
03-08
本学习笔记旨在全面解析JVM的工作原理，涵盖内存管理、类加载机制、垃圾收集、性能调优等多个关键领域，帮助读者从基础到深入地掌握JVM。 1. **JVM结构与运行过程** - JVM由类装载器、运行时数据区、执行引擎、...
快速解读GC日志
weixin_33912445的博客
10-18 574
本文是 Plumbr 发行的 Java垃圾收集指南 的部分内容。文中将介绍GC日志的输出格式, 以及如何解读GC日志, 从中提取有用的信息。我们通过 -XX:+UseSerialGC 选项,指定JVM使用串行垃圾收集器, 并使用下面的启动参数让 JVM 打印出详细的GC日志: -XX:+PrintGCDetails -XX:+Prin...
GC日志解读
huang211630的专栏
06-26 432
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
日志分析篇
最新发布
WX公众号-小白学安全
04-15 846
Windows系统日志是记录系统中硬件、软件和系统问题的信息，同时还可以监视系统中发生的事件。用户可以通过它来检查错误发生的原因，或者寻找受到攻击时攻击者留下的痕迹。Windows主要有以下三类日志记录系统事件：应用程序日志、系统日志和安全日志。系统日志记录操作系统组件产生的事件，主要包括驱动程序、系统组件和应用软件的崩溃以及数据丢失错误等。系统日志中记录的时间类型由Windows NT/2000操作系统预先定义。
分析GC日志解读
一个菜鸡的博客
04-29 1056
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 1531
一文带你GC日志怎么看，怎么使用参数输出GC日志
JVM性能学习笔记思维导图
03-24
本文将根据"JVM性能学习笔记思维导图"的主题，详细阐述JVM的主要组成部分，性能调优的关键点以及相关的工具与实践策略。** 1. **JVM结构与内存模型** - **类装载器(ClassLoader)**：负责加载类文件，确保类在运行...
JVM和性能优化学习思维笔记_swim5we_jvm_性能优化_
10-03
8. **JVM内存溢出**：分析和处理常见的OOM（Out of Memory）错误，如堆内存溢出、 PermGen或Metaspace溢出，了解如何通过日志和工具诊断并解决这些问题。 9. **代码优化**：避免过度设计，减少不必要的计算，优化...
大数据笔记kafka-jvm.zip
10-26
《大数据笔记：深入理解Kafka...通过深入学习《大数据笔记：深入理解Kafka与JVM》，读者将不仅掌握Kafka的基本概念和操作，还能了解到JVM在大数据处理中的关键作用，为在实际工作中应对复杂的大数据挑战打下坚实基础。
GC日志解读，这次别再说看不懂GC日志了
09-15 4472
测试环境：机器内存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 1790
GC 日志分析算是 JVM 调优中比较难的部分，今天这篇文章就来聊聊如何利用 JDK 现有的命令并且借助可视化工具如何去分析 GC 日志。
读懂 GC 日志
灵动的艺术的博客
08-26 1239
读懂 GC 日志安全点GC 打印控制可管理的 JVM 参数查看 JVM 参数调整 JVM 参数查看所有支持动态修改的 JVM 参数垃圾回收器日志解读CMS 日志解读JVM 配置示例 这个课题拖了很久了，可以说读懂 GC 日志对于一个 java 后端开发来说是必须的事情。然而读懂 GC 日志并非是一件容易的事情，首先要对 JVM 内存模型有基本的理解，熟悉常用的 JVM 垃圾回收器，其次要知晓每个参数能够产生的效果，再次要清楚不同的垃圾回收器的日志该如何去读。这些要求给 java 后端开发读懂 gc 日志带来
4种GC日志解读和分析
闫兵的博客
02-24 4158
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 435
每一种收集器的日志形式都是由它们自身的实现所决定的，换而言之，每个收集器的日志格式都可以不一样。但虚拟机设计者为了方便用户阅读，将各个收集器的日志都维持一定的共性。例如以下GC日志片段： 2019-05-20T12:26:59.890+0800: 348146.272: [GC (Allocation Failure) 4197972K->2706428K(10781376K), 0.09...
【深入理解JVM】详解GC日志参数配置及日志分析
程序员云帆哥的博客
07-31 4434
一、首先模拟一个JVM堆内存不足的异常场景。 public class GCTest { public static void main(String[] args) { List<Object> d = new ArrayList<Object>(); while(true){ d.add(new GCTest()); } }...
使用HPjmeter分析GC log
weixin_33734785的博客
07-16 481
为什么80%的码农都做不了架构师？>>> ...
宋红康jvm学习笔记 逃逸分析
05-19
逃逸分析可以优化程序的性能，因为当对象不逃逸时，JVM可以将它们放在线程栈上分配内存，而不需要在堆上分配内存，这样可以减少GC的负担。 逃逸分析有两种类型：全局逃逸和本地逃逸。全局逃逸是指对象逃逸到方法...

“相关推荐”对你有帮助么？

  • 非常没帮助
  • 没帮助
  • 一般
  • 有帮助
  • 非常有帮助
提交
评论 5
添加红包

请填写红包祝福语或标题

个

红包个数最小为10个

元

红包金额最低5元

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

抵扣说明：

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

余额充值