Open sendaoYan opened 20 hours ago
https://tone.aliyun-inc.com/ws/xesljfzh/test_result/407552
"Surrogate Locker Thread (Concurrent GC)" #4 daemon prio=9 os_prio=0 tid=0x0000ffff9c797000 nid=0x50fdc waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE "Finalizer" #3 daemon prio=8 os_prio=0 tid=0x0000ffff9c764000 nid=0x50fdb in Object.wait() [0x0000fffdf3029000] java.lang.Thread.State: WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x00000000e0821e60> (a java.lang.ref.ReferenceQueue$Lock) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:144) - locked <0x00000000e0821e60> (a java.lang.ref.ReferenceQueue$Lock) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:165) at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:188) "Reference Handler" #2 daemon prio=10 os_prio=0 tid=0x0000ffff9c75f800 nid=0x50fda in Object.wait() [0x0000fffdf3229000] java.lang.Thread.State: WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x00000000e0821e50> (a java.lang.ref.Reference$Lock) at java.lang.Object.wait(Object.java:502) at java.lang.ref.Reference.tryHandlePending(Reference.java:191) - locked <0x00000000e0821e50> (a java.lang.ref.Reference$Lock) at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:153) "main" #1 prio=5 os_prio=0 tid=0x0000ffff9c00e000 nid=0x50f74 in Object.wait() [0x0000ffffa17de000] java.lang.Thread.State: WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x00000000e0821e70> (a java.lang.Thread) at java.lang.Thread.join(Thread.java:1257) - locked <0x00000000e0821e70> (a java.lang.Thread) at java.lang.Thread.join(Thread.java:1331) at com.sun.javatest.regtest.agent.MainWrapper.main(MainWrapper.java:74) "VM Thread" os_prio=0 tid=0x0000ffff9c756000 nid=0x50fd9 runnable "Gang worker#0 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c025000 nid=0x50f76 runnable "Gang worker#1 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c026800 nid=0x50f77 runnable "Gang worker#2 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c028800 nid=0x50f78 runnable "Gang worker#3 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c02a000 nid=0x50f79 runnable "Gang worker#4 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c02c000 nid=0x50f7a runnable "Gang worker#5 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c02e000 nid=0x50f7b runnable "Gang worker#6 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c02f800 nid=0x50f7c runnable "Gang worker#7 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c031800 nid=0x50f7d runnable "Gang worker#8 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c033000 nid=0x50f7e runnable "Gang worker#9 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c035000 nid=0x50f7f runnable "Gang worker#10 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c036800 nid=0x50f80 runnable "Gang worker#11 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c038800 nid=0x50f81 runnable "Gang worker#12 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c03a800 nid=0x50f82 runnable "Gang worker#13 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c03c000 nid=0x50f83 runnable "Gang worker#14 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c03e000 nid=0x50f84 runnable "Gang worker#15 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c03f800 nid=0x50f85 runnable "Gang worker#16 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c041800 nid=0x50f86 runnable "Gang worker#17 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c043000 nid=0x50f87 runnable "Gang worker#18 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c045000 nid=0x50f88 runnable "Gang worker#19 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c047000 nid=0x50f89 runnable "Gang worker#20 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c048800 nid=0x50f8a runnable "Gang worker#21 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c04a800 nid=0x50f8b runnable "Gang worker#22 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c04c000 nid=0x50f8c runnable "Gang worker#23 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c04e000 nid=0x50f8d runnable "Gang worker#24 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c04f800 nid=0x50f8e runnable "Gang worker#25 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c051800 nid=0x50f8f runnable "Gang worker#26 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c053800 nid=0x50f90 runnable "Gang worker#27 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c055000 nid=0x50f91 runnable "Gang worker#28 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c057000 nid=0x50f92 runnable "Gang worker#29 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c058800 nid=0x50f93 runnable "Gang worker#30 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c05a800 nid=0x50f94 runnable "Gang worker#31 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c05c000 nid=0x50f95 runnable "Gang worker#32 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c05e000 nid=0x50f96 runnable "Gang worker#33 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c060000 nid=0x50f97 runnable "Gang worker#34 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c061800 nid=0x50f98 runnable "Gang worker#35 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c063800 nid=0x50f99 runnable "Gang worker#36 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c065000 nid=0x50f9a runnable "Gang worker#37 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c067000 nid=0x50f9b runnable "Gang worker#38 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c068800 nid=0x50f9c runnable "Gang worker#39 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c06a800 nid=0x50f9d runnable "Gang worker#40 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c06c800 nid=0x50f9e runnable "Gang worker#41 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c06e000 nid=0x50f9f runnable "Gang worker#42 (Parallel GC Threads)" os_prio=0 tid=0x0000ffff9c070000 nid=0x50fa0 runnable "G1 Main Concurrent Mark GC Thread" os_prio=0 tid=0x0000ffff9c0ec800 nid=0x50fcd runnable "Gang worker#0 (G1 Parallel Marking Threads)" os_prio=0 tid=0x0000ffff9c0ee000 nid=0x50fce runnable "Gang worker#1 (G1 Parallel Marking Threads)" os_prio=0 tid=0x0000ffff9c0f0000 nid=0x50fcf runnable "Gang worker#2 (G1 Parallel Marking Threads)" os_prio=0 tid=0x0000ffff9c0f1800 nid=0x50fd0 runnable "Gang worker#3 (G1 Parallel Marking Threads)" os_prio=0 tid=0x0000ffff9c0f3800 nid=0x50fd1 runnable "Gang worker#4 (G1 Parallel Marking Threads)" os_prio=0 tid=0x0000ffff9c0f5800 nid=0x50fd2 runnable "Gang worker#5 (G1 Parallel Marking Threads)" os_prio=0 tid=0x0000ffff9c0f7000 nid=0x50fd3 runnable "Gang worker#6 (G1 Parallel Marking Threads)" os_prio=0 tid=0x0000ffff9c0f9000 nid=0x50fd4 runnable "Gang worker#7 (G1 Parallel Marking Threads)" os_prio=0 tid=0x0000ffff9c0fa800 nid=0x50fd5 runnable "Gang worker#8 (G1 Parallel Marking Threads)" os_prio=0 tid=0x0000ffff9c0fc800 nid=0x50fd6 runnable "Gang worker#9 (G1 Parallel Marking Threads)" os_prio=0 tid=0x0000ffff9c0fe000 nid=0x50fd7 runnable "Gang worker#10 (G1 Parallel Marking Threads)" os_prio=0 tid=0x0000ffff9c100000 nid=0x50fd8 runnable "G1 Concurrent Refinement Thread#0" os_prio=0 tid=0x0000ffff9c0c3800 nid=0x50fcc runnable "G1 Concurrent Refinement Thread#1" os_prio=0 tid=0x0000ffff9c0c1800 nid=0x50fcb runnable "G1 Concurrent Refinement Thread#2" os_prio=0 tid=0x0000ffff9c0c0000 nid=0x50fca runnable "G1 Concurrent Refinement Thread#3" os_prio=0 tid=0x0000ffff9c0be000 nid=0x50fc9 runnable "G1 Concurrent Refinement Thread#4" os_prio=0 tid=0x0000ffff9c0bc000 nid=0x50fc8 runnable "G1 Concurrent Refinement Thread#5" os_prio=0 tid=0x0000ffff9c0ba800 nid=0x50fc7 runnable "G1 Concurrent Refinement Thread#6" os_prio=0 tid=0x0000ffff9c0b8800 nid=0x50fc6 runnable "G1 Concurrent Refinement Thread#7" os_prio=0 tid=0x0000ffff9c0b6800 nid=0x50fc5 runnable "G1 Concurrent Refinement Thread#8" os_prio=0 tid=0x0000ffff9c0b5000 nid=0x50fc4 runnable "G1 Concurrent Refinement Thread#9" os_prio=0 tid=0x0000ffff9c0b3000 nid=0x50fc3 runnable "G1 Concurrent Refinement Thread#10" os_prio=0 tid=0x0000ffff9c0b1800 nid=0x50fc2 runnable "G1 Concurrent Refinement Thread#11" os_prio=0 tid=0x0000ffff9c0af800 nid=0x50fc1 runnable "G1 Concurrent Refinement Thread#12" os_prio=0 tid=0x0000ffff9c0ad800 nid=0x50fc0 runnable "G1 Concurrent Refinement Thread#13" os_prio=0 tid=0x0000ffff9c0ac000 nid=0x50fbf runnable "G1 Concurrent Refinement Thread#14" os_prio=0 tid=0x0000ffff9c0aa000 nid=0x50fbe runnable "G1 Concurrent Refinement Thread#15" os_prio=0 tid=0x0000ffff9c0a8000 nid=0x50fbd runnable "G1 Concurrent Refinement Thread#16" os_prio=0 tid=0x0000ffff9c0a6800 nid=0x50fbc runnable "G1 Concurrent Refinement Thread#17" os_prio=0 tid=0x0000ffff9c0a4800 nid=0x50fbb runnable "G1 Concurrent Refinement Thread#18" os_prio=0 tid=0x0000ffff9c0a2800 nid=0x50fba runnable "G1 Concurrent Refinement Thread#19" os_prio=0 tid=0x0000ffff9c0a1000 nid=0x50fb9 runnable "G1 Concurrent Refinement Thread#20" os_prio=0 tid=0x0000ffff9c09f000 nid=0x50fb8 runnable "G1 Concurrent Refinement Thread#21" os_prio=0 tid=0x0000ffff9c09d000 nid=0x50fb7 runnable "G1 Concurrent Refinement Thread#22" os_prio=0 tid=0x0000ffff9c09b800 nid=0x50fb6 runnable "G1 Concurrent Refinement Thread#23" os_prio=0 tid=0x0000ffff9c099800 nid=0x50fb5 runnable "G1 Concurrent Refinement Thread#24" os_prio=0 tid=0x0000ffff9c097800 nid=0x50fb4 runnable "G1 Concurrent Refinement Thread#25" os_prio=0 tid=0x0000ffff9c096000 nid=0x50fb3 runnable "G1 Concurrent Refinement Thread#26" os_prio=0 tid=0x0000ffff9c094000 nid=0x50fb2 runnable "G1 Concurrent Refinement Thread#27" os_prio=0 tid=0x0000ffff9c092000 nid=0x50fb1 runnable "G1 Concurrent Refinement Thread#28" os_prio=0 tid=0x0000ffff9c090800 nid=0x50fb0 runnable "G1 Concurrent Refinement Thread#29" os_prio=0 tid=0x0000ffff9c08e800 nid=0x50faf runnable "G1 Concurrent Refinement Thread#30" os_prio=0 tid=0x0000ffff9c08c800 nid=0x50fae runnable "G1 Concurrent Refinement Thread#31" os_prio=0 tid=0x0000ffff9c08b000 nid=0x50fad runnable "G1 Concurrent Refinement Thread#32" os_prio=0 tid=0x0000ffff9c089000 nid=0x50fac runnable "G1 Concurrent Refinement Thread#33" os_prio=0 tid=0x0000ffff9c087000 nid=0x50fab runnable "G1 Concurrent Refinement Thread#34" os_prio=0 tid=0x0000ffff9c085800 nid=0x50faa runnable "G1 Concurrent Refinement Thread#35" os_prio=0 tid=0x0000ffff9c083800 nid=0x50fa9 runnable "G1 Concurrent Refinement Thread#36" os_prio=0 tid=0x0000ffff9c081800 nid=0x50fa8 runnable "G1 Concurrent Refinement Thread#37" os_prio=0 tid=0x0000ffff9c080000 nid=0x50fa7 runnable "G1 Concurrent Refinement Thread#38" os_prio=0 tid=0x0000ffff9c07e000 nid=0x50fa6 runnable "G1 Concurrent Refinement Thread#39" os_prio=0 tid=0x0000ffff9c07c000 nid=0x50fa5 runnable "G1 Concurrent Refinement Thread#40" os_prio=0 tid=0x0000ffff9c07a800 nid=0x50fa4 runnable "G1 Concurrent Refinement Thread#41" os_prio=0 tid=0x0000ffff9c078800 nid=0x50fa3 runnable "G1 Concurrent Refinement Thread#42" os_prio=0 tid=0x0000ffff9c076800 nid=0x50fa2 runnable "G1 Concurrent Refinement Thread#43" os_prio=0 tid=0x0000ffff9c075000 nid=0x50fa1 runnable "VM Periodic Task Thread" os_prio=0 tid=0x0000ffff9c7cf800 nid=0x50ff1 waiting on condition JNI global references: 212 --- Timeout information end. elapsed time (seconds): 1200.293 configuration: STDOUT: %% Test parameters %% Objects per region : 1 %% Heap fraction to allocate : 90% %% Regions to refresh to provoke GC: 10 %% Memory %% used : 10M %% available : 493M %% to allocate : 432M %% (in regs) : 54 %% G1 Region Size: 8M %% Objects %% N (array length) : 2097147 %% K (objects in regions): 1 %% Reference size : 4 %% Approximate obj size : 8191K) 0.167: [GC pause (G1 Humongous Allocation) (young) (initial-mark) 234M->224M(504M), 0.0149804 secs] 0.182: [GC concurrent-root-region-scan-start] 0.182: [GC concurrent-root-region-scan-end, 0.0007401 secs] 0.182: [GC concurrent-mark-start] %% step 1 out of 10 (~0% done) %% 0 --> 8 0.201: [GC concurrent-mark-end, 0.0182001 secs] 0.201: [GC remark, 0.0328510 secs] 0.234: [GC cleanup 436M->436M(504M), 0.0101771 secs] 0.252: [GC pause (G1 Humongous Allocation) (young)-- 492M->492M(504M), 0.0563353 secs] 0.309: [Full GC (Allocation Failure) 492M->432M(504M), 0.1693870 secs] 0.478: [GC pause (G1 Humongous Allocation) (young) (initial-mark) 440M->440M(504M), 0.0031029 secs] 0.482: [GC concurrent-root-region-scan-start] 0.482: [GC concurrent-root-region-scan-end, 0.0000163 secs] 0.482: [GC concurrent-mark-start] %% step 2 out of 10 (~0% done) %% 8 --> 0 0.556: [GC concurrent-mark-end, 0.0739897 secs] 0.556: [GC remark, 0.0071816 secs] 0.563: [GC cleanup 457M->449M(504M), 0.0012992 secs] 0.564: [GC concurrent-cleanup-start] 0.564: [GC concurrent-cleanup-end, 0.0000203 secs] 0.570: [GC pause (G1 Humongous Allocation) (young) 489M->488M(504M), 0.0111234 secs] 0.582: [GC pause (G1 Humongous Allocation) (young) (initial-mark) 496M->496M(504M), 0.0313107 secs] 0.613: [GC concurrent-root-region-scan-start] 0.613: [GC concurrent-root-region-scan-end, 0.0000262 secs] 0.613: [GC concurrent-mark-start] 0.613: [GC pause (G1 Humongous Allocation) (young) 496M->496M(504M), 0.0355179 secs] 0.649: [Full GC (Allocation Failure) 496M->432M(504M), 0.1362894 secs] 0.785: [GC concurrent-mark-abort] 0.785: [GC pause (G1 Humongous Allocation) (young) (initial-mark) 440M->440M(504M), 0.0724654 secs] 0.858: [GC concurrent-root-region-scan-start] 0.858: [GC concurrent-root-region-scan-end, 0.0000224 secs] 0.858: [GC concurrent-mark-start] %% step 3 out of 10 (~0% done) %% 0 --> 16 0.865: [GC pause (G1 Humongous Allocation) (young) 489M->488M(504M), 0.0561100 secs] 0.922: [GC pause (G1 Humongous Allocation) (young) 496M->496M(504M), 0.0361509 secs] 0.958: [Full GC (Allocation Failure) 496M->432M(504M), 0.1445141 secs] 1.103: [GC concurrent-mark-abort] 1.103: [GC pause (G1 Humongous Allocation) (young) (initial-mark) 440M->440M(504M), 0.0248341 secs] 1.128: [GC concurrent-root-region-scan-start] 1.128: [GC concurrent-root-region-scan-end, 0.0000297 secs] 1.128: [GC concurrent-mark-start] %% step 4 out of 10 (~1% done) %% 16 --> 520 1.156: [GC concurrent-mark-end, 0.0282301 secs] 1.156: [GC remark, 0.0072649 secs] 1.164: [GC cleanup 481M->473M(504M), 0.0031718 secs] 1.167: [GC concurrent-cleanup-start] 1.167: [GC concurrent-cleanup-end, 0.0000170 secs] 1.240: [GC pause (G1 Humongous Allocation) (young) 489M->488M(504M), 0.0297258 secs] 1.271: [GC pause (G1 Humongous Allocation) (young) (initial-mark) 496M->496M(504M), 0.0191960 secs] 1.290: [GC concurrent-root-region-scan-start] 1.290: [GC concurrent-root-region-scan-end, 0.0000205 secs] 1.290: [GC concurrent-mark-start] 1.290: [GC pause (G1 Humongous Allocation) (young) 496M->496M(504M), 0.0163026 secs] 1.306: [Full GC (Allocation Failure) 496M->432M(504M), 0.1632331 secs] 1.470: [GC concurrent-mark-abort] 1.470: [GC pause (G1 Humongous Allocation) (young) (initial-mark) 440M->440M(504M), 0.0198576 secs] 1.490: [GC concurrent-root-region-scan-start] 1.490: [GC concurrent-root-region-scan-end, 0.0000261 secs] 1.490: [GC concurrent-mark-start] %% step 5 out of 10 (~17% done) %% 520 --> 0 1.515: [GC concurrent-mark-end, 0.0248713 secs] 1.515: [GC remark, 0.0319373 secs] 1.547: [GC cleanup 489M->481M(504M), 0.0052489 secs] 1.553: [GC concurrent-cleanup-start] 1.553: [GC concurrent-cleanup-end, 0.0000223 secs] 1.617: [GC pause (WhiteBox Initiated Concurrent Mark) (young) (initial-mark) 481M->480M(504M), 0.0311922 secs] 1.648: [GC concurrent-root-region-scan-start] 1.648: [GC concurrent-root-region-scan-end, 0.0000221 secs] 1.648: [GC concurrent-mark-start] 1.688: [GC concurrent-mark-end, 0.0392809 secs] 1.688: [GC remark, 0.0170867 secs] 1.705: [GC cleanup 480M->432M(504M), 0.0026579 secs] 1.708: [GC concurrent-cleanup-start] 1.708: [GC concurrent-cleanup-end, 0.0000322 secs] 1.712: [GC pause (G1 Humongous Allocation) (young) 496M->496M(504M), 0.0822332 secs] 1.794: [Full GC (Allocation Failure) 496M->432M(504M), 0.1393864 secs] 1.934: [GC pause (G1 Humongous Allocation) (young) (initial-mark) 440M->440M(504M), 0.0360925 secs] 1.970: [GC concurrent-root-region-scan-start] 1.970: [GC concurrent-root-region-scan-end, 0.0000279 secs] 1.970: [GC concurrent-mark-start] %% step 6 out of 10 (~33% done) %% 0 --> 1024 2.011: [GC concurrent-mark-end, 0.0402485 secs] 2.011: [GC remark, 0.0190183 secs] 2.030: [GC cleanup 449M->441M(504M), 0.0017681 secs] 2.032: [GC concurrent-cleanup-start] 2.032: [GC concurrent-cleanup-end, 0.0000189 secs] 2.188: [GC pause (G1 Humongous Allocation) (young) 489M->488M(504M), 0.0492625 secs] 2.238: [GC pause (G1 Humongous Allocation) (young) (initial-mark) 496M->496M(504M), 0.0309709 secs] 2.269: [GC concurrent-root-region-scan-start] 2.269: [GC concurrent-root-region-scan-end, 0.0000237 secs] 2.269: [GC concurrent-mark-start] 2.270: [GC pause (G1 Humongous Allocation) (young) 496M->496M(504M), 0.0138578 secs] 2.284: [Full GC (Allocation Failure) 496M->432M(504M), 0.1495691 secs] 2.433: [GC concurrent-mark-abort] 2.434: [GC pause (G1 Humongous Allocation) (young) (initial-mark) 440M->440M(504M), 0.0250983 secs] 2.459: [GC concurrent-root-region-scan-start] 2.459: [GC concurrent-root-region-scan-end, 0.0000128 secs] 2.459: [GC concurrent-mark-start] %% step 7 out of 10 (~66% done) %% 1024 --> 1034 2.467: [GC pause (G1 Humongous Allocation) (young) 489M->488M(504M), 0.0363086 secs] 2.504: [GC pause (G1 Humongous Allocation) (young) 496M->496M(504M), 0.0568537 secs] 2.561: [Full GC (Allocation Failure) 496M->432M(504M), 0.1432140 secs] %% step 8 out of 10 (~66% done) %% 1034 --> 17 2.713: [GC concurrent-mark-abort] 2.842: [GC pause (WhiteBox Initiated Concurrent Mark) (young) (initial-mark) 473M->472M(504M), 0.0206854 secs] 2.863: [GC concurrent-root-region-scan-start] 2.863: [GC concurrent-root-region-scan-end, 0.0000233 secs] 2.863: [GC concurrent-mark-start] Timeout refired 1200 times
TestStressRSetCoarsening.java.log
https://tone.aliyun-inc.com/ws/xesljfzh/test_result/407552
TestStressRSetCoarsening.java.log