Uploaded image for project: 'JDK'
  1. JDK
  2. JDK-8314186

runtime/8176717/TestInheritFD.java failed with "Log file was leaked"

    XMLWordPrintable

Details

    • b07
    • x86_64
    • linux

    Description

      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.

      Attachments

        Issue Links

          Activity

            People

              jsjolen Johan Sjölen
              dcubed Daniel Daugherty
              Votes:
              0 Vote for this issue
              Watchers:
              7 Start watching this issue

              Dates

                Created:
                Updated:
                Resolved: