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

Stream fork/join tasks occasionally fail to complete

XMLWordPrintable

    • b115
    • generic
    • generic
    • Verified

      java/util/stream/test/org/openjdk/tests/java/util/stream/ToArrayOpTest.java

      Failed 1 time during jdk9/b56 same binaries run. Also see the test timeout at different runs at different machines.

      #section:build
      ----------messages:(3/3266)----------
      command: build org.openjdk.tests.java.lang.invoke.SerializedLambdaTest org.openjdk.tests.java.lang.invoke.DeserializeMethodTest
      ...
      elapsed time (seconds): 0.003
      result: Passed. All files up to date

      #section:testng
      ----------messages:(272/15342)----------
      command: testng org.openjdk.tests.java.util.stream.ToArrayOpTest
      reason: Assumed action based on file name: run testng org.openjdk.tests.java.util.stream.ToArrayOpTest
      Timeout information:
      Running jstack on process 36966
      2015-04-06 21:03:15
      Full thread dump Java HotSpot(TM) 64-Bit Server VM (1.9.0-ea-b56 mixed mode):

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

      "ForkJoinPool.commonPool-worker-28" #51 daemon prio=5 os_prio=64 tid=0x00000000032c1800 nid=0x4c waiting on condition [0xffff80ffa2094000]
         java.lang.Thread.State: WAITING (parking)
      at sun.misc.Unsafe.park(Native Method)
      - parking to wait for <0x00000000e000e880> (a java.util.concurrent.ForkJoinPool)
      at java.util.concurrent.ForkJoinPool.awaitWork(ForkJoinPool.java:1826)
      at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1695)
      at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:157)

      "ForkJoinPool.commonPool-worker-3" #50 daemon prio=5 os_prio=64 tid=0x0000000002f6d000 nid=0x4b waiting on condition [0xffff80ffa2195000]
         java.lang.Thread.State: WAITING (parking)
      at sun.misc.Unsafe.park(Native Method)
      - parking to wait for <0x00000000e000e880> (a java.util.concurrent.ForkJoinPool)
      at java.util.concurrent.ForkJoinPool.awaitWork(ForkJoinPool.java:1826)
      at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1695)
      at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:157)

      "ForkJoinPool.commonPool-worker-17" #48 daemon prio=5 os_prio=64 tid=0x0000000002bb1800 nid=0x49 waiting on condition [0xffff80ffa2397000]
         java.lang.Thread.State: TIMED_WAITING (parking)
      at sun.misc.Unsafe.park(Native Method)
      - parking to wait for <0x00000000e000e880> (a java.util.concurrent.ForkJoinPool)
      at java.util.concurrent.ForkJoinPool.awaitWork(ForkJoinPool.java:1826)
      at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1695)
      at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:157)

      "ForkJoinPool.commonPool-worker-27" #42 daemon prio=5 os_prio=64 tid=0x00000000026c8800 nid=0x44 waiting on condition [0xffff80ffa289c000]
         java.lang.Thread.State: WAITING (parking)
      at sun.misc.Unsafe.park(Native Method)
      - parking to wait for <0x00000000e000e880> (a java.util.concurrent.ForkJoinPool)
      at java.util.concurrent.ForkJoinPool.awaitWork(ForkJoinPool.java:1826)
      at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1695)
      at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:157)

      "ForkJoinPool.commonPool-worker-20" #43 daemon prio=5 os_prio=64 tid=0x0000000003ebd800 nid=0x43 waiting on condition [0xffff80ffa299d000]
         java.lang.Thread.State: WAITING (parking)
      at sun.misc.Unsafe.park(Native Method)
      - parking to wait for <0x00000000e000e880> (a java.util.concurrent.ForkJoinPool)
      at java.util.concurrent.ForkJoinPool.awaitWork(ForkJoinPool.java:1826)
      at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1695)
      at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:157)

      "ForkJoinPool.commonPool-worker-16" #39 daemon prio=5 os_prio=64 tid=0x0000000002b1f000 nid=0x40 waiting on condition [0xffff80ffa2ca0000]
         java.lang.Thread.State: WAITING (parking)
      at sun.misc.Unsafe.park(Native Method)
      - parking to wait for <0x00000000e000e880> (a java.util.concurrent.ForkJoinPool)
      at java.util.concurrent.ForkJoinPool.awaitWork(ForkJoinPool.java:1826)
      at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1695)
      at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:157)

      "ForkJoinPool.commonPool-worker-12" #35 daemon prio=5 os_prio=64 tid=0x0000000001c59800 nid=0x3e waiting on condition [0xffff80ffa2ea2000]
         java.lang.Thread.State: WAITING (parking)
      at sun.misc.Unsafe.park(Native Method)
      - parking to wait for <0x00000000e000e880> (a java.util.concurrent.ForkJoinPool)
      at java.util.concurrent.ForkJoinPool.awaitWork(ForkJoinPool.java:1826)
      at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1695)
      at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:157)

      "ForkJoinPool.commonPool-worker-19" #34 daemon prio=5 os_prio=64 tid=0x0000000002926800 nid=0x3b waiting on condition [0xffff80ffa31a5000]
         java.lang.Thread.State: WAITING (parking)
      at sun.misc.Unsafe.park(Native Method)
      - parking to wait for <0x00000000e000e880> (a java.util.concurrent.ForkJoinPool)
      at java.util.concurrent.ForkJoinPool.awaitWork(ForkJoinPool.java:1826)
      at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1695)
      at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:157)

      "ForkJoinPool.commonPool-worker-1" #32 daemon prio=5 os_prio=64 tid=0x000000000288f000 nid=0x37 waiting on condition [0xffff80ffa35a9000]
         java.lang.Thread.State: WAITING (parking)
      at sun.misc.Unsafe.park(Native Method)
      - parking to wait for <0x00000000e000e880> (a java.util.concurrent.ForkJoinPool)
      at java.util.concurrent.ForkJoinPool.awaitWork(ForkJoinPool.java:1826)
      at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1695)
      at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:157)

      "ForkJoinPool.commonPool-worker-22" #29 daemon prio=5 os_prio=64 tid=0x00000000019f5800 nid=0x36 waiting on condition [0xffff80ffa36aa000]
         java.lang.Thread.State: WAITING (parking)
      at sun.misc.Unsafe.park(Native Method)
      - parking to wait for <0x00000000e000e880> (a java.util.concurrent.ForkJoinPool)
      at java.util.concurrent.ForkJoinPool.awaitWork(ForkJoinPool.java:1826)
      at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1695)
      at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:157)

      "MainThread" #23 prio=5 os_prio=64 tid=0x000000000090f800 nid=0x30 in Object.wait() [0xffff80ffb3129000]
         java.lang.Thread.State: BLOCKED (on object monitor)
      at java.lang.Object.wait(Native Method)
      at java.util.concurrent.ForkJoinTask.externalAwaitDone(ForkJoinTask.java:334)
      - locked <0x00000000f797c750> (a java.util.stream.Nodes$ToArrayTask$OfLong)
      at java.util.concurrent.ForkJoinTask.doInvoke(ForkJoinTask.java:405)
      at java.util.concurrent.ForkJoinTask.invoke(ForkJoinTask.java:734)
      at java.util.stream.Nodes.flattenLong(Nodes.java:522)
      at java.util.stream.Nodes.collectLong(Nodes.java:405)
      at java.util.stream.LongPipeline.evaluateToNode(LongPipeline.java:140)
      at java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:572)
      at java.util.stream.AbstractPipeline.evaluateToArrayNode(AbstractPipeline.java:255)
      at java.util.stream.LongPipeline.toArray(LongPipeline.java:486)
      at org.openjdk.tests.java.util.stream.ToArrayOpTest.lambda$testLongOpsWithFilter$67(ToArrayOpTest.java:313)
      at org.openjdk.tests.java.util.stream.ToArrayOpTest$$Lambda$239/1024538978.apply(Unknown Source)
      at java.util.stream.OpTestCase$BaseTerminalTestScenario.run(OpTestCase.java:404)
      at java.util.stream.OpTestCase$ExerciseDataTerminalBuilder.exercise(OpTestCase.java:528)
      at java.util.stream.OpTestCase.exerciseTerminalOps(OpTestCase.java:565)
      at org.openjdk.tests.java.util.stream.ToArrayOpTest.testLongOpsWithFilter(ToArrayOpTest.java:313)
      at sun.reflect.GeneratedMethodAccessor24.invoke(Unknown Source)
      at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      at java.lang.reflect.Method.invoke(Method.java:502)
      at org.testng.internal.MethodInvocationHelper.invokeMethod(MethodInvocationHelper.java:84)
      at org.testng.internal.Invoker.invokeMethod(Invoker.java:714)
      at org.testng.internal.Invoker.invokeTestMethod(Invoker.java:901)
      at org.testng.internal.Invoker.invokeTestMethods(Invoker.java:1231)
      at org.testng.internal.TestMethodWorker.invokeTestMethods(TestMethodWorker.java:127)
      at org.testng.internal.TestMethodWorker.run(TestMethodWorker.java:111)
      at org.testng.TestRunner.privateRun(TestRunner.java:767)
      at org.testng.TestRunner.run(TestRunner.java:617)
      at org.testng.SuiteRunner.runTest(SuiteRunner.java:334)
      at org.testng.SuiteRunner.runSequentially(SuiteRunner.java:329)
      at org.testng.SuiteRunner.privateRun(SuiteRunner.java:291)
      at org.testng.SuiteRunner.run(SuiteRunner.java:240)
      at org.testng.SuiteRunnerWorker.runSuite(SuiteRunnerWorker.java:52)
      at org.testng.SuiteRunnerWorker.run(SuiteRunnerWorker.java:86)
      at org.testng.TestNG.runSuitesSequentially(TestNG.java:1224)
      at org.testng.TestNG.runSuitesLocally(TestNG.java:1149)
      at org.testng.TestNG.run(TestNG.java:1057)
      at com.sun.javatest.regtest.TestNGAction$TestNGRunner.main(TestNGAction.java:163)
      at com.sun.javatest.regtest.TestNGAction$TestNGRunner.main(TestNGAction.java:147)
      at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
      at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      at java.lang.reflect.Method.invoke(Method.java:502)
      at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:92)
      at java.lang.Thread.run(Thread.java:745)

      "Service Thread" #21 daemon prio=9 os_prio=64 tid=0x00000000006cd800 nid=0x2e runnable [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

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

      "C1 CompilerThread14" #19 daemon prio=9 os_prio=64 tid=0x00000000006ba800 nid=0x2c waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "C1 CompilerThread13" #18 daemon prio=9 os_prio=64 tid=0x00000000006b0000 nid=0x2b waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "C1 CompilerThread12" #17 daemon prio=9 os_prio=64 tid=0x000000000068d800 nid=0x2a waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "C1 CompilerThread11" #16 daemon prio=9 os_prio=64 tid=0x000000000068b800 nid=0x29 waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "C1 CompilerThread10" #15 daemon prio=9 os_prio=64 tid=0x0000000000689800 nid=0x28 waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "C2 CompilerThread9" #14 daemon prio=9 os_prio=64 tid=0x0000000000676800 nid=0x27 waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "C2 CompilerThread8" #13 daemon prio=9 os_prio=64 tid=0x0000000000674800 nid=0x26 waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "C2 CompilerThread7" #12 daemon prio=9 os_prio=64 tid=0x0000000000671800 nid=0x25 waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "C2 CompilerThread6" #11 daemon prio=9 os_prio=64 tid=0x000000000064f000 nid=0x24 waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "C2 CompilerThread5" #10 daemon prio=9 os_prio=64 tid=0x0000000000645000 nid=0x23 waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "C2 CompilerThread4" #9 daemon prio=9 os_prio=64 tid=0x0000000000610800 nid=0x22 waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "C2 CompilerThread3" #8 daemon prio=9 os_prio=64 tid=0x00000000005ed000 nid=0x21 waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "C2 CompilerThread2" #7 daemon prio=9 os_prio=64 tid=0x00000000005eb800 nid=0x20 waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "C2 CompilerThread1" #6 daemon prio=9 os_prio=64 tid=0x00000000005e8800 nid=0x1f waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

      "C2 CompilerThread0" #5 daemon prio=9 os_prio=64 tid=0x00000000005e2800 nid=0x1e waiting on condition [0x0000000000000000]
         java.lang.Thread.State: RUNNABLE

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

      "Finalizer" #3 daemon prio=8 os_prio=64 tid=0x00000000005ad000 nid=0x1c in Object.wait() [0xffff80ffb6993000]
         java.lang.Thread.State: WAITING (on object monitor)
      at java.lang.Object.wait(Native Method)
      - waiting on <0x00000000e0019d78> (a java.lang.ref.ReferenceQueue$Lock)
      at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:143)
      - locked <0x00000000e0019d78> (a java.lang.ref.ReferenceQueue$Lock)
      at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:164)
      at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:212)

      "Reference Handler" #2 daemon prio=10 os_prio=64 tid=0x00000000005a1000 nid=0x1b in Object.wait() [0xffff80ffb6a94000]
         java.lang.Thread.State: WAITING (on object monitor)
      at java.lang.Object.wait(Native Method)
      at java.lang.Object.wait(Object.java:508)
      at java.lang.ref.Reference.tryHandlePending(Reference.java:191)
      - locked <0x00000000e0016448> (a java.lang.ref.Reference$Lock)
      at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:153)

      "main" #1 prio=5 os_prio=64 tid=0x0000000000420000 nid=0x2 in Object.wait() [0xffff80ffbf19e000]
         java.lang.Thread.State: WAITING (on object monitor)
      at java.lang.Object.wait(Native Method)
      - waiting on <0x00000000e000f390> (a java.lang.Thread)
      at java.lang.Thread.join(Thread.java:1249)
      - locked <0x00000000e000f390> (a java.lang.Thread)
      at java.lang.Thread.join(Thread.java:1323)
      at com.sun.javatest.regtest.agent.MainWrapper.main(MainWrapper.java:69)

      "VM Thread" os_prio=64 tid=0x0000000000599800 nid=0x1a runnable

      "ParGC Thread#0" os_prio=64 tid=0x0000000000432800 nid=0x3 runnable

      "ParGC Thread#1" os_prio=64 tid=0x0000000000434000 nid=0x4 runnable

      "ParGC Thread#2" os_prio=64 tid=0x0000000000435800 nid=0x5 runnable

      "ParGC Thread#3" os_prio=64 tid=0x0000000000437000 nid=0x6 runnable

      "ParGC Thread#4" os_prio=64 tid=0x0000000000438800 nid=0x7 runnable

      "ParGC Thread#5" os_prio=64 tid=0x000000000043a800 nid=0x8 runnable

      "ParGC Thread#6" os_prio=64 tid=0x000000000043c000 nid=0x9 runnable

      "ParGC Thread#7" os_prio=64 tid=0x000000000043d800 nid=0xa runnable

      "ParGC Thread#8" os_prio=64 tid=0x000000000043f000 nid=0xb runnable

      "ParGC Thread#9" os_prio=64 tid=0x0000000000440800 nid=0xc runnable

      "ParGC Thread#10" os_prio=64 tid=0x0000000000442000 nid=0xd runnable

      "ParGC Thread#11" os_prio=64 tid=0x0000000000443800 nid=0xe runnable

      "ParGC Thread#12" os_prio=64 tid=0x0000000000445000 nid=0xf runnable

      "ParGC Thread#13" os_prio=64 tid=0x0000000000446800 nid=0x10 runnable

      "ParGC Thread#14" os_prio=64 tid=0x0000000000448000 nid=0x11 runnable

      "ParGC Thread#15" os_prio=64 tid=0x0000000000449800 nid=0x12 runnable

      "ParGC Thread#16" os_prio=64 tid=0x000000000044b000 nid=0x13 runnable

      "ParGC Thread#17" os_prio=64 tid=0x000000000044e000 nid=0x14 runnable

      "ParGC Thread#18" os_prio=64 tid=0x000000000044f800 nid=0x15 runnable

      "ParGC Thread#19" os_prio=64 tid=0x0000000000451000 nid=0x16 runnable

      "ParGC Thread#20" os_prio=64 tid=0x0000000000452800 nid=0x17 runnable

      "ParGC Thread#21" os_prio=64 tid=0x0000000000456000 nid=0x18 runnable

      "ParGC Thread#22" os_prio=64 tid=0x0000000000457800 nid=0x19 runnable

      "VM Periodic Task Thread" os_prio=64 tid=0x00000000006cf800 nid=0x2f waiting on condition

      JNI global references: 599

      --- Timeout information end.
      elapsed time (seconds): 480.552

        1. doit-setCompletion.PrintAssembly
          46 kB
        2. doit.extract_03_0_0.hs_err_pid.log.96
          31 kB
        3. doit.extract_03_0_0.log.96
          220 kB
        4. doit.extract_03_1_0.hs_err_pid.log.2328
          31 kB
        5. doit.extract_03_1_0.log.2328
          62 kB
        6. doit.extract_03_0_1.hs_err_pid.499
          29 kB
        7. doit.extract_03_0_1.log.499
          51 kB
        8. doit.extract_03_1_1.hs_err_pid.39
          29 kB
        9. doit.extract_03_1_1.log.39
          55 kB
        10. 8077392_repro.zip
          932 kB
        11. eval_note2
          27 kB
        12. eval_note4
          33 kB
        13. eval_note5
          13 kB

            dcubed Daniel Daugherty
            amlu Amy Lu (Inactive)
            Votes:
            0 Vote for this issue
            Watchers:
            11 Start watching this issue

              Created:
              Updated:
              Resolved: