[2025-08-13T19:56:10.745Z] Running test renaissance-als_0 ... [2025-08-13T19:56:10.745Z] =============================================== [2025-08-13T19:56:10.745Z] renaissance-als_0 Start Time: Wed Aug 13 15:56:10 2025 Epoch Time (ms): 1755114970670 [2025-08-13T19:56:10.745Z] variation: NoOptions [2025-08-13T19:56:10.745Z] JVM_OPTIONS: [2025-08-13T19:56:10.745Z] { \ [2025-08-13T19:56:10.745Z] echo ""; echo "TEST SETUP:"; \ [2025-08-13T19:56:10.745Z] echo "Nothing to be done for setup."; \ [2025-08-13T19:56:10.745Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17551149705909/renaissance-als_0"; \ [2025-08-13T19:56:10.745Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17551149705909/renaissance-als_0"; \ [2025-08-13T19:56:10.745Z] echo ""; echo "TESTING:"; \ [2025-08-13T19:56:10.745Z] "/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_17551149705909/renaissance-als_0"/als.json" als; \ [2025-08-13T19:56:10.745Z] 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_17551149705909/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-13T19:56:10.745Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-13T19:56:10.745Z] echo "Nothing to be done for teardown."; \ [2025-08-13T19:56:10.745Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17551149705909/TestTargetResult"; [2025-08-13T19:56:10.745Z] [2025-08-13T19:56:10.745Z] TEST SETUP: [2025-08-13T19:56:10.746Z] Nothing to be done for setup. [2025-08-13T19:56:10.746Z] [2025-08-13T19:56:10.746Z] TESTING: [2025-08-13T19:56:15.760Z] NOTE: 'als' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-08-13T19:56:18.239Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-08-13T19:56:18.239Z] GC before operation: completed in 20.396 ms, heap usage 51.000 MB -> 35.141 MB. [2025-08-13T19:56:29.428Z] ====== als (apache-spark) [default], iteration 0 completed (10159.628 ms) ====== [2025-08-13T19:56:29.428Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-08-13T19:56:29.428Z] GC before operation: completed in 48.866 ms, heap usage 211.270 MB -> 61.949 MB. [2025-08-13T19:56:33.422Z] ====== als (apache-spark) [default], iteration 1 completed (4166.188 ms) ====== [2025-08-13T19:56:33.422Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-08-13T19:56:33.422Z] GC before operation: completed in 47.007 ms, heap usage 590.760 MB -> 63.168 MB. [2025-08-13T19:56:37.620Z] ====== als (apache-spark) [default], iteration 2 completed (4015.803 ms) ====== [2025-08-13T19:56:37.620Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-08-13T19:56:37.620Z] GC before operation: completed in 61.691 ms, heap usage 388.611 MB -> 63.410 MB. [2025-08-13T19:56:40.817Z] ====== als (apache-spark) [default], iteration 3 completed (3642.737 ms) ====== [2025-08-13T19:56:40.817Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-08-13T19:56:40.817Z] GC before operation: completed in 47.815 ms, heap usage 333.919 MB -> 64.074 MB. [2025-08-13T19:56:44.867Z] ====== als (apache-spark) [default], iteration 4 completed (3370.395 ms) ====== [2025-08-13T19:56:44.867Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-08-13T19:56:44.867Z] GC before operation: completed in 59.448 ms, heap usage 325.373 MB -> 64.586 MB. [2025-08-13T19:56:48.961Z] ====== als (apache-spark) [default], iteration 5 completed (4020.058 ms) ====== [2025-08-13T19:56:48.961Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-08-13T19:56:48.961Z] GC before operation: completed in 62.535 ms, heap usage 335.434 MB -> 65.123 MB. [2025-08-13T19:56:52.199Z] ====== als (apache-spark) [default], iteration 6 completed (3778.215 ms) ====== [2025-08-13T19:56:52.199Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-08-13T19:56:52.561Z] GC before operation: completed in 85.819 ms, heap usage 475.630 MB -> 65.845 MB. [2025-08-13T19:56:56.752Z] ====== als (apache-spark) [default], iteration 7 completed (4092.254 ms) ====== [2025-08-13T19:56:56.752Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-08-13T19:56:56.752Z] GC before operation: completed in 56.069 ms, heap usage 179.979 MB -> 65.882 MB. [2025-08-13T19:57:00.771Z] ====== als (apache-spark) [default], iteration 8 completed (3775.770 ms) ====== [2025-08-13T19:57:00.771Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-08-13T19:57:00.771Z] GC before operation: completed in 47.257 ms, heap usage 445.399 MB -> 66.953 MB. [2025-08-13T19:57:04.818Z] ====== als (apache-spark) [default], iteration 9 completed (4094.702 ms) ====== [2025-08-13T19:57:04.818Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-08-13T19:57:04.818Z] GC before operation: completed in 56.309 ms, heap usage 386.667 MB -> 67.122 MB. [2025-08-13T19:57:08.859Z] ====== als (apache-spark) [default], iteration 10 completed (3648.649 ms) ====== [2025-08-13T19:57:08.859Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-08-13T19:57:08.859Z] GC before operation: completed in 59.728 ms, heap usage 186.171 MB -> 67.085 MB. [2025-08-13T19:57:13.086Z] ====== als (apache-spark) [default], iteration 11 completed (3800.758 ms) ====== [2025-08-13T19:57:13.086Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-08-13T19:57:13.086Z] GC before operation: completed in 68.827 ms, heap usage 188.132 MB -> 67.419 MB. [2025-08-13T19:57:17.083Z] ====== als (apache-spark) [default], iteration 12 completed (4172.538 ms) ====== [2025-08-13T19:57:17.083Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-08-13T19:57:17.083Z] GC before operation: completed in 58.012 ms, heap usage 440.592 MB -> 68.158 MB. [2025-08-13T19:57:21.166Z] ====== als (apache-spark) [default], iteration 13 completed (3607.569 ms) ====== [2025-08-13T19:57:21.166Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-08-13T19:57:21.166Z] GC before operation: completed in 45.936 ms, heap usage 443.023 MB -> 68.400 MB. [2025-08-13T19:57:24.293Z] ====== als (apache-spark) [default], iteration 14 completed (3382.341 ms) ====== [2025-08-13T19:57:24.293Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-08-13T19:57:24.293Z] GC before operation: completed in 66.871 ms, heap usage 255.229 MB -> 68.280 MB. [2025-08-13T19:57:27.468Z] ====== als (apache-spark) [default], iteration 15 completed (3046.104 ms) ====== [2025-08-13T19:57:27.468Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-08-13T19:57:27.468Z] GC before operation: completed in 43.786 ms, heap usage 384.797 MB -> 68.703 MB. [2025-08-13T19:57:32.407Z] ====== als (apache-spark) [default], iteration 16 completed (3064.406 ms) ====== [2025-08-13T19:57:32.407Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-08-13T19:57:32.407Z] GC before operation: completed in 48.897 ms, heap usage 197.377 MB -> 68.727 MB. [2025-08-13T19:57:34.215Z] ====== als (apache-spark) [default], iteration 17 completed (3140.013 ms) ====== [2025-08-13T19:57:34.215Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-08-13T19:57:34.215Z] GC before operation: completed in 50.014 ms, heap usage 530.309 MB -> 70.052 MB. [2025-08-13T19:57:37.319Z] ====== als (apache-spark) [default], iteration 18 completed (3179.443 ms) ====== [2025-08-13T19:57:37.319Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-08-13T19:57:37.319Z] GC before operation: completed in 60.565 ms, heap usage 360.719 MB -> 69.425 MB. [2025-08-13T19:57:40.515Z] ====== als (apache-spark) [default], iteration 19 completed (3063.028 ms) ====== [2025-08-13T19:57:40.515Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-08-13T19:57:40.515Z] GC before operation: completed in 55.540 ms, heap usage 490.287 MB -> 69.901 MB. [2025-08-13T19:57:44.548Z] ====== als (apache-spark) [default], iteration 20 completed (3418.341 ms) ====== [2025-08-13T19:57:44.548Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-08-13T19:57:44.548Z] GC before operation: completed in 47.846 ms, heap usage 474.409 MB -> 70.102 MB. [2025-08-13T19:57:46.964Z] ====== als (apache-spark) [default], iteration 21 completed (3101.832 ms) ====== [2025-08-13T19:57:46.964Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-08-13T19:57:47.324Z] GC before operation: completed in 43.952 ms, heap usage 396.097 MB -> 70.158 MB. [2025-08-13T19:57:50.498Z] ====== als (apache-spark) [default], iteration 22 completed (3138.638 ms) ====== [2025-08-13T19:57:50.498Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-08-13T19:57:50.498Z] GC before operation: completed in 45.143 ms, heap usage 120.963 MB -> 70.202 MB. [2025-08-13T19:57:53.706Z] ====== als (apache-spark) [default], iteration 23 completed (3173.806 ms) ====== [2025-08-13T19:57:53.706Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-08-13T19:57:53.706Z] GC before operation: completed in 60.041 ms, heap usage 388.191 MB -> 70.726 MB. [2025-08-13T19:57:57.655Z] ====== als (apache-spark) [default], iteration 24 completed (3182.798 ms) ====== [2025-08-13T19:57:57.655Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-08-13T19:57:57.656Z] GC before operation: completed in 54.863 ms, heap usage 194.204 MB -> 70.771 MB. [2025-08-13T19:58:00.073Z] ====== als (apache-spark) [default], iteration 25 completed (3043.046 ms) ====== [2025-08-13T19:58:00.073Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-08-13T19:58:00.073Z] GC before operation: completed in 52.840 ms, heap usage 476.281 MB -> 71.528 MB. [2025-08-13T19:58:04.121Z] ====== als (apache-spark) [default], iteration 26 completed (3232.742 ms) ====== [2025-08-13T19:58:04.121Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-08-13T19:58:04.121Z] GC before operation: completed in 55.288 ms, heap usage 358.885 MB -> 71.560 MB. [2025-08-13T19:58:06.530Z] ====== als (apache-spark) [default], iteration 27 completed (2926.780 ms) ====== [2025-08-13T19:58:06.530Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-08-13T19:58:06.530Z] GC before operation: completed in 46.262 ms, heap usage 225.554 MB -> 71.733 MB. [2025-08-13T19:58:09.684Z] ====== als (apache-spark) [default], iteration 28 completed (2915.123 ms) ====== [2025-08-13T19:58:09.684Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-08-13T19:58:09.684Z] GC before operation: completed in 55.182 ms, heap usage 495.619 MB -> 71.874 MB. [2025-08-13T19:58:12.830Z] ====== als (apache-spark) [default], iteration 29 completed (3204.593 ms) ====== [2025-08-13T19:58:13.180Z] ----------------------------------- [2025-08-13T19:58:13.180Z] renaissance-als_0_PASSED [2025-08-13T19:58:13.180Z] ----------------------------------- [2025-08-13T19:58:13.180Z] [2025-08-13T19:58:13.180Z] TEST TEARDOWN: [2025-08-13T19:58:13.180Z] Nothing to be done for teardown. [2025-08-13T19:58:13.180Z] renaissance-als_0 Finish Time: Wed Aug 13 15:58:12 2025 Epoch Time (ms): 1755115092992