[2025-11-26T22:46:33.143Z] Running test renaissance-als_0 ... [2025-11-26T22:46:33.143Z] =============================================== [2025-11-26T22:46:33.143Z] renaissance-als_0 Start Time: Wed Nov 26 22:46:32 2025 Epoch Time (ms): 1764197192822 [2025-11-26T22:46:33.143Z] variation: NoOptions [2025-11-26T22:46:33.143Z] JVM_OPTIONS: [2025-11-26T22:46:33.143Z] { \ [2025-11-26T22:46:33.143Z] echo ""; echo "TEST SETUP:"; \ [2025-11-26T22:46:33.143Z] echo "Nothing to be done for setup."; \ [2025-11-26T22:46:33.143Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17641970042591/renaissance-als_0"; \ [2025-11-26T22:46:33.143Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17641970042591/renaissance-als_0"; \ [2025-11-26T22:46:33.143Z] echo ""; echo "TESTING:"; \ [2025-11-26T22:46:33.143Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_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_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17641970042591/renaissance-als_0"/als.json" als; \ [2025-11-26T22:46:33.143Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17641970042591/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-26T22:46:33.143Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-26T22:46:33.143Z] echo "Nothing to be done for teardown."; \ [2025-11-26T22:46:33.143Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17641970042591/TestTargetResult"; [2025-11-26T22:46:33.143Z] [2025-11-26T22:46:33.143Z] TEST SETUP: [2025-11-26T22:46:33.143Z] Nothing to be done for setup. [2025-11-26T22:46:33.143Z] [2025-11-26T22:46:33.143Z] TESTING: [2025-11-26T22:46:37.893Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-11-26T22:46:41.677Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-26T22:46:41.677Z] GC before operation: completed in 40.880 ms, heap usage 72.426 MB -> 34.654 MB. [2025-11-26T22:47:09.845Z] ====== als (apache-spark) [default], iteration 0 completed (27121.172 ms) ====== [2025-11-26T22:47:09.845Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-26T22:47:09.845Z] GC before operation: completed in 130.332 ms, heap usage 412.190 MB -> 61.676 MB. [2025-11-26T22:47:22.954Z] ====== als (apache-spark) [default], iteration 1 completed (13011.321 ms) ====== [2025-11-26T22:47:22.954Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-26T22:47:22.954Z] GC before operation: completed in 109.890 ms, heap usage 626.516 MB -> 62.727 MB. [2025-11-26T22:47:33.776Z] ====== als (apache-spark) [default], iteration 2 completed (10196.270 ms) ====== [2025-11-26T22:47:33.776Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-26T22:47:33.776Z] GC before operation: completed in 83.462 ms, heap usage 358.531 MB -> 63.018 MB. [2025-11-26T22:47:44.583Z] ====== als (apache-spark) [default], iteration 3 completed (9979.825 ms) ====== [2025-11-26T22:47:44.583Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-26T22:47:44.583Z] GC before operation: completed in 85.581 ms, heap usage 408.468 MB -> 63.582 MB. [2025-11-26T22:47:55.377Z] ====== als (apache-spark) [default], iteration 4 completed (9722.770 ms) ====== [2025-11-26T22:47:55.377Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-26T22:47:55.377Z] GC before operation: completed in 89.148 ms, heap usage 773.978 MB -> 64.720 MB. [2025-11-26T22:48:04.363Z] ====== als (apache-spark) [default], iteration 5 completed (9530.236 ms) ====== [2025-11-26T22:48:04.364Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-26T22:48:04.364Z] GC before operation: completed in 88.469 ms, heap usage 579.818 MB -> 65.077 MB. [2025-11-26T22:48:13.257Z] ====== als (apache-spark) [default], iteration 6 completed (9375.771 ms) ====== [2025-11-26T22:48:13.257Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-26T22:48:13.584Z] GC before operation: completed in 147.072 ms, heap usage 617.872 MB -> 65.594 MB. [2025-11-26T22:48:24.383Z] ====== als (apache-spark) [default], iteration 7 completed (9313.090 ms) ====== [2025-11-26T22:48:24.383Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-26T22:48:24.383Z] GC before operation: completed in 85.872 ms, heap usage 628.556 MB -> 66.298 MB. [2025-11-26T22:48:33.276Z] ====== als (apache-spark) [default], iteration 8 completed (9264.698 ms) ====== [2025-11-26T22:48:33.276Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-26T22:48:33.276Z] GC before operation: completed in 134.716 ms, heap usage 292.449 MB -> 66.242 MB. [2025-11-26T22:48:42.215Z] ====== als (apache-spark) [default], iteration 9 completed (9224.819 ms) ====== [2025-11-26T22:48:42.215Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-26T22:48:42.215Z] GC before operation: completed in 95.588 ms, heap usage 135.010 MB -> 66.397 MB. [2025-11-26T22:48:53.025Z] ====== als (apache-spark) [default], iteration 10 completed (10058.165 ms) ====== [2025-11-26T22:48:53.025Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-26T22:48:53.025Z] GC before operation: completed in 122.037 ms, heap usage 277.458 MB -> 66.815 MB. [2025-11-26T22:49:03.832Z] ====== als (apache-spark) [default], iteration 11 completed (9478.809 ms) ====== [2025-11-26T22:49:03.832Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-26T22:49:03.832Z] GC before operation: completed in 89.913 ms, heap usage 453.960 MB -> 67.355 MB. [2025-11-26T22:49:12.873Z] ====== als (apache-spark) [default], iteration 12 completed (9247.015 ms) ====== [2025-11-26T22:49:12.873Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-26T22:49:12.873Z] GC before operation: completed in 95.716 ms, heap usage 364.105 MB -> 67.388 MB. [2025-11-26T22:49:21.769Z] ====== als (apache-spark) [default], iteration 13 completed (9343.983 ms) ====== [2025-11-26T22:49:21.769Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-26T22:49:21.769Z] GC before operation: completed in 114.637 ms, heap usage 516.765 MB -> 67.932 MB. [2025-11-26T22:49:32.573Z] ====== als (apache-spark) [default], iteration 14 completed (9563.942 ms) ====== [2025-11-26T22:49:32.573Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-26T22:49:32.573Z] GC before operation: completed in 108.324 ms, heap usage 635.787 MB -> 68.252 MB. [2025-11-26T22:49:41.474Z] ====== als (apache-spark) [default], iteration 15 completed (9269.106 ms) ====== [2025-11-26T22:49:41.474Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-26T22:49:41.474Z] GC before operation: completed in 95.461 ms, heap usage 391.747 MB -> 68.118 MB. [2025-11-26T22:49:50.351Z] ====== als (apache-spark) [default], iteration 16 completed (9114.589 ms) ====== [2025-11-26T22:49:50.351Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-26T22:49:50.351Z] GC before operation: completed in 95.556 ms, heap usage 370.069 MB -> 68.435 MB. [2025-11-26T22:50:01.285Z] ====== als (apache-spark) [default], iteration 17 completed (9064.514 ms) ====== [2025-11-26T22:50:01.285Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-26T22:50:01.285Z] GC before operation: completed in 94.373 ms, heap usage 190.344 MB -> 68.472 MB. [2025-11-26T22:50:10.198Z] ====== als (apache-spark) [default], iteration 18 completed (9422.882 ms) ====== [2025-11-26T22:50:10.199Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-26T22:50:10.199Z] GC before operation: completed in 107.702 ms, heap usage 673.600 MB -> 69.404 MB. [2025-11-26T22:50:19.081Z] ====== als (apache-spark) [default], iteration 19 completed (9106.582 ms) ====== [2025-11-26T22:50:19.081Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-26T22:50:19.081Z] GC before operation: completed in 99.943 ms, heap usage 635.256 MB -> 69.618 MB. [2025-11-26T22:50:29.968Z] ====== als (apache-spark) [default], iteration 20 completed (9628.580 ms) ====== [2025-11-26T22:50:29.968Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-26T22:50:29.968Z] GC before operation: completed in 117.323 ms, heap usage 295.360 MB -> 69.360 MB. [2025-11-26T22:50:38.923Z] ====== als (apache-spark) [default], iteration 21 completed (9432.515 ms) ====== [2025-11-26T22:50:38.923Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-26T22:50:38.923Z] GC before operation: completed in 100.773 ms, heap usage 511.774 MB -> 69.895 MB. [2025-11-26T22:50:47.897Z] ====== als (apache-spark) [default], iteration 22 completed (9097.505 ms) ====== [2025-11-26T22:50:47.897Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-26T22:50:47.897Z] GC before operation: completed in 120.207 ms, heap usage 622.422 MB -> 70.311 MB. [2025-11-26T22:50:58.714Z] ====== als (apache-spark) [default], iteration 23 completed (9069.922 ms) ====== [2025-11-26T22:50:58.714Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-26T22:50:58.714Z] GC before operation: completed in 108.422 ms, heap usage 474.840 MB -> 70.457 MB. [2025-11-26T22:51:07.630Z] ====== als (apache-spark) [default], iteration 24 completed (9187.572 ms) ====== [2025-11-26T22:51:07.630Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-26T22:51:07.630Z] GC before operation: completed in 107.926 ms, heap usage 678.169 MB -> 70.834 MB. [2025-11-26T22:51:16.558Z] ====== als (apache-spark) [default], iteration 25 completed (9275.445 ms) ====== [2025-11-26T22:51:16.558Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-26T22:51:16.558Z] GC before operation: completed in 94.756 ms, heap usage 415.681 MB -> 70.742 MB. [2025-11-26T22:51:25.445Z] ====== als (apache-spark) [default], iteration 26 completed (9239.151 ms) ====== [2025-11-26T22:51:25.445Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-26T22:51:25.778Z] GC before operation: completed in 94.136 ms, heap usage 123.103 MB -> 70.992 MB. [2025-11-26T22:51:36.610Z] ====== als (apache-spark) [default], iteration 27 completed (9057.240 ms) ====== [2025-11-26T22:51:36.610Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-26T22:51:36.610Z] GC before operation: completed in 99.754 ms, heap usage 582.988 MB -> 71.635 MB. [2025-11-26T22:51:45.480Z] ====== als (apache-spark) [default], iteration 28 completed (9113.161 ms) ====== [2025-11-26T22:51:45.480Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-26T22:51:45.480Z] GC before operation: completed in 99.728 ms, heap usage 402.224 MB -> 71.601 MB. [2025-11-26T22:51:54.445Z] ====== als (apache-spark) [default], iteration 29 completed (9050.134 ms) ====== [2025-11-26T22:51:54.445Z] ----------------------------------- [2025-11-26T22:51:54.445Z] renaissance-als_0_PASSED [2025-11-26T22:51:54.445Z] ----------------------------------- [2025-11-26T22:51:54.445Z] [2025-11-26T22:51:54.445Z] TEST TEARDOWN: [2025-11-26T22:51:54.445Z] Nothing to be done for teardown. [2025-11-26T22:51:54.445Z] renaissance-als_0 Finish Time: Wed Nov 26 22:51:53 2025 Epoch Time (ms): 1764197513949