JDK-8249171 : HandshakeTransitionTest.java timed out
  • Type: Bug
  • Component: hotspot
  • Sub-Component: runtime
  • Affected Version: 16,17
  • Priority: P4
  • Status: Resolved
  • Resolution: Cannot Reproduce
  • OS: linux
  • CPU: x86_64
  • Submitted: 2020-07-10
  • Updated: 2021-06-07
  • Resolved: 2021-06-07
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 :  
JDK-8263618 - misc tests timeout on a particular test machine with ZGC
Relates :  
JDK-8260062 - runtime/handshake/HandshakeDirectTest.java timed out
Description
The following test timed out in the JDK16 CI:

runtime/handshake/HandshakeTransitionTest.java

Here's a snippet from the log file:

#section:main
----------messages:(6/371)----------
command: main -Xbootclasspath/a:. -XX:+UnlockDiagnosticVMOptions -XX:+WhiteBoxAPI HandshakeTransitionTest
reason: User specified action: run main/othervm/native -Xbootclasspath/a:. -XX:+UnlockDiagnosticVMOptions -XX:+WhiteBoxAPI HandshakeTransitionTest 
Mode: othervm [/othervm specified]
Timeout information:
--- Timeout information end.
elapsed time (seconds): 1223.56
----------configuration:(0/0)----------
----------System.out:(3/2528)----------
Command line: [/opt/mach5/mesos/work_dir/jib-master/install/jdk-16+5-163/linux-x64-debug.jdk/jdk-16/fastdebug/bin/java -cp /opt/mach5/mesos/work_dir/slaves/52628e90-e5e7-4ef3-af97-10d8776d10db-S1014/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/98c190e1-b757-4131-b50f-f39f236edfc2/runs/bad25f36-0b1d-47d1-9375-b3aef4a1e4d9/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_runtime/classes/3/runtime/handshake/HandshakeTransitionTest.d:/opt/mach5/mesos/work_dir/jib-master/install/jdk-16+5-163/src.full/open/test/hotspot/jtreg/runtime/handshake:/opt/mach5/mesos/work_dir/slaves/52628e90-e5e7-4ef3-af97-10d8776d10db-S1014/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/98c190e1-b757-4131-b50f-f39f236edfc2/runs/bad25f36-0b1d-47d1-9375-b3aef4a1e4d9/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_runtime/classes/3/testlibrary:/opt/mach5/mesos/work_dir/jib-master/install/jdk-16+5-163/src.full/open/test/hotspot/jtreg/testlibrary:/opt/mach5/mesos/work_dir/slaves/52628e90-e5e7-4ef3-af97-10d8776d10db-S1014/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/98c190e1-b757-4131-b50f-f39f236edfc2/runs/bad25f36-0b1d-47d1-9375-b3aef4a1e4d9/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_runtime/classes/3/test/lib:/opt/mach5/mesos/work_dir/jib-master/install/jdk-16+5-163/src.full/open/test/lib:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/javatest.jar:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/jtreg.jar -XX:MaxRAMPercentage=6 -Djava.io.tmpdir=/opt/mach5/mesos/work_dir/slaves/52628e90-e5e7-4ef3-af97-10d8776d10db-S1014/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/98c190e1-b757-4131-b50f-f39f236edfc2/runs/bad25f36-0b1d-47d1-9375-b3aef4a1e4d9/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_runtime/tmp -Xcomp -XX:+CreateCoredumpOnCrash -ea -esa -XX:CompileThreshold=100 -XX:+UnlockExperimentalVMOptions -server -XX:+TieredCompilation -XX:-DoEscapeAnalysis -Djava.library.path=/opt/mach5/mesos/work_dir/jib-master/install/jdk-16+5-163/linux-x64-debug.test/hotspot/jtreg/native -XX:+SafepointALot -XX:+HandshakeALot -XX:GuaranteedSafepointInterval=20 -Xlog:ergo* -XX:ParallelGCThreads=1 -XX:ConcGCThreads=1 -XX:CICompilerCount=2 -XX:+UnlockExperimentalVMOptions -XX:-UseJVMCICompiler HandshakeTransitionTest$Test ]
[2020-07-09T22:28:28.278980983Z] Gathering output for process 3121611
Timeout refired 1200 times
----------System.err:(0/0)----------
----------rerun:(48/8661)*----------


Here are the options specified in this test task:

-Xcomp -XX:+CreateCoredumpOnCrash -ea -esa -XX:CompileThreshold=100 -XX:+UnlockExperimentalVMOptions -server -XX:+TieredCompilation -XX:-DoEscapeAnalysis
Comments
I haven't been able to reproduce the timeout. I believe this issue is related to the other timeouts we haven seen on Windows machines with -XX:+UseZGC(JDK-8263618). In particular this test failed on the same job where HandshakeDirectTest.java did (JDK-8260062). Test HandshakeDirectTest.java at least failed with timeout a couple more times after that but not this one. I think we should close this bug as CNR and link it to the other two bugs.
01-06-2021
Not reproducible. Will open again in case of new failure.
01-12-2020
Thanks [~mseledtsov] and [~dcubed].
01-12-2020
> Are you okay with that [~dcubed]? Yes.
01-12-2020
I tried to reproduce it locally but the test always passes. Since this timeout was only seen once almost 5 months ago maybe it makes sense to close it as can't reproduce and open it again in case it happens again. Are you okay with that [~dcubed]?
30-11-2020
ILW = MLM = P4
14-07-2020