[2026-01-15T07:21:27.981Z] Running test renaissance-als_0 ... [2026-01-15T07:21:27.982Z] =============================================== [2026-01-15T07:21:27.982Z] renaissance-als_0 Start Time: Thu Jan 15 07:21:26 2026 Epoch Time (ms): 1768461686618 [2026-01-15T07:21:27.982Z] variation: NoOptions [2026-01-15T07:21:27.982Z] JVM_OPTIONS: [2026-01-15T07:21:27.982Z] { \ [2026-01-15T07:21:27.982Z] echo ""; echo "TEST SETUP:"; \ [2026-01-15T07:21:27.982Z] echo "Nothing to be done for setup."; \ [2026-01-15T07:21:27.982Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17684615562012/renaissance-als_0"; \ [2026-01-15T07:21:27.982Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17684615562012/renaissance-als_0"; \ [2026-01-15T07:21:27.982Z] echo ""; echo "TESTING:"; \ [2026-01-15T07:21:27.982Z] "/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_17684615562012/renaissance-als_0"/als.json" als; \ [2026-01-15T07:21:27.982Z] 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_17684615562012/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-15T07:21:27.982Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-15T07:21:27.982Z] echo "Nothing to be done for teardown."; \ [2026-01-15T07:21:27.982Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17684615562012/TestTargetResult"; [2026-01-15T07:21:27.982Z] [2026-01-15T07:21:27.982Z] TEST SETUP: [2026-01-15T07:21:27.982Z] Nothing to be done for setup. [2026-01-15T07:21:27.982Z] [2026-01-15T07:21:27.982Z] TESTING: [2026-01-15T07:21:31.792Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-15T07:21:31.792Z] 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_17684615562012/renaissance-als_0/launcher-072127-16388549659029851502/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-15T07:21:31.792Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-15T07:21:31.792Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-15T07:22:51.435Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 16) threads. [2026-01-15T07:23:01.418Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-15T07:23:01.418Z] GC before operation: completed in 97.582 ms, heap usage 315.201 MB -> 34.467 MB. [2026-01-15T07:23:31.787Z] ====== als (apache-spark) [default], iteration 0 completed (30171.759 ms) ====== [2026-01-15T07:23:31.787Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-15T07:23:31.787Z] GC before operation: completed in 142.087 ms, heap usage 271.561 MB -> 60.987 MB. [2026-01-15T07:24:02.116Z] ====== als (apache-spark) [default], iteration 1 completed (26775.690 ms) ====== [2026-01-15T07:24:02.116Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-15T07:24:02.116Z] GC before operation: completed in 112.163 ms, heap usage 141.212 MB -> 62.021 MB. [2026-01-15T07:24:24.094Z] ====== als (apache-spark) [default], iteration 2 completed (23119.562 ms) ====== [2026-01-15T07:24:24.094Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-15T07:24:24.094Z] GC before operation: completed in 112.258 ms, heap usage 261.456 MB -> 62.526 MB. [2026-01-15T07:24:54.389Z] ====== als (apache-spark) [default], iteration 3 completed (28936.883 ms) ====== [2026-01-15T07:24:54.389Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-15T07:24:54.389Z] GC before operation: completed in 94.788 ms, heap usage 436.635 MB -> 63.577 MB. [2026-01-15T07:25:20.379Z] ====== als (apache-spark) [default], iteration 4 completed (28362.364 ms) ====== [2026-01-15T07:25:20.379Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-15T07:25:20.379Z] GC before operation: completed in 117.290 ms, heap usage 245.282 MB -> 63.973 MB. [2026-01-15T07:26:01.988Z] ====== als (apache-spark) [default], iteration 5 completed (36149.322 ms) ====== [2026-01-15T07:26:01.988Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-15T07:26:01.988Z] GC before operation: completed in 101.125 ms, heap usage 154.071 MB -> 64.435 MB. [2026-01-15T07:26:23.382Z] ====== als (apache-spark) [default], iteration 6 completed (26451.928 ms) ====== [2026-01-15T07:26:23.382Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-15T07:26:24.171Z] GC before operation: completed in 93.430 ms, heap usage 355.537 MB -> 65.083 MB. [2026-01-15T07:26:50.271Z] ====== als (apache-spark) [default], iteration 7 completed (26044.886 ms) ====== [2026-01-15T07:26:50.271Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-15T07:26:50.271Z] GC before operation: completed in 97.355 ms, heap usage 273.890 MB -> 65.602 MB. [2026-01-15T07:27:31.748Z] ====== als (apache-spark) [default], iteration 8 completed (37084.668 ms) ====== [2026-01-15T07:27:31.748Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-15T07:27:31.748Z] GC before operation: completed in 99.149 ms, heap usage 140.694 MB -> 65.873 MB. [2026-01-15T07:27:57.685Z] ====== als (apache-spark) [default], iteration 9 completed (26656.008 ms) ====== [2026-01-15T07:27:57.686Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-15T07:27:57.686Z] GC before operation: completed in 99.766 ms, heap usage 361.496 MB -> 66.970 MB. [2026-01-15T07:28:19.895Z] ====== als (apache-spark) [default], iteration 10 completed (25343.294 ms) ====== [2026-01-15T07:28:19.895Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-15T07:28:20.687Z] GC before operation: completed in 107.321 ms, heap usage 408.796 MB -> 66.695 MB. [2026-01-15T07:28:45.032Z] ====== als (apache-spark) [default], iteration 11 completed (24173.236 ms) ====== [2026-01-15T07:28:45.032Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-15T07:28:45.032Z] GC before operation: completed in 97.900 ms, heap usage 315.326 MB -> 66.969 MB. [2026-01-15T07:29:15.331Z] ====== als (apache-spark) [default], iteration 12 completed (29339.315 ms) ====== [2026-01-15T07:29:15.331Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-15T07:29:15.331Z] GC before operation: completed in 111.999 ms, heap usage 428.985 MB -> 67.666 MB. [2026-01-15T07:29:50.951Z] ====== als (apache-spark) [default], iteration 13 completed (35768.640 ms) ====== [2026-01-15T07:29:50.951Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-15T07:29:50.951Z] GC before operation: completed in 113.179 ms, heap usage 273.430 MB -> 67.456 MB. [2026-01-15T07:30:26.387Z] ====== als (apache-spark) [default], iteration 14 completed (30877.445 ms) ====== [2026-01-15T07:30:26.387Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-15T07:30:26.387Z] GC before operation: completed in 110.889 ms, heap usage 137.577 MB -> 67.781 MB. [2026-01-15T07:30:52.467Z] ====== als (apache-spark) [default], iteration 15 completed (29249.836 ms) ====== [2026-01-15T07:30:52.467Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-15T07:30:52.467Z] GC before operation: completed in 109.200 ms, heap usage 443.311 MB -> 68.484 MB. [2026-01-15T07:31:20.212Z] ====== als (apache-spark) [default], iteration 16 completed (26598.548 ms) ====== [2026-01-15T07:31:20.212Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-15T07:31:20.212Z] GC before operation: completed in 101.411 ms, heap usage 243.439 MB -> 68.432 MB. [2026-01-15T07:32:01.703Z] ====== als (apache-spark) [default], iteration 17 completed (37986.550 ms) ====== [2026-01-15T07:32:01.703Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-15T07:32:01.703Z] GC before operation: completed in 95.976 ms, heap usage 374.847 MB -> 68.685 MB. [2026-01-15T07:32:23.930Z] ====== als (apache-spark) [default], iteration 18 completed (24728.655 ms) ====== [2026-01-15T07:32:23.930Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-15T07:32:23.930Z] GC before operation: completed in 101.792 ms, heap usage 329.689 MB -> 68.955 MB. [2026-01-15T07:33:21.086Z] ====== als (apache-spark) [default], iteration 19 completed (55578.446 ms) ====== [2026-01-15T07:33:21.086Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-15T07:33:21.086Z] GC before operation: completed in 106.439 ms, heap usage 358.868 MB -> 69.231 MB. [2026-01-15T07:33:48.759Z] ====== als (apache-spark) [default], iteration 20 completed (31451.067 ms) ====== [2026-01-15T07:33:48.760Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-15T07:33:49.540Z] GC before operation: completed in 112.921 ms, heap usage 245.366 MB -> 69.384 MB. [2026-01-15T07:34:38.419Z] ====== als (apache-spark) [default], iteration 21 completed (47028.292 ms) ====== [2026-01-15T07:34:38.419Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-15T07:34:38.419Z] GC before operation: completed in 103.966 ms, heap usage 317.978 MB -> 69.770 MB. [2026-01-15T07:35:13.862Z] ====== als (apache-spark) [default], iteration 22 completed (33807.971 ms) ====== [2026-01-15T07:35:13.862Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-15T07:35:13.862Z] GC before operation: completed in 102.993 ms, heap usage 265.371 MB -> 69.914 MB. [2026-01-15T07:35:49.309Z] ====== als (apache-spark) [default], iteration 23 completed (37542.240 ms) ====== [2026-01-15T07:35:49.309Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-15T07:35:49.309Z] GC before operation: completed in 109.507 ms, heap usage 363.374 MB -> 70.408 MB. [2026-01-15T07:36:11.519Z] ====== als (apache-spark) [default], iteration 24 completed (23113.340 ms) ====== [2026-01-15T07:36:11.519Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-15T07:36:12.299Z] GC before operation: completed in 129.405 ms, heap usage 281.637 MB -> 70.550 MB. [2026-01-15T07:36:44.400Z] ====== als (apache-spark) [default], iteration 25 completed (28506.712 ms) ====== [2026-01-15T07:36:44.400Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-15T07:36:44.400Z] GC before operation: completed in 101.334 ms, heap usage 179.101 MB -> 70.716 MB. [2026-01-15T07:37:10.311Z] ====== als (apache-spark) [default], iteration 26 completed (28709.760 ms) ====== [2026-01-15T07:37:10.311Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-15T07:37:10.311Z] GC before operation: completed in 117.061 ms, heap usage 161.169 MB -> 70.939 MB. [2026-01-15T07:37:31.629Z] ====== als (apache-spark) [default], iteration 27 completed (19664.843 ms) ====== [2026-01-15T07:37:31.629Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-15T07:37:31.629Z] GC before operation: completed in 107.735 ms, heap usage 275.931 MB -> 71.167 MB. [2026-01-15T07:37:53.862Z] ====== als (apache-spark) [default], iteration 28 completed (21948.808 ms) ====== [2026-01-15T07:37:53.862Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-15T07:37:53.862Z] GC before operation: completed in 119.066 ms, heap usage 403.945 MB -> 71.284 MB. [2026-01-15T07:38:24.169Z] ====== als (apache-spark) [default], iteration 29 completed (28456.126 ms) ====== [2026-01-15T07:38:24.169Z] ----------------------------------- [2026-01-15T07:38:24.169Z] renaissance-als_0_PASSED [2026-01-15T07:38:24.169Z] ----------------------------------- [2026-01-15T07:38:24.169Z] [2026-01-15T07:38:24.169Z] TEST TEARDOWN: [2026-01-15T07:38:24.169Z] Nothing to be done for teardown. [2026-01-15T07:38:24.169Z] renaissance-als_0 Finish Time: Thu Jan 15 07:38:21 2026 Epoch Time (ms): 1768462701531