search

eclipse-openj9 / openj9

Eclipse OpenJ9: A Java Virtual Machine for OpenJDK that's optimized for small footprint, fast start-up, and high throughput. Builds on Eclipse OMR (https://github.com/eclipse/omr) and combines with the Extensions for OpenJDK for OpenJ9 repo.
Other
3.28k stars 720 forks source link

JDK 11 aarch64_linux - MathLoadTest_all_special_5m_9 NPE at java.base/java.lang.ClassLoader.addPackageToList(ClassLoader.java:529) #12337

Open JasonFengJ9 opened 3 years ago

JasonFengJ9 commented 3 years ago

Failure link

From an internal build Test_openjdk11_j9_special.system_aarch64_linux_testList_3/5/:

openjdk version "11.0.11" 2021-04-20
OpenJDK Runtime Environment AdoptOpenJDK-11.0.11+7-202103310107 (build 11.0.11+7-202103310107)
Eclipse OpenJ9 VM AdoptOpenJDK-11.0.11+7-202103310107 (build master-b20c7c695, JRE 11 Linux aarch64-64-Bit Compressed References 20210331_97 (JIT enabled, AOT enabled)
OpenJ9   - b20c7c695
OMR      - 0ba8989a2
JCL      - 72b96fe2b3 based on jdk-11.0.11+7)

Rerun in Grinder

Optional info

Failure output (captured from console output)

[2021-03-31T02:46:35.219Z] Running test MathLoadTest_all_special_5m_9 ...
[2021-03-31T02:46:35.219Z] ===============================================
[2021-03-31T02:46:35.219Z] MathLoadTest_all_special_5m_9 Start Time: Tue Mar 30 22:46:34 2021 Epoch Time (ms): 1617158794975
[2021-03-31T02:46:35.219Z] "/home/jenkins/workspace/Test_openjdk11_j9_special.system_aarch64_linux_testList_3/openjdkbinary/j2sdk-image/bin/java" -Xshareclasses:destroyAll; "/home/jenkins/workspace/Test_openjdk11_j9_special.system_aarch64_linux_testList_3/openjdkbinary/j2sdk-image/bin/java" -Xshareclasses:groupAccess,destroyAll; echo "cache cleanup done";
[2021-03-31T02:46:35.219Z] JVMSHRC005I No shared class caches available
[2021-03-31T02:46:35.219Z] JVMSHRC005I No shared class caches available
[2021-03-31T02:46:35.219Z] cache cleanup done
[2021-03-31T02:46:35.219Z] variation: Mode187
[2021-03-31T02:46:35.219Z] JVM_OPTIONS:  -Xaggressive -Xgcpolicy:gencon -Xjit -Xnocompressedrefs 

