[2026-01-22T06:37:18.675Z] Running test renaissance-als_0 ... [2026-01-22T06:37:18.675Z] =============================================== [2026-01-22T06:37:18.675Z] renaissance-als_0 Start Time: Thu Jan 22 06:37:18 2026 Epoch Time (ms): 1769063838657 [2026-01-22T06:37:19.014Z] variation: NoOptions [2026-01-22T06:37:19.014Z] JVM_OPTIONS: [2026-01-22T06:37:19.014Z] { \ [2026-01-22T06:37:19.014Z] echo ""; echo "TEST SETUP:"; \ [2026-01-22T06:37:19.014Z] echo "Nothing to be done for setup."; \ [2026-01-22T06:37:19.014Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17690638376385/renaissance-als_0"; \ [2026-01-22T06:37:19.014Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17690638376385/renaissance-als_0"; \ [2026-01-22T06:37:19.014Z] echo ""; echo "TESTING:"; \ [2026-01-22T06:37:19.014Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/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_riscv64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17690638376385/renaissance-als_0"/als.json" als; \ [2026-01-22T06:37:19.014Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17690638376385/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-22T06:37:19.014Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-22T06:37:19.014Z] echo "Nothing to be done for teardown."; \ [2026-01-22T06:37:19.014Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17690638376385/TestTargetResult"; [2026-01-22T06:37:19.014Z] [2026-01-22T06:37:19.014Z] TEST SETUP: [2026-01-22T06:37:19.014Z] Nothing to be done for setup. [2026-01-22T06:37:19.014Z] [2026-01-22T06:37:19.014Z] TESTING: [2026-01-22T06:37:21.286Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-22T06:37:21.286Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/output_17690638376385/renaissance-als_0/launcher-063719-3114391392246127461/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-22T06:37:21.286Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-22T06:37:21.286Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-22T06:37:44.380Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-22T06:38:00.547Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-22T06:38:00.547Z] GC before operation: completed in 217.644 ms, heap usage 145.296 MB -> 35.364 MB. [2026-01-22T06:39:10.109Z] ====== als (apache-spark) [default], iteration 0 completed (66342.782 ms) ====== [2026-01-22T06:39:10.109Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-22T06:39:10.109Z] GC before operation: completed in 570.158 ms, heap usage 210.678 MB -> 75.272 MB. [2026-01-22T06:39:37.894Z] ====== als (apache-spark) [default], iteration 1 completed (23702.115 ms) ====== [2026-01-22T06:39:37.894Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-22T06:39:37.894Z] GC before operation: completed in 701.616 ms, heap usage 784.165 MB -> 76.926 MB. [2026-01-22T06:40:00.990Z] ====== als (apache-spark) [default], iteration 2 completed (21964.599 ms) ====== [2026-01-22T06:40:00.990Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-22T06:40:00.990Z] GC before operation: completed in 662.561 ms, heap usage 141.648 MB -> 76.719 MB. [2026-01-22T06:40:24.112Z] ====== als (apache-spark) [default], iteration 3 completed (21080.253 ms) ====== [2026-01-22T06:40:24.112Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-22T06:40:24.112Z] GC before operation: completed in 714.256 ms, heap usage 211.380 MB -> 77.405 MB. [2026-01-22T06:40:43.347Z] ====== als (apache-spark) [default], iteration 4 completed (20221.627 ms) ====== [2026-01-22T06:40:43.347Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-22T06:40:43.690Z] GC before operation: completed in 741.702 ms, heap usage 246.397 MB -> 77.892 MB. [2026-01-22T06:41:06.795Z] ====== als (apache-spark) [default], iteration 5 completed (19800.529 ms) ====== [2026-01-22T06:41:06.795Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-22T06:41:06.795Z] GC before operation: completed in 740.905 ms, heap usage 379.757 MB -> 78.712 MB. [2026-01-22T06:41:25.997Z] ====== als (apache-spark) [default], iteration 6 completed (19783.327 ms) ====== [2026-01-22T06:41:25.997Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-22T06:41:26.729Z] GC before operation: completed in 744.979 ms, heap usage 468.076 MB -> 79.481 MB. [2026-01-22T06:41:49.846Z] ====== als (apache-spark) [default], iteration 7 completed (20402.557 ms) ====== [2026-01-22T06:41:49.846Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-22T06:41:49.846Z] GC before operation: completed in 793.023 ms, heap usage 263.300 MB -> 79.661 MB. [2026-01-22T06:42:12.945Z] ====== als (apache-spark) [default], iteration 8 completed (19604.283 ms) ====== [2026-01-22T06:42:12.945Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-22T06:42:12.945Z] GC before operation: completed in 767.333 ms, heap usage 137.629 MB -> 80.066 MB. [2026-01-22T06:42:32.158Z] ====== als (apache-spark) [default], iteration 9 completed (19684.871 ms) ====== [2026-01-22T06:42:32.158Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-22T06:42:32.158Z] GC before operation: completed in 803.507 ms, heap usage 487.594 MB -> 80.669 MB. [2026-01-22T06:42:51.719Z] ====== als (apache-spark) [default], iteration 10 completed (19619.679 ms) ====== [2026-01-22T06:42:51.719Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-22T06:42:52.892Z] GC before operation: completed in 857.190 ms, heap usage 224.580 MB -> 80.528 MB. [2026-01-22T06:43:15.989Z] ====== als (apache-spark) [default], iteration 11 completed (19319.708 ms) ====== [2026-01-22T06:43:15.989Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-22T06:43:15.989Z] GC before operation: completed in 824.314 ms, heap usage 1.425 GB -> 82.291 MB. [2026-01-22T06:43:35.166Z] ====== als (apache-spark) [default], iteration 12 completed (19176.596 ms) ====== [2026-01-22T06:43:35.166Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-22T06:43:35.166Z] GC before operation: completed in 826.337 ms, heap usage 531.619 MB -> 81.624 MB. [2026-01-22T06:43:54.360Z] ====== als (apache-spark) [default], iteration 13 completed (19156.829 ms) ====== [2026-01-22T06:43:54.360Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-22T06:43:55.546Z] GC before operation: completed in 917.130 ms, heap usage 274.736 MB -> 81.442 MB. [2026-01-22T06:44:18.639Z] ====== als (apache-spark) [default], iteration 14 completed (18975.745 ms) ====== [2026-01-22T06:44:18.639Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-22T06:44:18.639Z] GC before operation: completed in 860.739 ms, heap usage 139.025 MB -> 81.225 MB. [2026-01-22T06:44:37.814Z] ====== als (apache-spark) [default], iteration 15 completed (18996.326 ms) ====== [2026-01-22T06:44:37.814Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-22T06:44:37.814Z] GC before operation: completed in 866.758 ms, heap usage 471.401 MB -> 82.378 MB. [2026-01-22T06:44:57.018Z] ====== als (apache-spark) [default], iteration 16 completed (19011.139 ms) ====== [2026-01-22T06:44:57.018Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-22T06:44:57.356Z] GC before operation: completed in 880.896 ms, heap usage 114.526 MB -> 82.739 MB. [2026-01-22T06:45:20.443Z] ====== als (apache-spark) [default], iteration 17 completed (18764.761 ms) ====== [2026-01-22T06:45:20.443Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-22T06:45:20.443Z] GC before operation: completed in 893.256 ms, heap usage 381.418 MB -> 82.708 MB. [2026-01-22T06:45:39.612Z] ====== als (apache-spark) [default], iteration 18 completed (18393.096 ms) ====== [2026-01-22T06:45:39.612Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-22T06:45:39.612Z] GC before operation: completed in 891.471 ms, heap usage 463.804 MB -> 83.201 MB. [2026-01-22T06:45:58.881Z] ====== als (apache-spark) [default], iteration 19 completed (18719.087 ms) ====== [2026-01-22T06:45:58.881Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-22T06:45:58.881Z] GC before operation: completed in 908.944 ms, heap usage 504.378 MB -> 83.387 MB. [2026-01-22T06:46:21.990Z] ====== als (apache-spark) [default], iteration 20 completed (19591.415 ms) ====== [2026-01-22T06:46:21.990Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-22T06:46:21.990Z] GC before operation: completed in 912.419 ms, heap usage 190.827 MB -> 83.176 MB. [2026-01-22T06:46:41.184Z] ====== als (apache-spark) [default], iteration 21 completed (18985.425 ms) ====== [2026-01-22T06:46:41.184Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-22T06:46:41.184Z] GC before operation: completed in 923.540 ms, heap usage 491.477 MB -> 83.915 MB. [2026-01-22T06:47:00.589Z] ====== als (apache-spark) [default], iteration 22 completed (18757.768 ms) ====== [2026-01-22T06:47:00.589Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-22T06:47:00.589Z] GC before operation: completed in 924.696 ms, heap usage 500.149 MB -> 84.094 MB. [2026-01-22T06:47:23.736Z] ====== als (apache-spark) [default], iteration 23 completed (19437.383 ms) ====== [2026-01-22T06:47:23.736Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-22T06:47:23.736Z] GC before operation: completed in 957.058 ms, heap usage 246.256 MB -> 84.027 MB. [2026-01-22T06:47:42.911Z] ====== als (apache-spark) [default], iteration 24 completed (19024.117 ms) ====== [2026-01-22T06:47:42.911Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-22T06:47:42.911Z] GC before operation: completed in 929.328 ms, heap usage 247.794 MB -> 84.224 MB. [2026-01-22T06:48:02.131Z] ====== als (apache-spark) [default], iteration 25 completed (18659.863 ms) ====== [2026-01-22T06:48:02.131Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-22T06:48:02.866Z] GC before operation: completed in 950.466 ms, heap usage 617.050 MB -> 85.023 MB. [2026-01-22T06:48:22.054Z] ====== als (apache-spark) [default], iteration 26 completed (18205.501 ms) ====== [2026-01-22T06:48:22.054Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-22T06:48:22.790Z] GC before operation: completed in 947.839 ms, heap usage 344.086 MB -> 84.969 MB. [2026-01-22T06:48:41.983Z] ====== als (apache-spark) [default], iteration 27 completed (17755.622 ms) ====== [2026-01-22T06:48:41.983Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-22T06:48:42.706Z] GC before operation: completed in 953.969 ms, heap usage 269.963 MB -> 85.012 MB. [2026-01-22T06:49:01.912Z] ====== als (apache-spark) [default], iteration 28 completed (18824.720 ms) ====== [2026-01-22T06:49:01.912Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-22T06:49:03.101Z] GC before operation: completed in 959.059 ms, heap usage 243.314 MB -> 85.236 MB. [2026-01-22T06:49:22.280Z] ====== als (apache-spark) [default], iteration 29 completed (18070.879 ms) ====== [2026-01-22T06:49:23.943Z] ----------------------------------- [2026-01-22T06:49:23.943Z] renaissance-als_0_PASSED [2026-01-22T06:49:23.943Z] ----------------------------------- [2026-01-22T06:49:23.943Z] [2026-01-22T06:49:23.943Z] TEST TEARDOWN: [2026-01-22T06:49:23.943Z] Nothing to be done for teardown. [2026-01-22T06:49:23.943Z] renaissance-als_0 Finish Time: Thu Jan 22 06:49:23 2026 Epoch Time (ms): 1769064563882