openj9 icon indicating copy to clipboard operation
openj9 copied to clipboard

MathLoadTest_autosimd_special_5m_18 net.adoptopenjdk.test.autosimd.AutoSIMDTestFloat testMatrixMult ArrayIndexOutOfBoundsException at java.base/jdk.internal.util.ArraysSupport.mismatch(ArraysSupport.java:742)

Open JasonFengJ9 opened this issue 6 months ago • 3 comments

Failure link

From internal Test_openjdk24_j9_special.system_aarch64_linux_testList_2 (rhel8-aarch64-6)

openjdk version "24.0.1-beta" 2025-04-15
IBM Semeru Runtime Open Edition 24.0.1+9-202506040014 (build 24.0.1-beta+9-202506040014)
Eclipse OpenJ9 VM 24.0.1+9-202506040014 (build master-ee99618777, JRE 24 Linux aarch64-64-Bit Compressed References 20250604_74 (JIT enabled, AOT enabled)
OpenJ9   - ee99618777
OMR      - 556e0fe4b
JCL      - 801da8362 based on jdk-24.0.1+9)

Rerun in Grinder - Change TARGET to run only the failed test targets

Optional info

Failure output (captured from console output)

[2025-06-04T06:53:17.742Z] variation: Mode557
[2025-06-04T06:53:17.742Z] JVM_OPTIONS:  -XX:+UseCompressedOops -Xgcpolicy:balanced -Xdebug -Xrunjdwp:transport=dt_socket,address=8888,server=y,onthrow=no.pkg.foo,launch=echo -Xjit:count=0 

[2025-06-04T06:54:06.798Z] MLT 02:54:06.042 - Thread completed. Suite=0 thread=3
[2025-06-04T06:54:06.798Z] MLT 02:54:06.052 - Test failed
[2025-06-04T06:54:06.798Z] MLT   Failure num.  = 1
[2025-06-04T06:54:06.798Z] MLT   Test number   = 28
[2025-06-04T06:54:06.798Z] MLT   Test details  = 'ArbitraryJava[net.adoptopenjdk.test.autosimd.AutoSIMDTestFloat testMatrixMult]'
[2025-06-04T06:54:06.798Z] MLT   Suite number  = 0
[2025-06-04T06:54:06.798Z] MLT   Thread number = 4
[2025-06-04T06:54:06.798Z] MLT >>> Captured test output >>>
[2025-06-04T06:54:06.798Z] MLT Test failed:
[2025-06-04T06:54:06.798Z] MLT java.lang.ArrayIndexOutOfBoundsException
[2025-06-04T06:54:06.798Z] MLT 	at java.base/jdk.internal.util.ArraysSupport.mismatch(ArraysSupport.java:742)
[2025-06-04T06:54:06.798Z] MLT 	at java.base/jdk.internal.util.ArraysSupport.mismatch(ArraysSupport.java:723)
[2025-06-04T06:54:06.798Z] MLT 	at java.base/java.util.Arrays.equals(Arrays.java:2926)
[2025-06-04T06:54:06.798Z] MLT 	at net.adoptopenjdk.test.autosimd.AutoSIMDTestFloat.testMatrixMult(AutoSIMDTestFloat.java:442)
[2025-06-04T06:54:06.798Z] MLT 	at java.base/jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:104)
[2025-06-04T06:54:06.798Z] MLT 	at java.base/java.lang.reflect.Method.invoke(Method.java:571)
[2025-06-04T06:54:06.798Z] MLT 	at net.adoptopenjdk.loadTest.adaptors.ArbitraryJavaAdaptor.executeTest(ArbitraryJavaAdaptor.java:102)
[2025-06-04T06:54:06.798Z] MLT 	at net.adoptopenjdk.loadTest.LoadTestRunner$2.run(LoadTestRunner.java:182)
[2025-06-04T06:54:06.798Z] MLT 	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1095)
[2025-06-04T06:54:06.798Z] MLT 	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:619)
[2025-06-04T06:54:06.798Z] MLT 	at java.base/java.lang.Thread.run(Thread.java:1458)
[2025-06-04T06:54:06.798Z] MLT <<<
[2025-06-04T06:54:06.798Z] MLT 
[2025-06-04T06:54:06.798Z] MLT 02:54:06.052 - Thread completed. Suite=0 thread=4
[2025-06-04T06:54:06.798Z] MLT 02:54:06.097 - Load test completed
[2025-06-04T06:54:06.798Z] MLT 02:54:06.097 -   Ran     : 292
[2025-06-04T06:54:06.798Z] MLT 02:54:06.097 -   Passed  : 282
[2025-06-04T06:54:06.798Z] MLT 02:54:06.097 -   Failed  : 10
[2025-06-04T06:54:06.798Z] MLT 02:54:06.098 -   Result  : FAILED

[2025-06-04T06:54:07.406Z] MathLoadTest_autosimd_special_5m_18_FAILED

50x internal Grinder - 11/50 failed

openjdk24_j9_special.system_s390x_linux

JasonFengJ9 avatar Jun 04 '25 13:06 JasonFengJ9

openjdk24_j9_special.system_s390x_linux

[2025-06-05T03:27:54.877Z] variation: Mode557
[2025-06-05T03:27:54.877Z] JVM_OPTIONS:  -XX:+UseCompressedOops -Xgcpolicy:balanced -Xdebug -Xrunjdwp:transport=dt_socket,address=8888,server=y,onthrow=no.pkg.foo,launch=echo -Xjit:count=0 

[2025-06-05T03:28:42.133Z] MLT 20:26:57.850 - Test failed
[2025-06-05T03:28:42.133Z] MLT   Failure num.  = 1
[2025-06-05T03:28:42.133Z] MLT   Test number   = 38
[2025-06-05T03:28:42.134Z] MLT   Test details  = 'ArbitraryJava[net.adoptopenjdk.test.autosimd.AutoSIMDTestLong testMatrixMult]'
[2025-06-05T03:28:42.134Z] MLT   Suite number  = 0
[2025-06-05T03:28:42.134Z] MLT   Thread number = 1
[2025-06-05T03:28:42.134Z] MLT >>> Captured test output >>>
[2025-06-05T03:28:42.134Z] MLT Test failed:
[2025-06-05T03:28:42.134Z] MLT java.lang.RuntimeException: test failure
[2025-06-05T03:28:42.134Z] MLT 	at net.adoptopenjdk.test.autosimd.AutoSIMDTestLong.checkThat(AutoSIMDTestLong.java:583)
[2025-06-05T03:28:42.134Z] MLT 	at net.adoptopenjdk.test.autosimd.AutoSIMDTestLong.checkThat(AutoSIMDTestLong.java:578)
[2025-06-05T03:28:42.134Z] MLT 	at net.adoptopenjdk.test.autosimd.AutoSIMDTestLong.testMatrixMult(AutoSIMDTestLong.java:442)
[2025-06-05T03:28:42.134Z] MLT 	at java.base/jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:104)
[2025-06-05T03:28:42.134Z] MLT 	at java.base/java.lang.reflect.Method.invoke(Method.java:571)
[2025-06-05T03:28:42.134Z] MLT 	at net.adoptopenjdk.loadTest.adaptors.ArbitraryJavaAdaptor.executeTest(ArbitraryJavaAdaptor.java:102)
[2025-06-05T03:28:42.134Z] MLT 	at net.adoptopenjdk.loadTest.LoadTestRunner$2.run(LoadTestRunner.java:182)
[2025-06-05T03:28:42.134Z] MLT 	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1095)
[2025-06-05T03:28:42.134Z] MLT 	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:619)
[2025-06-05T03:28:42.134Z] MLT 	at java.base/java.lang.Thread.run(Thread.java:1458)
[2025-06-05T03:28:42.134Z] MLT <<<

[2025-06-05T03:28:42.134Z] MathLoadTest_autosimd_special_5m_18_FAILED

JasonFengJ9 avatar Jun 05 '25 12:06 JasonFengJ9

openjdk24_j9_special.system_x86-64_mac

[2025-06-10T04:04:07.878Z] variation: Mode557
[2025-06-10T04:04:07.878Z] JVM_OPTIONS:  -XX:+UseCompressedOops -Xgcpolicy:balanced -Xdebug -Xrunjdwp:transport=dt_socket,address=8888,server=y,onthrow=no.pkg.foo,launch=echo -Xjit:count=0 

[2025-06-10T04:04:37.494Z] MLT 00:04:36.640 - Thread completed. Suite=0 thread=2
[2025-06-10T04:04:37.494Z] MLT 00:04:36.658 - Test failed
[2025-06-10T04:04:37.494Z] MLT   Failure num.  = 1
[2025-06-10T04:04:37.494Z] MLT   Test number   = 28
[2025-06-10T04:04:37.494Z] MLT   Test details  = 'ArbitraryJava[net.adoptopenjdk.test.autosimd.AutoSIMDTestFloat testMatrixMult]'
[2025-06-10T04:04:37.494Z] MLT   Suite number  = 0
[2025-06-10T04:04:37.494Z] MLT   Thread number = 6
[2025-06-10T04:04:37.494Z] MLT >>> Captured test output >>>
[2025-06-10T04:04:37.494Z] MLT Test failed:
[2025-06-10T04:04:37.494Z] MLT java.lang.ArrayIndexOutOfBoundsException
[2025-06-10T04:04:37.494Z] MLT 	at java.base/jdk.internal.util.ArraysSupport.mismatch(ArraysSupport.java:742)
[2025-06-10T04:04:37.494Z] MLT 	at java.base/jdk.internal.util.ArraysSupport.mismatch(ArraysSupport.java:723)
[2025-06-10T04:04:37.494Z] MLT 	at java.base/java.util.Arrays.equals(Arrays.java:2926)
[2025-06-10T04:04:37.495Z] MLT 	at net.adoptopenjdk.test.autosimd.AutoSIMDTestFloat.testMatrixMult(AutoSIMDTestFloat.java:442)
[2025-06-10T04:04:37.495Z] MLT 	at java.base/jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:104)
[2025-06-10T04:04:37.495Z] MLT 	at java.base/java.lang.reflect.Method.invoke(Method.java:571)
[2025-06-10T04:04:37.495Z] MLT 	at net.adoptopenjdk.loadTest.adaptors.ArbitraryJavaAdaptor.executeTest(ArbitraryJavaAdaptor.java:102)
[2025-06-10T04:04:37.495Z] MLT 	at net.adoptopenjdk.loadTest.LoadTestRunner$2.run(LoadTestRunner.java:182)
[2025-06-10T04:04:37.495Z] MLT 	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1095)
[2025-06-10T04:04:37.495Z] MLT 	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:619)
[2025-06-10T04:04:37.495Z] MLT 	at java.base/java.lang.Thread.run(Thread.java:1458)
[2025-06-10T04:04:37.495Z] MLT <<<

JasonFengJ9 avatar Jun 10 '25 14:06 JasonFengJ9

Adding the comp:jit label since no failures were observed in the 100x grinder run with -Xint: https://hyc-runtimes-jenkins.swg-devops.com/job/Grinder/51368.

The test failure does not appear to be related to JDK 24 virtual threads (JEP 491). Moving this issue to the 0.53 release.

fyi @hzongaro

babsingh avatar Jun 10 '25 15:06 babsingh

@luke-li-2003, may I ask you to look at this? It looks like this failure first occurred after #21097 was merged, and the array access that is throwing the ArrayIndexOutOfBoundsException is using the result of a call to ArraysSupport.vectorizedMismtach.

hzongaro avatar Jul 04 '25 19:07 hzongaro

This seems like a regression from jdk23, which should be in the jdk24 milestone plan. Is https://github.com/eclipse-openj9/openj9/pull/22209 related?

pshipton avatar Aug 01 '25 17:08 pshipton

That is possible, I have not been able to reproduce it yet.

luke-li-2003 avatar Aug 01 '25 17:08 luke-li-2003

Try 50x (10x x 5) on alinux,zlinux,xmac on the last nightly build, which doesn't contain https://github.com/eclipse-openj9/openj9/pull/22209 yet. I set exit on failure. https://hyc-runtimes-jenkins.swg-devops.com/view/Test_grinder/job/Grinder/53542/

pshipton avatar Aug 01 '25 18:08 pshipton

The grinder passed so this seems to already be resolved.

pshipton avatar Aug 01 '25 20:08 pshipton