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

java/net/httpclient/whitebox/Driver.java failed due to timeout

    XMLWordPrintable

    Details

    • Subcomponent:
    • Resolved In Build:
      b172
    • CPU:
      generic
    • OS:
      solaris

      Backports

        Description

        ----------messages:(192/11622)----------
        command: testng jdk.incubator.httpclient/jdk.incubator.http.RawChannelTest
        reason: User specified action: run testng jdk.incubator.httpclient/jdk.incubator.http.RawChannelTest
        Mode: othervm [test needs --add-modules, test or library overrides a system module]
        Additional options from @modules: --add-modules jdk.incubator.httpclient
        Timeout information:
        Running jstack on process 16646
        2017-05-12 03:06:58
        Full thread dump Java HotSpot(TM) 64-Bit Server VM (9-ea+170-mach5-b2369 mixed mode):

        "Attach Listener" #18 daemon prio=9 os_prio=64 tid=0x0000000000d06000 nid=0x26 waiting on condition [0x0000000000000000]
           java.lang.Thread.State: RUNNABLE

        "HTTP-Cache-cleaner" #17 daemon prio=5 os_prio=64 tid=0x0000000000d03800 nid=0x25 waiting on condition [0xffff80ffb77fc000]
           java.lang.Thread.State: TIMED_WAITING (sleeping)
        at java.lang.Thread.sleep(java.base@9-ea/Native Method)
        at jdk.incubator.http.ConnectionPool$CacheCleaner.run(jdk.incubator.httpclient@9-ea/ConnectionPool.java:201)

        "Thread-3" #16 prio=5 os_prio=64 tid=0x0000000000db7000 nid=0x24 waiting on condition [0xffff80ffb78fd000]
           java.lang.Thread.State: WAITING (parking)
        at jdk.internal.misc.Unsafe.park(java.base@9-ea/Native Method)
        - parking to wait for <0x00000000ff7e3408> (a java.util.concurrent.CountDownLatch$Sync)
        at java.util.concurrent.locks.LockSupport.park(java.base@9-ea/LockSupport.java:192)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer.parkAndCheckInterrupt(java.base@9-ea/AbstractQueuedSynchronizer.java:871)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedInterruptibly(java.base@9-ea/AbstractQueuedSynchronizer.java:1024)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireSharedInterruptibly(java.base@9-ea/AbstractQueuedSynchronizer.java:1331)
        at java.util.concurrent.CountDownLatch.await(java.base@9-ea/CountDownLatch.java:232)
        at jdk.incubator.http.RawChannelTest$TestServer.writeSlowly(jdk.incubator.httpclient@9-ea/RawChannelTest.java:257)
        at jdk.incubator.http.RawChannelTest$TestServer.lambda$run$1(jdk.incubator.httpclient@9-ea/RawChannelTest.java:212)
        at jdk.incubator.http.RawChannelTest$TestServer$$Lambda$69/1629095335.run(jdk.incubator.httpclient@9-ea/Unknown Source)
        at java.lang.Thread.run(java.base@9-ea/Thread.java:844)

        "SelectorManager" #14 daemon prio=5 os_prio=64 tid=0x0000000001192800 nid=0x22 runnable [0xffff80ffb7dfe000]
           java.lang.Thread.State: RUNNABLE
        at sun.nio.ch.DevPollArrayWrapper.poll0(java.base@9-ea/Native Method)
        at sun.nio.ch.DevPollArrayWrapper.poll(java.base@9-ea/DevPollArrayWrapper.java:223)
        at sun.nio.ch.DevPollSelectorImpl.doSelect(java.base@9-ea/DevPollSelectorImpl.java:97)
        at sun.nio.ch.SelectorImpl.lockAndDoSelect(java.base@9-ea/SelectorImpl.java:86)
        - locked <0x00000000fef57b20> (a sun.nio.ch.Util$2)
        - locked <0x00000000fef57a90> (a java.util.Collections$UnmodifiableSet)
        - locked <0x00000000fef57938> (a sun.nio.ch.DevPollSelectorImpl)
        at sun.nio.ch.SelectorImpl.select(java.base@9-ea/SelectorImpl.java:97)
        at jdk.incubator.http.HttpClientImpl$SelectorManager.run(jdk.incubator.httpclient@9-ea/HttpClientImpl.java:375)

        "Thread-1" #13 prio=5 os_prio=64 tid=0x0000000001318800 nid=0x21 in Object.wait() [0xffff80ffb81fe000]
           java.lang.Thread.State: WAITING (on object monitor)
        at java.lang.Object.wait(java.base@9-ea/Native Method)
        - waiting on <0x00000000fed353f8> (a java.lang.Thread)
        at java.lang.Thread.join(java.base@9-ea/Thread.java:1353)
        - waiting to re-lock in wait() <0x00000000fed353f8> (a java.lang.Thread)
        at java.lang.Thread.join(java.base@9-ea/Thread.java:1427)
        at jdk.incubator.http.RawChannelTest$TestServer.run(jdk.incubator.httpclient@9-ea/RawChannelTest.java:225)

        "MainThread" #12 prio=5 os_prio=64 tid=0x0000000000a37000 nid=0x20 waiting on condition [0xffff80ffb85f2000]
           java.lang.Thread.State: WAITING (parking)
        at jdk.internal.misc.Unsafe.park(java.base@9-ea/Native Method)
        - parking to wait for <0x00000000ff7e3458> (a java.util.concurrent.CountDownLatch$Sync)
        at java.util.concurrent.locks.LockSupport.park(java.base@9-ea/LockSupport.java:192)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer.parkAndCheckInterrupt(java.base@9-ea/AbstractQueuedSynchronizer.java:871)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedInterruptibly(java.base@9-ea/AbstractQueuedSynchronizer.java:1024)
        at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireSharedInterruptibly(java.base@9-ea/AbstractQueuedSynchronizer.java:1331)
        at java.util.concurrent.CountDownLatch.await(java.base@9-ea/CountDownLatch.java:232)
        at jdk.incubator.http.RawChannelTest.test(jdk.incubator.httpclient@9-ea/RawChannelTest.java:168)
        at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method)
        at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
        at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:563)
        at org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:85)
        at org.testng.internal.Invoker.invokeMethod(Invoker.java:639)
        at org.testng.internal.Invoker.invokeTestMethod(Invoker.java:821)
        at org.testng.internal.Invoker.invokeTestMethods(Invoker.java:1131)
        at org.testng.internal.TestMethodWorker.invokeTestMethods(TestMethodWorker.java:124)
        at org.testng.internal.TestMethodWorker.run(TestMethodWorker.java:108)
        at org.testng.TestRunner.privateRun(TestRunner.java:773)
        at org.testng.TestRunner.run(TestRunner.java:623)
        at org.testng.SuiteRunner.runTest(SuiteRunner.java:357)
        at org.testng.SuiteRunner.runSequentially(SuiteRunner.java:352)
        at org.testng.SuiteRunner.privateRun(SuiteRunner.java:310)
        at org.testng.SuiteRunner.run(SuiteRunner.java:259)
        at org.testng.SuiteRunnerWorker.runSuite(SuiteRunnerWorker.java:52)
        at org.testng.SuiteRunnerWorker.run(SuiteRunnerWorker.java:86)
        at org.testng.TestNG.runSuitesSequentially(TestNG.java:1185)
        at org.testng.TestNG.runSuitesLocally(TestNG.java:1110)
        at org.testng.TestNG.run(TestNG.java:1018)
        at com.sun.javatest.regtest.agent.TestNGRunner.main(TestNGRunner.java:94)
        at com.sun.javatest.regtest.agent.TestNGRunner.main(TestNGRunner.java:54)
        at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method)
        at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
        at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:563)
        at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:115)
        at java.lang.Thread.run(java.base@9-ea/Thread.java:844)

        "Service Thread" #10 daemon prio=9 os_prio=64 tid=0x0000000000b01800 nid=0x1e runnable [0x0000000000000000]
           java.lang.Thread.State: RUNNABLE

        "Common-Cleaner" #9 daemon prio=8 os_prio=64 tid=0x0000000000870000 nid=0x1d in Object.wait() [0xffff80ffb88f7000]
           java.lang.Thread.State: TIMED_WAITING (on object monitor)
        at java.lang.Object.wait(java.base@9-ea/Native Method)
        - waiting on <0x00000000fff5b8a8> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(java.base@9-ea/ReferenceQueue.java:151)
        - waiting to re-lock in wait() <0x00000000fff5b8a8> (a java.lang.ref.ReferenceQueue$Lock)
        at jdk.internal.ref.CleanerImpl.run(java.base@9-ea/CleanerImpl.java:148)
        at java.lang.Thread.run(java.base@9-ea/Thread.java:844)
        at jdk.internal.misc.InnocuousThread.run(java.base@9-ea/InnocuousThread.java:122)

        "Sweeper thread" #8 daemon prio=9 os_prio=64 tid=0x00000000007ba000 nid=0x1c runnable [0x0000000000000000]
           java.lang.Thread.State: RUNNABLE

        "C1 CompilerThread2" #7 daemon prio=9 os_prio=64 tid=0x00000000007b0000 nid=0x1b waiting on condition [0x0000000000000000]
           java.lang.Thread.State: RUNNABLE
           No compile task

        "C2 CompilerThread1" #6 daemon prio=9 os_prio=64 tid=0x00000000007ae800 nid=0x1a waiting on condition [0x0000000000000000]
           java.lang.Thread.State: RUNNABLE
           No compile task

        "C2 CompilerThread0" #5 daemon prio=9 os_prio=64 tid=0x00000000007ac000 nid=0x19 waiting on condition [0x0000000000000000]
           java.lang.Thread.State: RUNNABLE
           No compile task

        "Signal Dispatcher" #4 daemon prio=9 os_prio=64 tid=0x00000000007aa800 nid=0x18 runnable [0x0000000000000000]
           java.lang.Thread.State: RUNNABLE

        "Finalizer" #3 daemon prio=8 os_prio=64 tid=0x000000000078d000 nid=0x17 in Object.wait() [0xffff80ffb8efd000]
           java.lang.Thread.State: WAITING (on object monitor)
        at java.lang.Object.wait(java.base@9-ea/Native Method)
        - waiting on <0x00000000fff0d068> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(java.base@9-ea/ReferenceQueue.java:151)
        - waiting to re-lock in wait() <0x00000000fff0d068> (a java.lang.ref.ReferenceQueue$Lock)
        at java.lang.ref.ReferenceQueue.remove(java.base@9-ea/ReferenceQueue.java:172)
        at java.lang.ref.Finalizer$FinalizerThread.run(java.base@9-ea/Finalizer.java:216)

        "Reference Handler" #2 daemon prio=10 os_prio=64 tid=0x0000000000781800 nid=0x16 waiting on condition [0xffff80ffb8ffe000]
           java.lang.Thread.State: RUNNABLE
        at java.lang.ref.Reference.waitForReferencePendingList(java.base@9-ea/Native Method)
        at java.lang.ref.Reference.processPendingReferences(java.base@9-ea/Reference.java:174)
        at java.lang.ref.Reference.access$000(java.base@9-ea/Reference.java:44)
        at java.lang.ref.Reference$ReferenceHandler.run(java.base@9-ea/Reference.java:138)

        "main" #1 prio=5 os_prio=64 tid=0x0000000000433800 nid=0x2 in Object.wait() [0xffff80ffbf51e000]
           java.lang.Thread.State: WAITING (on object monitor)
        at java.lang.Object.wait(java.base@9-ea/Native Method)
        - waiting on <0x00000000ffdc8000> (a java.lang.Thread)
        at java.lang.Thread.join(java.base@9-ea/Thread.java:1353)
        - waiting to re-lock in wait() <0x00000000ffdc8000> (a java.lang.Thread)
        at java.lang.Thread.join(java.base@9-ea/Thread.java:1427)
        at com.sun.javatest.regtest.agent.MainWrapper.main(MainWrapper.java:74)

        "VM Thread" os_prio=64 tid=0x0000000000776000 nid=0x15 runnable

        "GC Thread#0" os_prio=64 tid=0x000000000044c800 nid=0x3 runnable

        "GC Thread#1" os_prio=64 tid=0x000000000044d800 nid=0x4 runnable

        "GC Thread#2" os_prio=64 tid=0x000000000044f000 nid=0x5 runnable

        "GC Thread#3" os_prio=64 tid=0x0000000000450800 nid=0x6 runnable

        "GC Thread#4" os_prio=64 tid=0x0000000000452000 nid=0x7 runnable

        "GC Thread#5" os_prio=64 tid=0x0000000000453800 nid=0x8 runnable

        "GC Thread#6" os_prio=64 tid=0x0000000000455000 nid=0x9 runnable

        "G1 Main Marker" os_prio=64 tid=0x000000000047c800 nid=0x12 runnable

        "G1 Marker#0" os_prio=64 tid=0x000000000047e000 nid=0x13 runnable

        "G1 Marker#1" os_prio=64 tid=0x000000000047f800 nid=0x14 runnable

        "G1 Refine#0" os_prio=64 tid=0x0000000000460800 nid=0x10 runnable

        "G1 Refine#1" os_prio=64 tid=0x000000000045f000 nid=0xf runnable

        "G1 Refine#2" os_prio=64 tid=0x000000000045d000 nid=0xe runnable

        "G1 Refine#3" os_prio=64 tid=0x000000000045b800 nid=0xd runnable

        "G1 Refine#4" os_prio=64 tid=0x000000000045a000 nid=0xc runnable

        "G1 Refine#5" os_prio=64 tid=0x0000000000458800 nid=0xb runnable

        "G1 Refine#6" os_prio=64 tid=0x0000000000457000 nid=0xa runnable

        "G1 Young RemSet Sampling" os_prio=64 tid=0x0000000000462000 nid=0x11 runnable

        "VM Periodic Task Thread" os_prio=64 tid=0x0000000000a90800 nid=0x1f waiting on condition

        JNI global references: 760

        --- Timeout information end.
        elapsed time (seconds): 600.595
        ----------configuration:(5/301)----------
        Boot Layer
          add modules: jdk.incubator.httpclient
          add reads: jdk.incubator.httpclient ALL-UNNAMED
          patch: jdk.incubator.httpclient /scratch/jenkins/workspace/9-dev/testresults/solaris-x64/jdk_test.tier2/jtreg/JTwork/java/net/httpclient/whitebox/Driver.d/patches/jdk.incubator.httpclient

        ----------System.out:(14/782)----------
        [TestNG] Running:
          java/net/httpclient/whitebox/Driver.java

        Thread[MainThread,5,MainThreadGroup]: raw channel to http://127.0.0.1:48445/
        Thread[MainThread,5,MainThreadGroup]: RawChannel has 321 initial bytes
        Thread[SelectorManager,5,MainThreadGroup]: OP_WRITE #1
        Thread[SelectorManager,5,MainThreadGroup]: OP_WRITE clogged SNDBUF with 131024 bytes
        Thread[SelectorManager,5,MainThreadGroup]: OP_WRITE #2
        Thread[SelectorManager,5,MainThreadGroup]: OP_WRITE clogged SNDBUF with 131024 bytes
        Thread[SelectorManager,5,MainThreadGroup]: OP_WRITE #3
        Thread[SelectorManager,5,MainThreadGroup]: OP_WRITE clogged SNDBUF with 131024 bytes
        Thread[SelectorManager,5,MainThreadGroup]: OP_WRITE #4
        Thread[Thread-2,5,MainThreadGroup]: Server read 393072 bytes
        Timeout signalled after 600 seconds
        ----------System.err:(1/59)----------
        WARNING: Using incubator modules: jdk.incubator.httpclient
        ----------rerun:(33/2583)*----------
        HOME=/opt/mach5/mach5-one \\
        JDK8_HOME=/scratch/jenkins/workspace/mach5data/install/java/re/jdk/8/promoted/latest/bundles/solaris-x64/jdk-8-solaris-x64.tar.gz/jdk1.8.0 \\
        JTREG_TIMEOUT_FACTOR=5 \\
        JTREG_TIMEOUT_OPTION=-timeoutFactor:5 \\
        JTREG_VERBOSE=fail,error,time \\
        LC_ALL=en_US.UTF-8 \\
        PATH=/bin:/usr/bin \\
        TZ=localtime \\
        buildid=BUILD_ID \\
        CLASSPATH=/scratch/jenkins/workspace/9-dev/jdk/test/java/net/httpclient/whitebox:/scratch/jenkins/workspace/mach5data/install/java/re/jtreg/4.2/promoted/all/b07/bundles/jtreg_bin-4.2.zip/jtreg/lib/javatest.jar:/scratch/jenkins/workspace/mach5data/install/java/re/jtreg/4.2/promoted/all/b07/bundles/jtreg_bin-4.2.zip/jtreg/lib/jtreg.jar \\
            /scratch/jenkins/workspace/9-dev/build/jdk/bin/java \\
                -Dtest.class.path.prefix=/scratch/jenkins/workspace/9-dev/jdk/test/java/net/httpclient/whitebox \\
                -Dtest.src=/scratch/jenkins/workspace/9-dev/jdk/test/java/net/httpclient/whitebox \\
                -Dtest.src.path=/scratch/jenkins/workspace/9-dev/jdk/test/java/net/httpclient/whitebox \\
                -Dtest.classes=/scratch/jenkins/workspace/9-dev/testresults/solaris-x64/jdk_test.tier2/jtreg/JTwork/classes/3/java/net/httpclient/whitebox \\
                -Dtest.class.path=/scratch/jenkins/workspace/9-dev/testresults/solaris-x64/jdk_test.tier2/jtreg/JTwork/classes/3/java/net/httpclient/whitebox \\
                -Dtest.vm.opts='-ea -esa -Xmx512m' \\
                -Dtest.tool.vm.opts='-J-ea -J-esa -J-Xmx512m' \\
                -Dtest.compiler.opts= \\
                -Dtest.java.opts= \\
                -Dtest.jdk=/scratch/jenkins/workspace/9-dev/build/jdk \\
                -Dcompile.jdk=/scratch/jenkins/workspace/9-dev/build/jdk \\
                -Dtest.timeout.factor=5.0 \\
                -Dtest.modules=jdk.incubator.httpclient \\
                -Dtest.patch.path= \\
                -Xbootclasspath/a:/scratch/jenkins/workspace/mach5data/install/java/re/jtreg/4.2/promoted/all/b07/bundles/jtreg_bin-4.2.zip/jtreg/lib/testng.jar \\
                --patch-module jdk.incubator.httpclient=/scratch/jenkins/workspace/9-dev/testresults/solaris-x64/jdk_test.tier2/jtreg/JTwork/java/net/httpclient/whitebox/Driver.d/patches/jdk.incubator.httpclient \\
                --add-modules jdk.incubator.httpclient \\
                --add-reads jdk.incubator.httpclient=ALL-UNNAMED \\
                -ea \\
                -esa \\
                -Xmx512m \\
                com.sun.javatest.regtest.agent.MainWrapper /scratch/jenkins/workspace/9-dev/testresults/solaris-x64/jdk_test.tier2/jtreg/JTwork/java/net/httpclient/whitebox/Driver.d/testng.1.jta java/net/httpclient/whitebox/Driver.java false jdk.incubator.httpclient/jdk.incubator.http.RawChannelTest
        result: Error. Program `/scratch/jenkins/workspace/9-dev/build/jdk/bin/java' timed out (timeout set to 600000ms, elapsed time including timeout handling was 600588ms).


        test result: Error. Program `/scratch/jenkins/workspace/9-dev/build/jdk/bin/java' timed out (timeout set to 600000ms, elapsed time including timeout handling was 600588ms).

          Attachments

            Issue Links

              Activity

                People

                Assignee:
                dfuchs Daniel Fuchs
                Reporter:
                fyuan Frank Yuan (Inactive)
                Votes:
                0 Vote for this issue
                Watchers:
                7 Start watching this issue

                  Dates

                  Created:
                  Updated:
                  Resolved: