[2025-12-04T01:40:34.994Z] Running test renaissance-als_0 ... [2025-12-04T01:40:34.994Z] =============================================== [2025-12-04T01:40:34.994Z] renaissance-als_0 Start Time: Thu Dec 4 01:40:34 2025 Epoch Time (ms): 1764812434965 [2025-12-04T01:40:34.994Z] variation: NoOptions [2025-12-04T01:40:35.320Z] JVM_OPTIONS: [2025-12-04T01:40:35.320Z] { \ [2025-12-04T01:40:35.320Z] echo ""; echo "TEST SETUP:"; \ [2025-12-04T01:40:35.320Z] echo "Nothing to be done for setup."; \ [2025-12-04T01:40:35.320Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17648118864591/renaissance-als_0"; \ [2025-12-04T01:40:35.320Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17648118864591/renaissance-als_0"; \ [2025-12-04T01:40:35.320Z] echo ""; echo "TESTING:"; \ [2025-12-04T01:40:35.320Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17648118864591/renaissance-als_0"/als.json" als; \ [2025-12-04T01:40:35.320Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17648118864591/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-04T01:40:35.320Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-04T01:40:35.320Z] echo "Nothing to be done for teardown."; \ [2025-12-04T01:40:35.320Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17648118864591/TestTargetResult"; [2025-12-04T01:40:35.320Z] [2025-12-04T01:40:35.320Z] TEST SETUP: [2025-12-04T01:40:35.320Z] Nothing to be done for setup. [2025-12-04T01:40:35.320Z] [2025-12-04T01:40:35.320Z] TESTING: [2025-12-04T01:40:58.300Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-04T01:41:17.414Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-04T01:41:17.414Z] GC before operation: completed in 254.427 ms, heap usage 283.806 MB -> 35.359 MB. [2025-12-04T01:42:40.631Z] ====== als (apache-spark) [default], iteration 0 completed (70988.596 ms) ====== [2025-12-04T01:42:40.631Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-04T01:42:40.631Z] GC before operation: completed in 808.882 ms, heap usage 1.589 GB -> 76.960 MB. [2025-12-04T01:43:01.775Z] ====== als (apache-spark) [default], iteration 1 completed (27989.598 ms) ====== [2025-12-04T01:43:01.775Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-04T01:43:02.511Z] GC before operation: completed in 736.473 ms, heap usage 1.029 GB -> 77.397 MB. [2025-12-04T01:43:30.427Z] ====== als (apache-spark) [default], iteration 2 completed (24398.133 ms) ====== [2025-12-04T01:43:30.427Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-04T01:43:30.427Z] GC before operation: completed in 682.081 ms, heap usage 489.741 MB -> 77.304 MB. [2025-12-04T01:43:58.092Z] ====== als (apache-spark) [default], iteration 3 completed (24157.310 ms) ====== [2025-12-04T01:43:58.092Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-04T01:43:58.092Z] GC before operation: completed in 728.652 ms, heap usage 1.633 GB -> 79.115 MB. [2025-12-04T01:44:21.126Z] ====== als (apache-spark) [default], iteration 4 completed (23593.192 ms) ====== [2025-12-04T01:44:21.126Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-04T01:44:21.126Z] GC before operation: completed in 716.600 ms, heap usage 377.716 MB -> 78.270 MB. [2025-12-04T01:44:44.337Z] ====== als (apache-spark) [default], iteration 5 completed (22924.056 ms) ====== [2025-12-04T01:44:44.337Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-04T01:44:45.061Z] GC before operation: completed in 737.726 ms, heap usage 350.880 MB -> 78.752 MB. [2025-12-04T01:45:12.856Z] ====== als (apache-spark) [default], iteration 6 completed (22968.281 ms) ====== [2025-12-04T01:45:12.856Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-04T01:45:12.856Z] GC before operation: completed in 748.488 ms, heap usage 193.527 MB -> 79.110 MB. [2025-12-04T01:45:35.844Z] ====== als (apache-spark) [default], iteration 7 completed (21958.778 ms) ====== [2025-12-04T01:45:35.844Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-04T01:45:35.844Z] GC before operation: completed in 766.998 ms, heap usage 154.469 MB -> 79.688 MB. [2025-12-04T01:45:58.924Z] ====== als (apache-spark) [default], iteration 8 completed (21754.473 ms) ====== [2025-12-04T01:45:58.924Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-04T01:45:58.924Z] GC before operation: completed in 818.673 ms, heap usage 216.025 MB -> 80.202 MB. [2025-12-04T01:46:22.230Z] ====== als (apache-spark) [default], iteration 9 completed (21587.207 ms) ====== [2025-12-04T01:46:22.230Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-04T01:46:22.230Z] GC before operation: completed in 824.834 ms, heap usage 258.466 MB -> 80.575 MB. [2025-12-04T01:46:45.218Z] ====== als (apache-spark) [default], iteration 10 completed (21023.526 ms) ====== [2025-12-04T01:46:45.218Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-04T01:46:45.218Z] GC before operation: completed in 826.803 ms, heap usage 150.571 MB -> 80.990 MB. [2025-12-04T01:47:08.199Z] ====== als (apache-spark) [default], iteration 11 completed (21021.883 ms) ====== [2025-12-04T01:47:08.199Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-04T01:47:08.199Z] GC before operation: completed in 851.818 ms, heap usage 512.253 MB -> 81.640 MB. [2025-12-04T01:47:31.168Z] ====== als (apache-spark) [default], iteration 12 completed (21024.381 ms) ====== [2025-12-04T01:47:31.168Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-04T01:47:31.168Z] GC before operation: completed in 891.992 ms, heap usage 698.279 MB -> 82.163 MB. [2025-12-04T01:47:52.418Z] ====== als (apache-spark) [default], iteration 13 completed (21418.969 ms) ====== [2025-12-04T01:47:52.418Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-04T01:47:52.418Z] GC before operation: completed in 925.366 ms, heap usage 1.078 GB -> 82.954 MB. [2025-12-04T01:48:15.431Z] ====== als (apache-spark) [default], iteration 14 completed (21033.653 ms) ====== [2025-12-04T01:48:15.431Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-04T01:48:15.431Z] GC before operation: completed in 881.310 ms, heap usage 571.400 MB -> 82.669 MB. [2025-12-04T01:48:38.407Z] ====== als (apache-spark) [default], iteration 15 completed (21047.578 ms) ====== [2025-12-04T01:48:38.407Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-04T01:48:38.407Z] GC before operation: completed in 864.548 ms, heap usage 534.093 MB -> 82.658 MB. [2025-12-04T01:49:01.943Z] ====== als (apache-spark) [default], iteration 16 completed (21095.341 ms) ====== [2025-12-04T01:49:01.943Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-04T01:49:01.943Z] GC before operation: completed in 787.391 ms, heap usage 317.488 MB -> 82.647 MB. [2025-12-04T01:49:24.944Z] ====== als (apache-spark) [default], iteration 17 completed (20456.289 ms) ====== [2025-12-04T01:49:24.944Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-04T01:49:24.944Z] GC before operation: completed in 777.961 ms, heap usage 155.215 MB -> 82.689 MB. [2025-12-04T01:49:48.028Z] ====== als (apache-spark) [default], iteration 18 completed (20361.436 ms) ====== [2025-12-04T01:49:48.028Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-04T01:49:48.028Z] GC before operation: completed in 814.783 ms, heap usage 516.325 MB -> 83.491 MB. [2025-12-04T01:50:07.115Z] ====== als (apache-spark) [default], iteration 19 completed (20763.487 ms) ====== [2025-12-04T01:50:07.115Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-04T01:50:07.837Z] GC before operation: completed in 838.084 ms, heap usage 990.363 MB -> 84.266 MB. [2025-12-04T01:50:30.912Z] ====== als (apache-spark) [default], iteration 20 completed (21596.177 ms) ====== [2025-12-04T01:50:30.912Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-04T01:50:30.912Z] GC before operation: completed in 819.221 ms, heap usage 824.500 MB -> 84.559 MB. [2025-12-04T01:50:53.933Z] ====== als (apache-spark) [default], iteration 21 completed (20511.715 ms) ====== [2025-12-04T01:50:53.933Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-04T01:50:53.933Z] GC before operation: completed in 796.924 ms, heap usage 251.516 MB -> 84.001 MB. [2025-12-04T01:51:16.910Z] ====== als (apache-spark) [default], iteration 22 completed (20303.249 ms) ====== [2025-12-04T01:51:16.910Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-04T01:51:16.910Z] GC before operation: completed in 872.939 ms, heap usage 1.461 GB -> 85.590 MB. [2025-12-04T01:51:39.954Z] ====== als (apache-spark) [default], iteration 23 completed (20609.392 ms) ====== [2025-12-04T01:51:39.954Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-04T01:51:39.954Z] GC before operation: completed in 867.341 ms, heap usage 1.195 GB -> 85.595 MB. [2025-12-04T01:51:59.029Z] ====== als (apache-spark) [default], iteration 24 completed (20670.474 ms) ====== [2025-12-04T01:51:59.029Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-04T01:51:59.743Z] GC before operation: completed in 800.302 ms, heap usage 162.402 MB -> 84.776 MB. [2025-12-04T01:52:22.708Z] ====== als (apache-spark) [default], iteration 25 completed (20367.011 ms) ====== [2025-12-04T01:52:22.708Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-04T01:52:22.708Z] GC before operation: completed in 827.858 ms, heap usage 870.594 MB -> 85.762 MB. [2025-12-04T01:52:45.698Z] ====== als (apache-spark) [default], iteration 26 completed (19989.736 ms) ====== [2025-12-04T01:52:45.698Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-04T01:52:45.698Z] GC before operation: completed in 826.768 ms, heap usage 1002.103 MB -> 86.244 MB. [2025-12-04T01:53:04.801Z] ====== als (apache-spark) [default], iteration 27 completed (20248.142 ms) ====== [2025-12-04T01:53:04.801Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-04T01:53:05.510Z] GC before operation: completed in 811.074 ms, heap usage 178.133 MB -> 85.022 MB. [2025-12-04T01:53:28.558Z] ====== als (apache-spark) [default], iteration 28 completed (19999.227 ms) ====== [2025-12-04T01:53:28.558Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-04T01:53:28.558Z] GC before operation: completed in 820.527 ms, heap usage 607.862 MB -> 85.699 MB. [2025-12-04T01:53:51.530Z] ====== als (apache-spark) [default], iteration 29 completed (19888.541 ms) ====== [2025-12-04T01:53:51.530Z] ----------------------------------- [2025-12-04T01:53:51.530Z] renaissance-als_0_PASSED [2025-12-04T01:53:51.530Z] ----------------------------------- [2025-12-04T01:53:51.530Z] [2025-12-04T01:53:51.530Z] TEST TEARDOWN: [2025-12-04T01:53:51.530Z] Nothing to be done for teardown. [2025-12-04T01:53:51.530Z] renaissance-als_0 Finish Time: Thu Dec 4 01:53:50 2025 Epoch Time (ms): 1764813230881