Details
Description
The following test timed out in the JDK16 CI:
serviceability/sa/TestClassDump.java
Here's a snippet from the log file:
#section:driver
----------messages:(8/219)----------
command: driver TestClassDump
reason: User specified action: run driver TestClassDump
Mode: agentvm
Agent id: 9
Timeout refired 480 times
Timeout information:
--- Timeout information end.
elapsed time (seconds): 816.4
----------configuration:(13/1655)----------
Boot Layer
class path: /mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/javatest.jar
/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/jtreg.jar
/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/junit.jar
/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/testng.jar
patch: java.base /mesos/work_dir/slaves/0f76e7b4-d512-4e9f-a839-a1d961774dea-S23631/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/5bbaabb6-dfbc-47ed-a2e2-d0668cae4b66/runs/be253e2e-e000-43b3-b7a0-f2e4ccf0a853/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/patches/java.base
Test Layer
class path: /mesos/work_dir/slaves/0f76e7b4-d512-4e9f-a839-a1d961774dea-S23631/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/5bbaabb6-dfbc-47ed-a2e2-d0668cae4b66/runs/be253e2e-e000-43b3-b7a0-f2e4ccf0a853/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/classes/1/serviceability/sa/TestClassDump.d
/mesos/work_dir/jib-master/install/jdk-16+32-2192/src.full/open/test/hotspot/jtreg/serviceability/sa
/mesos/work_dir/slaves/0f76e7b4-d512-4e9f-a839-a1d961774dea-S23631/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/5bbaabb6-dfbc-47ed-a2e2-d0668cae4b66/runs/be253e2e-e000-43b3-b7a0-f2e4ccf0a853/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/classes/1/test/lib
/mesos/work_dir/jib-master/install/jdk-16+32-2192/src.full/open/test/lib
----------rerun:(30/5802)*----------
<snip>
----------System.out:(19/4763)----------
Command line: ['/mesos/work_dir/jib-master/install/jdk-16+32-2192/macosx-x64-debug.jdk/jdk-16/fastdebug/bin/java' '-XX:MaxRAMPercentage=12' '-Djava.io.tmpdir=/mesos/work_dir/slaves/0f76e7b4-d512-4e9f-a839-a1d961774dea-S23631/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/5bbaabb6-dfbc-47ed-a2e2-d0668cae4b66/runs/be253e2e-e000-43b3-b7a0-f2e4ccf0a853/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/tmp' '-Xshare:off' '-showversion' '-cp' '/mesos/work_dir/slaves/0f76e7b4-d512-4e9f-a839-a1d961774dea-S23631/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/5bbaabb6-dfbc-47ed-a2e2-d0668cae4b66/runs/be253e2e-e000-43b3-b7a0-f2e4ccf0a853/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/classes/1/serviceability/sa/TestClassDump.d:/mesos/work_dir/slaves/0f76e7b4-d512-4e9f-a839-a1d961774dea-S23631/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/5bbaabb6-dfbc-47ed-a2e2-d0668cae4b66/runs/be253e2e-e000-43b3-b7a0-f2e4ccf0a853/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/classes/1/test/lib' 'jdk.test.lib.apps.LingeredApp' 'a6538a60-b0f8-46a1-aa6a-014f02f4e77b.lck']
Started LingeredApp with pid 49271
Command line: [/mesos/work_dir/jib-master/install/jdk-16+32-2192/macosx-x64-debug.jdk/jdk-16/fastdebug/bin/java -cp /mesos/work_dir/slaves/0f76e7b4-d512-4e9f-a839-a1d961774dea-S23631/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/5bbaabb6-dfbc-47ed-a2e2-d0668cae4b66/runs/be253e2e-e000-43b3-b7a0-f2e4ccf0a853/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/classes/1/serviceability/sa/TestClassDump.d:/mesos/work_dir/jib-master/install/jdk-16+32-2192/src.full/open/test/hotspot/jtreg/serviceability/sa:/mesos/work_dir/slaves/0f76e7b4-d512-4e9f-a839-a1d961774dea-S23631/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/5bbaabb6-dfbc-47ed-a2e2-d0668cae4b66/runs/be253e2e-e000-43b3-b7a0-f2e4ccf0a853/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/classes/1/test/lib:/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/javatest.jar:/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/jtreg.jar:/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/junit.jar:/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/testng.jar -Dsun.jvm.hotspot.tools.jcore.outputDir=jtreg_classes -m jdk.hotspot.agent/sun.jvm.hotspot.tools.jcore.ClassDump 49271 ]
Adding 'sudo -E -n' to the command.
[2021-01-13T22:14:12.986064Z] Gathering output for process 49272
[2021-01-13T22:14:13.216653Z] Waiting for completion for process 49272
[2021-01-13T22:22:50.800598Z] Waiting for completion finished for process 49272
Command line: [/mesos/work_dir/jib-master/install/jdk-16+32-2192/macosx-x64-debug.jdk/jdk-16/fastdebug/bin/java -cp /mesos/work_dir/slaves/0f76e7b4-d512-4e9f-a839-a1d961774dea-S23631/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/5bbaabb6-dfbc-47ed-a2e2-d0668cae4b66/runs/be253e2e-e000-43b3-b7a0-f2e4ccf0a853/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/classes/1/serviceability/sa/TestClassDump.d:/mesos/work_dir/jib-master/install/jdk-16+32-2192/src.full/open/test/hotspot/jtreg/serviceability/sa:/mesos/work_dir/slaves/0f76e7b4-d512-4e9f-a839-a1d961774dea-S23631/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/5bbaabb6-dfbc-47ed-a2e2-d0668cae4b66/runs/be253e2e-e000-43b3-b7a0-f2e4ccf0a853/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/classes/1/test/lib:/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/javatest.jar:/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/jtreg.jar:/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/junit.jar:/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/testng.jar -Dsun.jvm.hotspot.tools.jcore.outputDir=jtreg_classes2 -Dsun.jvm.hotspot.tools.jcore.PackageNameFilter.pkgList=jdk,sun -m jdk.hotspot.agent/sun.jvm.hotspot.tools.jcore.ClassDump 49271 ]
Adding 'sudo -E -n' to the command.
[2021-01-13T22:22:54.311086Z] Gathering output for process 49417
[2021-01-13T22:22:54.313088Z] Waiting for completion for process 49417
[2021-01-13T22:24:47.790129Z] Waiting for completion finished for process 49417
LingeredApp stdout: [];
LingeredApp stderr: [java version "16-ea" 2021-03-16
Java(TM) SE Runtime Environment (fastdebug build 16-ea+32-2192)
Java HotSpot(TM) 64-Bit Server VM (fastdebug build 16-ea+32-2192, mixed mode)
]
LingeredApp exitValue = 0
Test PASSED
----------System.err:(3/35)----------
JavaTest Message: Test complete.
result: Error. Agent error: java.lang.Exception: Agent 9 timed out with a timeout of 480 seconds; check console log for any additional details
Based on the "Test PASSED" output, it looks like the
test passed while the timeout handler was running.
serviceability/sa/TestClassDump.java
Here's a snippet from the log file:
#section:driver
----------messages:(8/219)----------
command: driver TestClassDump
reason: User specified action: run driver TestClassDump
Mode: agentvm
Agent id: 9
Timeout refired 480 times
Timeout information:
--- Timeout information end.
elapsed time (seconds): 816.4
----------configuration:(13/1655)----------
Boot Layer
class path: /mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/javatest.jar
/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/jtreg.jar
/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/junit.jar
/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/testng.jar
patch: java.base /mesos/work_dir/slaves/0f76e7b4-d512-4e9f-a839-a1d961774dea-S23631/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/5bbaabb6-dfbc-47ed-a2e2-d0668cae4b66/runs/be253e2e-e000-43b3-b7a0-f2e4ccf0a853/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/patches/java.base
Test Layer
class path: /mesos/work_dir/slaves/0f76e7b4-d512-4e9f-a839-a1d961774dea-S23631/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/5bbaabb6-dfbc-47ed-a2e2-d0668cae4b66/runs/be253e2e-e000-43b3-b7a0-f2e4ccf0a853/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/classes/1/serviceability/sa/TestClassDump.d
/mesos/work_dir/jib-master/install/jdk-16+32-2192/src.full/open/test/hotspot/jtreg/serviceability/sa
/mesos/work_dir/slaves/0f76e7b4-d512-4e9f-a839-a1d961774dea-S23631/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/5bbaabb6-dfbc-47ed-a2e2-d0668cae4b66/runs/be253e2e-e000-43b3-b7a0-f2e4ccf0a853/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/classes/1/test/lib
/mesos/work_dir/jib-master/install/jdk-16+32-2192/src.full/open/test/lib
----------rerun:(30/5802)*----------
<snip>
----------System.out:(19/4763)----------
Command line: ['/mesos/work_dir/jib-master/install/jdk-16+32-2192/macosx-x64-debug.jdk/jdk-16/fastdebug/bin/java' '-XX:MaxRAMPercentage=12' '-Djava.io.tmpdir=/mesos/work_dir/slaves/0f76e7b4-d512-4e9f-a839-a1d961774dea-S23631/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/5bbaabb6-dfbc-47ed-a2e2-d0668cae4b66/runs/be253e2e-e000-43b3-b7a0-f2e4ccf0a853/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/tmp' '-Xshare:off' '-showversion' '-cp' '/mesos/work_dir/slaves/0f76e7b4-d512-4e9f-a839-a1d961774dea-S23631/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/5bbaabb6-dfbc-47ed-a2e2-d0668cae4b66/runs/be253e2e-e000-43b3-b7a0-f2e4ccf0a853/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/classes/1/serviceability/sa/TestClassDump.d:/mesos/work_dir/slaves/0f76e7b4-d512-4e9f-a839-a1d961774dea-S23631/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/5bbaabb6-dfbc-47ed-a2e2-d0668cae4b66/runs/be253e2e-e000-43b3-b7a0-f2e4ccf0a853/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/classes/1/test/lib' 'jdk.test.lib.apps.LingeredApp' 'a6538a60-b0f8-46a1-aa6a-014f02f4e77b.lck']
Started LingeredApp with pid 49271
Command line: [/mesos/work_dir/jib-master/install/jdk-16+32-2192/macosx-x64-debug.jdk/jdk-16/fastdebug/bin/java -cp /mesos/work_dir/slaves/0f76e7b4-d512-4e9f-a839-a1d961774dea-S23631/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/5bbaabb6-dfbc-47ed-a2e2-d0668cae4b66/runs/be253e2e-e000-43b3-b7a0-f2e4ccf0a853/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/classes/1/serviceability/sa/TestClassDump.d:/mesos/work_dir/jib-master/install/jdk-16+32-2192/src.full/open/test/hotspot/jtreg/serviceability/sa:/mesos/work_dir/slaves/0f76e7b4-d512-4e9f-a839-a1d961774dea-S23631/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/5bbaabb6-dfbc-47ed-a2e2-d0668cae4b66/runs/be253e2e-e000-43b3-b7a0-f2e4ccf0a853/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/classes/1/test/lib:/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/javatest.jar:/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/jtreg.jar:/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/junit.jar:/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/testng.jar -Dsun.jvm.hotspot.tools.jcore.outputDir=jtreg_classes -m jdk.hotspot.agent/sun.jvm.hotspot.tools.jcore.ClassDump 49271 ]
Adding 'sudo -E -n' to the command.
[2021-01-13T22:14:12.986064Z] Gathering output for process 49272
[2021-01-13T22:14:13.216653Z] Waiting for completion for process 49272
[2021-01-13T22:22:50.800598Z] Waiting for completion finished for process 49272
Command line: [/mesos/work_dir/jib-master/install/jdk-16+32-2192/macosx-x64-debug.jdk/jdk-16/fastdebug/bin/java -cp /mesos/work_dir/slaves/0f76e7b4-d512-4e9f-a839-a1d961774dea-S23631/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/5bbaabb6-dfbc-47ed-a2e2-d0668cae4b66/runs/be253e2e-e000-43b3-b7a0-f2e4ccf0a853/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/classes/1/serviceability/sa/TestClassDump.d:/mesos/work_dir/jib-master/install/jdk-16+32-2192/src.full/open/test/hotspot/jtreg/serviceability/sa:/mesos/work_dir/slaves/0f76e7b4-d512-4e9f-a839-a1d961774dea-S23631/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/5bbaabb6-dfbc-47ed-a2e2-d0668cae4b66/runs/be253e2e-e000-43b3-b7a0-f2e4ccf0a853/testoutput/test-support/jtreg_open_test_hotspot_jtreg_tier1_serviceability/classes/1/test/lib:/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/javatest.jar:/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/jtreg.jar:/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/junit.jar:/mesos/work_dir/jib-master/install/jtreg/5.1/b01/bundles/jtreg_bin-5.1.zip/jtreg/lib/testng.jar -Dsun.jvm.hotspot.tools.jcore.outputDir=jtreg_classes2 -Dsun.jvm.hotspot.tools.jcore.PackageNameFilter.pkgList=jdk,sun -m jdk.hotspot.agent/sun.jvm.hotspot.tools.jcore.ClassDump 49271 ]
Adding 'sudo -E -n' to the command.
[2021-01-13T22:22:54.311086Z] Gathering output for process 49417
[2021-01-13T22:22:54.313088Z] Waiting for completion for process 49417
[2021-01-13T22:24:47.790129Z] Waiting for completion finished for process 49417
LingeredApp stdout: [];
LingeredApp stderr: [java version "16-ea" 2021-03-16
Java(TM) SE Runtime Environment (fastdebug build 16-ea+32-2192)
Java HotSpot(TM) 64-Bit Server VM (fastdebug build 16-ea+32-2192, mixed mode)
]
LingeredApp exitValue = 0
Test PASSED
----------System.err:(3/35)----------
JavaTest Message: Test complete.
result: Error. Agent error: java.lang.Exception: Agent 9 timed out with a timeout of 480 seconds; check console log for any additional details
Based on the "Test PASSED" output, it looks like the
test passed while the timeout handler was running.