[2025-09-25T15:09:21.916Z] Running test renaissance-als_0 ... [2025-09-25T15:09:21.916Z] =============================================== [2025-09-25T15:09:21.916Z] renaissance-als_0 Start Time: Thu Sep 25 15:09:21 2025 Epoch Time (ms): 1758812961810 [2025-09-25T15:09:21.916Z] variation: NoOptions [2025-09-25T15:09:21.916Z] JVM_OPTIONS: [2025-09-25T15:09:21.916Z] { \ [2025-09-25T15:09:21.916Z] echo ""; echo "TEST SETUP:"; \ [2025-09-25T15:09:21.916Z] echo "Nothing to be done for setup."; \ [2025-09-25T15:09:21.916Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17588129608775/renaissance-als_0"; \ [2025-09-25T15:09:21.916Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17588129608775/renaissance-als_0"; \ [2025-09-25T15:09:21.916Z] echo ""; echo "TESTING:"; \ [2025-09-25T15:09:21.916Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17588129608775/renaissance-als_0"/als.json" als; \ [2025-09-25T15:09:21.916Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17588129608775/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-25T15:09:21.916Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-25T15:09:21.916Z] echo "Nothing to be done for teardown."; \ [2025-09-25T15:09:21.916Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17588129608775/TestTargetResult"; [2025-09-25T15:09:21.916Z] [2025-09-25T15:09:21.916Z] TEST SETUP: [2025-09-25T15:09:21.916Z] Nothing to be done for setup. [2025-09-25T15:09:21.916Z] [2025-09-25T15:09:21.916Z] TESTING: [2025-09-25T15:09:49.628Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-09-25T15:10:08.766Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-09-25T15:10:08.766Z] GC before operation: completed in 187.085 ms, heap usage 56.792 MB -> 36.311 MB. [2025-09-25T15:11:46.860Z] ====== als (apache-spark) [default], iteration 0 completed (85126.051 ms) ====== [2025-09-25T15:11:46.860Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-09-25T15:11:46.860Z] GC before operation: completed in 566.613 ms, heap usage 374.239 MB -> 76.558 MB. [2025-09-25T15:12:05.980Z] ====== als (apache-spark) [default], iteration 1 completed (28791.090 ms) ====== [2025-09-25T15:12:05.980Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-09-25T15:12:05.980Z] GC before operation: completed in 619.809 ms, heap usage 1.139 GB -> 78.560 MB. [2025-09-25T15:12:33.691Z] ====== als (apache-spark) [default], iteration 2 completed (23844.448 ms) ====== [2025-09-25T15:12:33.691Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-09-25T15:12:33.691Z] GC before operation: completed in 684.723 ms, heap usage 240.155 MB -> 78.038 MB. [2025-09-25T15:12:56.990Z] ====== als (apache-spark) [default], iteration 3 completed (23029.719 ms) ====== [2025-09-25T15:12:56.990Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-09-25T15:12:56.990Z] GC before operation: completed in 729.911 ms, heap usage 1.263 GB -> 79.857 MB. [2025-09-25T15:13:20.020Z] ====== als (apache-spark) [default], iteration 4 completed (21261.768 ms) ====== [2025-09-25T15:13:20.020Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-09-25T15:13:20.020Z] GC before operation: completed in 745.176 ms, heap usage 162.968 MB -> 79.235 MB. [2025-09-25T15:13:43.073Z] ====== als (apache-spark) [default], iteration 5 completed (21108.309 ms) ====== [2025-09-25T15:13:43.073Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-09-25T15:13:43.073Z] GC before operation: completed in 788.035 ms, heap usage 817.378 MB -> 80.441 MB. [2025-09-25T15:14:06.094Z] ====== als (apache-spark) [default], iteration 6 completed (20607.789 ms) ====== [2025-09-25T15:14:06.094Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-09-25T15:14:06.094Z] GC before operation: completed in 824.062 ms, heap usage 753.960 MB -> 81.100 MB. [2025-09-25T15:14:29.594Z] ====== als (apache-spark) [default], iteration 7 completed (20780.671 ms) ====== [2025-09-25T15:14:29.594Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-09-25T15:14:29.594Z] GC before operation: completed in 838.453 ms, heap usage 524.870 MB -> 81.191 MB. [2025-09-25T15:14:52.678Z] ====== als (apache-spark) [default], iteration 8 completed (20791.081 ms) ====== [2025-09-25T15:14:52.678Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-09-25T15:14:52.678Z] GC before operation: completed in 835.783 ms, heap usage 663.820 MB -> 82.049 MB. [2025-09-25T15:15:15.699Z] ====== als (apache-spark) [default], iteration 9 completed (20737.854 ms) ====== [2025-09-25T15:15:15.699Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-09-25T15:15:15.699Z] GC before operation: completed in 877.407 ms, heap usage 420.047 MB -> 81.815 MB. [2025-09-25T15:15:38.727Z] ====== als (apache-spark) [default], iteration 10 completed (20949.044 ms) ====== [2025-09-25T15:15:38.727Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-09-25T15:15:38.727Z] GC before operation: completed in 886.345 ms, heap usage 513.766 MB -> 82.206 MB. [2025-09-25T15:15:57.844Z] ====== als (apache-spark) [default], iteration 11 completed (20415.264 ms) ====== [2025-09-25T15:15:57.844Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-09-25T15:15:58.573Z] GC before operation: completed in 886.035 ms, heap usage 326.333 MB -> 82.278 MB. [2025-09-25T15:16:21.889Z] ====== als (apache-spark) [default], iteration 12 completed (20904.911 ms) ====== [2025-09-25T15:16:21.889Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-09-25T15:16:21.889Z] GC before operation: completed in 945.716 ms, heap usage 652.246 MB -> 82.937 MB. [2025-09-25T15:16:44.905Z] ====== als (apache-spark) [default], iteration 13 completed (20837.859 ms) ====== [2025-09-25T15:16:44.905Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-09-25T15:16:44.905Z] GC before operation: completed in 882.511 ms, heap usage 308.338 MB -> 82.867 MB. [2025-09-25T15:17:07.929Z] ====== als (apache-spark) [default], iteration 14 completed (20241.531 ms) ====== [2025-09-25T15:17:07.929Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-09-25T15:17:07.929Z] GC before operation: completed in 899.148 ms, heap usage 769.085 MB -> 83.811 MB. [2025-09-25T15:17:27.098Z] ====== als (apache-spark) [default], iteration 15 completed (19827.788 ms) ====== [2025-09-25T15:17:27.098Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-09-25T15:17:27.430Z] GC before operation: completed in 947.492 ms, heap usage 942.301 MB -> 84.181 MB. [2025-09-25T15:17:50.527Z] ====== als (apache-spark) [default], iteration 16 completed (20059.493 ms) ====== [2025-09-25T15:17:50.527Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-09-25T15:17:50.527Z] GC before operation: completed in 916.617 ms, heap usage 522.622 MB -> 84.042 MB. [2025-09-25T15:18:09.797Z] ====== als (apache-spark) [default], iteration 17 completed (19526.572 ms) ====== [2025-09-25T15:18:09.797Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-09-25T15:18:10.979Z] GC before operation: completed in 947.656 ms, heap usage 892.730 MB -> 84.753 MB. [2025-09-25T15:18:34.019Z] ====== als (apache-spark) [default], iteration 18 completed (19870.936 ms) ====== [2025-09-25T15:18:34.019Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-09-25T15:18:34.019Z] GC before operation: completed in 956.196 ms, heap usage 395.637 MB -> 84.398 MB. [2025-09-25T15:18:57.048Z] ====== als (apache-spark) [default], iteration 19 completed (20312.005 ms) ====== [2025-09-25T15:18:57.048Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-09-25T15:18:57.048Z] GC before operation: completed in 960.962 ms, heap usage 491.184 MB -> 84.787 MB. [2025-09-25T15:19:16.200Z] ====== als (apache-spark) [default], iteration 20 completed (20223.993 ms) ====== [2025-09-25T15:19:16.200Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-09-25T15:19:16.931Z] GC before operation: completed in 978.313 ms, heap usage 552.348 MB -> 85.159 MB. [2025-09-25T15:19:39.979Z] ====== als (apache-spark) [default], iteration 21 completed (19743.215 ms) ====== [2025-09-25T15:19:39.979Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-09-25T15:19:39.979Z] GC before operation: completed in 942.942 ms, heap usage 846.069 MB -> 85.739 MB. [2025-09-25T15:19:59.168Z] ====== als (apache-spark) [default], iteration 22 completed (19746.449 ms) ====== [2025-09-25T15:19:59.168Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-09-25T15:19:59.901Z] GC before operation: completed in 936.057 ms, heap usage 385.810 MB -> 85.362 MB. [2025-09-25T15:20:22.926Z] ====== als (apache-spark) [default], iteration 23 completed (19394.190 ms) ====== [2025-09-25T15:20:22.927Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-09-25T15:20:22.927Z] GC before operation: completed in 950.207 ms, heap usage 425.986 MB -> 85.710 MB. [2025-09-25T15:20:42.065Z] ====== als (apache-spark) [default], iteration 24 completed (19670.518 ms) ====== [2025-09-25T15:20:42.065Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-09-25T15:20:42.795Z] GC before operation: completed in 983.509 ms, heap usage 562.782 MB -> 86.198 MB. [2025-09-25T15:21:05.830Z] ====== als (apache-spark) [default], iteration 25 completed (19602.422 ms) ====== [2025-09-25T15:21:05.830Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-09-25T15:21:05.830Z] GC before operation: completed in 976.296 ms, heap usage 373.284 MB -> 86.124 MB. [2025-09-25T15:21:24.976Z] ====== als (apache-spark) [default], iteration 26 completed (19505.467 ms) ====== [2025-09-25T15:21:24.976Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-09-25T15:21:25.704Z] GC before operation: completed in 995.621 ms, heap usage 722.538 MB -> 86.726 MB. [2025-09-25T15:21:48.769Z] ====== als (apache-spark) [default], iteration 27 completed (19082.338 ms) ====== [2025-09-25T15:21:48.769Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-09-25T15:21:48.769Z] GC before operation: completed in 992.588 ms, heap usage 430.695 MB -> 86.621 MB. [2025-09-25T15:22:07.928Z] ====== als (apache-spark) [default], iteration 28 completed (19012.201 ms) ====== [2025-09-25T15:22:07.928Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-09-25T15:22:07.928Z] GC before operation: completed in 1004.468 ms, heap usage 930.411 MB -> 87.153 MB. [2025-09-25T15:22:30.977Z] ====== als (apache-spark) [default], iteration 29 completed (19112.828 ms) ====== [2025-09-25T15:22:30.977Z] ----------------------------------- [2025-09-25T15:22:30.977Z] renaissance-als_0_PASSED [2025-09-25T15:22:30.977Z] ----------------------------------- [2025-09-25T15:22:30.977Z] [2025-09-25T15:22:30.977Z] TEST TEARDOWN: [2025-09-25T15:22:30.977Z] Nothing to be done for teardown. [2025-09-25T15:22:30.977Z] renaissance-als_0 Finish Time: Thu Sep 25 15:22:29 2025 Epoch Time (ms): 1758813749922