[2026-01-31T14:56:37.073Z] Running test renaissance-als_0 ... [2026-01-31T14:56:37.073Z] =============================================== [2026-01-31T14:56:37.073Z] renaissance-als_0 Start Time: Sat Jan 31 14:56:36 2026 Epoch Time (ms): 1769871396272 [2026-01-31T14:56:37.073Z] variation: NoOptions [2026-01-31T14:56:37.073Z] JVM_OPTIONS: [2026-01-31T14:56:37.073Z] { \ [2026-01-31T14:56:37.073Z] echo ""; echo "TEST SETUP:"; \ [2026-01-31T14:56:37.073Z] echo "Nothing to be done for setup."; \ [2026-01-31T14:56:37.073Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17698712801857/renaissance-als_0"; \ [2026-01-31T14:56:37.073Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17698712801857/renaissance-als_0"; \ [2026-01-31T14:56:37.073Z] echo ""; echo "TESTING:"; \ [2026-01-31T14:56:37.073Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/jdkbinary/j2sdk-image/bin/java" --add-opens java.base/java.lang=ALL-UNNAMED --add-opens java.base/java.util=ALL-UNNAMED --add-opens java.base/java.util.concurrent=ALL-UNNAMED --add-opens java.base/java.nio=ALL-UNNAMED --add-opens java.base/sun.nio.ch=ALL-UNNAMED --add-opens java.base/java.lang.invoke=ALL-UNNAMED -jar "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17698712801857/renaissance-als_0"/als.json" als; \ [2026-01-31T14:56:37.073Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17698712801857/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-31T14:56:37.073Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-31T14:56:37.073Z] echo "Nothing to be done for teardown."; \ [2026-01-31T14:56:37.073Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17698712801857/TestTargetResult"; [2026-01-31T14:56:37.073Z] [2026-01-31T14:56:37.073Z] TEST SETUP: [2026-01-31T14:56:37.073Z] Nothing to be done for setup. [2026-01-31T14:56:37.073Z] [2026-01-31T14:56:37.073Z] TESTING: [2026-01-31T14:56:38.688Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-31T14:56:38.688Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/output_17698712801857/renaissance-als_0/launcher-145636-15753231638142430156/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-31T14:56:38.688Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-31T14:56:38.688Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-31T14:57:08.999Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 16) threads. [2026-01-31T14:57:12.468Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-31T14:57:13.252Z] GC before operation: completed in 62.519 ms, heap usage 500.923 MB -> 34.643 MB. [2026-01-31T14:57:39.211Z] ====== als (apache-spark) [default], iteration 0 completed (23585.614 ms) ====== [2026-01-31T14:57:39.211Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-31T14:57:39.211Z] GC before operation: completed in 142.693 ms, heap usage 236.698 MB -> 61.017 MB. [2026-01-31T14:57:55.457Z] ====== als (apache-spark) [default], iteration 1 completed (17133.916 ms) ====== [2026-01-31T14:57:55.457Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-31T14:57:55.457Z] GC before operation: completed in 107.969 ms, heap usage 165.283 MB -> 62.108 MB. [2026-01-31T14:58:09.358Z] ====== als (apache-spark) [default], iteration 2 completed (13918.839 ms) ====== [2026-01-31T14:58:09.358Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-31T14:58:09.358Z] GC before operation: completed in 119.889 ms, heap usage 141.455 MB -> 62.664 MB. [2026-01-31T14:58:26.035Z] ====== als (apache-spark) [default], iteration 3 completed (14542.391 ms) ====== [2026-01-31T14:58:26.035Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-31T14:58:26.035Z] GC before operation: completed in 112.426 ms, heap usage 178.279 MB -> 63.098 MB. [2026-01-31T14:58:42.299Z] ====== als (apache-spark) [default], iteration 4 completed (17310.700 ms) ====== [2026-01-31T14:58:42.299Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-31T14:58:42.299Z] GC before operation: completed in 145.091 ms, heap usage 369.200 MB -> 63.860 MB. [2026-01-31T14:59:01.329Z] ====== als (apache-spark) [default], iteration 5 completed (17463.432 ms) ====== [2026-01-31T14:59:01.329Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-31T14:59:01.329Z] GC before operation: completed in 135.071 ms, heap usage 358.464 MB -> 64.434 MB. [2026-01-31T14:59:20.351Z] ====== als (apache-spark) [default], iteration 6 completed (18979.582 ms) ====== [2026-01-31T14:59:20.351Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-31T14:59:20.351Z] GC before operation: completed in 112.550 ms, heap usage 278.534 MB -> 65.015 MB. [2026-01-31T14:59:36.636Z] ====== als (apache-spark) [default], iteration 7 completed (16855.559 ms) ====== [2026-01-31T14:59:36.636Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-31T14:59:36.636Z] GC before operation: completed in 101.253 ms, heap usage 364.973 MB -> 65.700 MB. [2026-01-31T14:59:59.028Z] ====== als (apache-spark) [default], iteration 8 completed (21016.697 ms) ====== [2026-01-31T14:59:59.028Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-31T14:59:59.028Z] GC before operation: completed in 123.564 ms, heap usage 335.124 MB -> 66.174 MB. [2026-01-31T15:00:15.514Z] ====== als (apache-spark) [default], iteration 9 completed (16421.720 ms) ====== [2026-01-31T15:00:15.514Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-31T15:00:15.514Z] GC before operation: completed in 95.778 ms, heap usage 435.188 MB -> 67.092 MB. [2026-01-31T15:00:31.815Z] ====== als (apache-spark) [default], iteration 10 completed (16659.264 ms) ====== [2026-01-31T15:00:31.816Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-31T15:00:31.816Z] GC before operation: completed in 98.284 ms, heap usage 383.062 MB -> 66.954 MB. [2026-01-31T15:00:50.844Z] ====== als (apache-spark) [default], iteration 11 completed (18290.633 ms) ====== [2026-01-31T15:00:50.844Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-31T15:00:50.844Z] GC before operation: completed in 117.705 ms, heap usage 239.346 MB -> 67.065 MB. [2026-01-31T15:01:09.912Z] ====== als (apache-spark) [default], iteration 12 completed (17630.453 ms) ====== [2026-01-31T15:01:09.912Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-31T15:01:09.912Z] GC before operation: completed in 121.232 ms, heap usage 141.903 MB -> 67.131 MB. [2026-01-31T15:01:26.182Z] ====== als (apache-spark) [default], iteration 13 completed (16338.307 ms) ====== [2026-01-31T15:01:26.182Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-31T15:01:26.182Z] GC before operation: completed in 95.277 ms, heap usage 283.677 MB -> 67.470 MB. [2026-01-31T15:01:42.501Z] ====== als (apache-spark) [default], iteration 14 completed (16783.935 ms) ====== [2026-01-31T15:01:42.501Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-31T15:01:42.501Z] GC before operation: completed in 116.698 ms, heap usage 353.333 MB -> 67.961 MB. [2026-01-31T15:02:01.733Z] ====== als (apache-spark) [default], iteration 15 completed (19204.471 ms) ====== [2026-01-31T15:02:01.733Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-31T15:02:01.733Z] GC before operation: completed in 101.282 ms, heap usage 471.019 MB -> 68.594 MB. [2026-01-31T15:02:18.240Z] ====== als (apache-spark) [default], iteration 16 completed (17041.072 ms) ====== [2026-01-31T15:02:18.240Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-31T15:02:19.021Z] GC before operation: completed in 93.063 ms, heap usage 151.413 MB -> 68.171 MB. [2026-01-31T15:02:35.365Z] ====== als (apache-spark) [default], iteration 17 completed (15185.824 ms) ====== [2026-01-31T15:02:35.365Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-31T15:02:35.365Z] GC before operation: completed in 112.313 ms, heap usage 158.832 MB -> 68.570 MB. [2026-01-31T15:02:54.467Z] ====== als (apache-spark) [default], iteration 18 completed (17832.036 ms) ====== [2026-01-31T15:02:54.467Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-31T15:02:54.467Z] GC before operation: completed in 108.623 ms, heap usage 371.357 MB -> 68.870 MB. [2026-01-31T15:03:13.483Z] ====== als (apache-spark) [default], iteration 19 completed (20815.867 ms) ====== [2026-01-31T15:03:13.483Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-31T15:03:13.483Z] GC before operation: completed in 111.191 ms, heap usage 428.616 MB -> 69.401 MB. [2026-01-31T15:03:35.720Z] ====== als (apache-spark) [default], iteration 20 completed (20265.125 ms) ====== [2026-01-31T15:03:35.720Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-31T15:03:35.720Z] GC before operation: completed in 115.595 ms, heap usage 354.525 MB -> 69.420 MB. [2026-01-31T15:03:57.923Z] ====== als (apache-spark) [default], iteration 21 completed (21609.381 ms) ====== [2026-01-31T15:03:57.923Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-31T15:03:57.923Z] GC before operation: completed in 111.497 ms, heap usage 469.652 MB -> 70.139 MB. [2026-01-31T15:04:16.924Z] ====== als (apache-spark) [default], iteration 22 completed (20890.136 ms) ====== [2026-01-31T15:04:16.924Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-31T15:04:17.728Z] GC before operation: completed in 120.584 ms, heap usage 130.450 MB -> 70.116 MB. [2026-01-31T15:04:36.976Z] ====== als (apache-spark) [default], iteration 23 completed (19416.619 ms) ====== [2026-01-31T15:04:36.976Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-31T15:04:36.976Z] GC before operation: completed in 106.980 ms, heap usage 372.517 MB -> 70.524 MB. [2026-01-31T15:04:59.338Z] ====== als (apache-spark) [default], iteration 24 completed (20755.788 ms) ====== [2026-01-31T15:04:59.338Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-31T15:04:59.338Z] GC before operation: completed in 100.693 ms, heap usage 163.097 MB -> 70.478 MB. [2026-01-31T15:05:18.392Z] ====== als (apache-spark) [default], iteration 25 completed (18123.629 ms) ====== [2026-01-31T15:05:18.392Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-31T15:05:18.392Z] GC before operation: completed in 116.183 ms, heap usage 145.755 MB -> 70.912 MB. [2026-01-31T15:05:34.694Z] ====== als (apache-spark) [default], iteration 26 completed (16428.161 ms) ====== [2026-01-31T15:05:34.694Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-31T15:05:34.694Z] GC before operation: completed in 99.110 ms, heap usage 468.549 MB -> 71.524 MB. [2026-01-31T15:05:51.022Z] ====== als (apache-spark) [default], iteration 27 completed (17941.461 ms) ====== [2026-01-31T15:05:51.022Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-31T15:05:51.810Z] GC before operation: completed in 100.040 ms, heap usage 273.385 MB -> 70.888 MB. [2026-01-31T15:06:14.047Z] ====== als (apache-spark) [default], iteration 28 completed (20756.695 ms) ====== [2026-01-31T15:06:14.047Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-31T15:06:14.047Z] GC before operation: completed in 99.719 ms, heap usage 147.169 MB -> 71.021 MB. [2026-01-31T15:07:11.336Z] ====== als (apache-spark) [default], iteration 29 completed (57907.193 ms) ====== [2026-01-31T15:07:21.315Z] ----------------------------------- [2026-01-31T15:07:21.315Z] renaissance-als_0_PASSED [2026-01-31T15:07:21.315Z] ----------------------------------- [2026-01-31T15:07:21.315Z] [2026-01-31T15:07:21.315Z] TEST TEARDOWN: [2026-01-31T15:07:21.315Z] Nothing to be done for teardown. [2026-01-31T15:07:21.315Z] renaissance-als_0 Finish Time: Sat Jan 31 15:07:19 2026 Epoch Time (ms): 1769872039723