JDK-8259789 : vmTestbase/vm/gc/compact tests time out with ZGC
  • Type: Bug
  • Component: hotspot
  • Sub-Component: gc
  • Affected Version: 17
  • Priority: P2
  • Status: Resolved
  • Resolution: Cannot Reproduce
  • OS: windows
  • CPU: x86_64
  • Submitted: 2021-01-14
  • Updated: 2021-05-24
  • Resolved: 2021-05-24
The Version table provides details related to the release that this issue/RFE will be addressed.

Unresolved : Release in which this issue/RFE will be addressed.
Resolved: Release in which this issue/RFE has been resolved.
Fixed : Release in which this issue/RFE has been fixed. The release containing this fix may be available for download as an Early Access Release or a General Availability Release.

To download the current JDK release, click here.
JDK 17
17Resolved
Related Reports
Relates :  
Relates :  
Description
The following tests timed out in the JDK17 CI:

vmTestbase/vm/gc/compact/Humongous_NonbranchyTree/TestDescription.java
vmTestbase/vm/gc/compact/Compact_InternedStrings_NonbranchyTree/TestDescription.java

Here's a snippet from the Humongous_NonbranchyTree log file:

#section:main
----------messages:(6/417)----------
command: main -XX:-UseGCOverheadLimit vm.gc.compact.Compact -gp nonbranchyTree(high) -gp1 random(arrays) -ms high -size2 1000000
reason: User specified action: run main/othervm -XX:-UseGCOverheadLimit vm.gc.compact.Compact -gp nonbranchyTree(high) -gp1 random(arrays) -ms high -size2 1000000 
Mode: othervm [/othervm specified]
Timeout information:
--- Timeout information end.
elapsed time (seconds): 2385.471
----------configuration:(0/0)----------
----------System.out:(31/1675)----------
Stress time: 120 seconds
Stress iterations factor: 1
Stress threads factor: 1
Stress runs factor: 1
Max memory: 2063597568
Sleep time: 500
Iterations: 0
Number of threads: 16
Run GC thread: false
Run mem diag thread: false
Run forever: false
Starting Thread[vm.gc.compact.Compact$Worker@3c75f18d,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@7384c783,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@60eeb9e7,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@3a7f5257,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@7386c70f,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@26ebae23,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@33df1a98,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@73f20b5e,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@36090490,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@4d682ec4,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@628e62b,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@6563a54b,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@1b3000f3,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@17a2135f,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@567aa63e,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@2e4b1847,5,MainThreadGroup]
For random generator using seed: 7613058073302752077
To re-run test with same seed value please add "-Djdk.test.lib.random.seed=7613058073302752077" to command line.
Timeout refired 480 times
No unexpected exceptions/errors are thrown
----------System.err:(1/16)----------
STATUS:Passed.
----------rerun:(42/6300)*----------

<snip>

result: Error. Program `c:\ade\mesos\work_dir\jib-master\install\jdk-17+5-266\windows-x64-debug.jdk\jdk-17\fastdebug\bin\java' timed out (timeout set to 480000ms, elapsed time including timeout handling was 2018867ms).

This test passed while the timeout handler was running.


Here's a snippet from the Compact_InternedStrings_NonbranchyTree log file:

#section:main
----------messages:(6/401)----------
command: main -XX:-UseGCOverheadLimit vm.gc.compact.Compact -gp interned(randomString) -gp1 nonbranchyTree(high) -ms high
reason: User specified action: run main/othervm -XX:-UseGCOverheadLimit vm.gc.compact.Compact -gp interned(randomString) -gp1 nonbranchyTree(high) -ms high 
Mode: othervm [/othervm specified]
Timeout information:
--- Timeout information end.
elapsed time (seconds): 911.97
----------configuration:(0/0)----------
----------System.out:(31/1674)----------
Stress time: 120 seconds
Stress iterations factor: 1
Stress threads factor: 1
Stress runs factor: 1
Max memory: 2063597568
Sleep time: 500
Iterations: 0
Number of threads: 16
Run GC thread: false
Run mem diag thread: false
Run forever: false
Starting Thread[vm.gc.compact.Compact$Worker@15165f1,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@4a0f7588,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@407bffd5,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@22b89a0b,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@d67769c,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@3c75f18d,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@23ef2481,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@45e18a35,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@7384c783,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@60eeb9e7,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@3a7f5257,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@7386c70f,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@26ebae23,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@33df1a98,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@73f20b5e,5,MainThreadGroup]
Starting Thread[vm.gc.compact.Compact$Worker@36090490,5,MainThreadGroup]
For random generator using seed: 7613058073302752077
To re-run test with same seed value please add "-Djdk.test.lib.random.seed=7613058073302752077" to command line.
Timeout refired 480 times
No unexpected exceptions/errors are thrown
----------System.err:(1/16)----------
STATUS:Passed.
----------rerun:(42/6419)*----------

<snip>

result: Error. Program `c:\ade\mesos\work_dir\jib-master\install\jdk-17+5-266\windows-x64-debug.jdk\jdk-17\fastdebug\bin\java' timed out (timeout set to 480000ms, elapsed time including timeout handling was 544717ms).

This test also passed while the timeout handler was running.

The test task includes the following VM options:

-XX:+CreateCoredumpOnCrash -XX:+UseZGC
Comments
Has not happened lately, and we've seen timeout on these kind of machines, that are not ZGC specific. I'll close this as CNR.
24-05-2021