The following test failed in the JDK20 CI:
runtime/cds/appcds/jcmd/JCmdTestDynamicDump.java
Here's a snippet from the log file:
#section:main
----------messages:(7/527)----------
command: main -Xbootclasspath/a:. -XX:+UnlockDiagnosticVMOptions -XX:+WhiteBoxAPI JCmdTestDynamicDump
reason: User specified action: run main/othervm/timeout=480 -Xbootclasspath/a:. -XX:+UnlockDiagnosticVMOptions -XX:+WhiteBoxAPI JCmdTestDynamicDump
Mode: othervm [/othervm specified]
Additional options from @modules: --add-modules jdk.jcmd --add-exports jdk.jcmd/sun.tools.common=ALL-UNNAMED --add-opens jdk.jcmd/sun.tools.common=ALL-UNNAMED
Timeout information:
--- Timeout information end.
elapsed time (seconds): 2004.71
----------configuration:(5/160)----------
Boot Layer
add modules: jdk.jcmd
add exports: jdk.jcmd/sun.tools.common ALL-UNNAMED
add opens: jdk.jcmd/sun.tools.common ALL-UNNAMED
----------System.out:(22/2779)----------
Jar file created: /System/Volumes/Data/mesos/work_dir/slaves/0c72054a-24ab-4dbb-944f-97f9341a1b96-S34753/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/7147585d-3e33-4026-878f-9db879415ed8/runs/476fc25c-21b8-4e08-bf87-ef1390477d59/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_tier2_runtime/scratch/1/test
Jar file created: /System/Volumes/Data/mesos/work_dir/slaves/0c72054a-24ab-4dbb-944f-97f9341a1b96-S34753/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/7147585d-3e33-4026-878f-9db879415ed8/runs/476fc25c-21b8-4e08-bf87-ef1390477d59/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_tier2_runtime/scratch/1/boot
1 Test dynamic dump with -XX:+RecordDynamicDumpInfo.
Command line: ['/System/Volumes/Data/mesos/work_dir/jib-master/install/jdk-20+7-372/macosx-aarch64-debug.jdk/jdk-20/fastdebug/bin/java' '-cp' '/System/Volumes/Data/mesos/work_dir/slaves/0c72054a-24ab-4dbb-944f-97f9341a1b96-S34753/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/7147585d-3e33-4026-878f-9db879415ed8/runs/476fc25c-21b8-4e08-bf87-ef1390477d59/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_tier2_runtime/scratch/1/test:/System/Volumes/Data/mesos/work_dir/slaves/0c72054a-24ab-4dbb-944f-97f9341a1b96-S34753/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/7147585d-3e33-4026-878f-9db879415ed8/runs/476fc25c-21b8-4e08-bf87-ef1390477d59/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_tier2_runtime/scratch/1/boot' '-XX:+RecordDynamicDumpInfo' 'JCmdTestLingeredApp' '32004432-80bd-414a-b42a-6fd1d517f9b9.lck']
Expected: SUCCESS
Executing command '[/System/Volumes/Data/mesos/work_dir/jib-master/install/jdk-20+7-372/macosx-aarch64-debug.jdk/jdk-20/fastdebug/bin/jcmd, 28465, VM.cds dynamic_dump mydynamic01.jsa]'
[2022-07-20T15:21:29.260919Z] Gathering output for process 28473
Timeout refired 1920 times
[2022-07-20T15:53:49.445051Z] Waiting for completion for process 28473
[2022-07-20T15:53:49.445140Z] Waiting for completion finished for process 28473
Output and diagnostic info for process 28473 was saved into 'pid-28473-output.log'
[2022-07-20T15:53:49.449119Z] Waiting for completion for process 28473
[2022-07-20T15:53:49.449168Z] Waiting for completion finished for process 28473
Command returned with exit code 134
[2022-07-20T15:53:49.449249Z] Waiting for completion for process 28473
[2022-07-20T15:53:49.449284Z] Waiting for completion finished for process 28473
[2022-07-20T15:53:49.449316Z] Waiting for completion for process 28473
[2022-07-20T15:53:49.449340Z] Waiting for completion finished for process 28473
[2022-07-20T15:53:49.454305Z] Waiting for completion for process 28473
[2022-07-20T15:53:49.454348Z] Waiting for completion finished for process 28473
----------System.err:(21/1180)----------
stdout: [28465:
];
stderr: [Java HotSpot(TM) 64-Bit Server VM warning: outputStream::do_vsnprintf output truncated -- buffer length is 2000 bytes but 2116 bytes are needed.
]
exitValue = 134
java.lang.RuntimeException: Expected to get exit value of [0], exit value is: [134]
at jdk.test.lib.process.OutputAnalyzer.shouldHaveExitValue(OutputAnalyzer.java:490)
at JCmdTestDumpBase.test(JCmdTestDumpBase.java:193)
at JCmdTestDynamicDump.test(JCmdTestDynamicDump.java:62)
at JCmdTestDumpBase.runTest(JCmdTestDumpBase.java:62)
at JCmdTestDynamicDump.main(JCmdTestDynamicDump.java:147)
at java.base/jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:104)
at java.base/java.lang.reflect.Method.invoke(Method.java:578)
at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:127)
at java.base/java.lang.Thread.run(Thread.java:1589)
JavaTest Message: Test threw exception: java.lang.RuntimeException: Expected to get exit value of [0], exit value is: [134]
JavaTest Message: shutting down test
STATUS:Failed.`main' threw exception: java.lang.RuntimeException: Expected to get exit value of [0], exit value is: [134]
----------rerun:(38/10051)*----------
....
test result: Error. Program `/System/Volumes/Data/mesos/work_dir/jib-master/install/jdk-20+7-372/macosx-aarch64-debug.jdk/jdk-20/fastdebug/bin/java' timed out (timeout set to 1920000ms, elapsed time including timeout handling was 2004708ms).
runtime/cds/appcds/jcmd/JCmdTestDynamicDump.java
Here's a snippet from the log file:
#section:main
----------messages:(7/527)----------
command: main -Xbootclasspath/a:. -XX:+UnlockDiagnosticVMOptions -XX:+WhiteBoxAPI JCmdTestDynamicDump
reason: User specified action: run main/othervm/timeout=480 -Xbootclasspath/a:. -XX:+UnlockDiagnosticVMOptions -XX:+WhiteBoxAPI JCmdTestDynamicDump
Mode: othervm [/othervm specified]
Additional options from @modules: --add-modules jdk.jcmd --add-exports jdk.jcmd/sun.tools.common=ALL-UNNAMED --add-opens jdk.jcmd/sun.tools.common=ALL-UNNAMED
Timeout information:
--- Timeout information end.
elapsed time (seconds): 2004.71
----------configuration:(5/160)----------
Boot Layer
add modules: jdk.jcmd
add exports: jdk.jcmd/sun.tools.common ALL-UNNAMED
add opens: jdk.jcmd/sun.tools.common ALL-UNNAMED
----------System.out:(22/2779)----------
Jar file created: /System/Volumes/Data/mesos/work_dir/slaves/0c72054a-24ab-4dbb-944f-97f9341a1b96-S34753/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/7147585d-3e33-4026-878f-9db879415ed8/runs/476fc25c-21b8-4e08-bf87-ef1390477d59/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_tier2_runtime/scratch/1/test
Jar file created: /System/Volumes/Data/mesos/work_dir/slaves/0c72054a-24ab-4dbb-944f-97f9341a1b96-S34753/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/7147585d-3e33-4026-878f-9db879415ed8/runs/476fc25c-21b8-4e08-bf87-ef1390477d59/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_tier2_runtime/scratch/1/boot
1 Test dynamic dump with -XX:+RecordDynamicDumpInfo.
Command line: ['/System/Volumes/Data/mesos/work_dir/jib-master/install/jdk-20+7-372/macosx-aarch64-debug.jdk/jdk-20/fastdebug/bin/java' '-cp' '/System/Volumes/Data/mesos/work_dir/slaves/0c72054a-24ab-4dbb-944f-97f9341a1b96-S34753/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/7147585d-3e33-4026-878f-9db879415ed8/runs/476fc25c-21b8-4e08-bf87-ef1390477d59/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_tier2_runtime/scratch/1/test:/System/Volumes/Data/mesos/work_dir/slaves/0c72054a-24ab-4dbb-944f-97f9341a1b96-S34753/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/7147585d-3e33-4026-878f-9db879415ed8/runs/476fc25c-21b8-4e08-bf87-ef1390477d59/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_tier2_runtime/scratch/1/boot' '-XX:+RecordDynamicDumpInfo' 'JCmdTestLingeredApp' '32004432-80bd-414a-b42a-6fd1d517f9b9.lck']
Expected: SUCCESS
Executing command '[/System/Volumes/Data/mesos/work_dir/jib-master/install/jdk-20+7-372/macosx-aarch64-debug.jdk/jdk-20/fastdebug/bin/jcmd, 28465, VM.cds dynamic_dump mydynamic01.jsa]'
[2022-07-20T15:21:29.260919Z] Gathering output for process 28473
Timeout refired 1920 times
[2022-07-20T15:53:49.445051Z] Waiting for completion for process 28473
[2022-07-20T15:53:49.445140Z] Waiting for completion finished for process 28473
Output and diagnostic info for process 28473 was saved into 'pid-28473-output.log'
[2022-07-20T15:53:49.449119Z] Waiting for completion for process 28473
[2022-07-20T15:53:49.449168Z] Waiting for completion finished for process 28473
Command returned with exit code 134
[2022-07-20T15:53:49.449249Z] Waiting for completion for process 28473
[2022-07-20T15:53:49.449284Z] Waiting for completion finished for process 28473
[2022-07-20T15:53:49.449316Z] Waiting for completion for process 28473
[2022-07-20T15:53:49.449340Z] Waiting for completion finished for process 28473
[2022-07-20T15:53:49.454305Z] Waiting for completion for process 28473
[2022-07-20T15:53:49.454348Z] Waiting for completion finished for process 28473
----------System.err:(21/1180)----------
stdout: [28465:
];
stderr: [Java HotSpot(TM) 64-Bit Server VM warning: outputStream::do_vsnprintf output truncated -- buffer length is 2000 bytes but 2116 bytes are needed.
]
exitValue = 134
java.lang.RuntimeException: Expected to get exit value of [0], exit value is: [134]
at jdk.test.lib.process.OutputAnalyzer.shouldHaveExitValue(OutputAnalyzer.java:490)
at JCmdTestDumpBase.test(JCmdTestDumpBase.java:193)
at JCmdTestDynamicDump.test(JCmdTestDynamicDump.java:62)
at JCmdTestDumpBase.runTest(JCmdTestDumpBase.java:62)
at JCmdTestDynamicDump.main(JCmdTestDynamicDump.java:147)
at java.base/jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:104)
at java.base/java.lang.reflect.Method.invoke(Method.java:578)
at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:127)
at java.base/java.lang.Thread.run(Thread.java:1589)
JavaTest Message: Test threw exception: java.lang.RuntimeException: Expected to get exit value of [0], exit value is: [134]
JavaTest Message: shutting down test
STATUS:Failed.`main' threw exception: java.lang.RuntimeException: Expected to get exit value of [0], exit value is: [134]
----------rerun:(38/10051)*----------
....
test result: Error. Program `/System/Volumes/Data/mesos/work_dir/jib-master/install/jdk-20+7-372/macosx-aarch64-debug.jdk/jdk-20/fastdebug/bin/java' timed out (timeout set to 1920000ms, elapsed time including timeout handling was 2004708ms).
- relates to
-
JDK-8299228 JCmdTestStaticDump.java timed out in VirtualMachineImpl.read()
-
- Open
-