[2025-06-18T23:30:01.224Z] Running test renaissance-als_0 ... [2025-06-18T23:30:01.224Z] =============================================== [2025-06-18T23:30:01.548Z] renaissance-als_0 Start Time: Wed Jun 18 23:30:01 2025 Epoch Time (ms): 1750289401222 [2025-06-18T23:30:01.548Z] variation: NoOptions [2025-06-18T23:30:01.548Z] JVM_OPTIONS: [2025-06-18T23:30:01.548Z] { \ [2025-06-18T23:30:01.548Z] echo ""; echo "TEST SETUP:"; \ [2025-06-18T23:30:01.548Z] echo "Nothing to be done for setup."; \ [2025-06-18T23:30:01.548Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_1750289400253/renaissance-als_0"; \ [2025-06-18T23:30:01.548Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_1750289400253/renaissance-als_0"; \ [2025-06-18T23:30:01.548Z] echo ""; echo "TESTING:"; \ [2025-06-18T23:30:01.548Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/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/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_1750289400253/renaissance-als_0"/als.json" als; \ [2025-06-18T23:30:01.548Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_1750289400253/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-18T23:30:01.548Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-18T23:30:01.548Z] echo "Nothing to be done for teardown."; \ [2025-06-18T23:30:01.548Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_1750289400253/TestTargetResult"; [2025-06-18T23:30:01.548Z] [2025-06-18T23:30:01.548Z] TEST SETUP: [2025-06-18T23:30:01.548Z] Nothing to be done for setup. [2025-06-18T23:30:01.548Z] [2025-06-18T23:30:01.548Z] TESTING: [2025-06-18T23:30:24.537Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-18T23:30:47.505Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-18T23:30:47.505Z] GC before operation: completed in 219.655 ms, heap usage 56.049 MB -> 35.472 MB. [2025-06-18T23:32:10.842Z] ====== als (apache-spark) [default], iteration 0 completed (78330.056 ms) ====== [2025-06-18T23:32:10.842Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-18T23:32:10.842Z] GC before operation: completed in 603.571 ms, heap usage 248.988 MB -> 75.519 MB. [2025-06-18T23:32:38.523Z] ====== als (apache-spark) [default], iteration 1 completed (28339.787 ms) ====== [2025-06-18T23:32:38.523Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-18T23:32:38.523Z] GC before operation: completed in 645.356 ms, heap usage 347.175 MB -> 76.468 MB. [2025-06-18T23:33:06.184Z] ====== als (apache-spark) [default], iteration 2 completed (23702.238 ms) ====== [2025-06-18T23:33:06.184Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-18T23:33:06.184Z] GC before operation: completed in 703.488 ms, heap usage 279.734 MB -> 77.115 MB. [2025-06-18T23:33:29.188Z] ====== als (apache-spark) [default], iteration 3 completed (22315.596 ms) ====== [2025-06-18T23:33:29.188Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-18T23:33:29.188Z] GC before operation: completed in 773.743 ms, heap usage 124.963 MB -> 77.614 MB. [2025-06-18T23:33:52.204Z] ====== als (apache-spark) [default], iteration 4 completed (22824.620 ms) ====== [2025-06-18T23:33:52.204Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-18T23:33:52.530Z] GC before operation: completed in 793.707 ms, heap usage 386.965 MB -> 78.356 MB. [2025-06-18T23:34:15.523Z] ====== als (apache-spark) [default], iteration 5 completed (20974.780 ms) ====== [2025-06-18T23:34:15.523Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-18T23:34:15.523Z] GC before operation: completed in 850.523 ms, heap usage 279.459 MB -> 78.838 MB. [2025-06-18T23:34:38.534Z] ====== als (apache-spark) [default], iteration 6 completed (20309.070 ms) ====== [2025-06-18T23:34:38.534Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-18T23:34:38.534Z] GC before operation: completed in 811.893 ms, heap usage 417.016 MB -> 79.391 MB. [2025-06-18T23:35:01.571Z] ====== als (apache-spark) [default], iteration 7 completed (20279.076 ms) ====== [2025-06-18T23:35:01.571Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-18T23:35:01.571Z] GC before operation: completed in 1096.929 ms, heap usage 200.970 MB -> 79.765 MB. [2025-06-18T23:35:24.564Z] ====== als (apache-spark) [default], iteration 8 completed (20256.507 ms) ====== [2025-06-18T23:35:24.564Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-18T23:35:24.564Z] GC before operation: completed in 857.093 ms, heap usage 128.781 MB -> 80.321 MB. [2025-06-18T23:35:43.665Z] ====== als (apache-spark) [default], iteration 9 completed (19679.600 ms) ====== [2025-06-18T23:35:43.665Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-18T23:35:44.008Z] GC before operation: completed in 894.305 ms, heap usage 445.640 MB -> 80.975 MB. [2025-06-18T23:36:07.051Z] ====== als (apache-spark) [default], iteration 10 completed (20216.285 ms) ====== [2025-06-18T23:36:07.051Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-18T23:36:07.051Z] GC before operation: completed in 970.251 ms, heap usage 721.984 MB -> 81.482 MB. [2025-06-18T23:36:30.443Z] ====== als (apache-spark) [default], iteration 11 completed (20421.931 ms) ====== [2025-06-18T23:36:30.443Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-18T23:36:30.443Z] GC before operation: completed in 792.225 ms, heap usage 152.251 MB -> 81.095 MB. [2025-06-18T23:36:53.436Z] ====== als (apache-spark) [default], iteration 12 completed (20278.969 ms) ====== [2025-06-18T23:36:53.436Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-18T23:36:53.436Z] GC before operation: completed in 841.003 ms, heap usage 1.024 GB -> 82.570 MB. [2025-06-18T23:37:12.563Z] ====== als (apache-spark) [default], iteration 13 completed (20632.716 ms) ====== [2025-06-18T23:37:12.563Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-18T23:37:13.281Z] GC before operation: completed in 795.568 ms, heap usage 215.273 MB -> 81.518 MB. [2025-06-18T23:37:36.279Z] ====== als (apache-spark) [default], iteration 14 completed (20193.674 ms) ====== [2025-06-18T23:37:36.279Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-18T23:37:36.279Z] GC before operation: completed in 770.604 ms, heap usage 741.020 MB -> 82.515 MB. [2025-06-18T23:37:59.299Z] ====== als (apache-spark) [default], iteration 15 completed (19879.429 ms) ====== [2025-06-18T23:37:59.300Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-18T23:37:59.300Z] GC before operation: completed in 792.161 ms, heap usage 219.663 MB -> 82.124 MB. [2025-06-18T23:38:18.386Z] ====== als (apache-spark) [default], iteration 16 completed (20178.293 ms) ====== [2025-06-18T23:38:18.386Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-18T23:38:18.717Z] GC before operation: completed in 783.008 ms, heap usage 284.443 MB -> 82.534 MB. [2025-06-18T23:38:41.762Z] ====== als (apache-spark) [default], iteration 17 completed (19774.416 ms) ====== [2025-06-18T23:38:41.762Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-18T23:38:41.762Z] GC before operation: completed in 840.028 ms, heap usage 213.884 MB -> 82.605 MB. [2025-06-18T23:39:04.754Z] ====== als (apache-spark) [default], iteration 18 completed (19868.186 ms) ====== [2025-06-18T23:39:04.754Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-18T23:39:04.754Z] GC before operation: completed in 797.736 ms, heap usage 400.296 MB -> 83.250 MB. [2025-06-18T23:39:23.857Z] ====== als (apache-spark) [default], iteration 19 completed (19609.899 ms) ====== [2025-06-18T23:39:23.857Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-18T23:39:23.857Z] GC before operation: completed in 810.512 ms, heap usage 586.864 MB -> 83.729 MB. [2025-06-18T23:39:46.864Z] ====== als (apache-spark) [default], iteration 20 completed (20029.060 ms) ====== [2025-06-18T23:39:46.864Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-18T23:39:46.864Z] GC before operation: completed in 807.927 ms, heap usage 300.176 MB -> 83.555 MB. [2025-06-18T23:40:05.982Z] ====== als (apache-spark) [default], iteration 21 completed (19460.249 ms) ====== [2025-06-18T23:40:05.982Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-18T23:40:06.310Z] GC before operation: completed in 825.216 ms, heap usage 583.987 MB -> 84.156 MB. [2025-06-18T23:40:29.293Z] ====== als (apache-spark) [default], iteration 22 completed (19030.394 ms) ====== [2025-06-18T23:40:29.293Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-18T23:40:29.293Z] GC before operation: completed in 812.799 ms, heap usage 420.127 MB -> 84.021 MB. [2025-06-18T23:40:48.697Z] ====== als (apache-spark) [default], iteration 23 completed (19143.542 ms) ====== [2025-06-18T23:40:48.697Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-18T23:40:48.697Z] GC before operation: completed in 836.719 ms, heap usage 271.393 MB -> 84.304 MB. [2025-06-18T23:41:11.717Z] ====== als (apache-spark) [default], iteration 24 completed (19476.586 ms) ====== [2025-06-18T23:41:11.717Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-18T23:41:11.717Z] GC before operation: completed in 872.552 ms, heap usage 290.863 MB -> 84.491 MB. [2025-06-18T23:41:30.795Z] ====== als (apache-spark) [default], iteration 25 completed (19488.796 ms) ====== [2025-06-18T23:41:30.795Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-18T23:41:30.795Z] GC before operation: completed in 849.668 ms, heap usage 236.555 MB -> 84.647 MB. [2025-06-18T23:41:51.620Z] ====== als (apache-spark) [default], iteration 26 completed (19272.610 ms) ====== [2025-06-18T23:41:51.621Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-18T23:41:51.621Z] GC before operation: completed in 809.047 ms, heap usage 407.913 MB -> 85.022 MB. [2025-06-18T23:42:14.607Z] ====== als (apache-spark) [default], iteration 27 completed (19368.692 ms) ====== [2025-06-18T23:42:14.607Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-18T23:42:14.607Z] GC before operation: completed in 863.986 ms, heap usage 1.148 GB -> 86.395 MB. [2025-06-18T23:42:33.674Z] ====== als (apache-spark) [default], iteration 28 completed (18830.034 ms) ====== [2025-06-18T23:42:33.674Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-18T23:42:33.674Z] GC before operation: completed in 774.993 ms, heap usage 191.154 MB -> 85.003 MB. [2025-06-18T23:42:52.750Z] ====== als (apache-spark) [default], iteration 29 completed (18830.090 ms) ====== [2025-06-18T23:42:56.507Z] ----------------------------------- [2025-06-18T23:42:56.507Z] renaissance-als_0_PASSED [2025-06-18T23:42:56.507Z] ----------------------------------- [2025-06-18T23:42:56.507Z] [2025-06-18T23:42:56.507Z] TEST TEARDOWN: [2025-06-18T23:42:56.507Z] Nothing to be done for teardown. [2025-06-18T23:42:56.507Z] renaissance-als_0 Finish Time: Wed Jun 18 23:42:55 2025 Epoch Time (ms): 1750290175831