[2025-10-22T18:21:08.347Z] Running test renaissance-als_0 ... [2025-10-22T18:21:08.347Z] =============================================== [2025-10-22T18:21:08.347Z] renaissance-als_0 Start Time: Wed Oct 22 18:21:08 2025 Epoch Time (ms): 1761157268009 [2025-10-22T18:21:08.347Z] variation: NoOptions [2025-10-22T18:21:08.347Z] JVM_OPTIONS: [2025-10-22T18:21:08.347Z] { \ [2025-10-22T18:21:08.347Z] echo ""; echo "TEST SETUP:"; \ [2025-10-22T18:21:08.347Z] echo "Nothing to be done for setup."; \ [2025-10-22T18:21:08.347Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17611570878187/renaissance-als_0"; \ [2025-10-22T18:21:08.347Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17611570878187/renaissance-als_0"; \ [2025-10-22T18:21:08.347Z] echo ""; echo "TESTING:"; \ [2025-10-22T18:21:08.347Z] "/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_17611570878187/renaissance-als_0"/als.json" als; \ [2025-10-22T18:21:08.347Z] 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_17611570878187/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-22T18:21:08.347Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-22T18:21:08.347Z] echo "Nothing to be done for teardown."; \ [2025-10-22T18:21:08.347Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17611570878187/TestTargetResult"; [2025-10-22T18:21:08.347Z] [2025-10-22T18:21:08.347Z] TEST SETUP: [2025-10-22T18:21:08.347Z] Nothing to be done for setup. [2025-10-22T18:21:08.347Z] [2025-10-22T18:21:08.347Z] TESTING: [2025-10-22T18:21:09.115Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-10-22T18:21:09.115Z] 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_17611570878187/renaissance-als_0/launcher-182108-5424011856521886477/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-10-22T18:21:09.115Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-10-22T18:21:09.115Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-10-22T18:21:16.720Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-10-22T18:21:21.838Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-10-22T18:21:21.838Z] GC before operation: completed in 56.177 ms, heap usage 51.319 MB -> 35.174 MB. [2025-10-22T18:21:45.976Z] ====== als (apache-spark) [default], iteration 0 completed (23065.112 ms) ====== [2025-10-22T18:21:45.976Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-10-22T18:21:45.976Z] GC before operation: completed in 135.459 ms, heap usage 182.592 MB -> 61.580 MB. [2025-10-22T18:21:55.648Z] ====== als (apache-spark) [default], iteration 1 completed (9753.616 ms) ====== [2025-10-22T18:21:55.648Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-10-22T18:21:55.648Z] GC before operation: completed in 93.828 ms, heap usage 462.912 MB -> 62.918 MB. [2025-10-22T18:22:05.090Z] ====== als (apache-spark) [default], iteration 2 completed (9145.993 ms) ====== [2025-10-22T18:22:05.090Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-10-22T18:22:05.496Z] GC before operation: completed in 96.554 ms, heap usage 538.855 MB -> 63.576 MB. [2025-10-22T18:22:14.946Z] ====== als (apache-spark) [default], iteration 3 completed (8850.045 ms) ====== [2025-10-22T18:22:14.946Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-10-22T18:22:14.946Z] GC before operation: completed in 113.499 ms, heap usage 543.840 MB -> 64.261 MB. [2025-10-22T18:22:24.376Z] ====== als (apache-spark) [default], iteration 4 completed (8819.252 ms) ====== [2025-10-22T18:22:24.376Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-10-22T18:22:24.376Z] GC before operation: completed in 101.291 ms, heap usage 180.643 MB -> 64.245 MB. [2025-10-22T18:22:33.986Z] ====== als (apache-spark) [default], iteration 5 completed (8410.697 ms) ====== [2025-10-22T18:22:33.986Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-10-22T18:22:33.986Z] GC before operation: completed in 98.056 ms, heap usage 179.277 MB -> 64.816 MB. [2025-10-22T18:22:41.706Z] ====== als (apache-spark) [default], iteration 6 completed (8452.222 ms) ====== [2025-10-22T18:22:41.707Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-10-22T18:22:41.707Z] GC before operation: completed in 102.696 ms, heap usage 440.454 MB -> 65.831 MB. [2025-10-22T18:22:51.186Z] ====== als (apache-spark) [default], iteration 7 completed (8398.591 ms) ====== [2025-10-22T18:22:51.186Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-10-22T18:22:51.186Z] GC before operation: completed in 97.984 ms, heap usage 398.602 MB -> 66.136 MB. [2025-10-22T18:22:59.222Z] ====== als (apache-spark) [default], iteration 8 completed (8446.881 ms) ====== [2025-10-22T18:22:59.222Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-10-22T18:22:59.222Z] GC before operation: completed in 105.293 ms, heap usage 215.590 MB -> 66.551 MB. [2025-10-22T18:23:08.836Z] ====== als (apache-spark) [default], iteration 9 completed (8206.674 ms) ====== [2025-10-22T18:23:08.836Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-10-22T18:23:08.836Z] GC before operation: completed in 106.757 ms, heap usage 493.234 MB -> 67.045 MB. [2025-10-22T18:23:16.656Z] ====== als (apache-spark) [default], iteration 10 completed (8239.378 ms) ====== [2025-10-22T18:23:16.656Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-10-22T18:23:16.656Z] GC before operation: completed in 101.807 ms, heap usage 207.165 MB -> 66.950 MB. [2025-10-22T18:23:26.151Z] ====== als (apache-spark) [default], iteration 11 completed (8230.630 ms) ====== [2025-10-22T18:23:26.151Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-10-22T18:23:26.151Z] GC before operation: completed in 106.228 ms, heap usage 198.557 MB -> 67.196 MB. [2025-10-22T18:23:33.956Z] ====== als (apache-spark) [default], iteration 12 completed (8323.864 ms) ====== [2025-10-22T18:23:33.956Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-10-22T18:23:33.956Z] GC before operation: completed in 106.700 ms, heap usage 224.827 MB -> 67.599 MB. [2025-10-22T18:23:43.273Z] ====== als (apache-spark) [default], iteration 13 completed (8392.791 ms) ====== [2025-10-22T18:23:43.273Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-10-22T18:23:43.273Z] GC before operation: completed in 112.282 ms, heap usage 487.410 MB -> 68.204 MB. [2025-10-22T18:23:51.343Z] ====== als (apache-spark) [default], iteration 14 completed (8176.986 ms) ====== [2025-10-22T18:23:51.343Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-10-22T18:23:51.343Z] GC before operation: completed in 110.732 ms, heap usage 386.377 MB -> 68.291 MB. [2025-10-22T18:24:00.807Z] ====== als (apache-spark) [default], iteration 15 completed (8120.672 ms) ====== [2025-10-22T18:24:00.807Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-10-22T18:24:00.807Z] GC before operation: completed in 112.074 ms, heap usage 554.798 MB -> 68.796 MB. [2025-10-22T18:24:08.716Z] ====== als (apache-spark) [default], iteration 16 completed (8125.834 ms) ====== [2025-10-22T18:24:08.716Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-10-22T18:24:08.716Z] GC before operation: completed in 125.265 ms, heap usage 398.970 MB -> 68.717 MB. [2025-10-22T18:24:16.496Z] ====== als (apache-spark) [default], iteration 17 completed (8180.791 ms) ====== [2025-10-22T18:24:16.496Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-10-22T18:24:16.496Z] GC before operation: completed in 109.596 ms, heap usage 191.102 MB -> 68.747 MB. [2025-10-22T18:24:27.893Z] ====== als (apache-spark) [default], iteration 18 completed (8143.880 ms) ====== [2025-10-22T18:24:27.893Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-10-22T18:24:27.893Z] GC before operation: completed in 109.206 ms, heap usage 284.702 MB -> 69.066 MB. [2025-10-22T18:24:34.316Z] ====== als (apache-spark) [default], iteration 19 completed (8393.074 ms) ====== [2025-10-22T18:24:34.316Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-10-22T18:24:34.316Z] GC before operation: completed in 110.774 ms, heap usage 279.911 MB -> 69.354 MB. [2025-10-22T18:24:42.146Z] ====== als (apache-spark) [default], iteration 20 completed (8092.229 ms) ====== [2025-10-22T18:24:42.146Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-10-22T18:24:42.146Z] GC before operation: completed in 111.628 ms, heap usage 291.777 MB -> 69.714 MB. [2025-10-22T18:24:51.545Z] ====== als (apache-spark) [default], iteration 21 completed (8304.149 ms) ====== [2025-10-22T18:24:51.546Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-10-22T18:24:51.546Z] GC before operation: completed in 112.047 ms, heap usage 394.920 MB -> 69.954 MB. [2025-10-22T18:24:59.277Z] ====== als (apache-spark) [default], iteration 22 completed (8141.331 ms) ====== [2025-10-22T18:24:59.277Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-10-22T18:24:59.277Z] GC before operation: completed in 115.013 ms, heap usage 210.017 MB -> 69.948 MB. [2025-10-22T18:25:08.075Z] ====== als (apache-spark) [default], iteration 23 completed (8255.990 ms) ====== [2025-10-22T18:25:08.075Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-10-22T18:25:08.075Z] GC before operation: completed in 112.961 ms, heap usage 339.329 MB -> 70.462 MB. [2025-10-22T18:25:17.524Z] ====== als (apache-spark) [default], iteration 24 completed (8380.638 ms) ====== [2025-10-22T18:25:17.524Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-10-22T18:25:17.524Z] GC before operation: completed in 124.017 ms, heap usage 490.818 MB -> 70.947 MB. [2025-10-22T18:25:25.371Z] ====== als (apache-spark) [default], iteration 25 completed (7965.143 ms) ====== [2025-10-22T18:25:25.371Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-10-22T18:25:25.371Z] GC before operation: completed in 108.342 ms, heap usage 207.097 MB -> 70.928 MB. [2025-10-22T18:25:34.707Z] ====== als (apache-spark) [default], iteration 26 completed (8099.933 ms) ====== [2025-10-22T18:25:34.707Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-10-22T18:25:34.707Z] GC before operation: completed in 99.068 ms, heap usage 453.080 MB -> 71.515 MB. [2025-10-22T18:25:42.536Z] ====== als (apache-spark) [default], iteration 27 completed (8038.778 ms) ====== [2025-10-22T18:25:42.536Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-10-22T18:25:42.536Z] GC before operation: completed in 96.312 ms, heap usage 240.250 MB -> 71.500 MB. [2025-10-22T18:25:50.376Z] ====== als (apache-spark) [default], iteration 28 completed (8072.489 ms) ====== [2025-10-22T18:25:50.376Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-10-22T18:25:50.376Z] GC before operation: completed in 91.098 ms, heap usage 324.636 MB -> 71.854 MB. [2025-10-22T18:25:59.792Z] ====== als (apache-spark) [default], iteration 29 completed (8116.869 ms) ====== [2025-10-22T18:25:59.792Z] ----------------------------------- [2025-10-22T18:25:59.792Z] renaissance-als_0_PASSED [2025-10-22T18:25:59.792Z] ----------------------------------- [2025-10-22T18:25:59.792Z] [2025-10-22T18:25:59.792Z] TEST TEARDOWN: [2025-10-22T18:25:59.792Z] Nothing to be done for teardown. [2025-10-22T18:25:59.792Z] renaissance-als_0 Finish Time: Wed Oct 22 18:25:58 2025 Epoch Time (ms): 1761157558927