Test java/nio/channels/Channels/AsyncCloseStreams.java can still timeout

XMLWordPrintable

    • Type: Bug
    • Resolution: Unresolved
    • Priority: P4
    • tbd
    • Affects Version/s: 27
    • Component/s: core-libs
    • x86_64
    • windows

      [23:23:47.322] STARTED AsyncCloseStreams::available 'available()'
      AsynchronousCloseException caught
      [23:23:47.368] SUCCESSFUL AsyncCloseStreams::available 'available()' [45ms]
      [23:23:47.376] STARTED AsyncCloseStreams::read 'read()'
      AsynchronousCloseException caught
      [23:23:49.097] SUCCESSFUL AsyncCloseStreams::read 'read()' [1721ms]
      [23:23:49.099] STARTED AsyncCloseStreams::write 'write()'
      result: Error. "junit" action timed out with a timeout of 480 seconds on agent 20

      The immediate thread dump in timeout shows a lot of innocusousThreads running in sun.nio.ch.Iocp.getQueuedCompletionStatus while the agent thread is doing:

      "AgentVMThread" #383 [2928] prio=5 os_prio=0 cpu=216453.12ms elapsed=501.01s allocated=309M defined_classes=8 tid=0x000001b278967340 nid=2928 runnable [0x0000003ad67fc000]
         java.lang.Thread.State: RUNNABLE
      at sun.nio.fs.WindowsNativeDispatcher.SetEndOfFile(java.base@27-ea/Native Method)
      at sun.nio.fs.WindowsChannelFactory.open(java.base@27-ea/WindowsChannelFactory.java:309)
      at sun.nio.fs.WindowsChannelFactory.newFileChannel(java.base@27-ea/WindowsChannelFactory.java:165)
      at sun.nio.fs.WindowsFileSystemProvider.newByteChannel(java.base@27-ea/WindowsFileSystemProvider.java:227)
      at java.nio.file.spi.FileSystemProvider.newOutputStream(java.base@27-ea/FileSystemProvider.java:426)
      at java.nio.file.Files.newOutputStream(java.base@27-ea/Files.java:215)
      at AsyncCloseStreams.write(AsyncCloseStreams.java:143)
      at java.lang.invoke.LambdaForm$DMH/0x0000000025080400.invokeVirtual(java.base@27-ea/LambdaForm$DMH)
      at java.lang.invoke.LambdaForm$MH/0x000000002513c400.invoke(java.base@27-ea/LambdaForm$MH)
      at java.lang.invoke.Invokers$Holder.invokeExact_MT(java.base@27-ea/Invokers$Holder)
      at jdk.internal.reflect.DirectMethodHandleAccessor.invokeImpl(java.base@27-ea/DirectMethodHandleAccessor.java:154)
      at jdk.internal.reflect.DirectMethodHandleAccessor.invoke(java.base@27-ea/DirectMethodHandleAccessor.java:104)
      at java.lang.reflect.Method.invoke(java.base@27-ea/Method.java:565)
      at org.junit.platform.commons.util.ReflectionUtils.invokeMethod(ReflectionUtils.java:786)
      at org.junit.platform.commons.support.ReflectionSupport.invokeMethod(ReflectionSupport.java:514)
      at org.junit.jupiter.engine.execution.MethodInvocation.proceed(MethodInvocation.java:60)
      at org.junit.jupiter.engine.execution.InvocationInterceptorChain$ValidatingInvocation.proceed(InvocationInterceptorChain.java:131)
      at org.junit.jupiter.engine.extension.TimeoutExtension.intercept(TimeoutExtension.java:161)
      at org.junit.jupiter.engine.extension.TimeoutExtension.interceptTestableMethod(TimeoutExtension.java:152)
      at org.junit.jupiter.engine.extension.TimeoutExtension.interceptTestMethod(TimeoutExtension.java:91)
      at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor$$Lambda/0x000000002522a140.apply(Unknown Source)
      at org.junit.jupiter.engine.execution.InterceptingExecutableInvoker$ReflectiveInterceptorCall.lambda$ofVoidMethod$0(InterceptingExecutableInvoker.java:112)
      at org.junit.jupiter.engine.execution.InterceptingExecutableInvoker$ReflectiveInterceptorCall$$Lambda/0x000000002522a570.apply(Unknown Source)
      at org.junit.jupiter.engine.execution.InterceptingExecutableInvoker.lambda$invoke$0(InterceptingExecutableInvoker.java:94)
      at org.junit.jupiter.engine.execution.InterceptingExecutableInvoker$$Lambda/0x00000000252686d8.apply(Unknown Source)
      at org.junit.jupiter.engine.execution.InvocationInterceptorChain$InterceptedInvocation.proceed(InvocationInterceptorChain.java:106)
      at org.junit.jupiter.engine.execution.InvocationInterceptorChain.proceed(InvocationInterceptorChain.java:64)
      at org.junit.jupiter.engine.execution.InvocationInterceptorChain.chainAndInvoke(InvocationInterceptorChain.java:45)
      at org.junit.jupiter.engine.execution.InvocationInterceptorChain.invoke(InvocationInterceptorChain.java:37)
      at org.junit.jupiter.engine.execution.InterceptingExecutableInvoker.invoke(InterceptingExecutableInvoker.java:93)
      at org.junit.jupiter.engine.execution.InterceptingExecutableInvoker.invoke(InterceptingExecutableInvoker.java:87)
      at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.lambda$invokeTestMethod$4(TestMethodTestDescriptor.java:221)
      at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor$$Lambda/0x000000002526d660.execute(Unknown Source)
      at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73)
      at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.invokeTestMethod(TestMethodTestDescriptor.java:217)
      at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.execute(TestMethodTestDescriptor.java:159)
      at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.execute(TestMethodTestDescriptor.java:70)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$6(NodeTestTask.java:157)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask$$Lambda/0x0000000025259f90.execute(Unknown Source)
      at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$8(NodeTestTask.java:147)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask$$Lambda/0x0000000025259d60.invoke(Unknown Source)
      at org.junit.platform.engine.support.hierarchical.Node.around(Node.java:137)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$9(NodeTestTask.java:145)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask$$Lambda/0x0000000025259928.execute(Unknown Source)
      at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask.executeRecursively(NodeTestTask.java:144)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask.execute(NodeTestTask.java:101)
      at org.junit.platform.engine.support.hierarchical.SameThreadHierarchicalTestExecutorService$$Lambda/0x000000002525aad0.accept(Unknown Source)
      at java.util.ArrayList.forEach(java.base@27-ea/ArrayList.java:1612)
      at org.junit.platform.engine.support.hierarchical.SameThreadHierarchicalTestExecutorService.invokeAll(SameThreadHierarchicalTestExecutorService.java:41)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$6(NodeTestTask.java:161)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask$$Lambda/0x0000000025259f90.execute(Unknown Source)
      at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$8(NodeTestTask.java:147)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask$$Lambda/0x0000000025259d60.invoke(Unknown Source)
      at org.junit.platform.engine.support.hierarchical.Node.around(Node.java:137)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$9(NodeTestTask.java:145)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask$$Lambda/0x0000000025259928.execute(Unknown Source)
      at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask.executeRecursively(NodeTestTask.java:144)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask.execute(NodeTestTask.java:101)
      at org.junit.platform.engine.support.hierarchical.SameThreadHierarchicalTestExecutorService$$Lambda/0x000000002525aad0.accept(Unknown Source)
      at java.util.ArrayList.forEach(java.base@27-ea/ArrayList.java:1612)
      at org.junit.platform.engine.support.hierarchical.SameThreadHierarchicalTestExecutorService.invokeAll(SameThreadHierarchicalTestExecutorService.java:41)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$6(NodeTestTask.java:161)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask$$Lambda/0x0000000025259f90.execute(Unknown Source)
      at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$8(NodeTestTask.java:147)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask$$Lambda/0x0000000025259d60.invoke(Unknown Source)
      at org.junit.platform.engine.support.hierarchical.Node.around(Node.java:137)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$9(NodeTestTask.java:145)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask$$Lambda/0x0000000025259928.execute(Unknown Source)
      at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask.executeRecursively(NodeTestTask.java:144)
      at org.junit.platform.engine.support.hierarchical.NodeTestTask.execute(NodeTestTask.java:101)
      at org.junit.platform.engine.support.hierarchical.SameThreadHierarchicalTestExecutorService.submit(SameThreadHierarchicalTestExecutorService.java:35)
      at org.junit.platform.engine.support.hierarchical.HierarchicalTestExecutor.execute(HierarchicalTestExecutor.java:57)
      at org.junit.platform.engine.support.hierarchical.HierarchicalTestEngine.execute(HierarchicalTestEngine.java:54)
      at org.junit.platform.launcher.core.EngineExecutionOrchestrator.executeEngine(EngineExecutionOrchestrator.java:230)
      at org.junit.platform.launcher.core.EngineExecutionOrchestrator.failOrExecuteEngine(EngineExecutionOrchestrator.java:204)
      at org.junit.platform.launcher.core.EngineExecutionOrchestrator.execute(EngineExecutionOrchestrator.java:172)
      at org.junit.platform.launcher.core.EngineExecutionOrchestrator.execute(EngineExecutionOrchestrator.java:101)
      at org.junit.platform.launcher.core.EngineExecutionOrchestrator.lambda$execute$0(EngineExecutionOrchestrator.java:64)
      at org.junit.platform.launcher.core.EngineExecutionOrchestrator$$Lambda/0x000000002523fdb8.accept(Unknown Source)
      at org.junit.platform.launcher.core.EngineExecutionOrchestrator.withInterceptedStreams(EngineExecutionOrchestrator.java:150)
      at org.junit.platform.launcher.core.EngineExecutionOrchestrator.execute(EngineExecutionOrchestrator.java:63)
      at org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:109)
      at org.junit.platform.launcher.core.DefaultLauncher.execute(DefaultLauncher.java:91)
      at org.junit.platform.launcher.core.DelegatingLauncher.execute(DelegatingLauncher.java:47)
      at org.junit.platform.launcher.core.InterceptingLauncher.lambda$execute$1(InterceptingLauncher.java:39)
      at org.junit.platform.launcher.core.InterceptingLauncher$$Lambda/0x00000000251a9240.proceed(Unknown Source)
      at org.junit.platform.launcher.core.ClasspathAlignmentCheckingLauncherInterceptor.intercept(ClasspathAlignmentCheckingLauncherInterceptor.java:25)
      at org.junit.platform.launcher.core.InterceptingLauncher.execute(InterceptingLauncher.java:38)
      at org.junit.platform.launcher.core.DelegatingLauncher.execute(DelegatingLauncher.java:47)
      at com.sun.javatest.regtest.agent.JUnitRunner.runWithJUnitPlatform(JUnitRunner.java:153)
      at com.sun.javatest.regtest.agent.JUnitRunner.main(JUnitRunner.java:112)
      at java.lang.invoke.LambdaForm$DMH/0x0000000025106000.invokeStatic(java.base@27-ea/LambdaForm$DMH)
      at java.lang.invoke.LambdaForm$MH/0x0000000025107000.invoke(java.base@27-ea/LambdaForm$MH)
      at java.lang.invoke.LambdaForm$MH/0x0000000025107400.invokeExact_MT(java.base@27-ea/LambdaForm$MH)
      at jdk.internal.reflect.DirectMethodHandleAccessor.invokeImpl(java.base@27-ea/DirectMethodHandleAccessor.java:156)
      at jdk.internal.reflect.DirectMethodHandleAccessor.invoke(java.base@27-ea/DirectMethodHandleAccessor.java:104)
      at java.lang.reflect.Method.invoke(java.base@27-ea/Method.java:565)
      at com.sun.javatest.regtest.agent.MainActionHelper$AgentVMRunnable.run(MainActionHelper.java:335)
      at java.lang.Thread.runWith(java.base@27-ea/Thread.java:1540)
      at java.lang.Thread.run(java.base@27-ea/Thread.java:1527)

            Assignee:
            Brian Burkhalter
            Reporter:
            David Holmes
            Votes:
            0 Vote for this issue
            Watchers:
            1 Start watching this issue

              Created:
              Updated: