JVM的GC日志及运行参数解读
正文
{Heap before GC invocations=35866 (full 34):
par new generation total 290176K, used 287809K [0x00000000d4400000, 0x00000000e7000000, 0x00000000e7000000)
eden space 273152K, 100% used [0x00000000d4400000, 0x00000000e4ec0000, 0x00000000e4ec0000)
from space 17024K, 86% used [0x00000000e4ec0000, 0x00000000e5d10770, 0x00000000e5f60000)
to space 17024K, 0% used [0x00000000e5f60000, 0x00000000e5f60000, 0x00000000e7000000)
concurrent mark-sweep generation total 409600K, used 159259K [0x00000000e7000000, 0x0000000100000000, 0x0000000100000000)
Metaspace used 137146K, capacity 146452K, committed 147072K, reserved 378880K
class space used 15581K, capacity 17238K, committed 17536K, reserved 247808K
2023-09-02T01:18:40.811+0000: 142176.656: [GC (Allocation Failure) 2023-09-02T01:18:40.811+0000: 142176.657: [ParNew: 287809K->13868K(290176K), 0.0142053 secs] 447069K->173146K(699776K), 0.0144550 secs] [Times: user=0.01 sys=0.00, real=0.01 secs]
Heap after GC invocations=35867 (full 34):
par new generation total 290176K, used 13868K [0x00000000d4400000, 0x00000000e7000000, 0x00000000e7000000)
eden space 273152K, 0% used [0x00000000d4400000, 0x00000000d4400000, 0x00000000e4ec0000)
from space 17024K, 81% used [0x00000000e5f60000, 0x00000000e6ceb260, 0x00000000e7000000)
to space 17024K, 0% used [0x00000000e4ec0000, 0x00000000e4ec0000, 0x00000000e5f60000)
concurrent mark-sweep generation total 409600K, used 159277K [0x00000000e7000000, 0x0000000100000000, 0x0000000100000000)
Metaspace used 137146K, capacity 146452K, committed 147072K, reserved 378880K
class space used 15581K, capacity 17238K, committed 17536K, reserved 247808K
}一段GC日志
JVM参数为：-Xms700m -Xmx700m -Xmn300m -XX:MetaspaceSize=250m -XX:MaxMetaspaceSize=250m -XX:SurvivorRatio=16
如上是一段GC日志，先来解读这一行：
2023-09-02T01:18:40.811+0000: 142176.656: [GC (Allocation Failure) 2023-09-02T01:18:40.811+0000: 142176.657: [ParNew: 287809K->13868K(290176K), 0.0142053 secs] 447069K->173146K(699776K), 0.0144550 secs] [Times: user=0.01 sys=0.00, real=0.01 secs]
2023-09-02T01:18:40.811+0000：表示的是一个日期格式
142176.656：系统运行142176.656秒的时候发生。
GC：表明进行一次垃圾回收，前面没有Full修饰，表明这是一次Minor GC。注意它不是表示只GC新生代。
(Allocation Failure)：表示本次引起GC的原因是因为在年轻代中没有足够的空间能够存储新的数据了。
ParNew：表明本次GC发生在年轻代并且使用的是ParNew垃圾收集器。ParNew是一个Serial收集器的多线程版本，会使用多个CPU和线程完成垃圾收集工作（默认使用的线程数和CPU数相同，可以使用-XX: ParallelGCThreads参数限制）。该收集器采用复制算法回收内存，期间会停止其他工作线程，即Stop The World。
287809K->13868K(290176K)：单位是KB
三个参数分别为：GC前该内存区域（这里是年轻代）使用容量，GC后该内存区域使用容量，该内存区域的总容量（Eden区 + 1个Survivor区）。
0.0142053 secs：该内存区域GC耗时，单位是秒。
447069K->173146K(699776K)：三个参数分别为：堆区垃圾回收前的大小，堆区垃圾回收后的大小，堆区总大小。
0.0144550 secs：该内存区域GC耗时，单位是秒。
[Times: user=0.01 sys=0.00, real=0.01 secs]：分别表示用户态耗时，内核态耗时和总耗时。
其他日志解读
如果设置了 PrintHeapAtGC 参数，则 HotSpot 在 GC 前后都会将 GC 堆的概要信息输出出来。
Heap before GC 和 Heap after GC 分别表示 GC 前后堆的信息的开始，invocations 表示 GC 的次数，可以看到 后面跟了个 invocations，这里 invocations 表示总的 GC 次数，可以发现在 after 之后，invocations 自增了，而 full 表示第几次 Full GC。
invocations 会随着系统运行一直自增下去，通过这些信息可以很轻松的统计出一段时间的 GC 次数。
Heap before GC invocations=35866 (full 34): par new generation total 290176K, used 287809K [0x00000000d4400000, 0x00000000e7000000, 0x00000000e7000000) eden space 273152K, 100% used [0x00000000d4400000, 0x00000000e4ec0000, 0x00000000e4ec0000) from space 17024K, 86% used [0x00000000e4ec0000, 0x00000000e5d10770, 0x00000000e5f60000) to space 17024K, 0% used [0x00000000e5f60000, 0x00000000e5f60000, 0x00000000e7000000) Heap after GC invocations=35867 (full 34): par new generation total 290176K, used 13868K [0x00000000d4400000, 0x00000000e7000000, 0x00000000e7000000) eden space 273152K, 0% used [0x00000000d4400000, 0x00000000d4400000, 0x00000000e4ec0000) from space 17024K, 81% used [0x00000000e5f60000, 0x00000000e6ceb260, 0x00000000e7000000) to space 17024K, 0% used [0x00000000e4ec0000, 0x00000000e4ec0000, 0x00000000e5f60000)
再看下面的日志，可以看到年轻代和老年代所使用的垃圾回收器，以及各自的情况。
新生代 par new generation 表示使用 ParNew 作为垃圾回收器，一共 290176 K 大小，使用了 287809 K 大小。其中 eden 区已经满了，from survivor 用了 86%，to survivor 用了 0%，每个后面都跟了内存地址，头一个表示起始地址，第二个表示当前用到的最大地址，第三个表示终止地址。
观察 before 和 after，细心点可以观察到 from 和 to 的地址对调了。
紧跟着 par new generation 后面的是 concurrent mark-sweep generation，总共的量409600K，使用的量159277K，地址可以清楚的看到，后面跟着的三个参数同样是起止地址，而第二个和第三个是相同的。
此外还给出了 Metaspace 的使用情况，以及 class space 的使用情况。这两个值初始会比较小，在使用过程中会容量会逐步扩大。
- used：加载的类的空间量。
- capacity： 当前分配块的元数据的空间。
- committed： 空间块的数量。
- reserved：元数据的空间保留（但不一定提交）的量。
以上就是JVM的GC日志及运行参数解读的详细内容，更多关于JVM GC日志解读的资料请关注脚本之家其它相关文章！
相关文章
这篇文章主要介绍了Spring Security 安全框架应用,本文给大家介绍的非常详细，对大家的学习或工作具有一定的参考借鉴价值，需要的朋友可以参考下2021-07-07
本文详细讲解了基于Java的Scoket编程，文中通过示例代码介绍的非常详细。对大家的学习或工作具有一定的参考借鉴价值，需要的朋友可以参考下2021-12-12
这篇文章主要介绍了IO密集型任务设置线程池线程数实现方式,具有很好的参考价值,希望对大家有所帮助,如有错误或未考虑完全的地方,望不吝赐教2024-07-07
通过Java Reflection实现编译时注解正确处理方法
Java注解是一种标记在JDK5及以后的版本中引入，用于Java语言中向程序添加元数据的方法，这篇文章主要介绍了通过Java Reflection实现编译时注解处理方法,需要的朋友可以参考下2023-06-06
这篇文章主要介绍了webuploader 实现图片批量上传功能，非常不错，具有参考借鉴价值,需要的朋友可以参考下2017-11-11
Spring Boot小型项目如何使用异步任务管理器实现不同业务间的解耦
这篇文章主要介绍了Spring Boot小型项目如何使用异步任务管理器实现不同业务间的解耦，文章围绕主题展开详细的内容介绍，具有一定的参考价值，需要的小伙伴可以参考一下2022-08-08
详解spring-boot集成elasticsearch及其简单应用
本篇文章主要介绍了详解spring-boot集成elasticsearch及其简单应用，具有一定的参考价值，感兴趣的小伙伴们可以参考一下2017-06-06
浅析 ArrayList 和 LinkedList 有什么区别
ArrayList 和 LinkedList 有什么区别，是面试官非常喜欢问的一个问题。今天通过本文给大家详细介绍下，感兴趣的朋友跟随小编一起看看吧2020-10-10
这篇文章主要介绍了Java新API的时间格式化，新的时间API的时间格式化由java.time.format.DateTimeFormatter负责，更多相关资料需要的小伙伴可以参考一下2022-05-05
这篇文章主要介绍了JAVA线程池原理,结合实例形式详细分析了java线程池概念、原理、创建、使用方法及相关注意事项,需要的朋友可以参考下2019-03-03
最新评论