[2025-12-16T08:51:11.883Z] Running test renaissance-als_0 ... [2025-12-16T08:51:11.883Z] =============================================== [2025-12-16T08:51:11.883Z] renaissance-als_0 Start Time: Tue Dec 16 08:51:11 2025 Epoch Time (ms): 1765875071623 [2025-12-16T08:51:11.883Z] variation: NoOptions [2025-12-16T08:51:11.883Z] JVM_OPTIONS: [2025-12-16T08:51:11.883Z] { \ [2025-12-16T08:51:11.883Z] echo ""; echo "TEST SETUP:"; \ [2025-12-16T08:51:11.883Z] echo "Nothing to be done for setup."; \ [2025-12-16T08:51:11.883Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17658748754119/renaissance-als_0"; \ [2025-12-16T08:51:11.883Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17658748754119/renaissance-als_0"; \ [2025-12-16T08:51:11.883Z] echo ""; echo "TESTING:"; \ [2025-12-16T08:51:11.883Z] "/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_17658748754119/renaissance-als_0"/als.json" als; \ [2025-12-16T08:51:11.883Z] 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_17658748754119/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-16T08:51:11.883Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-16T08:51:11.883Z] echo "Nothing to be done for teardown."; \ [2025-12-16T08:51:11.883Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17658748754119/TestTargetResult"; [2025-12-16T08:51:11.883Z] [2025-12-16T08:51:11.883Z] TEST SETUP: [2025-12-16T08:51:11.883Z] Nothing to be done for setup. [2025-12-16T08:51:11.883Z] [2025-12-16T08:51:11.883Z] TESTING: [2025-12-16T08:51:12.217Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-16T08:51:12.217Z] 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_17658748754119/renaissance-als_0/launcher-085111-11922633725031964047/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-16T08:51:12.217Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-16T08:51:12.217Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-16T08:51:18.418Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-12-16T08:51:23.363Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-16T08:51:23.363Z] GC before operation: completed in 57.263 ms, heap usage 51.500 MB -> 34.859 MB. [2025-12-16T08:51:51.697Z] ====== als (apache-spark) [default], iteration 0 completed (26808.008 ms) ====== [2025-12-16T08:51:51.697Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-16T08:51:51.697Z] GC before operation: completed in 109.843 ms, heap usage 267.983 MB -> 61.397 MB. [2025-12-16T08:52:05.361Z] ====== als (apache-spark) [default], iteration 1 completed (13685.463 ms) ====== [2025-12-16T08:52:05.361Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-16T08:52:05.361Z] GC before operation: completed in 104.219 ms, heap usage 85.330 MB -> 62.000 MB. [2025-12-16T08:52:18.822Z] ====== als (apache-spark) [default], iteration 2 completed (12899.778 ms) ====== [2025-12-16T08:52:18.822Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-16T08:52:18.822Z] GC before operation: completed in 114.723 ms, heap usage 254.093 MB -> 62.870 MB. [2025-12-16T08:52:32.267Z] ====== als (apache-spark) [default], iteration 3 completed (13126.405 ms) ====== [2025-12-16T08:52:32.267Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-16T08:52:32.267Z] GC before operation: completed in 104.607 ms, heap usage 94.031 MB -> 63.458 MB. [2025-12-16T08:52:43.488Z] ====== als (apache-spark) [default], iteration 4 completed (11881.533 ms) ====== [2025-12-16T08:52:43.488Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-16T08:52:43.488Z] GC before operation: completed in 112.687 ms, heap usage 462.750 MB -> 64.266 MB. [2025-12-16T08:52:56.963Z] ====== als (apache-spark) [default], iteration 5 completed (11556.574 ms) ====== [2025-12-16T08:52:56.963Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-16T08:52:56.963Z] GC before operation: completed in 110.015 ms, heap usage 206.325 MB -> 64.578 MB. [2025-12-16T08:53:08.149Z] ====== als (apache-spark) [default], iteration 6 completed (11441.504 ms) ====== [2025-12-16T08:53:08.149Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-16T08:53:08.149Z] GC before operation: completed in 117.299 ms, heap usage 204.369 MB -> 65.153 MB. [2025-12-16T08:53:21.604Z] ====== als (apache-spark) [default], iteration 7 completed (11757.258 ms) ====== [2025-12-16T08:53:21.604Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-16T08:53:21.604Z] GC before operation: completed in 116.070 ms, heap usage 206.560 MB -> 65.600 MB. [2025-12-16T08:53:31.401Z] ====== als (apache-spark) [default], iteration 8 completed (11473.584 ms) ====== [2025-12-16T08:53:31.401Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-16T08:53:31.401Z] GC before operation: completed in 119.242 ms, heap usage 461.660 MB -> 66.528 MB. [2025-12-16T08:53:44.782Z] ====== als (apache-spark) [default], iteration 9 completed (11633.784 ms) ====== [2025-12-16T08:53:44.782Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-16T08:53:44.782Z] GC before operation: completed in 122.455 ms, heap usage 378.768 MB -> 66.539 MB. [2025-12-16T08:53:55.754Z] ====== als (apache-spark) [default], iteration 10 completed (11589.254 ms) ====== [2025-12-16T08:53:55.754Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-16T08:53:55.754Z] GC before operation: completed in 127.080 ms, heap usage 191.804 MB -> 66.544 MB. [2025-12-16T08:54:09.007Z] ====== als (apache-spark) [default], iteration 11 completed (11375.739 ms) ====== [2025-12-16T08:54:09.007Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-16T08:54:09.007Z] GC before operation: completed in 119.543 ms, heap usage 185.936 MB -> 66.808 MB. [2025-12-16T08:54:20.506Z] ====== als (apache-spark) [default], iteration 12 completed (11329.315 ms) ====== [2025-12-16T08:54:20.506Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-16T08:54:20.506Z] GC before operation: completed in 118.322 ms, heap usage 324.181 MB -> 67.341 MB. [2025-12-16T08:54:30.288Z] ====== als (apache-spark) [default], iteration 13 completed (11228.917 ms) ====== [2025-12-16T08:54:30.288Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-16T08:54:30.288Z] GC before operation: completed in 123.299 ms, heap usage 509.908 MB -> 67.862 MB. [2025-12-16T08:54:43.945Z] ====== als (apache-spark) [default], iteration 14 completed (11286.440 ms) ====== [2025-12-16T08:54:43.945Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-16T08:54:43.945Z] GC before operation: completed in 122.840 ms, heap usage 183.889 MB -> 67.618 MB. [2025-12-16T08:54:53.346Z] ====== als (apache-spark) [default], iteration 15 completed (11234.586 ms) ====== [2025-12-16T08:54:53.346Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-16T08:54:53.682Z] GC before operation: completed in 201.068 ms, heap usage 484.703 MB -> 68.285 MB. [2025-12-16T08:55:07.140Z] ====== als (apache-spark) [default], iteration 16 completed (11256.446 ms) ====== [2025-12-16T08:55:07.140Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-16T08:55:07.140Z] GC before operation: completed in 127.542 ms, heap usage 488.785 MB -> 68.534 MB. [2025-12-16T08:55:18.225Z] ====== als (apache-spark) [default], iteration 17 completed (11295.592 ms) ====== [2025-12-16T08:55:18.225Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-16T08:55:18.225Z] GC before operation: completed in 121.461 ms, heap usage 358.481 MB -> 68.521 MB. [2025-12-16T08:55:29.241Z] ====== als (apache-spark) [default], iteration 18 completed (11454.144 ms) ====== [2025-12-16T08:55:29.241Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-16T08:55:29.241Z] GC before operation: completed in 126.889 ms, heap usage 240.593 MB -> 68.842 MB. [2025-12-16T08:55:42.527Z] ====== als (apache-spark) [default], iteration 19 completed (11705.536 ms) ====== [2025-12-16T08:55:42.527Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-16T08:55:42.527Z] GC before operation: completed in 120.988 ms, heap usage 355.379 MB -> 69.141 MB. [2025-12-16T08:55:53.490Z] ====== als (apache-spark) [default], iteration 20 completed (11776.307 ms) ====== [2025-12-16T08:55:53.490Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-16T08:55:53.490Z] GC before operation: completed in 121.823 ms, heap usage 309.801 MB -> 69.369 MB. [2025-12-16T08:56:06.766Z] ====== als (apache-spark) [default], iteration 21 completed (11741.925 ms) ====== [2025-12-16T08:56:06.766Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-16T08:56:06.766Z] GC before operation: completed in 121.391 ms, heap usage 118.257 MB -> 69.355 MB. [2025-12-16T08:56:17.663Z] ====== als (apache-spark) [default], iteration 22 completed (11077.731 ms) ====== [2025-12-16T08:56:17.663Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-16T08:56:17.663Z] GC before operation: completed in 128.546 ms, heap usage 359.377 MB -> 69.807 MB. [2025-12-16T08:56:28.566Z] ====== als (apache-spark) [default], iteration 23 completed (11171.479 ms) ====== [2025-12-16T08:56:28.566Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-16T08:56:28.566Z] GC before operation: completed in 130.532 ms, heap usage 195.597 MB -> 69.858 MB. [2025-12-16T08:56:39.565Z] ====== als (apache-spark) [default], iteration 24 completed (11539.721 ms) ====== [2025-12-16T08:56:39.565Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-16T08:56:39.565Z] GC before operation: completed in 126.497 ms, heap usage 386.963 MB -> 70.378 MB. [2025-12-16T08:56:52.728Z] ====== als (apache-spark) [default], iteration 25 completed (11155.192 ms) ====== [2025-12-16T08:56:52.728Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-16T08:56:52.728Z] GC before operation: completed in 125.170 ms, heap usage 224.959 MB -> 70.482 MB. [2025-12-16T08:57:03.530Z] ====== als (apache-spark) [default], iteration 26 completed (11260.138 ms) ====== [2025-12-16T08:57:03.530Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-16T08:57:03.530Z] GC before operation: completed in 128.245 ms, heap usage 187.451 MB -> 70.608 MB. [2025-12-16T08:57:14.343Z] ====== als (apache-spark) [default], iteration 27 completed (11206.161 ms) ====== [2025-12-16T08:57:14.343Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-16T08:57:14.343Z] GC before operation: completed in 129.272 ms, heap usage 490.706 MB -> 71.341 MB. [2025-12-16T08:57:27.528Z] ====== als (apache-spark) [default], iteration 28 completed (11328.844 ms) ====== [2025-12-16T08:57:27.528Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-16T08:57:27.528Z] GC before operation: completed in 124.462 ms, heap usage 279.449 MB -> 71.314 MB. [2025-12-16T08:57:38.484Z] ====== als (apache-spark) [default], iteration 29 completed (11132.741 ms) ====== [2025-12-16T08:57:38.484Z] ----------------------------------- [2025-12-16T08:57:38.484Z] renaissance-als_0_PASSED [2025-12-16T08:57:38.484Z] ----------------------------------- [2025-12-16T08:57:38.484Z] [2025-12-16T08:57:38.484Z] TEST TEARDOWN: [2025-12-16T08:57:38.484Z] Nothing to be done for teardown. [2025-12-16T08:57:38.484Z] renaissance-als_0 Finish Time: Tue Dec 16 08:57:37 2025 Epoch Time (ms): 1765875457316