您应该已经阅读了前面的章节:

  1. ​垃圾收集简单介绍 - GC參考手冊​
  2. ​Java中的垃圾收集 - GC參考手冊​
  3. ​GC 算法(基础篇) - GC參考手冊​

学习了GC算法的相关概念之后, 我们将介绍在JVM中这些算法的详细实现。

首先要记住的是, 大多数JVM都须要使用两种不同的GC算法 —— 一种用来清理年轻代, 还有一种用来清理老年代。

我们能够选择JVM内置的各种算法。

假设不通过參数明白指定垃圾收集算法, 则会使用宿主平台的默认实现。

本章会详细介绍各种算法的实现原理。

以下是关于Java 8中各种组合的垃圾收集器概要列表,对于之前的Java版本号来说,可用组合会有一些不同:

Young

Tenured

JVM options

Incremental(增量GC)

Incremental

-Xincgc

Serial

Serial

-XX:+UseSerialGC

Parallel Scavenge

Serial

-XX:+UseParallelGC -XX:-UseParallelOldGC

Parallel New

Serial

N/A

Serial

Parallel Old

N/A

Parallel Scavenge

Parallel Old

-XX:+UseParallelGC -XX:+UseParallelOldGC

Parallel New

Parallel Old

N/A

Serial

CMS

-XX:-UseParNewGC -XX:+UseConcMarkSweepGC

Parallel Scavenge

CMS

N/A

Parallel New

CMS

-XX:+UseParNewGC -XX:+UseConcMarkSweepGC

G1

-XX:+UseG1GC

看起来有些复杂, 不用操心。主要使用的是上表中黑体字表示的这四种组合。其余的要么是​​被废弃(deprecated)​​, 要么是不支持或者是不太适用于生产环境。

所以,接下来我们仅仅介绍以下这些组合及其工作原理:

  • 年轻代和老年代的串行GC(Serial GC)
  • 年轻代和老年代的并行GC(Parallel GC)
  • 年轻代的并行GC(Parallel New) + 老年代的CMS(Concurrent Mark and Sweep)
  • G1, 负责回收年轻代和老年代

Serial GC(串行GC)

Serial GC 对年轻代使用 ​​mark-copy(标记-复制) 算法​​, 对老年代使用 ​​mark-sweep-compact(标记-清除-整理)算法​​. 顾名思义, 两者都是单线程的垃圾收集器,不能进行并行处理。两者都会触发全线暂停(STW),停止全部的应用线程。

因此这样的GC算法不能充分利用多核CPU。无论有多少CPU内核, JVM 在垃圾收集时都仅仅能使用单个核心。

要启用此款收集器, 仅仅须要指定一个JVM启动參数就可以,同一时候对年轻代和老年代生效:

java -XX:+UseSerialGC com.mypackages.MyExecutableClass


该选项仅仅适合几百MB堆内存的JVM,并且是单核CPU时比較实用。 对于server端来说, 由于一般是多个CPU内核, 并不推荐使用, 除非确实须要限制JVM所使用的资源。大多数server端应用部署在多核平台上, 选择 Serial GC 就表示人为的限制系统资源的使用。

导致的就是资源闲置, 多的CPU资源也不能用来降低延迟,也不能用来添加吞吐量。

以下让我们看看Serial GC的垃圾收集日志, 并从中提取什么实用的信息。为了打开GC日志记录, 我们使用以下的JVM启动參数:

-XX:+PrintGCDetails -XX:+PrintGCDateStamps 
-XX:+PrintGCTimeStamps


产生的GC日志输出相似这样(为了排版,已手工折行):

2015-05-26T14:45:37.987-0200: 
151.126: [GC (Allocation Failure)
151.126: [DefNew: 629119K->69888K(629120K), 0.0584157 secs]
1619346K->1273247K(2027264K), 0.0585007 secs]
[Times: user=0.06 sys=0.00, real=0.06 secs]
2015-05-26T14:45:59.690-0200:
172.829: [GC (Allocation Failure)
172.829: [DefNew: 629120K->629120K(629120K), 0.0000372 secs]
172.829: [Tenured: 1203359K->755802K(1398144K), 0.1855567 secs]
1832479K->755802K(2027264K),
[Metaspace: 6741K->6741K(1056768K)], 0.1856954 secs]
[Times: user=0.18 sys=0.00, real=0.18 secs]


此GC日志片段展示了在JVM中发生的非常多事情。

实际上,在这段日志中产生了两个GC事件, 当中一次清理的是年轻代,还有一次清理的是整个堆内存。

让我们先来分析前一次GC,其在年轻代中产生。

Minor GC(小型GC)

以下代码片段展示了清理年轻代内存的GC事件:

