[2025-06-18T19:56:04.874Z] Running test renaissance-als_0 ... [2025-06-18T19:56:04.874Z] =============================================== [2025-06-18T19:56:04.874Z] renaissance-als_0 Start Time: Wed Jun 18 15:56:04 2025 Epoch Time (ms): 1750276564507 [2025-06-18T19:56:04.874Z] variation: NoOptions [2025-06-18T19:56:04.874Z] JVM_OPTIONS: [2025-06-18T19:56:04.874Z] { \ [2025-06-18T19:56:04.874Z] echo ""; echo "TEST SETUP:"; \ [2025-06-18T19:56:04.874Z] echo "Nothing to be done for setup."; \ [2025-06-18T19:56:04.874Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17502765649868/renaissance-als_0"; \ [2025-06-18T19:56:04.874Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17502765649868/renaissance-als_0"; \ [2025-06-18T19:56:04.874Z] echo ""; echo "TESTING:"; \ [2025-06-18T19:56:04.874Z] "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/jdkbinary/j2sdk-image/Contents/Home/bin/..//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 "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17502765649868/renaissance-als_0"/als.json" als; \ [2025-06-18T19:56:04.874Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17502765649868/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-18T19:56:04.874Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-18T19:56:04.874Z] echo "Nothing to be done for teardown."; \ [2025-06-18T19:56:04.874Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17502765649868/TestTargetResult"; [2025-06-18T19:56:04.874Z] [2025-06-18T19:56:04.874Z] TEST SETUP: [2025-06-18T19:56:04.874Z] Nothing to be done for setup. [2025-06-18T19:56:04.874Z] [2025-06-18T19:56:04.874Z] TESTING: [2025-06-18T19:56:08.913Z] NOTE: 'als' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-06-18T19:56:10.757Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-18T19:56:10.757Z] GC before operation: completed in 19.098 ms, heap usage 64.000 MB -> 35.083 MB. [2025-06-18T19:56:18.389Z] ====== als (apache-spark) [default], iteration 0 completed (7569.005 ms) ====== [2025-06-18T19:56:18.389Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-18T19:56:18.389Z] GC before operation: completed in 43.498 ms, heap usage 154.552 MB -> 61.662 MB. [2025-06-18T19:56:22.429Z] ====== als (apache-spark) [default], iteration 1 completed (3481.474 ms) ====== [2025-06-18T19:56:22.429Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-18T19:56:22.429Z] GC before operation: completed in 38.196 ms, heap usage 442.784 MB -> 63.073 MB. [2025-06-18T19:56:25.651Z] ====== als (apache-spark) [default], iteration 2 completed (3332.079 ms) ====== [2025-06-18T19:56:25.651Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-18T19:56:25.651Z] GC before operation: completed in 35.098 ms, heap usage 308.732 MB -> 63.392 MB. [2025-06-18T19:56:29.690Z] ====== als (apache-spark) [default], iteration 3 completed (3630.168 ms) ====== [2025-06-18T19:56:29.690Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-18T19:56:29.690Z] GC before operation: completed in 48.348 ms, heap usage 351.546 MB -> 64.042 MB. [2025-06-18T19:56:32.997Z] ====== als (apache-spark) [default], iteration 4 completed (3690.243 ms) ====== [2025-06-18T19:56:32.997Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-18T19:56:32.997Z] GC before operation: completed in 37.016 ms, heap usage 102.189 MB -> 64.296 MB. [2025-06-18T19:56:36.232Z] ====== als (apache-spark) [default], iteration 5 completed (3338.030 ms) ====== [2025-06-18T19:56:36.232Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-18T19:56:36.595Z] GC before operation: completed in 43.078 ms, heap usage 102.775 MB -> 64.881 MB. [2025-06-18T19:56:40.751Z] ====== als (apache-spark) [default], iteration 6 completed (3747.232 ms) ====== [2025-06-18T19:56:40.751Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-18T19:56:40.751Z] GC before operation: completed in 45.588 ms, heap usage 243.643 MB -> 65.573 MB. [2025-06-18T19:56:44.869Z] ====== als (apache-spark) [default], iteration 7 completed (4015.360 ms) ====== [2025-06-18T19:56:44.869Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-18T19:56:44.869Z] GC before operation: completed in 52.102 ms, heap usage 183.997 MB -> 66.006 MB. [2025-06-18T19:56:48.967Z] ====== als (apache-spark) [default], iteration 8 completed (3638.177 ms) ====== [2025-06-18T19:56:48.967Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-18T19:56:48.967Z] GC before operation: completed in 48.108 ms, heap usage 108.092 MB -> 66.534 MB. [2025-06-18T19:56:52.161Z] ====== als (apache-spark) [default], iteration 9 completed (3499.357 ms) ====== [2025-06-18T19:56:52.161Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-18T19:56:52.161Z] GC before operation: completed in 45.721 ms, heap usage 270.631 MB -> 66.985 MB. [2025-06-18T19:56:56.266Z] ====== als (apache-spark) [default], iteration 10 completed (3718.820 ms) ====== [2025-06-18T19:56:56.266Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-18T19:56:56.266Z] GC before operation: completed in 50.986 ms, heap usage 210.295 MB -> 67.098 MB. [2025-06-18T19:57:00.366Z] ====== als (apache-spark) [default], iteration 11 completed (3979.827 ms) ====== [2025-06-18T19:57:00.366Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-18T19:57:00.366Z] GC before operation: completed in 43.787 ms, heap usage 467.430 MB -> 67.739 MB. [2025-06-18T19:57:03.839Z] ====== als (apache-spark) [default], iteration 12 completed (3663.408 ms) ====== [2025-06-18T19:57:03.839Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-18T19:57:03.839Z] GC before operation: completed in 41.417 ms, heap usage 282.313 MB -> 67.672 MB. [2025-06-18T19:57:07.881Z] ====== als (apache-spark) [default], iteration 13 completed (3677.239 ms) ====== [2025-06-18T19:57:07.881Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-18T19:57:07.881Z] GC before operation: completed in 40.151 ms, heap usage 452.693 MB -> 68.288 MB. [2025-06-18T19:57:11.975Z] ====== als (apache-spark) [default], iteration 14 completed (3794.769 ms) ====== [2025-06-18T19:57:11.975Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-18T19:57:11.975Z] GC before operation: completed in 46.990 ms, heap usage 261.859 MB -> 68.163 MB. [2025-06-18T19:57:15.200Z] ====== als (apache-spark) [default], iteration 15 completed (3454.175 ms) ====== [2025-06-18T19:57:15.200Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-18T19:57:15.200Z] GC before operation: completed in 73.319 ms, heap usage 221.743 MB -> 68.463 MB. [2025-06-18T19:57:19.333Z] ====== als (apache-spark) [default], iteration 16 completed (3769.743 ms) ====== [2025-06-18T19:57:19.333Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-18T19:57:19.333Z] GC before operation: completed in 77.760 ms, heap usage 111.103 MB -> 68.623 MB. [2025-06-18T19:57:23.441Z] ====== als (apache-spark) [default], iteration 17 completed (3788.221 ms) ====== [2025-06-18T19:57:23.441Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-18T19:57:23.441Z] GC before operation: completed in 72.151 ms, heap usage 319.849 MB -> 69.132 MB. [2025-06-18T19:57:26.567Z] ====== als (apache-spark) [default], iteration 18 completed (3513.360 ms) ====== [2025-06-18T19:57:26.567Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-18T19:57:26.567Z] GC before operation: completed in 40.985 ms, heap usage 452.944 MB -> 69.559 MB. [2025-06-18T19:57:30.618Z] ====== als (apache-spark) [default], iteration 19 completed (3703.803 ms) ====== [2025-06-18T19:57:30.618Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-18T19:57:30.618Z] GC before operation: completed in 55.520 ms, heap usage 109.241 MB -> 69.388 MB. [2025-06-18T19:57:34.688Z] ====== als (apache-spark) [default], iteration 20 completed (3588.383 ms) ====== [2025-06-18T19:57:34.688Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-18T19:57:34.688Z] GC before operation: completed in 47.411 ms, heap usage 243.202 MB -> 70.238 MB. [2025-06-18T19:57:37.926Z] ====== als (apache-spark) [default], iteration 21 completed (3529.746 ms) ====== [2025-06-18T19:57:37.926Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-18T19:57:37.926Z] GC before operation: completed in 51.400 ms, heap usage 316.522 MB -> 70.091 MB. [2025-06-18T19:57:41.962Z] ====== als (apache-spark) [default], iteration 22 completed (3637.020 ms) ====== [2025-06-18T19:57:41.962Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-18T19:57:41.962Z] GC before operation: completed in 53.212 ms, heap usage 198.698 MB -> 70.186 MB. [2025-06-18T19:57:46.079Z] ====== als (apache-spark) [default], iteration 23 completed (3676.394 ms) ====== [2025-06-18T19:57:46.079Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-18T19:57:46.079Z] GC before operation: completed in 65.591 ms, heap usage 211.413 MB -> 70.530 MB. [2025-06-18T19:57:49.297Z] ====== als (apache-spark) [default], iteration 24 completed (3658.680 ms) ====== [2025-06-18T19:57:49.297Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-18T19:57:49.297Z] GC before operation: completed in 39.670 ms, heap usage 232.332 MB -> 70.840 MB. [2025-06-18T19:57:53.359Z] ====== als (apache-spark) [default], iteration 25 completed (3547.130 ms) ====== [2025-06-18T19:57:53.359Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-18T19:57:53.359Z] GC before operation: completed in 60.758 ms, heap usage 506.104 MB -> 71.470 MB. [2025-06-18T19:57:56.559Z] ====== als (apache-spark) [default], iteration 26 completed (3453.308 ms) ====== [2025-06-18T19:57:56.559Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-18T19:57:56.559Z] GC before operation: completed in 61.289 ms, heap usage 329.428 MB -> 71.505 MB. [2025-06-18T19:58:00.621Z] ====== als (apache-spark) [default], iteration 27 completed (3724.683 ms) ====== [2025-06-18T19:58:00.621Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-18T19:58:00.621Z] GC before operation: completed in 88.894 ms, heap usage 230.119 MB -> 71.616 MB. [2025-06-18T19:58:04.763Z] ====== als (apache-spark) [default], iteration 28 completed (3727.104 ms) ====== [2025-06-18T19:58:04.763Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-18T19:58:04.763Z] GC before operation: completed in 53.566 ms, heap usage 241.749 MB -> 71.903 MB. [2025-06-18T19:58:07.957Z] ====== als (apache-spark) [default], iteration 29 completed (3174.604 ms) ====== [2025-06-18T19:58:08.311Z] ----------------------------------- [2025-06-18T19:58:08.311Z] renaissance-als_0_PASSED [2025-06-18T19:58:08.311Z] ----------------------------------- [2025-06-18T19:58:08.311Z] [2025-06-18T19:58:08.311Z] TEST TEARDOWN: [2025-06-18T19:58:08.311Z] Nothing to be done for teardown. [2025-06-18T19:58:08.311Z] renaissance-als_0 Finish Time: Wed Jun 18 15:58:08 2025 Epoch Time (ms): 1750276688005