[2025-06-18T20:18:49.265Z] Running test renaissance-als_0 ... [2025-06-18T20:18:49.265Z] =============================================== [2025-06-18T20:18:49.265Z] renaissance-als_0 Start Time: Wed Jun 18 20:18:48 2025 Epoch Time (ms): 1750277928633 [2025-06-18T20:18:49.265Z] variation: NoOptions [2025-06-18T20:18:49.265Z] JVM_OPTIONS: [2025-06-18T20:18:49.265Z] { \ [2025-06-18T20:18:49.265Z] echo ""; echo "TEST SETUP:"; \ [2025-06-18T20:18:49.265Z] echo "Nothing to be done for setup."; \ [2025-06-18T20:18:49.265Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17502778884155/renaissance-als_0"; \ [2025-06-18T20:18:49.265Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17502778884155/renaissance-als_0"; \ [2025-06-18T20:18:49.265Z] echo ""; echo "TESTING:"; \ [2025-06-18T20:18:49.265Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17502778884155/renaissance-als_0"/als.json" als; \ [2025-06-18T20:18:49.265Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17502778884155/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-18T20:18:49.265Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-18T20:18:49.265Z] echo "Nothing to be done for teardown."; \ [2025-06-18T20:18:49.265Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17502778884155/TestTargetResult"; [2025-06-18T20:18:49.265Z] [2025-06-18T20:18:49.265Z] TEST SETUP: [2025-06-18T20:18:49.265Z] Nothing to be done for setup. [2025-06-18T20:18:49.265Z] [2025-06-18T20:18:49.265Z] TESTING: [2025-06-18T20:18:58.153Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-06-18T20:19:02.855Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-18T20:19:02.855Z] GC before operation: completed in 88.072 ms, heap usage 64.500 MB -> 34.833 MB. [2025-06-18T20:19:36.122Z] ====== als (apache-spark) [default], iteration 0 completed (31266.407 ms) ====== [2025-06-18T20:19:36.122Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-18T20:19:36.122Z] GC before operation: completed in 130.685 ms, heap usage 264.674 MB -> 61.583 MB. [2025-06-18T20:19:55.160Z] ====== als (apache-spark) [default], iteration 1 completed (16768.675 ms) ====== [2025-06-18T20:19:55.160Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-18T20:19:55.160Z] GC before operation: completed in 119.161 ms, heap usage 297.618 MB -> 62.467 MB. [2025-06-18T20:20:08.368Z] ====== als (apache-spark) [default], iteration 2 completed (15416.378 ms) ====== [2025-06-18T20:20:08.368Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-18T20:20:08.368Z] GC before operation: completed in 115.611 ms, heap usage 425.940 MB -> 63.325 MB. [2025-06-18T20:20:24.185Z] ====== als (apache-spark) [default], iteration 3 completed (15296.090 ms) ====== [2025-06-18T20:20:24.185Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-18T20:20:24.185Z] GC before operation: completed in 126.994 ms, heap usage 217.713 MB -> 63.478 MB. [2025-06-18T20:20:39.927Z] ====== als (apache-spark) [default], iteration 4 completed (14023.947 ms) ====== [2025-06-18T20:20:39.927Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-18T20:20:39.927Z] GC before operation: completed in 114.082 ms, heap usage 359.177 MB -> 64.269 MB. [2025-06-18T20:20:54.473Z] ====== als (apache-spark) [default], iteration 5 completed (14136.605 ms) ====== [2025-06-18T20:20:54.473Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-18T20:20:54.473Z] GC before operation: completed in 156.951 ms, heap usage 113.213 MB -> 64.866 MB. [2025-06-18T20:21:07.474Z] ====== als (apache-spark) [default], iteration 6 completed (13828.994 ms) ====== [2025-06-18T20:21:07.474Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-18T20:21:07.474Z] GC before operation: completed in 191.248 ms, heap usage 222.436 MB -> 65.212 MB. [2025-06-18T20:21:23.269Z] ====== als (apache-spark) [default], iteration 7 completed (13742.924 ms) ====== [2025-06-18T20:21:23.269Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-18T20:21:23.269Z] GC before operation: completed in 164.499 ms, heap usage 274.982 MB -> 65.796 MB. [2025-06-18T20:21:36.293Z] ====== als (apache-spark) [default], iteration 8 completed (13810.882 ms) ====== [2025-06-18T20:21:36.293Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-18T20:21:36.293Z] GC before operation: completed in 129.115 ms, heap usage 137.854 MB -> 66.370 MB. [2025-06-18T20:21:52.084Z] ====== als (apache-spark) [default], iteration 9 completed (13872.401 ms) ====== [2025-06-18T20:21:52.084Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-18T20:21:52.084Z] GC before operation: completed in 138.256 ms, heap usage 497.738 MB -> 67.040 MB. [2025-06-18T20:22:05.120Z] ====== als (apache-spark) [default], iteration 10 completed (13990.659 ms) ====== [2025-06-18T20:22:05.120Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-18T20:22:05.120Z] GC before operation: completed in 119.646 ms, heap usage 284.281 MB -> 67.053 MB. [2025-06-18T20:22:20.958Z] ====== als (apache-spark) [default], iteration 11 completed (13789.391 ms) ====== [2025-06-18T20:22:20.958Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-18T20:22:20.958Z] GC before operation: completed in 122.973 ms, heap usage 321.655 MB -> 67.246 MB. [2025-06-18T20:22:33.983Z] ====== als (apache-spark) [default], iteration 12 completed (13704.509 ms) ====== [2025-06-18T20:22:33.983Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-18T20:22:33.983Z] GC before operation: completed in 113.861 ms, heap usage 105.744 MB -> 67.200 MB. [2025-06-18T20:22:49.786Z] ====== als (apache-spark) [default], iteration 13 completed (13989.432 ms) ====== [2025-06-18T20:22:49.786Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-18T20:22:49.786Z] GC before operation: completed in 116.316 ms, heap usage 218.639 MB -> 67.640 MB. [2025-06-18T20:23:03.049Z] ====== als (apache-spark) [default], iteration 14 completed (13968.736 ms) ====== [2025-06-18T20:23:03.049Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-18T20:23:03.049Z] GC before operation: completed in 116.621 ms, heap usage 239.248 MB -> 67.843 MB. [2025-06-18T20:23:16.235Z] ====== als (apache-spark) [default], iteration 15 completed (13622.389 ms) ====== [2025-06-18T20:23:16.235Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-18T20:23:16.235Z] GC before operation: completed in 116.962 ms, heap usage 514.241 MB -> 68.413 MB. [2025-06-18T20:23:32.045Z] ====== als (apache-spark) [default], iteration 16 completed (13539.496 ms) ====== [2025-06-18T20:23:32.045Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-18T20:23:32.045Z] GC before operation: completed in 114.132 ms, heap usage 435.810 MB -> 68.638 MB. [2025-06-18T20:23:45.114Z] ====== als (apache-spark) [default], iteration 17 completed (13602.344 ms) ====== [2025-06-18T20:23:45.114Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-18T20:23:45.114Z] GC before operation: completed in 113.673 ms, heap usage 215.704 MB -> 68.501 MB. [2025-06-18T20:23:58.197Z] ====== als (apache-spark) [default], iteration 18 completed (13807.743 ms) ====== [2025-06-18T20:23:58.197Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-18T20:23:58.197Z] GC before operation: completed in 118.008 ms, heap usage 228.196 MB -> 68.973 MB. [2025-06-18T20:24:13.954Z] ====== als (apache-spark) [default], iteration 19 completed (13617.943 ms) ====== [2025-06-18T20:24:13.954Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-18T20:24:13.954Z] GC before operation: completed in 117.082 ms, heap usage 200.986 MB -> 69.053 MB. [2025-06-18T20:24:27.064Z] ====== als (apache-spark) [default], iteration 20 completed (13846.879 ms) ====== [2025-06-18T20:24:27.064Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-18T20:24:27.064Z] GC before operation: completed in 123.703 ms, heap usage 493.721 MB -> 69.742 MB. [2025-06-18T20:24:42.800Z] ====== als (apache-spark) [default], iteration 21 completed (14198.293 ms) ====== [2025-06-18T20:24:42.800Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-18T20:24:42.800Z] GC before operation: completed in 119.190 ms, heap usage 402.597 MB -> 69.790 MB. [2025-06-18T20:24:55.799Z] ====== als (apache-spark) [default], iteration 22 completed (13730.756 ms) ====== [2025-06-18T20:24:55.799Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-18T20:24:55.799Z] GC before operation: completed in 130.751 ms, heap usage 471.734 MB -> 70.311 MB. [2025-06-18T20:25:08.812Z] ====== als (apache-spark) [default], iteration 23 completed (13664.728 ms) ====== [2025-06-18T20:25:08.812Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-18T20:25:08.812Z] GC before operation: completed in 181.487 ms, heap usage 125.038 MB -> 70.094 MB. [2025-06-18T20:25:24.630Z] ====== als (apache-spark) [default], iteration 24 completed (13313.598 ms) ====== [2025-06-18T20:25:24.630Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-18T20:25:24.630Z] GC before operation: completed in 127.410 ms, heap usage 198.334 MB -> 70.420 MB. [2025-06-18T20:25:37.631Z] ====== als (apache-spark) [default], iteration 25 completed (13622.860 ms) ====== [2025-06-18T20:25:37.631Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-18T20:25:37.631Z] GC before operation: completed in 123.252 ms, heap usage 236.417 MB -> 70.857 MB. [2025-06-18T20:25:50.634Z] ====== als (apache-spark) [default], iteration 26 completed (13394.316 ms) ====== [2025-06-18T20:25:50.634Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-18T20:25:50.634Z] GC before operation: completed in 135.032 ms, heap usage 510.256 MB -> 71.510 MB. [2025-06-18T20:26:06.378Z] ====== als (apache-spark) [default], iteration 27 completed (13574.325 ms) ====== [2025-06-18T20:26:06.378Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-18T20:26:06.378Z] GC before operation: completed in 124.696 ms, heap usage 135.402 MB -> 71.296 MB. [2025-06-18T20:26:19.463Z] ====== als (apache-spark) [default], iteration 28 completed (13700.999 ms) ====== [2025-06-18T20:26:19.463Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-18T20:26:19.463Z] GC before operation: completed in 122.234 ms, heap usage 119.968 MB -> 70.965 MB. [2025-06-18T20:26:32.479Z] ====== als (apache-spark) [default], iteration 29 completed (13117.523 ms) ====== [2025-06-18T20:26:32.479Z] ----------------------------------- [2025-06-18T20:26:32.479Z] renaissance-als_0_PASSED [2025-06-18T20:26:32.479Z] ----------------------------------- [2025-06-18T20:26:32.479Z] [2025-06-18T20:26:32.479Z] TEST TEARDOWN: [2025-06-18T20:26:32.479Z] Nothing to be done for teardown. [2025-06-18T20:26:32.479Z] renaissance-als_0 Finish Time: Wed Jun 18 20:26:31 2025 Epoch Time (ms): 1750278391957