[2025-10-17T18:15:12.630Z] Running test renaissance-als_0 ... [2025-10-17T18:15:12.630Z] =============================================== [2025-10-17T18:15:12.630Z] renaissance-als_0 Start Time: Fri Oct 17 18:15:12 2025 Epoch Time (ms): 1760724912469 [2025-10-17T18:15:12.630Z] variation: NoOptions [2025-10-17T18:15:12.630Z] JVM_OPTIONS: [2025-10-17T18:15:12.630Z] { \ [2025-10-17T18:15:12.630Z] echo ""; echo "TEST SETUP:"; \ [2025-10-17T18:15:12.630Z] echo "Nothing to be done for setup."; \ [2025-10-17T18:15:12.630Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17607249122968/renaissance-als_0"; \ [2025-10-17T18:15:12.630Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17607249122968/renaissance-als_0"; \ [2025-10-17T18:15:12.630Z] echo ""; echo "TESTING:"; \ [2025-10-17T18:15:12.630Z] "/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_17607249122968/renaissance-als_0"/als.json" als; \ [2025-10-17T18:15:12.630Z] 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_17607249122968/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-17T18:15:12.630Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-17T18:15:12.630Z] echo "Nothing to be done for teardown."; \ [2025-10-17T18:15:12.630Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17607249122968/TestTargetResult"; [2025-10-17T18:15:12.630Z] [2025-10-17T18:15:12.630Z] TEST SETUP: [2025-10-17T18:15:12.630Z] Nothing to be done for setup. [2025-10-17T18:15:12.630Z] [2025-10-17T18:15:12.630Z] TESTING: [2025-10-17T18:15:13.374Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-10-17T18:15:13.374Z] 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_17607249122968/renaissance-als_0/launcher-181512-13593190083050972422/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-10-17T18:15:13.374Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-10-17T18:15:13.374Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-10-17T18:15:17.712Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-10-17T18:15:21.564Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-10-17T18:15:21.565Z] GC before operation: completed in 39.893 ms, heap usage 53.933 MB -> 35.109 MB. [2025-10-17T18:15:43.443Z] ====== als (apache-spark) [default], iteration 0 completed (19763.686 ms) ====== [2025-10-17T18:15:43.443Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-10-17T18:15:43.443Z] GC before operation: completed in 87.163 ms, heap usage 275.800 MB -> 61.917 MB. [2025-10-17T18:15:50.161Z] ====== als (apache-spark) [default], iteration 1 completed (7926.859 ms) ====== [2025-10-17T18:15:50.161Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-10-17T18:15:50.161Z] GC before operation: completed in 81.833 ms, heap usage 259.453 MB -> 62.679 MB. [2025-10-17T18:15:56.956Z] ====== als (apache-spark) [default], iteration 2 completed (7030.929 ms) ====== [2025-10-17T18:15:56.956Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-10-17T18:15:56.956Z] GC before operation: completed in 97.765 ms, heap usage 351.877 MB -> 63.396 MB. [2025-10-17T18:16:05.076Z] ====== als (apache-spark) [default], iteration 3 completed (7086.246 ms) ====== [2025-10-17T18:16:05.076Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-10-17T18:16:05.076Z] GC before operation: completed in 82.166 ms, heap usage 358.374 MB -> 63.956 MB. [2025-10-17T18:16:11.795Z] ====== als (apache-spark) [default], iteration 4 completed (6799.636 ms) ====== [2025-10-17T18:16:11.795Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-10-17T18:16:11.795Z] GC before operation: completed in 82.986 ms, heap usage 300.633 MB -> 64.502 MB. [2025-10-17T18:16:18.524Z] ====== als (apache-spark) [default], iteration 5 completed (6850.806 ms) ====== [2025-10-17T18:16:18.524Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-10-17T18:16:18.524Z] GC before operation: completed in 81.991 ms, heap usage 192.489 MB -> 64.862 MB. [2025-10-17T18:16:25.244Z] ====== als (apache-spark) [default], iteration 6 completed (6711.863 ms) ====== [2025-10-17T18:16:25.244Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-10-17T18:16:25.244Z] GC before operation: completed in 85.964 ms, heap usage 479.467 MB -> 65.850 MB. [2025-10-17T18:16:33.503Z] ====== als (apache-spark) [default], iteration 7 completed (6638.337 ms) ====== [2025-10-17T18:16:33.503Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-10-17T18:16:33.503Z] GC before operation: completed in 129.760 ms, heap usage 192.983 MB -> 65.943 MB. [2025-10-17T18:16:40.227Z] ====== als (apache-spark) [default], iteration 8 completed (6674.908 ms) ====== [2025-10-17T18:16:40.227Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-10-17T18:16:40.227Z] GC before operation: completed in 91.228 ms, heap usage 317.221 MB -> 66.667 MB. [2025-10-17T18:16:46.961Z] ====== als (apache-spark) [default], iteration 9 completed (6641.389 ms) ====== [2025-10-17T18:16:46.961Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-10-17T18:16:46.961Z] GC before operation: completed in 96.377 ms, heap usage 338.844 MB -> 66.983 MB. [2025-10-17T18:16:53.686Z] ====== als (apache-spark) [default], iteration 10 completed (6454.883 ms) ====== [2025-10-17T18:16:53.686Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-10-17T18:16:53.686Z] GC before operation: completed in 116.005 ms, heap usage 197.364 MB -> 66.999 MB. [2025-10-17T18:17:00.433Z] ====== als (apache-spark) [default], iteration 11 completed (6297.614 ms) ====== [2025-10-17T18:17:00.433Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-10-17T18:17:00.433Z] GC before operation: completed in 84.293 ms, heap usage 228.509 MB -> 67.472 MB. [2025-10-17T18:17:07.157Z] ====== als (apache-spark) [default], iteration 12 completed (6502.840 ms) ====== [2025-10-17T18:17:07.157Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-10-17T18:17:07.157Z] GC before operation: completed in 88.256 ms, heap usage 160.227 MB -> 67.523 MB. [2025-10-17T18:17:13.897Z] ====== als (apache-spark) [default], iteration 13 completed (6484.152 ms) ====== [2025-10-17T18:17:13.897Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-10-17T18:17:13.897Z] GC before operation: completed in 87.894 ms, heap usage 339.278 MB -> 68.063 MB. [2025-10-17T18:17:20.629Z] ====== als (apache-spark) [default], iteration 14 completed (6499.030 ms) ====== [2025-10-17T18:17:20.629Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-10-17T18:17:20.629Z] GC before operation: completed in 92.513 ms, heap usage 433.181 MB -> 68.536 MB. [2025-10-17T18:17:26.300Z] ====== als (apache-spark) [default], iteration 15 completed (6357.778 ms) ====== [2025-10-17T18:17:26.300Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-10-17T18:17:26.300Z] GC before operation: completed in 93.304 ms, heap usage 241.673 MB -> 68.470 MB. [2025-10-17T18:17:33.006Z] ====== als (apache-spark) [default], iteration 16 completed (6277.988 ms) ====== [2025-10-17T18:17:33.006Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-10-17T18:17:33.006Z] GC before operation: completed in 92.555 ms, heap usage 456.685 MB -> 69.071 MB. [2025-10-17T18:17:39.726Z] ====== als (apache-spark) [default], iteration 17 completed (6385.594 ms) ====== [2025-10-17T18:17:39.726Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-10-17T18:17:39.726Z] GC before operation: completed in 84.284 ms, heap usage 475.064 MB -> 69.341 MB. [2025-10-17T18:17:46.454Z] ====== als (apache-spark) [default], iteration 18 completed (6395.760 ms) ====== [2025-10-17T18:17:46.454Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-10-17T18:17:46.454Z] GC before operation: completed in 94.274 ms, heap usage 235.116 MB -> 69.287 MB. [2025-10-17T18:17:53.196Z] ====== als (apache-spark) [default], iteration 19 completed (6314.426 ms) ====== [2025-10-17T18:17:53.197Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-10-17T18:17:53.197Z] GC before operation: completed in 89.744 ms, heap usage 228.833 MB -> 69.642 MB. [2025-10-17T18:18:00.016Z] ====== als (apache-spark) [default], iteration 20 completed (6526.012 ms) ====== [2025-10-17T18:18:00.016Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-10-17T18:18:00.016Z] GC before operation: completed in 92.230 ms, heap usage 199.024 MB -> 69.658 MB. [2025-10-17T18:18:06.764Z] ====== als (apache-spark) [default], iteration 21 completed (6290.246 ms) ====== [2025-10-17T18:18:06.764Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-10-17T18:18:06.764Z] GC before operation: completed in 94.995 ms, heap usage 459.508 MB -> 70.335 MB. [2025-10-17T18:18:13.500Z] ====== als (apache-spark) [default], iteration 22 completed (6413.931 ms) ====== [2025-10-17T18:18:13.500Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-10-17T18:18:13.500Z] GC before operation: completed in 93.484 ms, heap usage 360.484 MB -> 70.365 MB. [2025-10-17T18:18:19.477Z] ====== als (apache-spark) [default], iteration 23 completed (6346.157 ms) ====== [2025-10-17T18:18:19.477Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-10-17T18:18:19.477Z] GC before operation: completed in 93.037 ms, heap usage 520.939 MB -> 70.899 MB. [2025-10-17T18:18:26.203Z] ====== als (apache-spark) [default], iteration 24 completed (6339.206 ms) ====== [2025-10-17T18:18:26.203Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-10-17T18:18:26.203Z] GC before operation: completed in 97.171 ms, heap usage 574.305 MB -> 71.215 MB. [2025-10-17T18:18:32.982Z] ====== als (apache-spark) [default], iteration 25 completed (6479.846 ms) ====== [2025-10-17T18:18:32.982Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-10-17T18:18:32.982Z] GC before operation: completed in 96.463 ms, heap usage 491.234 MB -> 71.366 MB. [2025-10-17T18:18:39.704Z] ====== als (apache-spark) [default], iteration 26 completed (6572.702 ms) ====== [2025-10-17T18:18:39.704Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-10-17T18:18:39.704Z] GC before operation: completed in 86.225 ms, heap usage 108.659 MB -> 71.214 MB. [2025-10-17T18:18:46.434Z] ====== als (apache-spark) [default], iteration 27 completed (6337.743 ms) ====== [2025-10-17T18:18:46.434Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-10-17T18:18:46.434Z] GC before operation: completed in 88.394 ms, heap usage 392.498 MB -> 71.715 MB. [2025-10-17T18:18:53.183Z] ====== als (apache-spark) [default], iteration 28 completed (6274.610 ms) ====== [2025-10-17T18:18:53.183Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-10-17T18:18:53.183Z] GC before operation: completed in 77.554 ms, heap usage 133.701 MB -> 71.793 MB. [2025-10-17T18:18:58.768Z] ====== als (apache-spark) [default], iteration 29 completed (6248.161 ms) ====== [2025-10-17T18:18:59.513Z] ----------------------------------- [2025-10-17T18:18:59.513Z] renaissance-als_0_PASSED [2025-10-17T18:18:59.513Z] ----------------------------------- [2025-10-17T18:18:59.513Z] [2025-10-17T18:18:59.513Z] TEST TEARDOWN: [2025-10-17T18:18:59.513Z] Nothing to be done for teardown. [2025-10-17T18:18:59.513Z] renaissance-als_0 Finish Time: Fri Oct 17 18:18:58 2025 Epoch Time (ms): 1760725138810