[2025-11-20T06:59:59.194Z] Running test renaissance-als_0 ... [2025-11-20T06:59:59.194Z] =============================================== [2025-11-20T06:59:59.194Z] renaissance-als_0 Start Time: Thu Nov 20 06:59:58 2025 Epoch Time (ms): 1763621998929 [2025-11-20T06:59:59.194Z] variation: NoOptions [2025-11-20T06:59:59.194Z] JVM_OPTIONS: [2025-11-20T06:59:59.194Z] { \ [2025-11-20T06:59:59.194Z] echo ""; echo "TEST SETUP:"; \ [2025-11-20T06:59:59.194Z] echo "Nothing to be done for setup."; \ [2025-11-20T06:59:59.194Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17636214501114/renaissance-als_0"; \ [2025-11-20T06:59:59.194Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17636214501114/renaissance-als_0"; \ [2025-11-20T06:59:59.194Z] echo ""; echo "TESTING:"; \ [2025-11-20T06:59:59.194Z] "/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_17636214501114/renaissance-als_0"/als.json" als; \ [2025-11-20T06:59:59.194Z] 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_17636214501114/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-20T06:59:59.194Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-20T06:59:59.194Z] echo "Nothing to be done for teardown."; \ [2025-11-20T06:59:59.194Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17636214501114/TestTargetResult"; [2025-11-20T06:59:59.194Z] [2025-11-20T06:59:59.194Z] TEST SETUP: [2025-11-20T06:59:59.194Z] Nothing to be done for setup. [2025-11-20T06:59:59.194Z] [2025-11-20T06:59:59.194Z] TESTING: [2025-11-20T07:00:22.310Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-20T07:00:41.424Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-20T07:00:41.424Z] GC before operation: completed in 206.406 ms, heap usage 59.589 MB -> 35.485 MB. [2025-11-20T07:02:04.702Z] ====== als (apache-spark) [default], iteration 0 completed (70159.344 ms) ====== [2025-11-20T07:02:04.702Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-20T07:02:04.702Z] GC before operation: completed in 749.699 ms, heap usage 135.489 MB -> 75.694 MB. [2025-11-20T07:02:23.760Z] ====== als (apache-spark) [default], iteration 1 completed (26023.924 ms) ====== [2025-11-20T07:02:23.760Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-20T07:02:23.760Z] GC before operation: completed in 702.670 ms, heap usage 740.521 MB -> 77.236 MB. [2025-11-20T07:02:51.442Z] ====== als (apache-spark) [default], iteration 2 completed (22545.553 ms) ====== [2025-11-20T07:02:51.442Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-20T07:02:51.442Z] GC before operation: completed in 712.963 ms, heap usage 691.280 MB -> 77.726 MB. [2025-11-20T07:03:14.454Z] ====== als (apache-spark) [default], iteration 3 completed (22293.244 ms) ====== [2025-11-20T07:03:14.454Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-20T07:03:14.454Z] GC before operation: completed in 716.404 ms, heap usage 312.863 MB -> 77.710 MB. [2025-11-20T07:03:37.478Z] ====== als (apache-spark) [default], iteration 4 completed (21333.230 ms) ====== [2025-11-20T07:03:37.478Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-20T07:03:37.478Z] GC before operation: completed in 740.694 ms, heap usage 613.615 MB -> 78.685 MB. [2025-11-20T07:04:00.469Z] ====== als (apache-spark) [default], iteration 5 completed (21185.032 ms) ====== [2025-11-20T07:04:00.469Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-20T07:04:00.469Z] GC before operation: completed in 617.548 ms, heap usage 184.943 MB -> 78.639 MB. [2025-11-20T07:04:23.462Z] ====== als (apache-spark) [default], iteration 6 completed (20969.690 ms) ====== [2025-11-20T07:04:23.463Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-20T07:04:23.463Z] GC before operation: completed in 660.961 ms, heap usage 172.836 MB -> 79.253 MB. [2025-11-20T07:04:42.547Z] ====== als (apache-spark) [default], iteration 7 completed (20608.809 ms) ====== [2025-11-20T07:04:42.547Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-20T07:04:42.886Z] GC before operation: completed in 697.704 ms, heap usage 665.300 MB -> 80.556 MB. [2025-11-20T07:05:05.878Z] ====== als (apache-spark) [default], iteration 8 completed (20727.339 ms) ====== [2025-11-20T07:05:05.878Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-20T07:05:05.878Z] GC before operation: completed in 775.394 ms, heap usage 996.802 MB -> 81.495 MB. [2025-11-20T07:05:28.931Z] ====== als (apache-spark) [default], iteration 9 completed (20254.593 ms) ====== [2025-11-20T07:05:28.931Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-20T07:05:28.931Z] GC before operation: completed in 798.541 ms, heap usage 1.487 GB -> 82.187 MB. [2025-11-20T07:05:51.926Z] ====== als (apache-spark) [default], iteration 10 completed (20486.197 ms) ====== [2025-11-20T07:05:51.926Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-20T07:05:51.926Z] GC before operation: completed in 746.047 ms, heap usage 466.076 MB -> 81.229 MB. [2025-11-20T07:06:11.173Z] ====== als (apache-spark) [default], iteration 11 completed (19988.247 ms) ====== [2025-11-20T07:06:11.173Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-20T07:06:11.512Z] GC before operation: completed in 767.447 ms, heap usage 1.050 GB -> 82.402 MB. [2025-11-20T07:06:34.556Z] ====== als (apache-spark) [default], iteration 12 completed (20064.469 ms) ====== [2025-11-20T07:06:34.556Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-20T07:06:34.556Z] GC before operation: completed in 748.397 ms, heap usage 1.563 GB -> 82.872 MB. [2025-11-20T07:06:53.641Z] ====== als (apache-spark) [default], iteration 13 completed (19815.391 ms) ====== [2025-11-20T07:06:53.641Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-20T07:06:54.373Z] GC before operation: completed in 763.466 ms, heap usage 386.062 MB -> 81.883 MB. [2025-11-20T07:07:17.378Z] ====== als (apache-spark) [default], iteration 14 completed (19746.387 ms) ====== [2025-11-20T07:07:17.378Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-20T07:07:17.378Z] GC before operation: completed in 784.710 ms, heap usage 295.929 MB -> 82.083 MB. [2025-11-20T07:07:36.486Z] ====== als (apache-spark) [default], iteration 15 completed (19622.462 ms) ====== [2025-11-20T07:07:36.486Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-20T07:07:37.213Z] GC before operation: completed in 769.648 ms, heap usage 571.938 MB -> 82.672 MB. [2025-11-20T07:08:00.445Z] ====== als (apache-spark) [default], iteration 16 completed (19907.762 ms) ====== [2025-11-20T07:08:00.445Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-20T07:08:00.445Z] GC before operation: completed in 793.134 ms, heap usage 462.569 MB -> 82.784 MB. [2025-11-20T07:08:19.534Z] ====== als (apache-spark) [default], iteration 17 completed (19531.449 ms) ====== [2025-11-20T07:08:19.534Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-20T07:08:20.257Z] GC before operation: completed in 810.362 ms, heap usage 164.695 MB -> 82.615 MB. [2025-11-20T07:08:43.270Z] ====== als (apache-spark) [default], iteration 18 completed (19868.129 ms) ====== [2025-11-20T07:08:43.270Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-20T07:08:43.270Z] GC before operation: completed in 868.646 ms, heap usage 1.177 GB -> 84.173 MB. [2025-11-20T07:09:06.260Z] ====== als (apache-spark) [default], iteration 19 completed (20317.300 ms) ====== [2025-11-20T07:09:06.260Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-20T07:09:06.260Z] GC before operation: completed in 847.557 ms, heap usage 301.825 MB -> 83.295 MB. [2025-11-20T07:09:25.361Z] ====== als (apache-spark) [default], iteration 20 completed (20017.064 ms) ====== [2025-11-20T07:09:25.361Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-20T07:09:25.695Z] GC before operation: completed in 819.362 ms, heap usage 518.058 MB -> 83.752 MB. [2025-11-20T07:09:48.708Z] ====== als (apache-spark) [default], iteration 21 completed (19849.600 ms) ====== [2025-11-20T07:09:48.708Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-20T07:09:48.708Z] GC before operation: completed in 818.453 ms, heap usage 187.334 MB -> 83.708 MB. [2025-11-20T07:10:12.266Z] ====== als (apache-spark) [default], iteration 22 completed (20230.874 ms) ====== [2025-11-20T07:10:12.266Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-20T07:10:12.266Z] GC before operation: completed in 829.920 ms, heap usage 354.306 MB -> 84.163 MB. [2025-11-20T07:10:31.316Z] ====== als (apache-spark) [default], iteration 23 completed (19633.931 ms) ====== [2025-11-20T07:10:31.316Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-20T07:10:31.316Z] GC before operation: completed in 843.191 ms, heap usage 118.427 MB -> 83.676 MB. [2025-11-20T07:10:54.318Z] ====== als (apache-spark) [default], iteration 24 completed (19631.430 ms) ====== [2025-11-20T07:10:54.318Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-20T07:10:54.318Z] GC before operation: completed in 913.336 ms, heap usage 727.926 MB -> 85.163 MB. [2025-11-20T07:11:13.499Z] ====== als (apache-spark) [default], iteration 25 completed (19412.717 ms) ====== [2025-11-20T07:11:13.499Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-20T07:11:13.499Z] GC before operation: completed in 832.424 ms, heap usage 206.823 MB -> 84.805 MB. [2025-11-20T07:11:36.539Z] ====== als (apache-spark) [default], iteration 26 completed (18718.492 ms) ====== [2025-11-20T07:11:36.539Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-20T07:11:36.539Z] GC before operation: completed in 878.434 ms, heap usage 145.640 MB -> 85.130 MB. [2025-11-20T07:11:55.617Z] ====== als (apache-spark) [default], iteration 27 completed (18823.780 ms) ====== [2025-11-20T07:11:55.617Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-20T07:11:55.617Z] GC before operation: completed in 857.955 ms, heap usage 294.761 MB -> 85.414 MB. [2025-11-20T07:12:18.586Z] ====== als (apache-spark) [default], iteration 28 completed (19858.198 ms) ====== [2025-11-20T07:12:18.586Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-20T07:12:18.586Z] GC before operation: completed in 892.029 ms, heap usage 1.190 GB -> 86.393 MB. [2025-11-20T07:12:37.691Z] ====== als (apache-spark) [default], iteration 29 completed (19000.892 ms) ====== [2025-11-20T07:12:39.329Z] ----------------------------------- [2025-11-20T07:12:39.329Z] renaissance-als_0_PASSED [2025-11-20T07:12:39.329Z] ----------------------------------- [2025-11-20T07:12:39.329Z] [2025-11-20T07:12:39.329Z] TEST TEARDOWN: [2025-11-20T07:12:39.329Z] Nothing to be done for teardown. [2025-11-20T07:12:39.329Z] renaissance-als_0 Finish Time: Thu Nov 20 07:12:38 2025 Epoch Time (ms): 1763622759002