zgc-dev

Download

zgc-dev@openjdk.org

July 2022

2 participants
2 discussions

Allocation rate which calculated by com.sun.management.GcInfo is bigger than Memory Allocation Rate(in zgc gc statistics)
by wangkang 13 Jul '22

13 Jul '22

1 0

Pause Mark Start took a long time
by kang wangk 13 Jul '22

13 Jul '22

Hi, I am running ZGC in production(Deployed in k8s) with following settings -Xms4000m -Xmx4000m -XX:ConcGCThreads=2 -XX:-UseDynamicNumberOfGCThreads -XX:+UseZGC -XX:-ZProactive -XX:ZAllocationSpikeTolerance=3 -XX:ZCollectionInterval=60 Occasionally encounter a long Pause Mark Start time(the longest pause time is 5s) Here are some GC logs blow [2022-07-12T10:52:52.242+0800][10][info][gc,init] Initializing The Z Garbage Collector [2022-07-12T10:52:52.242+0800][10][info][gc,init] Version: 17.0.3+8-LTS-111 (release) [2022-07-12T10:52:52.242+0800][10][info][gc,init] NUMA Support: Disabled [2022-07-12T10:52:52.242+0800][10][info][gc,init] CPUs: 64 total, 4 available [2022-07-12T10:52:52.242+0800][10][info][gc,init] Memory: 10240M [2022-07-12T10:52:52.243+0800][10][info][gc,init] Large Page Support: Disabled [2022-07-12T10:52:52.243+0800][10][info][gc,init] GC Workers: 2/3 (static) [2022-07-12T10:52:52.243+0800][10][info][gc,init] Address Space Type: Contiguous/Unrestricted/Complete [2022-07-12T10:52:52.243+0800][10][info][gc,init] Address Space Size: 64000M x 3 = 192000M [2022-07-12T10:52:52.243+0800][10][info][gc,init] Heap Backing File: /memfd:java_heap [2022-07-12T10:52:52.243+0800][10][info][gc,init] Heap Backing Filesystem: tmpfs (0x1021994) [2022-07-12T10:52:52.243+0800][10][info][gc,init] Min Capacity: 4000M [2022-07-12T10:52:52.243+0800][10][info][gc,init] Initial Capacity: 4000M [2022-07-12T10:52:52.243+0800][10][info][gc,init] Max Capacity: 4000M [2022-07-12T10:52:52.243+0800][10][info][gc,init] Medium Page Size: 32M [2022-07-12T10:52:52.243+0800][10][info][gc,init] Pre-touch: Enabled [2022-07-12T10:52:52.243+0800][10][info][gc,init] Available space on backing filesystem: N/A [2022-07-12T10:52:52.243+0800][10][info][gc,init] Uncommit: Implicitly Disabled (-Xms equals -Xmx) [2022-07-12T10:52:55.014+0800][10][info][gc,init] Runtime Workers: 3 [2022-07-12T10:52:55.014+0800][10][info][gc ] Using The Z Garbage Collector [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] === Garbage Collection Statistics ======================================================================================================================= [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Last 10s Last 10m Last 10h Total [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Avg / Max Avg / Max Avg / Max Avg / Max [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Collector: Garbage Collection Cycle 0.000 / 0.000 215.897 / 245.004 199.964 / 281.004 198.501 / 327.779 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Contention: Mark Segment Reset Contention 0 / 0 0 / 3 0 / 13 0 / 17 ops/s [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Contention: Mark SeqNum Reset Contention 0 / 0 0 / 2 0 / 3 0 / 4 ops/s [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Critical: Allocation Stall 0 / 0 0 / 0 0 / 0 0 / 0 ops/s [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Critical: Allocation Stall 0.000 / 0.000 0.000 / 0.000 0.000 / 0.000 0.000 / 0.000 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Critical: GC Locker Stall 0 / 0 0 / 1 0 / 1 0 / 1 ops/s [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Critical: GC Locker Stall 0.000 / 0.000 0.001 / 0.001 0.022 / 0.138 0.032 / 0.197 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Critical: Relocation Stall 0 / 0 0 / 0 0 / 0 0 / 0 ops/s [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Critical: Relocation Stall 0.000 / 0.000 0.000 / 0.000 0.000 / 0.000 0.000 / 0.000 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Memory: Allocation Rate 106 / 124 109 / 240 100 / 308 104 / 352 MB/s [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Memory: Out Of Memory 0 / 0 0 / 0 0 / 0 0 / 0 ops/s [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Memory: Page Cache Flush 0 / 0 0 / 0 0 / 0 0 / 0 MB/s [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Memory: Page Cache Hit L1 53 / 62 54 / 76 49 / 94 51 / 176 ops/s [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Memory: Page Cache Hit L2 0 / 0 0 / 0 0 / 0 0 / 0 ops/s [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Memory: Page Cache Hit L3 0 / 0 0 / 0 0 / 1 0 / 123 ops/s [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Memory: Page Cache Miss 0 / 0 0 / 0 0 / 0 0 / 1 ops/s [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Memory: Uncommit 0 / 0 0 / 0 0 / 0 0 / 0 MB/s [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Memory: Undo Object Allocation Failed 0 / 0 0 / 94 0 / 189 0 / 438 ops/s [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Memory: Undo Object Allocation Succeeded 0 / 0 0 / 90 0 / 571 0 / 571 ops/s [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Memory: Undo Page Allocation 0 / 1 0 / 3 0 / 7 0 / 9 ops/s [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Phase: Concurrent Mark 0.000 / 0.000 162.242 / 191.178 149.779 / 233.888 149.319 / 253.310 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Phase: Concurrent Mark Continue 0.000 / 0.000 0.000 / 0.000 0.000 / 0.000 0.000 / 0.000 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Phase: Concurrent Mark Free 0.000 / 0.000 0.001 / 0.003 0.001 / 0.035 0.001 / 0.035 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Phase: Concurrent Process Non-Strong References 0.000 / 0.000 40.077 / 43.167 36.932 / 73.540 35.951 / 73.540 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Phase: Concurrent Relocate 0.000 / 0.000 9.764 / 13.321 9.670 / 18.334 9.656 / 64.541 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Phase: Concurrent Reset Relocation Set 0.000 / 0.000 0.246 / 0.377 0.234 / 0.385 0.228 / 0.459 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Phase: Concurrent Select Relocation Set 0.000 / 0.000 2.843 / 3.684 2.670 / 4.496 2.647 / 9.518 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Phase: Pause Mark End 0.000 / 0.000 0.036 / 0.059 0.030 / 0.080 0.029 / 0.105 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Phase: Pause Mark Start 0.000 / 0.000 0.040 / 0.050 0.057 / 17.923 0.085 / 116.781 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Phase: Pause Relocate Start 0.000 / 0.000 0.022 / 0.032 0.021 / 0.060 0.021 / 0.063 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Subphase: Concurrent Classes Purge 0.000 / 0.000 1.484 / 2.016 1.290 / 2.861 1.230 / 3.239 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Subphase: Concurrent Classes Unlink 0.000 / 0.000 34.848 / 38.008 32.338 / 68.815 31.461 / 68.815 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Subphase: Concurrent Mark 0.000 / 0.000 158.162 / 187.147 146.016 / 230.654 145.542 / 250.339 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Subphase: Concurrent Mark Try Flush 0.000 / 0.000 0.208 / 0.536 0.197 / 79.168 0.179 / 79.168 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Subphase: Concurrent Mark Try Terminate 0.000 / 0.000 0.468 / 1.076 0.455 / 5.019 0.449 / 32.389 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Subphase: Concurrent References Enqueue 0.000 / 0.000 0.001 / 0.008 0.002 / 0.015 0.002 / 0.031 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Subphase: Concurrent References Process 0.000 / 0.000 0.417 / 0.662 0.297 / 0.828 0.305 / 4.058 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Subphase: Concurrent Roots ClassLoaderDataGraph 0.000 / 0.000 0.421 / 1.062 0.393 / 1.062 0.392 / 1.062 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Subphase: Concurrent Roots CodeCache 0.000 / 0.000 0.000 / 0.000 0.000 / 0.000 0.000 / 0.000 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Subphase: Concurrent Roots JavaThreads 0.000 / 0.000 2.863 / 3.649 2.609 / 4.023 2.636 / 4.221 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Subphase: Concurrent Roots OopStorageSet 0.000 / 0.000 0.101 / 0.148 0.094 / 0.172 0.092 / 0.748 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Subphase: Concurrent Weak Roots OopStorageSet 0.000 / 0.000 2.895 / 3.442 2.633 / 3.805 2.588 / 4.245 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] Subphase: Pause Mark Try Complete 0.000 / 0.000 0.000 / 0.000 0.000 / 0.000 0.003 / 0.006 ms [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] System: Java Threads 0 / 0 204 / 227 173 / 227 167 / 227 threads [2022-07-13T14:18:33.036+0800][18][info][gc,stats ] ========================================================================================================================================================= [2022-07-13T14:18:37.836+0800][16][info][gc,task ] GC(2822) Using 2 workers [2022-07-13T14:18:38.568+0800][23][info][gc,phases ] GC(2822) Pause Mark Start 731.329ms [2022-07-13T14:18:38.568+0800][23][info][safepoint ] Safepoint "ZMarkStart", Time since last: 33682417078 ns, Reaching safepoint: 78660 ns, At safepoint: 731509582 ns, Total: 731588242 ns [2022-07-13T14:18:38.763+0800][16][info][gc,phases ] GC(2822) Concurrent Mark 195.357ms [2022-07-13T14:18:38.763+0800][23][info][gc,phases ] GC(2822) Pause Mark End 0.037ms [2022-07-13T14:18:38.763+0800][23][info][safepoint ] Safepoint "ZMarkEnd", Time since last: 195457277 ns, Reaching safepoint: 59774 ns, At safepoint: 69617 ns, Total: 129391 ns [2022-07-13T14:18:38.763+0800][16][info][gc,phases ] GC(2822) Concurrent Mark Free 0.002ms [2022-07-13T14:18:38.807+0800][16][info][gc,phases ] GC(2822) Concurrent Process Non-Strong References 43.503ms [2022-07-13T14:18:38.807+0800][16][info][gc,phases ] GC(2822) Concurrent Reset Relocation Set 0.266ms [2022-07-13T14:18:38.811+0800][16][info][gc,phases ] GC(2822) Concurrent Select Relocation Set 3.787ms [2022-07-13T14:18:38.811+0800][23][info][gc,phases ] GC(2822) Pause Relocate Start 0.022ms [2022-07-13T14:18:38.811+0800][23][info][safepoint ] Safepoint "ZRelocateStart", Time since last: 47720139 ns, Reaching safepoint: 71865 ns, At safepoint: 50103 ns, Total: 121968 ns [2022-07-13T14:18:38.823+0800][16][info][gc,phases ] GC(2822) Concurrent Relocate 12.090ms [2022-07-13T14:18:38.823+0800][16][info][gc,load ] GC(2822) Load: 17.47/17.79/16.25 [2022-07-13T14:18:38.823+0800][16][info][gc,mmu ] GC(2822) MMU: 2ms/0.0%, 5ms/0.0%, 10ms/0.0%, 20ms/0.0%, 50ms/0.0%, 100ms/0.0% [2022-07-13T14:18:38.823+0800][16][info][gc,marking ] GC(2822) Mark: 2 stripe(s), 2 proactive flush(es), 1 terminate flush(es), 0 completion(s), 0 continuation(s) [2022-07-13T14:18:38.823+0800][16][info][gc,marking ] GC(2822) Mark Stack Usage: 32M [2022-07-13T14:18:38.823+0800][16][info][gc,nmethod ] GC(2822) NMethods: 23051 registered, 1360 unregistered [2022-07-13T14:18:38.823+0800][16][info][gc,metaspace] GC(2822) Metaspace: 113M used, 114M committed, 516M reserved [2022-07-13T14:18:38.823+0800][16][info][gc,ref ] GC(2822) Soft: 1059 encountered, 271 discovered, 0 enqueued [2022-07-13T14:18:38.823+0800][16][info][gc,ref ] GC(2822) Weak: 16086 encountered, 1352 discovered, 0 enqueued [2022-07-13T14:18:38.823+0800][16][info][gc,ref ] GC(2822) Final: 30 encountered, 2 discovered, 0 enqueued [2022-07-13T14:18:38.823+0800][16][info][gc,ref ] GC(2822) Phantom: 290 encountered, 250 discovered, 1 enqueued [2022-07-13T14:18:38.823+0800][16][info][gc,reloc ] GC(2822) Small Pages: 1852 / 3704M, Empty: 698M, Relocated: 10M, In-Place: 0 [2022-07-13T14:18:38.823+0800][16][info][gc,reloc ] GC(2822) Medium Pages: 2 / 64M, Empty: 32M, Relocated: 0M, In-Place: 0 [2022-07-13T14:18:38.823+0800][16][info][gc,reloc ] GC(2822) Large Pages: 0 / 0M, Empty: 0M, Relocated: 0M, In-Place: 0 [2022-07-13T14:18:38.823+0800][16][info][gc,reloc ] GC(2822) Forwarding Usage: 3M [2022-07-13T14:18:38.823+0800][16][info][gc ] GC(2822) Garbage Collection (High Usage) 3768M(94%)->238M(6%) [2022-07-13T14:18:42.813+0800][23][info][safepoint ] Safepoint "Cleanup", Time since last: 4000282554 ns, Reaching safepoint: 1161862 ns, At safepoint: 32230 ns, Total: 1194092 ns [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] === Garbage Collection Statistics ======================================================================================================================= [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Last 10s Last 10m Last 10h Total [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Avg / Max Avg / Max Avg / Max Avg / Max [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Collector: Garbage Collection Cycle 987.238 / 987.238 256.494 / 987.238 200.744 / 987.238 198.781 / 987.238 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Contention: Mark Segment Reset Contention 0 / 0 0 / 3 0 / 13 0 / 17 ops/s [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Contention: Mark SeqNum Reset Contention 0 / 0 0 / 2 0 / 3 0 / 4 ops/s [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Critical: Allocation Stall 0 / 0 0 / 0 0 / 0 0 / 0 ops/s [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Critical: Allocation Stall 0.000 / 0.000 0.000 / 0.000 0.000 / 0.000 0.000 / 0.000 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Critical: GC Locker Stall 0 / 0 0 / 1 0 / 1 0 / 1 ops/s [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Critical: GC Locker Stall 0.000 / 0.000 0.001 / 0.001 0.022 / 0.138 0.032 / 0.197 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Critical: Relocation Stall 0 / 0 0 / 0 0 / 0 0 / 0 ops/s [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Critical: Relocation Stall 0.000 / 0.000 0.000 / 0.000 0.000 / 0.000 0.000 / 0.000 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Memory: Allocation Rate 105 / 128 109 / 240 100 / 308 104 / 352 MB/s [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Memory: Out Of Memory 0 / 0 0 / 0 0 / 0 0 / 0 ops/s [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Memory: Page Cache Flush 0 / 0 0 / 0 0 / 0 0 / 0 MB/s [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Memory: Page Cache Hit L1 51 / 64 54 / 76 49 / 94 51 / 176 ops/s [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Memory: Page Cache Hit L2 0 / 0 0 / 0 0 / 0 0 / 0 ops/s [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Memory: Page Cache Hit L3 1 / 16 0 / 16 0 / 16 0 / 123 ops/s [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Memory: Page Cache Miss 0 / 0 0 / 0 0 / 0 0 / 1 ops/s [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Memory: Uncommit 0 / 0 0 / 0 0 / 0 0 / 0 MB/s [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Memory: Undo Object Allocation Failed 1 / 14 0 / 94 0 / 189 0 / 438 ops/s [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Memory: Undo Object Allocation Succeeded 10 / 109 0 / 109 0 / 571 0 / 571 ops/s [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Memory: Undo Page Allocation 0 / 4 0 / 4 0 / 7 0 / 9 ops/s [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Phase: Concurrent Mark 195.357 / 195.357 163.985 / 195.357 149.824 / 233.888 149.336 / 253.310 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Phase: Concurrent Mark Continue 0.000 / 0.000 0.000 / 0.000 0.000 / 0.000 0.000 / 0.000 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Phase: Concurrent Mark Free 0.002 / 0.002 0.001 / 0.003 0.001 / 0.035 0.001 / 0.035 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Phase: Concurrent Process Non-Strong References 43.503 / 43.503 40.257 / 43.503 36.939 / 73.540 35.954 / 73.540 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Phase: Concurrent Relocate 12.090 / 12.090 9.887 / 13.321 9.672 / 18.334 9.657 / 64.541 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Phase: Concurrent Reset Relocation Set 0.266 / 0.266 0.247 / 0.377 0.234 / 0.385 0.228 / 0.459 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Phase: Concurrent Select Relocation Set 3.787 / 3.787 2.893 / 3.787 2.671 / 4.496 2.647 / 9.518 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Phase: Pause Mark End 0.037 / 0.037 0.036 / 0.059 0.030 / 0.080 0.029 / 0.105 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Phase: Pause Mark Start 731.329 / 731.329 38.529 / 731.329 0.782 / 731.329 0.344 / 731.329 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Phase: Pause Relocate Start 0.022 / 0.022 0.022 / 0.032 0.021 / 0.060 0.021 / 0.063 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Subphase: Concurrent Classes Purge 1.826 / 1.826 1.502 / 2.016 1.290 / 2.861 1.230 / 3.239 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Subphase: Concurrent Classes Unlink 38.001 / 38.001 35.014 / 38.008 32.344 / 68.815 31.464 / 68.815 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Subphase: Concurrent Mark 190.810 / 191.151 159.881 / 191.151 146.060 / 230.654 145.558 / 250.339 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Subphase: Concurrent Mark Try Flush 0.259 / 0.548 0.211 / 0.548 0.197 / 79.168 0.179 / 79.168 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Subphase: Concurrent Mark Try Terminate 0.545 / 1.065 0.472 / 1.076 0.456 / 5.019 0.449 / 32.389 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Subphase: Concurrent References Enqueue 0.009 / 0.009 0.002 / 0.009 0.002 / 0.015 0.002 / 0.031 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Subphase: Concurrent References Process 0.338 / 0.338 0.412 / 0.662 0.297 / 0.828 0.305 / 4.058 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Subphase: Concurrent Roots ClassLoaderDataGraph 0.376 / 0.659 0.419 / 1.062 0.393 / 1.062 0.392 / 1.062 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Subphase: Concurrent Roots CodeCache 0.000 / 0.000 0.000 / 0.000 0.000 / 0.000 0.000 / 0.000 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Subphase: Concurrent Roots JavaThreads 3.345 / 3.622 2.889 / 3.649 2.610 / 4.023 2.636 / 4.221 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Subphase: Concurrent Roots OopStorageSet 0.103 / 0.110 0.101 / 0.148 0.094 / 0.172 0.092 / 0.748 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Subphase: Concurrent Weak Roots OopStorageSet 2.779 / 2.780 2.889 / 3.442 2.633 / 3.805 2.588 / 4.245 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] Subphase: Pause Mark Try Complete 0.000 / 0.000 0.000 / 0.000 0.000 / 0.000 0.003 / 0.006 ms [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] System: Java Threads 226 / 234 205 / 234 173 / 234 167 / 234 threads [2022-07-13T14:18:43.036+0800][18][info][gc,stats ] ========================================================================================================================================================= In my opinion, this stage is very light. What can cause this phenomenon Thanks!

2 1