[2025-11-26T22:47:03.303Z] Running test renaissance-als_0 ... [2025-11-26T22:47:03.303Z] =============================================== [2025-11-26T22:47:03.635Z] renaissance-als_0 Start Time: Wed Nov 26 22:47:03 2025 Epoch Time (ms): 1764197223305 [2025-11-26T22:47:03.635Z] variation: NoOptions [2025-11-26T22:47:03.635Z] JVM_OPTIONS: [2025-11-26T22:47:03.636Z] { \ [2025-11-26T22:47:03.636Z] echo ""; echo "TEST SETUP:"; \ [2025-11-26T22:47:03.636Z] echo "Nothing to be done for setup."; \ [2025-11-26T22:47:03.636Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_1764196669606/renaissance-als_0"; \ [2025-11-26T22:47:03.636Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_1764196669606/renaissance-als_0"; \ [2025-11-26T22:47:03.636Z] echo ""; echo "TESTING:"; \ [2025-11-26T22:47:03.636Z] "/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_1764196669606/renaissance-als_0"/als.json" als; \ [2025-11-26T22:47:03.636Z] 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_1764196669606/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-26T22:47:03.636Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-26T22:47:03.636Z] echo "Nothing to be done for teardown."; \ [2025-11-26T22:47:03.636Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_1764196669606/TestTargetResult"; [2025-11-26T22:47:03.636Z] [2025-11-26T22:47:03.636Z] TEST SETUP: [2025-11-26T22:47:03.636Z] Nothing to be done for setup. [2025-11-26T22:47:03.636Z] [2025-11-26T22:47:03.636Z] TESTING: [2025-11-26T22:47:26.593Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-26T22:47:45.720Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-26T22:47:45.720Z] GC before operation: completed in 203.436 ms, heap usage 64.133 MB -> 35.571 MB. [2025-11-26T22:49:09.064Z] ====== als (apache-spark) [default], iteration 0 completed (79444.763 ms) ====== [2025-11-26T22:49:09.064Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-26T22:49:09.064Z] GC before operation: completed in 708.020 ms, heap usage 440.697 MB -> 75.817 MB. [2025-11-26T22:49:42.326Z] ====== als (apache-spark) [default], iteration 1 completed (28784.906 ms) ====== [2025-11-26T22:49:42.326Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-26T22:49:42.326Z] GC before operation: completed in 850.980 ms, heap usage 1.094 GB -> 77.514 MB. [2025-11-26T22:50:09.990Z] ====== als (apache-spark) [default], iteration 2 completed (25425.898 ms) ====== [2025-11-26T22:50:09.990Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-26T22:50:09.990Z] GC before operation: completed in 645.306 ms, heap usage 242.960 MB -> 77.036 MB. [2025-11-26T22:50:33.683Z] ====== als (apache-spark) [default], iteration 3 completed (23389.766 ms) ====== [2025-11-26T22:50:33.683Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-26T22:50:33.683Z] GC before operation: completed in 674.411 ms, heap usage 195.402 MB -> 77.550 MB. [2025-11-26T22:50:56.659Z] ====== als (apache-spark) [default], iteration 4 completed (22183.262 ms) ====== [2025-11-26T22:50:56.659Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-26T22:50:56.659Z] GC before operation: completed in 706.667 ms, heap usage 286.184 MB -> 78.248 MB. [2025-11-26T22:51:24.301Z] ====== als (apache-spark) [default], iteration 5 completed (22283.099 ms) ====== [2025-11-26T22:51:24.301Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-26T22:51:24.301Z] GC before operation: completed in 738.108 ms, heap usage 236.005 MB -> 78.844 MB. [2025-11-26T22:51:47.272Z] ====== als (apache-spark) [default], iteration 6 completed (22539.156 ms) ====== [2025-11-26T22:51:47.272Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-26T22:51:47.272Z] GC before operation: completed in 782.789 ms, heap usage 780.405 MB -> 80.232 MB. [2025-11-26T22:52:10.332Z] ====== als (apache-spark) [default], iteration 7 completed (22260.203 ms) ====== [2025-11-26T22:52:10.332Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-26T22:52:10.332Z] GC before operation: completed in 870.861 ms, heap usage 1.102 GB -> 81.029 MB. [2025-11-26T22:52:33.311Z] ====== als (apache-spark) [default], iteration 8 completed (21920.234 ms) ====== [2025-11-26T22:52:33.311Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-26T22:52:33.311Z] GC before operation: completed in 820.906 ms, heap usage 826.620 MB -> 81.136 MB. [2025-11-26T22:52:56.330Z] ====== als (apache-spark) [default], iteration 9 completed (22211.808 ms) ====== [2025-11-26T22:52:56.330Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-26T22:52:56.658Z] GC before operation: completed in 832.045 ms, heap usage 357.159 MB -> 80.776 MB. [2025-11-26T22:53:19.659Z] ====== als (apache-spark) [default], iteration 10 completed (21988.049 ms) ====== [2025-11-26T22:53:19.659Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-26T22:53:20.384Z] GC before operation: completed in 863.743 ms, heap usage 722.448 MB -> 81.541 MB. [2025-11-26T22:53:48.032Z] ====== als (apache-spark) [default], iteration 11 completed (22248.171 ms) ====== [2025-11-26T22:53:48.032Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-26T22:53:48.032Z] GC before operation: completed in 831.818 ms, heap usage 184.296 MB -> 81.199 MB. [2025-11-26T22:54:11.029Z] ====== als (apache-spark) [default], iteration 12 completed (21931.151 ms) ====== [2025-11-26T22:54:11.029Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-26T22:54:11.029Z] GC before operation: completed in 867.879 ms, heap usage 453.925 MB -> 81.756 MB. [2025-11-26T22:54:34.017Z] ====== als (apache-spark) [default], iteration 13 completed (21664.797 ms) ====== [2025-11-26T22:54:34.017Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-26T22:54:34.017Z] GC before operation: completed in 869.748 ms, heap usage 384.732 MB -> 81.804 MB. [2025-11-26T22:54:57.461Z] ====== als (apache-spark) [default], iteration 14 completed (21990.817 ms) ====== [2025-11-26T22:54:57.461Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-26T22:54:57.461Z] GC before operation: completed in 887.012 ms, heap usage 398.189 MB -> 82.100 MB. [2025-11-26T22:55:20.430Z] ====== als (apache-spark) [default], iteration 15 completed (21125.631 ms) ====== [2025-11-26T22:55:20.430Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-26T22:55:20.430Z] GC before operation: completed in 914.253 ms, heap usage 313.999 MB -> 82.497 MB. [2025-11-26T22:55:43.414Z] ====== als (apache-spark) [default], iteration 16 completed (21230.199 ms) ====== [2025-11-26T22:55:43.414Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-26T22:55:43.414Z] GC before operation: completed in 902.186 ms, heap usage 579.456 MB -> 83.021 MB. [2025-11-26T22:56:06.401Z] ====== als (apache-spark) [default], iteration 17 completed (21399.091 ms) ====== [2025-11-26T22:56:06.401Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-26T22:56:06.401Z] GC before operation: completed in 913.661 ms, heap usage 356.824 MB -> 82.964 MB. [2025-11-26T22:56:29.358Z] ====== als (apache-spark) [default], iteration 18 completed (20691.966 ms) ====== [2025-11-26T22:56:29.358Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-26T22:56:29.358Z] GC before operation: completed in 943.092 ms, heap usage 175.746 MB -> 82.933 MB. [2025-11-26T22:56:52.363Z] ====== als (apache-spark) [default], iteration 19 completed (21116.519 ms) ====== [2025-11-26T22:56:52.363Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-26T22:56:52.363Z] GC before operation: completed in 949.548 ms, heap usage 446.685 MB -> 83.637 MB. [2025-11-26T22:57:15.372Z] ====== als (apache-spark) [default], iteration 20 completed (21618.261 ms) ====== [2025-11-26T22:57:15.372Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-26T22:57:15.372Z] GC before operation: completed in 921.757 ms, heap usage 505.104 MB -> 83.895 MB. [2025-11-26T22:57:38.354Z] ====== als (apache-spark) [default], iteration 21 completed (21246.775 ms) ====== [2025-11-26T22:57:38.354Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-26T22:57:38.354Z] GC before operation: completed in 938.800 ms, heap usage 527.599 MB -> 84.150 MB. [2025-11-26T22:58:01.363Z] ====== als (apache-spark) [default], iteration 22 completed (20853.296 ms) ====== [2025-11-26T22:58:01.363Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-26T22:58:01.363Z] GC before operation: completed in 946.041 ms, heap usage 543.453 MB -> 84.649 MB. [2025-11-26T22:58:24.351Z] ====== als (apache-spark) [default], iteration 23 completed (20753.956 ms) ====== [2025-11-26T22:58:24.351Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-26T22:58:24.351Z] GC before operation: completed in 934.124 ms, heap usage 319.456 MB -> 84.386 MB. [2025-11-26T22:58:43.418Z] ====== als (apache-spark) [default], iteration 24 completed (20778.529 ms) ====== [2025-11-26T22:58:43.418Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-26T22:58:44.137Z] GC before operation: completed in 973.058 ms, heap usage 423.249 MB -> 85.754 MB. [2025-11-26T22:59:07.109Z] ====== als (apache-spark) [default], iteration 25 completed (20677.057 ms) ====== [2025-11-26T22:59:07.109Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-26T22:59:07.109Z] GC before operation: completed in 1032.677 ms, heap usage 1.102 GB -> 86.148 MB. [2025-11-26T22:59:30.689Z] ====== als (apache-spark) [default], iteration 26 completed (21701.892 ms) ====== [2025-11-26T22:59:30.689Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-26T22:59:30.689Z] GC before operation: completed in 1018.005 ms, heap usage 471.891 MB -> 85.450 MB. [2025-11-26T22:59:53.655Z] ====== als (apache-spark) [default], iteration 27 completed (20160.530 ms) ====== [2025-11-26T22:59:53.655Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-26T22:59:53.655Z] GC before operation: completed in 966.302 ms, heap usage 300.265 MB -> 85.170 MB. [2025-11-26T23:00:13.664Z] ====== als (apache-spark) [default], iteration 28 completed (20520.206 ms) ====== [2025-11-26T23:00:13.664Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-26T23:00:14.871Z] GC before operation: completed in 924.990 ms, heap usage 1.623 GB -> 86.878 MB. [2025-11-26T23:00:36.054Z] ====== als (apache-spark) [default], iteration 29 completed (20472.389 ms) ====== [2025-11-26T23:00:38.042Z] ----------------------------------- [2025-11-26T23:00:38.042Z] renaissance-als_0_PASSED [2025-11-26T23:00:38.042Z] ----------------------------------- [2025-11-26T23:00:38.042Z] [2025-11-26T23:00:38.042Z] TEST TEARDOWN: [2025-11-26T23:00:38.042Z] Nothing to be done for teardown. [2025-11-26T23:00:38.042Z] renaissance-als_0 Finish Time: Wed Nov 26 23:00:38 2025 Epoch Time (ms): 1764198038013