{Heap before GC invocations=100440 (full 0):
garbage-first heap total 10485760K, used 6343139K [0x0000000540000000, 0x0000000540405000, 0x00000007c0000000)
region size 4096K, 864 young (3538944K), 2 survivors (8192K)
Metaspace used 115295K, capacity 119550K, committed 119808K, reserved 1155072K
class space used 13540K, capacity 14319K, committed 14336K, reserved 1048576K
2021-05-13T12:19:13.340+0800: 1269645.332: [GC pause (G1 Evacuation Pause) (young), 0.0311615 secs]
[Parallel Time: 22.8 ms, GC Workers: 8]
[GC Worker Start (ms): Min: 1269645334.1, Avg: 1269645334.2, Max: 1269645334.2, Diff: 0.2]
[Ext Root Scanning (ms): Min: 4.2, Avg: 5.1, Max: 9.1, Diff: 4.9, Sum: 41.0]
[Update RS (ms): Min: 3.7, Avg: 7.6, Max: 8.5, Diff: 4.8, Sum: 60.6]
[Processed Buffers: Min: 98, Avg: 200.9, Max: 235, Diff: 137, Sum: 1607]
[Scan RS (ms): Min: 1.8, Avg: 2.0, Max: 2.1, Diff: 0.3, Sum: 16.1]
[Code Root Scanning (ms): Min: 0.1, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.8]
[Object Copy (ms): Min: 7.2, Avg: 7.6, Max: 7.8, Diff: 0.6, Sum: 60.4]
[Termination (ms): Min: 0.0, Avg: 0.1, Max: 0.1, Diff: 0.1, Sum: 0.4]
[Termination Attempts: Min: 1, Avg: 46.6, Max: 65, Diff: 64, Sum: 373]
[GC Worker Other (ms): Min: 0.0, Avg: 0.1, Max: 0.2, Diff: 0.1, Sum: 0.8]
[GC Worker Total (ms): Min: 22.4, Avg: 22.5, Max: 22.6, Diff: 0.2, Sum: 180.1]
[GC Worker End (ms): Min: 1269645356.6, Avg: 1269645356.7, Max: 1269645356.7, Diff: 0.1]
[Code Root Fixup: 0.2 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 0.6 ms]
[Other: 7.6 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 3.4 ms]
[Ref Enq: 0.2 ms]
[Redirty Cards: 0.2 ms]
[Humongous Register: 0.1 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 1.0 ms]
[Eden: 3448.0M(2676.0M)->0.0B(2040.0M) Survivors: 8192.0K->16.0M Heap: 6194.5M(10.0G)->2755.2M(10.0G)]
Heap after GC invocations=100441 (full 0):
garbage-first heap total 10485760K, used 2821346K [0x0000000540000000, 0x0000000540405000, 0x00000007c0000000)
region size 4096K, 4 young (16384K), 4 survivors (16384K)
Metaspace used 115295K, capacity 119550K, committed 119808K, reserved 1155072K
class space used 13540K, capacity 14319K, committed 14336K, reserved 1048576K
}
[Times: user=0.18 sys=0.02, real=9.65 secs]

我看这个gc是0.03秒，为啥实际是9.65秒？