[2025-08-28T08:10:17.659Z] Running test renaissance-als_0 ... [2025-08-28T08:10:17.659Z] =============================================== [2025-08-28T08:10:17.659Z] renaissance-als_0 Start Time: Thu Aug 28 08:10:17 2025 Epoch Time (ms): 1756368617418 [2025-08-28T08:10:17.659Z] variation: NoOptions [2025-08-28T08:10:17.659Z] JVM_OPTIONS: [2025-08-28T08:10:17.659Z] { \ [2025-08-28T08:10:17.659Z] echo ""; echo "TEST SETUP:"; \ [2025-08-28T08:10:17.659Z] echo "Nothing to be done for setup."; \ [2025-08-28T08:10:17.659Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17563686166155/renaissance-als_0"; \ [2025-08-28T08:10:17.659Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17563686166155/renaissance-als_0"; \ [2025-08-28T08:10:17.659Z] echo ""; echo "TESTING:"; \ [2025-08-28T08:10:17.659Z] "/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_17563686166155/renaissance-als_0"/als.json" als; \ [2025-08-28T08:10:17.659Z] 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_17563686166155/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-28T08:10:17.659Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-28T08:10:17.659Z] echo "Nothing to be done for teardown."; \ [2025-08-28T08:10:17.659Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17563686166155/TestTargetResult"; [2025-08-28T08:10:17.659Z] [2025-08-28T08:10:17.659Z] TEST SETUP: [2025-08-28T08:10:17.659Z] Nothing to be done for setup. [2025-08-28T08:10:17.659Z] [2025-08-28T08:10:17.659Z] TESTING: [2025-08-28T08:10:40.703Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-28T08:11:03.711Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-08-28T08:11:03.711Z] GC before operation: completed in 222.585 ms, heap usage 59.049 MB -> 35.579 MB. [2025-08-28T08:12:26.728Z] ====== als (apache-spark) [default], iteration 0 completed (72625.798 ms) ====== [2025-08-28T08:12:26.728Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-08-28T08:12:26.728Z] GC before operation: completed in 747.840 ms, heap usage 608.775 MB -> 76.052 MB. [2025-08-28T08:12:49.738Z] ====== als (apache-spark) [default], iteration 1 completed (28098.333 ms) ====== [2025-08-28T08:12:49.738Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-08-28T08:12:49.738Z] GC before operation: completed in 718.420 ms, heap usage 502.441 MB -> 76.950 MB. [2025-08-28T08:13:17.453Z] ====== als (apache-spark) [default], iteration 2 completed (24627.421 ms) ====== [2025-08-28T08:13:17.453Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-08-28T08:13:17.453Z] GC before operation: completed in 776.334 ms, heap usage 923.401 MB -> 77.942 MB. [2025-08-28T08:13:40.555Z] ====== als (apache-spark) [default], iteration 3 completed (24310.828 ms) ====== [2025-08-28T08:13:40.555Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-08-28T08:13:40.555Z] GC before operation: completed in 766.011 ms, heap usage 440.121 MB -> 77.960 MB. [2025-08-28T08:14:08.486Z] ====== als (apache-spark) [default], iteration 4 completed (22970.332 ms) ====== [2025-08-28T08:14:08.486Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-08-28T08:14:08.486Z] GC before operation: completed in 761.997 ms, heap usage 555.120 MB -> 78.799 MB. [2025-08-28T08:14:31.521Z] ====== als (apache-spark) [default], iteration 5 completed (22845.339 ms) ====== [2025-08-28T08:14:31.521Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-08-28T08:14:31.521Z] GC before operation: completed in 756.240 ms, heap usage 136.584 MB -> 78.711 MB. [2025-08-28T08:14:54.551Z] ====== als (apache-spark) [default], iteration 6 completed (22889.999 ms) ====== [2025-08-28T08:14:54.552Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-08-28T08:14:54.552Z] GC before operation: completed in 689.877 ms, heap usage 679.405 MB -> 80.129 MB. [2025-08-28T08:15:17.584Z] ====== als (apache-spark) [default], iteration 7 completed (21944.156 ms) ====== [2025-08-28T08:15:17.584Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-08-28T08:15:18.312Z] GC before operation: completed in 752.195 ms, heap usage 678.317 MB -> 80.471 MB. [2025-08-28T08:15:41.602Z] ====== als (apache-spark) [default], iteration 8 completed (22452.790 ms) ====== [2025-08-28T08:15:41.602Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-08-28T08:15:42.764Z] GC before operation: completed in 755.177 ms, heap usage 1.226 GB -> 81.650 MB. [2025-08-28T08:16:10.494Z] ====== als (apache-spark) [default], iteration 9 completed (22977.578 ms) ====== [2025-08-28T08:16:10.494Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-08-28T08:16:10.494Z] GC before operation: completed in 751.218 ms, heap usage 769.921 MB -> 81.601 MB. [2025-08-28T08:16:33.499Z] ====== als (apache-spark) [default], iteration 10 completed (22277.240 ms) ====== [2025-08-28T08:16:33.499Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-08-28T08:16:33.499Z] GC before operation: completed in 781.767 ms, heap usage 1.157 GB -> 82.231 MB. [2025-08-28T08:16:56.520Z] ====== als (apache-spark) [default], iteration 11 completed (22187.074 ms) ====== [2025-08-28T08:16:56.520Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-08-28T08:16:56.520Z] GC before operation: completed in 709.243 ms, heap usage 238.790 MB -> 81.165 MB. [2025-08-28T08:17:19.771Z] ====== als (apache-spark) [default], iteration 12 completed (21340.816 ms) ====== [2025-08-28T08:17:19.771Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-08-28T08:17:19.771Z] GC before operation: completed in 736.036 ms, heap usage 262.257 MB -> 81.439 MB. [2025-08-28T08:17:42.785Z] ====== als (apache-spark) [default], iteration 13 completed (21639.370 ms) ====== [2025-08-28T08:17:42.785Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-08-28T08:17:42.785Z] GC before operation: completed in 750.147 ms, heap usage 723.283 MB -> 82.244 MB. [2025-08-28T08:18:05.814Z] ====== als (apache-spark) [default], iteration 14 completed (22220.016 ms) ====== [2025-08-28T08:18:05.814Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-08-28T08:18:05.814Z] GC before operation: completed in 756.779 ms, heap usage 132.633 MB -> 82.372 MB. [2025-08-28T08:18:28.796Z] ====== als (apache-spark) [default], iteration 15 completed (21335.606 ms) ====== [2025-08-28T08:18:28.796Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-08-28T08:18:28.796Z] GC before operation: completed in 783.007 ms, heap usage 1.326 GB -> 83.478 MB. [2025-08-28T08:18:51.889Z] ====== als (apache-spark) [default], iteration 16 completed (21488.369 ms) ====== [2025-08-28T08:18:51.889Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-08-28T08:18:51.889Z] GC before operation: completed in 805.304 ms, heap usage 511.081 MB -> 82.848 MB. [2025-08-28T08:19:15.009Z] ====== als (apache-spark) [default], iteration 17 completed (21883.851 ms) ====== [2025-08-28T08:19:15.009Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-08-28T08:19:15.009Z] GC before operation: completed in 821.865 ms, heap usage 571.940 MB -> 83.338 MB. [2025-08-28T08:19:38.054Z] ====== als (apache-spark) [default], iteration 18 completed (21767.176 ms) ====== [2025-08-28T08:19:38.054Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-08-28T08:19:38.387Z] GC before operation: completed in 857.337 ms, heap usage 843.159 MB -> 84.591 MB. [2025-08-28T08:20:01.428Z] ====== als (apache-spark) [default], iteration 19 completed (21780.241 ms) ====== [2025-08-28T08:20:01.428Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-08-28T08:20:02.148Z] GC before operation: completed in 881.660 ms, heap usage 1.255 GB -> 84.518 MB. [2025-08-28T08:20:25.158Z] ====== als (apache-spark) [default], iteration 20 completed (21533.335 ms) ====== [2025-08-28T08:20:25.158Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-08-28T08:20:25.158Z] GC before operation: completed in 874.947 ms, heap usage 944.621 MB -> 84.504 MB. [2025-08-28T08:20:48.420Z] ====== als (apache-spark) [default], iteration 21 completed (21836.017 ms) ====== [2025-08-28T08:20:48.420Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-08-28T08:20:48.744Z] GC before operation: completed in 847.937 ms, heap usage 440.621 MB -> 83.996 MB. [2025-08-28T08:21:11.739Z] ====== als (apache-spark) [default], iteration 22 completed (21576.745 ms) ====== [2025-08-28T08:21:11.739Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-08-28T08:21:11.739Z] GC before operation: completed in 836.148 ms, heap usage 579.773 MB -> 84.545 MB. [2025-08-28T08:21:39.444Z] ====== als (apache-spark) [default], iteration 23 completed (22081.189 ms) ====== [2025-08-28T08:21:39.444Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-08-28T08:21:39.444Z] GC before operation: completed in 805.474 ms, heap usage 238.389 MB -> 84.341 MB. [2025-08-28T08:22:02.442Z] ====== als (apache-spark) [default], iteration 24 completed (22073.959 ms) ====== [2025-08-28T08:22:02.442Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-08-28T08:22:02.442Z] GC before operation: completed in 806.821 ms, heap usage 167.242 MB -> 84.637 MB. [2025-08-28T08:22:21.763Z] ====== als (apache-spark) [default], iteration 25 completed (21109.663 ms) ====== [2025-08-28T08:22:21.763Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-08-28T08:22:22.473Z] GC before operation: completed in 851.545 ms, heap usage 931.623 MB -> 85.841 MB. [2025-08-28T08:22:45.488Z] ====== als (apache-spark) [default], iteration 26 completed (21270.005 ms) ====== [2025-08-28T08:22:45.488Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-08-28T08:22:45.488Z] GC before operation: completed in 881.163 ms, heap usage 1.271 GB -> 86.447 MB. [2025-08-28T08:23:08.522Z] ====== als (apache-spark) [default], iteration 27 completed (20956.377 ms) ====== [2025-08-28T08:23:08.522Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-08-28T08:23:08.522Z] GC before operation: completed in 770.862 ms, heap usage 224.916 MB -> 85.270 MB. [2025-08-28T08:23:31.504Z] ====== als (apache-spark) [default], iteration 28 completed (21011.271 ms) ====== [2025-08-28T08:23:31.504Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-08-28T08:23:31.504Z] GC before operation: completed in 793.746 ms, heap usage 1.557 GB -> 86.731 MB. [2025-08-28T08:23:54.567Z] ====== als (apache-spark) [default], iteration 29 completed (20997.932 ms) ====== [2025-08-28T08:23:55.795Z] ----------------------------------- [2025-08-28T08:23:55.795Z] renaissance-als_0_PASSED [2025-08-28T08:23:55.795Z] ----------------------------------- [2025-08-28T08:23:55.795Z] [2025-08-28T08:23:55.795Z] TEST TEARDOWN: [2025-08-28T08:23:55.795Z] Nothing to be done for teardown. [2025-08-28T08:23:55.795Z] renaissance-als_0 Finish Time: Thu Aug 28 08:23:55 2025 Epoch Time (ms): 1756369435744