java - Safepoint+stats log with None vmop operation in output JDK12

Question

我正在使用 -Xlog:safepoint+stats=debug:file=safepoint.log vm 参数在 JDK12 上运行应用程序以记录安全点操作并使用 ZGC 运行。我无法理解日志输出:

[1408.417s][debug][safepoint,stats]           vmop                            [ threads:    total initially_running wait_to_block ][ time:    spin   block    sync cleanup    vmop ] page_trap_count
[1412.164s][debug][safepoint,stats] 1412.162: ZOperation                    [               376                 0             7 ][             0       0       0       0       1 ]               7
[1413.164s][debug][safepoint,stats] 1413.164: None                          [               376                 0             0 ][             0       0       0       0       0 ]               0
[1414.165s][debug][safepoint,stats] 1414.164: None                          [               376                 0             1 ][             0       0       0       0       0 ]               1

我理解第一行告诉 ZOperation 花费了 1 毫秒，并且有 7 个线程参与了阻塞。

第二行没看懂，什么是"None" vmop操作？看起来那个操作的持续时间是 0。它真的是 0 或者它是 0 因为它花费了不到 1 毫秒？如果是，则可以设置更高粒度的时间记录，以查看花费了多少纳秒？还是微秒？

我每秒记录了很多这种“无”操作。我想知道 JVM 在暂停期间正在做什么。我每 10 秒跟踪一次 ZGC 时间和 SafePoint 时间，聚合的安全点时间比聚合的 gc 暂停时间高 5 倍。我想以某种方式减少我的应用程序的安全点时间。

Answer 1

这是一个没有 VM 操作的安全点。它的目的是执行定期清理任务，例如放气空闲监视器或清除内联缓存缓冲区，只有在没有 Java 线程运行时才能安全地完成这些任务。

如果有清理任务要做，强制安全点每 GuaranteedSafepointInterval 毫秒发生一次。默认值为 1000。请注意，在您的情况下，无操作安全点恰好发生在前一个安全点之后 1 秒。

可以调整间隔

-XX:+UnlockDiagnosticVMOptions -XX:GuaranteedSafepointInterval=<ms>

为了避免混淆，在 JDK 13 中，此类无操作安全点获得了 Cleanup 操作名称。