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

runThese times out with ZGC

XMLWordPrintable

    • x86_64

      The following test failed in the JDK17 CI:

      applications/runthese/RunThese24H.java

      Here's a snippet from the log file:

      #section:main
      ----------messages:(7/518)----------
      command: main applications.kitchensink.process.glue.Main runthese.properties test.duration=1440m
      reason: User specified action: run main/othervm/timeout=108000 applications.kitchensink.process.glue.Main runthese.properties test.duration=1440m
      Mode: othervm [/othervm specified]
      Additional options from @modules: --add-modules java.base,jdk.incubator.foreign --add-exports java.base/jdk.internal.org.objectweb.asm=ALL-UNNAMED
      Timeout information:
      --- Timeout information end.
      elapsed time (seconds): 108047.291
      ----------configuration:(4/141)----------
      Boot Layer
        add modules: java.base jdk.incubator.foreign
        add exports: java.base/jdk.internal.org.objectweb.asm ALL-UNNAMED

      ----------System.out:(1101/96176)*----------
      C:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+31-2645\\src.full\\closed\\test\\hotspot\\jtreg\\applications\\runthese\\applications\\kitchensink\\resources\\kitchensink.default.properties
      C:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+31-2645\\src.full\\closed\\test\\hotspot\\jtreg\\applications\\kitchensink\\resources\\kitchensink.default.properties
      C:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+31-2645\\src.full\\closed\\test\\hotspot\\jtreg\\applications\\runthese\\applications\\kitchensink\\resources\\kitchensink.default.properties
      C:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+31-2645\\src.full\\closed\\test\\hotspot\\jtreg\\applications\\kitchensink\\resources\\kitchensink.default.properties
      Implicit dependency needed
      [jib LOG] Downloading jdk/16/36/bundles/windows-x64/jdk-16_windows-x64_bin.zip ... Checksum
      [jib LOG] Installing jdk/16/36/windows-x64.jdk ... found in jdk/16/36/bundles/windows-x64/jdk-16_windows-x64_bin.zip Checksum
      [jib LOG] Resuming jck/15/b01/bundles/JCK-runtime-15.jar ... NoOfTestToPrase =56356 TotalNoOfParsedTest=56355 TotalNoOfManual = 1 class=56356 args=3562 title=56356
      [stress.process.out] For random generator using seed: -1413986703527097850
      [stress.process.out] To re-run test with same seed value please add "-Djdk.test.lib.random.seed=-1413986703527097850" to command line.
      [stress.process.out] Stress process main method is started.
      [stress.process.out] ......................................
      [stress.process.out] Enter[0] (1626063488758):javasoft.sqe.tests.api.javax.annotation.processing.AbstractProcessor.AbstractProcessorTests
      [stress.process.out] ......................................
      [stress.process.out] Exit[0] (1626063488871):javasoft.sqe.tests.api.javax.annotation.processing.AbstractProcessor.AbstractProcessorTests
      [stress.process.out] Creating StreamObject from Object
      [stress.process.out] Checking for JDK Version : 6.0
      [stress.process.out] Creating spec class.

      <snip>

      [stress.process.out] testAbnormalInvokeAll3Singleton: Passed. OKAY
      [stress.process.out] testInForkJoinPool: Passed. OKAY
      [stress.process.out] testGetPool: Passed. OKAY
      [stress.process.out] testGetPool2: Passed. OKAY
      [stress.process.out] testTryUnfork: Passed. OKAY
      [stress.process.out] testPollTaskAsync: Passed. OKAY
      [stress.process.out] testPollTask: Passed. OKAY
      [stress.process.out] testAdapt_Runnable_toString: Passed. OKAY
      [stress.process.out] testAdapt_Callable_toString: Passed. OKAY
      [stress.process.out] testInvokeAll3: Passed. OKAY
      [stress.process.out] testInvokeAll2: Passed. OKAY
      [stress.process.out] testInvokeAll1: Passed. OKAY
      ----------System.err:(708/82501)*----------

      <snip>

      [stress.process.err] Testcase "passedArrayNonEmptyUnorderedCheck_givenNonNulls(414)" failed with arguments [TestData{creator=javasoft.sqe.tests.api.java.util.Collections.unmodifiable.sortedmap.EntrySetToArray$$Lambda$2814633/0x0000000803954668@468a2e40, expectedContent=[0=0], dataTags=[ANY, NOT_EMPTY, NOT_EQUAL_ELEMENTS, ANY_NON_NULL, HAS_NO_NULLS, LONGS, ELEMENTARY_TYPES, SAME_TYPE, SINGLE, COMPARABLE, FIRST_ELEMENT_IS_NOT_NULL, NOT_MORE_THAN_THOUSAND, NOT_MORE_THAN_TEN, NOT_MORE_THAN_THREE, NOT_MORE_THAN_FIVE, NOT_MORE_THAN_TWO, NOT_MORE_THAN_FIFTY, NOT_MORE_THAN_HUNDRED, ALL_ZEROS, NOT_NEGATIVE, VALUE_IS_LESS_THAN_1000]}, 6]
      [stress.process.err]
      [stress.process.err] Note: testcase total time, ms: 3791
      [stress.process.err] checkReduce: Passed. test cases: 5; all passedTestcase "passedArrayNonEmptyUnorderedCheck_givenNonNulls(415)" failed with message: Expected element 0=0 not found in the returned array [0=0, null, java.lang.Object@37af7c8e, java.lang.Object@5b707dbb, java.lang.Object@58a22cf4, java.lang.Object@6dfee1fd, java.lang.Object@2179bbc0, java.lang.Object@79bc87f1, java.lang.Object@30b0c1be, java.lang.Object@2a5b2f5c, java.lang.Object@4610266c]
      [stress.process.err] Testcase "passedArrayNonEmptyUnorderedCheck_givenNonNulls(1121)" failed with message: Expected element javasoft.sqe.tests.api.java.util.stream.testdata.Item@0=javasoft.sqe.tests.api.java.util.stream.testdata.Item@0 not found in the returned array [javasoft.sqe.tests.api.java.util.stream.testdata.Item@0=javasoft.sqe.tests.api.java.util.stream.testdata.Item@0]
      [stress.process.err] at com.sun.tck.lib.Assert.fail(Assert.java:490)
      [stress.process.err] at javasoft.sqe.tests.api.java.util.Collection.ToArrayTestBase.unorderedCheck(ToArrayTestBase.java:127)
      [stress.process.err] at javasoft.sqe.tests.api.java.util.Collection.ToArrayTestBase.passedArrayNonEmptyUnorderedCheck_givenNonNulls(ToArrayTestBase.java:320)
      [stress.process.err] at jdk.internal.reflect.GeneratedMethodAccessor990831.invoke(Unknown Source)
      [stress.process.err] at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      [stress.process.err] at java.base/java.lang.reflect.Method.invoke(Method.java:568)
      [stress.process.err] at com.oracle.tck.lib.autd2.processors.tc.TGFTestCaseMethodSetting.lambda$processSettingMethodPhase$0(TGFTestCaseMethodSetting.java:128)
      [stress.process.err] at com.oracle.tck.lib.autd2.processors.tc.DefaultThreadRunning.process(DefaultThreadRunning.java:61)
      [stress.process.err] at com.oracle.tck.lib.autd2.processors.tc.DefaultThreadRunning.process(DefaultThreadRunning.java:16)
      [stress.process.err] at com.oracle.tck.lib.autd2.AUTD2Utils.iterateThroughProcessorsUntilAllAreDone(AUTD2Utils.java:200)
      [stress.process.err] at com.oracle.tck.lib.autd2.AUTD2Utils.iterateLifePhases(AUTD2Utils.java:39)
      [stress.process.err] at com.oracle.tck.lib.autd2.AUTD2Utils.iterateTestCaseLifePhase(AUTD2Utils.java:379)
      [stress.process.err] at com.oracle.tck.lib.autd2.processors.tg.RunningTestCases.runTestCaseAsNeeded(RunningTestCases.java:63)
      [stress.process.err] at com.oracle.tck.lib.autd2.processors.tg.RunningTestCases.lambda$process$0(RunningTestCases.java:41)
      [stress.process.err] at java.base/java.lang.Iterable.forEach(Iterable.java:75)
      [stress.process.err] at com.oracle.tck.lib.autd2.processors.tg.RunningTestCases.process(RunningTestCases.java:33)
      [stress.process.err] at com.oracle.tck.lib.autd2.processors.tg.RunningTestCases.process(RunningTestCases.java:22)
      [stress.process.err] at com.oracle.tck.lib.autd2.AUTD2Utils.iterateThroughProcessorsUntilAllAreDone(AUTD2Utils.java:200)
      [stress.process.err] at com.oracle.tck.lib.autd2.AUTD2Utils.iterateLifePhases(AUTD2Utils.java:39)
      [stress.process.err] at com.oracle.tck.lib.autd2.TestRunner.performRun(TestRunner.java:111)
      [stress.process.err] at com.oracle.tck.lib.autd2.TestRunner.run(TestRunner.java:313)
      [stress.process.err] at com.oracle.tck.lib.autd2.AUTD2.run(AUTD2.java:53)
      [stress.process.err] at com.oracle.tck.lib.autd2.AUTD2.run(AUTD2.java:97)
      [stress.process.err] at javasoft.sqe.tests.api.java.util.Collections.unmodifiable.sortedmap.EntrySetToArray.main(EntrySetToArray.java:31)
      [stress.process.err] Testcase "passedArrayNonEmptyUnorderedCheck_givenNonNulls(415)" failed with arguments [TestData{creator=javasoft.sqe.tests.api.java.util.Collections.unmodifiable.sortedmap.EntrySetToArray$$Lambda$2814633/0x0000000803954668@468a2e40, expectedContent=[0=0], dataTags=[ANY, NOT_EMPTY, NOT_EQUAL_ELEMENTS, ANY_NON_NULL, HAS_NO_NULLS, LONGS, ELEMENTARY_TYPES, SAME_TYPE, SINGLE, COMPARABLE, FIRST_ELEMENT_IS_NOT_NULL, NOT_MORE_THAN_THOUSAND, NOT_MORE_THAN_TEN, NOT_MORE_THAN_THREE, NOT_MORE_THAN_FIVE, NOT_MORE_THAN_TWO, NOT_MORE_THAN_FIFTY, NOT_MORE_THAN_HUNDRED, ALL_ZEROS, NOT_NEGATIVE, VALUE_IS_LESS_THAN_1000]}, 10]
      [stress.process.err] Testcase "passedArrayNonEmptyUnorderedCheck_givenNonNulls(416)" failed with message: Expected element -1=-1 not found in the returned array [-1=-1]
      ----------rerun:(42/6143)*----------

      <snip>

      result: Error. Program `c:\ade\mesos\work_dir\jib-master\install\jdk-17+31-2645\windows-x64.jdk\jdk-17\bin\java' timed out (timeout set to 108000000ms, elapsed time including timeout handling was 108047290ms).


      I'm starting this in hotspot/test since I'm not sure that we don't
      have a problem with RunThese. There have been so many
      recent strange RunThese failures that I'm wondering if that
      harness/infrastructure has some problems.

      Here's a similar timeout failure with RunThese30M.java:

          JDK-8267571 applications/runthese/RunThese30M.java timed out at 2 hours

            lmesnik Leonid Mesnik
            dcubed Daniel Daugherty
            Votes:
            0 Vote for this issue
            Watchers:
            5 Start watching this issue

              Created:
              Updated:
              Resolved: