[2025-12-13T15:29:38.758Z] Running test renaissance-als_0 ... [2025-12-13T15:29:38.758Z] =============================================== [2025-12-13T15:29:38.758Z] renaissance-als_0 Start Time: Sat Dec 13 15:29:38 2025 Epoch Time (ms): 1765639778025 [2025-12-13T15:29:38.758Z] variation: NoOptions [2025-12-13T15:29:38.758Z] JVM_OPTIONS: [2025-12-13T15:29:38.758Z] { \ [2025-12-13T15:29:38.758Z] echo ""; echo "TEST SETUP:"; \ [2025-12-13T15:29:38.758Z] echo "Nothing to be done for setup."; \ [2025-12-13T15:29:38.758Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17656397778184/renaissance-als_0"; \ [2025-12-13T15:29:38.758Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17656397778184/renaissance-als_0"; \ [2025-12-13T15:29:38.758Z] echo ""; echo "TESTING:"; \ [2025-12-13T15:29:38.758Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/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_ppc64le_linux_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17656397778184/renaissance-als_0"/als.json" als; \ [2025-12-13T15:29:38.758Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17656397778184/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-13T15:29:38.758Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-13T15:29:38.758Z] echo "Nothing to be done for teardown."; \ [2025-12-13T15:29:38.758Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17656397778184/TestTargetResult"; [2025-12-13T15:29:38.758Z] [2025-12-13T15:29:38.758Z] TEST SETUP: [2025-12-13T15:29:38.758Z] Nothing to be done for setup. [2025-12-13T15:29:38.758Z] [2025-12-13T15:29:38.758Z] TESTING: [2025-12-13T15:29:38.758Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-13T15:29:38.758Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/output_17656397778184/renaissance-als_0/launcher-152938-5913714077532841337/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-13T15:29:38.758Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-13T15:29:38.758Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-13T15:29:44.174Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-13T15:29:48.360Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-13T15:29:48.360Z] GC before operation: completed in 69.058 ms, heap usage 54.984 MB -> 35.158 MB. [2025-12-13T15:30:06.968Z] ====== als (apache-spark) [default], iteration 0 completed (16139.061 ms) ====== [2025-12-13T15:30:06.968Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-13T15:30:06.968Z] GC before operation: completed in 121.734 ms, heap usage 326.009 MB -> 61.748 MB. [2025-12-13T15:30:16.187Z] ====== als (apache-spark) [default], iteration 1 completed (7704.958 ms) ====== [2025-12-13T15:30:16.187Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-13T15:30:16.187Z] GC before operation: completed in 91.510 ms, heap usage 277.953 MB -> 62.618 MB. [2025-12-13T15:30:20.377Z] ====== als (apache-spark) [default], iteration 2 completed (6860.159 ms) ====== [2025-12-13T15:30:20.377Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-13T15:30:20.377Z] GC before operation: completed in 89.832 ms, heap usage 491.123 MB -> 63.598 MB. [2025-12-13T15:30:27.128Z] ====== als (apache-spark) [default], iteration 3 completed (6787.937 ms) ====== [2025-12-13T15:30:27.128Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-13T15:30:28.089Z] GC before operation: completed in 90.751 ms, heap usage 355.198 MB -> 63.985 MB. [2025-12-13T15:30:34.851Z] ====== als (apache-spark) [default], iteration 4 completed (6636.871 ms) ====== [2025-12-13T15:30:34.851Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-13T15:30:34.851Z] GC before operation: completed in 77.006 ms, heap usage 187.444 MB -> 64.326 MB. [2025-12-13T15:30:41.991Z] ====== als (apache-spark) [default], iteration 5 completed (6578.302 ms) ====== [2025-12-13T15:30:41.991Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-13T15:30:41.991Z] GC before operation: completed in 76.773 ms, heap usage 350.416 MB -> 65.040 MB. [2025-12-13T15:30:48.737Z] ====== als (apache-spark) [default], iteration 6 completed (6459.013 ms) ====== [2025-12-13T15:30:48.737Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-13T15:30:48.737Z] GC before operation: completed in 81.627 ms, heap usage 474.356 MB -> 65.773 MB. [2025-12-13T15:30:55.475Z] ====== als (apache-spark) [default], iteration 7 completed (6460.719 ms) ====== [2025-12-13T15:30:55.475Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-13T15:30:55.475Z] GC before operation: completed in 76.722 ms, heap usage 328.144 MB -> 66.153 MB. [2025-12-13T15:31:02.216Z] ====== als (apache-spark) [default], iteration 8 completed (6523.929 ms) ====== [2025-12-13T15:31:02.216Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-13T15:31:02.216Z] GC before operation: completed in 77.195 ms, heap usage 200.802 MB -> 66.548 MB. [2025-12-13T15:31:08.965Z] ====== als (apache-spark) [default], iteration 9 completed (6484.842 ms) ====== [2025-12-13T15:31:08.965Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-13T15:31:08.965Z] GC before operation: completed in 83.184 ms, heap usage 323.802 MB -> 66.859 MB. [2025-12-13T15:31:15.728Z] ====== als (apache-spark) [default], iteration 10 completed (6431.137 ms) ====== [2025-12-13T15:31:15.728Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-13T15:31:15.728Z] GC before operation: completed in 85.642 ms, heap usage 462.526 MB -> 67.279 MB. [2025-12-13T15:31:22.471Z] ====== als (apache-spark) [default], iteration 11 completed (6415.862 ms) ====== [2025-12-13T15:31:22.471Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-13T15:31:22.471Z] GC before operation: completed in 81.647 ms, heap usage 382.278 MB -> 67.484 MB. [2025-12-13T15:31:29.216Z] ====== als (apache-spark) [default], iteration 12 completed (6578.345 ms) ====== [2025-12-13T15:31:29.216Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-13T15:31:29.216Z] GC before operation: completed in 89.901 ms, heap usage 472.237 MB -> 67.922 MB. [2025-12-13T15:31:35.959Z] ====== als (apache-spark) [default], iteration 13 completed (6382.008 ms) ====== [2025-12-13T15:31:35.959Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-13T15:31:35.959Z] GC before operation: completed in 83.083 ms, heap usage 528.861 MB -> 68.313 MB. [2025-12-13T15:31:42.709Z] ====== als (apache-spark) [default], iteration 14 completed (6342.471 ms) ====== [2025-12-13T15:31:42.709Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-13T15:31:42.709Z] GC before operation: completed in 92.531 ms, heap usage 195.424 MB -> 68.008 MB. [2025-12-13T15:31:49.481Z] ====== als (apache-spark) [default], iteration 15 completed (6378.835 ms) ====== [2025-12-13T15:31:49.481Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-13T15:31:49.481Z] GC before operation: completed in 82.349 ms, heap usage 149.085 MB -> 68.274 MB. [2025-12-13T15:31:56.240Z] ====== als (apache-spark) [default], iteration 16 completed (6347.604 ms) ====== [2025-12-13T15:31:56.240Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-13T15:31:56.240Z] GC before operation: completed in 80.813 ms, heap usage 226.487 MB -> 68.634 MB. [2025-12-13T15:32:01.657Z] ====== als (apache-spark) [default], iteration 17 completed (6313.791 ms) ====== [2025-12-13T15:32:01.657Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-13T15:32:01.657Z] GC before operation: completed in 83.653 ms, heap usage 186.052 MB -> 68.764 MB. [2025-12-13T15:32:08.434Z] ====== als (apache-spark) [default], iteration 18 completed (6334.101 ms) ====== [2025-12-13T15:32:08.434Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-13T15:32:08.434Z] GC before operation: completed in 84.350 ms, heap usage 480.240 MB -> 69.453 MB. [2025-12-13T15:32:15.910Z] ====== als (apache-spark) [default], iteration 19 completed (6337.072 ms) ====== [2025-12-13T15:32:15.910Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-13T15:32:15.910Z] GC before operation: completed in 89.826 ms, heap usage 472.696 MB -> 69.665 MB. [2025-12-13T15:32:22.664Z] ====== als (apache-spark) [default], iteration 20 completed (6357.171 ms) ====== [2025-12-13T15:32:22.665Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-13T15:32:22.665Z] GC before operation: completed in 104.058 ms, heap usage 234.045 MB -> 69.679 MB. [2025-12-13T15:32:29.418Z] ====== als (apache-spark) [default], iteration 21 completed (6726.059 ms) ====== [2025-12-13T15:32:29.418Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-13T15:32:29.418Z] GC before operation: completed in 98.217 ms, heap usage 319.553 MB -> 69.969 MB. [2025-12-13T15:32:36.197Z] ====== als (apache-spark) [default], iteration 22 completed (6675.156 ms) ====== [2025-12-13T15:32:36.197Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-13T15:32:36.197Z] GC before operation: completed in 108.564 ms, heap usage 508.923 MB -> 70.433 MB. [2025-12-13T15:32:42.951Z] ====== als (apache-spark) [default], iteration 23 completed (6607.631 ms) ====== [2025-12-13T15:32:42.951Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-13T15:32:42.951Z] GC before operation: completed in 111.315 ms, heap usage 258.067 MB -> 70.338 MB. [2025-12-13T15:32:49.709Z] ====== als (apache-spark) [default], iteration 24 completed (6542.271 ms) ====== [2025-12-13T15:32:49.709Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-13T15:32:49.709Z] GC before operation: completed in 123.340 ms, heap usage 459.565 MB -> 70.985 MB. [2025-12-13T15:32:56.455Z] ====== als (apache-spark) [default], iteration 25 completed (6620.122 ms) ====== [2025-12-13T15:32:56.455Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-13T15:32:56.455Z] GC before operation: completed in 110.110 ms, heap usage 495.267 MB -> 71.238 MB. [2025-12-13T15:33:03.200Z] ====== als (apache-spark) [default], iteration 26 completed (6356.451 ms) ====== [2025-12-13T15:33:03.200Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-13T15:33:03.200Z] GC before operation: completed in 102.660 ms, heap usage 165.458 MB -> 71.070 MB. [2025-12-13T15:33:09.989Z] ====== als (apache-spark) [default], iteration 27 completed (6382.985 ms) ====== [2025-12-13T15:33:09.989Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-13T15:33:09.989Z] GC before operation: completed in 112.497 ms, heap usage 472.490 MB -> 71.737 MB. [2025-12-13T15:33:16.746Z] ====== als (apache-spark) [default], iteration 28 completed (6395.838 ms) ====== [2025-12-13T15:33:16.746Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-13T15:33:16.746Z] GC before operation: completed in 113.377 ms, heap usage 166.656 MB -> 71.610 MB. [2025-12-13T15:33:23.522Z] ====== als (apache-spark) [default], iteration 29 completed (6384.659 ms) ====== [2025-12-13T15:33:23.522Z] ----------------------------------- [2025-12-13T15:33:23.522Z] renaissance-als_0_PASSED [2025-12-13T15:33:23.522Z] ----------------------------------- [2025-12-13T15:33:23.522Z] [2025-12-13T15:33:23.522Z] TEST TEARDOWN: [2025-12-13T15:33:23.522Z] Nothing to be done for teardown. [2025-12-13T15:33:23.522Z] renaissance-als_0 Finish Time: Sat Dec 13 15:33:23 2025 Epoch Time (ms): 1765640003114