[2026-01-10T13:02:02.816Z] Running test renaissance-als_0 ... [2026-01-10T13:02:02.816Z] =============================================== [2026-01-10T13:02:02.816Z] renaissance-als_0 Start Time: Sat Jan 10 08:02:02 2026 Epoch Time (ms): 1768050122519 [2026-01-10T13:02:02.816Z] variation: NoOptions [2026-01-10T13:02:02.816Z] JVM_OPTIONS: [2026-01-10T13:02:02.816Z] { \ [2026-01-10T13:02:02.816Z] echo ""; echo "TEST SETUP:"; \ [2026-01-10T13:02:02.816Z] echo "Nothing to be done for setup."; \ [2026-01-10T13:02:02.816Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_1768050119684/renaissance-als_0"; \ [2026-01-10T13:02:02.816Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_1768050119684/renaissance-als_0"; \ [2026-01-10T13:02:02.816Z] echo ""; echo "TESTING:"; \ [2026-01-10T13:02:02.816Z] "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/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_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_1768050119684/renaissance-als_0"/als.json" als; \ [2026-01-10T13:02:02.816Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_1768050119684/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-10T13:02:02.816Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-10T13:02:02.816Z] echo "Nothing to be done for teardown."; \ [2026-01-10T13:02:02.816Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_1768050119684/TestTargetResult"; [2026-01-10T13:02:02.816Z] [2026-01-10T13:02:02.816Z] TEST SETUP: [2026-01-10T13:02:02.816Z] Nothing to be done for setup. [2026-01-10T13:02:02.816Z] [2026-01-10T13:02:02.816Z] TESTING: [2026-01-10T13:02:28.216Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-10T13:02:39.104Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-10T13:02:39.635Z] GC before operation: completed in 121.828 ms, heap usage 191.602 MB -> 34.078 MB. [2026-01-10T13:03:53.471Z] ====== als (apache-spark) [default], iteration 0 completed (64684.443 ms) ====== [2026-01-10T13:03:53.471Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-10T13:03:53.471Z] GC before operation: completed in 194.724 ms, heap usage 293.505 MB -> 60.626 MB. [2026-01-10T13:04:25.364Z] ====== als (apache-spark) [default], iteration 1 completed (36157.572 ms) ====== [2026-01-10T13:04:25.364Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-10T13:04:25.364Z] GC before operation: completed in 403.663 ms, heap usage 513.542 MB -> 62.330 MB. [2026-01-10T13:05:02.045Z] ====== als (apache-spark) [default], iteration 2 completed (36160.426 ms) ====== [2026-01-10T13:05:02.045Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-10T13:05:02.045Z] GC before operation: completed in 1182.430 ms, heap usage 353.428 MB -> 62.639 MB. [2026-01-10T13:05:33.841Z] ====== als (apache-spark) [default], iteration 3 completed (27053.770 ms) ====== [2026-01-10T13:05:33.841Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-10T13:05:33.841Z] GC before operation: completed in 350.929 ms, heap usage 1.047 GB -> 63.881 MB. [2026-01-10T13:06:10.955Z] ====== als (apache-spark) [default], iteration 4 completed (33761.656 ms) ====== [2026-01-10T13:06:10.955Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-10T13:06:10.955Z] GC before operation: completed in 281.540 ms, heap usage 729.838 MB -> 64.030 MB. [2026-01-10T13:06:37.847Z] ====== als (apache-spark) [default], iteration 5 completed (29512.080 ms) ====== [2026-01-10T13:06:37.847Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-10T13:06:37.847Z] GC before operation: completed in 234.628 ms, heap usage 617.297 MB -> 64.647 MB. [2026-01-10T13:07:04.390Z] ====== als (apache-spark) [default], iteration 6 completed (25895.663 ms) ====== [2026-01-10T13:07:04.390Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-10T13:07:04.390Z] GC before operation: completed in 229.529 ms, heap usage 249.505 MB -> 64.390 MB. [2026-01-10T13:07:35.924Z] ====== als (apache-spark) [default], iteration 7 completed (29958.585 ms) ====== [2026-01-10T13:07:35.924Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-10T13:07:35.924Z] GC before operation: completed in 328.754 ms, heap usage 427.914 MB -> 65.488 MB. [2026-01-10T13:08:02.399Z] ====== als (apache-spark) [default], iteration 8 completed (28733.995 ms) ====== [2026-01-10T13:08:02.399Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-10T13:08:03.754Z] GC before operation: completed in 985.916 ms, heap usage 415.008 MB -> 66.116 MB. [2026-01-10T13:08:35.729Z] ====== als (apache-spark) [default], iteration 9 completed (31173.245 ms) ====== [2026-01-10T13:08:35.729Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-10T13:08:35.729Z] GC before operation: completed in 222.601 ms, heap usage 412.214 MB -> 66.059 MB. [2026-01-10T13:09:07.085Z] ====== als (apache-spark) [default], iteration 10 completed (29174.492 ms) ====== [2026-01-10T13:09:07.085Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-10T13:09:07.085Z] GC before operation: completed in 355.270 ms, heap usage 424.331 MB -> 66.628 MB. [2026-01-10T13:09:37.839Z] ====== als (apache-spark) [default], iteration 11 completed (31525.969 ms) ====== [2026-01-10T13:09:37.839Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-10T13:09:37.839Z] GC before operation: completed in 319.896 ms, heap usage 971.438 MB -> 67.367 MB. [2026-01-10T13:10:04.342Z] ====== als (apache-spark) [default], iteration 12 completed (23124.165 ms) ====== [2026-01-10T13:10:04.342Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-10T13:10:04.342Z] GC before operation: completed in 673.991 ms, heap usage 1.139 GB -> 68.006 MB. [2026-01-10T13:10:31.158Z] ====== als (apache-spark) [default], iteration 13 completed (28468.620 ms) ====== [2026-01-10T13:10:31.158Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-10T13:10:31.659Z] GC before operation: completed in 296.862 ms, heap usage 437.721 MB -> 67.424 MB. [2026-01-10T13:10:57.902Z] ====== als (apache-spark) [default], iteration 14 completed (25339.307 ms) ====== [2026-01-10T13:10:57.902Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-10T13:10:57.902Z] GC before operation: completed in 289.981 ms, heap usage 371.766 MB -> 67.399 MB. [2026-01-10T13:11:35.447Z] ====== als (apache-spark) [default], iteration 15 completed (33263.756 ms) ====== [2026-01-10T13:11:35.447Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-10T13:11:35.447Z] GC before operation: completed in 181.653 ms, heap usage 640.268 MB -> 67.860 MB. [2026-01-10T13:12:01.982Z] ====== als (apache-spark) [default], iteration 16 completed (28500.111 ms) ====== [2026-01-10T13:12:01.982Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-10T13:12:01.982Z] GC before operation: completed in 449.206 ms, heap usage 1.560 GB -> 69.450 MB. [2026-01-10T13:12:33.748Z] ====== als (apache-spark) [default], iteration 17 completed (30141.096 ms) ====== [2026-01-10T13:12:33.748Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-10T13:12:33.749Z] GC before operation: completed in 433.018 ms, heap usage 804.355 MB -> 68.720 MB. [2026-01-10T13:13:05.717Z] ====== als (apache-spark) [default], iteration 18 completed (27368.118 ms) ====== [2026-01-10T13:13:05.717Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-10T13:13:05.717Z] GC before operation: completed in 682.025 ms, heap usage 796.366 MB -> 69.048 MB. [2026-01-10T13:13:27.813Z] ====== als (apache-spark) [default], iteration 19 completed (23466.700 ms) ====== [2026-01-10T13:13:27.813Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-10T13:13:27.813Z] GC before operation: completed in 938.629 ms, heap usage 720.538 MB -> 69.211 MB. [2026-01-10T13:13:57.111Z] ====== als (apache-spark) [default], iteration 20 completed (28002.941 ms) ====== [2026-01-10T13:13:57.111Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-10T13:13:57.111Z] GC before operation: completed in 334.351 ms, heap usage 585.646 MB -> 69.376 MB. [2026-01-10T13:14:28.420Z] ====== als (apache-spark) [default], iteration 21 completed (31858.894 ms) ====== [2026-01-10T13:14:28.420Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-10T13:14:28.975Z] GC before operation: completed in 811.033 ms, heap usage 354.311 MB -> 69.297 MB. [2026-01-10T13:15:00.962Z] ====== als (apache-spark) [default], iteration 22 completed (27697.145 ms) ====== [2026-01-10T13:15:00.962Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-10T13:15:00.962Z] GC before operation: completed in 225.631 ms, heap usage 370.633 MB -> 69.393 MB. [2026-01-10T13:15:27.441Z] ====== als (apache-spark) [default], iteration 23 completed (26532.879 ms) ====== [2026-01-10T13:15:27.441Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-10T13:15:27.441Z] GC before operation: completed in 255.306 ms, heap usage 669.620 MB -> 70.302 MB. [2026-01-10T13:15:58.870Z] ====== als (apache-spark) [default], iteration 24 completed (32073.824 ms) ====== [2026-01-10T13:15:58.870Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-10T13:15:58.870Z] GC before operation: completed in 275.817 ms, heap usage 112.846 MB -> 71.373 MB. [2026-01-10T13:16:25.104Z] ====== als (apache-spark) [default], iteration 25 completed (25046.141 ms) ====== [2026-01-10T13:16:25.104Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-10T13:16:25.104Z] GC before operation: completed in 303.603 ms, heap usage 1002.216 MB -> 71.498 MB. [2026-01-10T13:16:51.571Z] ====== als (apache-spark) [default], iteration 26 completed (27359.464 ms) ====== [2026-01-10T13:16:51.571Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-10T13:16:51.571Z] GC before operation: completed in 394.528 ms, heap usage 481.783 MB -> 70.849 MB. [2026-01-10T13:17:18.803Z] ====== als (apache-spark) [default], iteration 27 completed (27431.590 ms) ====== [2026-01-10T13:17:18.803Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-10T13:17:19.369Z] GC before operation: completed in 250.345 ms, heap usage 715.119 MB -> 71.304 MB. [2026-01-10T13:17:50.705Z] ====== als (apache-spark) [default], iteration 28 completed (27504.227 ms) ====== [2026-01-10T13:17:50.705Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-10T13:17:50.705Z] GC before operation: completed in 189.891 ms, heap usage 921.052 MB -> 71.347 MB. [2026-01-10T13:18:16.989Z] ====== als (apache-spark) [default], iteration 29 completed (29358.024 ms) ====== [2026-01-10T13:18:19.160Z] ----------------------------------- [2026-01-10T13:18:19.160Z] renaissance-als_0_PASSED [2026-01-10T13:18:19.160Z] ----------------------------------- [2026-01-10T13:18:19.160Z] [2026-01-10T13:18:19.160Z] TEST TEARDOWN: [2026-01-10T13:18:19.160Z] Nothing to be done for teardown. [2026-01-10T13:18:19.160Z] renaissance-als_0 Finish Time: Sat Jan 10 08:18:18 2026 Epoch Time (ms): 1768051098745