[2025-11-11T15:29:48.374Z] Running test renaissance-als_0 ... [2025-11-11T15:29:48.374Z] =============================================== [2025-11-11T15:29:48.374Z] renaissance-als_0 Start Time: Tue Nov 11 15:29:48 2025 Epoch Time (ms): 1762874988170 [2025-11-11T15:29:48.374Z] variation: NoOptions [2025-11-11T15:29:48.374Z] JVM_OPTIONS: [2025-11-11T15:29:48.374Z] { \ [2025-11-11T15:29:48.374Z] echo ""; echo "TEST SETUP:"; \ [2025-11-11T15:29:48.374Z] echo "Nothing to be done for setup."; \ [2025-11-11T15:29:48.374Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17628747899203/renaissance-als_0"; \ [2025-11-11T15:29:48.374Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17628747899203/renaissance-als_0"; \ [2025-11-11T15:29:48.374Z] echo ""; echo "TESTING:"; \ [2025-11-11T15:29:48.374Z] "/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_17628747899203/renaissance-als_0"/als.json" als; \ [2025-11-11T15:29:48.374Z] 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_17628747899203/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-11T15:29:48.374Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-11T15:29:48.374Z] echo "Nothing to be done for teardown."; \ [2025-11-11T15:29:48.374Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17628747899203/TestTargetResult"; [2025-11-11T15:29:48.374Z] [2025-11-11T15:29:48.374Z] TEST SETUP: [2025-11-11T15:29:48.374Z] Nothing to be done for setup. [2025-11-11T15:29:48.374Z] [2025-11-11T15:29:48.374Z] TESTING: [2025-11-11T15:29:55.690Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-11-11T15:29:59.510Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-11T15:29:59.510Z] GC before operation: completed in 76.873 ms, heap usage 63.000 MB -> 34.892 MB. [2025-11-11T15:30:33.026Z] ====== als (apache-spark) [default], iteration 0 completed (27716.549 ms) ====== [2025-11-11T15:30:33.026Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-11T15:30:33.026Z] GC before operation: completed in 119.087 ms, heap usage 156.056 MB -> 61.487 MB. [2025-11-11T15:30:43.936Z] ====== als (apache-spark) [default], iteration 1 completed (14082.285 ms) ====== [2025-11-11T15:30:43.936Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-11T15:30:43.936Z] GC before operation: completed in 121.932 ms, heap usage 544.624 MB -> 62.945 MB. [2025-11-11T15:30:57.119Z] ====== als (apache-spark) [default], iteration 2 completed (12595.595 ms) ====== [2025-11-11T15:30:57.119Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-11T15:30:57.119Z] GC before operation: completed in 102.283 ms, heap usage 72.026 MB -> 62.932 MB. [2025-11-11T15:31:08.016Z] ====== als (apache-spark) [default], iteration 3 completed (12646.735 ms) ====== [2025-11-11T15:31:08.016Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-11T15:31:08.350Z] GC before operation: completed in 112.135 ms, heap usage 382.260 MB -> 63.754 MB. [2025-11-11T15:31:21.559Z] ====== als (apache-spark) [default], iteration 4 completed (12054.695 ms) ====== [2025-11-11T15:31:21.559Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-11T15:31:21.559Z] GC before operation: completed in 107.473 ms, heap usage 451.088 MB -> 64.522 MB. [2025-11-11T15:31:34.794Z] ====== als (apache-spark) [default], iteration 5 completed (11899.707 ms) ====== [2025-11-11T15:31:34.794Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-11T15:31:34.794Z] GC before operation: completed in 111.349 ms, heap usage 312.678 MB -> 64.816 MB. [2025-11-11T15:31:45.669Z] ====== als (apache-spark) [default], iteration 6 completed (11823.876 ms) ====== [2025-11-11T15:31:45.669Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-11T15:31:45.669Z] GC before operation: completed in 172.923 ms, heap usage 349.556 MB -> 65.356 MB. [2025-11-11T15:31:58.853Z] ====== als (apache-spark) [default], iteration 7 completed (12025.051 ms) ====== [2025-11-11T15:31:58.853Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-11T15:31:58.853Z] GC before operation: completed in 116.875 ms, heap usage 464.955 MB -> 66.171 MB. [2025-11-11T15:32:09.737Z] ====== als (apache-spark) [default], iteration 8 completed (11706.125 ms) ====== [2025-11-11T15:32:09.737Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-11T15:32:09.737Z] GC before operation: completed in 123.805 ms, heap usage 135.673 MB -> 66.252 MB. [2025-11-11T15:32:22.924Z] ====== als (apache-spark) [default], iteration 9 completed (11521.249 ms) ====== [2025-11-11T15:32:22.924Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-11T15:32:22.924Z] GC before operation: completed in 126.596 ms, heap usage 415.747 MB -> 66.728 MB. [2025-11-11T15:32:33.805Z] ====== als (apache-spark) [default], iteration 10 completed (11645.848 ms) ====== [2025-11-11T15:32:33.805Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-11T15:32:33.805Z] GC before operation: completed in 127.849 ms, heap usage 411.467 MB -> 66.980 MB. [2025-11-11T15:32:46.998Z] ====== als (apache-spark) [default], iteration 11 completed (11288.671 ms) ====== [2025-11-11T15:32:46.998Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-11T15:32:46.998Z] GC before operation: completed in 116.061 ms, heap usage 299.459 MB -> 67.160 MB. [2025-11-11T15:32:57.897Z] ====== als (apache-spark) [default], iteration 12 completed (11458.983 ms) ====== [2025-11-11T15:32:57.897Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-11T15:32:57.897Z] GC before operation: completed in 121.498 ms, heap usage 318.583 MB -> 67.460 MB. [2025-11-11T15:33:08.767Z] ====== als (apache-spark) [default], iteration 13 completed (11382.431 ms) ====== [2025-11-11T15:33:08.767Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-11T15:33:08.767Z] GC before operation: completed in 105.405 ms, heap usage 251.771 MB -> 67.566 MB. [2025-11-11T15:33:21.945Z] ====== als (apache-spark) [default], iteration 14 completed (11356.601 ms) ====== [2025-11-11T15:33:21.945Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-11T15:33:21.945Z] GC before operation: completed in 109.099 ms, heap usage 183.314 MB -> 67.728 MB. [2025-11-11T15:33:32.823Z] ====== als (apache-spark) [default], iteration 15 completed (11255.038 ms) ====== [2025-11-11T15:33:32.823Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-11T15:33:32.823Z] GC before operation: completed in 115.874 ms, heap usage 140.758 MB -> 67.985 MB. [2025-11-11T15:33:43.875Z] ====== als (apache-spark) [default], iteration 16 completed (11103.623 ms) ====== [2025-11-11T15:33:43.875Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-11T15:33:43.875Z] GC before operation: completed in 126.635 ms, heap usage 621.053 MB -> 68.819 MB. [2025-11-11T15:33:57.060Z] ====== als (apache-spark) [default], iteration 17 completed (11139.400 ms) ====== [2025-11-11T15:33:57.060Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-11T15:33:57.060Z] GC before operation: completed in 115.611 ms, heap usage 320.958 MB -> 68.700 MB. [2025-11-11T15:34:07.971Z] ====== als (apache-spark) [default], iteration 18 completed (10993.247 ms) ====== [2025-11-11T15:34:07.971Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-11T15:34:07.971Z] GC before operation: completed in 122.241 ms, heap usage 247.265 MB -> 68.951 MB. [2025-11-11T15:34:18.875Z] ====== als (apache-spark) [default], iteration 19 completed (11147.198 ms) ====== [2025-11-11T15:34:18.875Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-11T15:34:18.875Z] GC before operation: completed in 120.892 ms, heap usage 341.840 MB -> 69.285 MB. [2025-11-11T15:34:29.753Z] ====== als (apache-spark) [default], iteration 20 completed (11423.594 ms) ====== [2025-11-11T15:34:29.753Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-11T15:34:29.753Z] GC before operation: completed in 115.500 ms, heap usage 507.213 MB -> 70.256 MB. [2025-11-11T15:34:42.949Z] ====== als (apache-spark) [default], iteration 21 completed (12114.245 ms) ====== [2025-11-11T15:34:42.949Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-11T15:34:42.949Z] GC before operation: completed in 110.925 ms, heap usage 392.774 MB -> 69.762 MB. [2025-11-11T15:34:53.831Z] ====== als (apache-spark) [default], iteration 22 completed (11415.960 ms) ====== [2025-11-11T15:34:53.831Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-11T15:34:53.831Z] GC before operation: completed in 115.198 ms, heap usage 154.325 MB -> 69.716 MB. [2025-11-11T15:35:07.022Z] ====== als (apache-spark) [default], iteration 23 completed (11280.365 ms) ====== [2025-11-11T15:35:07.022Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-11T15:35:07.022Z] GC before operation: completed in 111.600 ms, heap usage 159.413 MB -> 70.051 MB. [2025-11-11T15:35:17.883Z] ====== als (apache-spark) [default], iteration 24 completed (11403.618 ms) ====== [2025-11-11T15:35:17.883Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-11T15:35:17.883Z] GC before operation: completed in 113.197 ms, heap usage 240.389 MB -> 70.915 MB. [2025-11-11T15:35:28.755Z] ====== als (apache-spark) [default], iteration 25 completed (11304.216 ms) ====== [2025-11-11T15:35:28.755Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-11T15:35:29.091Z] GC before operation: completed in 116.090 ms, heap usage 187.876 MB -> 70.560 MB. [2025-11-11T15:35:42.290Z] ====== als (apache-spark) [default], iteration 26 completed (11632.287 ms) ====== [2025-11-11T15:35:42.290Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-11T15:35:42.290Z] GC before operation: completed in 120.444 ms, heap usage 184.753 MB -> 70.764 MB. [2025-11-11T15:35:53.160Z] ====== als (apache-spark) [default], iteration 27 completed (11380.237 ms) ====== [2025-11-11T15:35:53.160Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-11T15:35:53.160Z] GC before operation: completed in 114.927 ms, heap usage 356.984 MB -> 71.244 MB. [2025-11-11T15:36:04.036Z] ====== als (apache-spark) [default], iteration 28 completed (11280.640 ms) ====== [2025-11-11T15:36:04.036Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-11T15:36:04.369Z] GC before operation: completed in 124.071 ms, heap usage 279.861 MB -> 71.423 MB. [2025-11-11T15:36:17.550Z] ====== als (apache-spark) [default], iteration 29 completed (11278.144 ms) ====== [2025-11-11T15:36:17.550Z] ----------------------------------- [2025-11-11T15:36:17.550Z] renaissance-als_0_PASSED [2025-11-11T15:36:17.550Z] ----------------------------------- [2025-11-11T15:36:17.550Z] [2025-11-11T15:36:17.550Z] TEST TEARDOWN: [2025-11-11T15:36:17.550Z] Nothing to be done for teardown. [2025-11-11T15:36:17.550Z] renaissance-als_0 Finish Time: Tue Nov 11 15:36:15 2025 Epoch Time (ms): 1762875375912