[2025-09-05T15:50:27.447Z] Running test renaissance-als_0 ... [2025-09-05T15:50:27.447Z] =============================================== [2025-09-05T15:50:27.447Z] renaissance-als_0 Start Time: Fri Sep 5 15:50:27 2025 Epoch Time (ms): 1757087427126 [2025-09-05T15:50:27.447Z] variation: NoOptions [2025-09-05T15:50:27.447Z] JVM_OPTIONS: [2025-09-05T15:50:27.447Z] { \ [2025-09-05T15:50:27.447Z] echo ""; echo "TEST SETUP:"; \ [2025-09-05T15:50:27.447Z] echo "Nothing to be done for setup."; \ [2025-09-05T15:50:27.447Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17570874265764/renaissance-als_0"; \ [2025-09-05T15:50:27.447Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17570874265764/renaissance-als_0"; \ [2025-09-05T15:50:27.447Z] echo ""; echo "TESTING:"; \ [2025-09-05T15:50:27.447Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_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_aarch64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17570874265764/renaissance-als_0"/als.json" als; \ [2025-09-05T15:50:27.447Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17570874265764/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-05T15:50:27.447Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-05T15:50:27.447Z] echo "Nothing to be done for teardown."; \ [2025-09-05T15:50:27.447Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17570874265764/TestTargetResult"; [2025-09-05T15:50:27.447Z] [2025-09-05T15:50:27.447Z] TEST SETUP: [2025-09-05T15:50:27.447Z] Nothing to be done for setup. [2025-09-05T15:50:27.448Z] [2025-09-05T15:50:27.448Z] TESTING: [2025-09-05T15:50:28.380Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-09-05T15:50:28.380Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/output_17570874265764/renaissance-als_0/launcher-155027-3578534275864629710/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-09-05T15:50:28.380Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-09-05T15:50:28.380Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-09-05T15:50:36.917Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-09-05T15:50:42.192Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-09-05T15:50:42.192Z] GC before operation: completed in 51.654 ms, heap usage 164.516 MB -> 35.202 MB. [2025-09-05T15:51:03.217Z] ====== als (apache-spark) [default], iteration 0 completed (18518.377 ms) ====== [2025-09-05T15:51:03.217Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-09-05T15:51:03.217Z] GC before operation: completed in 108.423 ms, heap usage 141.326 MB -> 61.730 MB. [2025-09-05T15:51:08.638Z] ====== als (apache-spark) [default], iteration 1 completed (6738.702 ms) ====== [2025-09-05T15:51:08.638Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-09-05T15:51:08.638Z] GC before operation: completed in 105.685 ms, heap usage 158.746 MB -> 62.568 MB. [2025-09-05T15:51:15.227Z] ====== als (apache-spark) [default], iteration 2 completed (6120.865 ms) ====== [2025-09-05T15:51:15.227Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-09-05T15:51:15.227Z] GC before operation: completed in 105.471 ms, heap usage 119.001 MB -> 63.148 MB. [2025-09-05T15:51:21.863Z] ====== als (apache-spark) [default], iteration 3 completed (6084.466 ms) ====== [2025-09-05T15:51:21.863Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-09-05T15:51:21.863Z] GC before operation: completed in 111.934 ms, heap usage 290.035 MB -> 63.870 MB. [2025-09-05T15:51:27.152Z] ====== als (apache-spark) [default], iteration 4 completed (5875.760 ms) ====== [2025-09-05T15:51:27.152Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-09-05T15:51:27.152Z] GC before operation: completed in 127.702 ms, heap usage 504.566 MB -> 64.727 MB. [2025-09-05T15:51:33.765Z] ====== als (apache-spark) [default], iteration 5 completed (5577.870 ms) ====== [2025-09-05T15:51:33.765Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-09-05T15:51:33.766Z] GC before operation: completed in 114.547 ms, heap usage 582.561 MB -> 65.385 MB. [2025-09-05T15:51:39.041Z] ====== als (apache-spark) [default], iteration 6 completed (5559.085 ms) ====== [2025-09-05T15:51:39.041Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-09-05T15:51:39.041Z] GC before operation: completed in 122.507 ms, heap usage 549.178 MB -> 65.975 MB. [2025-09-05T15:51:45.680Z] ====== als (apache-spark) [default], iteration 7 completed (5529.146 ms) ====== [2025-09-05T15:51:45.680Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-09-05T15:51:45.680Z] GC before operation: completed in 123.599 ms, heap usage 251.221 MB -> 66.045 MB. [2025-09-05T15:51:50.961Z] ====== als (apache-spark) [default], iteration 8 completed (5199.547 ms) ====== [2025-09-05T15:51:50.961Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-09-05T15:51:50.961Z] GC before operation: completed in 118.658 ms, heap usage 112.103 MB -> 66.387 MB. [2025-09-05T15:51:56.250Z] ====== als (apache-spark) [default], iteration 9 completed (5177.954 ms) ====== [2025-09-05T15:51:56.250Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-09-05T15:51:56.250Z] GC before operation: completed in 124.876 ms, heap usage 545.212 MB -> 67.232 MB. [2025-09-05T15:52:01.541Z] ====== als (apache-spark) [default], iteration 10 completed (5283.370 ms) ====== [2025-09-05T15:52:01.541Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-09-05T15:52:01.541Z] GC before operation: completed in 124.202 ms, heap usage 217.731 MB -> 67.010 MB. [2025-09-05T15:52:07.545Z] ====== als (apache-spark) [default], iteration 11 completed (5172.853 ms) ====== [2025-09-05T15:52:07.545Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-09-05T15:52:07.545Z] GC before operation: completed in 128.707 ms, heap usage 318.237 MB -> 67.451 MB. [2025-09-05T15:52:12.834Z] ====== als (apache-spark) [default], iteration 12 completed (5138.649 ms) ====== [2025-09-05T15:52:12.834Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-09-05T15:52:12.834Z] GC before operation: completed in 125.181 ms, heap usage 383.105 MB -> 67.764 MB. [2025-09-05T15:52:18.114Z] ====== als (apache-spark) [default], iteration 13 completed (5131.360 ms) ====== [2025-09-05T15:52:18.114Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-09-05T15:52:18.114Z] GC before operation: completed in 137.496 ms, heap usage 449.046 MB -> 68.249 MB. [2025-09-05T15:52:23.416Z] ====== als (apache-spark) [default], iteration 14 completed (5140.108 ms) ====== [2025-09-05T15:52:23.416Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-09-05T15:52:23.416Z] GC before operation: completed in 128.744 ms, heap usage 446.796 MB -> 68.545 MB. [2025-09-05T15:52:28.700Z] ====== als (apache-spark) [default], iteration 15 completed (5077.739 ms) ====== [2025-09-05T15:52:28.700Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-09-05T15:52:28.700Z] GC before operation: completed in 137.659 ms, heap usage 295.757 MB -> 68.595 MB. [2025-09-05T15:52:33.999Z] ====== als (apache-spark) [default], iteration 16 completed (4993.881 ms) ====== [2025-09-05T15:52:33.999Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-09-05T15:52:33.999Z] GC before operation: completed in 126.941 ms, heap usage 136.133 MB -> 68.573 MB. [2025-09-05T15:52:39.286Z] ====== als (apache-spark) [default], iteration 17 completed (5022.087 ms) ====== [2025-09-05T15:52:39.286Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-09-05T15:52:39.286Z] GC before operation: completed in 132.226 ms, heap usage 398.583 MB -> 69.060 MB. [2025-09-05T15:52:44.754Z] ====== als (apache-spark) [default], iteration 18 completed (5187.631 ms) ====== [2025-09-05T15:52:44.754Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-09-05T15:52:44.754Z] GC before operation: completed in 134.884 ms, heap usage 231.788 MB -> 69.179 MB. [2025-09-05T15:52:51.377Z] ====== als (apache-spark) [default], iteration 19 completed (5441.972 ms) ====== [2025-09-05T15:52:51.377Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-09-05T15:52:51.377Z] GC before operation: completed in 141.365 ms, heap usage 356.164 MB -> 69.629 MB. [2025-09-05T15:52:56.658Z] ====== als (apache-spark) [default], iteration 20 completed (5331.125 ms) ====== [2025-09-05T15:52:56.658Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-09-05T15:52:56.658Z] GC before operation: completed in 135.399 ms, heap usage 157.216 MB -> 69.797 MB. [2025-09-05T15:53:01.954Z] ====== als (apache-spark) [default], iteration 21 completed (5221.607 ms) ====== [2025-09-05T15:53:01.954Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-09-05T15:53:01.955Z] GC before operation: completed in 130.895 ms, heap usage 346.743 MB -> 70.127 MB. [2025-09-05T15:53:07.300Z] ====== als (apache-spark) [default], iteration 22 completed (5219.843 ms) ====== [2025-09-05T15:53:07.300Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-09-05T15:53:07.300Z] GC before operation: completed in 154.598 ms, heap usage 252.769 MB -> 70.284 MB. [2025-09-05T15:53:12.607Z] ====== als (apache-spark) [default], iteration 23 completed (5087.285 ms) ====== [2025-09-05T15:53:12.607Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-09-05T15:53:12.607Z] GC before operation: completed in 143.939 ms, heap usage 291.601 MB -> 70.527 MB. [2025-09-05T15:53:17.909Z] ====== als (apache-spark) [default], iteration 24 completed (5282.284 ms) ====== [2025-09-05T15:53:17.909Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-09-05T15:53:17.909Z] GC before operation: completed in 152.645 ms, heap usage 540.715 MB -> 71.222 MB. [2025-09-05T15:53:23.895Z] ====== als (apache-spark) [default], iteration 25 completed (5215.700 ms) ====== [2025-09-05T15:53:23.895Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-09-05T15:53:23.895Z] GC before operation: completed in 153.020 ms, heap usage 189.316 MB -> 70.979 MB. [2025-09-05T15:53:29.185Z] ====== als (apache-spark) [default], iteration 26 completed (5233.226 ms) ====== [2025-09-05T15:53:29.185Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-09-05T15:53:29.185Z] GC before operation: completed in 146.098 ms, heap usage 296.397 MB -> 71.507 MB. [2025-09-05T15:53:35.793Z] ====== als (apache-spark) [default], iteration 27 completed (5496.766 ms) ====== [2025-09-05T15:53:35.793Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-09-05T15:53:35.793Z] GC before operation: completed in 151.981 ms, heap usage 354.047 MB -> 71.725 MB. [2025-09-05T15:53:41.103Z] ====== als (apache-spark) [default], iteration 28 completed (5497.529 ms) ====== [2025-09-05T15:53:41.103Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-09-05T15:53:41.103Z] GC before operation: completed in 149.996 ms, heap usage 295.574 MB -> 71.911 MB. [2025-09-05T15:53:46.388Z] ====== als (apache-spark) [default], iteration 29 completed (5297.132 ms) ====== [2025-09-05T15:53:47.325Z] ----------------------------------- [2025-09-05T15:53:47.325Z] renaissance-als_0_PASSED [2025-09-05T15:53:47.325Z] ----------------------------------- [2025-09-05T15:53:47.325Z] [2025-09-05T15:53:47.325Z] TEST TEARDOWN: [2025-09-05T15:53:47.325Z] Nothing to be done for teardown. [2025-09-05T15:53:47.325Z] renaissance-als_0 Finish Time: Fri Sep 5 15:53:46 2025 Epoch Time (ms): 1757087626545