The following test failed in the JDK22 CI:
runtime/8176717/TestInheritFD.java
Here's a snippet from the log file:
#section:driver
----------messages:(8/303)----------
command: driver TestInheritFD
reason: User specified action: run driver TestInheritFD
started: Sat Aug 12 09:46:08 UTC 2023
Mode: agentvm
Agent id: 14
Additional exports to unnamed modules from @modules: java.base/jdk.internal.misc
finished: Sat Aug 12 09:46:45 UTC 2023
elapsed time (seconds): 37.566
----------configuration:(15/1943)----------
<snip>
----------System.out:(29/5670)----------
subProcessTimeout=60 seconds.
First VM starts.
Command line: [/opt/mach5/mesos/work_dir/jib-master/install/jdk-22+11-741/linux-x64-debug.jdk/jdk-22/fastdebug/bin/java -cp /opt/mach5/mesos/work_dir/slaves/cd627e65-f015-4fb1-a1d2-b6c9b8127f98-S130200/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/a16d4976-8aef-4cb4-b616-d00b546adccf/runs/b9d45234-8222-4507-9a18-bb01daf94d15/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_runtime/classes/1/runtime/8176717/TestInheritFD.d:/opt/mach5/mesos/work_dir/jib-master/install/jdk-22+11-741/src.full/open/test/hotspot/jtreg/runtime/8176717:/opt/mach5/mesos/work_dir/slaves/cd627e65-f015-4fb1-a1d2-b6c9b8127f98-S130200/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/a16d4976-8aef-4cb4-b616-d00b546adccf/runs/b9d45234-8222-4507-9a18-bb01daf94d15/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_runtime/classes/1/test/lib:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/7.3/1/bundles/jtreg-7.3+1.zip/jtreg/lib/jtreg.jar:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/7.3/1/bundles/jtreg-7.3+1.zip/jtreg/lib/junit-platform-console-standalone-1.9.2.jar:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/7.3/1/bundles/jtreg-7.3+1.zip/jtreg/lib/testng-7.3.0.jar:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/7.3/1/bundles/jtreg-7.3+1.zip/jtreg/lib/jcommander-1.82.jar:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/7.3/1/bundles/jtreg-7.3+1.zip/jtreg/lib/guice-5.1.0.jar -Xlog:gc:"logging6630298047709292382.strangelogsuffixthatcanbecheckedfor" -Dtest.jdk=/opt/mach5/mesos/work_dir/jib-master/install/jdk-22+11-741/linux-x64-debug.jdk/jdk-22/fastdebug TestInheritFD$VMStartedWithLogging logging6630298047709292382.strangelogsuffixthatcanbecheckedfor ]
secondVMPID=1313444
thirdVMPID=1313487
secondVMlsofPID=1313515
Called Thread.sleep(100) 30 times.
subs[0]={pid=1313515, name=second VM lsof} finished.
subs[1]={pid=1313444, name=second VM} finished.
subs[2]={pid=1313487, name=third VM} finished.
subs[3]={pid=0, name=null} finished.
<BEGIN commFile contents>
Second VM pid=1313444
Command line: [/opt/mach5/mesos/work_dir/jib-master/install/jdk-22+11-741/linux-x64-debug.jdk/jdk-22/fastdebug/bin/java -cp /opt/mach5/mesos/work_dir/slaves/cd627e65-f015-4fb1-a1d2-b6c9b8127f98-S130200/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/a16d4976-8aef-4cb4-b616-d00b546adccf/runs/b9d45234-8222-4507-9a18-bb01daf94d15/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_runtime/classes/1/runtime/8176717/TestInheritFD.d:/opt/mach5/mesos/work_dir/jib-master/install/jdk-22+11-741/src.full/open/test/hotspot/jtreg/runtime/8176717:/opt/mach5/mesos/work_dir/slaves/cd627e65-f015-4fb1-a1d2-b6c9b8127f98-S130200/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/a16d4976-8aef-4cb4-b616-d00b546adccf/runs/b9d45234-8222-4507-9a18-bb01daf94d15/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_runtime/classes/1/test/lib:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/7.3/1/bundles/jtreg-7.3+1.zip/jtreg/lib/jtreg.jar:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/7.3/1/bundles/jtreg-7.3+1.zip/jtreg/lib/junit-platform-console-standalone-1.9.2.jar:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/7.3/1/bundles/jtreg-7.3+1.zip/jtreg/lib/testng-7.3.0.jar:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/7.3/1/bundles/jtreg-7.3+1.zip/jtreg/lib/jcommander-1.82.jar:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/7.3/1/bundles/jtreg-7.3+1.zip/jtreg/lib/guice-5.1.0.jar -Dtest.jdk=/opt/mach5/mesos/work_dir/jib-master/install/jdk-22+11-741/linux-x64-debug.jdk/jdk-22/fastdebug TestInheritFD$VMShouldNotInheritFileDescriptors logging6630298047709292382.strangelogsuffixthatcanbecheckedfor 1313444 ]
using command: /usr/bin/lsof -a +d /opt/mach5/mesos/work_dir/slaves/cd627e65-f015-4fb1-a1d2-b6c9b8127f98-S130200/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/a16d4976-8aef-4cb4-b616-d00b546adccf/runs/b9d45234-8222-4507-9a18-bb01daf94d15/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_runtime/scratch/4 -p 1313444
Third VM pid=1313487
Third VM waiting for second VM pid=1313444
Exception was thrown: java.util.concurrent.CompletionException: java.util.concurrent.TimeoutException
VM RESULT => VM EXIT
Third VM ends.
Second VM lsof pid=1313515
(Second VM) Open file descriptors:
COMMAND PID USER FD TYPE DEVICE SIZE/OFF NODE NAME
java 1313444 mach5-one cwd DIR 252,0 4096 271584401 /opt/mach5/mesos/work_dir/slaves/cd627e65-f015-4fb1-a1d2-b6c9b8127f98-S130200/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/a16d4976-8aef-4cb4-b616-d00b546adccf/runs/b9d45234-8222-4507-9a18-bb01daf94d15/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_runtime/scratch/4
java 1313444 mach5-one 1w REG 252,0 2260 271584410 /opt/mach5/mesos/work_dir/slaves/cd627e65-f015-4fb1-a1d2-b6c9b8127f98-S130200/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/a16d4976-8aef-4cb4-b616-d00b546adccf/runs/b9d45234-8222-4507-9a18-bb01daf94d15/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_runtime/scratch/4/communication16592222536903649865.txt
java 1313444 mach5-one 4w REG 252,0 28 271584411 /opt/mach5/mesos/work_dir/slaves/cd627e65-f015-4fb1-a1d2-b6c9b8127f98-S130200/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/a16d4976-8aef-4cb4-b616-d00b546adccf/runs/b9d45234-8222-4507-9a18-bb01daf94d15/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_runtime/scratch/4/logging6630298047709292382.strangelogsuffixthatcanbecheckedfor
Second VM ends.
<END commFile contents>
----------System.err:(10/539)----------
java.lang.RuntimeException: Log file was leaked to the third VM.
at TestInheritFD.main(TestInheritFD.java:244)
at java.base/jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:103)
at java.base/java.lang.reflect.Method.invoke(Method.java:580)
at com.sun.javatest.regtest.agent.MainActionHelper$AgentVMRunnable.run(MainActionHelper.java:333)
at java.base/java.lang.Thread.run(Thread.java:1570)
JavaTest Message: Test threw exception: java.lang.RuntimeException
JavaTest Message: shutting down test
result: Failed. Execution failed: `main' threw exception: java.lang.RuntimeException: Log file was leaked to the third VM.
Historically almost all of the failures of this test have been test
infrastructure related. This failure mode is different and appears
to be a detection of the actual bug the test was designed to
catch so I'm starting this bug as a P3.
runtime/8176717/TestInheritFD.java
Here's a snippet from the log file:
#section:driver
----------messages:(8/303)----------
command: driver TestInheritFD
reason: User specified action: run driver TestInheritFD
started: Sat Aug 12 09:46:08 UTC 2023
Mode: agentvm
Agent id: 14
Additional exports to unnamed modules from @modules: java.base/jdk.internal.misc
finished: Sat Aug 12 09:46:45 UTC 2023
elapsed time (seconds): 37.566
----------configuration:(15/1943)----------
<snip>
----------System.out:(29/5670)----------
subProcessTimeout=60 seconds.
First VM starts.
Command line: [/opt/mach5/mesos/work_dir/jib-master/install/jdk-22+11-741/linux-x64-debug.jdk/jdk-22/fastdebug/bin/java -cp /opt/mach5/mesos/work_dir/slaves/cd627e65-f015-4fb1-a1d2-b6c9b8127f98-S130200/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/a16d4976-8aef-4cb4-b616-d00b546adccf/runs/b9d45234-8222-4507-9a18-bb01daf94d15/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_runtime/classes/1/runtime/8176717/TestInheritFD.d:/opt/mach5/mesos/work_dir/jib-master/install/jdk-22+11-741/src.full/open/test/hotspot/jtreg/runtime/8176717:/opt/mach5/mesos/work_dir/slaves/cd627e65-f015-4fb1-a1d2-b6c9b8127f98-S130200/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/a16d4976-8aef-4cb4-b616-d00b546adccf/runs/b9d45234-8222-4507-9a18-bb01daf94d15/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_runtime/classes/1/test/lib:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/7.3/1/bundles/jtreg-7.3+1.zip/jtreg/lib/jtreg.jar:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/7.3/1/bundles/jtreg-7.3+1.zip/jtreg/lib/junit-platform-console-standalone-1.9.2.jar:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/7.3/1/bundles/jtreg-7.3+1.zip/jtreg/lib/testng-7.3.0.jar:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/7.3/1/bundles/jtreg-7.3+1.zip/jtreg/lib/jcommander-1.82.jar:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/7.3/1/bundles/jtreg-7.3+1.zip/jtreg/lib/guice-5.1.0.jar -Xlog:gc:"logging6630298047709292382.strangelogsuffixthatcanbecheckedfor" -Dtest.jdk=/opt/mach5/mesos/work_dir/jib-master/install/jdk-22+11-741/linux-x64-debug.jdk/jdk-22/fastdebug TestInheritFD$VMStartedWithLogging logging6630298047709292382.strangelogsuffixthatcanbecheckedfor ]
secondVMPID=1313444
thirdVMPID=1313487
secondVMlsofPID=1313515
Called Thread.sleep(100) 30 times.
subs[0]={pid=1313515, name=second VM lsof} finished.
subs[1]={pid=1313444, name=second VM} finished.
subs[2]={pid=1313487, name=third VM} finished.
subs[3]={pid=0, name=null} finished.
<BEGIN commFile contents>
Second VM pid=1313444
Command line: [/opt/mach5/mesos/work_dir/jib-master/install/jdk-22+11-741/linux-x64-debug.jdk/jdk-22/fastdebug/bin/java -cp /opt/mach5/mesos/work_dir/slaves/cd627e65-f015-4fb1-a1d2-b6c9b8127f98-S130200/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/a16d4976-8aef-4cb4-b616-d00b546adccf/runs/b9d45234-8222-4507-9a18-bb01daf94d15/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_runtime/classes/1/runtime/8176717/TestInheritFD.d:/opt/mach5/mesos/work_dir/jib-master/install/jdk-22+11-741/src.full/open/test/hotspot/jtreg/runtime/8176717:/opt/mach5/mesos/work_dir/slaves/cd627e65-f015-4fb1-a1d2-b6c9b8127f98-S130200/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/a16d4976-8aef-4cb4-b616-d00b546adccf/runs/b9d45234-8222-4507-9a18-bb01daf94d15/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_runtime/classes/1/test/lib:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/7.3/1/bundles/jtreg-7.3+1.zip/jtreg/lib/jtreg.jar:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/7.3/1/bundles/jtreg-7.3+1.zip/jtreg/lib/junit-platform-console-standalone-1.9.2.jar:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/7.3/1/bundles/jtreg-7.3+1.zip/jtreg/lib/testng-7.3.0.jar:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/7.3/1/bundles/jtreg-7.3+1.zip/jtreg/lib/jcommander-1.82.jar:/opt/mach5/mesos/work_dir/jib-master/install/jtreg/7.3/1/bundles/jtreg-7.3+1.zip/jtreg/lib/guice-5.1.0.jar -Dtest.jdk=/opt/mach5/mesos/work_dir/jib-master/install/jdk-22+11-741/linux-x64-debug.jdk/jdk-22/fastdebug TestInheritFD$VMShouldNotInheritFileDescriptors logging6630298047709292382.strangelogsuffixthatcanbecheckedfor 1313444 ]
using command: /usr/bin/lsof -a +d /opt/mach5/mesos/work_dir/slaves/cd627e65-f015-4fb1-a1d2-b6c9b8127f98-S130200/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/a16d4976-8aef-4cb4-b616-d00b546adccf/runs/b9d45234-8222-4507-9a18-bb01daf94d15/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_runtime/scratch/4 -p 1313444
Third VM pid=1313487
Third VM waiting for second VM pid=1313444
Exception was thrown: java.util.concurrent.CompletionException: java.util.concurrent.TimeoutException
VM RESULT => VM EXIT
Third VM ends.
Second VM lsof pid=1313515
(Second VM) Open file descriptors:
COMMAND PID USER FD TYPE DEVICE SIZE/OFF NODE NAME
java 1313444 mach5-one cwd DIR 252,0 4096 271584401 /opt/mach5/mesos/work_dir/slaves/cd627e65-f015-4fb1-a1d2-b6c9b8127f98-S130200/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/a16d4976-8aef-4cb4-b616-d00b546adccf/runs/b9d45234-8222-4507-9a18-bb01daf94d15/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_runtime/scratch/4
java 1313444 mach5-one 1w REG 252,0 2260 271584410 /opt/mach5/mesos/work_dir/slaves/cd627e65-f015-4fb1-a1d2-b6c9b8127f98-S130200/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/a16d4976-8aef-4cb4-b616-d00b546adccf/runs/b9d45234-8222-4507-9a18-bb01daf94d15/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_runtime/scratch/4/communication16592222536903649865.txt
java 1313444 mach5-one 4w REG 252,0 28 271584411 /opt/mach5/mesos/work_dir/slaves/cd627e65-f015-4fb1-a1d2-b6c9b8127f98-S130200/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/a16d4976-8aef-4cb4-b616-d00b546adccf/runs/b9d45234-8222-4507-9a18-bb01daf94d15/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_runtime/scratch/4/logging6630298047709292382.strangelogsuffixthatcanbecheckedfor
Second VM ends.
<END commFile contents>
----------System.err:(10/539)----------
java.lang.RuntimeException: Log file was leaked to the third VM.
at TestInheritFD.main(TestInheritFD.java:244)
at java.base/jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:103)
at java.base/java.lang.reflect.Method.invoke(Method.java:580)
at com.sun.javatest.regtest.agent.MainActionHelper$AgentVMRunnable.run(MainActionHelper.java:333)
at java.base/java.lang.Thread.run(Thread.java:1570)
JavaTest Message: Test threw exception: java.lang.RuntimeException
JavaTest Message: shutting down test
result: Failed. Execution failed: `main' threw exception: java.lang.RuntimeException: Log file was leaked to the third VM.
Historically almost all of the failures of this test have been test
infrastructure related. This failure mode is different and appears
to be a detection of the actual bug the test was designed to
catch so I'm starting this bug as a P3.