[2026-01-24T14:56:12.210Z] Running test renaissance-als_0 ... [2026-01-24T14:56:12.210Z] =============================================== [2026-01-24T14:56:12.562Z] renaissance-als_0 Start Time: Sat Jan 24 14:56:12 2026 Epoch Time (ms): 1769266572287 [2026-01-24T14:56:12.562Z] variation: NoOptions [2026-01-24T14:56:12.562Z] JVM_OPTIONS: [2026-01-24T14:56:12.562Z] { \ [2026-01-24T14:56:12.562Z] echo ""; echo "TEST SETUP:"; \ [2026-01-24T14:56:12.562Z] echo "Nothing to be done for setup."; \ [2026-01-24T14:56:12.562Z] mkdir -p "C:/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17692663697190\\renaissance-als_0"; \ [2026-01-24T14:56:12.562Z] cd "C:/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17692663697190\\renaissance-als_0"; \ [2026-01-24T14:56:12.562Z] echo ""; echo "TESTING:"; \ [2026-01-24T14:56:12.562Z] "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_17692663697190\\renaissance-als_0"\\als.json" als; \ [2026-01-24T14:56:12.562Z] 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_17692663697190\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-24T14:56:12.562Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-24T14:56:12.562Z] echo "Nothing to be done for teardown."; \ [2026-01-24T14:56:12.562Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17692663697190\\TestTargetResult"; [2026-01-24T14:56:12.562Z] [2026-01-24T14:56:12.562Z] TEST SETUP: [2026-01-24T14:56:12.562Z] Nothing to be done for setup. [2026-01-24T14:56:12.912Z] [2026-01-24T14:56:12.912Z] TESTING: [2026-01-24T14:56:13.262Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-24T14:56:13.262Z] 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_17692663697190/renaissance-als_0/launcher-145612-12890009473541929075/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-24T14:56:13.263Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-24T14:56:13.263Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-24T14:56:29.304Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-24T14:56:31.002Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-24T14:56:31.002Z] GC before operation: completed in 42.738 ms, heap usage 57.000 MB -> 35.566 MB. [2026-01-24T14:56:59.300Z] ====== als (apache-spark) [default], iteration 0 completed (27201.399 ms) ====== [2026-01-24T14:56:59.300Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-24T14:56:59.300Z] GC before operation: completed in 154.759 ms, heap usage 439.317 MB -> 62.206 MB. [2026-01-24T14:57:15.558Z] ====== als (apache-spark) [default], iteration 1 completed (16096.257 ms) ====== [2026-01-24T14:57:15.558Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-24T14:57:15.558Z] GC before operation: completed in 77.050 ms, heap usage 183.005 MB -> 62.877 MB. [2026-01-24T14:57:31.815Z] ====== als (apache-spark) [default], iteration 2 completed (16008.547 ms) ====== [2026-01-24T14:57:31.815Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-24T14:57:31.815Z] GC before operation: completed in 85.602 ms, heap usage 317.810 MB -> 63.732 MB. [2026-01-24T14:57:47.927Z] ====== als (apache-spark) [default], iteration 3 completed (15571.786 ms) ====== [2026-01-24T14:57:47.927Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-24T14:57:47.927Z] GC before operation: completed in 91.615 ms, heap usage 224.768 MB -> 64.179 MB. [2026-01-24T14:58:04.125Z] ====== als (apache-spark) [default], iteration 4 completed (15818.554 ms) ====== [2026-01-24T14:58:04.125Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-24T14:58:04.125Z] GC before operation: completed in 86.549 ms, heap usage 227.148 MB -> 64.655 MB. [2026-01-24T14:58:20.375Z] ====== als (apache-spark) [default], iteration 5 completed (16142.826 ms) ====== [2026-01-24T14:58:20.375Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-24T14:58:20.375Z] GC before operation: completed in 89.779 ms, heap usage 316.706 MB -> 65.347 MB. [2026-01-24T14:58:36.605Z] ====== als (apache-spark) [default], iteration 6 completed (15150.673 ms) ====== [2026-01-24T14:58:36.605Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-24T14:58:36.605Z] GC before operation: completed in 87.196 ms, heap usage 453.786 MB -> 66.159 MB. [2026-01-24T14:58:52.795Z] ====== als (apache-spark) [default], iteration 7 completed (15524.146 ms) ====== [2026-01-24T14:58:52.795Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-24T14:58:52.795Z] GC before operation: completed in 90.010 ms, heap usage 523.362 MB -> 66.652 MB. [2026-01-24T14:59:08.917Z] ====== als (apache-spark) [default], iteration 8 completed (15412.523 ms) ====== [2026-01-24T14:59:08.917Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-24T14:59:08.917Z] GC before operation: completed in 87.949 ms, heap usage 452.436 MB -> 67.183 MB. [2026-01-24T14:59:22.242Z] ====== als (apache-spark) [default], iteration 9 completed (15118.468 ms) ====== [2026-01-24T14:59:22.242Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-24T14:59:22.242Z] GC before operation: completed in 108.323 ms, heap usage 232.370 MB -> 67.143 MB. [2026-01-24T14:59:38.266Z] ====== als (apache-spark) [default], iteration 10 completed (15232.278 ms) ====== [2026-01-24T14:59:38.266Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-24T14:59:38.266Z] GC before operation: completed in 91.829 ms, heap usage 264.085 MB -> 67.393 MB. [2026-01-24T14:59:54.412Z] ====== als (apache-spark) [default], iteration 11 completed (15055.771 ms) ====== [2026-01-24T14:59:54.412Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-24T14:59:54.412Z] GC before operation: completed in 95.015 ms, heap usage 228.980 MB -> 67.634 MB. [2026-01-24T15:00:10.489Z] ====== als (apache-spark) [default], iteration 12 completed (14955.588 ms) ====== [2026-01-24T15:00:10.489Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-24T15:00:10.489Z] GC before operation: completed in 91.954 ms, heap usage 348.619 MB -> 68.069 MB. [2026-01-24T15:00:23.801Z] ====== als (apache-spark) [default], iteration 13 completed (15577.638 ms) ====== [2026-01-24T15:00:23.801Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-24T15:00:23.801Z] GC before operation: completed in 87.210 ms, heap usage 508.188 MB -> 68.513 MB. [2026-01-24T15:00:39.904Z] ====== als (apache-spark) [default], iteration 14 completed (15312.064 ms) ====== [2026-01-24T15:00:39.904Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-24T15:00:39.904Z] GC before operation: completed in 79.647 ms, heap usage 273.034 MB -> 68.409 MB. [2026-01-24T15:00:56.198Z] ====== als (apache-spark) [default], iteration 15 completed (14908.280 ms) ====== [2026-01-24T15:00:56.198Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-24T15:00:56.198Z] GC before operation: completed in 88.740 ms, heap usage 521.835 MB -> 68.996 MB. [2026-01-24T15:01:12.218Z] ====== als (apache-spark) [default], iteration 16 completed (15453.134 ms) ====== [2026-01-24T15:01:12.218Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-24T15:01:12.218Z] GC before operation: completed in 86.519 ms, heap usage 343.446 MB -> 69.092 MB. [2026-01-24T15:01:25.478Z] ====== als (apache-spark) [default], iteration 17 completed (14825.633 ms) ====== [2026-01-24T15:01:25.478Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-24T15:01:25.478Z] GC before operation: completed in 83.564 ms, heap usage 444.445 MB -> 69.502 MB. [2026-01-24T15:01:41.441Z] ====== als (apache-spark) [default], iteration 18 completed (15037.491 ms) ====== [2026-01-24T15:01:41.441Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-24T15:01:41.441Z] GC before operation: completed in 89.720 ms, heap usage 488.425 MB -> 69.821 MB. [2026-01-24T15:01:57.710Z] ====== als (apache-spark) [default], iteration 19 completed (15759.411 ms) ====== [2026-01-24T15:01:57.710Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-24T15:01:57.710Z] GC before operation: completed in 83.179 ms, heap usage 264.347 MB -> 69.723 MB. [2026-01-24T15:02:13.768Z] ====== als (apache-spark) [default], iteration 20 completed (15480.504 ms) ====== [2026-01-24T15:02:13.768Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-24T15:02:13.768Z] GC before operation: completed in 84.961 ms, heap usage 255.642 MB -> 70.073 MB. [2026-01-24T15:02:27.133Z] ====== als (apache-spark) [default], iteration 21 completed (14862.371 ms) ====== [2026-01-24T15:02:27.133Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-24T15:02:27.503Z] GC before operation: completed in 85.323 ms, heap usage 366.651 MB -> 70.296 MB. [2026-01-24T15:02:43.686Z] ====== als (apache-spark) [default], iteration 22 completed (15711.988 ms) ====== [2026-01-24T15:02:43.686Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-24T15:02:43.686Z] GC before operation: completed in 86.224 ms, heap usage 462.559 MB -> 70.778 MB. [2026-01-24T15:02:59.793Z] ====== als (apache-spark) [default], iteration 23 completed (15239.022 ms) ====== [2026-01-24T15:02:59.793Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-24T15:02:59.793Z] GC before operation: completed in 85.614 ms, heap usage 271.186 MB -> 70.775 MB. [2026-01-24T15:03:16.026Z] ====== als (apache-spark) [default], iteration 24 completed (15425.937 ms) ====== [2026-01-24T15:03:16.026Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-24T15:03:16.026Z] GC before operation: completed in 88.134 ms, heap usage 457.044 MB -> 71.335 MB. [2026-01-24T15:03:32.164Z] ====== als (apache-spark) [default], iteration 25 completed (15330.689 ms) ====== [2026-01-24T15:03:32.164Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-24T15:03:32.164Z] GC before operation: completed in 84.883 ms, heap usage 360.526 MB -> 71.445 MB. [2026-01-24T15:03:45.615Z] ====== als (apache-spark) [default], iteration 26 completed (14825.369 ms) ====== [2026-01-24T15:03:45.615Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-24T15:03:45.615Z] GC before operation: completed in 83.970 ms, heap usage 254.753 MB -> 71.555 MB. [2026-01-24T15:04:01.710Z] ====== als (apache-spark) [default], iteration 27 completed (15178.401 ms) ====== [2026-01-24T15:04:01.710Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-24T15:04:01.710Z] GC before operation: completed in 86.490 ms, heap usage 240.286 MB -> 71.842 MB. [2026-01-24T15:04:17.837Z] ====== als (apache-spark) [default], iteration 28 completed (15383.786 ms) ====== [2026-01-24T15:04:17.837Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-24T15:04:17.837Z] GC before operation: completed in 91.506 ms, heap usage 360.768 MB -> 72.214 MB. [2026-01-24T15:04:31.239Z] ====== als (apache-spark) [default], iteration 29 completed (15017.473 ms) ====== [2026-01-24T15:04:31.960Z] ----------------------------------- [2026-01-24T15:04:31.960Z] renaissance-als_0_PASSED [2026-01-24T15:04:31.960Z] ----------------------------------- [2026-01-24T15:04:31.960Z] [2026-01-24T15:04:31.960Z] TEST TEARDOWN: [2026-01-24T15:04:31.960Z] Nothing to be done for teardown. [2026-01-24T15:04:31.960Z] renaissance-als_0 Finish Time: Sat Jan 24 15:04:31 2026 Epoch Time (ms): 1769267071796