【Java虚拟机】JVM日志分析和可视化工具实操

/**
 * 模拟OOM测试
 * @author lixiang
 * @date 2023/5/4 20:53
 */
public class JVMTest {
    public static void main(String[] args) throws InterruptedException {
        List<Object> objects = new ArrayList<>();
        while(true){
            objects.add(new Object());
        }
    }
}

-XX:+UseG1GC -XX:MaxGCPauseMillis=100 -Xms524m -Xmx524m -XX:+PrintCommandLineFlags -Xlog:gc:gc.log

-XX:+UseG1GC -XX:MaxGCPauseMillis=100 -Xms524m -Xmx524m -XX:+PrintCommandLineFlags  -Xlog:gc=debug,gc+metaspace:gc.log

-XX:+UseG1GC -XX:MaxGCPauseMillis=100 -Xms524m -Xmx524m -XX:+PrintCommandLineFlags  -Xlog:gc*=debug:gc.log
# gc*=debug：指定输出GC相关日志，级别为debug，*表示所有的GC标签都会输出日志。

-XX:+UseG1GC -XX:MaxGCPauseMillis=100 -Xms524m -Xmx524m -XX:+PrintCommandLineFlags -Xlog:gc=info:gc.log

日志内容
[24.575s][info][gc] GC(0) Pause Young (Concurrent Start) (G1 Humongous Allocation) 240M->238M(528M) 5.888ms
字段拆解
  [24.575s]：GC发生的时间戳，表示程序运行的时间
  [info]：日志级别，表示这是一条信息级别的日志
  [gc]：日志标签，表示这是一条GC相关的日志
  GC(0)：GC的编号，表示这是第一次GC
  Pause Young (Concurrent Start) (G1 Humongous Allocation)：
    GC的类型，表示这是一次Young GC，同时也是一次 Humongous Allocation的GC，其中Concurrent Start表示并发启动的GC。
  240M->238M(528M)：
    GC前后堆内存的使用情况，其中240M表示GC前的已使用内存，238M表示GC后的已使用内存，528M表示堆内存的总大小
  5.888ms：GC的耗时，表示这次GC的执行时间
  这条GC日志记录了程序运行了24.575秒时发生的一次Young GC，回收了2M的内存空间，耗时5.888毫秒
————————————————————————————————————————————————————————
日志内容
[24.783s][info][gc] GC(3) Concurrent Cycle
[24.784s][info][gc] GC(3) Pause Remark 242M->242M(528M) 0.383ms
[24.784s][info][gc] GC(3) Pause Cleanup 242M->242M(528M) 0.066ms
[24.785s][info][gc] GC(3) Concurrent Cycle 2.374ms
字段拆解
  [24.783s][info][gc] GC(3) Concurrent Cycle：
  Mixed GC的相关信息，表示这是一次Mixed GC的开始。
  [24.784s][info][gc] GC(3) Pause Remark 242M->242M(528M) 0.383ms
  Mixed GC的相关信息，表示这是一次Remark阶段的GC，回收了0M的内存空间，耗时0.383毫秒。
  [24.784s][info][gc] GC(3) Pause Cleanup 242M->242M(528M) 0.066ms：
  Mixed GC的相关信息，表示这是一次Cleanup阶段的GC，回收了0M的内存空间，耗时0.066毫秒。
  [24.785s][info][gc] GC(3) Concurrent Cycle 2.374ms：
  Mixed GC的相关信息，表示这是一次Mixed GC的结束，耗时2.374毫秒。
  这段GC日志记录了程序运行了24.783秒一次Mixed GC，Mixed GC回收了0M的内存空间，耗时2.374毫秒

-XX:+UseG1GC -XX:MaxGCPauseMillis=100 -Xms524m -Xmx524m -XX:+PrintCommandLineFlags -Xlog:gc*=info:file=portal_gc.log:utctime,level,tags:filecount=5,filesize=1M

-XX:+UseG1GC：使用G1垃圾回收器
-XX:MaxGCPauseMillis=100：设置最大垃圾回收暂停时间为100毫秒
-Xms524m：设置JVM堆的初始大小为524MB
-Xmx524m：设置JVM堆的最大大小为524MB
-XX:+PrintCommandLineFlags：打印JVM启动参数
-Xlog:gc*=info:file=portal_gc.log:utctime,level,tags:filecount=5,filesize=1M：
  Xlog：指定日志输出方式为日志文件。
  gc*：指定日志输出类型为GC相关的日志。
  info：指定输出日志的级别为info级别。
  file=portal_gc.log：指定日志输出的文件名为portal_gc.log。
  utctime：指定日志输出的时间戳使用UTC时间。
  level,tags：指定日志输出的格式包含级别和标签信息。
  filecount=5：指定最多保存5个日志文件。
  filesize=1M：指定每个日志文件的大小为1MB。

-XX:+UseG1GC -XX:MaxGCPauseMillis=100 -Xms524m -Xmx524m -XX:+PrintCommandLineFlags -Xlog:gc*=info:file=portal_gc.log:utctime,level,tags:filecount=5,filesize=1M -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath=heapdump.hprof

-server
-Xms8g
-Xmx8g
-XX:+UseG1GC
-XX:MaxGCPauseMillis=200
-XX:G1HeapRegionSize=32M
-XX:ActiveProcessorCount=8
-XX:+HeapDumpOnOutOfMemoryError
-XX:HeapDumpPath=heapdump.hprof
-XX:+PrintCommandLineFlags 
-Xlog:gc*=info:file=portal_gc.log:utctime,level,tags:filecount=50,filesize=100M
参数说明
  -Xms8g：指定JVM堆内存最小值为8G。
  -Xmx8g：指定JVM堆内存最大值为8G。
  -XX:+UseG1GC：指定使用G1垃圾收集器。
  -XX:MaxGCPauseMillis=200：指定最大垃圾回收暂停时间为200毫秒。
  -XX:G1HeapRegionSize=32M：指定G1垃圾收集器的堆区域大小为32MB。
  -XX:ActiveProcessorCount=8：指定并行垃圾回收器的线程数为8，在JDK 9及之后的版本中，ParallelGCThreads参数已被替代为-XX:ActiveProcessorCount参数，用于自动计算并行垃圾回收线程数
  -server：指定JVM使用服务器模式运行，优化性能。
  -XX:+HeapDumpOnOutOfMemoryError：指定在发生内存溢出时生成堆转储文件。
  -XX:HeapDumpPath=/var/log/heapdump.hprof：指定堆转储文件的路径。
  -Xlog:gc*=info:file=portal_gc.log:utctime,level,tags:filecount=50,filesize=100M：指定GC日志的输出格式和位置，记录GC相关信息。
  -XX:+PrintCommandLineFlags：打印JVM启动时的命令行参数，可以去除
方便测试OOM，可以调整 -Xms524m -Xmx524m