G1 GC take a long time for Object Copy

My application is a typical Java web application, Java 8, using G1 GC. I have encountered a GC problem. Within 10 minutes after the application is started (when the old generation is gradually increasing), long GC often occurs, but this does not happen during the stable operation stage. The following is my GC log:

Plain text
Copy to clipboard
Open code in new window
EnlighterJS 3 Syntax Highlighter
<code>{Heap before GC invocations=43 (full 0):
garbage-first heap total 5242880K, used 496183K [0x00000006a7800000, 0x00000006a7a05000, 0x00000007e7800000)
region size 2048K, 128 young (262144K), 54 survivors (110592K)
Metaspace used 228219K, capacity 255729K, committed 255872K, reserved 1275904K
class space used 24456K, capacity 29030K, committed 29056K, reserved 1048576K
2024-06-20T11:44:47.168+0800: 260.143: [GC pause (G1 Evacuation Pause) (young), 9.8956459 secs]
[Parallel Time: 9891.3 ms, GC Workers: 4]
[GC Worker Start (ms): Min: 260143.9, Avg: 260145.0, Max: 260147.2, Diff: 3.3]
[Ext Root Scanning (ms): Min: 5.1, Avg: 8.8, Max: 12.5, Diff: 7.5, Sum: 35.2]
[Update RS (ms): Min: 0.0, Avg: 12.2, Max: 16.2, Diff: 16.2, Sum: 48.6]
[Processed Buffers: Min: 0, Avg: 39.2, Max: 69, Diff: 69, Sum: 157]
[Scan RS (ms): Min: 0.0, Avg: 1.1, Max: 1.9, Diff: 1.8, Sum: 4.4]
[Code Root Scanning (ms): Min: 0.0, Avg: 4.0, Max: 6.1, Diff: 6.1, Sum: 15.8]
[Object Copy (ms): Min: 9855.0, Avg: 9863.9, Max: 9884.8, Diff: 29.8, Sum: 39455.6]
[Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
[Termination Attempts: Min: 1, Avg: 15.8, Max: 23, Diff: 22, Sum: 63]
[GC Worker Other (ms): Min: 0.1, Avg: 0.1, Max: 0.1, Diff: 0.0, Sum: 0.2]
[GC Worker Total (ms): Min: 9887.8, Avg: 9890.0, Max: 9891.1, Diff: 3.3, Sum: 39560.0]
[GC Worker End (ms): Min: 270035.0, Avg: 270035.0, Max: 270035.0, Diff: 0.1]
[Code Root Fixup: 1.1 ms]
[Code Root Purge: 0.0 ms]
[Clear CT: 0.2 ms]
[Other: 3.0 ms]
[Choose CSet: 0.0 ms]
[Ref Proc: 1.1 ms]
[Ref Enq: 0.0 ms]
[Redirty Cards: 0.5 ms]
[Humongous Register: 0.1 ms]
[Humongous Reclaim: 0.0 ms]
[Free CSet: 0.4 ms]
[Eden: 148.0M(148.0M)->0.0B(234.0M) Survivors: 108.0M->22.0M Heap: 484.6M(5120.0M)->338.4M(5120.0M)]
Heap after GC invocations=44 (full 0):
garbage-first heap total 5242880K, used 346567K [0x00000006a7800000, 0x00000006a7a05000, 0x00000007e7800000)
region size 2048K, 11 young (22528K), 11 survivors (22528K)
Metaspace used 228219K, capacity 255729K, committed 255872K, reserved 1275904K
class space used 24456K, capacity 29030K, committed 29056K, reserved 1048576K
}
[Times: user=17.10 sys=0.90, real=9.90 secs]
</code>
<code>{Heap before GC invocations=43 (full 0): garbage-first heap total 5242880K, used 496183K [0x00000006a7800000, 0x00000006a7a05000, 0x00000007e7800000) region size 2048K, 128 young (262144K), 54 survivors (110592K) Metaspace used 228219K, capacity 255729K, committed 255872K, reserved 1275904K class space used 24456K, capacity 29030K, committed 29056K, reserved 1048576K 2024-06-20T11:44:47.168+0800: 260.143: [GC pause (G1 Evacuation Pause) (young), 9.8956459 secs] [Parallel Time: 9891.3 ms, GC Workers: 4] [GC Worker Start (ms): Min: 260143.9, Avg: 260145.0, Max: 260147.2, Diff: 3.3] [Ext Root Scanning (ms): Min: 5.1, Avg: 8.8, Max: 12.5, Diff: 7.5, Sum: 35.2] [Update RS (ms): Min: 0.0, Avg: 12.2, Max: 16.2, Diff: 16.2, Sum: 48.6] [Processed Buffers: Min: 0, Avg: 39.2, Max: 69, Diff: 69, Sum: 157] [Scan RS (ms): Min: 0.0, Avg: 1.1, Max: 1.9, Diff: 1.8, Sum: 4.4] [Code Root Scanning (ms): Min: 0.0, Avg: 4.0, Max: 6.1, Diff: 6.1, Sum: 15.8] [Object Copy (ms): Min: 9855.0, Avg: 9863.9, Max: 9884.8, Diff: 29.8, Sum: 39455.6] [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0] [Termination Attempts: Min: 1, Avg: 15.8, Max: 23, Diff: 22, Sum: 63] [GC Worker Other (ms): Min: 0.1, Avg: 0.1, Max: 0.1, Diff: 0.0, Sum: 0.2] [GC Worker Total (ms): Min: 9887.8, Avg: 9890.0, Max: 9891.1, Diff: 3.3, Sum: 39560.0] [GC Worker End (ms): Min: 270035.0, Avg: 270035.0, Max: 270035.0, Diff: 0.1] [Code Root Fixup: 1.1 ms] [Code Root Purge: 0.0 ms] [Clear CT: 0.2 ms] [Other: 3.0 ms] [Choose CSet: 0.0 ms] [Ref Proc: 1.1 ms] [Ref Enq: 0.0 ms] [Redirty Cards: 0.5 ms] [Humongous Register: 0.1 ms] [Humongous Reclaim: 0.0 ms] [Free CSet: 0.4 ms] [Eden: 148.0M(148.0M)->0.0B(234.0M) Survivors: 108.0M->22.0M Heap: 484.6M(5120.0M)->338.4M(5120.0M)] Heap after GC invocations=44 (full 0): garbage-first heap total 5242880K, used 346567K [0x00000006a7800000, 0x00000006a7a05000, 0x00000007e7800000) region size 2048K, 11 young (22528K), 11 survivors (22528K) Metaspace used 228219K, capacity 255729K, committed 255872K, reserved 1275904K class space used 24456K, capacity 29030K, committed 29056K, reserved 1048576K } [Times: user=17.10 sys=0.90, real=9.90 secs] </code>
{Heap before GC invocations=43 (full 0):
 garbage-first heap   total 5242880K, used 496183K [0x00000006a7800000, 0x00000006a7a05000, 0x00000007e7800000)
  region size 2048K, 128 young (262144K), 54 survivors (110592K)
 Metaspace       used 228219K, capacity 255729K, committed 255872K, reserved 1275904K
  class space    used 24456K, capacity 29030K, committed 29056K, reserved 1048576K
2024-06-20T11:44:47.168+0800: 260.143: [GC pause (G1 Evacuation Pause) (young), 9.8956459 secs]
   [Parallel Time: 9891.3 ms, GC Workers: 4]
      [GC Worker Start (ms): Min: 260143.9, Avg: 260145.0, Max: 260147.2, Diff: 3.3]
      [Ext Root Scanning (ms): Min: 5.1, Avg: 8.8, Max: 12.5, Diff: 7.5, Sum: 35.2]
      [Update RS (ms): Min: 0.0, Avg: 12.2, Max: 16.2, Diff: 16.2, Sum: 48.6]
         [Processed Buffers: Min: 0, Avg: 39.2, Max: 69, Diff: 69, Sum: 157]
      [Scan RS (ms): Min: 0.0, Avg: 1.1, Max: 1.9, Diff: 1.8, Sum: 4.4]
      [Code Root Scanning (ms): Min: 0.0, Avg: 4.0, Max: 6.1, Diff: 6.1, Sum: 15.8]
      [Object Copy (ms): Min: 9855.0, Avg: 9863.9, Max: 9884.8, Diff: 29.8, Sum: 39455.6]
      [Termination (ms): Min: 0.0, Avg: 0.0, Max: 0.0, Diff: 0.0, Sum: 0.0]
         [Termination Attempts: Min: 1, Avg: 15.8, Max: 23, Diff: 22, Sum: 63]
      [GC Worker Other (ms): Min: 0.1, Avg: 0.1, Max: 0.1, Diff: 0.0, Sum: 0.2]
      [GC Worker Total (ms): Min: 9887.8, Avg: 9890.0, Max: 9891.1, Diff: 3.3, Sum: 39560.0]
      [GC Worker End (ms): Min: 270035.0, Avg: 270035.0, Max: 270035.0, Diff: 0.1]
   [Code Root Fixup: 1.1 ms]
   [Code Root Purge: 0.0 ms]
   [Clear CT: 0.2 ms]
   [Other: 3.0 ms]
      [Choose CSet: 0.0 ms]
      [Ref Proc: 1.1 ms]
      [Ref Enq: 0.0 ms]
      [Redirty Cards: 0.5 ms]
      [Humongous Register: 0.1 ms]
      [Humongous Reclaim: 0.0 ms]
      [Free CSet: 0.4 ms]
   [Eden: 148.0M(148.0M)->0.0B(234.0M) Survivors: 108.0M->22.0M Heap: 484.6M(5120.0M)->338.4M(5120.0M)]
Heap after GC invocations=44 (full 0):
 garbage-first heap   total 5242880K, used 346567K [0x00000006a7800000, 0x00000006a7a05000, 0x00000007e7800000)
  region size 2048K, 11 young (22528K), 11 survivors (22528K)
 Metaspace       used 228219K, capacity 255729K, committed 255872K, reserved 1275904K
  class space    used 24456K, capacity 29030K, committed 29056K, reserved 1048576K
}
 [Times: user=17.10 sys=0.90, real=9.90 secs] 

I tried increasing the SURVIVOR area, but it didn’t work. Here are my JVM parameters:

-Xmx5120m -Xms5120m -XX:MaxMetaspaceSize=400M -XX:MetaspaceSize=400M -XX:+UseG1GC -XX:SurvivorRatio=6 -Dnacos.use.endpoint.parsing.rule=false -Dnacos.use.cloud.namespace.parsing=false -XX:+PrintHeapAtGC -XX:+PrintGCDateStamps -XX:+PrintGCDetails -XX:+PrintGCApplicationStoppedTime -XX:+PrintGCApplicationConcurrentTime -Xloggc:/tmp1/main-gc.log

New contributor

hongyiheng is a new contributor to this site. Take care in asking for clarification, commenting, and answering.
Check out our Code of Conduct.

Trang chủ Giới thiệu Sinh nhật bé trai Sinh nhật bé gái Tổ chức sự kiện Biểu diễn giải trí Dịch vụ khác Trang trí tiệc cưới Tổ chức khai trương Tư vấn dịch vụ Thư viện ảnh Tin tức - sự kiện Liên hệ Chú hề sinh nhật Trang trí YEAR END PARTY công ty Trang trí tất niên cuối năm Trang trí tất niên xu hướng mới nhất Trang trí sinh nhật bé trai Hải Đăng Trang trí sinh nhật bé Khánh Vân Trang trí sinh nhật Bích Ngân Trang trí sinh nhật bé Thanh Trang Thuê ông già Noel phát quà Biểu diễn xiếc khỉ Xiếc quay đĩa Dịch vụ tổ chức sự kiện 5 sao Thông tin về chúng tôi Dịch vụ sinh nhật bé trai Dịch vụ sinh nhật bé gái Sự kiện trọn gói Các tiết mục giải trí Dịch vụ bổ trợ Tiệc cưới sang trọng Dịch vụ khai trương Tư vấn tổ chức sự kiện Hình ảnh sự kiện Cập nhật tin tức Liên hệ ngay Thuê chú hề chuyên nghiệp Tiệc tất niên cho công ty Trang trí tiệc cuối năm Tiệc tất niên độc đáo Sinh nhật bé Hải Đăng Sinh nhật đáng yêu bé Khánh Vân Sinh nhật sang trọng Bích Ngân Tiệc sinh nhật bé Thanh Trang Dịch vụ ông già Noel Xiếc thú vui nhộn Biểu diễn xiếc quay đĩa Dịch vụ tổ chức tiệc uy tín Khám phá dịch vụ của chúng tôi Tiệc sinh nhật cho bé trai Trang trí tiệc cho bé gái Gói sự kiện chuyên nghiệp Chương trình giải trí hấp dẫn Dịch vụ hỗ trợ sự kiện Trang trí tiệc cưới đẹp Khởi đầu thành công với khai trương Chuyên gia tư vấn sự kiện Xem ảnh các sự kiện đẹp Tin mới về sự kiện Kết nối với đội ngũ chuyên gia Chú hề vui nhộn cho tiệc sinh nhật Ý tưởng tiệc cuối năm Tất niên độc đáo Trang trí tiệc hiện đại Tổ chức sinh nhật cho Hải Đăng Sinh nhật độc quyền Khánh Vân Phong cách tiệc Bích Ngân Trang trí tiệc bé Thanh Trang Thuê dịch vụ ông già Noel chuyên nghiệp Xem xiếc khỉ đặc sắc Xiếc quay đĩa thú vị
Trang chủ Giới thiệu Sinh nhật bé trai Sinh nhật bé gái Tổ chức sự kiện Biểu diễn giải trí Dịch vụ khác Trang trí tiệc cưới Tổ chức khai trương Tư vấn dịch vụ Thư viện ảnh Tin tức - sự kiện Liên hệ Chú hề sinh nhật Trang trí YEAR END PARTY công ty Trang trí tất niên cuối năm Trang trí tất niên xu hướng mới nhất Trang trí sinh nhật bé trai Hải Đăng Trang trí sinh nhật bé Khánh Vân Trang trí sinh nhật Bích Ngân Trang trí sinh nhật bé Thanh Trang Thuê ông già Noel phát quà Biểu diễn xiếc khỉ Xiếc quay đĩa
Thiết kế website Thiết kế website Thiết kế website Cách kháng tài khoản quảng cáo Mua bán Fanpage Facebook Dịch vụ SEO Tổ chức sinh nhật