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
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
- duplicates
-
JDK-8273695 Safepoint deadlock on VMOperation_lock
- Closed
- relates to
-
JDK-8265489 Stress test times out because of long ObjectSynchronizer::monitors_iterate(...) operation
- Resolved
-
JDK-8273695 Safepoint deadlock on VMOperation_lock
- Closed