[2025-12-27T13:06:57.115Z] Running test renaissance-als_0 ... [2025-12-27T13:06:57.115Z] =============================================== [2025-12-27T13:06:57.115Z] renaissance-als_0 Start Time: Sat Dec 27 13:06:56 2025 Epoch Time (ms): 1766840816988 [2025-12-27T13:06:57.115Z] variation: NoOptions [2025-12-27T13:06:57.115Z] JVM_OPTIONS: [2025-12-27T13:06:57.115Z] { \ [2025-12-27T13:06:57.115Z] echo ""; echo "TEST SETUP:"; \ [2025-12-27T13:06:57.115Z] echo "Nothing to be done for setup."; \ [2025-12-27T13:06:57.115Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17668406205434/renaissance-als_0"; \ [2025-12-27T13:06:57.115Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17668406205434/renaissance-als_0"; \ [2025-12-27T13:06:57.115Z] echo ""; echo "TESTING:"; \ [2025-12-27T13:06:57.115Z] "/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_17668406205434/renaissance-als_0"/als.json" als; \ [2025-12-27T13:06:57.115Z] 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_17668406205434/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-27T13:06:57.115Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-27T13:06:57.115Z] echo "Nothing to be done for teardown."; \ [2025-12-27T13:06:57.115Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17668406205434/TestTargetResult"; [2025-12-27T13:06:57.115Z] [2025-12-27T13:06:57.115Z] TEST SETUP: [2025-12-27T13:06:57.115Z] Nothing to be done for setup. [2025-12-27T13:06:57.115Z] [2025-12-27T13:06:57.115Z] TESTING: [2025-12-27T13:06:57.850Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-27T13:06:57.850Z] 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_17668406205434/renaissance-als_0/launcher-130657-13816227196318803096/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-27T13:06:57.850Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-27T13:06:57.850Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-27T13:07:03.919Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-12-27T13:07:07.819Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-27T13:07:07.819Z] GC before operation: completed in 82.519 ms, heap usage 52.500 MB -> 34.963 MB. [2025-12-27T13:07:35.852Z] ====== als (apache-spark) [default], iteration 0 completed (25822.362 ms) ====== [2025-12-27T13:07:35.853Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-27T13:07:35.853Z] GC before operation: completed in 157.676 ms, heap usage 728.376 MB -> 61.949 MB. [2025-12-27T13:07:49.150Z] ====== als (apache-spark) [default], iteration 1 completed (13980.121 ms) ====== [2025-12-27T13:07:49.150Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-27T13:07:49.150Z] GC before operation: completed in 108.631 ms, heap usage 212.259 MB -> 62.365 MB. [2025-12-27T13:08:02.360Z] ====== als (apache-spark) [default], iteration 2 completed (13138.729 ms) ====== [2025-12-27T13:08:02.360Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-27T13:08:02.695Z] GC before operation: completed in 112.511 ms, heap usage 230.769 MB -> 62.809 MB. [2025-12-27T13:08:15.929Z] ====== als (apache-spark) [default], iteration 3 completed (12988.539 ms) ====== [2025-12-27T13:08:15.929Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-27T13:08:15.929Z] GC before operation: completed in 108.758 ms, heap usage 340.813 MB -> 63.535 MB. [2025-12-27T13:08:29.186Z] ====== als (apache-spark) [default], iteration 4 completed (12012.319 ms) ====== [2025-12-27T13:08:29.186Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-27T13:08:29.186Z] GC before operation: completed in 113.207 ms, heap usage 268.586 MB -> 63.859 MB. [2025-12-27T13:08:42.410Z] ====== als (apache-spark) [default], iteration 5 completed (11738.709 ms) ====== [2025-12-27T13:08:42.410Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-27T13:08:42.410Z] GC before operation: completed in 118.098 ms, heap usage 487.833 MB -> 64.832 MB. [2025-12-27T13:08:53.419Z] ====== als (apache-spark) [default], iteration 6 completed (11870.832 ms) ====== [2025-12-27T13:08:53.419Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-27T13:08:53.419Z] GC before operation: completed in 116.791 ms, heap usage 191.406 MB -> 65.033 MB. [2025-12-27T13:09:06.733Z] ====== als (apache-spark) [default], iteration 7 completed (11607.334 ms) ====== [2025-12-27T13:09:06.733Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-27T13:09:06.733Z] GC before operation: completed in 113.182 ms, heap usage 146.348 MB -> 65.406 MB. [2025-12-27T13:09:17.781Z] ====== als (apache-spark) [default], iteration 8 completed (12086.407 ms) ====== [2025-12-27T13:09:17.781Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-27T13:09:17.781Z] GC before operation: completed in 117.257 ms, heap usage 94.698 MB -> 65.306 MB. [2025-12-27T13:09:28.784Z] ====== als (apache-spark) [default], iteration 9 completed (11573.145 ms) ====== [2025-12-27T13:09:28.784Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-27T13:09:29.129Z] GC before operation: completed in 125.197 ms, heap usage 468.536 MB -> 66.617 MB. [2025-12-27T13:09:42.446Z] ====== als (apache-spark) [default], iteration 10 completed (11662.538 ms) ====== [2025-12-27T13:09:42.446Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-27T13:09:42.446Z] GC before operation: completed in 122.956 ms, heap usage 388.413 MB -> 66.652 MB. [2025-12-27T13:09:53.346Z] ====== als (apache-spark) [default], iteration 11 completed (11487.434 ms) ====== [2025-12-27T13:09:53.346Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-27T13:09:53.346Z] GC before operation: completed in 190.565 ms, heap usage 469.644 MB -> 67.182 MB. [2025-12-27T13:10:04.296Z] ====== als (apache-spark) [default], iteration 12 completed (11281.959 ms) ====== [2025-12-27T13:10:04.296Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-27T13:10:04.653Z] GC before operation: completed in 129.041 ms, heap usage 472.904 MB -> 67.636 MB. [2025-12-27T13:10:18.131Z] ====== als (apache-spark) [default], iteration 13 completed (11456.607 ms) ====== [2025-12-27T13:10:18.131Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-27T13:10:18.131Z] GC before operation: completed in 118.893 ms, heap usage 98.873 MB -> 67.272 MB. [2025-12-27T13:10:29.061Z] ====== als (apache-spark) [default], iteration 14 completed (11414.708 ms) ====== [2025-12-27T13:10:29.061Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-27T13:10:29.061Z] GC before operation: completed in 121.287 ms, heap usage 175.309 MB -> 67.507 MB. [2025-12-27T13:10:40.013Z] ====== als (apache-spark) [default], iteration 15 completed (11299.077 ms) ====== [2025-12-27T13:10:40.013Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-27T13:10:40.013Z] GC before operation: completed in 123.675 ms, heap usage 275.439 MB -> 67.814 MB. [2025-12-27T13:10:50.983Z] ====== als (apache-spark) [default], iteration 16 completed (11242.819 ms) ====== [2025-12-27T13:10:50.984Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-27T13:10:51.329Z] GC before operation: completed in 126.028 ms, heap usage 425.810 MB -> 68.467 MB. [2025-12-27T13:11:04.581Z] ====== als (apache-spark) [default], iteration 17 completed (11406.805 ms) ====== [2025-12-27T13:11:04.581Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-27T13:11:04.581Z] GC before operation: completed in 121.551 ms, heap usage 145.457 MB -> 68.278 MB. [2025-12-27T13:11:15.674Z] ====== als (apache-spark) [default], iteration 18 completed (11437.675 ms) ====== [2025-12-27T13:11:15.674Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-27T13:11:15.674Z] GC before operation: completed in 124.631 ms, heap usage 309.953 MB -> 68.801 MB. [2025-12-27T13:11:26.603Z] ====== als (apache-spark) [default], iteration 19 completed (11406.023 ms) ====== [2025-12-27T13:11:26.603Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-27T13:11:26.603Z] GC before operation: completed in 126.264 ms, heap usage 273.004 MB -> 68.831 MB. [2025-12-27T13:11:39.851Z] ====== als (apache-spark) [default], iteration 20 completed (11527.554 ms) ====== [2025-12-27T13:11:39.851Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-27T13:11:39.851Z] GC before operation: completed in 129.124 ms, heap usage 203.405 MB -> 69.000 MB. [2025-12-27T13:11:50.867Z] ====== als (apache-spark) [default], iteration 21 completed (11614.202 ms) ====== [2025-12-27T13:11:50.867Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-27T13:11:50.868Z] GC before operation: completed in 128.338 ms, heap usage 266.352 MB -> 69.363 MB. [2025-12-27T13:12:04.197Z] ====== als (apache-spark) [default], iteration 22 completed (11568.874 ms) ====== [2025-12-27T13:12:04.197Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-27T13:12:04.197Z] GC before operation: completed in 162.301 ms, heap usage 473.413 MB -> 69.953 MB. [2025-12-27T13:12:15.093Z] ====== als (apache-spark) [default], iteration 23 completed (11224.068 ms) ====== [2025-12-27T13:12:15.093Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-27T13:12:15.093Z] GC before operation: completed in 128.844 ms, heap usage 440.959 MB -> 70.258 MB. [2025-12-27T13:12:25.989Z] ====== als (apache-spark) [default], iteration 24 completed (11407.541 ms) ====== [2025-12-27T13:12:25.989Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-27T13:12:25.989Z] GC before operation: completed in 130.640 ms, heap usage 446.407 MB -> 70.531 MB. [2025-12-27T13:12:39.211Z] ====== als (apache-spark) [default], iteration 25 completed (11434.711 ms) ====== [2025-12-27T13:12:39.211Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-27T13:12:39.211Z] GC before operation: completed in 132.565 ms, heap usage 548.220 MB -> 70.867 MB. [2025-12-27T13:12:50.270Z] ====== als (apache-spark) [default], iteration 26 completed (11565.489 ms) ====== [2025-12-27T13:12:50.270Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-27T13:12:50.270Z] GC before operation: completed in 129.175 ms, heap usage 227.074 MB -> 70.684 MB. [2025-12-27T13:13:01.178Z] ====== als (apache-spark) [default], iteration 27 completed (11599.980 ms) ====== [2025-12-27T13:13:01.178Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-27T13:13:01.178Z] GC before operation: completed in 132.930 ms, heap usage 499.955 MB -> 71.315 MB. [2025-12-27T13:13:14.385Z] ====== als (apache-spark) [default], iteration 28 completed (11446.241 ms) ====== [2025-12-27T13:13:14.385Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-27T13:13:14.385Z] GC before operation: completed in 129.650 ms, heap usage 272.561 MB -> 71.214 MB. [2025-12-27T13:13:25.292Z] ====== als (apache-spark) [default], iteration 29 completed (11231.064 ms) ====== [2025-12-27T13:13:25.292Z] ----------------------------------- [2025-12-27T13:13:25.292Z] renaissance-als_0_PASSED [2025-12-27T13:13:25.292Z] ----------------------------------- [2025-12-27T13:13:25.292Z] [2025-12-27T13:13:25.292Z] TEST TEARDOWN: [2025-12-27T13:13:25.292Z] Nothing to be done for teardown. [2025-12-27T13:13:25.292Z] renaissance-als_0 Finish Time: Sat Dec 27 13:13:24 2025 Epoch Time (ms): 1766841204595