【Java】JVM性能调优实战：让你的IntelliJ Idea纵享丝滑

Z时代
2024-01-10
分类：技术分享

前言

在前面整理了一篇关于JVM故障诊断和处理工具，考虑到大部分的Java程序员都使用的时IntelliJ Idea，本篇就使用工具来实战演练对IntelliJ Idea运行速度调优

调优前的运行状态

原始配置内容

要查询idea原始配置文件的路径可以在VisualVM中的概述中查看

【Java】JVM性能调优实战：让你的IntelliJ Idea纵享丝滑

原始配置内容：

-XX:ReservedCodeCacheSize=240m -XX:+UseCompressedOops -Dfile.encoding=UTF-8 -XX:SoftRefLRUPolicyMSPerMB=50 -ea -Dsun.io.useCanonCaches=false -Djava.net.preferIPv4Stack=true -Djdk.http.auth.tunneling.disabledSchemes="" -XX:+HeapDumpOnOutOfMemoryError -XX:-OmitStackTraceInFastThrow -XX:ErrorFile=$USER_HOME/java_error_in_idea_%p.log -XX:HeapDumpPath=$USER_HOME/java_error_in_idea.hprof

-Xmx512m

打印启动时间插件开发

需要直观的看到优化前和优化后启动时间的变化，所以需要简单做一个Idea的插件开发，关于Idea插件开发的流程建议参考我以前的文章《IDEA插件：多线程文件下载插件开发
》

JVM的启动时间到所有组件初始化完成后的时间就看做是IDEA的启动时间，代码如下

public class MyApplicationInitializedListener implements ApplicationInitializedListener {
@Override
public void componentsInitialized() {
RuntimeMXBean bean = ManagementFactory.getRuntimeMXBean();
long startTime = bean.getStartTime();
long costTime = System.currentTimeMillis() - startTime;
Messages.showMessageDialog("毫秒：" + costTime, "启动耗时", Messages.getInformationIcon());
}}

plugin.xml中添加如下代码：

<extensions defaultextensionns="com.intellij">
<applicationinitializedlistener id="MyApplicationInitializedListener" implementation="cn.silently9527.MyApplicationInitializedListener" /></extensions>

优化前的启动信息与时间消耗

【Java】JVM性能调优实战：让你的IntelliJ Idea纵享丝滑

根据VisualGC和IDEA启动插件收集到的信息：

IDEA启动耗时 15s

总共垃圾收集22次，耗时1.2s，其中新生代GC 17次，耗时324ms; 老年代GC 5次，耗时953ms

加载类27526个，耗时 21s

> 按照这个数据来看也算是正常，15s 其实也在接受范围内，由于本文主要演示性能调优，所以需要测试能否在快一些

开始尝试优化

调整内存来控制垃圾回收频率

图上我们可以看出，启动参数指定的512m的内存被分配到新生代的只有169m，由于IDEA是我们开发常用的工具，平时的编译过程也需要足够的内存，所以我们需要先把总的内存扩大，这里我设置最大的内存-Xmx1024m，为了让JVM在GC期间不需要再浪费时间再动态计算扩容大小，同时也设置了-Xms1024m；

在启动的过程中Eden共发生了17次GC，为了减少新生代gc次数，我把新生代的内存大小设置成-Xmn256m;

重新启动之后查看VisualGC，新生代gc次数从 17次降低到了 7次，耗时从 324ms 降低到了 152ms。

【Java】JVM性能调优实战：让你的IntelliJ Idea纵享丝滑

在调整内存前发生了5次Full GC，调整内存后的依然还是有4次Full GC，但是从两张图我们可以看出，老年代的空间还有很多剩余，是不应该发生Full GC的；考虑是否是代码中有地方手动调用System.gc()出发了Full GC，所以添加了参数-XX:+DisableExplicitGC，再次重新启动IDEA，结果很失望，依然还有4次Full GC；

再次仔细观察优化前的图，注意看 Last Cause: Metadata GC Threshold , 最后一次gc是应该Metaspace区域内存不够发生的GC，为了验证我们的猜想，打印出GC日志来看看。在idea.vmoptions中添加打印日志相关的参数：

-XX:+PrintGCDetails -XX:+PrintGCDateStamps

-Xloggc:../gc.log

> JVM的GC日志的主要参数包括如下几个：
> - -XX:+PrintGC 输出GC日志
> - -XX:+PrintGCDetails 输出GC的详细日志
> - -XX:+PrintGCTimeStamps 输出GC的时间戳（以基准时间的形式）
> - -XX:+PrintGCDateStamps 输出GC的时间戳（以日期的形式，如 2013-05-04T21:53:59.234+0800）
> - -XX:+PrintHeapAtGC 在进行GC的前后打印出堆的信息
> - -Xloggc:../logs/gc.log 日志文件的输出路径

重新启动idea，查看gc.log

【Java】JVM性能调优实战：让你的IntelliJ Idea纵享丝滑