如何使用“-Xlog:heap*=debug”“-Xlog:GC*=debug”从GC正确读取日志的某些部分

Question

假设我有非常简单的代码：

public class Sandbox { 

    public static void main(String[] args) {

        Map<String, Integer> map = new HashMap<>();

        while (true) {
            map.put(new Random().toString(), new Random().nextInt());
        }
    }
}

它有多“不正确”并不重要，重要的是它做了什么。它最终会产生一个OutOfMemory。我用：

java -Xms50M -Xmx50M "-Xlog:heap*=debug" "-Xlog:gc*=debug" Sandbox 

会有很多日志记录，我会把它们切到我关心的最重要的部分（我还从一些行中删除了一些信息，以便于阅读）

*****************************     GC(0)    *********************************

[0.115s][debug][gc,heap] GC(0) Heap before GC invocations=0 (full 0)
[0.115s][debug][gc,heap] GC(0) region size 1024K, 23 young, 0 survivors

[0.127s][info ][gc,heap] GC(0) Eden regions: 23->0(15)
[0.128s][info ][gc,heap] GC(0) Survivor regions: 0->3(3)
[0.128s][info ][gc,heap] GC(0) Old regions: 0->4
[0.128s][info ][gc,heap] GC(0) Archive regions: 2->2
[0.128s][info ][gc,heap] GC(0) Humongous regions: 1->1

[0.128s][debug][gc,heap] GC(0) Heap after GC invocations=1 (full 0):
[0.128s][debug][gc,heap] GC(0) region size 1024K, 3 young, 3 survivors


 *****************************     GC(1)    *********************************

[0.159s][debug][gc,heap] GC(1) Heap before GC invocations=1 (full 0)
[0.159s][debug][gc,heap] GC(1) region size 1024K, 18 young, 3 survivors

[0.181s][info ][gc,heap] GC(1) Eden regions: 15->0(1)
[0.181s][info ][gc,heap] GC(1) Survivor regions: 3->3(3)
[0.181s][info ][gc,heap] GC(1) Old regions: 4->10
[0.181s][info ][gc,heap] GC(1) Archive regions: 2->2
[0.181s][info ][gc,heap] GC(1) Humongous regions: 3->3

[0.181s][debug][gc,heap] GC(1) Heap after GC invocations=2 (full 0)
[0.181s][debug][gc,heap] GC(1) region size 1024K, 3 young, 3 survivors

*****************************     GC(2)    *********************************

[0.182s][debug][gc,heap] GC(2) Heap before GC invocations=2 (full 0)
[0.182s][debug][gc,heap] GC(2) region size 1024K, 4 young, 3 survivors 

[0.190s][info ][gc,heap] GC(2) Eden regions: 1->0(9)
[0.190s][info ][gc,heap] GC(2) Survivor regions: 3->1(1)
[0.190s][info ][gc,heap] GC(2) Old regions: 10->13
[0.190s][info ][gc,heap] GC(2) Archive regions: 2->2
[0.190s][info ][gc,heap] GC(2) Humongous regions: 3->3

[0.190s][debug][gc,heap] GC(2) Heap after GC invocations=3 (full 0)
[0.190s][debug][gc,heap] GC(2) region size 1024K, 1 young, 1 survivors

它们到底是什么意思？

Answer 1

我在网上只找到了两个引用以某种方式解释了这一点：一个在这里，另一个在这里。不幸的是，它们都没有多大意义，所以我不得不查看生成它的源代码以获得理解。

第一行：

[0.115s][debug][gc,heap] GC(0) Heap before GC invocations=0 (full 0)
[0.115s][debug][gc,heap] GC(0) region size 1024K, 23 young, 0 survivors

告诉你，在第一次调用GC（0）之前，young space分配了23个区域。它还（间接地）告诉你，在这23个区域中：0是幸存者区域，这意味着23是Eden区域。

下一行：

[0.127s][info ][gc,heap] GC(0) Eden     regions:     23 -> 0 (15)
[0.128s][info ][gc,heap] GC(0) Survivor regions:     0  -> 3 (3)

告诉你在GC操作之前，有23伊甸园区域。它们都被清除了（即0）（毕竟这是年轻GC存在的原因）。然后它表明在GC之前有0Survivor区域，但作为这个循环的结果，生成了3个Survivor Region。

你必须格外小心这意味着什么。这并没有显示下一个周期有多少区域可用，只显示发生的过渡。

这些日志中解释了这种“过渡”：

[0.128s][debug][gc,heap] GC(0) Heap after GC invocations=1 (full 0):
[0.128s][debug][gc,heap] GC(0) region size 1024K, 3 young, 3 survivors

请注意，堆是如何转变为3个年轻人，3个幸存者的（因此，0伊甸园，所有23都被清除）。

之后还有另一个步骤：调整区域总数。怎样你可以自己看看源代码，找到答案。

具体来说，（3）将在这里进行计算，这里有更多细节（以及非常好的注释），它基本上表示下一个GC周期将有多少幸存者区域可用。在大多数情况下，（3）将等于-

这个“调整”是关于（15）和（3），这意味着下一个周期将有15个伊甸园区域和3个幸存者区域；也由下一个周期开始时的日志表示。

[0.159s][debug][gc,heap] GC(1) Heap before GC invocations=1 (full 0)
[0.159s][debug][gc,heap] GC(1) region size 1024K, 18 young, 3 survivors

我需要在这里做一个评论：这些是下一个周期中GC应该使用的提示，它们可能会被忽略（例如，巨大的分配会导致这种情况）。

所以我们现在可以划一条线了。这些日志中有两个逻辑部分。

>

过渡-年轻地区如何因这个周期而改变

调整过渡后年轻地区的数量。

现在，消化一些日志应该相当容易，例如：

[0.182s][debug][gc,heap] GC(2) Heap before GC invocations=2 (full 0)
[0.182s][debug][gc,heap] GC(2) region size 1024K, 4 young, 3 survivors

它始于4年轻=1伊甸园3幸存者

[0.190s][info ][gc,heap] GC(2) Eden     regions: 1 -> 0 (9)
[0.190s][info ][gc,heap] GC(2) Survivor regions: 3 -> 1 (1)

它过渡到0伊甸园，1幸存者

[0.190s][info ][gc,heap] GC(2) Eden     regions: 1 -> 0 (9)
[0.190s][info ][gc,heap] GC(2) Survivor regions: 3 -> 1 (1)

它应用启发式生成9 Eden，1 Survivor到下一个GC周期。