[2025-12-27T17:34:14.284Z] Running test renaissance-als_0 ... [2025-12-27T17:34:14.284Z] =============================================== [2025-12-27T17:34:14.284Z] renaissance-als_0 Start Time: Sat Dec 27 17:34:14 2025 Epoch Time (ms): 1766856854201 [2025-12-27T17:34:14.284Z] variation: NoOptions [2025-12-27T17:34:14.284Z] JVM_OPTIONS: [2025-12-27T17:34:14.284Z] { \ [2025-12-27T17:34:14.284Z] echo ""; echo "TEST SETUP:"; \ [2025-12-27T17:34:14.284Z] echo "Nothing to be done for setup."; \ [2025-12-27T17:34:14.284Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17668568543754/renaissance-als_0"; \ [2025-12-27T17:34:14.284Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17668568543754/renaissance-als_0"; \ [2025-12-27T17:34:14.284Z] echo ""; echo "TESTING:"; \ [2025-12-27T17:34:14.284Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-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_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17668568543754/renaissance-als_0"/als.json" als; \ [2025-12-27T17:34:14.284Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17668568543754/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-27T17:34:14.284Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-27T17:34:14.284Z] echo "Nothing to be done for teardown."; \ [2025-12-27T17:34:14.284Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17668568543754/TestTargetResult"; [2025-12-27T17:34:14.284Z] [2025-12-27T17:34:14.284Z] TEST SETUP: [2025-12-27T17:34:14.284Z] Nothing to be done for setup. [2025-12-27T17:34:14.284Z] [2025-12-27T17:34:14.284Z] TESTING: [2025-12-27T17:34:15.029Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-27T17:34:15.029Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/output_17668568543754/renaissance-als_0/launcher-173414-1311342094615744429/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-27T17:34:15.029Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-27T17:34:15.029Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-27T17:34:20.477Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-27T17:34:23.791Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-27T17:34:23.791Z] GC before operation: completed in 41.285 ms, heap usage 50.827 MB -> 34.996 MB. [2025-12-27T17:34:45.648Z] ====== als (apache-spark) [default], iteration 0 completed (19883.952 ms) ====== [2025-12-27T17:34:45.648Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-27T17:34:45.648Z] GC before operation: completed in 89.419 ms, heap usage 272.727 MB -> 61.726 MB. [2025-12-27T17:34:52.755Z] ====== als (apache-spark) [default], iteration 1 completed (8168.304 ms) ====== [2025-12-27T17:34:52.755Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-27T17:34:52.755Z] GC before operation: completed in 77.314 ms, heap usage 460.158 MB -> 62.929 MB. [2025-12-27T17:34:59.465Z] ====== als (apache-spark) [default], iteration 2 completed (6891.781 ms) ====== [2025-12-27T17:34:59.465Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-27T17:35:00.219Z] GC before operation: completed in 87.680 ms, heap usage 157.255 MB -> 63.027 MB. [2025-12-27T17:35:06.944Z] ====== als (apache-spark) [default], iteration 3 completed (7172.879 ms) ====== [2025-12-27T17:35:06.944Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-27T17:35:06.944Z] GC before operation: completed in 81.313 ms, heap usage 501.012 MB -> 64.082 MB. [2025-12-27T17:35:15.057Z] ====== als (apache-spark) [default], iteration 4 completed (6950.457 ms) ====== [2025-12-27T17:35:15.057Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-27T17:35:15.057Z] GC before operation: completed in 87.775 ms, heap usage 123.672 MB -> 64.172 MB. [2025-12-27T17:35:21.786Z] ====== als (apache-spark) [default], iteration 5 completed (6960.690 ms) ====== [2025-12-27T17:35:21.786Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-27T17:35:21.786Z] GC before operation: completed in 81.015 ms, heap usage 180.110 MB -> 64.756 MB. [2025-12-27T17:35:28.515Z] ====== als (apache-spark) [default], iteration 6 completed (6863.017 ms) ====== [2025-12-27T17:35:28.515Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-27T17:35:29.273Z] GC before operation: completed in 85.946 ms, heap usage 486.411 MB -> 65.735 MB. [2025-12-27T17:35:35.998Z] ====== als (apache-spark) [default], iteration 7 completed (6687.686 ms) ====== [2025-12-27T17:35:35.998Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-27T17:35:35.998Z] GC before operation: completed in 97.017 ms, heap usage 334.784 MB -> 66.065 MB. [2025-12-27T17:35:42.721Z] ====== als (apache-spark) [default], iteration 8 completed (6794.393 ms) ====== [2025-12-27T17:35:42.721Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-27T17:35:42.721Z] GC before operation: completed in 92.310 ms, heap usage 524.560 MB -> 66.961 MB. [2025-12-27T17:35:49.944Z] ====== als (apache-spark) [default], iteration 9 completed (6632.529 ms) ====== [2025-12-27T17:35:49.944Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-27T17:35:49.944Z] GC before operation: completed in 86.440 ms, heap usage 295.607 MB -> 66.926 MB. [2025-12-27T17:35:56.662Z] ====== als (apache-spark) [default], iteration 10 completed (6727.433 ms) ====== [2025-12-27T17:35:56.662Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-27T17:35:56.662Z] GC before operation: completed in 99.156 ms, heap usage 346.649 MB -> 67.068 MB. [2025-12-27T17:36:03.375Z] ====== als (apache-spark) [default], iteration 11 completed (6470.637 ms) ====== [2025-12-27T17:36:03.375Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-27T17:36:03.375Z] GC before operation: completed in 98.828 ms, heap usage 510.913 MB -> 67.633 MB. [2025-12-27T17:36:11.481Z] ====== als (apache-spark) [default], iteration 12 completed (6939.215 ms) ====== [2025-12-27T17:36:11.481Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-27T17:36:11.481Z] GC before operation: completed in 84.292 ms, heap usage 353.168 MB -> 67.606 MB. [2025-12-27T17:36:18.192Z] ====== als (apache-spark) [default], iteration 13 completed (6516.244 ms) ====== [2025-12-27T17:36:18.192Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-27T17:36:18.192Z] GC before operation: completed in 99.140 ms, heap usage 200.078 MB -> 67.751 MB. [2025-12-27T17:36:24.904Z] ====== als (apache-spark) [default], iteration 14 completed (6518.238 ms) ====== [2025-12-27T17:36:24.904Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-27T17:36:24.904Z] GC before operation: completed in 94.044 ms, heap usage 207.049 MB -> 67.997 MB. [2025-12-27T17:36:31.619Z] ====== als (apache-spark) [default], iteration 15 completed (6542.319 ms) ====== [2025-12-27T17:36:31.619Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-27T17:36:31.619Z] GC before operation: completed in 90.107 ms, heap usage 167.036 MB -> 68.172 MB. [2025-12-27T17:36:37.595Z] ====== als (apache-spark) [default], iteration 16 completed (6315.594 ms) ====== [2025-12-27T17:36:37.595Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-27T17:36:37.595Z] GC before operation: completed in 99.127 ms, heap usage 191.273 MB -> 68.409 MB. [2025-12-27T17:36:44.319Z] ====== als (apache-spark) [default], iteration 17 completed (6708.648 ms) ====== [2025-12-27T17:36:44.319Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-27T17:36:44.319Z] GC before operation: completed in 102.724 ms, heap usage 105.612 MB -> 68.716 MB. [2025-12-27T17:36:52.417Z] ====== als (apache-spark) [default], iteration 18 completed (6771.354 ms) ====== [2025-12-27T17:36:52.417Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-27T17:36:52.417Z] GC before operation: completed in 94.811 ms, heap usage 324.978 MB -> 69.201 MB. [2025-12-27T17:36:59.129Z] ====== als (apache-spark) [default], iteration 19 completed (6728.572 ms) ====== [2025-12-27T17:36:59.130Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-27T17:36:59.130Z] GC before operation: completed in 108.482 ms, heap usage 340.001 MB -> 69.633 MB. [2025-12-27T17:37:05.847Z] ====== als (apache-spark) [default], iteration 20 completed (6720.188 ms) ====== [2025-12-27T17:37:05.847Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-27T17:37:05.848Z] GC before operation: completed in 101.500 ms, heap usage 452.434 MB -> 69.988 MB. [2025-12-27T17:37:12.690Z] ====== als (apache-spark) [default], iteration 21 completed (6897.973 ms) ====== [2025-12-27T17:37:12.690Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-27T17:37:12.690Z] GC before operation: completed in 102.216 ms, heap usage 173.821 MB -> 69.808 MB. [2025-12-27T17:37:19.422Z] ====== als (apache-spark) [default], iteration 22 completed (6479.120 ms) ====== [2025-12-27T17:37:19.422Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-27T17:37:19.422Z] GC before operation: completed in 97.824 ms, heap usage 223.055 MB -> 70.099 MB. [2025-12-27T17:37:26.158Z] ====== als (apache-spark) [default], iteration 23 completed (6608.533 ms) ====== [2025-12-27T17:37:26.158Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-27T17:37:26.158Z] GC before operation: completed in 107.594 ms, heap usage 460.458 MB -> 70.801 MB. [2025-12-27T17:37:33.365Z] ====== als (apache-spark) [default], iteration 24 completed (6737.270 ms) ====== [2025-12-27T17:37:33.365Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-27T17:37:33.365Z] GC before operation: completed in 99.801 ms, heap usage 494.648 MB -> 71.051 MB. [2025-12-27T17:37:40.095Z] ====== als (apache-spark) [default], iteration 25 completed (6632.548 ms) ====== [2025-12-27T17:37:40.095Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-27T17:37:40.095Z] GC before operation: completed in 102.827 ms, heap usage 473.480 MB -> 71.290 MB. [2025-12-27T17:37:46.817Z] ====== als (apache-spark) [default], iteration 26 completed (6507.241 ms) ====== [2025-12-27T17:37:46.817Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-27T17:37:46.817Z] GC before operation: completed in 104.413 ms, heap usage 378.614 MB -> 71.375 MB. [2025-12-27T17:37:53.536Z] ====== als (apache-spark) [default], iteration 27 completed (6637.310 ms) ====== [2025-12-27T17:37:53.536Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-27T17:37:53.536Z] GC before operation: completed in 105.344 ms, heap usage 247.644 MB -> 71.424 MB. [2025-12-27T17:38:00.299Z] ====== als (apache-spark) [default], iteration 28 completed (6350.552 ms) ====== [2025-12-27T17:38:00.299Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-27T17:38:00.300Z] GC before operation: completed in 101.070 ms, heap usage 255.415 MB -> 71.288 MB. [2025-12-27T17:38:06.999Z] ====== als (apache-spark) [default], iteration 29 completed (6445.009 ms) ====== [2025-12-27T17:38:06.999Z] ----------------------------------- [2025-12-27T17:38:06.999Z] renaissance-als_0_PASSED [2025-12-27T17:38:06.999Z] ----------------------------------- [2025-12-27T17:38:06.999Z] [2025-12-27T17:38:06.999Z] TEST TEARDOWN: [2025-12-27T17:38:06.999Z] Nothing to be done for teardown. [2025-12-27T17:38:06.999Z] renaissance-als_0 Finish Time: Sat Dec 27 17:38:06 2025 Epoch Time (ms): 1766857086835