[2025-07-01T07:11:06.999Z] Running test renaissance-als_0 ... [2025-07-01T07:11:06.999Z] =============================================== [2025-07-01T07:11:06.999Z] renaissance-als_0 Start Time: Tue Jul 1 07:11:06 2025 Epoch Time (ms): 1751353866521 [2025-07-01T07:11:06.999Z] variation: NoOptions [2025-07-01T07:11:06.999Z] JVM_OPTIONS: [2025-07-01T07:11:06.999Z] { \ [2025-07-01T07:11:06.999Z] echo ""; echo "TEST SETUP:"; \ [2025-07-01T07:11:06.999Z] echo "Nothing to be done for setup."; \ [2025-07-01T07:11:06.999Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17513538659840/renaissance-als_0"; \ [2025-07-01T07:11:06.999Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17513538659840/renaissance-als_0"; \ [2025-07-01T07:11:06.999Z] echo ""; echo "TESTING:"; \ [2025-07-01T07:11:06.999Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-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_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17513538659840/renaissance-als_0"/als.json" als; \ [2025-07-01T07:11:06.999Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17513538659840/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-01T07:11:06.999Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-01T07:11:06.999Z] echo "Nothing to be done for teardown."; \ [2025-07-01T07:11:06.999Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_1/aqa-tests/TKG/../TKG/output_17513538659840/TestTargetResult"; [2025-07-01T07:11:06.999Z] [2025-07-01T07:11:06.999Z] TEST SETUP: [2025-07-01T07:11:06.999Z] Nothing to be done for setup. [2025-07-01T07:11:06.999Z] [2025-07-01T07:11:06.999Z] TESTING: [2025-07-01T07:11:23.248Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-07-01T07:11:35.364Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-07-01T07:11:35.364Z] GC before operation: completed in 160.712 ms, heap usage 54.000 MB -> 35.090 MB. [2025-07-01T07:12:55.180Z] ====== als (apache-spark) [default], iteration 0 completed (68898.383 ms) ====== [2025-07-01T07:12:55.180Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-07-01T07:12:55.180Z] GC before operation: completed in 363.355 ms, heap usage 203.090 MB -> 61.909 MB. [2025-07-01T07:13:15.786Z] ====== als (apache-spark) [default], iteration 1 completed (27577.544 ms) ====== [2025-07-01T07:13:15.786Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-07-01T07:13:15.786Z] GC before operation: completed in 306.248 ms, heap usage 437.740 MB -> 63.216 MB. [2025-07-01T07:13:39.147Z] ====== als (apache-spark) [default], iteration 2 completed (23769.157 ms) ====== [2025-07-01T07:13:39.148Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-07-01T07:13:40.015Z] GC before operation: completed in 293.095 ms, heap usage 382.804 MB -> 63.567 MB. [2025-07-01T07:14:03.323Z] ====== als (apache-spark) [default], iteration 3 completed (21596.891 ms) ====== [2025-07-01T07:14:03.323Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-07-01T07:14:03.323Z] GC before operation: completed in 301.533 ms, heap usage 457.088 MB -> 64.367 MB. [2025-07-01T07:14:23.886Z] ====== als (apache-spark) [default], iteration 4 completed (20086.182 ms) ====== [2025-07-01T07:14:23.886Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-07-01T07:14:23.886Z] GC before operation: completed in 235.010 ms, heap usage 245.351 MB -> 64.591 MB. [2025-07-01T07:14:43.897Z] ====== als (apache-spark) [default], iteration 5 completed (18549.706 ms) ====== [2025-07-01T07:14:43.897Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-07-01T07:14:43.897Z] GC before operation: completed in 232.644 ms, heap usage 120.426 MB -> 65.042 MB. [2025-07-01T07:15:01.171Z] ====== als (apache-spark) [default], iteration 6 completed (17330.018 ms) ====== [2025-07-01T07:15:01.171Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-07-01T07:15:01.171Z] GC before operation: completed in 227.762 ms, heap usage 105.554 MB -> 65.290 MB. [2025-07-01T07:15:24.426Z] ====== als (apache-spark) [default], iteration 7 completed (21767.563 ms) ====== [2025-07-01T07:15:24.426Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-07-01T07:15:24.426Z] GC before operation: completed in 188.986 ms, heap usage 495.198 MB -> 66.655 MB. [2025-07-01T07:15:41.548Z] ====== als (apache-spark) [default], iteration 8 completed (16680.778 ms) ====== [2025-07-01T07:15:41.548Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-07-01T07:15:41.548Z] GC before operation: completed in 135.852 ms, heap usage 352.326 MB -> 66.927 MB. [2025-07-01T07:15:52.351Z] ====== als (apache-spark) [default], iteration 9 completed (11971.181 ms) ====== [2025-07-01T07:15:52.351Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-07-01T07:15:52.351Z] GC before operation: completed in 166.004 ms, heap usage 289.886 MB -> 67.027 MB. [2025-07-01T07:16:04.922Z] ====== als (apache-spark) [default], iteration 10 completed (11765.859 ms) ====== [2025-07-01T07:16:04.922Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-07-01T07:16:04.922Z] GC before operation: completed in 178.389 ms, heap usage 122.987 MB -> 67.118 MB. [2025-07-01T07:16:15.611Z] ====== als (apache-spark) [default], iteration 11 completed (10721.045 ms) ====== [2025-07-01T07:16:15.611Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-07-01T07:16:15.611Z] GC before operation: completed in 111.833 ms, heap usage 311.174 MB -> 67.647 MB. [2025-07-01T07:16:24.356Z] ====== als (apache-spark) [default], iteration 12 completed (7889.647 ms) ====== [2025-07-01T07:16:24.356Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-07-01T07:16:24.356Z] GC before operation: completed in 97.447 ms, heap usage 436.325 MB -> 68.084 MB. [2025-07-01T07:16:33.133Z] ====== als (apache-spark) [default], iteration 13 completed (9066.398 ms) ====== [2025-07-01T07:16:33.133Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-07-01T07:16:33.133Z] GC before operation: completed in 135.662 ms, heap usage 136.845 MB -> 67.870 MB. [2025-07-01T07:16:45.484Z] ====== als (apache-spark) [default], iteration 14 completed (11885.729 ms) ====== [2025-07-01T07:16:45.484Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-07-01T07:16:45.484Z] GC before operation: completed in 137.358 ms, heap usage 111.359 MB -> 68.171 MB. [2025-07-01T07:16:57.930Z] ====== als (apache-spark) [default], iteration 15 completed (10508.318 ms) ====== [2025-07-01T07:16:57.930Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-07-01T07:16:57.930Z] GC before operation: completed in 93.084 ms, heap usage 363.062 MB -> 68.573 MB. [2025-07-01T07:17:05.219Z] ====== als (apache-spark) [default], iteration 16 completed (8398.946 ms) ====== [2025-07-01T07:17:05.219Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-07-01T07:17:05.219Z] GC before operation: completed in 101.895 ms, heap usage 500.145 MB -> 69.096 MB. [2025-07-01T07:17:12.624Z] ====== als (apache-spark) [default], iteration 17 completed (6902.146 ms) ====== [2025-07-01T07:17:12.624Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-07-01T07:17:12.624Z] GC before operation: completed in 97.028 ms, heap usage 488.842 MB -> 69.887 MB. [2025-07-01T07:17:23.022Z] ====== als (apache-spark) [default], iteration 18 completed (10048.667 ms) ====== [2025-07-01T07:17:23.022Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-07-01T07:17:23.022Z] GC before operation: completed in 96.246 ms, heap usage 392.027 MB -> 69.380 MB. [2025-07-01T07:17:30.294Z] ====== als (apache-spark) [default], iteration 19 completed (7288.811 ms) ====== [2025-07-01T07:17:30.294Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-07-01T07:17:30.294Z] GC before operation: completed in 95.073 ms, heap usage 477.130 MB -> 69.912 MB. [2025-07-01T07:17:40.788Z] ====== als (apache-spark) [default], iteration 20 completed (10392.440 ms) ====== [2025-07-01T07:17:40.788Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-07-01T07:17:40.788Z] GC before operation: completed in 153.089 ms, heap usage 166.943 MB -> 69.674 MB. [2025-07-01T07:17:51.847Z] ====== als (apache-spark) [default], iteration 21 completed (11086.286 ms) ====== [2025-07-01T07:17:51.847Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-07-01T07:17:52.683Z] GC before operation: completed in 128.241 ms, heap usage 325.433 MB -> 70.196 MB. [2025-07-01T07:18:03.149Z] ====== als (apache-spark) [default], iteration 22 completed (9446.803 ms) ====== [2025-07-01T07:18:03.149Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-07-01T07:18:03.149Z] GC before operation: completed in 92.866 ms, heap usage 437.696 MB -> 70.833 MB. [2025-07-01T07:18:11.964Z] ====== als (apache-spark) [default], iteration 23 completed (9713.901 ms) ====== [2025-07-01T07:18:11.964Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-07-01T07:18:11.964Z] GC before operation: completed in 133.048 ms, heap usage 273.759 MB -> 70.694 MB. [2025-07-01T07:18:24.345Z] ====== als (apache-spark) [default], iteration 24 completed (11134.151 ms) ====== [2025-07-01T07:18:24.345Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-07-01T07:18:24.345Z] GC before operation: completed in 113.187 ms, heap usage 506.747 MB -> 71.265 MB. [2025-07-01T07:18:35.724Z] ====== als (apache-spark) [default], iteration 25 completed (10482.448 ms) ====== [2025-07-01T07:18:35.724Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-07-01T07:18:35.725Z] GC before operation: completed in 111.229 ms, heap usage 297.714 MB -> 71.214 MB. [2025-07-01T07:18:46.929Z] ====== als (apache-spark) [default], iteration 26 completed (11779.986 ms) ====== [2025-07-01T07:18:46.929Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-07-01T07:18:46.929Z] GC before operation: completed in 114.861 ms, heap usage 617.084 MB -> 71.795 MB. [2025-07-01T07:18:55.675Z] ====== als (apache-spark) [default], iteration 27 completed (9358.147 ms) ====== [2025-07-01T07:18:55.675Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-07-01T07:18:55.675Z] GC before operation: completed in 94.971 ms, heap usage 436.115 MB -> 72.487 MB. [2025-07-01T07:19:04.390Z] ====== als (apache-spark) [default], iteration 28 completed (8448.731 ms) ====== [2025-07-01T07:19:04.390Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-07-01T07:19:04.390Z] GC before operation: completed in 97.302 ms, heap usage 386.185 MB -> 71.599 MB. [2025-07-01T07:19:13.116Z] ====== als (apache-spark) [default], iteration 29 completed (8618.183 ms) ====== [2025-07-01T07:19:13.937Z] ----------------------------------- [2025-07-01T07:19:13.937Z] renaissance-als_0_PASSED [2025-07-01T07:19:13.937Z] ----------------------------------- [2025-07-01T07:19:13.937Z] [2025-07-01T07:19:13.937Z] TEST TEARDOWN: [2025-07-01T07:19:13.937Z] Nothing to be done for teardown. [2025-07-01T07:19:13.937Z] renaissance-als_0 Finish Time: Tue Jul 1 07:19:13 2025 Epoch Time (ms): 1751354353688