[2025-12-20T14:50:50.361Z] Running test renaissance-als_0 ... [2025-12-20T14:50:50.361Z] =============================================== [2025-12-20T14:50:50.361Z] renaissance-als_0 Start Time: Sat Dec 20 14:50:50 2025 Epoch Time (ms): 1766242250282 [2025-12-20T14:50:50.361Z] variation: NoOptions [2025-12-20T14:50:50.361Z] JVM_OPTIONS: [2025-12-20T14:50:50.361Z] { \ [2025-12-20T14:50:50.361Z] echo ""; echo "TEST SETUP:"; \ [2025-12-20T14:50:50.361Z] echo "Nothing to be done for setup."; \ [2025-12-20T14:50:50.361Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17662422493056/renaissance-als_0"; \ [2025-12-20T14:50:50.361Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17662422493056/renaissance-als_0"; \ [2025-12-20T14:50:50.361Z] echo ""; echo "TESTING:"; \ [2025-12-20T14:50:50.361Z] "/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_17662422493056/renaissance-als_0"/als.json" als; \ [2025-12-20T14:50:50.361Z] 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_17662422493056/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-20T14:50:50.361Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-20T14:50:50.361Z] echo "Nothing to be done for teardown."; \ [2025-12-20T14:50:50.361Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17662422493056/TestTargetResult"; [2025-12-20T14:50:50.361Z] [2025-12-20T14:50:50.361Z] TEST SETUP: [2025-12-20T14:50:50.361Z] Nothing to be done for setup. [2025-12-20T14:50:50.361Z] [2025-12-20T14:50:50.361Z] TESTING: [2025-12-20T14:50:51.939Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-20T14:50:51.939Z] 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_17662422493056/renaissance-als_0/launcher-145050-12475550832327453453/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-20T14:50:51.939Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-20T14:50:51.939Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-20T14:51:00.331Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-20T14:51:06.259Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-20T14:51:06.259Z] GC before operation: completed in 90.357 ms, heap usage 52.877 MB -> 35.106 MB. [2025-12-20T14:51:41.792Z] ====== als (apache-spark) [default], iteration 0 completed (30281.296 ms) ====== [2025-12-20T14:51:41.792Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-20T14:51:41.792Z] GC before operation: completed in 171.932 ms, heap usage 140.385 MB -> 61.562 MB. [2025-12-20T14:51:51.588Z] ====== als (apache-spark) [default], iteration 1 completed (12368.504 ms) ====== [2025-12-20T14:51:51.588Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-20T14:51:51.588Z] GC before operation: completed in 193.213 ms, heap usage 197.753 MB -> 62.631 MB. [2025-12-20T14:52:03.217Z] ====== als (apache-spark) [default], iteration 2 completed (11569.081 ms) ====== [2025-12-20T14:52:03.217Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-20T14:52:03.217Z] GC before operation: completed in 196.853 ms, heap usage 184.324 MB -> 63.038 MB. [2025-12-20T14:52:14.811Z] ====== als (apache-spark) [default], iteration 3 completed (10992.511 ms) ====== [2025-12-20T14:52:14.811Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-20T14:52:14.811Z] GC before operation: completed in 160.476 ms, heap usage 473.117 MB -> 64.027 MB. [2025-12-20T14:52:26.426Z] ====== als (apache-spark) [default], iteration 4 completed (11344.478 ms) ====== [2025-12-20T14:52:26.426Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-20T14:52:26.426Z] GC before operation: completed in 192.023 ms, heap usage 391.148 MB -> 64.369 MB. [2025-12-20T14:52:38.021Z] ====== als (apache-spark) [default], iteration 5 completed (11397.788 ms) ====== [2025-12-20T14:52:38.021Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-20T14:52:38.779Z] GC before operation: completed in 197.688 ms, heap usage 430.880 MB -> 65.248 MB. [2025-12-20T14:52:50.460Z] ====== als (apache-spark) [default], iteration 6 completed (11742.867 ms) ====== [2025-12-20T14:52:50.460Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-20T14:52:51.246Z] GC before operation: completed in 205.074 ms, heap usage 174.689 MB -> 65.308 MB. [2025-12-20T14:53:03.465Z] ====== als (apache-spark) [default], iteration 7 completed (11475.393 ms) ====== [2025-12-20T14:53:03.466Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-20T14:53:03.466Z] GC before operation: completed in 212.489 ms, heap usage 425.315 MB -> 66.328 MB. [2025-12-20T14:53:15.060Z] ====== als (apache-spark) [default], iteration 8 completed (10651.834 ms) ====== [2025-12-20T14:53:15.060Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-20T14:53:15.060Z] GC before operation: completed in 221.083 ms, heap usage 100.807 MB -> 66.450 MB. [2025-12-20T14:53:24.855Z] ====== als (apache-spark) [default], iteration 9 completed (10222.391 ms) ====== [2025-12-20T14:53:24.855Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-20T14:53:24.855Z] GC before operation: completed in 162.395 ms, heap usage 212.177 MB -> 66.741 MB. [2025-12-20T14:53:36.465Z] ====== als (apache-spark) [default], iteration 10 completed (9724.393 ms) ====== [2025-12-20T14:53:36.465Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-20T14:53:36.465Z] GC before operation: completed in 186.334 ms, heap usage 260.886 MB -> 67.023 MB. [2025-12-20T14:53:46.697Z] ====== als (apache-spark) [default], iteration 11 completed (10665.800 ms) ====== [2025-12-20T14:53:46.697Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-20T14:53:46.697Z] GC before operation: completed in 196.535 ms, heap usage 182.061 MB -> 67.197 MB. [2025-12-20T14:53:58.294Z] ====== als (apache-spark) [default], iteration 12 completed (10002.274 ms) ====== [2025-12-20T14:53:58.294Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-20T14:53:58.294Z] GC before operation: completed in 205.904 ms, heap usage 431.990 MB -> 67.863 MB. [2025-12-20T14:54:08.094Z] ====== als (apache-spark) [default], iteration 13 completed (10424.485 ms) ====== [2025-12-20T14:54:08.094Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-20T14:54:08.094Z] GC before operation: completed in 215.463 ms, heap usage 451.487 MB -> 68.239 MB. [2025-12-20T14:54:17.925Z] ====== als (apache-spark) [default], iteration 14 completed (10009.272 ms) ====== [2025-12-20T14:54:17.926Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-20T14:54:18.696Z] GC before operation: completed in 229.193 ms, heap usage 397.302 MB -> 68.187 MB. [2025-12-20T14:54:28.506Z] ====== als (apache-spark) [default], iteration 15 completed (9925.805 ms) ====== [2025-12-20T14:54:28.506Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-20T14:54:28.506Z] GC before operation: completed in 168.722 ms, heap usage 440.206 MB -> 68.719 MB. [2025-12-20T14:54:40.246Z] ====== als (apache-spark) [default], iteration 16 completed (9800.098 ms) ====== [2025-12-20T14:54:40.246Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-20T14:54:40.246Z] GC before operation: completed in 213.673 ms, heap usage 240.855 MB -> 68.628 MB. [2025-12-20T14:54:50.035Z] ====== als (apache-spark) [default], iteration 17 completed (10410.790 ms) ====== [2025-12-20T14:54:50.035Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-20T14:54:50.035Z] GC before operation: completed in 219.308 ms, heap usage 284.935 MB -> 68.863 MB. [2025-12-20T14:54:59.881Z] ====== als (apache-spark) [default], iteration 18 completed (9774.002 ms) ====== [2025-12-20T14:54:59.881Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-20T14:55:00.645Z] GC before operation: completed in 231.634 ms, heap usage 278.911 MB -> 69.166 MB. [2025-12-20T14:55:12.284Z] ====== als (apache-spark) [default], iteration 19 completed (10603.202 ms) ====== [2025-12-20T14:55:12.284Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-20T14:55:12.284Z] GC before operation: completed in 245.906 ms, heap usage 534.081 MB -> 69.843 MB. [2025-12-20T14:55:22.074Z] ====== als (apache-spark) [default], iteration 20 completed (10572.135 ms) ====== [2025-12-20T14:55:22.074Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-20T14:55:22.837Z] GC before operation: completed in 198.056 ms, heap usage 468.896 MB -> 69.912 MB. [2025-12-20T14:55:34.976Z] ====== als (apache-spark) [default], iteration 21 completed (10976.832 ms) ====== [2025-12-20T14:55:34.976Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-20T14:55:34.976Z] GC before operation: completed in 218.570 ms, heap usage 189.439 MB -> 69.803 MB. [2025-12-20T14:55:46.585Z] ====== als (apache-spark) [default], iteration 22 completed (11406.871 ms) ====== [2025-12-20T14:55:46.586Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-20T14:55:46.586Z] GC before operation: completed in 253.788 ms, heap usage 352.464 MB -> 70.352 MB. [2025-12-20T14:55:58.333Z] ====== als (apache-spark) [default], iteration 23 completed (10970.963 ms) ====== [2025-12-20T14:55:58.333Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-20T14:55:58.333Z] GC before operation: completed in 208.243 ms, heap usage 403.654 MB -> 70.645 MB. [2025-12-20T14:56:09.999Z] ====== als (apache-spark) [default], iteration 24 completed (11059.268 ms) ====== [2025-12-20T14:56:09.999Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-20T14:56:09.999Z] GC before operation: completed in 208.880 ms, heap usage 346.364 MB -> 70.880 MB. [2025-12-20T14:56:19.795Z] ====== als (apache-spark) [default], iteration 25 completed (10549.834 ms) ====== [2025-12-20T14:56:19.796Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-20T14:56:19.796Z] GC before operation: completed in 212.336 ms, heap usage 287.909 MB -> 71.058 MB. [2025-12-20T14:56:31.568Z] ====== als (apache-spark) [default], iteration 26 completed (10366.574 ms) ====== [2025-12-20T14:56:31.568Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-20T14:56:31.568Z] GC before operation: completed in 188.196 ms, heap usage 254.627 MB -> 71.202 MB. [2025-12-20T14:56:41.349Z] ====== als (apache-spark) [default], iteration 27 completed (10202.432 ms) ====== [2025-12-20T14:56:41.349Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-20T14:56:41.349Z] GC before operation: completed in 251.921 ms, heap usage 375.899 MB -> 71.604 MB. [2025-12-20T14:56:52.933Z] ====== als (apache-spark) [default], iteration 28 completed (10017.731 ms) ====== [2025-12-20T14:56:52.933Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-20T14:56:52.933Z] GC before operation: completed in 218.798 ms, heap usage 185.959 MB -> 71.718 MB. [2025-12-20T14:57:02.800Z] ====== als (apache-spark) [default], iteration 29 completed (9448.552 ms) ====== [2025-12-20T14:57:02.800Z] ----------------------------------- [2025-12-20T14:57:02.800Z] renaissance-als_0_PASSED [2025-12-20T14:57:02.800Z] ----------------------------------- [2025-12-20T14:57:02.800Z] [2025-12-20T14:57:02.800Z] TEST TEARDOWN: [2025-12-20T14:57:02.800Z] Nothing to be done for teardown. [2025-12-20T14:57:02.800Z] renaissance-als_0 Finish Time: Sat Dec 20 14:57:02 2025 Epoch Time (ms): 1766242622147