[2025-12-13T13:33:45.918Z] Running test renaissance-als_0 ... [2025-12-13T13:33:45.918Z] =============================================== [2025-12-13T13:33:45.918Z] renaissance-als_0 Start Time: Sat Dec 13 13:33:45 2025 Epoch Time (ms): 1765632825150 [2025-12-13T13:33:45.918Z] variation: NoOptions [2025-12-13T13:33:45.918Z] JVM_OPTIONS: [2025-12-13T13:33:45.918Z] { \ [2025-12-13T13:33:45.918Z] echo ""; echo "TEST SETUP:"; \ [2025-12-13T13:33:45.918Z] echo "Nothing to be done for setup."; \ [2025-12-13T13:33:45.918Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17656328247060/renaissance-als_0"; \ [2025-12-13T13:33:45.918Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17656328247060/renaissance-als_0"; \ [2025-12-13T13:33:45.918Z] echo ""; echo "TESTING:"; \ [2025-12-13T13:33:45.918Z] "/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_17656328247060/renaissance-als_0"/als.json" als; \ [2025-12-13T13:33:45.918Z] 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_17656328247060/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-13T13:33:45.918Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-13T13:33:45.918Z] echo "Nothing to be done for teardown."; \ [2025-12-13T13:33:45.918Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17656328247060/TestTargetResult"; [2025-12-13T13:33:45.918Z] [2025-12-13T13:33:45.918Z] TEST SETUP: [2025-12-13T13:33:45.918Z] Nothing to be done for setup. [2025-12-13T13:33:45.918Z] [2025-12-13T13:33:45.918Z] TESTING: [2025-12-13T13:33:46.658Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-13T13:33:46.658Z] 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_17656328247060/renaissance-als_0/launcher-133345-198167194886984036/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-13T13:33:46.658Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-13T13:33:46.658Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-13T13:33:54.740Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-13T13:34:02.869Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-13T13:34:02.870Z] GC before operation: completed in 114.633 ms, heap usage 152.500 MB -> 35.084 MB. [2025-12-13T13:34:32.584Z] ====== als (apache-spark) [default], iteration 0 completed (29415.221 ms) ====== [2025-12-13T13:34:32.584Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-13T13:34:32.584Z] GC before operation: completed in 136.266 ms, heap usage 219.223 MB -> 61.613 MB. [2025-12-13T13:34:46.430Z] ====== als (apache-spark) [default], iteration 1 completed (12056.022 ms) ====== [2025-12-13T13:34:46.430Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-13T13:34:46.430Z] GC before operation: completed in 163.928 ms, heap usage 370.252 MB -> 62.714 MB. [2025-12-13T13:34:59.958Z] ====== als (apache-spark) [default], iteration 2 completed (13085.481 ms) ====== [2025-12-13T13:34:59.958Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-13T13:34:59.958Z] GC before operation: completed in 202.727 ms, heap usage 232.554 MB -> 63.242 MB. [2025-12-13T13:35:11.588Z] ====== als (apache-spark) [default], iteration 3 completed (12050.667 ms) ====== [2025-12-13T13:35:11.588Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-13T13:35:11.588Z] GC before operation: completed in 218.905 ms, heap usage 112.630 MB -> 63.772 MB. [2025-12-13T13:35:25.305Z] ====== als (apache-spark) [default], iteration 4 completed (12190.902 ms) ====== [2025-12-13T13:35:25.305Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-13T13:35:25.305Z] GC before operation: completed in 195.294 ms, heap usage 470.439 MB -> 64.760 MB. [2025-12-13T13:35:37.457Z] ====== als (apache-spark) [default], iteration 5 completed (11583.061 ms) ====== [2025-12-13T13:35:37.457Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-13T13:35:37.457Z] GC before operation: completed in 222.798 ms, heap usage 489.458 MB -> 65.265 MB. [2025-12-13T13:35:49.018Z] ====== als (apache-spark) [default], iteration 6 completed (12023.635 ms) ====== [2025-12-13T13:35:49.018Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-13T13:35:49.018Z] GC before operation: completed in 183.710 ms, heap usage 89.514 MB -> 65.358 MB. [2025-12-13T13:36:02.630Z] ====== als (apache-spark) [default], iteration 7 completed (11550.863 ms) ====== [2025-12-13T13:36:02.630Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-13T13:36:02.630Z] GC before operation: completed in 229.360 ms, heap usage 260.678 MB -> 66.033 MB. [2025-12-13T13:36:14.153Z] ====== als (apache-spark) [default], iteration 8 completed (11396.302 ms) ====== [2025-12-13T13:36:14.153Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-13T13:36:14.153Z] GC before operation: completed in 208.122 ms, heap usage 506.677 MB -> 66.857 MB. [2025-12-13T13:36:26.144Z] ====== als (apache-spark) [default], iteration 9 completed (11233.132 ms) ====== [2025-12-13T13:36:26.144Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-13T13:36:26.144Z] GC before operation: completed in 251.244 ms, heap usage 534.906 MB -> 67.217 MB. [2025-12-13T13:36:37.732Z] ====== als (apache-spark) [default], iteration 10 completed (11174.864 ms) ====== [2025-12-13T13:36:37.732Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-13T13:36:37.732Z] GC before operation: completed in 193.346 ms, heap usage 100.836 MB -> 66.943 MB. [2025-12-13T13:36:49.197Z] ====== als (apache-spark) [default], iteration 11 completed (11151.868 ms) ====== [2025-12-13T13:36:49.197Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-13T13:36:49.197Z] GC before operation: completed in 183.158 ms, heap usage 381.868 MB -> 67.410 MB. [2025-12-13T13:37:00.632Z] ====== als (apache-spark) [default], iteration 12 completed (11688.789 ms) ====== [2025-12-13T13:37:00.632Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-13T13:37:00.632Z] GC before operation: completed in 207.649 ms, heap usage 344.127 MB -> 67.676 MB. [2025-12-13T13:37:14.778Z] ====== als (apache-spark) [default], iteration 13 completed (13443.505 ms) ====== [2025-12-13T13:37:14.778Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-13T13:37:14.778Z] GC before operation: completed in 215.831 ms, heap usage 353.986 MB -> 67.857 MB. [2025-12-13T13:37:41.195Z] ====== als (apache-spark) [default], iteration 14 completed (23114.894 ms) ====== [2025-12-13T13:37:41.195Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-13T13:37:41.195Z] GC before operation: completed in 235.599 ms, heap usage 492.710 MB -> 68.352 MB. [2025-12-13T13:38:23.782Z] ====== als (apache-spark) [default], iteration 15 completed (39640.509 ms) ====== [2025-12-13T13:38:23.782Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-13T13:38:23.782Z] GC before operation: completed in 189.638 ms, heap usage 186.808 MB -> 68.098 MB. [2025-12-13T13:39:00.025Z] ====== als (apache-spark) [default], iteration 16 completed (39580.393 ms) ====== [2025-12-13T13:39:00.025Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-13T13:39:00.025Z] GC before operation: completed in 162.440 ms, heap usage 192.683 MB -> 68.399 MB. [2025-12-13T13:39:13.493Z] ====== als (apache-spark) [default], iteration 17 completed (13804.749 ms) ====== [2025-12-13T13:39:13.493Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-13T13:39:13.493Z] GC before operation: completed in 169.260 ms, heap usage 482.625 MB -> 69.099 MB. [2025-12-13T13:39:27.096Z] ====== als (apache-spark) [default], iteration 18 completed (13274.854 ms) ====== [2025-12-13T13:39:27.096Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-13T13:39:27.097Z] GC before operation: completed in 166.911 ms, heap usage 198.440 MB -> 68.903 MB. [2025-12-13T13:39:40.634Z] ====== als (apache-spark) [default], iteration 19 completed (13824.934 ms) ====== [2025-12-13T13:39:40.634Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-13T13:39:40.634Z] GC before operation: completed in 193.864 ms, heap usage 155.510 MB -> 69.166 MB. [2025-12-13T13:39:50.725Z] ====== als (apache-spark) [default], iteration 20 completed (9462.339 ms) ====== [2025-12-13T13:39:50.725Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-13T13:39:50.725Z] GC before operation: completed in 176.215 ms, heap usage 525.519 MB -> 70.013 MB. [2025-12-13T13:40:04.377Z] ====== als (apache-spark) [default], iteration 21 completed (12229.276 ms) ====== [2025-12-13T13:40:04.377Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-13T13:40:04.377Z] GC before operation: completed in 161.564 ms, heap usage 215.174 MB -> 69.843 MB. [2025-12-13T13:40:14.171Z] ====== als (apache-spark) [default], iteration 22 completed (10169.311 ms) ====== [2025-12-13T13:40:14.171Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-13T13:40:14.171Z] GC before operation: completed in 164.021 ms, heap usage 301.727 MB -> 70.147 MB. [2025-12-13T13:40:26.313Z] ====== als (apache-spark) [default], iteration 23 completed (11415.131 ms) ====== [2025-12-13T13:40:26.313Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-13T13:40:26.313Z] GC before operation: completed in 184.525 ms, heap usage 388.175 MB -> 70.446 MB. [2025-12-13T13:40:37.978Z] ====== als (apache-spark) [default], iteration 24 completed (10523.963 ms) ====== [2025-12-13T13:40:37.979Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-13T13:40:37.979Z] GC before operation: completed in 190.240 ms, heap usage 392.098 MB -> 70.732 MB. [2025-12-13T13:40:51.826Z] ====== als (apache-spark) [default], iteration 25 completed (14534.549 ms) ====== [2025-12-13T13:40:51.826Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-13T13:40:51.826Z] GC before operation: completed in 163.656 ms, heap usage 429.201 MB -> 71.337 MB. [2025-12-13T13:41:05.308Z] ====== als (apache-spark) [default], iteration 26 completed (13215.791 ms) ====== [2025-12-13T13:41:05.308Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-13T13:41:05.308Z] GC before operation: completed in 182.615 ms, heap usage 395.828 MB -> 71.364 MB. [2025-12-13T13:41:16.768Z] ====== als (apache-spark) [default], iteration 27 completed (10032.705 ms) ====== [2025-12-13T13:41:16.768Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-13T13:41:16.768Z] GC before operation: completed in 178.010 ms, heap usage 558.361 MB -> 71.897 MB. [2025-12-13T13:41:26.419Z] ====== als (apache-spark) [default], iteration 28 completed (9983.536 ms) ====== [2025-12-13T13:41:26.419Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-13T13:41:26.419Z] GC before operation: completed in 158.197 ms, heap usage 391.181 MB -> 71.773 MB. [2025-12-13T13:41:38.383Z] ====== als (apache-spark) [default], iteration 29 completed (12082.039 ms) ====== [2025-12-13T13:41:39.123Z] ----------------------------------- [2025-12-13T13:41:39.123Z] renaissance-als_0_PASSED [2025-12-13T13:41:39.123Z] ----------------------------------- [2025-12-13T13:41:39.123Z] [2025-12-13T13:41:39.123Z] TEST TEARDOWN: [2025-12-13T13:41:39.123Z] Nothing to be done for teardown. [2025-12-13T13:41:39.123Z] renaissance-als_0 Finish Time: Sat Dec 13 13:41:38 2025 Epoch Time (ms): 1765633298773