[2025-12-20T13:06:17.253Z] Running test renaissance-als_0 ... [2025-12-20T13:06:17.253Z] =============================================== [2025-12-20T13:06:17.253Z] renaissance-als_0 Start Time: Sat Dec 20 13:06:16 2025 Epoch Time (ms): 1766235976987 [2025-12-20T13:06:17.253Z] variation: NoOptions [2025-12-20T13:06:17.253Z] JVM_OPTIONS: [2025-12-20T13:06:17.253Z] { \ [2025-12-20T13:06:17.253Z] echo ""; echo "TEST SETUP:"; \ [2025-12-20T13:06:17.253Z] echo "Nothing to be done for setup."; \ [2025-12-20T13:06:17.253Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17662357818326/renaissance-als_0"; \ [2025-12-20T13:06:17.253Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17662357818326/renaissance-als_0"; \ [2025-12-20T13:06:17.253Z] echo ""; echo "TESTING:"; \ [2025-12-20T13:06:17.253Z] "/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_17662357818326/renaissance-als_0"/als.json" als; \ [2025-12-20T13:06:17.253Z] 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_17662357818326/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-20T13:06:17.253Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-20T13:06:17.253Z] echo "Nothing to be done for teardown."; \ [2025-12-20T13:06:17.253Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17662357818326/TestTargetResult"; [2025-12-20T13:06:17.253Z] [2025-12-20T13:06:17.253Z] TEST SETUP: [2025-12-20T13:06:17.253Z] Nothing to be done for setup. [2025-12-20T13:06:17.253Z] [2025-12-20T13:06:17.253Z] TESTING: [2025-12-20T13:06:17.912Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-20T13:06:17.912Z] 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_17662357818326/renaissance-als_0/launcher-130617-14220553777416599161/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-20T13:06:17.912Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-20T13:06:17.912Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-20T13:06:28.441Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-12-20T13:06:32.335Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-20T13:06:32.335Z] GC before operation: completed in 60.202 ms, heap usage 166.500 MB -> 34.909 MB. [2025-12-20T13:07:00.693Z] ====== als (apache-spark) [default], iteration 0 completed (27243.631 ms) ====== [2025-12-20T13:07:00.693Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-20T13:07:00.693Z] GC before operation: completed in 133.343 ms, heap usage 210.612 MB -> 61.215 MB. [2025-12-20T13:07:15.628Z] ====== als (apache-spark) [default], iteration 1 completed (14609.115 ms) ====== [2025-12-20T13:07:15.628Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-20T13:07:16.274Z] GC before operation: completed in 103.323 ms, heap usage 215.961 MB -> 62.227 MB. [2025-12-20T13:07:30.980Z] ====== als (apache-spark) [default], iteration 2 completed (13371.520 ms) ====== [2025-12-20T13:07:30.980Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-20T13:07:30.980Z] GC before operation: completed in 111.689 ms, heap usage 494.945 MB -> 63.124 MB. [2025-12-20T13:07:43.514Z] ====== als (apache-spark) [default], iteration 3 completed (13438.263 ms) ====== [2025-12-20T13:07:43.514Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-20T13:07:43.514Z] GC before operation: completed in 106.802 ms, heap usage 224.872 MB -> 63.408 MB. [2025-12-20T13:07:58.146Z] ====== als (apache-spark) [default], iteration 4 completed (12890.836 ms) ====== [2025-12-20T13:07:58.146Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-20T13:07:58.146Z] GC before operation: completed in 105.222 ms, heap usage 314.054 MB -> 64.059 MB. [2025-12-20T13:08:10.622Z] ====== als (apache-spark) [default], iteration 5 completed (12765.999 ms) ====== [2025-12-20T13:08:10.622Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-20T13:08:10.622Z] GC before operation: completed in 115.761 ms, heap usage 81.002 MB -> 64.283 MB. [2025-12-20T13:08:23.423Z] ====== als (apache-spark) [default], iteration 6 completed (12495.149 ms) ====== [2025-12-20T13:08:23.423Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-20T13:08:23.423Z] GC before operation: completed in 132.847 ms, heap usage 111.785 MB -> 64.834 MB. [2025-12-20T13:08:35.858Z] ====== als (apache-spark) [default], iteration 7 completed (12849.921 ms) ====== [2025-12-20T13:08:35.858Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-20T13:08:35.858Z] GC before operation: completed in 159.117 ms, heap usage 359.475 MB -> 65.644 MB. [2025-12-20T13:08:50.504Z] ====== als (apache-spark) [default], iteration 8 completed (12599.625 ms) ====== [2025-12-20T13:08:50.504Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-20T13:08:50.504Z] GC before operation: completed in 114.119 ms, heap usage 354.344 MB -> 66.154 MB. [2025-12-20T13:09:03.119Z] ====== als (apache-spark) [default], iteration 9 completed (12878.875 ms) ====== [2025-12-20T13:09:03.119Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-20T13:09:03.119Z] GC before operation: completed in 120.805 ms, heap usage 325.363 MB -> 66.492 MB. [2025-12-20T13:09:15.738Z] ====== als (apache-spark) [default], iteration 10 completed (12709.842 ms) ====== [2025-12-20T13:09:15.738Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-20T13:09:15.738Z] GC before operation: completed in 116.983 ms, heap usage 338.996 MB -> 66.770 MB. [2025-12-20T13:09:28.369Z] ====== als (apache-spark) [default], iteration 11 completed (12473.773 ms) ====== [2025-12-20T13:09:28.369Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-20T13:09:28.369Z] GC before operation: completed in 121.645 ms, heap usage 234.299 MB -> 66.921 MB. [2025-12-20T13:09:41.045Z] ====== als (apache-spark) [default], iteration 12 completed (12289.786 ms) ====== [2025-12-20T13:09:41.045Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-20T13:09:41.045Z] GC before operation: completed in 120.747 ms, heap usage 183.238 MB -> 67.044 MB. [2025-12-20T13:09:54.360Z] ====== als (apache-spark) [default], iteration 13 completed (12413.872 ms) ====== [2025-12-20T13:09:54.360Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-20T13:09:54.360Z] GC before operation: completed in 120.364 ms, heap usage 208.269 MB -> 67.329 MB. [2025-12-20T13:10:06.974Z] ====== als (apache-spark) [default], iteration 14 completed (12372.550 ms) ====== [2025-12-20T13:10:06.974Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-20T13:10:06.974Z] GC before operation: completed in 121.018 ms, heap usage 105.050 MB -> 67.518 MB. [2025-12-20T13:10:19.579Z] ====== als (apache-spark) [default], iteration 15 completed (12268.191 ms) ====== [2025-12-20T13:10:19.579Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-20T13:10:19.579Z] GC before operation: completed in 121.114 ms, heap usage 357.345 MB -> 68.042 MB. [2025-12-20T13:10:32.166Z] ====== als (apache-spark) [default], iteration 16 completed (12182.332 ms) ====== [2025-12-20T13:10:32.166Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-20T13:10:32.166Z] GC before operation: completed in 129.768 ms, heap usage 217.607 MB -> 68.132 MB. [2025-12-20T13:10:44.695Z] ====== als (apache-spark) [default], iteration 17 completed (12479.924 ms) ====== [2025-12-20T13:10:44.695Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-20T13:10:44.695Z] GC before operation: completed in 126.252 ms, heap usage 429.316 MB -> 68.771 MB. [2025-12-20T13:10:57.387Z] ====== als (apache-spark) [default], iteration 18 completed (12462.103 ms) ====== [2025-12-20T13:10:57.387Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-20T13:10:57.387Z] GC before operation: completed in 121.077 ms, heap usage 344.456 MB -> 68.771 MB. [2025-12-20T13:11:10.229Z] ====== als (apache-spark) [default], iteration 19 completed (12341.705 ms) ====== [2025-12-20T13:11:10.229Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-20T13:11:10.229Z] GC before operation: completed in 122.728 ms, heap usage 160.176 MB -> 68.842 MB. [2025-12-20T13:11:22.585Z] ====== als (apache-spark) [default], iteration 20 completed (12894.314 ms) ====== [2025-12-20T13:11:22.585Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-20T13:11:23.228Z] GC before operation: completed in 120.817 ms, heap usage 85.173 MB -> 68.686 MB. [2025-12-20T13:11:35.782Z] ====== als (apache-spark) [default], iteration 21 completed (12628.139 ms) ====== [2025-12-20T13:11:35.782Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-20T13:11:35.782Z] GC before operation: completed in 119.959 ms, heap usage 105.551 MB -> 69.326 MB. [2025-12-20T13:11:48.200Z] ====== als (apache-spark) [default], iteration 22 completed (12297.851 ms) ====== [2025-12-20T13:11:48.200Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-20T13:11:48.844Z] GC before operation: completed in 120.714 ms, heap usage 261.816 MB -> 69.705 MB. [2025-12-20T13:12:01.206Z] ====== als (apache-spark) [default], iteration 23 completed (12152.374 ms) ====== [2025-12-20T13:12:01.206Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-20T13:12:01.206Z] GC before operation: completed in 126.303 ms, heap usage 205.746 MB -> 69.923 MB. [2025-12-20T13:12:13.722Z] ====== als (apache-spark) [default], iteration 24 completed (12501.528 ms) ====== [2025-12-20T13:12:13.722Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-20T13:12:14.378Z] GC before operation: completed in 132.173 ms, heap usage 458.537 MB -> 70.558 MB. [2025-12-20T13:12:27.241Z] ====== als (apache-spark) [default], iteration 25 completed (12045.870 ms) ====== [2025-12-20T13:12:27.241Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-20T13:12:27.241Z] GC before operation: completed in 125.698 ms, heap usage 151.818 MB -> 70.445 MB. [2025-12-20T13:12:39.736Z] ====== als (apache-spark) [default], iteration 26 completed (12342.883 ms) ====== [2025-12-20T13:12:39.736Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-20T13:12:39.736Z] GC before operation: completed in 127.531 ms, heap usage 503.298 MB -> 71.114 MB. [2025-12-20T13:12:52.517Z] ====== als (apache-spark) [default], iteration 27 completed (12210.563 ms) ====== [2025-12-20T13:12:52.517Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-20T13:12:52.517Z] GC before operation: completed in 123.908 ms, heap usage 330.655 MB -> 71.133 MB. [2025-12-20T13:13:05.197Z] ====== als (apache-spark) [default], iteration 28 completed (12457.622 ms) ====== [2025-12-20T13:13:05.197Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-20T13:13:05.197Z] GC before operation: completed in 126.900 ms, heap usage 354.580 MB -> 70.929 MB. [2025-12-20T13:13:17.758Z] ====== als (apache-spark) [default], iteration 29 completed (11988.258 ms) ====== [2025-12-20T13:13:17.758Z] ----------------------------------- [2025-12-20T13:13:17.758Z] renaissance-als_0_PASSED [2025-12-20T13:13:17.758Z] ----------------------------------- [2025-12-20T13:13:17.758Z] [2025-12-20T13:13:17.758Z] TEST TEARDOWN: [2025-12-20T13:13:17.758Z] Nothing to be done for teardown. [2025-12-20T13:13:17.758Z] renaissance-als_0 Finish Time: Sat Dec 20 13:13:16 2025 Epoch Time (ms): 1766236396605