[2026-01-24T14:33:04.818Z] Running test renaissance-als_0 ... [2026-01-24T14:33:04.818Z] =============================================== [2026-01-24T14:33:04.818Z] renaissance-als_0 Start Time: Sat Jan 24 14:33:04 2026 Epoch Time (ms): 1769265184696 [2026-01-24T14:33:04.818Z] variation: NoOptions [2026-01-24T14:33:04.818Z] JVM_OPTIONS: [2026-01-24T14:33:04.818Z] { \ [2026-01-24T14:33:04.818Z] echo ""; echo "TEST SETUP:"; \ [2026-01-24T14:33:04.818Z] echo "Nothing to be done for setup."; \ [2026-01-24T14:33:04.818Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17692649899272/renaissance-als_0"; \ [2026-01-24T14:33:04.818Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17692649899272/renaissance-als_0"; \ [2026-01-24T14:33:04.818Z] echo ""; echo "TESTING:"; \ [2026-01-24T14:33:04.818Z] "/home/jenkins/workspace/Test_openjdk25_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_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17692649899272/renaissance-als_0"/als.json" als; \ [2026-01-24T14:33:04.818Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17692649899272/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-24T14:33:04.818Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-24T14:33:04.818Z] echo "Nothing to be done for teardown."; \ [2026-01-24T14:33:04.818Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17692649899272/TestTargetResult"; [2026-01-24T14:33:04.818Z] [2026-01-24T14:33:04.818Z] TEST SETUP: [2026-01-24T14:33:04.818Z] Nothing to be done for setup. [2026-01-24T14:33:04.818Z] [2026-01-24T14:33:04.818Z] TESTING: [2026-01-24T14:33:05.549Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-24T14:33:05.549Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/output_17692649899272/renaissance-als_0/launcher-143304-16041156150829247745/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-24T14:33:05.549Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-24T14:33:05.549Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-24T14:33:12.962Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-01-24T14:33:16.812Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-24T14:33:16.812Z] GC before operation: completed in 50.698 ms, heap usage 68.000 MB -> 34.928 MB. [2026-01-24T14:33:44.792Z] ====== als (apache-spark) [default], iteration 0 completed (27325.793 ms) ====== [2026-01-24T14:33:44.792Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-24T14:33:44.792Z] GC before operation: completed in 119.548 ms, heap usage 150.422 MB -> 61.318 MB. [2026-01-24T14:34:00.846Z] ====== als (apache-spark) [default], iteration 1 completed (13906.561 ms) ====== [2026-01-24T14:34:00.846Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-24T14:34:00.846Z] GC before operation: completed in 112.470 ms, heap usage 453.778 MB -> 62.552 MB. [2026-01-24T14:34:14.140Z] ====== als (apache-spark) [default], iteration 2 completed (12947.310 ms) ====== [2026-01-24T14:34:14.140Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-24T14:34:14.140Z] GC before operation: completed in 110.839 ms, heap usage 431.946 MB -> 63.109 MB. [2026-01-24T14:34:27.478Z] ====== als (apache-spark) [default], iteration 3 completed (12958.864 ms) ====== [2026-01-24T14:34:27.478Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-24T14:34:27.478Z] GC before operation: completed in 112.680 ms, heap usage 207.899 MB -> 63.291 MB. [2026-01-24T14:34:40.755Z] ====== als (apache-spark) [default], iteration 4 completed (12694.012 ms) ====== [2026-01-24T14:34:40.755Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-24T14:34:40.755Z] GC before operation: completed in 110.465 ms, heap usage 343.703 MB -> 64.021 MB. [2026-01-24T14:34:51.737Z] ====== als (apache-spark) [default], iteration 5 completed (11741.557 ms) ====== [2026-01-24T14:34:51.737Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-24T14:34:51.737Z] GC before operation: completed in 114.195 ms, heap usage 470.224 MB -> 64.816 MB. [2026-01-24T14:35:05.035Z] ====== als (apache-spark) [default], iteration 6 completed (11749.887 ms) ====== [2026-01-24T14:35:05.035Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-24T14:35:05.035Z] GC before operation: completed in 114.013 ms, heap usage 241.603 MB -> 65.117 MB. [2026-01-24T14:35:16.020Z] ====== als (apache-spark) [default], iteration 7 completed (12373.096 ms) ====== [2026-01-24T14:35:16.020Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-24T14:35:16.020Z] GC before operation: completed in 120.018 ms, heap usage 268.685 MB -> 65.736 MB. [2026-01-24T14:35:29.477Z] ====== als (apache-spark) [default], iteration 8 completed (11987.477 ms) ====== [2026-01-24T14:35:29.477Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-24T14:35:29.477Z] GC before operation: completed in 115.380 ms, heap usage 103.062 MB -> 66.074 MB. [2026-01-24T14:35:40.426Z] ====== als (apache-spark) [default], iteration 9 completed (11878.284 ms) ====== [2026-01-24T14:35:40.426Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-24T14:35:40.770Z] GC before operation: completed in 130.375 ms, heap usage 462.570 MB -> 66.694 MB. [2026-01-24T14:35:54.110Z] ====== als (apache-spark) [default], iteration 10 completed (11800.570 ms) ====== [2026-01-24T14:35:54.110Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-24T14:35:54.110Z] GC before operation: completed in 120.938 ms, heap usage 488.941 MB -> 66.887 MB. [2026-01-24T14:36:05.062Z] ====== als (apache-spark) [default], iteration 11 completed (11586.692 ms) ====== [2026-01-24T14:36:05.062Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-24T14:36:05.062Z] GC before operation: completed in 123.023 ms, heap usage 520.771 MB -> 67.300 MB. [2026-01-24T14:36:18.356Z] ====== als (apache-spark) [default], iteration 12 completed (11508.191 ms) ====== [2026-01-24T14:36:18.356Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-24T14:36:18.356Z] GC before operation: completed in 130.650 ms, heap usage 484.854 MB -> 67.341 MB. [2026-01-24T14:36:29.339Z] ====== als (apache-spark) [default], iteration 13 completed (11687.337 ms) ====== [2026-01-24T14:36:29.339Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-24T14:36:29.339Z] GC before operation: completed in 120.443 ms, heap usage 362.436 MB -> 67.391 MB. [2026-01-24T14:36:40.400Z] ====== als (apache-spark) [default], iteration 14 completed (11610.473 ms) ====== [2026-01-24T14:36:40.400Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-24T14:36:40.400Z] GC before operation: completed in 126.157 ms, heap usage 112.962 MB -> 67.483 MB. [2026-01-24T14:36:53.682Z] ====== als (apache-spark) [default], iteration 15 completed (11428.728 ms) ====== [2026-01-24T14:36:53.682Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-24T14:36:53.682Z] GC before operation: completed in 197.481 ms, heap usage 489.961 MB -> 68.325 MB. [2026-01-24T14:37:04.667Z] ====== als (apache-spark) [default], iteration 16 completed (11422.961 ms) ====== [2026-01-24T14:37:04.667Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-24T14:37:04.667Z] GC before operation: completed in 124.628 ms, heap usage 259.573 MB -> 68.122 MB. [2026-01-24T14:37:15.697Z] ====== als (apache-spark) [default], iteration 17 completed (11386.855 ms) ====== [2026-01-24T14:37:15.697Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-24T14:37:16.037Z] GC before operation: completed in 127.588 ms, heap usage 215.438 MB -> 68.335 MB. [2026-01-24T14:37:29.379Z] ====== als (apache-spark) [default], iteration 18 completed (11689.869 ms) ====== [2026-01-24T14:37:29.379Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-24T14:37:29.379Z] GC before operation: completed in 125.677 ms, heap usage 205.020 MB -> 68.459 MB. [2026-01-24T14:37:40.552Z] ====== als (apache-spark) [default], iteration 19 completed (11492.441 ms) ====== [2026-01-24T14:37:40.552Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-24T14:37:40.552Z] GC before operation: completed in 125.968 ms, heap usage 191.820 MB -> 68.793 MB. [2026-01-24T14:37:53.811Z] ====== als (apache-spark) [default], iteration 20 completed (11683.398 ms) ====== [2026-01-24T14:37:53.811Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-24T14:37:53.811Z] GC before operation: completed in 125.422 ms, heap usage 208.373 MB -> 69.148 MB. [2026-01-24T14:38:05.480Z] ====== als (apache-spark) [default], iteration 21 completed (12268.310 ms) ====== [2026-01-24T14:38:05.480Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-24T14:38:05.480Z] GC before operation: completed in 127.038 ms, heap usage 454.366 MB -> 69.745 MB. [2026-01-24T14:38:16.424Z] ====== als (apache-spark) [default], iteration 22 completed (11479.883 ms) ====== [2026-01-24T14:38:16.424Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-24T14:38:16.424Z] GC before operation: completed in 136.720 ms, heap usage 212.000 MB -> 69.488 MB. [2026-01-24T14:38:29.834Z] ====== als (apache-spark) [default], iteration 23 completed (11408.148 ms) ====== [2026-01-24T14:38:29.834Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-24T14:38:29.834Z] GC before operation: completed in 129.933 ms, heap usage 201.920 MB -> 69.801 MB. [2026-01-24T14:38:40.779Z] ====== als (apache-spark) [default], iteration 24 completed (11399.192 ms) ====== [2026-01-24T14:38:40.779Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-24T14:38:40.779Z] GC before operation: completed in 151.965 ms, heap usage 500.518 MB -> 70.479 MB. [2026-01-24T14:38:51.780Z] ====== als (apache-spark) [default], iteration 25 completed (11510.253 ms) ====== [2026-01-24T14:38:51.780Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-24T14:38:51.780Z] GC before operation: completed in 128.323 ms, heap usage 173.493 MB -> 70.374 MB. [2026-01-24T14:39:05.033Z] ====== als (apache-spark) [default], iteration 26 completed (11362.926 ms) ====== [2026-01-24T14:39:05.033Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-24T14:39:05.033Z] GC before operation: completed in 128.357 ms, heap usage 117.661 MB -> 70.686 MB. [2026-01-24T14:39:15.980Z] ====== als (apache-spark) [default], iteration 27 completed (11485.889 ms) ====== [2026-01-24T14:39:15.980Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-24T14:39:15.980Z] GC before operation: completed in 127.397 ms, heap usage 355.630 MB -> 71.076 MB. [2026-01-24T14:39:29.252Z] ====== als (apache-spark) [default], iteration 28 completed (12332.192 ms) ====== [2026-01-24T14:39:29.252Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-24T14:39:29.252Z] GC before operation: completed in 132.439 ms, heap usage 485.480 MB -> 71.053 MB. [2026-01-24T14:39:40.196Z] ====== als (apache-spark) [default], iteration 29 completed (11298.680 ms) ====== [2026-01-24T14:39:40.196Z] ----------------------------------- [2026-01-24T14:39:40.196Z] renaissance-als_0_PASSED [2026-01-24T14:39:40.196Z] ----------------------------------- [2026-01-24T14:39:40.196Z] [2026-01-24T14:39:40.196Z] TEST TEARDOWN: [2026-01-24T14:39:40.196Z] Nothing to be done for teardown. [2026-01-24T14:39:40.196Z] renaissance-als_0 Finish Time: Sat Jan 24 14:39:39 2026 Epoch Time (ms): 1769265579786