[2021-03-31T02:47:17.243Z] MLT testFailure: testItem_0262(net.adoptopenjdk.test.bigdecimal.TestSuite017): null
[2021-03-31T02:47:17.243Z] MLT java.lang.NullPointerException
[2021-03-31T02:47:17.243Z] MLT  at java.base/java.lang.ClassLoader.addPackageToList(ClassLoader.java:529)
[2021-03-31T02:47:17.243Z] MLT  at java.base/java.lang.VMAccess.addPackageToList(VMAccess.java:183)
[2021-03-31T02:47:17.243Z] MLT  at java.base/jdk.internal.misc.Unsafe.defineClass(Unsafe.java:1510)
[2021-03-31T02:47:17.243Z] MLT  at java.base/jdk.internal.reflect.ClassDefiner.defineClass(ClassDefiner.java:63)
[2021-03-31T02:47:17.243Z] MLT  at java.base/jdk.internal.reflect.MethodAccessorGenerator$1.run(MethodAccessorGenerator.java:400)
[2021-03-31T02:47:17.243Z] MLT  at java.base/jdk.internal.reflect.MethodAccessorGenerator$1.run(MethodAccessorGenerator.java:394)
[2021-03-31T02:47:17.244Z] MLT  at java.base/java.security.AccessController.doPrivileged(AccessController.java:682)
[2021-03-31T02:47:17.244Z] MLT  at java.base/jdk.internal.reflect.MethodAccessorGenerator.generate(MethodAccessorGenerator.java:393)
[2021-03-31T02:47:17.244Z] MLT  at java.base/jdk.internal.reflect.MethodAccessorGenerator.generateMethod(MethodAccessorGenerator.java:75)
[2021-03-31T02:47:17.244Z] MLT  at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:53)
[2021-03-31T02:47:17.244Z] MLT  at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[2021-03-31T02:47:17.244Z] MLT  at java.base/java.lang.reflect.Method.invoke(Method.java:566)
[2021-03-31T02:47:17.244Z] MLT  at junit.framework.TestCase.runTest(TestCase.java:176)
[2021-03-31T02:47:17.244Z] MLT  at junit.framework.TestCase.runBare(TestCase.java:141)
[2021-03-31T02:47:17.244Z] MLT  at junit.framework.TestResult$1.protect(TestResult.java:122)
[2021-03-31T02:47:17.244Z] MLT  at junit.framework.TestResult.runProtected(TestResult.java:142)
[2021-03-31T02:47:17.244Z] MLT  at junit.framework.TestResult.run(TestResult.java:125)
[2021-03-31T02:47:17.244Z] MLT  at junit.framework.TestCase.run(TestCase.java:129)
[2021-03-31T02:47:17.244Z] MLT  at junit.framework.TestSuite.runTest(TestSuite.java:252)
[2021-03-31T02:47:17.244Z] MLT  at junit.framework.TestSuite.run(TestSuite.java:247)
[2021-03-31T02:47:17.244Z] MLT  at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:86)
[2021-03-31T02:47:17.244Z] MLT  at org.junit.runners.Suite.runChild(Suite.java:128)
[2021-03-31T02:47:17.244Z] MLT  at org.junit.runners.Suite.runChild(Suite.java:27)
[2021-03-31T02:47:17.244Z] MLT  at org.junit.runners.ParentRunner$3.run(ParentRunner.java:290)
[2021-03-31T02:47:17.244Z] MLT  at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:71)
[2021-03-31T02:47:17.244Z] MLT  at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:288)
[2021-03-31T02:47:17.244Z] MLT  at org.junit.runners.ParentRunner.access$000(ParentRunner.java:58)
[2021-03-31T02:47:17.244Z] MLT  at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:268)
[2021-03-31T02:47:17.244Z] MLT  at org.junit.runners.ParentRunner.run(ParentRunner.java:363)
[2021-03-31T02:47:17.244Z] MLT  at org.junit.runner.JUnitCore.run(JUnitCore.java:137)
[2021-03-31T02:47:17.244Z] MLT  at org.junit.runner.JUnitCore.run(JUnitCore.java:115)
[2021-03-31T02:47:17.244Z] MLT  at net.adoptopenjdk.loadTest.adaptors.JUnitAdaptor.executeTest(JUnitAdaptor.java:130)
[2021-03-31T02:47:17.244Z] MLT  at net.adoptopenjdk.loadTest.LoadTestRunner$2.run(LoadTestRunner.java:182)
[2021-03-31T02:47:17.244Z] MLT  at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
[2021-03-31T02:47:17.244Z] MLT  at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[2021-03-31T02:47:17.244Z] MLT  at java.base/java.lang.Thread.run(Thread.java:836)

The NPE in question is at https://github.com/eclipse/openj9/blob/5a48e54c3b5022ac1d8246abd30701617f5ae1bb/jcl/src/java.base/share/classes/java/lang/ClassLoader.java#L659. This appears a JIT issue. fyi @knn-k

pshipton commented 3 years ago

~Seems a dup of https://github.com/eclipse/openj9/issues/8189~

pshipton commented 3 years ago

Actually I take that back, the stack is different.

knn-k commented 3 years ago

I ran this test 10 times locally, and all of them were successful. I used the following build (the same hash values as those in the original report):

openjdk version "11.0.11" 2021-04-20
OpenJDK Runtime Environment AdoptOpenJDK-11.0.11+7-202103311051 (build 11.0.11+7-202103311051)
Eclipse OpenJ9 VM AdoptOpenJDK-11.0.11+7-202103311051 (build master-b20c7c695, JRE 11 Linux aarch64-64-Bit Compressed References 20210331_700 (JIT enabled, AOT enabled)
OpenJ9   - b20c7c695
OMR      - 0ba8989a2
JCL      - 72b96fe2b3 based on jdk-11.0.11+7)
JasonFengJ9 commented 3 years ago

