[2026-01-16T15:01:26.681Z] Running test renaissance-als_0 ... [2026-01-16T15:01:26.681Z] =============================================== [2026-01-16T15:01:26.681Z] renaissance-als_0 Start Time: Fri Jan 16 10:01:26 2026 Epoch Time (ms): 1768575686442 [2026-01-16T15:01:26.681Z] variation: NoOptions [2026-01-16T15:01:26.681Z] JVM_OPTIONS: [2026-01-16T15:01:26.681Z] { \ [2026-01-16T15:01:26.681Z] echo ""; echo "TEST SETUP:"; \ [2026-01-16T15:01:26.681Z] echo "Nothing to be done for setup."; \ [2026-01-16T15:01:26.681Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1768575682930/renaissance-als_0"; \ [2026-01-16T15:01:26.681Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1768575682930/renaissance-als_0"; \ [2026-01-16T15:01:26.681Z] echo ""; echo "TESTING:"; \ [2026-01-16T15:01:26.681Z] "/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_1768575682930/renaissance-als_0"/als.json" als; \ [2026-01-16T15:01:26.681Z] 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_1768575682930/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-16T15:01:26.681Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-16T15:01:26.682Z] echo "Nothing to be done for teardown."; \ [2026-01-16T15:01:26.682Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1768575682930/TestTargetResult"; [2026-01-16T15:01:27.137Z] [2026-01-16T15:01:27.137Z] TEST SETUP: [2026-01-16T15:01:27.137Z] Nothing to be done for setup. [2026-01-16T15:01:27.137Z] [2026-01-16T15:01:27.137Z] TESTING: [2026-01-16T15:01:53.594Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-16T15:02:06.637Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-16T15:02:06.637Z] GC before operation: completed in 260.259 ms, heap usage 303.080 MB -> 34.277 MB. [2026-01-16T15:03:21.012Z] ====== als (apache-spark) [default], iteration 0 completed (64174.240 ms) ====== [2026-01-16T15:03:21.012Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-16T15:03:21.012Z] GC before operation: completed in 186.237 ms, heap usage 173.112 MB -> 63.159 MB. [2026-01-16T15:03:42.756Z] ====== als (apache-spark) [default], iteration 1 completed (27963.709 ms) ====== [2026-01-16T15:03:42.756Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-16T15:03:42.756Z] GC before operation: completed in 663.745 ms, heap usage 409.851 MB -> 62.069 MB. [2026-01-16T15:04:04.415Z] ====== als (apache-spark) [default], iteration 2 completed (19730.304 ms) ====== [2026-01-16T15:04:04.415Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-16T15:04:04.415Z] GC before operation: completed in 162.783 ms, heap usage 322.303 MB -> 62.377 MB. [2026-01-16T15:04:26.209Z] ====== als (apache-spark) [default], iteration 3 completed (22758.257 ms) ====== [2026-01-16T15:04:26.209Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-16T15:04:26.209Z] GC before operation: completed in 814.461 ms, heap usage 167.936 MB -> 62.768 MB. [2026-01-16T15:04:47.651Z] ====== als (apache-spark) [default], iteration 4 completed (19642.439 ms) ====== [2026-01-16T15:04:47.651Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-16T15:04:47.651Z] GC before operation: completed in 78.377 ms, heap usage 326.832 MB -> 63.408 MB. [2026-01-16T15:05:09.147Z] ====== als (apache-spark) [default], iteration 5 completed (21584.302 ms) ====== [2026-01-16T15:05:09.147Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-16T15:05:09.147Z] GC before operation: completed in 127.773 ms, heap usage 400.368 MB -> 64.008 MB. [2026-01-16T15:05:27.225Z] ====== als (apache-spark) [default], iteration 6 completed (18944.744 ms) ====== [2026-01-16T15:05:27.225Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-16T15:05:27.225Z] GC before operation: completed in 251.877 ms, heap usage 1.181 GB -> 65.697 MB. [2026-01-16T15:05:48.722Z] ====== als (apache-spark) [default], iteration 7 completed (18215.128 ms) ====== [2026-01-16T15:05:48.722Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-16T15:05:48.722Z] GC before operation: completed in 158.387 ms, heap usage 590.178 MB -> 65.600 MB. [2026-01-16T15:06:00.828Z] ====== als (apache-spark) [default], iteration 8 completed (14417.114 ms) ====== [2026-01-16T15:06:00.828Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-16T15:06:00.828Z] GC before operation: completed in 296.928 ms, heap usage 1.092 GB -> 66.825 MB. [2026-01-16T15:06:19.088Z] ====== als (apache-spark) [default], iteration 9 completed (15676.445 ms) ====== [2026-01-16T15:06:19.088Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-16T15:06:19.088Z] GC before operation: completed in 215.479 ms, heap usage 1.211 GB -> 67.186 MB. [2026-01-16T15:06:39.649Z] ====== als (apache-spark) [default], iteration 10 completed (21226.349 ms) ====== [2026-01-16T15:06:39.649Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-16T15:06:39.649Z] GC before operation: completed in 247.727 ms, heap usage 612.796 MB -> 67.625 MB. [2026-01-16T15:07:05.321Z] ====== als (apache-spark) [default], iteration 11 completed (23994.939 ms) ====== [2026-01-16T15:07:05.321Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-16T15:07:05.321Z] GC before operation: completed in 94.591 ms, heap usage 761.257 MB -> 67.271 MB. [2026-01-16T15:07:20.401Z] ====== als (apache-spark) [default], iteration 12 completed (16228.094 ms) ====== [2026-01-16T15:07:20.401Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-16T15:07:20.401Z] GC before operation: completed in 122.866 ms, heap usage 272.837 MB -> 66.605 MB. [2026-01-16T15:07:38.154Z] ====== als (apache-spark) [default], iteration 13 completed (16654.913 ms) ====== [2026-01-16T15:07:38.154Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-16T15:07:38.154Z] GC before operation: completed in 84.290 ms, heap usage 362.409 MB -> 67.067 MB. [2026-01-16T15:07:55.829Z] ====== als (apache-spark) [default], iteration 14 completed (16232.029 ms) ====== [2026-01-16T15:07:55.829Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-16T15:07:55.829Z] GC before operation: completed in 429.637 ms, heap usage 127.992 MB -> 67.382 MB. [2026-01-16T15:08:08.278Z] ====== als (apache-spark) [default], iteration 15 completed (11400.585 ms) ====== [2026-01-16T15:08:08.278Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-16T15:08:08.278Z] GC before operation: completed in 99.502 ms, heap usage 155.316 MB -> 67.254 MB. [2026-01-16T15:08:23.130Z] ====== als (apache-spark) [default], iteration 16 completed (15479.472 ms) ====== [2026-01-16T15:08:23.130Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-16T15:08:23.130Z] GC before operation: completed in 83.921 ms, heap usage 417.215 MB -> 68.131 MB. [2026-01-16T15:08:37.915Z] ====== als (apache-spark) [default], iteration 17 completed (14163.925 ms) ====== [2026-01-16T15:08:37.915Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-16T15:08:37.915Z] GC before operation: completed in 100.295 ms, heap usage 156.972 MB -> 68.007 MB. [2026-01-16T15:08:52.873Z] ====== als (apache-spark) [default], iteration 18 completed (15089.511 ms) ====== [2026-01-16T15:08:52.873Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-16T15:08:52.873Z] GC before operation: completed in 485.615 ms, heap usage 257.200 MB -> 68.464 MB. [2026-01-16T15:09:10.580Z] ====== als (apache-spark) [default], iteration 19 completed (16025.300 ms) ====== [2026-01-16T15:09:10.580Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-16T15:09:10.580Z] GC before operation: completed in 99.344 ms, heap usage 414.314 MB -> 68.846 MB. [2026-01-16T15:09:25.436Z] ====== als (apache-spark) [default], iteration 20 completed (15227.219 ms) ====== [2026-01-16T15:09:25.436Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-16T15:09:25.436Z] GC before operation: completed in 107.992 ms, heap usage 311.684 MB -> 70.996 MB. [2026-01-16T15:09:40.619Z] ====== als (apache-spark) [default], iteration 21 completed (14626.486 ms) ====== [2026-01-16T15:09:40.619Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-16T15:09:40.619Z] GC before operation: completed in 99.552 ms, heap usage 413.788 MB -> 69.419 MB. [2026-01-16T15:10:02.069Z] ====== als (apache-spark) [default], iteration 22 completed (20117.282 ms) ====== [2026-01-16T15:10:02.069Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-16T15:10:02.069Z] GC before operation: completed in 451.290 ms, heap usage 234.028 MB -> 69.336 MB. [2026-01-16T15:10:20.109Z] ====== als (apache-spark) [default], iteration 23 completed (17872.690 ms) ====== [2026-01-16T15:10:20.109Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-16T15:10:20.109Z] GC before operation: completed in 127.968 ms, heap usage 145.908 MB -> 69.946 MB. [2026-01-16T15:10:41.498Z] ====== als (apache-spark) [default], iteration 24 completed (19024.193 ms) ====== [2026-01-16T15:10:41.498Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-16T15:10:41.498Z] GC before operation: completed in 894.301 ms, heap usage 1.180 GB -> 71.502 MB. [2026-01-16T15:10:59.162Z] ====== als (apache-spark) [default], iteration 25 completed (17685.701 ms) ====== [2026-01-16T15:10:59.162Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-16T15:10:59.162Z] GC before operation: completed in 637.659 ms, heap usage 311.026 MB -> 70.543 MB. [2026-01-16T15:11:16.963Z] ====== als (apache-spark) [default], iteration 26 completed (18213.903 ms) ====== [2026-01-16T15:11:16.964Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-16T15:11:16.964Z] GC before operation: completed in 230.329 ms, heap usage 436.370 MB -> 70.770 MB. [2026-01-16T15:11:35.470Z] ====== als (apache-spark) [default], iteration 27 completed (17420.807 ms) ====== [2026-01-16T15:11:35.470Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-16T15:11:35.470Z] GC before operation: completed in 267.959 ms, heap usage 379.217 MB -> 70.822 MB. [2026-01-16T15:12:01.388Z] ====== als (apache-spark) [default], iteration 28 completed (23072.131 ms) ====== [2026-01-16T15:12:01.388Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-16T15:12:01.388Z] GC before operation: completed in 273.850 ms, heap usage 473.304 MB -> 71.047 MB. [2026-01-16T15:12:16.066Z] ====== als (apache-spark) [default], iteration 29 completed (16798.241 ms) ====== [2026-01-16T15:12:16.066Z] ----------------------------------- [2026-01-16T15:12:16.066Z] renaissance-als_0_PASSED [2026-01-16T15:12:16.066Z] ----------------------------------- [2026-01-16T15:12:16.066Z] [2026-01-16T15:12:16.066Z] TEST TEARDOWN: [2026-01-16T15:12:16.066Z] Nothing to be done for teardown. [2026-01-16T15:12:16.066Z] renaissance-als_0 Finish Time: Fri Jan 16 10:12:15 2026 Epoch Time (ms): 1768576335692