[2025-12-06T12:32:39.699Z] Running test renaissance-als_0 ... [2025-12-06T12:32:39.699Z] =============================================== [2025-12-06T12:32:39.699Z] renaissance-als_0 Start Time: Sat Dec 6 12:32:39 2025 Epoch Time (ms): 1765024359486 [2025-12-06T12:32:39.699Z] variation: NoOptions [2025-12-06T12:32:39.699Z] JVM_OPTIONS: [2025-12-06T12:32:39.699Z] { \ [2025-12-06T12:32:39.699Z] echo ""; echo "TEST SETUP:"; \ [2025-12-06T12:32:39.699Z] echo "Nothing to be done for setup."; \ [2025-12-06T12:32:39.699Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17650241815482/renaissance-als_0"; \ [2025-12-06T12:32:39.699Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17650241815482/renaissance-als_0"; \ [2025-12-06T12:32:39.699Z] echo ""; echo "TESTING:"; \ [2025-12-06T12:32:39.699Z] "/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_17650241815482/renaissance-als_0"/als.json" als; \ [2025-12-06T12:32:39.699Z] 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_17650241815482/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-06T12:32:39.699Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-06T12:32:39.699Z] echo "Nothing to be done for teardown."; \ [2025-12-06T12:32:39.699Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17650241815482/TestTargetResult"; [2025-12-06T12:32:39.699Z] [2025-12-06T12:32:39.699Z] TEST SETUP: [2025-12-06T12:32:39.699Z] Nothing to be done for setup. [2025-12-06T12:32:39.699Z] [2025-12-06T12:32:39.699Z] TESTING: [2025-12-06T12:32:40.015Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-06T12:32:40.015Z] 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_17650241815482/renaissance-als_0/launcher-123239-9731563169773547051/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-06T12:32:40.015Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-06T12:32:40.015Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-06T12:32:48.728Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-12-06T12:32:51.596Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-06T12:32:51.596Z] GC before operation: completed in 52.737 ms, heap usage 183.500 MB -> 34.934 MB. [2025-12-06T12:33:19.127Z] ====== als (apache-spark) [default], iteration 0 completed (25838.089 ms) ====== [2025-12-06T12:33:19.127Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-06T12:33:19.127Z] GC before operation: completed in 99.026 ms, heap usage 176.179 MB -> 61.428 MB. [2025-12-06T12:33:32.068Z] ====== als (apache-spark) [default], iteration 1 completed (11402.135 ms) ====== [2025-12-06T12:33:32.068Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-06T12:33:32.068Z] GC before operation: completed in 87.901 ms, heap usage 255.874 MB -> 62.308 MB. [2025-12-06T12:33:42.783Z] ====== als (apache-spark) [default], iteration 2 completed (11096.895 ms) ====== [2025-12-06T12:33:42.783Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-06T12:33:42.783Z] GC before operation: completed in 87.745 ms, heap usage 277.212 MB -> 62.984 MB. [2025-12-06T12:33:53.435Z] ====== als (apache-spark) [default], iteration 3 completed (11107.135 ms) ====== [2025-12-06T12:33:53.435Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-06T12:33:53.435Z] GC before operation: completed in 82.220 ms, heap usage 76.264 MB -> 63.371 MB. [2025-12-06T12:34:04.089Z] ====== als (apache-spark) [default], iteration 4 completed (10119.095 ms) ====== [2025-12-06T12:34:04.089Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-06T12:34:04.089Z] GC before operation: completed in 100.422 ms, heap usage 360.265 MB -> 64.221 MB. [2025-12-06T12:34:14.738Z] ====== als (apache-spark) [default], iteration 5 completed (9744.408 ms) ====== [2025-12-06T12:34:14.738Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-06T12:34:14.738Z] GC before operation: completed in 113.313 ms, heap usage 209.748 MB -> 64.494 MB. [2025-12-06T12:34:23.574Z] ====== als (apache-spark) [default], iteration 6 completed (9714.482 ms) ====== [2025-12-06T12:34:23.574Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-06T12:34:23.574Z] GC before operation: completed in 104.955 ms, heap usage 377.806 MB -> 65.243 MB. [2025-12-06T12:34:34.226Z] ====== als (apache-spark) [default], iteration 7 completed (10084.058 ms) ====== [2025-12-06T12:34:34.226Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-06T12:34:34.226Z] GC before operation: completed in 93.293 ms, heap usage 328.702 MB -> 65.759 MB. [2025-12-06T12:34:44.904Z] ====== als (apache-spark) [default], iteration 8 completed (9855.789 ms) ====== [2025-12-06T12:34:44.904Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-06T12:34:44.904Z] GC before operation: completed in 96.609 ms, heap usage 155.185 MB -> 66.116 MB. [2025-12-06T12:34:55.548Z] ====== als (apache-spark) [default], iteration 9 completed (9645.009 ms) ====== [2025-12-06T12:34:55.548Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-06T12:34:55.548Z] GC before operation: completed in 98.490 ms, heap usage 469.447 MB -> 66.799 MB. [2025-12-06T12:35:04.280Z] ====== als (apache-spark) [default], iteration 10 completed (9865.445 ms) ====== [2025-12-06T12:35:04.281Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-06T12:35:04.595Z] GC before operation: completed in 96.155 ms, heap usage 222.071 MB -> 66.790 MB. [2025-12-06T12:35:15.308Z] ====== als (apache-spark) [default], iteration 11 completed (9419.750 ms) ====== [2025-12-06T12:35:15.308Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-06T12:35:15.308Z] GC before operation: completed in 100.884 ms, heap usage 273.036 MB -> 67.062 MB. [2025-12-06T12:35:24.035Z] ====== als (apache-spark) [default], iteration 12 completed (9632.841 ms) ====== [2025-12-06T12:35:24.035Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-06T12:35:24.035Z] GC before operation: completed in 95.717 ms, heap usage 203.055 MB -> 67.234 MB. [2025-12-06T12:35:34.691Z] ====== als (apache-spark) [default], iteration 13 completed (9518.092 ms) ====== [2025-12-06T12:35:34.691Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-06T12:35:34.691Z] GC before operation: completed in 98.672 ms, heap usage 100.154 MB -> 67.317 MB. [2025-12-06T12:35:45.337Z] ====== als (apache-spark) [default], iteration 14 completed (9421.696 ms) ====== [2025-12-06T12:35:45.337Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-06T12:35:45.337Z] GC before operation: completed in 101.144 ms, heap usage 483.698 MB -> 68.140 MB. [2025-12-06T12:35:54.056Z] ====== als (apache-spark) [default], iteration 15 completed (9507.529 ms) ====== [2025-12-06T12:35:54.056Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-06T12:35:54.056Z] GC before operation: completed in 102.556 ms, heap usage 148.425 MB -> 67.916 MB. [2025-12-06T12:36:04.742Z] ====== als (apache-spark) [default], iteration 16 completed (9341.972 ms) ====== [2025-12-06T12:36:04.742Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-06T12:36:04.742Z] GC before operation: completed in 175.516 ms, heap usage 114.399 MB -> 68.237 MB. [2025-12-06T12:36:13.483Z] ====== als (apache-spark) [default], iteration 17 completed (9647.207 ms) ====== [2025-12-06T12:36:13.483Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-06T12:36:13.483Z] GC before operation: completed in 100.867 ms, heap usage 213.575 MB -> 68.527 MB. [2025-12-06T12:36:24.144Z] ====== als (apache-spark) [default], iteration 18 completed (9665.675 ms) ====== [2025-12-06T12:36:24.144Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-06T12:36:24.144Z] GC before operation: completed in 100.780 ms, heap usage 160.959 MB -> 68.782 MB. [2025-12-06T12:36:32.876Z] ====== als (apache-spark) [default], iteration 19 completed (9350.392 ms) ====== [2025-12-06T12:36:32.876Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-06T12:36:32.876Z] GC before operation: completed in 104.953 ms, heap usage 157.973 MB -> 68.987 MB. [2025-12-06T12:36:43.617Z] ====== als (apache-spark) [default], iteration 20 completed (9551.495 ms) ====== [2025-12-06T12:36:43.617Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-06T12:36:43.617Z] GC before operation: completed in 109.712 ms, heap usage 448.490 MB -> 69.779 MB. [2025-12-06T12:36:54.254Z] ====== als (apache-spark) [default], iteration 21 completed (10126.719 ms) ====== [2025-12-06T12:36:54.255Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-06T12:36:54.255Z] GC before operation: completed in 105.180 ms, heap usage 365.700 MB -> 69.760 MB. [2025-12-06T12:37:03.111Z] ====== als (apache-spark) [default], iteration 22 completed (9286.355 ms) ====== [2025-12-06T12:37:03.111Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-06T12:37:03.111Z] GC before operation: completed in 112.238 ms, heap usage 447.175 MB -> 70.055 MB. [2025-12-06T12:37:13.786Z] ====== als (apache-spark) [default], iteration 23 completed (9519.143 ms) ====== [2025-12-06T12:37:13.786Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-06T12:37:13.786Z] GC before operation: completed in 108.233 ms, heap usage 349.154 MB -> 70.207 MB. [2025-12-06T12:37:22.594Z] ====== als (apache-spark) [default], iteration 24 completed (10057.468 ms) ====== [2025-12-06T12:37:22.594Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-06T12:37:22.594Z] GC before operation: completed in 107.703 ms, heap usage 251.262 MB -> 70.302 MB. [2025-12-06T12:37:33.222Z] ====== als (apache-spark) [default], iteration 25 completed (9332.924 ms) ====== [2025-12-06T12:37:33.222Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-06T12:37:33.222Z] GC before operation: completed in 103.125 ms, heap usage 183.482 MB -> 70.439 MB. [2025-12-06T12:37:43.925Z] ====== als (apache-spark) [default], iteration 26 completed (9720.818 ms) ====== [2025-12-06T12:37:43.925Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-06T12:37:43.925Z] GC before operation: completed in 105.822 ms, heap usage 467.066 MB -> 71.084 MB. [2025-12-06T12:37:52.664Z] ====== als (apache-spark) [default], iteration 27 completed (9481.339 ms) ====== [2025-12-06T12:37:52.664Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-06T12:37:52.664Z] GC before operation: completed in 105.910 ms, heap usage 428.753 MB -> 71.379 MB. [2025-12-06T12:38:03.336Z] ====== als (apache-spark) [default], iteration 28 completed (9813.840 ms) ====== [2025-12-06T12:38:03.336Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-06T12:38:03.336Z] GC before operation: completed in 99.586 ms, heap usage 529.810 MB -> 71.766 MB. [2025-12-06T12:38:12.131Z] ====== als (apache-spark) [default], iteration 29 completed (9277.570 ms) ====== [2025-12-06T12:38:12.131Z] ----------------------------------- [2025-12-06T12:38:12.131Z] renaissance-als_0_PASSED [2025-12-06T12:38:12.131Z] ----------------------------------- [2025-12-06T12:38:12.131Z] [2025-12-06T12:38:12.131Z] TEST TEARDOWN: [2025-12-06T12:38:12.131Z] Nothing to be done for teardown. [2025-12-06T12:38:12.131Z] renaissance-als_0 Finish Time: Sat Dec 6 12:38:11 2025 Epoch Time (ms): 1765024691972