[2025-11-12T22:45:44.374Z] Running test renaissance-als_0 ... [2025-11-12T22:45:44.374Z] =============================================== [2025-11-12T22:45:44.374Z] renaissance-als_0 Start Time: Wed Nov 12 17:45:44 2025 Epoch Time (ms): 1762987544054 [2025-11-12T22:45:44.374Z] variation: NoOptions [2025-11-12T22:45:44.374Z] JVM_OPTIONS: [2025-11-12T22:45:44.374Z] { \ [2025-11-12T22:45:44.374Z] echo ""; echo "TEST SETUP:"; \ [2025-11-12T22:45:44.374Z] echo "Nothing to be done for setup."; \ [2025-11-12T22:45:44.374Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17629875435339/renaissance-als_0"; \ [2025-11-12T22:45:44.374Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17629875435339/renaissance-als_0"; \ [2025-11-12T22:45:44.374Z] echo ""; echo "TESTING:"; \ [2025-11-12T22:45:44.374Z] "/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_17629875435339/renaissance-als_0"/als.json" als; \ [2025-11-12T22:45:44.374Z] 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_17629875435339/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-12T22:45:44.374Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-12T22:45:44.374Z] echo "Nothing to be done for teardown."; \ [2025-11-12T22:45:44.374Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17629875435339/TestTargetResult"; [2025-11-12T22:45:44.374Z] [2025-11-12T22:45:44.374Z] TEST SETUP: [2025-11-12T22:45:44.374Z] Nothing to be done for setup. [2025-11-12T22:45:44.374Z] [2025-11-12T22:45:44.374Z] TESTING: [2025-11-12T22:45:47.469Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-12T22:45:49.269Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-12T22:45:49.269Z] GC before operation: completed in 23.362 ms, heap usage 58.908 MB -> 35.338 MB. [2025-11-12T22:45:55.443Z] ====== als (apache-spark) [default], iteration 0 completed (6278.995 ms) ====== [2025-11-12T22:45:55.443Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-12T22:45:55.443Z] GC before operation: completed in 45.431 ms, heap usage 217.973 MB -> 62.076 MB. [2025-11-12T22:45:58.614Z] ====== als (apache-spark) [default], iteration 1 completed (2893.148 ms) ====== [2025-11-12T22:45:58.614Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-12T22:45:58.614Z] GC before operation: completed in 31.843 ms, heap usage 301.039 MB -> 63.033 MB. [2025-11-12T22:46:01.760Z] ====== als (apache-spark) [default], iteration 2 completed (2706.983 ms) ====== [2025-11-12T22:46:01.760Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-12T22:46:01.760Z] GC before operation: completed in 45.087 ms, heap usage 366.607 MB -> 63.873 MB. [2025-11-12T22:46:04.220Z] ====== als (apache-spark) [default], iteration 3 completed (2647.369 ms) ====== [2025-11-12T22:46:04.220Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-12T22:46:04.220Z] GC before operation: completed in 41.058 ms, heap usage 360.051 MB -> 64.380 MB. [2025-11-12T22:46:06.661Z] ====== als (apache-spark) [default], iteration 4 completed (2490.827 ms) ====== [2025-11-12T22:46:06.661Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-12T22:46:06.661Z] GC before operation: completed in 45.289 ms, heap usage 177.928 MB -> 64.743 MB. [2025-11-12T22:46:09.795Z] ====== als (apache-spark) [default], iteration 5 completed (2632.458 ms) ====== [2025-11-12T22:46:09.795Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-12T22:46:09.795Z] GC before operation: completed in 47.957 ms, heap usage 204.820 MB -> 65.217 MB. [2025-11-12T22:46:12.190Z] ====== als (apache-spark) [default], iteration 6 completed (2563.760 ms) ====== [2025-11-12T22:46:12.190Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-12T22:46:12.190Z] GC before operation: completed in 46.413 ms, heap usage 114.819 MB -> 65.785 MB. [2025-11-12T22:46:14.589Z] ====== als (apache-spark) [default], iteration 7 completed (2468.811 ms) ====== [2025-11-12T22:46:14.589Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-12T22:46:14.589Z] GC before operation: completed in 46.947 ms, heap usage 196.218 MB -> 66.371 MB. [2025-11-12T22:46:17.797Z] ====== als (apache-spark) [default], iteration 8 completed (2581.602 ms) ====== [2025-11-12T22:46:17.797Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-12T22:46:17.797Z] GC before operation: completed in 56.806 ms, heap usage 103.383 MB -> 66.604 MB. [2025-11-12T22:46:20.237Z] ====== als (apache-spark) [default], iteration 9 completed (2495.804 ms) ====== [2025-11-12T22:46:20.237Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-12T22:46:20.237Z] GC before operation: completed in 56.996 ms, heap usage 244.137 MB -> 67.346 MB. [2025-11-12T22:46:22.677Z] ====== als (apache-spark) [default], iteration 10 completed (2598.609 ms) ====== [2025-11-12T22:46:22.677Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-12T22:46:22.677Z] GC before operation: completed in 50.403 ms, heap usage 258.835 MB -> 67.574 MB. [2025-11-12T22:46:25.184Z] ====== als (apache-spark) [default], iteration 11 completed (2392.428 ms) ====== [2025-11-12T22:46:25.184Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-12T22:46:25.184Z] GC before operation: completed in 37.391 ms, heap usage 182.633 MB -> 67.725 MB. [2025-11-12T22:46:27.649Z] ====== als (apache-spark) [default], iteration 12 completed (2349.112 ms) ====== [2025-11-12T22:46:27.649Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-12T22:46:27.649Z] GC before operation: completed in 41.480 ms, heap usage 266.386 MB -> 68.223 MB. [2025-11-12T22:46:30.047Z] ====== als (apache-spark) [default], iteration 13 completed (2400.710 ms) ====== [2025-11-12T22:46:30.047Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-12T22:46:30.405Z] GC before operation: completed in 38.783 ms, heap usage 434.287 MB -> 68.633 MB. [2025-11-12T22:46:32.833Z] ====== als (apache-spark) [default], iteration 14 completed (2346.680 ms) ====== [2025-11-12T22:46:32.833Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-12T22:46:32.833Z] GC before operation: completed in 34.719 ms, heap usage 256.762 MB -> 68.701 MB. [2025-11-12T22:46:35.239Z] ====== als (apache-spark) [default], iteration 15 completed (2517.455 ms) ====== [2025-11-12T22:46:35.239Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-12T22:46:35.239Z] GC before operation: completed in 30.600 ms, heap usage 95.819 MB -> 68.818 MB. [2025-11-12T22:46:37.654Z] ====== als (apache-spark) [default], iteration 16 completed (2371.255 ms) ====== [2025-11-12T22:46:37.654Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-12T22:46:37.654Z] GC before operation: completed in 34.684 ms, heap usage 430.741 MB -> 69.252 MB. [2025-11-12T22:46:40.058Z] ====== als (apache-spark) [default], iteration 17 completed (2377.545 ms) ====== [2025-11-12T22:46:40.058Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-12T22:46:40.058Z] GC before operation: completed in 33.361 ms, heap usage 270.926 MB -> 69.325 MB. [2025-11-12T22:46:43.183Z] ====== als (apache-spark) [default], iteration 18 completed (2420.888 ms) ====== [2025-11-12T22:46:43.183Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-12T22:46:43.183Z] GC before operation: completed in 36.418 ms, heap usage 337.373 MB -> 69.765 MB. [2025-11-12T22:46:44.994Z] ====== als (apache-spark) [default], iteration 19 completed (2386.000 ms) ====== [2025-11-12T22:46:44.994Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-12T22:46:45.361Z] GC before operation: completed in 33.428 ms, heap usage 534.264 MB -> 70.422 MB. [2025-11-12T22:46:47.782Z] ====== als (apache-spark) [default], iteration 20 completed (2490.446 ms) ====== [2025-11-12T22:46:47.782Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-12T22:46:47.782Z] GC before operation: completed in 36.345 ms, heap usage 444.870 MB -> 70.551 MB. [2025-11-12T22:46:50.197Z] ====== als (apache-spark) [default], iteration 21 completed (2354.205 ms) ====== [2025-11-12T22:46:50.197Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-12T22:46:50.197Z] GC before operation: completed in 40.541 ms, heap usage 400.204 MB -> 70.604 MB. [2025-11-12T22:46:52.736Z] ====== als (apache-spark) [default], iteration 22 completed (2483.957 ms) ====== [2025-11-12T22:46:52.736Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-12T22:46:52.736Z] GC before operation: completed in 35.513 ms, heap usage 292.468 MB -> 70.761 MB. [2025-11-12T22:46:55.256Z] ====== als (apache-spark) [default], iteration 23 completed (2462.175 ms) ====== [2025-11-12T22:46:55.256Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-12T22:46:55.256Z] GC before operation: completed in 45.655 ms, heap usage 264.773 MB -> 71.052 MB. [2025-11-12T22:46:58.416Z] ====== als (apache-spark) [default], iteration 24 completed (2472.838 ms) ====== [2025-11-12T22:46:58.417Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-12T22:46:58.417Z] GC before operation: completed in 35.816 ms, heap usage 373.628 MB -> 71.451 MB. [2025-11-12T22:47:00.300Z] ====== als (apache-spark) [default], iteration 25 completed (2380.786 ms) ====== [2025-11-12T22:47:00.300Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-12T22:47:00.665Z] GC before operation: completed in 49.309 ms, heap usage 330.902 MB -> 71.554 MB. [2025-11-12T22:47:03.150Z] ====== als (apache-spark) [default], iteration 26 completed (2426.527 ms) ====== [2025-11-12T22:47:03.150Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-12T22:47:03.150Z] GC before operation: completed in 37.116 ms, heap usage 286.534 MB -> 71.683 MB. [2025-11-12T22:47:05.602Z] ====== als (apache-spark) [default], iteration 27 completed (2503.273 ms) ====== [2025-11-12T22:47:05.602Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-12T22:47:05.602Z] GC before operation: completed in 41.103 ms, heap usage 223.812 MB -> 71.942 MB. [2025-11-12T22:47:08.087Z] ====== als (apache-spark) [default], iteration 28 completed (2420.182 ms) ====== [2025-11-12T22:47:08.087Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-12T22:47:08.087Z] GC before operation: completed in 47.825 ms, heap usage 203.964 MB -> 72.131 MB. [2025-11-12T22:47:10.514Z] ====== als (apache-spark) [default], iteration 29 completed (2406.039 ms) ====== [2025-11-12T22:47:10.866Z] ----------------------------------- [2025-11-12T22:47:10.866Z] renaissance-als_0_PASSED [2025-11-12T22:47:10.866Z] ----------------------------------- [2025-11-12T22:47:10.866Z] [2025-11-12T22:47:10.866Z] TEST TEARDOWN: [2025-11-12T22:47:10.866Z] Nothing to be done for teardown. [2025-11-12T22:47:10.866Z] renaissance-als_0 Finish Time: Wed Nov 12 17:47:10 2025 Epoch Time (ms): 1762987630622