[2025-09-04T05:13:30.467Z] Running test renaissance-als_0 ... [2025-09-04T05:13:30.467Z] =============================================== [2025-09-04T05:13:30.467Z] renaissance-als_0 Start Time: Thu Sep 4 05:13:30 2025 Epoch Time (ms): 1756962810369 [2025-09-04T05:13:30.467Z] variation: NoOptions [2025-09-04T05:13:30.467Z] JVM_OPTIONS: [2025-09-04T05:13:30.467Z] { \ [2025-09-04T05:13:30.467Z] echo ""; echo "TEST SETUP:"; \ [2025-09-04T05:13:30.467Z] echo "Nothing to be done for setup."; \ [2025-09-04T05:13:30.467Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17569628091184/renaissance-als_0"; \ [2025-09-04T05:13:30.467Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17569628091184/renaissance-als_0"; \ [2025-09-04T05:13:30.467Z] echo ""; echo "TESTING:"; \ [2025-09-04T05:13:30.467Z] "/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_17569628091184/renaissance-als_0"/als.json" als; \ [2025-09-04T05:13:30.467Z] 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_17569628091184/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-04T05:13:30.467Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-04T05:13:30.467Z] echo "Nothing to be done for teardown."; \ [2025-09-04T05:13:30.467Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17569628091184/TestTargetResult"; [2025-09-04T05:13:30.467Z] [2025-09-04T05:13:30.467Z] TEST SETUP: [2025-09-04T05:13:30.467Z] Nothing to be done for setup. [2025-09-04T05:13:30.467Z] [2025-09-04T05:13:30.467Z] TESTING: [2025-09-04T05:13:58.271Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-09-04T05:14:14.128Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-09-04T05:14:14.128Z] GC before operation: completed in 219.271 ms, heap usage 58.564 MB -> 35.421 MB. [2025-09-04T05:15:37.185Z] ====== als (apache-spark) [default], iteration 0 completed (70530.695 ms) ====== [2025-09-04T05:15:37.185Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-09-04T05:15:37.185Z] GC before operation: completed in 719.516 ms, heap usage 156.618 MB -> 75.505 MB. [2025-09-04T05:15:56.308Z] ====== als (apache-spark) [default], iteration 1 completed (25790.043 ms) ====== [2025-09-04T05:15:56.308Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-09-04T05:15:56.308Z] GC before operation: completed in 709.869 ms, heap usage 505.163 MB -> 76.818 MB. [2025-09-04T05:16:24.041Z] ====== als (apache-spark) [default], iteration 2 completed (24107.055 ms) ====== [2025-09-04T05:16:24.041Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-09-04T05:16:24.041Z] GC before operation: completed in 768.574 ms, heap usage 909.330 MB -> 77.872 MB. [2025-09-04T05:16:47.078Z] ====== als (apache-spark) [default], iteration 3 completed (22557.873 ms) ====== [2025-09-04T05:16:47.078Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-09-04T05:16:47.078Z] GC before operation: completed in 765.715 ms, heap usage 939.018 MB -> 78.466 MB. [2025-09-04T05:17:10.155Z] ====== als (apache-spark) [default], iteration 4 completed (21872.866 ms) ====== [2025-09-04T05:17:10.155Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-09-04T05:17:10.155Z] GC before operation: completed in 767.720 ms, heap usage 1.217 GB -> 79.372 MB. [2025-09-04T05:17:33.222Z] ====== als (apache-spark) [default], iteration 5 completed (21839.637 ms) ====== [2025-09-04T05:17:33.222Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-09-04T05:17:33.948Z] GC before operation: completed in 664.074 ms, heap usage 396.371 MB -> 78.906 MB. [2025-09-04T05:18:01.648Z] ====== als (apache-spark) [default], iteration 6 completed (22595.434 ms) ====== [2025-09-04T05:18:01.648Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-09-04T05:18:01.648Z] GC before operation: completed in 628.375 ms, heap usage 471.053 MB -> 79.522 MB. [2025-09-04T05:18:21.180Z] ====== als (apache-spark) [default], iteration 7 completed (20865.801 ms) ====== [2025-09-04T05:18:21.180Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-09-04T05:18:21.180Z] GC before operation: completed in 681.001 ms, heap usage 375.433 MB -> 79.975 MB. [2025-09-04T05:18:44.218Z] ====== als (apache-spark) [default], iteration 8 completed (20999.629 ms) ====== [2025-09-04T05:18:44.218Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-09-04T05:18:44.218Z] GC before operation: completed in 658.952 ms, heap usage 342.716 MB -> 80.590 MB. [2025-09-04T05:19:07.466Z] ====== als (apache-spark) [default], iteration 9 completed (20898.953 ms) ====== [2025-09-04T05:19:07.466Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-09-04T05:19:07.466Z] GC before operation: completed in 739.382 ms, heap usage 1022.115 MB -> 81.569 MB. [2025-09-04T05:19:30.482Z] ====== als (apache-spark) [default], iteration 10 completed (20552.168 ms) ====== [2025-09-04T05:19:30.482Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-09-04T05:19:30.482Z] GC before operation: completed in 695.397 ms, heap usage 277.273 MB -> 80.974 MB. [2025-09-04T05:19:49.608Z] ====== als (apache-spark) [default], iteration 11 completed (19905.007 ms) ====== [2025-09-04T05:19:49.608Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-09-04T05:19:49.945Z] GC before operation: completed in 737.045 ms, heap usage 558.190 MB -> 81.563 MB. [2025-09-04T05:20:12.974Z] ====== als (apache-spark) [default], iteration 12 completed (19880.553 ms) ====== [2025-09-04T05:20:12.974Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-09-04T05:20:12.974Z] GC before operation: completed in 720.628 ms, heap usage 173.135 MB -> 81.460 MB. [2025-09-04T05:20:35.993Z] ====== als (apache-spark) [default], iteration 13 completed (20527.658 ms) ====== [2025-09-04T05:20:35.993Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-09-04T05:20:35.993Z] GC before operation: completed in 725.906 ms, heap usage 506.554 MB -> 81.919 MB. [2025-09-04T05:20:55.175Z] ====== als (apache-spark) [default], iteration 14 completed (20303.435 ms) ====== [2025-09-04T05:20:55.175Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-09-04T05:20:55.908Z] GC before operation: completed in 748.785 ms, heap usage 245.615 MB -> 81.947 MB. [2025-09-04T05:21:18.922Z] ====== als (apache-spark) [default], iteration 15 completed (19636.923 ms) ====== [2025-09-04T05:21:18.922Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-09-04T05:21:18.922Z] GC before operation: completed in 823.998 ms, heap usage 189.080 MB -> 82.136 MB. [2025-09-04T05:21:38.032Z] ====== als (apache-spark) [default], iteration 16 completed (19661.180 ms) ====== [2025-09-04T05:21:38.032Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-09-04T05:21:38.761Z] GC before operation: completed in 786.113 ms, heap usage 270.570 MB -> 82.530 MB. [2025-09-04T05:22:01.839Z] ====== als (apache-spark) [default], iteration 17 completed (19333.751 ms) ====== [2025-09-04T05:22:01.839Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-09-04T05:22:01.839Z] GC before operation: completed in 811.268 ms, heap usage 153.309 MB -> 82.668 MB. [2025-09-04T05:22:20.955Z] ====== als (apache-spark) [default], iteration 18 completed (19356.108 ms) ====== [2025-09-04T05:22:20.955Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-09-04T05:22:20.955Z] GC before operation: completed in 808.988 ms, heap usage 733.388 MB -> 83.924 MB. [2025-09-04T05:22:43.979Z] ====== als (apache-spark) [default], iteration 19 completed (20034.292 ms) ====== [2025-09-04T05:22:43.979Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-09-04T05:22:43.979Z] GC before operation: completed in 820.377 ms, heap usage 567.204 MB -> 83.811 MB. [2025-09-04T05:23:07.005Z] ====== als (apache-spark) [default], iteration 20 completed (20885.887 ms) ====== [2025-09-04T05:23:07.005Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-09-04T05:23:07.005Z] GC before operation: completed in 783.375 ms, heap usage 591.572 MB -> 83.980 MB. [2025-09-04T05:23:26.130Z] ====== als (apache-spark) [default], iteration 21 completed (19996.785 ms) ====== [2025-09-04T05:23:26.130Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-09-04T05:23:26.967Z] GC before operation: completed in 839.935 ms, heap usage 260.635 MB -> 83.703 MB. [2025-09-04T05:23:50.010Z] ====== als (apache-spark) [default], iteration 22 completed (19520.548 ms) ====== [2025-09-04T05:23:50.010Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-09-04T05:23:50.010Z] GC before operation: completed in 813.281 ms, heap usage 572.820 MB -> 85.997 MB. [2025-09-04T05:24:09.116Z] ====== als (apache-spark) [default], iteration 23 completed (20102.706 ms) ====== [2025-09-04T05:24:09.116Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-09-04T05:24:09.834Z] GC before operation: completed in 864.468 ms, heap usage 1.089 GB -> 85.567 MB. [2025-09-04T05:24:32.875Z] ====== als (apache-spark) [default], iteration 24 completed (19796.967 ms) ====== [2025-09-04T05:24:32.875Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-09-04T05:24:32.875Z] GC before operation: completed in 791.934 ms, heap usage 304.686 MB -> 84.637 MB. [2025-09-04T05:24:51.993Z] ====== als (apache-spark) [default], iteration 25 completed (19437.191 ms) ====== [2025-09-04T05:24:51.993Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-09-04T05:24:52.850Z] GC before operation: completed in 865.300 ms, heap usage 666.570 MB -> 86.390 MB. [2025-09-04T05:25:15.854Z] ====== als (apache-spark) [default], iteration 26 completed (19387.575 ms) ====== [2025-09-04T05:25:15.854Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-09-04T05:25:15.854Z] GC before operation: completed in 794.989 ms, heap usage 869.699 MB -> 85.849 MB. [2025-09-04T05:25:34.950Z] ====== als (apache-spark) [default], iteration 27 completed (18957.490 ms) ====== [2025-09-04T05:25:34.950Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-09-04T05:25:34.950Z] GC before operation: completed in 829.097 ms, heap usage 676.516 MB -> 85.978 MB. [2025-09-04T05:25:57.982Z] ====== als (apache-spark) [default], iteration 28 completed (20045.617 ms) ====== [2025-09-04T05:25:57.982Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-09-04T05:25:57.982Z] GC before operation: completed in 851.006 ms, heap usage 871.157 MB -> 86.438 MB. [2025-09-04T05:26:17.090Z] ====== als (apache-spark) [default], iteration 29 completed (19033.739 ms) ====== [2025-09-04T05:26:19.380Z] ----------------------------------- [2025-09-04T05:26:19.380Z] renaissance-als_0_PASSED [2025-09-04T05:26:19.380Z] ----------------------------------- [2025-09-04T05:26:19.716Z] [2025-09-04T05:26:19.716Z] TEST TEARDOWN: [2025-09-04T05:26:19.716Z] Nothing to be done for teardown. [2025-09-04T05:26:19.716Z] renaissance-als_0 Finish Time: Thu Sep 4 05:26:19 2025 Epoch Time (ms): 1756963579474