JDK-8235777 : compiler/graalunit/CoreTest.java times out with serial and parallel GC
  • Type: Bug
  • Component: hotspot
  • Sub-Component: compiler
  • Affected Version: 14
  • Priority: P4
  • Status: Closed
  • Resolution: Duplicate
  • OS: linux
  • CPU: x86_64
  • Submitted: 2019-12-11
  • Updated: 2020-01-13
  • Resolved: 2019-12-11
Related Reports
Duplicate :  
Description
The following test timed out in the JDK14 CI:

compiler/graalunit/CoreTest.java

It timed out with both serial GC and parallel GC.

Here's a snippet from the end of the -XX:+UseParallelGC log:

OK (1319 tests)


10 longest running test classes:
    383,733 ms    org.graalvm.compiler.core.test.CountedLoopTest
     29,556 ms    org.graalvm.compiler.core.test.TrivialInliningExplosionTest
     13,414 ms    org.graalvm.compiler.core.test.SubWordFieldStoreTest
     11,656 ms    org.graalvm.compiler.core.test.BciBlockMappingTest
      8,152 ms    org.graalvm.compiler.core.test.MemoryArithmeticTest
      7,963 ms    org.graalvm.compiler.core.test.ArrayLengthProviderTest
      4,096 ms    org.graalvm.compiler.core.test.ea.CountUppercaseParallelTest
      3,914 ms    org.graalvm.compiler.core.test.inlining.InliningTest
      3,738 ms    org.graalvm.compiler.core.test.inlining.NestedLoopEffectsPhaseComplexityTest
      2,873 ms    org.graalvm.compiler.core.test.SubWordArrayStoreTest
10 longest running tests:
     75,336 ms    incrementUnsigned7(org.graalvm.compiler.core.test.CountedLoopTest)
     36,886 ms    increment7(org.graalvm.compiler.core.test.CountedLoopTest)
     35,033 ms    incrementUnsigned8b(org.graalvm.compiler.core.test.CountedLoopTest)
     34,729 ms    decrement4(org.graalvm.compiler.core.test.CountedLoopTest)
     32,448 ms    decrementUnsigned7(org.graalvm.compiler.core.test.CountedLoopTest)
     31,878 ms    incrementUnsigned8a(org.graalvm.compiler.core.test.CountedLoopTest)
     29,555 ms    test(org.graalvm.compiler.core.test.TrivialInliningExplosionTest)
     25,731 ms    decrementEq5(org.graalvm.compiler.core.test.CountedLoopTest)
     22,454 ms    incrementEq7(org.graalvm.compiler.core.test.CountedLoopTest)
     19,771 ms    decrement5(org.graalvm.compiler.core.test.CountedLoopTest)
WARNING: An illegal reflective access operation has occurred
WARNING: Illegal reflective access by org.graalvm.compiler.core.test.MethodHandleEagerResolution (file:/opt/mach5/mesos/work_dir/jib-master/install/jdk-14+27-1312/linux-x64-debug.test/hotspot/jtreg/graal/jdk.vm.compiler.tests.jar) to field java.lang.String.value
WARNING: Please consider reporting this to the maintainers of org.graalvm.compiler.core.test.MethodHandleEagerResolution
WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
WARNING: All illegal access operations will be denied in a future release
Java HotSpot(TM) 64-Bit Server VM warning: outputStream::do_vsnprintf output truncated -- buffer length is 2000 bytes but 2384 bytes are needed.

[2019-12-11T12:03:01.411512Z] Waiting for completion for process 20533
[2019-12-11T12:03:01.411821Z] Waiting for completion finished for process 20533
----------System.err:(1/15)----------
STATUS:Passed.
----------rerun:(37/7640)*----------

Notice that the test passed while JTREG timeout handling was
running. Perhaps the test just needs a slightly longer default
timeout value?


Here's a snippet from the end of the -XX:+UseSerialGC log:

OK (1319 tests)


10 longest running test classes:
    315,852 ms    org.graalvm.compiler.core.test.CountedLoopTest
     31,972 ms    org.graalvm.compiler.core.test.TrivialInliningExplosionTest
     12,639 ms    org.graalvm.compiler.core.test.BciBlockMappingTest
     12,616 ms    org.graalvm.compiler.core.test.SubWordFieldStoreTest
      9,380 ms    org.graalvm.compiler.core.test.MemoryArithmeticTest
      8,251 ms    org.graalvm.compiler.core.test.ArrayLengthProviderTest
      5,223 ms    org.graalvm.compiler.core.test.DirectByteBufferTest
      5,083 ms    org.graalvm.compiler.core.test.SubWordArrayStoreTest
      3,539 ms    org.graalvm.compiler.core.test.ea.CountUppercaseParallelTest
      3,477 ms    org.graalvm.compiler.core.test.InterfaceMethodHandleTest
10 longest running tests:
     41,972 ms    incrementUnsigned7(org.graalvm.compiler.core.test.CountedLoopTest)
     34,574 ms    decrementUnsigned7(org.graalvm.compiler.core.test.CountedLoopTest)
     31,966 ms    decrementEq5(org.graalvm.compiler.core.test.CountedLoopTest)
     31,966 ms    test(org.graalvm.compiler.core.test.TrivialInliningExplosionTest)
     28,376 ms    decrement4(org.graalvm.compiler.core.test.CountedLoopTest)
     27,259 ms    increment7(org.graalvm.compiler.core.test.CountedLoopTest)
     23,464 ms    incrementEq7(org.graalvm.compiler.core.test.CountedLoopTest)
     20,245 ms    decrement5(org.graalvm.compiler.core.test.CountedLoopTest)
     20,148 ms    decrementUnsigned8(org.graalvm.compiler.core.test.CountedLoopTest)
     19,293 ms    incrementUnsigned8a(org.graalvm.compiler.core.test.CountedLoopTest)
WARNING: An illegal reflective access operation has occurred
WARNING: Illegal reflective access by org.graalvm.compiler.core.test.MethodHandleEagerResolution (file:/opt/mach5/mesos/work_dir/jib-master/install/jdk-14+27-1312/linux-x64-debug.test/hotspot/jtreg/graal/jdk.vm.compiler.tests.jar) to field java.lang.String.value
WARNING: Please consider reporting this to the maintainers of org.graalvm.compiler.core.test.MethodHandleEagerResolution
WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
WARNING: All illegal access operations will be denied in a future release

[2019-12-11T12:01:40.969262Z] Waiting for completion for process 29740
[2019-12-11T12:01:40.969568Z] Waiting for completion finished for process 29740
----------System.err:(1/15)----------
STATUS:Passed.
----------rerun:(36/7573)*----------

Notice that the test passed while JTREG timeout handling was
running. Perhaps the test just needs a slightly longer default
timeout value?
Comments
graalunit tests should run with increased timeout factor (this is how they are run in graal tiers). JDK-8215728 accidentally re-enabled graalunit tests to be executed as part of 'hotspot_compiler_all_gcs' test group which run with smaller timeout factor. Will close this bug as duplicate of JDK-8235773
11-12-2019