[2025-12-16T08:58:34.959Z] Running test renaissance-als_0 ... [2025-12-16T08:58:34.959Z] =============================================== [2025-12-16T08:58:34.959Z] renaissance-als_0 Start Time: Tue Dec 16 08:58:34 2025 Epoch Time (ms): 1765875514536 [2025-12-16T08:58:34.959Z] variation: NoOptions [2025-12-16T08:58:34.959Z] JVM_OPTIONS: [2025-12-16T08:58:34.959Z] { \ [2025-12-16T08:58:34.959Z] echo ""; echo "TEST SETUP:"; \ [2025-12-16T08:58:34.959Z] echo "Nothing to be done for setup."; \ [2025-12-16T08:58:34.959Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17658751941891/renaissance-als_0"; \ [2025-12-16T08:58:34.959Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17658751941891/renaissance-als_0"; \ [2025-12-16T08:58:34.959Z] echo ""; echo "TESTING:"; \ [2025-12-16T08:58:34.959Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_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_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17658751941891/renaissance-als_0"/als.json" als; \ [2025-12-16T08:58:34.959Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17658751941891/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-16T08:58:34.959Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-16T08:58:34.959Z] echo "Nothing to be done for teardown."; \ [2025-12-16T08:58:34.959Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17658751941891/TestTargetResult"; [2025-12-16T08:58:34.959Z] [2025-12-16T08:58:34.959Z] TEST SETUP: [2025-12-16T08:58:34.959Z] Nothing to be done for setup. [2025-12-16T08:58:34.959Z] [2025-12-16T08:58:34.959Z] TESTING: [2025-12-16T08:58:37.035Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-16T08:58:37.035Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/output_17658751941891/renaissance-als_0/launcher-085835-16478383021206194166/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-16T08:58:37.035Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-16T08:58:37.035Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-16T08:58:48.995Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-16T08:58:59.255Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-16T08:58:59.255Z] GC before operation: completed in 106.811 ms, heap usage 53.000 MB -> 35.261 MB. [2025-12-16T08:59:45.075Z] ====== als (apache-spark) [default], iteration 0 completed (42924.005 ms) ====== [2025-12-16T08:59:45.075Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-16T08:59:45.075Z] GC before operation: completed in 316.653 ms, heap usage 184.456 MB -> 61.681 MB. [2025-12-16T09:00:04.335Z] ====== als (apache-spark) [default], iteration 1 completed (18696.589 ms) ====== [2025-12-16T09:00:04.335Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-16T09:00:04.335Z] GC before operation: completed in 243.459 ms, heap usage 178.946 MB -> 62.587 MB. [2025-12-16T09:00:23.639Z] ====== als (apache-spark) [default], iteration 2 completed (17246.788 ms) ====== [2025-12-16T09:00:23.639Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-16T09:00:23.639Z] GC before operation: completed in 269.900 ms, heap usage 315.694 MB -> 63.442 MB. [2025-12-16T09:00:40.002Z] ====== als (apache-spark) [default], iteration 3 completed (16589.580 ms) ====== [2025-12-16T09:00:40.002Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-16T09:00:40.002Z] GC before operation: completed in 262.330 ms, heap usage 310.799 MB -> 63.997 MB. [2025-12-16T09:00:56.468Z] ====== als (apache-spark) [default], iteration 4 completed (15376.845 ms) ====== [2025-12-16T09:00:56.468Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-16T09:00:56.468Z] GC before operation: completed in 226.514 ms, heap usage 262.787 MB -> 64.419 MB. [2025-12-16T09:01:12.965Z] ====== als (apache-spark) [default], iteration 5 completed (15416.839 ms) ====== [2025-12-16T09:01:12.965Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-16T09:01:12.965Z] GC before operation: completed in 243.599 ms, heap usage 117.434 MB -> 64.951 MB. [2025-12-16T09:01:29.314Z] ====== als (apache-spark) [default], iteration 6 completed (15414.550 ms) ====== [2025-12-16T09:01:29.314Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-16T09:01:29.314Z] GC before operation: completed in 314.491 ms, heap usage 452.441 MB -> 65.919 MB. [2025-12-16T09:01:48.359Z] ====== als (apache-spark) [default], iteration 7 completed (16757.639 ms) ====== [2025-12-16T09:01:48.359Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-16T09:01:48.359Z] GC before operation: completed in 238.571 ms, heap usage 281.788 MB -> 66.176 MB. [2025-12-16T09:02:04.220Z] ====== als (apache-spark) [default], iteration 8 completed (15515.939 ms) ====== [2025-12-16T09:02:04.220Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-16T09:02:04.220Z] GC before operation: completed in 283.877 ms, heap usage 222.719 MB -> 66.662 MB. [2025-12-16T09:02:20.727Z] ====== als (apache-spark) [default], iteration 9 completed (15567.295 ms) ====== [2025-12-16T09:02:20.727Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-16T09:02:20.727Z] GC before operation: completed in 283.787 ms, heap usage 208.123 MB -> 66.810 MB. [2025-12-16T09:02:37.934Z] ====== als (apache-spark) [default], iteration 10 completed (15599.726 ms) ====== [2025-12-16T09:02:37.934Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-16T09:02:37.934Z] GC before operation: completed in 261.481 ms, heap usage 209.391 MB -> 67.048 MB. [2025-12-16T09:02:51.973Z] ====== als (apache-spark) [default], iteration 11 completed (14652.922 ms) ====== [2025-12-16T09:02:51.973Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-16T09:02:51.973Z] GC before operation: completed in 274.875 ms, heap usage 1.068 GB -> 68.424 MB. [2025-12-16T09:03:08.245Z] ====== als (apache-spark) [default], iteration 12 completed (15231.362 ms) ====== [2025-12-16T09:03:08.245Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-16T09:03:08.245Z] GC before operation: completed in 256.806 ms, heap usage 541.277 MB -> 68.114 MB. [2025-12-16T09:03:22.539Z] ====== als (apache-spark) [default], iteration 13 completed (14293.376 ms) ====== [2025-12-16T09:03:22.540Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-16T09:03:23.530Z] GC before operation: completed in 245.083 ms, heap usage 440.658 MB -> 68.310 MB. [2025-12-16T09:03:37.335Z] ====== als (apache-spark) [default], iteration 14 completed (12648.748 ms) ====== [2025-12-16T09:03:37.335Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-16T09:03:37.335Z] GC before operation: completed in 211.189 ms, heap usage 492.544 MB -> 68.512 MB. [2025-12-16T09:03:49.251Z] ====== als (apache-spark) [default], iteration 15 completed (12683.010 ms) ====== [2025-12-16T09:03:49.251Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-16T09:03:50.285Z] GC before operation: completed in 233.463 ms, heap usage 405.032 MB -> 68.605 MB. [2025-12-16T09:04:12.364Z] ====== als (apache-spark) [default], iteration 16 completed (13140.858 ms) ====== [2025-12-16T09:04:12.364Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-16T09:04:12.364Z] GC before operation: completed in 217.924 ms, heap usage 163.601 MB -> 68.599 MB. [2025-12-16T09:04:16.689Z] ====== als (apache-spark) [default], iteration 17 completed (12061.298 ms) ====== [2025-12-16T09:04:16.689Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-16T09:04:16.689Z] GC before operation: completed in 207.186 ms, heap usage 440.706 MB -> 69.358 MB. [2025-12-16T09:04:28.609Z] ====== als (apache-spark) [default], iteration 18 completed (12130.394 ms) ====== [2025-12-16T09:04:28.609Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-16T09:04:29.597Z] GC before operation: completed in 180.402 ms, heap usage 285.131 MB -> 69.322 MB. [2025-12-16T09:04:43.078Z] ====== als (apache-spark) [default], iteration 19 completed (10780.099 ms) ====== [2025-12-16T09:04:43.078Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-16T09:04:43.078Z] GC before operation: completed in 158.214 ms, heap usage 473.820 MB -> 69.919 MB. [2025-12-16T09:04:52.271Z] ====== als (apache-spark) [default], iteration 20 completed (10798.619 ms) ====== [2025-12-16T09:04:52.271Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-16T09:04:52.271Z] GC before operation: completed in 162.030 ms, heap usage 549.650 MB -> 70.269 MB. [2025-12-16T09:05:10.122Z] ====== als (apache-spark) [default], iteration 21 completed (10757.757 ms) ====== [2025-12-16T09:05:10.122Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-16T09:05:10.122Z] GC before operation: completed in 179.018 ms, heap usage 170.925 MB -> 69.966 MB. [2025-12-16T09:05:14.618Z] ====== als (apache-spark) [default], iteration 22 completed (10609.083 ms) ====== [2025-12-16T09:05:14.618Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-16T09:05:14.618Z] GC before operation: completed in 160.607 ms, heap usage 208.976 MB -> 70.258 MB. [2025-12-16T09:05:26.279Z] ====== als (apache-spark) [default], iteration 23 completed (10735.389 ms) ====== [2025-12-16T09:05:26.279Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-16T09:05:26.279Z] GC before operation: completed in 164.336 ms, heap usage 491.100 MB -> 70.943 MB. [2025-12-16T09:05:36.441Z] ====== als (apache-spark) [default], iteration 24 completed (10756.368 ms) ====== [2025-12-16T09:05:36.441Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-16T09:05:37.408Z] GC before operation: completed in 186.159 ms, heap usage 224.298 MB -> 70.809 MB. [2025-12-16T09:05:48.202Z] ====== als (apache-spark) [default], iteration 25 completed (11112.711 ms) ====== [2025-12-16T09:05:48.202Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-16T09:05:49.187Z] GC before operation: completed in 218.861 ms, heap usage 495.723 MB -> 71.479 MB. [2025-12-16T09:06:00.849Z] ====== als (apache-spark) [default], iteration 26 completed (10701.394 ms) ====== [2025-12-16T09:06:00.849Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-16T09:06:00.849Z] GC before operation: completed in 179.969 ms, heap usage 236.559 MB -> 71.408 MB. [2025-12-16T09:06:11.152Z] ====== als (apache-spark) [default], iteration 27 completed (10619.251 ms) ====== [2025-12-16T09:06:11.152Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-16T09:06:11.152Z] GC before operation: completed in 173.968 ms, heap usage 560.090 MB -> 72.081 MB. [2025-12-16T09:06:22.907Z] ====== als (apache-spark) [default], iteration 28 completed (10678.732 ms) ====== [2025-12-16T09:06:22.907Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-16T09:06:22.907Z] GC before operation: completed in 165.609 ms, heap usage 385.497 MB -> 71.596 MB. [2025-12-16T09:06:34.641Z] ====== als (apache-spark) [default], iteration 29 completed (11684.078 ms) ====== [2025-12-16T09:06:35.602Z] ----------------------------------- [2025-12-16T09:06:35.602Z] renaissance-als_0_PASSED [2025-12-16T09:06:35.602Z] ----------------------------------- [2025-12-16T09:06:35.602Z] [2025-12-16T09:06:35.602Z] TEST TEARDOWN: [2025-12-16T09:06:35.602Z] Nothing to be done for teardown. [2025-12-16T09:06:35.602Z] renaissance-als_0 Finish Time: Tue Dec 16 09:06:35 2025 Epoch Time (ms): 1765875995486