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

two java/nio/channels/vthread/BlockingChannelOps.java subtests timed out

XMLWordPrintable

    • Icon: Bug Bug
    • Resolution: Unresolved
    • Icon: P4 P4
    • tbd
    • 25
    • core-libs
    • x86_64
    • linux

      The following two java/nio/channels/vthread/BlockingChannelOps.java
      subtests timed out in the JDK26 CI:

      java/nio/channels/vthread/BlockingChannelOps.java#poller-modes
      java/nio/channels/vthread/BlockingChannelOps.java#no-vmcontinuations

      Here's a log file snippet from the 'poller-modes' subtest log file:

      #section:junit
      ----------messages:(9/356)----------
      command: junit -Djdk.pollerMode=1 BlockingChannelOps
      reason: User specified action: run junit/othervm -Djdk.pollerMode=1 BlockingChannelOps
      started: Mon Aug 04 23:35:30 UTC 2025
      Mode: othervm [/othervm specified]
      Process id: 2197412
      Timeout information:
      --- Timeout information end.
      finished: Mon Aug 04 23:57:05 UTC 2025
      elapsed time (seconds): 1294.776
      ----------configuration:(0/0)----------
      ----------System.out:(1060/68595)----------
      -- VirtualThread[#82]/waiting --
        java.base/java.lang.VirtualThread.park(VirtualThread.java:738)
        java.base/java.lang.System$1.parkVirtualThread(System.java:2284)
        java.base/java.util.concurrent.locks.LockSupport.park(LockSupport.java:367)
        java.base/sun.nio.ch.Poller.poll(Poller.java:197)
        java.base/sun.nio.ch.Poller.poll(Poller.java:144)
        java.base/sun.nio.ch.SelChImpl.park(SelChImpl.java:88)
        java.base/sun.nio.ch.SelChImpl.park(SelChImpl.java:116)
        java.base/sun.nio.ch.SocketChannelImpl.implWrite(SocketChannelImpl.java:565)
        java.base/sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:614)
        BlockingChannelOps.lambda$testSocketChannelWriteAsyncClose$0(BlockingChannelOps.java:203)
        jdk.test.lib.thread.VThreadRunner.lambda$run$0(VThreadRunner.java:66)
        java.base/java.lang.VirtualThread.run(VirtualThread.java:456)

      <snip>

      -- VirtualThread[#82]/waiting --
        java.base/java.lang.VirtualThread.park(VirtualThread.java:738)
        java.base/java.lang.System$1.parkVirtualThread(System.java:2284)
        java.base/java.util.concurrent.locks.LockSupport.park(LockSupport.java:367)
        java.base/sun.nio.ch.Poller.poll(Poller.java:197)
        java.base/sun.nio.ch.Poller.poll(Poller.java:144)
        java.base/sun.nio.ch.SelChImpl.park(SelChImpl.java:88)
        java.base/sun.nio.ch.SelChImpl.park(SelChImpl.java:116)
        java.base/sun.nio.ch.SocketChannelImpl.implWrite(SocketChannelImpl.java:565)
        java.base/sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.jav

      ...
      Output overflow:
      JT Harness has limited the test output to the text
      at the beginning and the end, so that you can see how the
      test began, and how it completed.

      If you need to see more of the output from the test,
      set the system property javatest.maxOutputSize to a higher
      value. The current value is 100000
      ...

      .base/java.lang.System$1.parkVirtualThread(System.java:2284)
        java.base/java.util.concurrent.locks.LockSupport.park(LockSupport.java:367)
        java.base/sun.nio.ch.Poller.poll(Poller.java:197)
        java.base/sun.nio.ch.Poller.poll(Poller.java:144)
        java.base/sun.nio.ch.SelChImpl.park(SelChImpl.java:88)
        java.base/sun.nio.ch.SelChImpl.park(SelChImpl.java:116)
        java.base/sun.nio.ch.SocketChannelImpl.implWrite(SocketChannelImpl.java:565)
        java.base/sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:614)
        BlockingChannelOps.lambda$testSocketChannelWriteAsyncClose$0(BlockingChannelOps.java:203)
        jdk.test.lib.thread.VThreadRunner.lambda$run$0(VThreadRunner.java:66)
        java.base/java.lang.VirtualThread.run(VirtualThread.java:456)

      <snip>

      -- VirtualThread[#82]/waiting --
        java.base/java.lang.VirtualThread.park(VirtualThread.java:738)
        java.base/java.lang.System$1.parkVirtualThread(System.java:2284)
        java.base/java.util.concurrent.locks.LockSupport.park(LockSupport.java:367)
        java.base/sun.nio.ch.Poller.poll(Poller.java:197)
        java.base/sun.nio.ch.Poller.poll(Poller.java:144)
        java.base/sun.nio.ch.SelChImpl.park(SelChImpl.java:88)
        java.base/sun.nio.ch.SelChImpl.park(SelChImpl.java:116)
        java.base/sun.nio.ch.SocketChannelImpl.implWrite(SocketChannelImpl.java:565)
        java.base/sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:614)
        BlockingChannelOps.lambda$testSocketChannelWriteAsyncClose$0(BlockingChannelOps.java:203)
        jdk.test.lib.thread.VThreadRunner.lambda$run$0(VThreadRunner.java:66)
        java.base/java.lang.VirtualThread.run(VirtualThread.java:456)
      ----------System.err:(39/3923)----------
      STARTED BlockingChannelOps::testPipeReadInterrupt 'testPipeReadInterrupt()'
      SUCCESSFUL BlockingChannelOps::testPipeReadInterrupt 'testPipeReadInterrupt()' [2920ms]
      STARTED BlockingChannelOps::testServerSocketChannelAccept1 'testServerSocketChannelAccept1()'
      SUCCESSFUL BlockingChannelOps::testServerSocketChannelAccept1 'testServerSocketChannelAccept1()' [634ms]
      STARTED BlockingChannelOps::testServerSocketChannelAccept2 'testServerSocketChannelAccept2()'
      SUCCESSFUL BlockingChannelOps::testServerSocketChannelAccept2 'testServerSocketChannelAccept2()' [447ms]
      STARTED BlockingChannelOps::testServerSocketChannelAcceptAsyncClose 'testServerSocketChannelAcceptAsyncClose()'
      SUCCESSFUL BlockingChannelOps::testServerSocketChannelAcceptAsyncClose 'testServerSocketChannelAcceptAsyncClose()' [183ms]
      STARTED BlockingChannelOps::testSocketChannelRead 'testSocketChannelRead()'
      SUCCESSFUL BlockingChannelOps::testSocketChannelRead 'testSocketChannelRead()' [335ms]
      STARTED BlockingChannelOps::testSocketChannelReadInterrupt 'testSocketChannelReadInterrupt()'
      SUCCESSFUL BlockingChannelOps::testSocketChannelReadInterrupt 'testSocketChannelReadInterrupt()' [101ms]
      STARTED BlockingChannelOps::testSocketChannelAdaptorAccept1 'testSocketChannelAdaptorAccept1()'
      SUCCESSFUL BlockingChannelOps::testSocketChannelAdaptorAccept1 'testSocketChannelAdaptorAccept1()' [76ms]
      STARTED BlockingChannelOps::testSocketChannelAdaptorAccept2 'testSocketChannelAdaptorAccept2()'
      SUCCESSFUL BlockingChannelOps::testSocketChannelAdaptorAccept2 'testSocketChannelAdaptorAccept2()' [219ms]
      STARTED BlockingChannelOps::testServerSocketChannelAcceptInterrupt 'testServerSocketChannelAcceptInterrupt()'
      SUCCESSFUL BlockingChannelOps::testServerSocketChannelAcceptInterrupt 'testServerSocketChannelAcceptInterrupt()' [79ms]
      STARTED BlockingChannelOps::testPipeWriteAsyncClose 'testPipeWriteAsyncClose()'
      SUCCESSFUL BlockingChannelOps::testPipeWriteAsyncClose 'testPipeWriteAsyncClose()' [114ms]
      STARTED BlockingChannelOps::testPipeReadWrite1 'testPipeReadWrite1()'
      SUCCESSFUL BlockingChannelOps::testPipeReadWrite1 'testPipeReadWrite1()' [116ms]
      STARTED BlockingChannelOps::testPipeReadWrite2 'testPipeReadWrite2()'
      SUCCESSFUL BlockingChannelOps::testPipeReadWrite2 'testPipeReadWrite2()' [59ms]
      STARTED BlockingChannelOps::testPipeReadWrite3 'testPipeReadWrite3()'
      SUCCESSFUL BlockingChannelOps::testPipeReadWrite3 'testPipeReadWrite3()' [87ms]
      STARTED BlockingChannelOps::testSocketChannelWrite 'testSocketChannelWrite()'
      SUCCESSFUL BlockingChannelOps::testSocketChannelWrite 'testSocketChannelWrite()' [125ms]
      STARTED BlockingChannelOps::testDatagramSocketAdaptorReceiveInterrupt1 'testDatagramSocketAdaptorReceiveInterrupt1()'
      SUCCESSFUL BlockingChannelOps::testDatagramSocketAdaptorReceiveInterrupt1 'testDatagramSocketAdaptorReceiveInterrupt1()' [440ms]
      STARTED BlockingChannelOps::testDatagramSocketAdaptorReceiveInterrupt2 'testDatagramSocketAdaptorReceiveInterrupt2()'
      SUCCESSFUL BlockingChannelOps::testDatagramSocketAdaptorReceiveInterrupt2 'testDatagramSocketAdaptorReceiveInterrupt2()' [134ms]
      STARTED BlockingChannelOps::testDatagramSocketAdaptorReceiveAsyncClose1 'testDatagramSocketAdaptorReceiveAsyncClose1()'
      SUCCESSFUL BlockingChannelOps::testDatagramSocketAdaptorReceiveAsyncClose1 'testDatagramSocketAdaptorReceiveAsyncClose1()' [241ms]
      STARTED BlockingChannelOps::testDatagramSocketAdaptorReceiveAsyncClose2 'testDatagramSocketAdaptorReceiveAsyncClose2()'
      SUCCESSFUL BlockingChannelOps::testDatagramSocketAdaptorReceiveAsyncClose2 'testDatagramSocketAdaptorReceiveAsyncClose2()' [50ms]
      STARTED BlockingChannelOps::testSocketChannelReadWrite1 'testSocketChannelReadWrite1()'
      SUCCESSFUL BlockingChannelOps::testSocketChannelReadWrite1 'testSocketChannelReadWrite1()' [297ms]
      STARTED BlockingChannelOps::testSocketChannelWriteAsyncClose 'testSocketChannelWriteAsyncClose()'
      ----------rerun:(42/7674)*----------

      <snip>

      result: Error. Program `/opt/mach5/mesos/work_dir/jib-master/install/jdk-25+35-3487/linux-x64-debug.jdk/jdk-25/fastdebug/bin/java' timed out (timeout set to 1200000ms, elapsed time including timeout handling was 1294773ms).


      Here's a log file snippet from the 'no-vmcontinuations' subtest:

      #section:junit
      ----------messages:(9/426)----------
      command: junit -XX:+UnlockExperimentalVMOptions -XX:-VMContinuations BlockingChannelOps
      reason: User specified action: run junit/othervm -XX:+UnlockExperimentalVMOptions -XX:-VMContinuations BlockingChannelOps
      started: Mon Aug 04 23:35:22 UTC 2025
      Mode: othervm [/othervm specified]
      Process id: 2197170
      Timeout information:
      --- Timeout information end.
      finished: Mon Aug 04 23:55:44 UTC 2025
      elapsed time (seconds): 1222.115
      ----------configuration:(0/0)----------
      ----------System.out:(1118/74969)----------
      -- VirtualThread[#78]/waiting --
        java.base/jdk.internal.misc.Unsafe.park(Native Method)
        java.base/java.lang.ThreadBuilders$BoundVirtualThread.park(ThreadBuilders.java:438)
        java.base/java.lang.System$1.parkVirtualThread(System.java:2284)
        java.base/java.util.concurrent.locks.LockSupport.park(LockSupport.java:367)
        java.base/sun.nio.ch.Poller.poll(Poller.java:197)
        java.base/sun.nio.ch.Poller.poll(Poller.java:144)
        java.base/sun.nio.ch.SelChImpl.park(SelChImpl.java:88)
        java.base/sun.nio.ch.SelChImpl.park(SelChImpl.java:116)
        java.base/sun.nio.ch.SocketChannelImpl.implWrite(SocketChannelImpl.java:565)
        java.base/sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:614)
        BlockingChannelOps.lambda$testSocketChannelWriteAsyncClose$0(BlockingChannelOps.java:203)
        jdk.test.lib.thread.VThreadRunner.lambda$run$0(VThreadRunner.java:66)
        java.base/java.lang.ThreadBuilders$BoundVirtualThread.run(ThreadBuilders.java:432)

      <snip>

      -- VirtualThread[#78]/waiting --
        java.base/jdk.internal.misc.Unsafe.park(Native Method)
        java.base/java.lang.ThreadBuilders$BoundVirtualThread.park(ThreadBuilders.java:438)
        java.base/java.lang.System$1.parkVirtualThread(System.java:2284)
        java.base/java.util.concurrent.locks.LockSupport.park(LockSupport.java:367)
        java.base/sun.nio.ch.Poller.poll(Poller.java:197)
        java.base/sun.nio.ch.Poller.poll(Poller.java:144)
        java.base/sun.nio.ch.SelChImpl.park(SelChImpl.java:88)
        java.base/sun.nio.ch.SelChImpl.park(SelChImpl.java:116)
        java.base/sun.nio.ch.SocketChannelImpl.implWrite(SocketChannelImpl.java:565)
        java.base/sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:614)
        BlockingChannelOps.lambda$testSocketChannelWriteAsyncClose$0(BlockingChannelOps.java:203)
        jdk.test.lib.thread.VThreadRunner.lambda$run$0(VThreadRunner.java:66)
        java.base/java.lang.ThreadBuilders$BoundVirtualThread.run(ThreadBuilders.java:432)
      Timeout signalled after 1200 seconds
      ----------System.err:(69/6594)----------
      STARTED BlockingChannelOps::testPipeReadInterrupt 'testPipeReadInterrupt()'
      SUCCESSFUL BlockingChannelOps::testPipeReadInterrupt 'testPipeReadInterrupt()' [2414ms]
      STARTED BlockingChannelOps::testServerSocketChannelAccept1 'testServerSocketChannelAccept1()'
      SUCCESSFUL BlockingChannelOps::testServerSocketChannelAccept1 'testServerSocketChannelAccept1()' [616ms]
      STARTED BlockingChannelOps::testServerSocketChannelAccept2 'testServerSocketChannelAccept2()'
      SUCCESSFUL BlockingChannelOps::testServerSocketChannelAccept2 'testServerSocketChannelAccept2()' [391ms]
      STARTED BlockingChannelOps::testServerSocketChannelAcceptAsyncClose 'testServerSocketChannelAcceptAsyncClose()'
      SUCCESSFUL BlockingChannelOps::testServerSocketChannelAcceptAsyncClose 'testServerSocketChannelAcceptAsyncClose()' [181ms]
      STARTED BlockingChannelOps::testSocketChannelRead 'testSocketChannelRead()'
      SUCCESSFUL BlockingChannelOps::testSocketChannelRead 'testSocketChannelRead()' [317ms]
      STARTED BlockingChannelOps::testSocketChannelReadInterrupt 'testSocketChannelReadInterrupt()'
      SUCCESSFUL BlockingChannelOps::testSocketChannelReadInterrupt 'testSocketChannelReadInterrupt()' [85ms]
      STARTED BlockingChannelOps::testSocketChannelAdaptorAccept1 'testSocketChannelAdaptorAccept1()'
      SUCCESSFUL BlockingChannelOps::testSocketChannelAdaptorAccept1 'testSocketChannelAdaptorAccept1()' [76ms]
      STARTED BlockingChannelOps::testSocketChannelAdaptorAccept2 'testSocketChannelAdaptorAccept2()'
      SUCCESSFUL BlockingChannelOps::testSocketChannelAdaptorAccept2 'testSocketChannelAdaptorAccept2()' [116ms]
      STARTED BlockingChannelOps::testServerSocketChannelAcceptInterrupt 'testServerSocketChannelAcceptInterrupt()'
      SUCCESSFUL BlockingChannelOps::testServerSocketChannelAcceptInterrupt 'testServerSocketChannelAcceptInterrupt()' [39ms]
      STARTED BlockingChannelOps::testPipeWriteAsyncClose 'testPipeWriteAsyncClose()'
      SUCCESSFUL BlockingChannelOps::testPipeWriteAsyncClose 'testPipeWriteAsyncClose()' [116ms]
      STARTED BlockingChannelOps::testPipeReadWrite1 'testPipeReadWrite1()'
      SUCCESSFUL BlockingChannelOps::testPipeReadWrite1 'testPipeReadWrite1()' [126ms]
      STARTED BlockingChannelOps::testPipeReadWrite2 'testPipeReadWrite2()'
      SUCCESSFUL BlockingChannelOps::testPipeReadWrite2 'testPipeReadWrite2()' [63ms]
      STARTED BlockingChannelOps::testPipeReadWrite3 'testPipeReadWrite3()'
      SUCCESSFUL BlockingChannelOps::testPipeReadWrite3 'testPipeReadWrite3()' [69ms]
      STARTED BlockingChannelOps::testSocketChannelWrite 'testSocketChannelWrite()'
      SUCCESSFUL BlockingChannelOps::testSocketChannelWrite 'testSocketChannelWrite()' [117ms]
      STARTED BlockingChannelOps::testDatagramSocketAdaptorReceiveInterrupt1 'testDatagramSocketAdaptorReceiveInterrupt1()'
      SUCCESSFUL BlockingChannelOps::testDatagramSocketAdaptorReceiveInterrupt1 'testDatagramSocketAdaptorReceiveInterrupt1()' [407ms]
      STARTED BlockingChannelOps::testDatagramSocketAdaptorReceiveInterrupt2 'testDatagramSocketAdaptorReceiveInterrupt2()'
      SUCCESSFUL BlockingChannelOps::testDatagramSocketAdaptorReceiveInterrupt2 'testDatagramSocketAdaptorReceiveInterrupt2()' [123ms]
      STARTED BlockingChannelOps::testDatagramSocketAdaptorReceiveAsyncClose1 'testDatagramSocketAdaptorReceiveAsyncClose1()'
      SUCCESSFUL BlockingChannelOps::testDatagramSocketAdaptorReceiveAsyncClose1 'testDatagramSocketAdaptorReceiveAsyncClose1()' [191ms]
      STARTED BlockingChannelOps::testDatagramSocketAdaptorReceiveAsyncClose2 'testDatagramSocketAdaptorReceiveAsyncClose2()'
      SUCCESSFUL BlockingChannelOps::testDatagramSocketAdaptorReceiveAsyncClose2 'testDatagramSocketAdaptorReceiveAsyncClose2()' [49ms]
      STARTED BlockingChannelOps::testSocketChannelReadWrite1 'testSocketChannelReadWrite1()'
      SUCCESSFUL BlockingChannelOps::testSocketChannelReadWrite1 'testSocketChannelReadWrite1()' [261ms]
      STARTED BlockingChannelOps::testSocketChannelWriteAsyncClose 'testSocketChannelWriteAsyncClose()'
      SUCCESSFUL BlockingChannelOps::testSocketChannelWriteAsyncClose 'testSocketChannelWriteAsyncClose()' [1158083ms]
      STARTED BlockingChannelOps::testSocketAdaptorRead1 'testSocketAdaptorRead1()'
      SUCCESSFUL BlockingChannelOps::testSocketAdaptorRead1 'testSocketAdaptorRead1()' [1855ms]
      STARTED BlockingChannelOps::testSocketAdaptorRead2 'testSocketAdaptorRead2()'
      SUCCESSFUL BlockingChannelOps::testSocketAdaptorRead2 'testSocketAdaptorRead2()' [68ms]
      STARTED BlockingChannelOps::testDatagramChannelReceiveAsyncClose 'testDatagramChannelReceiveAsyncClose()'
      SUCCESSFUL BlockingChannelOps::testDatagramChannelReceiveAsyncClose 'testDatagramChannelReceiveAsyncClose()' [334ms]
      STARTED BlockingChannelOps::testDatagramChannelReceiveInterrupt 'testDatagramChannelReceiveInterrupt()'
      SUCCESSFUL BlockingChannelOps::testDatagramChannelReceiveInterrupt 'testDatagramChannelReceiveInterrupt()' [80ms]
      STARTED BlockingChannelOps::testDatagramChannelSendReceive1 'testDatagramChannelSendReceive1()'
      SUCCESSFUL BlockingChannelOps::testDatagramChannelSendReceive1 'testDatagramChannelSendReceive1()' [206ms]
      STARTED BlockingChannelOps::testDatagramChannelSendReceive2 'testDatagramChannelSendReceive2()'
      SUCCESSFUL BlockingChannelOps::testDatagramChannelSendReceive2 'testDatagramChannelSendReceive2()' [82ms]
      STARTED BlockingChannelOps::testPipeWriteInterrupt 'testPipeWriteInterrupt()'
      SUCCESSFUL BlockingChannelOps::testPipeWriteInterrupt 'testPipeWriteInterrupt()' [160ms]
      STARTED BlockingChannelOps::testSocketChannelWriteInterrupt 'testSocketChannelWriteInterrupt()'
      SUCCESSFUL BlockingChannelOps::testSocketChannelWriteInterrupt 'testSocketChannelWriteInterrupt()' [70ms]
      STARTED BlockingChannelOps::testDatagramSocketAdaptorReceive1 'testDatagramSocketAdaptorReceive1()'
      SUCCESSFUL BlockingChannelOps::testDatagramSocketAdaptorReceive1 'testDatagramSocketAdaptorReceive1()' [224ms]
      STARTED BlockingChannelOps::testDatagramSocketAdaptorReceive2 'testDatagramSocketAdaptorReceive2()'
      SUCCESSFUL BlockingChannelOps::testDatagramSocketAdaptorReceive2 'testDatagramSocketAdaptorReceive2()' [88ms]
      STARTED BlockingChannelOps::testPipeReadAsyncClose 'testPipeReadAsyncClose()'
      SUCCESSFUL BlockingChannelOps::testPipeReadAsyncClose 'testPipeReadAsyncClose()' [94ms]
      STARTED BlockingChannelOps::testSocketChannelReadAsyncClose 'testSocketChannelReadAsyncClose()'
      SUCCESSFUL BlockingChannelOps::testSocketChannelReadAsyncClose 'testSocketChannelReadAsyncClose()' [80ms]

      [ JUnit Containers: found 4, started 4, succeeded 4, failed 0, aborted 0, skipped 0]
      [ JUnit Tests: found 32, started 32, succeeded 32, failed 0, aborted 0, skipped 0]

      STATUS:Passed.
      ----------rerun:(43/7762)*----------

      It appears that this subtest PASSED while the timeout handler was running.

            alanb Alan Bateman
            dcubed Daniel Daugherty
            Votes:
            0 Vote for this issue
            Watchers:
            2 Start watching this issue

              Created:
              Updated: