dacapo-h2_0
[2025-10-23T22:34:05.388Z] Running test dacapo-h2_0 ...
[2025-10-23T22:34:05.388Z] ===============================================
[2025-10-23T22:34:05.388Z] dacapo-h2_0 Start Time: Thu Oct 23 22:34:04 2025 Epoch Time (ms): 1761258844051
[2025-10-23T22:34:05.388Z] variation: NoOptions
[2025-10-23T22:34:05.389Z] JVM_OPTIONS:
[2025-10-23T22:34:05.389Z] { \
[2025-10-23T22:34:05.389Z] echo ""; echo "TEST SETUP:"; \
[2025-10-23T22:34:05.389Z] echo "Nothing to be done for setup."; \
[2025-10-23T22:34:05.389Z] mkdir -p "/ssd/jenkins/workspace/Test_openjdk11_hs_sanity.perf_arm_linux_rerun/aqa-tests/TKG/../TKG/output_17612587255860/dacapo-h2_0"; \
[2025-10-23T22:34:05.389Z] cd "/ssd/jenkins/workspace/Test_openjdk11_hs_sanity.perf_arm_linux_rerun/aqa-tests/TKG/../TKG/output_17612587255860/dacapo-h2_0"; \
[2025-10-23T22:34:05.389Z] echo ""; echo "TESTING:"; \
[2025-10-23T22:34:05.389Z] "/ssd/jenkins/workspace/Test_openjdk11_hs_sanity.perf_arm_linux_rerun/jdkbinary/j2sdk-image/bin/java" -jar "/ssd/jenkins/workspace/Test_openjdk11_hs_sanity.perf_arm_linux_rerun/aqa-tests/TKG/../../jvmtest/perf/dacapo/dacapo.jar" h2; \
[2025-10-23T22:34:05.389Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "dacapo-h2_0""_PASSED"; echo "-----------------------------------"; cd /ssd/jenkins/workspace/Test_openjdk11_hs_sanity.perf_arm_linux_rerun/aqa-tests/TKG/..; rm -f -r "/ssd/jenkins/workspace/Test_openjdk11_hs_sanity.perf_arm_linux_rerun/aqa-tests/TKG/../TKG/output_17612587255860/dacapo-h2_0"; else echo "-----------------------------------"; echo "dacapo-h2_0""_FAILED"; echo "-----------------------------------"; fi; \
[2025-10-23T22:34:05.389Z] echo ""; echo "TEST TEARDOWN:"; \
[2025-10-23T22:34:05.389Z] echo "Nothing to be done for teardown."; \
[2025-10-23T22:34:05.389Z] } 2>&1 | tee -a "/ssd/jenkins/workspace/Test_openjdk11_hs_sanity.perf_arm_linux_rerun/aqa-tests/TKG/../TKG/output_17612587255860/TestTargetResult";
[2025-10-23T22:34:05.389Z]
[2025-10-23T22:34:05.389Z] TEST SETUP:
[2025-10-23T22:34:05.389Z] Nothing to be done for setup.
[2025-10-23T22:34:05.389Z]
[2025-10-23T22:34:05.389Z] TESTING:
[2025-10-23T22:34:05.389Z] Using scaled threading model. 8 processors detected, 8 threads used to drive the workload, in a possible range of [1,1024]
[2025-10-23T22:34:06.308Z] Version: h2 2.2.220 derby 10.15.2.0 (use -p to print nominal benchmark stats)
[2025-10-23T22:34:06.308Z] Preparing the H2 database...
[2025-10-23T22:34:06.308Z] TPCC configuration: scale: 16, terminals: 8, transactions: 100000
[2025-10-23T22:37:02.451Z] ===== DaCapo 23.11-MR2-chopin-minimal h2 starting =====
[2025-10-23T22:37:02.451Z] Starting 100000 requests...
[2025-10-23T22:37:02.451Z]
[2025-10-23T22:37:10.746Z] 5%
[2025-10-23T22:37:16.229Z] 10%
[2025-10-23T22:37:24.423Z] 15%
[2025-10-23T22:37:29.929Z] 20%
[2025-10-23T22:37:38.092Z] 25%
[2025-10-23T22:37:43.755Z] 30%
[2025-10-23T22:37:51.909Z] 35%
[2025-10-23T22:37:57.362Z] 40%
[2025-10-23T22:38:05.543Z] 45%
[2025-10-23T22:38:13.835Z] 50%
[2025-10-23T22:38:22.052Z] 55%
[2025-10-23T22:38:30.236Z] 60%
[2025-10-23T22:38:42.194Z] 65%
[2025-10-23T22:38:59.365Z] 70%
[2025-10-23T22:39:49.411Z] 75%
[2025-10-23T22:41:44.889Z] 80%
[2025-10-23T22:44:10.892Z] 85%
[2025-10-23T22:47:07.046Z] 90%
[2025-10-23T22:51:49.787Z] 95%
[2025-10-23T22:51:49.787Z] 100%
[2025-10-23T22:51:49.787Z] Completed requests
[2025-10-23T22:51:49.787Z] Stock level ............. 3973 ( 4.0%)
[2025-10-23T22:51:49.787Z] Order status by name .... 2361 ( 2.4%)
[2025-10-23T22:51:49.787Z] Order status by ID ...... 1609 ( 1.6%)
[2025-10-23T22:51:49.787Z] Payment by name ......... 25847 (25.8%)
[2025-10-23T22:51:49.787Z] Payment by ID ........... 17220 (17.2%)
[2025-10-23T22:51:49.787Z] Delivery schedule ....... 4029 ( 4.0%)
[2025-10-23T22:51:49.787Z] New order ............... 44509 (44.5%)
[2025-10-23T22:51:49.787Z] New order rollback ...... 452 ( 0.5%)
[2025-10-23T22:51:49.787Z] ===== DaCapo 23.11-MR2-chopin-minimal h2 PASSED in 895033 msec =====
[2025-10-23T22:51:49.787Z] ===== DaCapo processed 100000 requests in 894956 msec, 111 requests per second =====
[2025-10-23T22:51:49.787Z] ===== DaCapo tail latency, simple: 50% 3801 usec, 90% 26886 usec, 99% 2932736 usec, 99.9% 3037200 usec, 99.99% 3154247 usec, max 3200057 usec, measured over 100000 events =====
[2025-10-23T22:51:49.787Z] ===== DaCapo tail latency, metered 100ms smoothing: 50% 6717 usec, 90% 32243 usec, 99% 2932736 usec, 99.9% 3037200 usec, 99.99% 3154247 usec, max 3200057 usec, measured over 100000 events =====
[2025-10-23T22:51:49.787Z] ===== DaCapo tail latency, metered full smoothing: 50% 4358 usec, 90% 46596 usec, 99% 3310305 usec, 99.9% 3595763 usec, 99.99% 3643981 usec, max 3682287 usec, measured over 100000 events =====
[2025-10-23T22:54:46.698Z] Resetting database to initial state.....java.lang.reflect.InvocationTargetException
[2025-10-23T22:54:46.698Z] java.lang.reflect.InvocationTargetException
[2025-10-23T22:54:46.698Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[2025-10-23T22:54:46.698Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[2025-10-23T22:54:46.698Z] at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[2025-10-23T22:54:46.698Z] at java.base/java.lang.reflect.Method.invoke(Method.java:566)
[2025-10-23T22:54:46.698Z] at org.dacapo.harness.H2.postIteration(H2.java:115)
[2025-10-23T22:54:46.698Z] at org.dacapo.harness.Benchmark.run(Benchmark.java:274)
[2025-10-23T22:54:46.698Z] at org.dacapo.harness.TestHarness.runBenchmark(TestHarness.java:225)
[2025-10-23T22:54:46.698Z] at org.dacapo.harness.TestHarness.main(TestHarness.java:170)
[2025-10-23T22:54:46.698Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[2025-10-23T22:54:46.698Z] at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[2025-10-23T22:54:46.698Z] at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[2025-10-23T22:54:46.698Z] at java.base/java.lang.reflect.Method.invoke(Method.java:566)
[2025-10-23T22:54:46.698Z] at Harness.main(Harness.java:39)
[2025-10-23T22:54:46.698Z] Caused by: org.h2.jdbc.JdbcSQLNonTransientConnectionException: Out of memory.; SQL statement:
[2025-10-23T22:54:46.698Z] DELETE FROM ORDERLINE WHERE OL_INITIAL = FALSE [90108-220]
[2025-10-23T22:54:46.698Z] at org.h2.message.DbException.getJdbcSQLException(DbException.java:690)
[2025-10-23T22:54:46.698Z] at org.h2.message.DbException.getJdbcSQLException(DbException.java:489)
[2025-10-23T22:54:46.698Z] at org.h2.message.DbException.get(DbException.java:212)
[2025-10-23T22:54:46.698Z] at org.h2.message.DbException.convert(DbException.java:401)
[2025-10-23T22:54:46.698Z] at org.h2.command.Command.executeUpdate(Command.java:262)
[2025-10-23T22:54:46.698Z] at org.h2.jdbc.JdbcPreparedStatement.execute(JdbcPreparedStatement.java:254)
[2025-10-23T22:54:46.698Z] at org.dacapo.h2.TPCC.resetToInitialData(Unknown Source)
[2025-10-23T22:54:46.698Z] at org.dacapo.h2.TPCC.postIteration(Unknown Source)
[2025-10-23T22:54:46.698Z] ... 13 more
[2025-10-23T22:54:46.698Z] Caused by: java.lang.OutOfMemoryError: Java heap space
[2025-10-23T22:54:46.698Z] at org.h2.result.LocalResult.addRowForTable(LocalResult.java:393)
[2025-10-23T22:54:46.698Z] at org.h2.command.dml.Delete.update(Delete.java:77)
[2025-10-23T22:54:46.698Z] at org.h2.command.dml.DataChangeStatement.update(DataChangeStatement.java:74)
[2025-10-23T22:54:46.698Z] at org.h2.command.CommandContainer.update(CommandContainer.java:169)
[2025-10-23T22:54:46.698Z] at org.h2.command.Command.executeUpdate(Command.java:252)
[2025-10-23T22:54:46.698Z] ... 16 more
[2025-10-23T22:54:46.698Z] -----------------------------------
[2025-10-23T22:54:46.698Z] dacapo-h2_0_FAILED
[2025-10-23T22:54:46.698Z] -----------------------------------
[2025-10-23T22:54:46.698Z]
[2025-10-23T22:54:46.698Z] TEST TEARDOWN:
[2025-10-23T22:54:46.698Z] Nothing to be done for teardown.
[2025-10-23T22:54:46.698Z] dacapo-h2_0 Finish Time: Thu Oct 23 22:54:34 2025 Epoch Time (ms): 1761260074408