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

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

XMLWordPrintable

    • b13
    • 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:
              4 Start watching this issue

                Created:
                Updated:
                Resolved: