[2025-10-03T20:12:35.248Z] Running test renaissance-als_0 ... [2025-10-03T20:12:35.248Z] =============================================== [2025-10-03T20:12:35.869Z] renaissance-als_0 Start Time: Fri Oct 3 13:12:35 2025 Epoch Time (ms): 1759522355234 [2025-10-03T20:12:35.869Z] variation: NoOptions [2025-10-03T20:12:35.869Z] JVM_OPTIONS: [2025-10-03T20:12:35.869Z] { \ [2025-10-03T20:12:35.869Z] echo ""; echo "TEST SETUP:"; \ [2025-10-03T20:12:35.869Z] echo "Nothing to be done for setup."; \ [2025-10-03T20:12:35.869Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17595223484180/renaissance-als_0"; \ [2025-10-03T20:12:35.869Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17595223484180/renaissance-als_0"; \ [2025-10-03T20:12:35.869Z] echo ""; echo "TESTING:"; \ [2025-10-03T20:12:35.869Z] "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_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_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17595223484180/renaissance-als_0"/als.json" als; \ [2025-10-03T20:12:35.869Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17595223484180/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-03T20:12:35.869Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-03T20:12:35.869Z] echo "Nothing to be done for teardown."; \ [2025-10-03T20:12:35.869Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17595223484180/TestTargetResult"; [2025-10-03T20:12:35.869Z] [2025-10-03T20:12:35.869Z] TEST SETUP: [2025-10-03T20:12:35.869Z] Nothing to be done for setup. [2025-10-03T20:12:35.869Z] [2025-10-03T20:12:35.869Z] TESTING: [2025-10-03T20:13:08.054Z] NOTE: 'als' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-10-03T20:13:40.550Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-10-03T20:13:40.550Z] GC before operation: completed in 486.005 ms, heap usage 180.600 MB -> 35.235 MB. [2025-10-03T20:15:57.820Z] ====== als (apache-spark) [default], iteration 0 completed (128090.111 ms) ====== [2025-10-03T20:15:57.820Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-10-03T20:15:57.820Z] GC before operation: completed in 512.226 ms, heap usage 167.172 MB -> 62.789 MB. [2025-10-03T20:16:50.810Z] ====== als (apache-spark) [default], iteration 1 completed (51967.429 ms) ====== [2025-10-03T20:16:50.810Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-10-03T20:16:50.810Z] GC before operation: completed in 1190.131 ms, heap usage 643.450 MB -> 63.405 MB. [2025-10-03T20:17:21.257Z] ====== als (apache-spark) [default], iteration 2 completed (36515.855 ms) ====== [2025-10-03T20:17:21.257Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-10-03T20:17:21.743Z] GC before operation: completed in 555.339 ms, heap usage 804.244 MB -> 64.286 MB. [2025-10-03T20:18:16.819Z] ====== als (apache-spark) [default], iteration 3 completed (48969.923 ms) ====== [2025-10-03T20:18:16.819Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-10-03T20:18:16.819Z] GC before operation: completed in 1030.134 ms, heap usage 833.301 MB -> 64.919 MB. [2025-10-03T20:19:12.521Z] ====== als (apache-spark) [default], iteration 4 completed (58423.358 ms) ====== [2025-10-03T20:19:12.521Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-10-03T20:19:13.064Z] GC before operation: completed in 865.129 ms, heap usage 474.097 MB -> 65.087 MB. [2025-10-03T20:19:51.322Z] ====== als (apache-spark) [default], iteration 5 completed (36952.167 ms) ====== [2025-10-03T20:19:51.322Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-10-03T20:19:51.322Z] GC before operation: completed in 337.831 ms, heap usage 803.259 MB -> 66.219 MB. [2025-10-03T20:20:28.379Z] ====== als (apache-spark) [default], iteration 6 completed (32353.259 ms) ====== [2025-10-03T20:20:28.379Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-10-03T20:20:28.379Z] GC before operation: completed in 281.469 ms, heap usage 957.223 MB -> 66.684 MB. [2025-10-03T20:21:00.630Z] ====== als (apache-spark) [default], iteration 7 completed (31150.369 ms) ====== [2025-10-03T20:21:00.630Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-10-03T20:21:00.630Z] GC before operation: completed in 254.272 ms, heap usage 916.590 MB -> 67.225 MB. [2025-10-03T20:21:27.199Z] ====== als (apache-spark) [default], iteration 8 completed (29675.449 ms) ====== [2025-10-03T20:21:27.199Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-10-03T20:21:27.199Z] GC before operation: completed in 300.807 ms, heap usage 891.444 MB -> 67.712 MB. [2025-10-03T20:21:58.533Z] ====== als (apache-spark) [default], iteration 9 completed (29480.631 ms) ====== [2025-10-03T20:21:58.533Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-10-03T20:21:58.533Z] GC before operation: completed in 297.025 ms, heap usage 900.315 MB -> 67.877 MB. [2025-10-03T20:22:26.134Z] ====== als (apache-spark) [default], iteration 10 completed (28446.790 ms) ====== [2025-10-03T20:22:26.134Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-10-03T20:22:26.134Z] GC before operation: completed in 331.107 ms, heap usage 386.482 MB -> 67.568 MB. [2025-10-03T20:22:56.800Z] ====== als (apache-spark) [default], iteration 11 completed (27005.488 ms) ====== [2025-10-03T20:22:56.800Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-10-03T20:22:56.800Z] GC before operation: completed in 259.653 ms, heap usage 863.094 MB -> 68.429 MB. [2025-10-03T20:23:23.044Z] ====== als (apache-spark) [default], iteration 12 completed (25731.613 ms) ====== [2025-10-03T20:23:23.044Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-10-03T20:23:23.044Z] GC before operation: completed in 241.129 ms, heap usage 330.319 MB -> 67.874 MB. [2025-10-03T20:23:49.667Z] ====== als (apache-spark) [default], iteration 13 completed (26199.062 ms) ====== [2025-10-03T20:23:49.667Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-10-03T20:23:49.667Z] GC before operation: completed in 265.586 ms, heap usage 753.772 MB -> 68.763 MB. [2025-10-03T20:24:20.812Z] ====== als (apache-spark) [default], iteration 14 completed (29896.827 ms) ====== [2025-10-03T20:24:20.812Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-10-03T20:24:20.812Z] GC before operation: completed in 257.425 ms, heap usage 600.085 MB -> 68.843 MB. [2025-10-03T20:24:51.807Z] ====== als (apache-spark) [default], iteration 15 completed (30358.635 ms) ====== [2025-10-03T20:24:51.807Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-10-03T20:24:51.807Z] GC before operation: completed in 284.953 ms, heap usage 244.681 MB -> 68.535 MB. [2025-10-03T20:25:18.623Z] ====== als (apache-spark) [default], iteration 16 completed (28977.813 ms) ====== [2025-10-03T20:25:18.623Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-10-03T20:25:18.623Z] GC before operation: completed in 285.515 ms, heap usage 575.279 MB -> 69.223 MB. [2025-10-03T20:25:50.532Z] ====== als (apache-spark) [default], iteration 17 completed (29445.595 ms) ====== [2025-10-03T20:25:50.532Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-10-03T20:25:50.532Z] GC before operation: completed in 453.734 ms, heap usage 912.324 MB -> 70.003 MB. [2025-10-03T20:26:21.550Z] ====== als (apache-spark) [default], iteration 18 completed (30835.698 ms) ====== [2025-10-03T20:26:21.550Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-10-03T20:26:21.550Z] GC before operation: completed in 281.330 ms, heap usage 709.533 MB -> 69.971 MB. [2025-10-03T20:26:52.577Z] ====== als (apache-spark) [default], iteration 19 completed (27993.068 ms) ====== [2025-10-03T20:26:52.577Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-10-03T20:26:52.577Z] GC before operation: completed in 272.135 ms, heap usage 522.535 MB -> 70.033 MB. [2025-10-03T20:27:25.503Z] ====== als (apache-spark) [default], iteration 20 completed (32964.627 ms) ====== [2025-10-03T20:27:25.503Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-10-03T20:27:25.503Z] GC before operation: completed in 290.616 ms, heap usage 548.297 MB -> 70.284 MB. [2025-10-03T20:27:56.351Z] ====== als (apache-spark) [default], iteration 21 completed (29982.735 ms) ====== [2025-10-03T20:27:56.351Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-10-03T20:27:56.351Z] GC before operation: completed in 277.676 ms, heap usage 774.050 MB -> 70.777 MB. [2025-10-03T20:28:22.502Z] ====== als (apache-spark) [default], iteration 22 completed (29201.064 ms) ====== [2025-10-03T20:28:22.502Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-10-03T20:28:22.502Z] GC before operation: completed in 221.992 ms, heap usage 430.156 MB -> 70.652 MB. [2025-10-03T20:28:54.502Z] ====== als (apache-spark) [default], iteration 23 completed (27519.141 ms) ====== [2025-10-03T20:28:54.502Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-10-03T20:28:54.502Z] GC before operation: completed in 340.750 ms, heap usage 981.294 MB -> 71.709 MB. [2025-10-03T20:29:21.576Z] ====== als (apache-spark) [default], iteration 24 completed (29368.313 ms) ====== [2025-10-03T20:29:21.576Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-10-03T20:29:21.576Z] GC before operation: completed in 232.147 ms, heap usage 303.693 MB -> 70.978 MB. [2025-10-03T20:29:48.153Z] ====== als (apache-spark) [default], iteration 25 completed (23549.222 ms) ====== [2025-10-03T20:29:48.153Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-10-03T20:29:48.153Z] GC before operation: completed in 251.194 ms, heap usage 533.112 MB -> 71.684 MB. [2025-10-03T20:30:10.149Z] ====== als (apache-spark) [default], iteration 26 completed (22654.054 ms) ====== [2025-10-03T20:30:10.149Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-10-03T20:30:10.149Z] GC before operation: completed in 348.699 ms, heap usage 1.311 GB -> 72.923 MB. [2025-10-03T20:30:35.548Z] ====== als (apache-spark) [default], iteration 27 completed (26610.107 ms) ====== [2025-10-03T20:30:35.548Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-10-03T20:30:35.548Z] GC before operation: completed in 185.905 ms, heap usage 271.443 MB -> 71.363 MB. [2025-10-03T20:31:05.839Z] ====== als (apache-spark) [default], iteration 28 completed (26479.743 ms) ====== [2025-10-03T20:31:05.839Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-10-03T20:31:05.839Z] GC before operation: completed in 289.519 ms, heap usage 632.180 MB -> 71.859 MB. [2025-10-03T20:31:31.065Z] ====== als (apache-spark) [default], iteration 29 completed (27432.412 ms) ====== [2025-10-03T20:31:31.065Z] ----------------------------------- [2025-10-03T20:31:31.065Z] renaissance-als_0_PASSED [2025-10-03T20:31:31.065Z] ----------------------------------- [2025-10-03T20:31:31.065Z] [2025-10-03T20:31:31.065Z] TEST TEARDOWN: [2025-10-03T20:31:31.065Z] Nothing to be done for teardown. [2025-10-03T20:31:31.065Z] renaissance-als_0 Finish Time: Fri Oct 3 13:31:30 2025 Epoch Time (ms): 1759523490893