java/util/stream/test/org/openjdk/tests/java/util/stream/FlatMapOpTest.java timeout

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

Test fail due to time out in different runs, at different machine. Most often on Solaris Sparcv9.

Here's one example from JDK9/b55 promotion testing:

This run is on a Solaris 10, Sparcv9 1504 MHz, 2 cores, 2G machine. (But timeout also happens on other machines with 4 cores, 32G)

This run uses jtreg options:
-conc:2 \
-vmoption:-Xmx768M \
-javaoption:-Xmixed -javaoption:-d64 -javaoption:-server \
-javaoption:-XX:+UseG1GC \
-timeout:3 \
-agentvm \

As all java/util/stream tests are in exclusiveAccess.dirs (Tests that cannot run concurrently), -conc:2 should not matter.

See the log details:

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

#section:testng
----------messages:(133/7704)----------
command: testng org.openjdk.tests.java.util.stream.FlatMapOpTest
reason: Assumed action based on file name: run testng org.openjdk.tests.java.util.stream.FlatMapOpTest
Timeout information:
Running jstack on process 485
2015-03-25 03:07:07
Full thread dump Java HotSpot(TM) 64-Bit Server VM (1.9.0-ea-b55 mixed mode):

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

"ForkJoinPool.commonPool-worker-1" #66 daemon prio=5 os_prio=64 tid=0x00000001006b7000 nid=0x4b waiting on condition [0xfffffffef19fe000]
   java.lang.Thread.State: TIMED_WAITING (parking)
at sun.misc.Unsafe.park(Native Method)
- parking to wait for <0xffffffff3af98808> (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" #11 prio=5 os_prio=64 tid=0x0000000100518000 nid=0x14 runnable [0xfffffffef28fb000]
   java.lang.Thread.State: RUNNABLE
at java.util.Arrays.copyOf(Arrays.java:3332)
at java.lang.AbstractStringBuilder.expandCapacity(AbstractStringBuilder.java:140)
at java.lang.AbstractStringBuilder.ensureCapacityInternal(AbstractStringBuilder.java:124)
at java.lang.AbstractStringBuilder.append(AbstractStringBuilder.java:650)
at java.lang.StringBuilder.append(StringBuilder.java:208)
at org.testng.Assert.assertEquals(Assert.java:589)
at org.testng.Assert.assertEquals(Assert.java:643)
at org.testng.Assert.assertEquals(Assert.java:618)
at java.util.stream.LambdaTestHelpers.assertContents(LambdaTestHelpers.java:279)
at java.util.stream.LambdaTestHelpers.assertContentsEqual(LambdaTestHelpers.java:390)
at java.util.stream.OpTestCase$ExerciseDataStreamBuilder.lambda$new$0(OpTestCase.java:220)
at java.util.stream.OpTestCase$ExerciseDataStreamBuilder$$Lambda$87/1895020189.assertResult(Unknown Source)
at java.util.stream.OpTestCase$ExerciseDataStreamBuilder.lambda$exercise$2(OpTestCase.java:351)
at java.util.stream.OpTestCase$ExerciseDataStreamBuilder$$Lambda$94/23476684.run(Unknown Source)
at java.util.stream.LambdaTestHelpers.launderAssertion(LambdaTestHelpers.java:405)
at java.util.stream.OpTestCase$ExerciseDataStreamBuilder.exercise(OpTestCase.java:354)
at java.util.stream.OpTestCase.exerciseOps(OpTestCase.java:100)
at org.openjdk.tests.java.util.stream.FlatMapOpTest.testOps(FlatMapOpTest.java:145)
at sun.reflect.GeneratedMethodAccessor11.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" #9 daemon prio=9 os_prio=64 tid=0x0000000100345000 nid=0x12 runnable [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

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

"C1 CompilerThread1" #7 daemon prio=9 os_prio=64 tid=0x00000001002e5800 nid=0x10 waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

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

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

"Surrogate Locker Thread (Concurrent GC)" #4 daemon prio=9 os_prio=64 tid=0x00000001002d3800 nid=0xd waiting on condition [0x0000000000000000]
   java.lang.Thread.State: RUNNABLE

"Finalizer" #3 daemon prio=8 os_prio=64 tid=0x00000001002a7800 nid=0xc in Object.wait() [0xfffffffef42fe000]
   java.lang.Thread.State: WAITING (on object monitor)
at java.lang.Object.wait(Native Method)
- waiting on <0xffffffff3a429628> (a java.lang.ref.ReferenceQueue$Lock)
at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:143)
- locked <0xffffffff3a429628> (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=0x000000010029b000 nid=0xb in Object.wait() [0xfffffffef4efe000]
   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 <0xffffffff3a42a240> (a java.lang.ref.Reference$Lock)
at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:153)

"main" #1 prio=5 os_prio=64 tid=0x0000000100110000 nid=0x2 in Object.wait() [0xffffffff7afff000]
   java.lang.Thread.State: WAITING (on object monitor)
at java.lang.Object.wait(Native Method)
- waiting on <0xffffffff3a400d10> (a java.lang.Thread)
at java.lang.Thread.join(Thread.java:1249)
- locked <0xffffffff3a400d10> (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=0x0000000100295800 nid=0xa runnable

"Gang worker#0 (Parallel GC Threads)" os_prio=64 tid=0x0000000100123800 nid=0x3 runnable

"Gang worker#1 (Parallel GC Threads)" os_prio=64 tid=0x0000000100125000 nid=0x4 runnable

"G1 Main Concurrent Mark GC Thread" os_prio=64 tid=0x0000000100133800 nid=0x8 runnable

"Gang worker#0 (G1 Parallel Marking Threads)" os_prio=64 tid=0x0000000100135000 nid=0x9 runnable

"G1 Concurrent Refinement Thread#0" os_prio=64 tid=0x000000010012a800 nid=0x7 runnable

"G1 Concurrent Refinement Thread#1" os_prio=64 tid=0x0000000100129000 nid=0x6 runnable

"G1 Concurrent Refinement Thread#2" os_prio=64 tid=0x0000000100127000 nid=0x5 runnable

"VM Periodic Task Thread" os_prio=64 tid=0x0000000100347800 nid=0x13 waiting on condition

JNI global references: 645

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