[2026-02-05T22:31:12.424Z] Running test renaissance-als_0 ... [2026-02-05T22:31:12.424Z] =============================================== [2026-02-05T22:31:12.424Z] renaissance-als_0 Start Time: Thu Feb 5 22:31:12 2026 Epoch Time (ms): 1770330672206 [2026-02-05T22:31:12.424Z] variation: NoOptions [2026-02-05T22:31:12.424Z] JVM_OPTIONS: [2026-02-05T22:31:12.424Z] { \ [2026-02-05T22:31:12.424Z] echo ""; echo "TEST SETUP:"; \ [2026-02-05T22:31:12.424Z] echo "Nothing to be done for setup."; \ [2026-02-05T22:31:12.424Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17703304964296/renaissance-als_0"; \ [2026-02-05T22:31:12.424Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17703304964296/renaissance-als_0"; \ [2026-02-05T22:31:12.424Z] echo ""; echo "TESTING:"; \ [2026-02-05T22:31:12.424Z] "/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_17703304964296/renaissance-als_0"/als.json" als; \ [2026-02-05T22:31:12.424Z] 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_17703304964296/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-05T22:31:12.424Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-05T22:31:12.424Z] echo "Nothing to be done for teardown."; \ [2026-02-05T22:31:12.424Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17703304964296/TestTargetResult"; [2026-02-05T22:31:12.424Z] [2026-02-05T22:31:12.424Z] TEST SETUP: [2026-02-05T22:31:12.424Z] Nothing to be done for setup. [2026-02-05T22:31:12.424Z] [2026-02-05T22:31:12.424Z] TESTING: [2026-02-05T22:31:12.754Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-02-05T22:31:12.754Z] 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_17703304964296/renaissance-als_0/launcher-223112-7553909836689776545/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-02-05T22:31:12.754Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-02-05T22:31:12.754Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-02-05T22:31:21.650Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-02-05T22:31:24.620Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-05T22:31:24.620Z] GC before operation: completed in 49.458 ms, heap usage 159.016 MB -> 34.940 MB. [2026-02-05T22:31:52.448Z] ====== als (apache-spark) [default], iteration 0 completed (25270.709 ms) ====== [2026-02-05T22:31:52.448Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-05T22:31:52.448Z] GC before operation: completed in 101.180 ms, heap usage 154.421 MB -> 61.472 MB. [2026-02-05T22:32:03.287Z] ====== als (apache-spark) [default], iteration 1 completed (12126.639 ms) ====== [2026-02-05T22:32:03.287Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-05T22:32:03.287Z] GC before operation: completed in 92.464 ms, heap usage 186.812 MB -> 62.226 MB. [2026-02-05T22:32:14.121Z] ====== als (apache-spark) [default], iteration 2 completed (11010.303 ms) ====== [2026-02-05T22:32:14.121Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-05T22:32:14.456Z] GC before operation: completed in 93.852 ms, heap usage 210.828 MB -> 62.782 MB. [2026-02-05T22:32:27.601Z] ====== als (apache-spark) [default], iteration 3 completed (11156.639 ms) ====== [2026-02-05T22:32:27.601Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-05T22:32:27.601Z] GC before operation: completed in 89.864 ms, heap usage 222.144 MB -> 63.505 MB. [2026-02-05T22:32:36.486Z] ====== als (apache-spark) [default], iteration 4 completed (10543.074 ms) ====== [2026-02-05T22:32:36.486Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-05T22:32:36.824Z] GC before operation: completed in 96.745 ms, heap usage 196.644 MB -> 63.955 MB. [2026-02-05T22:32:47.745Z] ====== als (apache-spark) [default], iteration 5 completed (10142.750 ms) ====== [2026-02-05T22:32:47.745Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-05T22:32:47.745Z] GC before operation: completed in 103.272 ms, heap usage 241.707 MB -> 64.585 MB. [2026-02-05T22:32:58.573Z] ====== als (apache-spark) [default], iteration 6 completed (9970.623 ms) ====== [2026-02-05T22:32:58.573Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-05T22:32:58.573Z] GC before operation: completed in 94.825 ms, heap usage 198.333 MB -> 64.982 MB. [2026-02-05T22:33:09.401Z] ====== als (apache-spark) [default], iteration 7 completed (10303.179 ms) ====== [2026-02-05T22:33:09.401Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-05T22:33:09.401Z] GC before operation: completed in 98.905 ms, heap usage 117.743 MB -> 65.549 MB. [2026-02-05T22:33:18.356Z] ====== als (apache-spark) [default], iteration 8 completed (9869.867 ms) ====== [2026-02-05T22:33:18.356Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-05T22:33:18.356Z] GC before operation: completed in 103.314 ms, heap usage 299.622 MB -> 66.295 MB. [2026-02-05T22:33:29.208Z] ====== als (apache-spark) [default], iteration 9 completed (9971.386 ms) ====== [2026-02-05T22:33:29.208Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-05T22:33:29.208Z] GC before operation: completed in 105.387 ms, heap usage 447.165 MB -> 66.770 MB. [2026-02-05T22:33:40.039Z] ====== als (apache-spark) [default], iteration 10 completed (9965.872 ms) ====== [2026-02-05T22:33:40.039Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-05T22:33:40.039Z] GC before operation: completed in 108.599 ms, heap usage 252.115 MB -> 66.624 MB. [2026-02-05T22:33:50.887Z] ====== als (apache-spark) [default], iteration 11 completed (9931.851 ms) ====== [2026-02-05T22:33:50.887Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-05T22:33:50.887Z] GC before operation: completed in 96.480 ms, heap usage 185.766 MB -> 66.804 MB. [2026-02-05T22:33:59.779Z] ====== als (apache-spark) [default], iteration 12 completed (9986.865 ms) ====== [2026-02-05T22:33:59.779Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-05T22:33:59.779Z] GC before operation: completed in 105.297 ms, heap usage 356.744 MB -> 67.393 MB. [2026-02-05T22:34:10.724Z] ====== als (apache-spark) [default], iteration 13 completed (9772.325 ms) ====== [2026-02-05T22:34:10.725Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-05T22:34:10.725Z] GC before operation: completed in 96.412 ms, heap usage 201.677 MB -> 67.372 MB. [2026-02-05T22:34:19.618Z] ====== als (apache-spark) [default], iteration 14 completed (9637.019 ms) ====== [2026-02-05T22:34:19.618Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-05T22:34:19.618Z] GC before operation: completed in 104.431 ms, heap usage 177.950 MB -> 67.653 MB. [2026-02-05T22:34:30.453Z] ====== als (apache-spark) [default], iteration 15 completed (9724.034 ms) ====== [2026-02-05T22:34:30.453Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-05T22:34:30.453Z] GC before operation: completed in 161.021 ms, heap usage 93.961 MB -> 67.935 MB. [2026-02-05T22:34:41.304Z] ====== als (apache-spark) [default], iteration 16 completed (9503.365 ms) ====== [2026-02-05T22:34:41.304Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-05T22:34:41.304Z] GC before operation: completed in 101.938 ms, heap usage 174.387 MB -> 68.057 MB. [2026-02-05T22:34:50.219Z] ====== als (apache-spark) [default], iteration 17 completed (9623.430 ms) ====== [2026-02-05T22:34:50.219Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-05T22:34:50.219Z] GC before operation: completed in 101.391 ms, heap usage 97.092 MB -> 68.054 MB. [2026-02-05T22:35:01.219Z] ====== als (apache-spark) [default], iteration 18 completed (9728.991 ms) ====== [2026-02-05T22:35:01.219Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-05T22:35:01.219Z] GC before operation: completed in 100.402 ms, heap usage 441.463 MB -> 69.014 MB. [2026-02-05T22:35:10.125Z] ====== als (apache-spark) [default], iteration 19 completed (9280.538 ms) ====== [2026-02-05T22:35:10.125Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-05T22:35:10.125Z] GC before operation: completed in 102.653 ms, heap usage 276.319 MB -> 68.964 MB. [2026-02-05T22:35:19.037Z] ====== als (apache-spark) [default], iteration 20 completed (9709.087 ms) ====== [2026-02-05T22:35:19.037Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-05T22:35:19.037Z] GC before operation: completed in 98.079 ms, heap usage 318.097 MB -> 69.371 MB. [2026-02-05T22:35:29.867Z] ====== als (apache-spark) [default], iteration 21 completed (9682.922 ms) ====== [2026-02-05T22:35:29.867Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-05T22:35:29.867Z] GC before operation: completed in 105.047 ms, heap usage 215.504 MB -> 69.288 MB. [2026-02-05T22:35:38.803Z] ====== als (apache-spark) [default], iteration 22 completed (9585.862 ms) ====== [2026-02-05T22:35:38.803Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-05T22:35:39.134Z] GC before operation: completed in 100.513 ms, heap usage 207.912 MB -> 69.608 MB. [2026-02-05T22:35:50.050Z] ====== als (apache-spark) [default], iteration 23 completed (9214.692 ms) ====== [2026-02-05T22:35:50.050Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-05T22:35:50.050Z] GC before operation: completed in 100.892 ms, heap usage 306.770 MB -> 70.085 MB. [2026-02-05T22:35:58.960Z] ====== als (apache-spark) [default], iteration 24 completed (9638.525 ms) ====== [2026-02-05T22:35:58.960Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-05T22:35:58.960Z] GC before operation: completed in 104.621 ms, heap usage 356.596 MB -> 70.399 MB. [2026-02-05T22:36:07.852Z] ====== als (apache-spark) [default], iteration 25 completed (9321.603 ms) ====== [2026-02-05T22:36:07.852Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-05T22:36:07.852Z] GC before operation: completed in 110.103 ms, heap usage 443.462 MB -> 70.814 MB. [2026-02-05T22:36:18.687Z] ====== als (apache-spark) [default], iteration 26 completed (9272.222 ms) ====== [2026-02-05T22:36:18.687Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-05T22:36:18.687Z] GC before operation: completed in 153.841 ms, heap usage 262.768 MB -> 70.745 MB. [2026-02-05T22:36:27.659Z] ====== als (apache-spark) [default], iteration 27 completed (9425.344 ms) ====== [2026-02-05T22:36:27.659Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-05T22:36:27.659Z] GC before operation: completed in 100.175 ms, heap usage 130.144 MB -> 70.914 MB. [2026-02-05T22:36:38.495Z] ====== als (apache-spark) [default], iteration 28 completed (9398.511 ms) ====== [2026-02-05T22:36:38.495Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-05T22:36:38.495Z] GC before operation: completed in 105.661 ms, heap usage 228.335 MB -> 71.332 MB. [2026-02-05T22:36:47.395Z] ====== als (apache-spark) [default], iteration 29 completed (9087.032 ms) ====== [2026-02-05T22:36:47.395Z] ----------------------------------- [2026-02-05T22:36:47.395Z] renaissance-als_0_PASSED [2026-02-05T22:36:47.395Z] ----------------------------------- [2026-02-05T22:36:47.395Z] [2026-02-05T22:36:47.395Z] TEST TEARDOWN: [2026-02-05T22:36:47.395Z] Nothing to be done for teardown. [2026-02-05T22:36:47.395Z] renaissance-als_0 Finish Time: Thu Feb 5 22:36:46 2026 Epoch Time (ms): 1770331006571