[2026-01-03T15:28:05.682Z] Running test renaissance-als_0 ... [2026-01-03T15:28:05.682Z] =============================================== [2026-01-03T15:28:05.682Z] renaissance-als_0 Start Time: Sat Jan 3 15:28:05 2026 Epoch Time (ms): 1767454085629 [2026-01-03T15:28:06.037Z] variation: NoOptions [2026-01-03T15:28:06.037Z] JVM_OPTIONS: [2026-01-03T15:28:06.037Z] { \ [2026-01-03T15:28:06.037Z] echo ""; echo "TEST SETUP:"; \ [2026-01-03T15:28:06.037Z] echo "Nothing to be done for setup."; \ [2026-01-03T15:28:06.038Z] mkdir -p "C:/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17674538802805\\renaissance-als_0"; \ [2026-01-03T15:28:06.038Z] cd "C:/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17674538802805\\renaissance-als_0"; \ [2026-01-03T15:28:06.038Z] echo ""; echo "TESTING:"; \ [2026-01-03T15:28:06.038Z] "c:/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_windows/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 "C:/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17674538802805\\renaissance-als_0"\\als.json" als; \ [2026-01-03T15:28:06.038Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17674538802805\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-03T15:28:06.038Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-03T15:28:06.038Z] echo "Nothing to be done for teardown."; \ [2026-01-03T15:28:06.038Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17674538802805\\TestTargetResult"; [2026-01-03T15:28:06.038Z] [2026-01-03T15:28:06.038Z] TEST SETUP: [2026-01-03T15:28:06.038Z] Nothing to be done for setup. [2026-01-03T15:28:06.038Z] [2026-01-03T15:28:06.038Z] TESTING: [2026-01-03T15:28:06.781Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-03T15:28:06.781Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/C:/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_windows/aqa-tests/TKG/output_17674538802805/renaissance-als_0/launcher-152806-5332416079173075688/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-03T15:28:06.781Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-03T15:28:06.781Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-03T15:28:22.803Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-03T15:28:24.022Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-03T15:28:24.375Z] GC before operation: completed in 38.161 ms, heap usage 56.000 MB -> 35.567 MB. [2026-01-03T15:28:53.043Z] ====== als (apache-spark) [default], iteration 0 completed (27541.462 ms) ====== [2026-01-03T15:28:53.043Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-03T15:28:53.043Z] GC before operation: completed in 122.416 ms, heap usage 151.845 MB -> 62.200 MB. [2026-01-03T15:29:09.426Z] ====== als (apache-spark) [default], iteration 1 completed (16115.969 ms) ====== [2026-01-03T15:29:09.426Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-03T15:29:09.426Z] GC before operation: completed in 76.204 ms, heap usage 206.842 MB -> 63.136 MB. [2026-01-03T15:29:25.944Z] ====== als (apache-spark) [default], iteration 2 completed (16287.385 ms) ====== [2026-01-03T15:29:25.944Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-03T15:29:25.944Z] GC before operation: completed in 80.007 ms, heap usage 246.965 MB -> 63.702 MB. [2026-01-03T15:29:42.442Z] ====== als (apache-spark) [default], iteration 3 completed (15784.708 ms) ====== [2026-01-03T15:29:42.442Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-03T15:29:42.442Z] GC before operation: completed in 79.139 ms, heap usage 171.371 MB -> 64.205 MB. [2026-01-03T15:29:58.538Z] ====== als (apache-spark) [default], iteration 4 completed (15806.357 ms) ====== [2026-01-03T15:29:58.538Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-03T15:29:58.538Z] GC before operation: completed in 82.074 ms, heap usage 213.194 MB -> 64.930 MB. [2026-01-03T15:30:14.864Z] ====== als (apache-spark) [default], iteration 5 completed (15998.075 ms) ====== [2026-01-03T15:30:14.864Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-03T15:30:14.864Z] GC before operation: completed in 81.474 ms, heap usage 384.450 MB -> 65.632 MB. [2026-01-03T15:30:31.023Z] ====== als (apache-spark) [default], iteration 6 completed (15469.558 ms) ====== [2026-01-03T15:30:31.023Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-03T15:30:31.023Z] GC before operation: completed in 84.759 ms, heap usage 347.777 MB -> 66.151 MB. [2026-01-03T15:30:47.190Z] ====== als (apache-spark) [default], iteration 7 completed (15762.728 ms) ====== [2026-01-03T15:30:47.190Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-03T15:30:47.190Z] GC before operation: completed in 95.173 ms, heap usage 304.753 MB -> 66.725 MB. [2026-01-03T15:31:03.291Z] ====== als (apache-spark) [default], iteration 8 completed (15899.342 ms) ====== [2026-01-03T15:31:03.291Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-03T15:31:03.291Z] GC before operation: completed in 85.233 ms, heap usage 509.398 MB -> 67.440 MB. [2026-01-03T15:31:19.423Z] ====== als (apache-spark) [default], iteration 9 completed (15197.932 ms) ====== [2026-01-03T15:31:19.423Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-03T15:31:19.423Z] GC before operation: completed in 91.266 ms, heap usage 203.545 MB -> 67.239 MB. [2026-01-03T15:31:35.596Z] ====== als (apache-spark) [default], iteration 10 completed (15799.560 ms) ====== [2026-01-03T15:31:35.596Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-03T15:31:35.596Z] GC before operation: completed in 95.344 ms, heap usage 194.656 MB -> 67.427 MB. [2026-01-03T15:31:49.133Z] ====== als (apache-spark) [default], iteration 11 completed (15450.797 ms) ====== [2026-01-03T15:31:49.133Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-03T15:31:49.133Z] GC before operation: completed in 88.976 ms, heap usage 205.544 MB -> 67.692 MB. [2026-01-03T15:32:05.277Z] ====== als (apache-spark) [default], iteration 12 completed (15179.032 ms) ====== [2026-01-03T15:32:05.277Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-03T15:32:05.277Z] GC before operation: completed in 87.916 ms, heap usage 129.525 MB -> 68.003 MB. [2026-01-03T15:32:21.449Z] ====== als (apache-spark) [default], iteration 13 completed (15357.951 ms) ====== [2026-01-03T15:32:21.449Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-03T15:32:21.449Z] GC before operation: completed in 91.020 ms, heap usage 354.970 MB -> 68.364 MB. [2026-01-03T15:32:37.511Z] ====== als (apache-spark) [default], iteration 14 completed (15542.923 ms) ====== [2026-01-03T15:32:37.511Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-03T15:32:37.511Z] GC before operation: completed in 92.132 ms, heap usage 499.783 MB -> 68.954 MB. [2026-01-03T15:32:53.542Z] ====== als (apache-spark) [default], iteration 15 completed (15292.919 ms) ====== [2026-01-03T15:32:53.542Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-03T15:32:53.542Z] GC before operation: completed in 93.268 ms, heap usage 352.171 MB -> 68.929 MB. [2026-01-03T15:33:06.946Z] ====== als (apache-spark) [default], iteration 16 completed (15283.749 ms) ====== [2026-01-03T15:33:06.946Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-03T15:33:06.946Z] GC before operation: completed in 88.877 ms, heap usage 252.018 MB -> 69.105 MB. [2026-01-03T15:33:23.034Z] ====== als (apache-spark) [default], iteration 17 completed (15443.979 ms) ====== [2026-01-03T15:33:23.034Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-03T15:33:23.034Z] GC before operation: completed in 92.209 ms, heap usage 260.135 MB -> 69.371 MB. [2026-01-03T15:33:39.167Z] ====== als (apache-spark) [default], iteration 18 completed (15392.609 ms) ====== [2026-01-03T15:33:39.167Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-03T15:33:39.167Z] GC before operation: completed in 87.809 ms, heap usage 354.780 MB -> 69.744 MB. [2026-01-03T15:33:55.268Z] ====== als (apache-spark) [default], iteration 19 completed (16125.211 ms) ====== [2026-01-03T15:33:55.268Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-03T15:33:55.268Z] GC before operation: completed in 89.105 ms, heap usage 357.230 MB -> 70.036 MB. [2026-01-03T15:34:11.419Z] ====== als (apache-spark) [default], iteration 20 completed (15403.154 ms) ====== [2026-01-03T15:34:11.419Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-03T15:34:11.419Z] GC before operation: completed in 89.546 ms, heap usage 255.672 MB -> 70.173 MB. [2026-01-03T15:34:27.623Z] ====== als (apache-spark) [default], iteration 21 completed (15349.906 ms) ====== [2026-01-03T15:34:27.623Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-03T15:34:27.623Z] GC before operation: completed in 97.114 ms, heap usage 320.482 MB -> 70.542 MB. [2026-01-03T15:34:43.739Z] ====== als (apache-spark) [default], iteration 22 completed (15728.706 ms) ====== [2026-01-03T15:34:43.740Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-03T15:34:43.740Z] GC before operation: completed in 88.478 ms, heap usage 344.672 MB -> 70.847 MB. [2026-01-03T15:34:57.294Z] ====== als (apache-spark) [default], iteration 23 completed (15172.881 ms) ====== [2026-01-03T15:34:57.294Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-03T15:34:57.294Z] GC before operation: completed in 90.088 ms, heap usage 150.679 MB -> 70.872 MB. [2026-01-03T15:35:13.796Z] ====== als (apache-spark) [default], iteration 24 completed (15395.129 ms) ====== [2026-01-03T15:35:13.796Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-03T15:35:13.796Z] GC before operation: completed in 90.658 ms, heap usage 249.658 MB -> 71.302 MB. [2026-01-03T15:35:30.015Z] ====== als (apache-spark) [default], iteration 25 completed (15565.193 ms) ====== [2026-01-03T15:35:30.015Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-03T15:35:30.015Z] GC before operation: completed in 92.682 ms, heap usage 530.893 MB -> 71.991 MB. [2026-01-03T15:35:46.148Z] ====== als (apache-spark) [default], iteration 26 completed (15219.536 ms) ====== [2026-01-03T15:35:46.148Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-03T15:35:46.148Z] GC before operation: completed in 91.606 ms, heap usage 155.939 MB -> 71.646 MB. [2026-01-03T15:35:59.492Z] ====== als (apache-spark) [default], iteration 27 completed (15519.406 ms) ====== [2026-01-03T15:35:59.493Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-03T15:35:59.853Z] GC before operation: completed in 103.117 ms, heap usage 515.245 MB -> 71.773 MB. [2026-01-03T15:36:16.206Z] ====== als (apache-spark) [default], iteration 28 completed (15549.172 ms) ====== [2026-01-03T15:36:16.206Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-03T15:36:16.206Z] GC before operation: completed in 95.029 ms, heap usage 149.344 MB -> 71.607 MB. [2026-01-03T15:36:32.422Z] ====== als (apache-spark) [default], iteration 29 completed (15350.406 ms) ====== [2026-01-03T15:36:32.422Z] ----------------------------------- [2026-01-03T15:36:32.422Z] renaissance-als_0_PASSED [2026-01-03T15:36:32.422Z] ----------------------------------- [2026-01-03T15:36:32.422Z] [2026-01-03T15:36:32.422Z] TEST TEARDOWN: [2026-01-03T15:36:32.422Z] Nothing to be done for teardown. [2026-01-03T15:36:32.422Z] renaissance-als_0 Finish Time: Sat Jan 3 15:36:31 2026 Epoch Time (ms): 1767454591557