Just in case, the test was run with JVM_OPTIONS: -Xaggressive -Xgcpolicy:gencon -Xjit -Xnocompressedrefs.

knn-k commented 3 years ago

I ran the test with make _MathLoadTest_all_special_5m_9.

JasonFengJ9 commented 3 years ago

I ran this test 10 times locally, and all of them were successful.

More iterations are required. An internal 100x grinder (Grinder_testList_0/210/ - Edit: 1/100 failed - launched another 200x grinder) reproduced the NPE.

09:55:36  MLT 09:55:35.526 - Starting thread. Suite=0 thread=6
09:55:57  MLT 09:55:55.605 - Completed 6.7%. Number of tests started=1142
09:56:02  MLT 09:56:00.953 - First failure detected by thread: load-1. Not creating dumps as no dump generation is requested for this load test
09:56:02  MLT 09:56:01.243 - Test failed
09:56:02  MLT   Failure num.  = 1
09:56:02  MLT   Test number   = 91
09:56:02  MLT   Test details  = 'ArbitraryJava[net.adoptopenjdk.test.autosimd.AutoSIMDTestFloat testLincombWithTemp]'
09:56:02  MLT   Suite number  = 0
09:56:02  MLT   Thread number = 5
09:56:02  MLT >>> Captured test output >>>
09:56:02  MLT Test failed:
09:56:02  MLT java.lang.NullPointerException
09:56:02  MLT   at java.base/java.lang.ClassLoader.addPackageToList(ClassLoader.java:529)
09:56:02  MLT   at java.base/java.lang.VMAccess.addPackageToList(VMAccess.java:183)
09:56:02  MLT   at java.base/jdk.internal.misc.Unsafe.defineClass(Unsafe.java:1510)
09:56:02  MLT   at java.base/jdk.internal.reflect.ClassDefiner.defineClass(ClassDefiner.java:63)
09:56:02  MLT   at java.base/jdk.internal.reflect.MethodAccessorGenerator$1.run(MethodAccessorGenerator.java:400)

Update Not reproduced in a 200x grinder, it appears the failure rate is low, will watch a few nightly runs.

JasonFengJ9 commented 3 years ago

Another appearance at Test_openjdk11_j9_extended.system_aarch64_linux/15/ (ub18-aarch64-9), along with j9mm.479 * ** ASSERTION FAILED ** at ../../../../../../openj9/runtime/gc_glue_java/ConcurrentMarkingDelegate.cpp:61

openjdk version "11.0.12" 2021-07-20
OpenJDK Runtime Environment Temurin-11.0.12+5 (build 11.0.12+5)
Eclipse OpenJ9 VM Temurin-11.0.12+5 (build master-f6bdf7af9, JRE 11 Linux aarch64-64-Bit Compressed References 20210605_147 (JIT enabled, AOT enabled)
OpenJ9   - f6bdf7af9
OMR      - bb369d1aa
JCL      - 1fe213d07d based on jdk-11.0.12+5)

Rerun in Grinder

[2021-06-05T03:15:06.195Z] Running test MathLoadTest_all_5m_1 ...
[2021-06-05T03:15:06.195Z] ===============================================
[2021-06-05T03:15:06.195Z] MathLoadTest_all_5m_1 Start Time: Fri Jun  4 23:15:04 2021 Epoch Time (ms): 1622862904855
[2021-06-05T03:15:06.195Z] "/home/jenkins/workspace/Test_openjdk11_j9_extended.system_aarch64_linux/openjdkbinary/j2sdk-image/bin/java" -Xshareclasses:destroyAll; "/home/jenkins/workspace/Test_openjdk11_j9_extended.system_aarch64_linux/openjdkbinary/j2sdk-image/bin/java" -Xshareclasses:groupAccess,destroyAll; echo "cache cleanup done";
[2021-06-05T03:15:06.195Z] JVMSHRC005I No shared class caches available
[2021-06-05T03:15:06.195Z] JVMSHRC005I No shared class caches available
[2021-06-05T03:15:06.195Z] cache cleanup done
[2021-06-05T03:15:06.195Z] variation: Mode650
[2021-06-05T03:15:06.195Z] JVM_OPTIONS:  -XX:-UseCompressedOops 

