-
Bug
-
Resolution: Cannot Reproduce
-
P4
-
17, 18
-
x86_64
-
windows
The following test timed out in the JDK17 CI:
vmTestbase/jit/escape/AdaptiveBlocking/AdaptiveBlocking001/AdaptiveBlocking001.java
Here's a snippet from the log file:
#section:driver
----------messages:(8/489)----------
command: driver ExecDriver --java -server -Xcomp -XX:+DoEscapeAnalysis jit.escape.AdaptiveBlocking.AdaptiveBlocking001.AdaptiveBlocking001 -numRounds 10
reason: User specified action: run driver/timeout=300 ExecDriver --java -server -Xcomp -XX:+DoEscapeAnalysis jit.escape.AdaptiveBlocking.AdaptiveBlocking001.AdaptiveBlocking001 -numRounds 10
Mode: agentvm
Agent id: 5
Timeout refired 3000 times
Timeout information:
--- Timeout information end.
elapsed time (seconds): 4706.187
----------configuration:(15/1548)*----------
Boot Layer
class path: C:\\ade\\mesos\\work_dir\\jib-master\\install\\jtreg\\5.1\\b01\\bundles\\jtreg_bin-5.1.zip\\jtreg\\lib\\javatest.jar
C:\\ade\\mesos\\work_dir\\jib-master\\install\\jtreg\\5.1\\b01\\bundles\\jtreg_bin-5.1.zip\\jtreg\\lib\\jtreg.jar
C:\\ade\\mesos\\work_dir\\jib-master\\install\\jtreg\\5.1\\b01\\bundles\\jtreg_bin-5.1.zip\\jtreg\\lib\\junit.jar
C:\\ade\\mesos\\work_dir\\jib-master\\install\\jtreg\\5.1\\b01\\bundles\\jtreg_bin-5.1.zip\\jtreg\\lib\\testng.jar
patch: java.base T:\\testoutput\\test-support\\jtreg_open_test_hotspot_jtreg_vmTestbase_vm_compiler_quick\\patches\\java.base
Test Layer
class path: T:\\testoutput\\test-support\\jtreg_open_test_hotspot_jtreg_vmTestbase_vm_compiler_quick\\classes\\2\\vmTestbase\\jit\\escape\\AdaptiveBlocking\\AdaptiveBlocking001\\AdaptiveBlocking001.d
C:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+6-358\\src.full\\open\\test\\hotspot\\jtreg\\vmTestbase\\jit\\escape\\AdaptiveBlocking\\AdaptiveBlocking001
T:\\testoutput\\test-support\\jtreg_open_test_hotspot_jtreg_vmTestbase_vm_compiler_quick\\classes\\2\\vmTestbase
C:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+6-358\\src.full\\open\\test\\hotspot\\jtreg\\vmTestbase
T:\\testoutput\\test-support\\jtreg_open_test_hotspot_jtreg_vmTestbase_vm_compiler_quick\\classes\\2\\test\\lib
C:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+6-358\\src.full\\open\\test\\lib
----------rerun:(37/6301)*----------
<snip>
----------System.out:(23/1638)*----------
exec [c:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+6-358\\windows-x64-debug.jdk\\jdk-17\\fastdebug\\bin\\java, -XX:MaxRAMPercentage=6, -Djava.io.tmpdir=t:\\testoutput\\test-support\\jtreg_open_test_hotspot_jtreg_vmTestbase_vm_compiler_quick\\tmp, -XX:+CreateCoredumpOnCrash, -XX:+UseZGC, -cp, T:\\testoutput\\test-support\\jtreg_open_test_hotspot_jtreg_vmTestbase_vm_compiler_quick\\classes\\2\\vmTestbase\\jit\\escape\\AdaptiveBlocking\\AdaptiveBlocking001\\AdaptiveBlocking001.d;T:\\testoutput\\test-support\\jtreg_open_test_hotspot_jtreg_vmTestbase_vm_compiler_quick\\classes\\2\\vmTestbase;T:\\testoutput\\test-support\\jtreg_open_test_hotspot_jtreg_vmTestbase_vm_compiler_quick\\classes\\2\\test\\lib, -Djava.library.path=c:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+6-358\\windows-x64-debug.test\\hotspot\\jtreg\\native, -server, -Xcomp, -XX:+DoEscapeAnalysis, jit.escape.AdaptiveBlocking.AdaptiveBlocking001.AdaptiveBlocking001, -numRounds, 10]
Adaptive blocking test
round #0, latent locks: no...
round #0, latent locks: yes...
round #1, latent locks: no...
round #1, latent locks: yes...
round #2, latent locks: no...
round #2, latent locks: yes...
round #3, latent locks: no...
round #3, latent locks: yes...
round #4, latent locks: no...
round #4, latent locks: yes...
round #5, latent locks: no...
round #5, latent locks: yes...
round #6, latent locks: no...
round #6, latent locks: yes...
round #7, latent locks: no...
round #7, latent locks: yes...
round #8, latent locks: no...
round #8, latent locks: yes...
round #9, latent locks: no...
round #9, latent locks: yes...
TEST PASSED
----------System.err:(3/38)----------
JavaTest Message: Test complete.
result: Error. Agent error: java.lang.Exception: Agent 5 timed out with a timeout of 3000 seconds; check console log for any additional details
Please note that the test passed while the timeout handler was
running. The test executed for 78 minutes:
elapsed time (seconds): 4706.187
which is a pretty long time. The test is invoked with:
run driver/timeout=300
and the typical timeoutFactor is 4 so this test should have
run for (4 * 300) => 1200 seconds before timeout. Instead
we see this:
Timeout refired 3000 times
which indicates that that the timeoutFactor is 10 for this
test task. Normally, I would check the task's log file, but
the test task is showing a state of "SCHEDULED" so I
think the test task is being rerun (for some reason).
Starting this bug off in hotspot/compiler since this is a
"jit/escape" test.
vmTestbase/jit/escape/AdaptiveBlocking/AdaptiveBlocking001/AdaptiveBlocking001.java
Here's a snippet from the log file:
#section:driver
----------messages:(8/489)----------
command: driver ExecDriver --java -server -Xcomp -XX:+DoEscapeAnalysis jit.escape.AdaptiveBlocking.AdaptiveBlocking001.AdaptiveBlocking001 -numRounds 10
reason: User specified action: run driver/timeout=300 ExecDriver --java -server -Xcomp -XX:+DoEscapeAnalysis jit.escape.AdaptiveBlocking.AdaptiveBlocking001.AdaptiveBlocking001 -numRounds 10
Mode: agentvm
Agent id: 5
Timeout refired 3000 times
Timeout information:
--- Timeout information end.
elapsed time (seconds): 4706.187
----------configuration:(15/1548)*----------
Boot Layer
class path: C:\\ade\\mesos\\work_dir\\jib-master\\install\\jtreg\\5.1\\b01\\bundles\\jtreg_bin-5.1.zip\\jtreg\\lib\\javatest.jar
C:\\ade\\mesos\\work_dir\\jib-master\\install\\jtreg\\5.1\\b01\\bundles\\jtreg_bin-5.1.zip\\jtreg\\lib\\jtreg.jar
C:\\ade\\mesos\\work_dir\\jib-master\\install\\jtreg\\5.1\\b01\\bundles\\jtreg_bin-5.1.zip\\jtreg\\lib\\junit.jar
C:\\ade\\mesos\\work_dir\\jib-master\\install\\jtreg\\5.1\\b01\\bundles\\jtreg_bin-5.1.zip\\jtreg\\lib\\testng.jar
patch: java.base T:\\testoutput\\test-support\\jtreg_open_test_hotspot_jtreg_vmTestbase_vm_compiler_quick\\patches\\java.base
Test Layer
class path: T:\\testoutput\\test-support\\jtreg_open_test_hotspot_jtreg_vmTestbase_vm_compiler_quick\\classes\\2\\vmTestbase\\jit\\escape\\AdaptiveBlocking\\AdaptiveBlocking001\\AdaptiveBlocking001.d
C:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+6-358\\src.full\\open\\test\\hotspot\\jtreg\\vmTestbase\\jit\\escape\\AdaptiveBlocking\\AdaptiveBlocking001
T:\\testoutput\\test-support\\jtreg_open_test_hotspot_jtreg_vmTestbase_vm_compiler_quick\\classes\\2\\vmTestbase
C:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+6-358\\src.full\\open\\test\\hotspot\\jtreg\\vmTestbase
T:\\testoutput\\test-support\\jtreg_open_test_hotspot_jtreg_vmTestbase_vm_compiler_quick\\classes\\2\\test\\lib
C:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+6-358\\src.full\\open\\test\\lib
----------rerun:(37/6301)*----------
<snip>
----------System.out:(23/1638)*----------
exec [c:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+6-358\\windows-x64-debug.jdk\\jdk-17\\fastdebug\\bin\\java, -XX:MaxRAMPercentage=6, -Djava.io.tmpdir=t:\\testoutput\\test-support\\jtreg_open_test_hotspot_jtreg_vmTestbase_vm_compiler_quick\\tmp, -XX:+CreateCoredumpOnCrash, -XX:+UseZGC, -cp, T:\\testoutput\\test-support\\jtreg_open_test_hotspot_jtreg_vmTestbase_vm_compiler_quick\\classes\\2\\vmTestbase\\jit\\escape\\AdaptiveBlocking\\AdaptiveBlocking001\\AdaptiveBlocking001.d;T:\\testoutput\\test-support\\jtreg_open_test_hotspot_jtreg_vmTestbase_vm_compiler_quick\\classes\\2\\vmTestbase;T:\\testoutput\\test-support\\jtreg_open_test_hotspot_jtreg_vmTestbase_vm_compiler_quick\\classes\\2\\test\\lib, -Djava.library.path=c:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+6-358\\windows-x64-debug.test\\hotspot\\jtreg\\native, -server, -Xcomp, -XX:+DoEscapeAnalysis, jit.escape.AdaptiveBlocking.AdaptiveBlocking001.AdaptiveBlocking001, -numRounds, 10]
Adaptive blocking test
round #0, latent locks: no...
round #0, latent locks: yes...
round #1, latent locks: no...
round #1, latent locks: yes...
round #2, latent locks: no...
round #2, latent locks: yes...
round #3, latent locks: no...
round #3, latent locks: yes...
round #4, latent locks: no...
round #4, latent locks: yes...
round #5, latent locks: no...
round #5, latent locks: yes...
round #6, latent locks: no...
round #6, latent locks: yes...
round #7, latent locks: no...
round #7, latent locks: yes...
round #8, latent locks: no...
round #8, latent locks: yes...
round #9, latent locks: no...
round #9, latent locks: yes...
TEST PASSED
----------System.err:(3/38)----------
JavaTest Message: Test complete.
result: Error. Agent error: java.lang.Exception: Agent 5 timed out with a timeout of 3000 seconds; check console log for any additional details
Please note that the test passed while the timeout handler was
running. The test executed for 78 minutes:
elapsed time (seconds): 4706.187
which is a pretty long time. The test is invoked with:
run driver/timeout=300
and the typical timeoutFactor is 4 so this test should have
run for (4 * 300) => 1200 seconds before timeout. Instead
we see this:
Timeout refired 3000 times
which indicates that that the timeoutFactor is 10 for this
test task. Normally, I would check the task's log file, but
the test task is showing a state of "SCHEDULED" so I
think the test task is being rerun (for some reason).
Starting this bug off in hotspot/compiler since this is a
"jit/escape" test.
- relates to
-
JDK-8260062 runtime/handshake/HandshakeDirectTest.java timed out
- Closed
-
JDK-8263618 misc tests timeout on a particular test machine with ZGC
- Closed