[2025-06-12T02:10:06.840Z] Running test renaissance-als_0 ... [2025-06-12T02:10:06.840Z] =============================================== [2025-06-12T02:10:06.840Z] renaissance-als_0 Start Time: Thu Jun 12 02:10:06 2025 Epoch Time (ms): 1749694206415 [2025-06-12T02:10:06.840Z] variation: NoOptions [2025-06-12T02:10:06.840Z] JVM_OPTIONS: [2025-06-12T02:10:06.840Z] { \ [2025-06-12T02:10:06.840Z] echo ""; echo "TEST SETUP:"; \ [2025-06-12T02:10:06.840Z] echo "Nothing to be done for setup."; \ [2025-06-12T02:10:06.840Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17496941643392/renaissance-als_0"; \ [2025-06-12T02:10:06.841Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17496941643392/renaissance-als_0"; \ [2025-06-12T02:10:06.841Z] echo ""; echo "TESTING:"; \ [2025-06-12T02:10:06.841Z] "/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_17496941643392/renaissance-als_0"/als.json" als; \ [2025-06-12T02:10:06.841Z] 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_17496941643392/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-12T02:10:06.841Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-12T02:10:06.841Z] echo "Nothing to be done for teardown."; \ [2025-06-12T02:10:06.841Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17496941643392/TestTargetResult"; [2025-06-12T02:10:06.841Z] [2025-06-12T02:10:06.841Z] TEST SETUP: [2025-06-12T02:10:06.841Z] Nothing to be done for setup. [2025-06-12T02:10:06.841Z] [2025-06-12T02:10:06.841Z] TESTING: [2025-06-12T02:10:12.675Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-06-12T02:10:17.385Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-12T02:10:17.385Z] GC before operation: completed in 90.092 ms, heap usage 58.576 MB -> 34.750 MB. [2025-06-12T02:10:57.356Z] ====== als (apache-spark) [default], iteration 0 completed (32975.834 ms) ====== [2025-06-12T02:10:57.356Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-12T02:10:57.356Z] GC before operation: completed in 134.327 ms, heap usage 515.905 MB -> 61.771 MB. [2025-06-12T02:11:10.383Z] ====== als (apache-spark) [default], iteration 1 completed (17207.115 ms) ====== [2025-06-12T02:11:10.383Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-12T02:11:10.383Z] GC before operation: completed in 107.395 ms, heap usage 414.592 MB -> 62.496 MB. [2025-06-12T02:11:26.179Z] ====== als (apache-spark) [default], iteration 2 completed (15434.032 ms) ====== [2025-06-12T02:11:26.179Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-12T02:11:26.179Z] GC before operation: completed in 113.420 ms, heap usage 719.100 MB -> 63.528 MB. [2025-06-12T02:11:40.812Z] ====== als (apache-spark) [default], iteration 3 completed (15625.978 ms) ====== [2025-06-12T02:11:40.812Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-12T02:11:40.812Z] GC before operation: completed in 131.730 ms, heap usage 231.972 MB -> 63.469 MB. [2025-06-12T02:11:56.565Z] ====== als (apache-spark) [default], iteration 4 completed (14878.083 ms) ====== [2025-06-12T02:11:56.565Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-12T02:11:56.565Z] GC before operation: completed in 106.662 ms, heap usage 266.749 MB -> 64.011 MB. [2025-06-12T02:12:12.427Z] ====== als (apache-spark) [default], iteration 5 completed (14830.184 ms) ====== [2025-06-12T02:12:12.427Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-12T02:12:12.427Z] GC before operation: completed in 108.924 ms, heap usage 294.163 MB -> 64.610 MB. [2025-06-12T02:12:28.195Z] ====== als (apache-spark) [default], iteration 6 completed (14263.116 ms) ====== [2025-06-12T02:12:28.195Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-12T02:12:28.195Z] GC before operation: completed in 118.247 ms, heap usage 603.891 MB -> 65.661 MB. [2025-06-12T02:12:41.207Z] ====== als (apache-spark) [default], iteration 7 completed (14478.784 ms) ====== [2025-06-12T02:12:41.207Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-12T02:12:41.207Z] GC before operation: completed in 120.000 ms, heap usage 690.560 MB -> 66.232 MB. [2025-06-12T02:12:56.997Z] ====== als (apache-spark) [default], iteration 8 completed (14408.931 ms) ====== [2025-06-12T02:12:56.997Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-12T02:12:56.997Z] GC before operation: completed in 119.413 ms, heap usage 785.361 MB -> 66.982 MB. [2025-06-12T02:13:12.759Z] ====== als (apache-spark) [default], iteration 9 completed (14545.250 ms) ====== [2025-06-12T02:13:12.759Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-12T02:13:12.759Z] GC before operation: completed in 126.305 ms, heap usage 383.656 MB -> 66.624 MB. [2025-06-12T02:13:25.947Z] ====== als (apache-spark) [default], iteration 10 completed (14331.520 ms) ====== [2025-06-12T02:13:25.947Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-12T02:13:25.947Z] GC before operation: completed in 127.028 ms, heap usage 629.162 MB -> 67.095 MB. [2025-06-12T02:13:41.708Z] ====== als (apache-spark) [default], iteration 11 completed (14691.623 ms) ====== [2025-06-12T02:13:41.708Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-12T02:13:41.708Z] GC before operation: completed in 117.346 ms, heap usage 285.940 MB -> 67.068 MB. [2025-06-12T02:13:54.759Z] ====== als (apache-spark) [default], iteration 12 completed (14077.641 ms) ====== [2025-06-12T02:13:54.759Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-12T02:13:54.760Z] GC before operation: completed in 118.270 ms, heap usage 374.567 MB -> 67.329 MB. [2025-06-12T02:14:10.521Z] ====== als (apache-spark) [default], iteration 13 completed (14509.767 ms) ====== [2025-06-12T02:14:10.521Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-12T02:14:10.521Z] GC before operation: completed in 117.643 ms, heap usage 464.296 MB -> 67.789 MB. [2025-06-12T02:14:23.546Z] ====== als (apache-spark) [default], iteration 14 completed (13912.002 ms) ====== [2025-06-12T02:14:23.546Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-12T02:14:23.885Z] GC before operation: completed in 198.026 ms, heap usage 565.680 MB -> 68.073 MB. [2025-06-12T02:14:39.637Z] ====== als (apache-spark) [default], iteration 15 completed (14191.099 ms) ====== [2025-06-12T02:14:39.637Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-12T02:14:39.637Z] GC before operation: completed in 123.442 ms, heap usage 723.491 MB -> 68.615 MB. [2025-06-12T02:14:52.785Z] ====== als (apache-spark) [default], iteration 16 completed (13954.948 ms) ====== [2025-06-12T02:14:52.785Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-12T02:14:52.785Z] GC before operation: completed in 124.943 ms, heap usage 654.508 MB -> 68.850 MB. [2025-06-12T02:15:08.549Z] ====== als (apache-spark) [default], iteration 17 completed (14009.533 ms) ====== [2025-06-12T02:15:08.549Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-12T02:15:08.549Z] GC before operation: completed in 123.914 ms, heap usage 483.307 MB -> 68.883 MB. [2025-06-12T02:15:21.568Z] ====== als (apache-spark) [default], iteration 18 completed (14102.742 ms) ====== [2025-06-12T02:15:21.568Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-12T02:15:21.568Z] GC before operation: completed in 120.830 ms, heap usage 130.139 MB -> 68.699 MB. [2025-06-12T02:15:37.369Z] ====== als (apache-spark) [default], iteration 19 completed (13986.440 ms) ====== [2025-06-12T02:15:37.369Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-12T02:15:37.369Z] GC before operation: completed in 126.392 ms, heap usage 743.362 MB -> 69.654 MB. [2025-06-12T02:15:53.125Z] ====== als (apache-spark) [default], iteration 20 completed (14545.023 ms) ====== [2025-06-12T02:15:53.125Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-12T02:15:53.125Z] GC before operation: completed in 142.069 ms, heap usage 662.513 MB -> 69.907 MB. [2025-06-12T02:16:06.207Z] ====== als (apache-spark) [default], iteration 21 completed (14484.137 ms) ====== [2025-06-12T02:16:06.207Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-12T02:16:06.207Z] GC before operation: completed in 119.720 ms, heap usage 169.142 MB -> 69.526 MB. [2025-06-12T02:16:21.971Z] ====== als (apache-spark) [default], iteration 22 completed (14366.282 ms) ====== [2025-06-12T02:16:21.971Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-12T02:16:21.971Z] GC before operation: completed in 194.310 ms, heap usage 374.957 MB -> 69.874 MB. [2025-06-12T02:16:34.997Z] ====== als (apache-spark) [default], iteration 23 completed (13982.364 ms) ====== [2025-06-12T02:16:34.997Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-12T02:16:34.997Z] GC before operation: completed in 188.753 ms, heap usage 593.429 MB -> 70.580 MB. [2025-06-12T02:16:50.794Z] ====== als (apache-spark) [default], iteration 24 completed (14622.888 ms) ====== [2025-06-12T02:16:50.794Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-12T02:16:50.794Z] GC before operation: completed in 126.076 ms, heap usage 152.824 MB -> 70.307 MB. [2025-06-12T02:17:03.806Z] ====== als (apache-spark) [default], iteration 25 completed (13836.865 ms) ====== [2025-06-12T02:17:03.806Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-12T02:17:04.127Z] GC before operation: completed in 125.311 ms, heap usage 106.494 MB -> 69.943 MB. [2025-06-12T02:17:19.881Z] ====== als (apache-spark) [default], iteration 26 completed (14036.261 ms) ====== [2025-06-12T02:17:19.881Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-12T02:17:19.881Z] GC before operation: completed in 159.950 ms, heap usage 462.868 MB -> 71.132 MB. [2025-06-12T02:17:32.997Z] ====== als (apache-spark) [default], iteration 27 completed (14274.327 ms) ====== [2025-06-12T02:17:32.997Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-12T02:17:32.997Z] GC before operation: completed in 129.567 ms, heap usage 227.376 MB -> 70.987 MB. [2025-06-12T02:17:48.784Z] ====== als (apache-spark) [default], iteration 28 completed (14057.743 ms) ====== [2025-06-12T02:17:48.784Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-12T02:17:48.784Z] GC before operation: completed in 125.929 ms, heap usage 263.805 MB -> 71.386 MB. [2025-06-12T02:18:01.832Z] ====== als (apache-spark) [default], iteration 29 completed (13627.078 ms) ====== [2025-06-12T02:18:01.832Z] ----------------------------------- [2025-06-12T02:18:01.832Z] renaissance-als_0_PASSED [2025-06-12T02:18:01.832Z] ----------------------------------- [2025-06-12T02:18:01.832Z] [2025-06-12T02:18:01.832Z] TEST TEARDOWN: [2025-06-12T02:18:01.832Z] Nothing to be done for teardown. [2025-06-12T02:18:01.832Z] renaissance-als_0 Finish Time: Thu Jun 12 02:18:01 2025 Epoch Time (ms): 1749694681518