[2021-06-05T03:15:41.283Z] MLT 23:15:40.193 - First failure detected by thread: load-2. Not creating dumps as no dump generation is requested for this load test
[2021-06-05T03:15:41.283Z] MLT 23:15:40.385 - Test failed
[2021-06-05T03:15:41.283Z] MLT   Failure num.  = 1
[2021-06-05T03:15:41.283Z] MLT   Test number   = 71
[2021-06-05T03:15:41.283Z] MLT   Test details  = 'ArbitraryJava[net.adoptopenjdk.test.autosimd.AutoSIMDTestInteger testLincombWithTemp]'
[2021-06-05T03:15:41.283Z] MLT   Suite number  = 0
[2021-06-05T03:15:41.283Z] MLT   Thread number = 3
[2021-06-05T03:15:41.283Z] MLT >>> Captured test output >>>
[2021-06-05T03:15:41.283Z] MLT Test failed:
[2021-06-05T03:15:41.283Z] MLT java.lang.NullPointerException
[2021-06-05T03:15:41.283Z] MLT  at java.base/java.lang.ClassLoader.addPackageToList(ClassLoader.java:531)
[2021-06-05T03:15:41.283Z] MLT  at java.base/java.lang.VMAccess.addPackageToList(VMAccess.java:183)
[2021-06-05T03:15:41.283Z] MLT  at java.base/jdk.internal.misc.Unsafe.defineClass(Unsafe.java:1510)
[2021-06-05T03:15:41.283Z] MLT  at java.base/jdk.internal.reflect.ClassDefiner.defineClass(ClassDefiner.java:63)
[2021-06-05T03:15:41.283Z] MLT  at java.base/jdk.internal.reflect.MethodAccessorGenerator$1.run(MethodAccessorGenerator.java:400)
[2021-06-05T03:15:41.283Z] MLT  at java.base/jdk.internal.reflect.MethodAccessorGenerator$1.run(MethodAccessorGenerator.java:394)
[2021-06-05T03:15:41.283Z] MLT  at java.base/java.security.AccessController.doPrivileged(AccessController.java:682)
[2021-06-05T03:15:41.283Z] MLT  at java.base/jdk.internal.reflect.MethodAccessorGenerator.generate(MethodAccessorGenerator.java:393)
[2021-06-05T03:15:41.283Z] MLT  at java.base/jdk.internal.reflect.MethodAccessorGenerator.generateMethod(MethodAccessorGenerator.java:75)
[2021-06-05T03:15:41.284Z] MLT  at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:53)
[2021-06-05T03:15:41.284Z] MLT  at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[2021-06-05T03:15:41.284Z] MLT  at java.base/java.lang.reflect.Method.invoke(Method.java:566)
[2021-06-05T03:15:41.284Z] MLT  at net.adoptopenjdk.loadTest.adaptors.ArbitraryJavaAdaptor.executeTest(ArbitraryJavaAdaptor.java:102)
[2021-06-05T03:15:41.284Z] MLT  at net.adoptopenjdk.loadTest.LoadTestRunner$2.run(LoadTestRunner.java:182)
[2021-06-05T03:15:41.284Z] MLT  at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
[2021-06-05T03:15:41.284Z] MLT  at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[2021-06-05T03:15:41.284Z] MLT  at java.base/java.lang.Thread.run(Thread.java:866)
[2021-06-05T03:15:41.284Z] MLT <<<
[2021-06-05T03:15:41.284Z] MLT 
[2021-06-05T03:15:41.284Z] MLT 23:15:40.616 - suite.getInventory().getInventoryFileRef(): openjdk.test.load/config/inventories/math/math.xml
[2021-06-05T03:15:41.284Z] MLT 23:15:40.618 - suite.isCreateDump() : false
[2021-06-05T03:15:41.284Z] MLT 23:15:40.618 - Test failed. Details recorded in execution log.
[2021-06-05T03:15:41.284Z] MLT 23:15:40.781 - suite.getInventory().getInventoryFileRef(): openjdk.test.load/config/inventories/math/math.xml
[2021-06-05T03:15:41.284Z] MLT 23:15:40.781 - suite.isCreateDump() : false
[2021-06-05T03:15:41.284Z] MLT 23:15:40.781 - Test failed. Details recorded in execution log.
[2021-06-05T03:15:44.055Z] MLT 23:15:41.883 - suite.getInventory().getInventoryFileRef(): openjdk.test.load/config/inventories/math/math.xml
[2021-06-05T03:15:44.055Z] MLT 23:15:41.885 - suite.isCreateDump() : false
[2021-06-05T03:15:44.055Z] MLT 23:15:41.885 - Test failed. Details recorded in execution log.
[2021-06-05T03:15:44.055Z] MLT 23:15:41.918 - suite.getInventory().getInventoryFileRef(): openjdk.test.load/config/inventories/math/math.xml
[2021-06-05T03:15:44.055Z] MLT 23:15:41.920 - suite.isCreateDump() : false
[2021-06-05T03:15:44.055Z] MLT 23:15:41.920 - Test failed. Details recorded in execution log.
[2021-06-05T03:15:44.055Z] MLT 23:15:41.928 - suite.getInventory().getInventoryFileRef(): openjdk.test.load/config/inventories/math/math.xml
[2021-06-05T03:15:44.055Z] MLT 23:15:41.929 - suite.isCreateDump() : false
[2021-06-05T03:15:44.055Z] MLT 23:15:41.929 - Test failed. Details recorded in execution log.
[2021-06-05T03:15:44.055Z] MLT 23:15:42.063 - Test failed. Details recorded in execution log.
[2021-06-05T03:15:44.055Z] MLT stderr 0000000000508400: Object neither in heap nor stack-allocated in thread load-6
[2021-06-05T03:15:44.055Z] MLT stderr 0000000000508400: O-Slot=0000000000513280
[2021-06-05T03:15:44.055Z] MLT stderr 0000000000508400: O-Slot value=0000000000000004
[2021-06-05T03:15:44.055Z] MLT stderr 0000000000508400: PC=0000FFFF9637C405
[2021-06-05T03:15:44.055Z] MLT stderr 0000000000508400: framesWalked=3
[2021-06-05T03:15:44.055Z] MLT stderr 0000000000508400: arg0EA=0000000000513330
[2021-06-05T03:15:44.055Z] MLT stderr 0000000000508400: walkSP=00000000005130C8
[2021-06-05T03:15:44.055Z] MLT stderr 0000000000508400: literals=0000000000000010
[2021-06-05T03:15:44.055Z] MLT stderr 0000000000508400: jitInfo=0000FFFF5C095C38
[2021-06-05T03:15:44.055Z] MLT stderr 0000000000508400: method=0000000000258BB8 (jdk/internal/reflect/ClassDefiner.defineClass(Ljava/lang/String;[BIILjava/lang/ClassLoader;)Ljava/lang/Class;) (JIT)
[2021-06-05T03:15:44.055Z] MLT stderr 0000000000508400: stack=000000000050E8F0-0000000000514150
[2021-06-05T03:15:44.055Z] MLT stderr 03:15:42.088 0x508400    j9mm.479    *   ** ASSERTION FAILED ** at ../../../../../../openj9/runtime/gc_glue_java/ConcurrentMarkingDelegate.cpp:61: ((MM_StackSlotValidator(MM_StackSlotValidator::NOT_ON_HEAP, object, stackLocation, walkState).validate(data->env)))
[2021-06-05T03:15:44.055Z] MLT stderr JVMDUMP039I Processing dump event "traceassert", detail "" at 2021/06/04 23:15:42 - please wait.
[2021-06-05T03:15:44.055Z] MLT stderr JVMDUMP032I JVM requested System dump using '/home/jenkins/workspace/Test_openjdk11_j9_extended.system_aarch64_linux/openjdk-tests/TKG/output_16228574759728/MathLoadTest_all_5m_1/20210604-231504-MathLoadTest/results/core.20210604.231542.4788.0001.dmp' in response to an event
[2021-06-05T03:15:49.248Z] MLT stderr JVMPORT030W /proc/sys/kernel/core_pattern setting "|/usr/share/apport/apport %p %s %c %d %P %E" specifies that the core dump is to be piped to an external program.  Attempting to rename either core or core.4844.
[2021-06-05T03:15:49.248Z] MLT stderr 
[2021-06-05T03:15:49.248Z] MLT stderr JVMDUMP010I System dump written to /home/jenkins/workspace/Test_openjdk11_j9_extended.system_aarch64_linux/openjdk-tests/TKG/output_16228574759728/MathLoadTest_all_5m_1/20210604-231504-MathLoadTest/results/core.20210604.231542.4788.0001.dmp
[2021-06-05T03:15:49.248Z] MLT stderr 0000000000508400: Object neither in heap nor stack-allocated in thread load-6
[2021-06-05T03:15:49.248Z] MLT stderr 0000000000508400: O-Slot=0000000000513280
[2021-06-05T03:15:49.248Z] MLT stderr 0000000000508400: O-Slot value=0000000000000004
[2021-06-05T03:15:49.248Z] MLT stderr 0000000000508400: PC=0000FFFF9637C405
[2021-06-05T03:15:49.248Z] MLT stderr 0000000000508400: framesWalked=3
[2021-06-05T03:15:49.248Z] MLT stderr 0000000000508400: arg0EA=0000000000513330
[2021-06-05T03:15:49.248Z] MLT stderr 0000000000508400: walkSP=00000000005130C8
[2021-06-05T03:15:49.248Z] MLT stderr 0000000000508400: literals=0000000000000010
[2021-06-05T03:15:49.248Z] MLT stderr 0000000000508400: jitInfo=0000FFFF5C095C38
[2021-06-05T03:15:49.248Z] MLT stderr 0000000000508400: method=0000000000258BB8 (jdk/internal/reflect/ClassDefiner.defineClass(Ljava/lang/String;[BIILjava/lang/ClassLoader;)Ljava/lang/Class;) (JIT)
[2021-06-05T03:15:49.248Z] MLT stderr 0000000000508400: stack=000000000050E8F0-0000000000514150
[2021-06-05T03:15:49.248Z] MLT stderr 0000000000508400: Object neither in heap nor stack-allocated in thread load-6
[2021-06-05T03:15:49.248Z] MLT stderr 0000000000508400: O-Slot=0000FFFF5D1F9380
[2021-06-05T03:15:49.248Z] MLT stderr 0000000000508400: O-Slot value=0000000000000004
[2021-06-05T03:15:49.248Z] MLT stderr 0000000000508400: PC=0000FFFF9637C405
[2021-06-05T03:15:49.248Z] MLT stderr 0000000000508400: framesWalked=3
[2021-06-05T03:15:49.248Z] MLT stderr 0000000000508400: arg0EA=0000000000513330
[2021-06-05T03:15:49.248Z] MLT stderr 0000000000508400: walkSP=00000000005130C8
[2021-06-05T03:15:49.248Z] MLT stderr 0000000000508400: literals=0000000000000010
[2021-06-05T03:15:49.248Z] MLT stderr 0000000000508400: jitInfo=0000FFFF5C095C38
[2021-06-05T03:15:49.248Z] MLT stderr 0000000000508400: method=0000000000258BB8 (jdk/internal/reflect/ClassDefiner.defineClass(Ljava/lang/String;[BIILjava/lang/ClassLoader;)Ljava/lang/Class;) (JIT)
[2021-06-05T03:15:49.248Z] MLT stderr 0000000000508400: stack=000000000050E8F0-0000000000514150
[2021-06-05T03:15:49.248Z] MLT stderr JVMDUMP032I JVM requested Java dump using '/home/jenkins/workspace/Test_openjdk11_j9_extended.system_aarch64_linux/openjdk-tests/TKG/output_16228574759728/MathLoadTest_all_5m_1/20210604-231504-MathLoadTest/results/javacore.20210604.231542.4788.0002.txt' in response to an event
[2021-06-05T03:15:49.248Z] STF 23:15:46.420 - Found dump at: /home/jenkins/workspace/Test_openjdk11_j9_extended.system_aarch64_linux/openjdk-tests/TKG/output_16228574759728/MathLoadTest_all_5m_1/20210604-231504-MathLoadTest/results/javacore.20210604.231542.4788.0002.txt
[2021-06-05T03:15:49.248Z] STF 23:15:46.421 - Found dump at: /home/jenkins/workspace/Test_openjdk11_j9_extended.system_aarch64_linux/openjdk-tests/TKG/output_16228574759728/MathLoadTest_all_5m_1/20210604-231504-MathLoadTest/results/core.20210604.231542.4788.0001.dmp
[2021-06-05T03:15:49.248Z] MLT 23:15:46.554 - suite.getInventory().getInventoryFileRef(): openjdk.test.load/config/inventories/math/math.xml
[2021-06-05T03:15:49.248Z] MLT 23:15:46.555 - suite.isCreateDump() : false
[2021-06-05T03:15:49.248Z] MLT 23:15:46.555 - Test failed. Details recorded in execution log.
[2021-06-05T03:15:49.248Z] MLT stderr javacore file generated - /home/jenkins/workspace/Test_openjdk11_j9_extended.system_aarch64_linux/openjdk-tests/TKG/output_16228574759728/MathLoadTest_all_5m_1/20210604-231504-MathLoadTest/results/javacore.20210604.231542.4788.0002.txt
[2021-06-05T03:15:49.248Z] MLT stderr core file generated - /home/jenkins/workspace/Test_openjdk11_j9_extended.system_aarch64_linux/openjdk-tests/TKG/output_16228574759728/MathLoadTest_all_5m_1/20210604-231504-MathLoadTest/results/core.20210604.231542.4788.0001.dmp
[2021-06-05T03:15:51.950Z] MLT 23:15:51.499 - Completed 13.7%. Number of tests started=1627 (+564) (with 8 failure(s))
[2021-06-05T03:15:51.950Z] MLT stderr JVMDUMP010I Java dump written to /home/jenkins/workspace/Test_openjdk11_j9_extended.system_aarch64_linux/openjdk-tests/TKG/output_16228574759728/MathLoadTest_all_5m_1/20210604-231504-MathLoadTest/results/javacore.20210604.231542.4788.0002.txt
[2021-06-05T03:15:51.950Z] MLT stderr 0000000000508400: Object neither in heap nor stack-allocated in thread load-6
[2021-06-05T03:15:51.950Z] MLT stderr 0000000000508400: O-Slot=0000000000513280
[2021-06-05T03:15:51.950Z] MLT stderr 0000000000508400: O-Slot value=0000000000000004
[2021-06-05T03:15:51.950Z] MLT stderr 0000000000508400: PC=0000FFFF9637C405
[2021-06-05T03:15:51.950Z] MLT stderr 0000000000508400: framesWalked=3
[2021-06-05T03:15:51.950Z] MLT stderr 0000000000508400: arg0EA=0000000000513330
[2021-06-05T03:15:51.950Z] MLT stderr 0000000000508400: walkSP=00000000005130C8
[2021-06-05T03:15:51.950Z] MLT stderr 0000000000508400: literals=0000000000000010
[2021-06-05T03:15:51.950Z] MLT stderr 0000000000508400: jitInfo=0000FFFF5C095C38
[2021-06-05T03:15:51.950Z] MLT stderr 0000000000508400: method=0000000000258BB8 (jdk/internal/reflect/ClassDefiner.defineClass(Ljava/lang/String;[BIILjava/lang/ClassLoader;)Ljava/lang/Class;) (JIT)
[2021-06-05T03:15:51.950Z] MLT stderr 0000000000508400: stack=000000000050E8F0-0000000000514150
[2021-06-05T03:15:51.950Z] MLT stderr 0000000000508400: Object neither in heap nor stack-allocated in thread load-6
[2021-06-05T03:15:51.950Z] MLT stderr 0000000000508400: O-Slot=0000FFFF5D1F9380
[2021-06-05T03:15:51.950Z] MLT stderr 0000000000508400: O-Slot value=0000000000000004
[2021-06-05T03:15:51.950Z] MLT stderr 0000000000508400: PC=0000FFFF9637C405
[2021-06-05T03:15:51.950Z] MLT stderr 0000000000508400: framesWalked=3
[2021-06-05T03:15:51.950Z] MLT stderr 0000000000508400: arg0EA=0000000000513330
[2021-06-05T03:15:51.950Z] MLT stderr 0000000000508400: walkSP=00000000005130C8
[2021-06-05T03:15:51.950Z] MLT stderr 0000000000508400: literals=0000000000000010
[2021-06-05T03:15:51.950Z] MLT stderr 0000000000508400: jitInfo=0000FFFF5C095C38
[2021-06-05T03:15:51.950Z] MLT stderr 0000000000508400: method=0000000000258BB8 (jdk/internal/reflect/ClassDefiner.defineClass(Ljava/lang/String;[BIILjava/lang/ClassLoader;)Ljava/lang/Class;) (JIT)
[2021-06-05T03:15:51.950Z] MLT stderr 0000000000508400: stack=000000000050E8F0-0000000000514150
[2021-06-05T03:15:51.950Z] MLT stderr JVMDUMP032I JVM requested Snap dump using '/home/jenkins/workspace/Test_openjdk11_j9_extended.system_aarch64_linux/openjdk-tests/TKG/output_16228574759728/MathLoadTest_all_5m_1/20210604-231504-MathLoadTest/results/Snap.20210604.231542.4788.0003.trc' in response to an event
[2021-06-05T03:15:51.951Z] STF 23:15:51.537 - Found dump at: /home/jenkins/workspace/Test_openjdk11_j9_extended.system_aarch64_linux/openjdk-tests/TKG/output_16228574759728/MathLoadTest_all_5m_1/20210604-231504-MathLoadTest/results/Snap.20210604.231542.4788.0003.trc
[2021-06-05T03:15:54.483Z] MLT stderr Snap file generated - /home/jenkins/workspace/Test_openjdk11_j9_extended.system_aarch64_linux/openjdk-tests/TKG/output_16228574759728/MathLoadTest_all_5m_1/20210604-231504-MathLoadTest/results/Snap.20210604.231542.4788.0003.trc
[2021-06-05T03:15:54.483Z] MLT stderr JVMDUMP010I Snap dump written to /home/jenkins/workspace/Test_openjdk11_j9_extended.system_aarch64_linux/openjdk-tests/TKG/output_16228574759728/MathLoadTest_all_5m_1/20210604-231504-MathLoadTest/results/Snap.20210604.231542.4788.0003.trc
[2021-06-05T03:15:54.483Z] MLT stderr JVMDUMP013I Processed dump event "traceassert", detail "".
[2021-06-05T03:15:54.483Z] STF 23:15:51.959 - **FAILED** Process MLT ended with exit code (255) and not the expected exit code/s (0)
[2021-06-05T03:15:54.483Z] STF 23:15:51.959 - Monitoring Report Summary:
[2021-06-05T03:15:54.483Z] STF 23:15:51.959 -   o Process MLT has crashed unexpectedly
[2021-06-05T03:15:54.483Z] STF 23:15:51.961 - Killing processes: MLT
[2021-06-05T03:15:54.483Z] STF 23:15:51.961 -   o Process MLT pid 4788 is not running
[2021-06-05T03:15:54.483Z] **FAILED** at step 1 (Run math load test). Expected return value=0 Actual=1 at /home/jenkins/workspace/Test_openjdk11_j9_extended.system_aarch64_linux/openjdk-tests/TKG/../TKG/output_16228574759728/MathLoadTest_all_5m_1/20210604-231504-MathLoadTest/execute.pl line 92.
[2021-06-05T03:15:54.483Z] STF 23:15:52.072 - **FAILED** execute script failed. Expected return value=0 Actual=1
[2021-06-05T03:15:54.483Z] STF 23:15:52.072 - 
[2021-06-05T03:15:54.483Z] STF 23:15:52.072 - ====================   T E A R D O W N   ====================
[2021-06-05T03:15:54.483Z] STF 23:15:52.072 - Running teardown: perl /home/jenkins/workspace/Test_openjdk11_j9_extended.system_aarch64_linux/openjdk-tests/TKG/../TKG/output_16228574759728/MathLoadTest_all_5m_1/20210604-231504-MathLoadTest/tearDown.pl
[2021-06-05T03:15:54.483Z] STF 23:15:52.157 - TEARDOWN stage completed
[2021-06-05T03:15:54.483Z] STF 23:15:52.161 - 
[2021-06-05T03:15:54.483Z] STF 23:15:52.161 - =====================   R E S U L T S   =====================
[2021-06-05T03:15:54.483Z] STF 23:15:52.161 - Stage results:
[2021-06-05T03:15:54.483Z] STF 23:15:52.161 -   setUp:     pass
[2021-06-05T03:15:54.483Z] STF 23:15:52.161 -   execute:  *fail*
[2021-06-05T03:15:54.483Z] STF 23:15:52.162 -   teardown:  pass
[2021-06-05T03:15:54.483Z] STF 23:15:52.162 - 
[2021-06-05T03:15:54.483Z] STF 23:15:52.162 - Overall result: **FAILED**
[2021-06-05T03:15:54.483Z] 
[2021-06-05T03:15:54.483Z] MathLoadTest_all_5m_1_FAILED