​2015-05-26T14:45:37.987-0200​1 : ​​151.1262​2 : [ ​​GC​3 (​​Allocation Failure​4 151.126:

[​​DefNew​5 : ​​629119K->69888K​6​(629120K)​7 , 0.0584157 secs] ​​1619346K->1273247K​8

​(2027264K)​9, ​​0.0585007 secs​10] ​​[Times: user=0.06 sys=0.00, real=0.06 secs]​11


  1. 2015-05-26T14:45:37.987-0200 – GC事件開始的时间. 当中-0200表示西二时区,而中国所在的东8区为 +0800
  2. 151.126 – GC事件開始时,相对于JVM启动时的间隔时间,单位是秒。
  3. GC – 用来区分 Minor GC 还是 Full GC 的标志。
    GC表明这是一次小型GC(Minor GC)
  4. Allocation Failure – 触发 GC 的原因。本次GC事件, 是由于年轻代中没有空间来存放新的数据结构引起的。
  5. DefNew – 垃圾收集器的名称。这个神奇的名字表示的是在年轻代中使用的: 单线程, 标记-复制(mark-copy), 全线暂停(STW) 垃圾收集器。
  6. 629119K->69888K – 在垃圾收集之前和之后年轻代的使用量。
  7. (629120K) – 年轻代总的空间大小。
  8. 1619346K->1273247K – 在垃圾收集之前和之后整个堆内存的使用情况。
  9. (2027264K) – 可用堆的总空间大小。
  10. 0.0585007 secs – GC事件持续的时间,以秒为单位。
  11. [Times: user=0.06 sys=0.00, real=0.06 secs] – GC事件的持续时间, 通过三个部分来衡量:
  • user – 在此次垃圾回收过程中, 全部 GC线程所消耗的CPU时间之和。
  • sys – GC过程中中操作系统调用和系统等待事件所消耗的时间。
  • real – 应用程序暂停的时间。由于串行垃圾收集器(Serial Garbage Collector)仅仅使用单线程, 因此 real time 等于 user 和 system 时间的总和。

能够从上面的日志片段了解到, 在GC事件中,JVM 的内存使用情况发生了怎样的变化。此次垃圾收集之前, 堆内存总的使用量为 1,619,346K。当中,年轻代使用了 629,119K。能够算出,老年代使用量为 990,227K

更重要的信息蕴含在下一批数字中, 垃圾收集之后, 年轻代的使用量降低了 559,231K, 但堆内存的整体使用量仅仅下降了 346,099K。 从中能够算出,有 213,132K 的对象从年轻代提升到了老年代。

此次GC事件也能够用以下的示意图来说明, 显示的是GC開始之前, 以及刚刚结束之后, 这两个时间点内存使用情况的快照:

4. GC 算法(实现篇) - GC參考手冊_垃圾收集

Full GC(全然GC)

理解第一次 minor GC 事件后,让我们看看日志中的第二次GC事件:

​2015-05-26T14:45:59.690-0200​1 : ​​172.829​2 : [GC (Allocation Failure 172.829:

​[DefNew: 629120K->629120K(629120K), 0.0000372 secs​3] 172.829:[​​Tenured​4:

​1203359K->755802K​5​(1398144K)​6, ​​0.1855567 secs​7 ] ​​1832479K->755802K​8

​(2027264K)​9, ​​[Metaspace: 6741K->6741K(1056768K)]​10

​[Times: user=0.18 sys=0.00, real=0.18 secs]​11


>

  1. ​2015-05-26T14:45:59.690-0200​​ – GC事件開始的时间. 当中​​-0200​​表示西二时区,而中国所在的东8区为 ​​+0800​​。
  2. ​172.829​​ – GC事件開始时,相对于JVM启动时的间隔时间,单位是秒。
  3. [DefNew: 629120K->629120K(629120K), 0.0000372 secs – 与上面的演示样例相似, 由于内存分配失败,在年轻代中发生了一次 minor GC。此次GC相同使用的是 DefNew 收集器, 让年轻代的使用量从 629120K 降为 0。
    注意,JVM对此次GC的报告有些问题,误将年轻代觉得是全然填满的。此次垃圾收集消耗了 0.0000372秒。
  4. Tenured – 用于清理老年代空间的垃圾收集器名称。Tenured 表明使用的是单线程的全线暂停垃圾收集器, 收集算法为 标记-清除-整理(mark-sweep-compact )。
  5. ​1203359K->755802K​​ – 在垃圾收集之前和之后老年代的使用量。
  6. ​(1398144K)​​ – 老年代的总空间大小。
  7. ​0.1855567 secs​​ – 清理老年代所花的时间。
  8. ​1832479K->755802K​​ – 在垃圾收集之前和之后,整个堆内存的使用情况。
  9. ​(2027264K)​​ – 可用堆的总空间大小。
  10. ​[Metaspace: 6741K->6741K(1056768K)]​​ – 关于 Metaspace 空间, 相同的信息。能够看出, 此次GC过程中 Metaspace 中没有收集到不论什么垃圾。
  11. ​[Times: user=0.18 sys=0.00, real=0.18 secs]​​ – GC事件的持续时间, 通过三个部分来衡量:
  • user – 在此次垃圾回收过程中, 全部 GC线程所消耗的CPU时间之和。
  • sys – GC过程中中操作系统调用和系统等待事件所消耗的时间。
  • real – 应用程序暂停的时间。由于串行垃圾收集器(Serial Garbage Collector)仅仅使用单线程, 因此 real time 等于 user 和 system 时间的总和。

和 Minor GC 相比,最明显的差别是 —— 在此次GC事件中, 除了年轻代, 还清理了老年代和Metaspace. 在GC事件開始之前, 以及刚刚结束之后的内存布局,能够用以下的示意图来说明:

4. GC 算法(实现篇) - GC參考手冊_垃圾收集_02

Parallel GC(并行GC)

并行垃圾收集器这一类组合, 在年轻代使用 ​​标记-复制(mark-copy)算法​​, 在老年代使用 ​​标记-清除-整理(mark-sweep-compact)算法​​。年轻代和老年代的垃圾回收都会触发STW事件,暂停全部的应用线程来运行垃圾收集。两者在运行 标记和 复制/整理阶段时都使用多个线程, 因此得名“(Parallel)”。

通过并行运行, 使得GC时间大幅降低。

通过命令行參数 ​​-XX:ParallelGCThreads=NNN​​ 来指定 GC 线程数。 其默认值为CPU内核数。

能够通过以下的随意一组命令行參数来指定并行GC:

java -XX:+UseParallelGC com.mypackages.MyExecutableClass
java -XX:+UseParallelOldGC com.mypackages.MyExecutableClass
java -XX:+UseParallelGC -XX:+UseParallelOldGC com.mypackages.MyExecutableClass


并行垃圾收集器适用于多核server,主要目标是添加吞吐量。

由于对系统资源的有效使用,能达到更高的吞吐量:

  • 在GC期间, 全部 CPU 内核都在并行清理垃圾, 所以暂停时间更短
  • 在两次GC周期的间隔期, 没有GC线程在运行,不会消耗不论什么系统资源

还有一方面, 由于此GC的全部阶段都不能中断, 所以并行GC非常easy出现长时间的停顿. 假设延迟是系统的主要目标, 那么就应该选择其它垃圾收集器组合。

译者注: 长时间卡顿的意思是。此GC启动之后,属于一次性完毕全部操作, 于是单次 pause 的时间会较长。

让我们看看并行垃圾收集器的GC日志长什么样, 从中我们能够得到哪些实用信息。以下的GC日志中显示了一次 minor GC 暂停 和一次 major GC 暂停:

2015-05-26T14:27:40.915-0200: 116.115: [GC (Allocation Failure) 
[PSYoungGen: 2694440K->1305132K(2796544K)]
9556775K->8438926K(11185152K)
, 0.2406675 secs]
[Times: user=1.77 sys=0.01, real=0.24 secs]
2015-05-26T14:27:41.155-0200: 116.356: [Full GC (Ergonomics)
[PSYoungGen: 1305132K->0K(2796544K)]
[ParOldGen: 7133794K->6597672K(8388608K)] 8438926K->6597672K(11185152K),
[Metaspace: 6745K->6745K(1056768K)]
, 0.9158801 secs]
[Times: user=4.49 sys=0.64, real=0.92 secs]


Minor GC(小型GC)

第一次GC事件表示发生在年轻代的垃圾收集:

​2015-05-26T14:27:40.915-0200​1: ​​116.115​2: ​​[ GC​3 (​​Allocation Failure​4)

[​​PSYoungGen​5: ​​2694440K->1305132K​6​(2796544K)​7] ​​9556775K->8438926K​8

​(11185152K)​9, ​​0.2406675 secs​10]

​[Times: user=1.77 sys=0.01, real=0.24 secs]​11


>

  1. ​2015-05-26T14:27:40.915-0200​​ – GC事件開始的时间. 当中​​-0200​​表示西二时区,而中国所在的东8区为 ​​+0800​​。
  2. ​116.115​​ – GC事件開始时,相对于JVM启动时的间隔时间,单位是秒。
  3. ​GC​​ – 用来区分 Minor GC 还是 Full GC 的标志。​​GC​​表明这是一次小型GC(Minor GC)
  4. ​Allocation Failure​​ – 触发垃圾收集的原因。本次GC事件, 是由于年轻代中没有适当的空间存放新的数据结构引起的。
  5. ​PSYoungGen​​ – 垃圾收集器的名称。这个名字表示的是在年轻代中使用的: 并行的 标记-复制(mark-copy), 全线暂停(STW) 垃圾收集器。
  6. ​2694440K->1305132K​​ – 在垃圾收集之前和之后的年轻代使用量。
  7. ​(2796544K)​​ – 年轻代的总大小。
  8. ​9556775K->8438926K​​ – 在垃圾收集之前和之后整个堆内存的使用量。
  9. ​(11185152K)​​ – 可用堆的总大小。
  10. ​0.2406675 secs​​ – GC事件持续的时间,以秒为单位。
  11. ​[Times: user=1.77 sys=0.01, real=0.24 secs]​​ – GC事件的持续时间, 通过三个部分来衡量:
  • user – 在此次垃圾回收过程中, 由GC线程所消耗的总的CPU时间。
  • sys – GC过程中中操作系统调用和系统等待事件所消耗的时间。
  • real – 应用程序暂停的时间。在 Parallel GC 中, 这个数字约等于: (user time + system time)/GC线程数。 这里使用了8个线程。 请注意,总有一定比例的处理过程是不能并行进行的。

所以,能够简单地算出, 在垃圾收集之前, 堆内存总使用量为 9,556,775K。 当中年轻代为 2,694,440K。同一时候算出老年代使用量为 6,862,335K. 在垃圾收集之后, 年轻代使用量降低为 1,389,308K, 但总的堆内存使用量仅仅降低了 ​​1,117,849K​​。这表示有大小为 271,459K 的对象从年轻代提升到老年代。

4. GC 算法(实现篇) - GC參考手冊_老年代_03

Full GC(全然GC)

学习了并行GC怎样清理年轻代之后, 以下介绍清理整个堆内存的GC日志以及怎样进行分析:

​2015-05-26T14:27:41.155-0200​​ : ​​116.356​​ : [​​Full GC​​ (​​Ergonomics​​)

​[PSYoungGen: 1305132K->0K(2796544K)]​​ [​​ParOldGen​​ :​​7133794K->6597672K​

​(8388608K)​​] ​​8438926K->6597672K​​ ​​(11185152K)​​,

​[Metaspace: 6745K->6745K(1056768K)]​​, ​​0.9158801 secs​​,

​[Times: user=4.49 sys=0.64, real=0.92 secs]​


  1. ​2015-05-26T14:27:41.155-0200​​ – GC事件開始的时间. 当中​​-0200​​表示西二时区,而中国所在的东8区为 ​​+0800​​。
  2. ​116.356​​ – GC事件開始时,相对于JVM启动时的间隔时间,单位是秒。 我们能够看到, 此次事件在前一次 MinorGC完毕之后立马就開始了。
  3. ​Full GC​​ – 用来表示此次是 Full GC 的标志。​​Full GC​​表明本次清理的是年轻代和老年代。
  4. ​Ergonomics​​ – 触发垃圾收集的原因。​​Ergonomics​​ 表示JVM内部环境觉得此时能够进行一次垃圾收集。
  5. ​[PSYoungGen: 1305132K->0K(2796544K)]​​ – 和上面的演示样例一样, 清理年轻代的垃圾收集器是名为 “PSYoungGen” 的STW收集器, 採用标记-复制(mark-copy)算法。 年轻代使用量从 1305132K 变为 ​​0​​, 一般 Full GC 的结果都是这样。
  6. ​ParOldGen​​ – 用于清理老年代空间的垃圾收集器类型。在这里使用的是名为 ParOldGen 的垃圾收集器, 这是一款并行 STW垃圾收集器, 算法为 标记-清除-整理(mark-sweep-compact)。
  7. ​7133794K->6597672K​​ – 在垃圾收集之前和之后老年代内存的使用情况。
  8. ​(8388608K)​​ – 老年代的总空间大小。
  9. ​8438926K->6597672K​​ – 在垃圾收集之前和之后堆内存的使用情况。
  10. ​(11185152K)​​ – 可用堆内存的总容量。
  11. ​[Metaspace: 6745K->6745K(1056768K)]​​ – 相似的信息,关于 Metaspace 空间的。能够看出, 在GC事件中 Metaspace 里面没有回收不论什么对象。
  12. ​0.9158801 secs​​ – GC事件持续的时间,以秒为单位。
  13. ​[Times: user=4.49 sys=0.64, real=0.92 secs]​​ – GC事件的持续时间, 通过三个部分来衡量:
  • user – 在此次垃圾回收过程中, 由GC线程所消耗的总的CPU时间。
  • sys – GC过程中中操作系统调用和系统等待事件所消耗的时间。
  • real – 应用程序暂停的时间。在 Parallel GC 中, 这个数字约等于: (user time + system time)/GC线程数。
    这里使用了8个线程。 请注意,总有一定比例的处理过程是不能并行进行的。

相同,和 Minor GC 的差别是非常明显的 —— 在此次GC事件中, 除了年轻代, 还清理了老年代和 Metaspace. 在GC事件前后的内存布局例如以下图所看到的:

4. GC 算法(实现篇) - GC參考手冊_堆区_04

Concurrent Mark and Sweep(并发标记-清除)

CMS的官方名称为 “Mostly Concurrent Mark and Sweep Garbage Collector”(主要并发-标记-清除-垃圾收集器). 其对年轻代採用并行 STW方式的 ​​mark-copy (标记-复制)算法​​, 对老年代主要使用并发 ​​mark-sweep (标记-清除)算法​​。

CMS的设计目标是避免在老年代垃圾收集时出现长时间的卡顿。主要通过两种手段来达成此目标。

  • 第一, 不正确老年代进行整理, 而是使用空暇列表(free-lists)来管理内存空间的回收。
  • 第二, 在 mark-and-sweep (标记-清除) 阶段的大部分工作和应用线程一起并发运行。

也就是说, 在这些阶段并没有明显的应用线程暂停。但值得注意的是, 它仍然和应用线程争抢CPU时间。

默认情况下, CMS 使用的并发线程数等于CPU内核数的 ​​1/4​​。

通过以下选项来指定CMS垃圾收集器:

java -XX:+UseConcMarkSweepGC com.mypackages.MyExecutableClass


假设server是多核CPU,并且主要调优目标是降低延迟, 那么使用CMS是个非常明智的选择. 降低每一次GC停顿的时间,会直接影响到终端用户对系统的体验, 用户会觉得系统非常灵敏。 由于多数时候都有部分CPU资源被GC消耗, 所以在CPU资源受限的情况下,CMS会比并行GC的吞吐量差一些。

和前面的GC算法一样, 我们先来看看CMS算法在实际应用中的GC日志, 当中包含一次 minor GC, 以及一次 major GC 停顿:

2015-05-26T16:23:07.219-0200: 64.322: [GC (Allocation Failure) 64.322: 
[ParNew: 613404K->68068K(613440K), 0.1020465 secs]
10885349K->10880154K(12514816K), 0.1021309 secs]
[Times: user=0.78 sys=0.01, real=0.11 secs]
2015-05-26T16:23:07.321-0200: 64.425: [GC (CMS Initial Mark)
[1 CMS-initial-mark: 10812086K(11901376K)]
10887844K(12514816K), 0.0001997 secs]
[Times: user=0.00 sys=0.00, real=0.00 secs]
2015-05-26T16:23:07.321-0200: 64.425: [CMS-concurrent-mark-start]
2015-05-26T16:23:07.357-0200: 64.460: [CMS-concurrent-mark: 0.035/0.035 secs]
[Times: user=0.07 sys=0.00, real=0.03 secs]
2015-05-26T16:23:07.357-0200: 64.460: [CMS-concurrent-preclean-start]
2015-05-26T16:23:07.373-0200: 64.476: [CMS-concurrent-preclean: 0.016/0.016 secs]
[Times: user=0.02 sys=0.00, real=0.02 secs]
2015-05-26T16:23:07.373-0200: 64.476: [CMS-concurrent-abortable-preclean-start]
2015-05-26T16:23:08.446-0200: 65.550: [CMS-concurrent-abortable-preclean: 0.167/1.074 secs]
[Times: user=0.20 sys=0.00, real=1.07 secs]
2015-05-26T16:23:08.447-0200: 65.550: [GC (CMS Final Remark)
[YG occupancy: 387920 K (613440 K)]
65.550: [Rescan (parallel) , 0.0085125 secs]
65.559: [weak refs processing, 0.0000243 secs]
65.559: [class unloading, 0.0013120 secs]
65.560: [scrub symbol table, 0.0008345 secs]
65.561: [scrub string table, 0.0001759 secs]
[1 CMS-remark: 10812086K(11901376K)]
11200006K(12514816K), 0.0110730 secs]
[Times: user=0.06 sys=0.00, real=0.01 secs]
2015-05-26T16:23:08.458-0200: 65.561: [CMS-concurrent-sweep-start]
2015-05-26T16:23:08.485-0200: 65.588: [CMS-concurrent-sweep: 0.027/0.027 secs]
[Times: user=0.03 sys=0.00, real=0.03 secs]
2015-05-26T16:23:08.485-0200: 65.589: [CMS-concurrent-reset-start]
2015-05-26T16:23:08.497-0200: 65.601: [CMS-concurrent-reset: 0.012/0.012 secs]
[Times: user=0.01 sys=0.00, real=0.01 secs]


Minor GC(小型GC)

日志中的第一次GC事件是清理年轻代的小型GC(Minor GC)。

让我们来分析 CMS 垃圾收集器的行为:

​2015-05-26T16:23:07.219-0200​​: ​​64.322​​:[​​GC​​(​​Allocation Failure​​) 64.322:

[​​ParNew​​: ​​613404K->68068K​​​​(613440K)​​, ​​0.1020465 secs​​]

​10885349K->10880154K​​​​(12514816K)​​, ​​0.1021309 secs​​]

​[Times: user=0.78 sys=0.01, real=0.11 secs]​


>

  1. ​2015-05-26T16:23:07.219-0200​​ – GC事件開始的时间. 当中​​-0200​​表示西二时区,而中国所在的东8区为 ​​+0800​​。
  2. ​64.322​​ – GC事件開始时,相对于JVM启动时的间隔时间,单位是秒。
  3. ​GC​​ – 用来区分 Minor GC 还是 Full GC 的标志。​​GC​​表明这是一次小型GC(Minor GC)
  4. ​Allocation Failure​​ – 触发垃圾收集的原因。本次GC事件, 是由于年轻代中没有适当的空间存放新的数据结构引起的。
  5. ​ParNew​​ – 垃圾收集器的名称。这个名字表示的是在年轻代中使用的: 并行的 标记-复制(mark-copy), 全线暂停(STW)垃圾收集器, 专门设计了用来配合老年代使用的 Concurrent Mark & Sweep 垃圾收集器。
  6. ​613404K->68068K​​ – 在垃圾收集之前和之后的年轻代使用量。
  7. ​(613440K)​​ – 年轻代的总大小。
  8. ​0.1020465 secs​​ – 垃圾收集器在 w/o final cleanup 阶段消耗的时间
  9. ​10885349K->10880154K​​ – 在垃圾收集之前和之后堆内存的使用情况。
  10. ​(12514816K)​​ – 可用堆的总大小。
  11. ​0.1021309 secs​​ – 垃圾收集器在标记和复制年轻代存活对象时所消耗的时间。包含和ConcurrentMarkSweep收集器的通信开销, 提升存活时间达标的对象到老年代,以及垃圾收集后期的一些终于清理。
  12. ​[Times: user=0.78 sys=0.01, real=0.11 secs]​​ – GC事件的持续时间, 通过三个部分来衡量:
  • user – 在此次垃圾回收过程中, 由GC线程所消耗的总的CPU时间。
  • sys – GC过程中中操作系统调用和系统等待事件所消耗的时间。
  • real – 应用程序暂停的时间。在并行GC(Parallel GC)中, 这个数字约等于: (user time + system time)/GC线程数。 这里使用的是8个线程。 请注意,总是有固定比例的处理过程是不能并行化的。

从上面的日志能够看出,在GC之前总的堆内存使用量为 10,885,349K, 年轻代的使用量为 613,404K。这意味着老年代使用量等于 10,271,945K。GC之后,年轻代的使用量降低了 545,336K, 而总的堆内存使用仅仅下降了 5,195K

能够算出有 540,141K 的对象从年轻代提升到老年代。

4. GC 算法(实现篇) - GC參考手冊_垃圾收集器_05

Full GC(全然GC)

如今, 我们已经熟悉了怎样解读GC日志, 接下来将介绍一种全然不同的日志格式。

以下这一段非常长非常长的日志, 就是CMS对老年代进行垃圾收集时输出的各阶段日志。为了简洁,我们对这些阶段逐个介绍。 首先来看CMS收集器整个GC事件的日志:

2015-05-26T16:23:07.321-0200: 64.425: [GC (CMS Initial Mark) 
[1 CMS-initial-mark: 10812086K(11901376K)]
10887844K(12514816K), 0.0001997 secs]
[Times: user=0.00 sys=0.00, real=0.00 secs]
2015-05-26T16:23:07.321-0200: 64.425: [CMS-concurrent-mark-start]
2015-05-26T16:23:07.357-0200: 64.460: [CMS-concurrent-mark: 0.035/0.035 secs]
[Times: user=0.07 sys=0.00, real=0.03 secs]
2015-05-26T16:23:07.357-0200: 64.460: [CMS-concurrent-preclean-start]
2015-05-26T16:23:07.373-0200: 64.476: [CMS-concurrent-preclean: 0.016/0.016 secs]
[Times: user=0.02 sys=0.00, real=0.02 secs]
2015-05-26T16:23:07.373-0200: 64.476: [CMS-concurrent-abortable-preclean-start]
2015-05-26T16:23:08.446-0200: 65.550: [CMS-concurrent-abortable-preclean: 0.167/1.074 secs]
[Times: user=0.20 sys=0.00, real=1.07 secs]
2015-05-26T16:23:08.447-0200: 65.550: [GC (CMS Final Remark)
[YG occupancy: 387920 K (613440 K)]
65.550: [Rescan (parallel) , 0.0085125 secs]
65.559: [weak refs processing, 0.0000243 secs]
65.559: [class unloading, 0.0013120 secs]
65.560: [scrub symbol table, 0.0008345 secs]
65.561: [scrub string table, 0.0001759 secs]
[1 CMS-remark: 10812086K(11901376K)]
11200006K(12514816K), 0.0110730 secs]
[Times: user=0.06 sys=0.00, real=0.01 secs]
2015-05-26T16:23:08.458-0200: 65.561: [CMS-concurrent-sweep-start]
2015-05-26T16:23:08.485-0200: 65.588: [CMS-concurrent-sweep: 0.027/0.027 secs]
[Times: user=0.03 sys=0.00, real=0.03 secs]
2015-05-26T16:23:08.485-0200: 65.589: [CMS-concurrent-reset-start]
2015-05-26T16:23:08.497-0200: 65.601: [CMS-concurrent-reset: 0.012/0.012 secs]
[Times: user=0.01 sys=0.00, real=0.01 secs]


仅仅是要记住 —— 在实际情况下, 进行老年代的并发回收时, 可能会伴随着多次年轻代的小型GC. 在这样的情况下, 大型GC的日志中就会掺杂着多次小型GC事件, 像前面所介绍的一样。

阶段 1: Initial Mark(初始标记). 这是第一次STW事件。

此阶段的目标是标记老年代中全部存活的对象, 包含 GC ROOR 的直接引用, 以及由年轻代中存活对象所引用的对象。

后者也非常重要, 由于老年代是独立进行回收的。

4. GC 算法(实现篇) - GC參考手冊_垃圾收集器_06

​2015-05-26T16:23:07.321-0200: 64.42​1: [GC (​​CMS Initial Mark​1

[1 CMS-initial-mark: ​​10812086K​1​(11901376K)​1] ​​10887844K​1​(12514816K)​1,

​0.0001997 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]​1


>

  1. ​2015-05-26T16:23:07.321-0200: 64.42​​ – GC事件開始的时间. 当中 ​​-0200​​ 是时区,而中国所在的东8区为 +0800。 而 64.42 是相对于JVM启动的时间。 以下的其它阶段也是一样,所以就不再反复介绍。
  2. ​CMS Initial Mark​​ – 垃圾回收的阶段名称为 “Initial Mark”。 标记全部的 GC Root。
  3. ​10812086K​​ – 老年代的当前使用量。
  4. ​(11901376K)​​ – 老年代中可用内存总量。
  5. ​10887844K​​ – 当前堆内存的使用量。
  6. ​(12514816K)​​ – 可用堆的总大小。
  7. ​0.0001997 secs] [Times: user=0.00 sys=0.00, real=0.00 secs]​​ – 此次暂停的持续时间, 以 user, system 和 real time 3个部分进行衡量。

阶段 2: Concurrent Mark(并发标记). 在此阶段, 垃圾收集器遍历老年代, 标记全部的存活对象, 从前一阶段 “Initial Mark” 找到的 root 根開始算起。

顾名思义, “并发标记”阶段, 就是与应用程序同一时候运行,不用暂停的阶段。 请注意, 并非全部老年代中存活的对象都在此阶段被标记, 由于在标记过程中对象的引用关系还在发生变化。

4. GC 算法(实现篇) - GC參考手冊_垃圾收集器_07

在上面的示意图中, “Current object” 旁边的一个引用被标记线程并发删除了。

2015-05-26T16:23:07.321-0200: 64.425: [CMS-concurrent-mark-start]

2015-05-26T16:23:07.357-0200: 64.460: [​​CMS-concurrent-mark​1: ​​035/0.035 secs​1]

​[Times: user=0.07 sys=0.00, real=0.03 secs]​1


>

  1. ​CMS-concurrent-mark​​ – 并发标记(“Concurrent Mark”) 是CMS垃圾收集中的一个阶段, 遍历老年代并标记全部的存活对象。
  2. ​035/0.035 secs​​ – 此阶段的持续时间, 各自是运行时间和相应的实际时间。
  3. ​[Times: user=0.07 sys=0.00, real=0.03 secs]​​ – ​​Times​​ 这部分对并发阶段来说没多少意义, 由于是从并发标记開始时计算的,而这段时间内不仅并发标记在运行,程序也在运行

阶段 3: Concurrent Preclean(并发预清理). 此阶段相同是与应用线程并行运行的, 不须要停止应用线程。

由于前一阶段是与程序并发进行的,可能有一些引用已经改变。假设在并发标记过程中发生了引用关系变化,JVM会(通过“Card”)将发生了改变的区域标记为“脏”区(这就是所谓的​​卡片标记,Card Marking​​)。

4. GC 算法(实现篇) - GC參考手冊_垃圾收集器_08

在预清理阶段,这些脏对象会被统计出来,从他们可达的对象也被标记下来。此阶段完毕后, 用以标记的 card 也就被清空了。

4. GC 算法(实现篇) - GC參考手冊_垃圾收集器_09

此外, 本阶段也会运行一些必要的细节处理, 并为 Final Remark 阶段做一些准备工作。

>

2015-05-26T16:23:07.357-0200: 64.460: [CMS-concurrent-preclean-start]

2015-05-26T16:23:07.373-0200: 64.476: [​​CMS-concurrent-preclean​​: ​​0.016/0.016 secs​​] ​​[Times: user=0.02 sys=0.00, real=0.02 secs]​


>

  1. ​CMS-concurrent-preclean​​ – 并发预清理阶段, 统计此前的标记阶段中发生了改变的对象。
  2. ​0.016/0.016 secs​​ – 此阶段的持续时间, 各自是运行时间和相应的实际时间。
  3. ​[Times: user=0.02 sys=0.00, real=0.02 secs]​​ – Times 这部分对并发阶段来说没多少意义, 由于是从并发标记開始时计算的,而这段时间内不仅GC的并发标记在运行,程序也在运行。

阶段 4: Concurrent Abortable Preclean(并发可取消的预清理). 此阶段也不停止应用线程. 本阶段尝试在 STW 的 Final Remark 之前尽可能地多做一些工作。

本阶段的详细时间取决于多种因素, 由于它循环做相同的事情,直到满足某个退出条件( 如迭代次数, 实用工作量, 消耗的系统时间,等等)。

>

2015-05-26T16:23:07.373-0200: 64.476: [CMS-concurrent-abortable-preclean-start]

2015-05-26T16:23:08.446-0200: 65.550: [CMS-concurrent-abortable-preclean1: 0.167/1.074 secs2][Times: user=0.20 sys=0.00, real=1.07 secs]3


>

  1. ​CMS-concurrent-abortable-preclean​​ – 此阶段的名称: “Concurrent Abortable Preclean”。
  2. 0.167/1.074 secs – 此阶段的持续时间, 运行时间和相应的实际时间。有趣的是, 用户时间明显比时钟时间要小非常多。
    通常情况下我们看到的都是时钟时间小于用户时间, 这意味着由于有一些并行工作, 所以运行时间才会小于使用的CPU时间。这里仅仅进行了少量的工作 — 0.167秒的CPU时间,GC线程经历了非常多系统等待。从本质上讲,GC线程试图在必须运行 STW暂停之前等待尽可能长的时间。默认条件下,此阶段能够持续最多5秒钟。
  3. [Times: user=0.20 sys=0.00, real=1.07 secs] – “Times” 这部分对并发阶段来说没多少意义, 由于是从并发标记開始时计算的,而这段时间内不仅GC的并发标记线程在运行,程序也在运行

此阶段可能显著影响STW停顿的持续时间, 并且有很多重要的​​配置选项​​和失败模式。

阶段 5: Final Remark(终于标记). 这是此次GC事件中第二次(也是最后一次)STW阶段。

本阶段的目标是完毕老年代中全部存活对象的标记. 由于之前的 preclean 阶段是并发的, 有可能无法跟上应用程序的变化速度。所以须要 STW暂停来处理复杂情况。

通常CMS会尝试在年轻代尽可能空的情况运行 final remark 阶段, 以免接连多次发生 STW 事件。

看起来略微比之前的阶段要复杂一些:

​2015-05-26T16:23:08.447-0200: 65.550​​: [GC (​​CMS Final Remark​​) [​​YG occupancy: 387920 K (613440 K)​​]
65.550: ​​[Rescan (parallel) , 0.0085125 secs]​​ 65.559: [​​weak refs processing, 0.0000243 secs]65.559​​: [​​class unloading, 0.0013120 secs]65.560​​: [​​scrub string table, 0.0001759 secs​​]
[1 CMS-remark: ​​10812086K(11901376K)​​] ​​11200006K(12514816K)​​,​​0.0110730 secs​​] ​​[Times: user=0.06 sys=0.00, real=0.01 secs]​


>

  1. ​2015-05-26T16:23:08.447-0200: 65.550​​ – GC事件開始的时间. 包含时钟时间,以及相对于JVM启动的时间. 当中​​-0200​​表示西二时区,而中国所在的东8区为 ​​+0800​​。
  2. ​CMS Final Remark​​ – 此阶段的名称为 “Final Remark”, 标记老年代中全部存活的对象。包含在此前的并发标记过程中创建/改动的引用。
  3. ​YG occupancy: 387920 K (613440 K)​​ – 当前年轻代的使用量和总容量。
  4. ​[Rescan (parallel) , 0.0085125 secs]​​ – 在程序暂停时又一次进行扫描(Rescan),以完毕存活对象的标记。此时 rescan 是并行运行的,消耗的时间为 0.0085125秒
  5. ​weak refs processing, 0.0000243 secs]65.559​​ – 处理弱引用的第一个子阶段(sub-phases)。 显示的是持续时间和開始时间戳。
  6. ​class unloading, 0.0013120 secs]65.560​​ – 第二个子阶段, 卸载不使用的类。 显示的是持续时间和開始的时间戳。
  7. ​scrub string table, 0.0001759 secs​​ – 最后一个子阶段, 清理持有class级别 metadata 的符号表(symbol tables),以及内部化字符串相应的 string tables。当然也显示了暂停的时钟时间。
  8. ​10812086K(11901376K)​​ – 此阶段完毕后老年代的使用量和总容量
  9. ​11200006K(12514816K)​​ – 此阶段完毕后整个堆内存的使用量和总容量
  10. ​0.0110730 secs​​ – 此阶段的持续时间。
  11. ​[Times: user=0.06 sys=0.00, real=0.01 secs]​​ – GC事件的持续时间, 通过不同的类别来衡量: user, system and real time。

在5个标记阶段完毕之后, 老年代中全部的存活对象都被标记了, 如今GC将清除全部不使用的对象来回收老年代空间:

阶段 6: Concurrent Sweep(并发清除). 此阶段与应用程序并发运行,不须要STW停顿。目的是删除未使用的对象,并收回他们占用的空间。

4. GC 算法(实现篇) - GC參考手冊_堆区_10

>

2015-05-26T16:23:08.458-0200: 65.561: [CMS-concurrent-sweep-start] 2015-05-26T16:23:08.485-0200: 65.588: [​​CMS-concurrent-sweep​​: ​​0.027/0.027 secs​​] ​​[Times: user=0.03 sys=0.00, real=0.03 secs]​


>

  1. ​CMS-concurrent-sweep​​ – 此阶段的名称, “Concurrent Sweep”, 清除未被标记、不再使用的对象以释放内存空间。
  2. ​0.027/0.027 secs​​ – 此阶段的持续时间, 各自是运行时间和实际时间
  3. ​[Times: user=0.03 sys=0.00, real=0.03 secs]​​ – “Times”部分对并发阶段来说没有多少意义, 由于是从并发标记開始时计算的,而这段时间内不仅是并发标记在运行,程序也在运行。

阶段 7: Concurrent Reset(并发重置). 此阶段与应用程序并发运行,重置CMS算法相关的内部数据, 为下一次GC循环做准备。

>

2015-05-26T16:23:08.485-0200: 65.589: [CMS-concurrent-reset-start] 2015-05-26T16:23:08.497-0200: 65.601: [​​CMS-concurrent-reset​​: ​​0.012/0.012 secs​​] ​​[Times: user=0.01 sys=0.00, real=0.01 secs]​


>

  1. ​CMS-concurrent-reset​​ – 此阶段的名称, “Concurrent Reset”, 重置CMS算法的内部数据结构, 为下一次GC循环做准备。
  2. ​0.012/0.012 secs​​ – 此阶段的持续时间, 各自是运行时间和相应的实际时间
  3. ​[Times: user=0.01 sys=0.00, real=0.01 secs]​​ – “Times”部分对并发阶段来说没多少意义, 由于是从并发标记開始时计算的,而这段时间内不仅GC线程在运行,程序也在运行。

总之, CMS垃圾收集器在降低停顿时间上做了非常多给力的工作, 大量的并发线程运行的工作并不须要暂停应用线程。 当然, CMS也有一些缺点,当中最大的问题就是老年代内存碎片问题, 在某些情况下GC会造成不可预測的暂停时间, 特别是堆内存较大的情况下。

G1 – Garbage First(垃圾优先算法)

G1最基本的设计目标是: 将STW停顿的时间和分布变成可预期以及可配置的。其实, G1是一款软实时垃圾收集器, 也就是说能够为其设置某项特定的性能指标. 能够指定: 在随意 ​​xx​​ 毫秒的时间范围内, STW停顿不得超过 ​​x​​ 毫秒。 如: 随意1秒暂停时间不得超过5毫秒. Garbage-First GC 会尽力达成这个目标(有非常大的概率会满足, 但并不全然确定,详细是多少将是硬实时的[hard real-time])。

为了达成这项指标, G1 有一些独特的实现。首先, 堆不再分成连续的年轻代和老年代空间。而是划分为多个(一般是2048个)能够存放对象的 小堆区(smaller heap regions)

每一个小堆区都可能是 Eden区, Survivor区或者Old区. 在逻辑上, 全部的Eden区和Survivor区合起来就是年轻代, 全部的Old区拼在一起那就是老年代:

4. GC 算法(实现篇) - GC參考手冊_垃圾收集_11

这样的划分使得 GC不必每次都去收集整个堆空间, 而是以增量的方式来处理: 每次仅仅处理一部分小堆区,称为此次的回收集(collection set). 每次暂停都会收集全部年轻代的小堆区, 但可能仅仅包含一部分老年代小堆区:

4. GC 算法(实现篇) - GC參考手冊_堆区_12

G1的还有一项创新, 是在并发阶段估算每一个小堆区存活对象的总数。用来构建回收集(collection set)的原则是: 垃圾最多的小堆区会被优先收集

这也是G1名称的由来: garbage-first。

要启用G1收集器, 使用的命令行參数为:

java -XX:+UseG1GC com.mypackages.MyExecutableClass


Evacuation Pause: Fully Young(转移暂停:纯年轻代模式)

在应用程序刚启动时, G1还未运行过(not-yet-executed)并发阶段, 也就没有获得不论什么额外的信息, 处于初始的 fully-young 模式. 在年轻代空间用满之后, 应用线程被暂停, 年轻代堆区中的存活对象被拷贝到存活区, 假设还没有存活区,则选择随意一部分空暇的小堆区用作存活区。

复制的过程称为转移(Evacuation), 这和前面讲过的年轻代收集器基本上是一样的工作原理。转移暂停的日志信息非常长,为简单起见, 我们去除了一些不重要的信息. 在并发阶段之后我们会进行详细的解说。此外, 由于日志记录非常多, 所以并行阶段和“其它”阶段的日志将拆分为多个部分来进行解说:

​0.134: [GC pause (G1 Evacuation Pause) (young), 0.0144119 secs]​

​[Parallel Time: 13.9 ms, GC Workers: 8]​


[Code Root Fixup: 0.0 ms]

[Code Root Purge: 0.0 ms]

[Clear CT: 0.1 ms]

[Other: 0.4 ms]


[Eden: 24.0M(24.0M)->0.0B(13.0M) Survivors: 0.0B->3072.0K Heap: 24.0M(256.0M)->21.9M(256.0M)]
[Times: user=0.04 sys=0.04, real=0.02 secs]


>

  1. ​0.134: [GC pause (G1 Evacuation Pause) (young), 0.0144119 secs]​​ – G1转移暂停,仅仅清理年轻代空间。暂停在JVM启动之后 134 ms 開始, 持续的系统时间为 0.0144秒
  2. ​[Parallel Time: 13.9 ms, GC Workers: 8]​​ – 表明后面的活动由8个 Worker 线程并行运行, 消耗时间为13.9毫秒(real time)。
  3. ​…​​ – 为阅读方便, 省略了部分内容,请參考后文。
  4. ​[Code Root Fixup: 0.0 ms]​​ – 释放用于管理并行活动的内部数据。一般都接近于零。这是串行运行的过程。
  5. ​[Code Root Purge: 0.0 ms]​​ – 清理其它部分数据, 也是非常快的, 但如非必要则差点儿等于零。这是串行运行的过程。
  6. ​[Other: 0.4 ms]​​ – 其它活动消耗的时间, 当中有非常多是并行运行的。
  7. ​…​​ – 请參考后文。
  8. ​[Eden: 24.0M(24.0M)->0.0B(13.0M)​​ – 暂停之前和暂停之后, Eden 区的使用量/总容量。
  9. ​Survivors: 0.0B->3072.0K​​ – 暂停之前和暂停之后, 存活区的使用量。
  10. ​Heap: 24.0M(256.0M)->21.9M(256.0M)]​​ – 暂停之前和暂停之后, 整个堆内存的使用量与总容量。
  11. ​[Times: user=0.04 sys=0.04, real=0.02 secs]​​ – GC事件的持续时间, 通过三个部分来衡量:
  • user – 在此次垃圾回收过程中, 由GC线程所消耗的总的CPU时间。
  • sys – GC过程中, 系统调用和系统等待事件所消耗的时间。
  • real – 应用程序暂停的时间。在并行GC(Parallel GC)中, 这个数字约等于: (user time + system time)/GC线程数。 这里使用的是8个线程。 请注意,总是有一定比例的处理过程是不能并行化的。

说明: 系统时间(wall clock time, elapsed time), 是指一段程序从运行到终止。系统时钟走过的时间。一般来说,系统时间都是要大于CPU时间

最繁重的GC任务由多个专用的 worker 线程来运行。以下的日志描写叙述了他们的行为:

​[Parallel Time: 13.9 ms, GC Workers: 8]​

​[GC Worker Start (ms)​​: Min: 134.0, Avg: 134.1, Max: 134.1, Diff: 0.1]

​[Ext Root Scanning (ms)​​: Min: 0.1, Avg: 0.2, Max: 0.3, Diff: 0.2, Sum: 1.2]
[Update RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]

[Processed Buffers: Min: 0, Avg: 0.0, Max: 0, Diff: 0, Sum: 0]

[Scan RS (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]

​[Code Root Scanning (ms)​​: Min: 0.0, Avg: 0.0, Max: 0.2, Diff: 0.2, Sum: 0.2]

​[Object Copy (ms)​​: Min: 10.8, Avg: 12.1, Max: 12.6, Diff: 1.9, Sum: 96.5]

​[Termination (ms)​​: Min: 0.8, Avg: 1.5, Max: 2.8, Diff: 1.9, Sum: 12.2]

​[Termination Attempts​​: Min: 173, Avg: 293.2, Max: 362, Diff: 189, Sum: 2346]

​[GC Worker Other (ms)​​: Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.1]

​GC Worker Total (ms)​​: Min: 13.7, Avg: 13.8, Max: 13.8, Diff: 0.1, Sum: 110.2]

​[GC Worker End (ms)​​: Min: 147.8, Avg: 147.8, Max: 147.8, Diff: 0.0]


>

  1. ​[Parallel Time: 13.9 ms, GC Workers: 8]​​ – 表明下列活动由8个线程并行运行,消耗的时间为13.9毫秒(real time)。
  2. ​[GC Worker Start (ms)​​ – GC的worker线程開始启动时,相对于 pause 開始的时间戳。假设 ​​Min​​ 和 ​​Max​​ 差别非常大,则表明本机其它进程所使用的线程数量过多, 挤占了GC的CPU时间。
  3. ​[Ext Root Scanning (ms)​​ – 用了多长时间来扫描堆外(non-heap)的root, 如 classloaders, JNI引用, JVM的系统root等。后面显示了运行时间, “Sum” 指的是CPU时间。
  4. ​[Code Root Scanning (ms)​​ – 用了多长时间来扫描实际代码中的 root: 比如局部变量等等(local vars)。
  5. ​[Object Copy (ms)​​ – 用了多长时间来拷贝收集区内的存活对象。
  6. ​[Termination (ms)​​ – GC的worker线程用了多长时间来确保自身能够安全地停止, 这段时间什么也不用做, stop 之后该线程就终止运行了。
  7. ​[Termination Attempts​​ – GC的worker 线程尝试多少次 try 和 teminate。假设worker发现还有一些任务没处理完,则这一次尝试就是失败的, 临时还不能终止。
  8. ​[GC Worker Other (ms)​​ – 一些琐碎的小活动,在GC日志中不值得单独列出来。
  9. ​GC Worker Total (ms)​​ – GC的worker 线程的工作时间总计。
  10. ​[GC Worker End (ms)​​ – GC的worker 线程完毕作业的时间戳。通常来说这部分数字应该大致相等, 否则就说明有太多的线程被挂起, 非常可能是由于​​坏邻居效应(noisy neighbor)​​ 所导致的。

此外,在转移暂停期间,还有一些琐碎运行的小活动。这里我们仅仅介绍当中的一部分, 其余的会在后面进行讨论。

​[Other: 0.4 ms]​

[Choose CSet: 0.0 ms]

​[Ref Proc: 0.2 ms]​

​[Ref Enq: 0.0 ms]​

[Redirty Cards: 0.1 ms]

[Humongous Register: 0.0 ms]

[Humongous Reclaim: 0.0 ms]

​[Free CSet: 0.0 ms]​


>

  1. ​[Other: 0.4 ms]​​ – 其它活动消耗的时间, 当中有非常多也是并行运行的。
  2. ​[Ref Proc: 0.2 ms]​​ – 处理非强引用(non-strong)的时间: 进行清理或者决定是否须要清理。
  3. ​[Ref Enq: 0.0 ms]​​ – 用来将剩下的 non-strong 引用排列到合适的 ReferenceQueue中。
  4. ​[Free CSet: 0.0 ms]​​ – 将回收集中被释放的小堆归还所消耗的时间, 以便他们能用来分配新的对象。

Concurrent Marking(并发标记)

G1收集器的非常多概念建立在CMS的基础上,所以以下的内容须要你对CMS有一定的理解. 尽管也有非常多地方不同, 但并发标记的目标基本上是一样的. G1的并发标记通过 Snapshot-At-The-Beginning(開始时快照) 的方式, 在标记阶段開始时记下全部的存活对象。

即使在标记的同一时候又有一些变成了垃圾. 通过对象是存活信息, 能够构建出每一个小堆区的存活状态, 以便回收集能高效地进行选择。

这些信息在接下来的阶段会用来运行老年代区域的垃圾收集。在两种情况下是全然地并发运行的: 一、假设在标记阶段确定某个小堆区仅仅包含垃圾; 二、在STW转移暂停期间, 同一时候包含垃圾和存活对象的老年代小堆区。

当堆内存的整体使用比例达到一定数值时,就会触发并发标记。

默认值为 ​​45%​​, 但也能够通过JVM參数 ​InitiatingHeapOccupancyPercent​ 来设置。

和CMS一样, G1的并发标记也是由多个阶段组成, 当中一些是全然并发的, 还有一些阶段须要暂停应用线程。

阶段 1: Initial Mark(初始标记)。

此阶段标记全部从GC root 直接可达的对象。

在CMS中须要一次STW暂停, 但G1里面一般是在转移暂停的同一时候处理这些事情, 所以它的开销是非常小的. 能够在 Evacuation Pause 日志中的第一行看到(initial-mark)暂停:

1.631: [GC pause (G1 Evacuation Pause) (young) (initial-mark), 0.0062656 secs]


阶段 2: Root Region Scan(Root区扫描). 此阶段标记全部从 “根区域” 可达的存活对象。

根区域包含: 非空的区域, 以及在标记过程中不得不收集的区域。由于在并发标记的过程中迁移对象会造成非常多麻烦, 所以此阶段必须在下一次转移暂停之前完毕。假设必须启动转移暂停, 则会先要求根区域扫描中止, 等它完毕才干继续扫描. 在当前版本号的实现中, 根区域是存活的小堆区: y包含下一次转移暂停中肯定会被清理的那部分年轻代小堆区。

1.362: [GC concurrent-root-region-scan-start]
1.364: [GC concurrent-root-region-scan-end, 0.0028513 secs]


阶段 3: Concurrent Mark(并发标记). 此阶段非常相似于CMS: 它仅仅是遍历对象图, 并在一个特殊的位图中标记能訪问到的对象. 为了确保标记開始时的快照准确性, 全部应用线程并发对对象图运行的引用更新,G1 要求放弃前面阶段为了标记目的而引用的过时引用。

这是通过使用 ​​Pre-Write​​ 屏障来实现的,(不要和之后介绍的 ​​Post-Write​​ 混淆, 也不要和多线程开发中的内存屏障(memory barriers)相混淆)。Pre-Write屏障的作用是: G1在进行并发标记时, 假设程序将对象的某个属性做了变更, 就会在 log buffers 中存储之前的引用。 由并发标记线程负责处理。

1.364: [GC concurrent-mark-start]
1.645: [GC co ncurrent-mark-end, 0.2803470 secs]


阶段 4: Remark(再次标记). 和CMS相似,这也是一次STW停顿,以完毕标记过程。对于G1,它短暂地停止应用线程, 停止并发更新日志的写入, 处理当中的少量信息, 并标记全部在并发标记開始时未被标记的存活对象。这一阶段也运行某些额外的清理, 如引用处理(參见 Evacuation Pause log) 或者类卸载(class unloading)。

1.645: [GC remark 1.645: [Finalize Marking, 0.0009461 secs]
1.646: [GC ref-proc, 0.0000417 secs] 1.646:
[Unloading, 0.0011301 secs], 0.0074056 secs]
[Times: user=0.01 sys=0.00, real=0.01 secs]


阶段 5: Cleanup(清理). 最后这个小阶段为即将到来的转移阶段做准备, 统计小堆区中全部存活的对象, 并将小堆区进行排序, 以提升GC的效率. 此阶段也为下一次标记运行全部必需的整理工作(house-keeping activities): 维护并发标记的内部状态。

最后要提醒的是, 全部不包含存活对象的小堆区在此阶段都被回收了。

有一部分是并发的: 比如空堆区的回收,还有大部分的存活率计算, 此阶段也须要一个短暂的STW暂停, 以不受应用线程的影响来完毕作业. 这样的STW停顿的日志例如以下:

1.652: [GC cleanup 1213M->1213M(1885M), 0.0030492 secs]
[Times: user=0.01 sys=0.00, real=0.00 secs]


假设发现某些小堆区中仅仅包含垃圾, 则日志格式可能会有点不同, 如:

1.872: [GC cleanup 1357M->173M(1996M), 0.0015664 secs]

[Times: user=0.01 sys=0.00, real=0.01 secs]

1.874: [GC concurrent-cleanup-start]

1.876: [GC concurrent-cleanup-end, 0.0014846 secs]

Evacuation Pause: Mixed (转移暂停: 混合模式)

能并发清理老年代中整个整个的小堆区是一种最优情形, 但有时候并非这样。并发标记完毕之后, G1将运行一次混合收集(mixed collection), 不仅仅清理年轻代, 还将一部分老年代区域也加入到 collection set 中。

混合模式的转移暂停(Evacuation pause)不一定紧跟着并发标记阶段。有非常多规则和历史数据会影响混合模式的启动时机。比方, 假若在老年代中能够并发地腾出非常多的小堆区,就没有必要启动混合模式。

因此, 在并发标记与混合转移暂停之间, 非常可能会存在多次 fully-young 转移暂停。

加入到回收集的老年代小堆区的详细数字及其顺序, 也是基于很多规则来判定的。 当中包含指定的软实时性能指标, 存活性,以及在并发标记期间收集的GC效率等数据, 外加一些可配置的JVM选项. 混合收集的过程, 非常大程度上和前面的 fully-young gc 是一样的, 但这里我们还要介绍一个概念: remembered sets(历史记忆集)。

Remembered sets (历史记忆集)是用来支持不同的小堆区进行独立回收的。比如,在收集A、B、C区时, 我们必须要知道是否有从D区或者E区指向当中的引用, 以确定他们的存活性. 可是遍历整个堆须要相当长的时间, 这就违背了增量收集的初衷, 因此必须採取某种优化手段. 其它GC算法有独立的 Card Table 来支持年轻代的垃圾收集一样, 而G1中使用的是 Remembered Sets。

例如以下图所看到的, 每一个小堆区都有一个 remembered set, 列出了从外部指向本区的全部引用。这些引用将被视为附加的 GC root. 注意,在并发标记过程中,老年代中被确定为垃圾的对象会被忽略, 即使有外部引用指向他们: 由于在这样的情况下引用者也是垃圾。

4. GC 算法(实现篇) - GC參考手冊_堆内存_13

接下来的行为,和其它垃圾收集器一样: 多个GC线程并行地找出哪些是存活对象,确定哪些是垃圾:

4. GC 算法(实现篇) - GC參考手冊_堆区_14

最后, 存活对象被转移到存活区(survivor regions), 在必要时会创建新的小堆区。

如今,空的小堆区被释放, 可用于存放新的对象了。

4. GC 算法(实现篇) - GC參考手冊_堆区_15

为了维护 remembered set, 在程序运行的过程中, 仅仅要写入某个字段,就会产生一个 Post-Write 屏障。

假设生成的引用是跨区域的(cross-region),即从一个区指向还有一个区, 就会在目标区的Remembered Set中,出现一个相应的条目。为了降低 Write Barrier 造成的开销, 将卡片放入Remembered Set 的过程是异步的, 并且经过了非常多的优化. 整体上是这样: Write Barrier 把脏卡信息存放到本地缓冲区(local buffer), 有专门的GC线程负责收集, 并将相关信息传给被引用区的 remembered set。

混合模式下的日志, 和纯年轻代模式相比, 能够发现一些有趣的地方:

[​​[Update RS (ms)​​: Min: 0.7, Avg: 0.8, Max: 0.9, Diff: 0.2, Sum: 6.1]

​[Processed Buffers​​: Min: 0, Avg: 2.2, Max: 5, Diff: 5, Sum: 18]

​[Scan RS (ms)​​: Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.2, Sum: 0.8]

​[Clear CT: 0.2 ms]​

​[Redirty Cards: 0.1 ms]​



1. ​​[Update RS (ms)​​ – 由于 Remembered Sets 是并发处理的,必须确保在实际的垃圾收集之前, 缓冲区中的 card 得到处理。假设card数量非常多, 则GC并发线程的负载可能就会非常高。可能的原因是, 改动的字段过多, 或者CPU资源受限。
  1. ​[Processed Buffers​​ – 每一个 worker 线程处理了多少个本地缓冲区(local buffer)。
  2. ​[Scan RS (ms)​​ – 用了多长时间扫描来自RSet的引用。
  3. ​[Clear CT: 0.2 ms]​​ – 清理 card table 中 cards 的时间。清理工作仅仅是简单地删除“脏”状态, 此状态用来标识一个字段是否被更新的, 供Remembered Sets使用。
  4. ​[Redirty Cards: 0.1 ms]​​ – 将 card table 中适当的位置标记为 dirty 所花费的时间。”适当的位置”是由GC本身运行的堆内存改变所决定的, 比如引用排队等。

总结

通过本节内容的学习, 你应该对G1垃圾收集器有了一定了解。

当然, 为了简洁, 我们省略了非常多实现细节。 比如怎样处理​​巨无霸对象(humongous objects)​​。

综合来看, G1是HotSpot中最先进的准产品级(production-ready)垃圾收集器。重要的是, HotSpot project师的主要精力都放在不断改进G1上面, 在新的java版本号中,将会带来新的功能和优化。

能够看到, G1 攻克了 CMS 中的各种疑难问题, 包含暂停时间的可预測性, 并终结了堆内存的碎片化。

对单业务延迟非常敏感的系统来说, 假设CPU资源不受限制,那么G1能够说是 HotSpot 中最好的选择, 特别是在最新版本号的Java虚拟机中。

当然,这样的降低延迟的优化也不是没有代价的: 由于额外的写屏障(write barriers)和更积极的守护线程, G1的开销会更大。

所以, 假设系统属于吞吐量优先型的, 又或者CPU持续占用100%, 而又不在乎单次GC的暂停时间, 那么CMS是更好的选择。

总之: G1适合大内存,须要低延迟的场景

选择正确的GC算法,唯一可行的方式就是去尝试,并找出不正确劲的地方, 在下一章我们将给出一般指导原则。

注意,G1可能会成为Java 9的默认GC: ​​http://openjdk.java.net/jeps/248​

Shenandoah 的性能

译注: Shenandoah: 谢南多厄河; 情人渡,水手谣; –> 此款GC临时没有标准的中文译名; 翻译为大水手垃圾收集器?

我们列出了HotSpot中可用的全部 “准生产级” 算法。

还有一种还在实验室中的算法, 称为超低延迟垃圾收集器(Ultra-Low-Pause-Time Garbage Collector). 它的设计目标是管理大型的多核server上,超大型的堆内存: 管理 100GB 及以上的堆容量, GC暂停时间小于 10ms。 当然,也是须要和吞吐量进行权衡的: 没有GC暂停的时候,算法的实现对吞吐量的性能损失不能超过10%

在新算法作为准产品级进行公布之前, 我们不准备去讨论详细的实现细节, 但它也构建在前面所提到的非常多算法的基础上, 比如并发标记和增量收集。但当中有非常多东西是不同的。

它不再将堆内存划分成多个代, 而是仅仅採用单个空间. 没错, Shenandoah 并非一款分代垃圾收集器。

这也就不再须要 card tables 和 remembered sets. 它还使用转发指针(forwarding pointers), 以及Brooks 风格的读屏障(Brooks style read barrier), 以同意对存活对象的并发复制, 从而降低GC暂停的次数和时间。