[2025-09-25T04:58:04.763Z] Running test renaissance-als_0 ... [2025-09-25T04:58:04.763Z] =============================================== [2025-09-25T04:58:05.348Z] renaissance-als_0 Start Time: Thu Sep 25 00:58:04 2025 Epoch Time (ms): 1758776284741 [2025-09-25T04:58:05.348Z] variation: NoOptions [2025-09-25T04:58:05.348Z] JVM_OPTIONS: [2025-09-25T04:58:05.348Z] { \ [2025-09-25T04:58:05.348Z] echo ""; echo "TEST SETUP:"; \ [2025-09-25T04:58:05.348Z] echo "Nothing to be done for setup."; \ [2025-09-25T04:58:05.348Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17587762696023/renaissance-als_0"; \ [2025-09-25T04:58:05.348Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17587762696023/renaissance-als_0"; \ [2025-09-25T04:58:05.348Z] echo ""; echo "TESTING:"; \ [2025-09-25T04:58:05.348Z] "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/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_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17587762696023/renaissance-als_0"/als.json" als; \ [2025-09-25T04:58:05.348Z] 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_0/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17587762696023/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-25T04:58:05.348Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-25T04:58:05.348Z] echo "Nothing to be done for teardown."; \ [2025-09-25T04:58:05.348Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17587762696023/TestTargetResult"; [2025-09-25T04:58:05.972Z] [2025-09-25T04:58:05.972Z] TEST SETUP: [2025-09-25T04:58:05.972Z] Nothing to be done for setup. [2025-09-25T04:58:05.972Z] [2025-09-25T04:58:05.972Z] TESTING: [2025-09-25T04:59:01.542Z] NOTE: 'als' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-09-25T04:59:40.190Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-09-25T04:59:40.190Z] GC before operation: completed in 491.980 ms, heap usage 325.207 MB -> 35.049 MB. [2025-09-25T05:02:13.425Z] ====== als (apache-spark) [default], iteration 0 completed (148718.207 ms) ====== [2025-09-25T05:02:13.425Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-09-25T05:02:13.425Z] GC before operation: completed in 310.273 ms, heap usage 482.691 MB -> 61.978 MB. [2025-09-25T05:03:30.699Z] ====== als (apache-spark) [default], iteration 1 completed (76539.185 ms) ====== [2025-09-25T05:03:30.699Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-09-25T05:03:30.699Z] GC before operation: completed in 597.913 ms, heap usage 498.428 MB -> 62.856 MB. [2025-09-25T05:04:48.223Z] ====== als (apache-spark) [default], iteration 2 completed (69762.831 ms) ====== [2025-09-25T05:04:48.223Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-09-25T05:04:48.223Z] GC before operation: completed in 555.772 ms, heap usage 709.520 MB -> 63.716 MB. [2025-09-25T05:05:53.085Z] ====== als (apache-spark) [default], iteration 3 completed (65048.542 ms) ====== [2025-09-25T05:05:53.085Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-09-25T05:05:53.085Z] GC before operation: completed in 371.491 ms, heap usage 511.719 MB -> 63.930 MB. [2025-09-25T05:07:09.813Z] ====== als (apache-spark) [default], iteration 4 completed (70776.371 ms) ====== [2025-09-25T05:07:09.813Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-09-25T05:07:09.813Z] GC before operation: completed in 402.272 ms, heap usage 869.176 MB -> 64.766 MB. [2025-09-25T05:08:14.368Z] ====== als (apache-spark) [default], iteration 5 completed (65246.547 ms) ====== [2025-09-25T05:08:14.368Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-09-25T05:08:14.368Z] GC before operation: completed in 1453.653 ms, heap usage 810.773 MB -> 65.322 MB. [2025-09-25T05:09:20.940Z] ====== als (apache-spark) [default], iteration 6 completed (71106.813 ms) ====== [2025-09-25T05:09:20.940Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-09-25T05:09:20.940Z] GC before operation: completed in 528.753 ms, heap usage 895.137 MB -> 65.908 MB. [2025-09-25T05:10:25.819Z] ====== als (apache-spark) [default], iteration 7 completed (63305.210 ms) ====== [2025-09-25T05:10:25.819Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-09-25T05:10:26.381Z] GC before operation: completed in 1154.751 ms, heap usage 347.080 MB -> 65.925 MB. [2025-09-25T05:11:31.203Z] ====== als (apache-spark) [default], iteration 8 completed (62104.476 ms) ====== [2025-09-25T05:11:31.203Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-09-25T05:11:31.203Z] GC before operation: completed in 1362.097 ms, heap usage 752.599 MB -> 66.959 MB. [2025-09-25T05:12:47.930Z] ====== als (apache-spark) [default], iteration 9 completed (65587.062 ms) ====== [2025-09-25T05:12:47.930Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-09-25T05:12:47.930Z] GC before operation: completed in 437.911 ms, heap usage 229.855 MB -> 66.381 MB. [2025-09-25T05:13:33.735Z] ====== als (apache-spark) [default], iteration 10 completed (53892.354 ms) ====== [2025-09-25T05:13:33.735Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-09-25T05:13:33.735Z] GC before operation: completed in 354.555 ms, heap usage 252.785 MB -> 66.835 MB. [2025-09-25T05:14:49.837Z] ====== als (apache-spark) [default], iteration 11 completed (65622.583 ms) ====== [2025-09-25T05:14:49.837Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-09-25T05:14:49.837Z] GC before operation: completed in 569.707 ms, heap usage 712.618 MB -> 67.517 MB. [2025-09-25T05:15:54.776Z] ====== als (apache-spark) [default], iteration 12 completed (66595.079 ms) ====== [2025-09-25T05:15:54.776Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-09-25T05:15:54.776Z] GC before operation: completed in 620.911 ms, heap usage 254.253 MB -> 67.409 MB. [2025-09-25T05:17:00.114Z] ====== als (apache-spark) [default], iteration 13 completed (65325.770 ms) ====== [2025-09-25T05:17:00.114Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-09-25T05:17:00.114Z] GC before operation: completed in 576.368 ms, heap usage 1.238 GB -> 68.875 MB. [2025-09-25T05:18:04.409Z] ====== als (apache-spark) [default], iteration 14 completed (61176.447 ms) ====== [2025-09-25T05:18:04.409Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-09-25T05:18:04.409Z] GC before operation: completed in 822.662 ms, heap usage 928.977 MB -> 68.628 MB. [2025-09-25T05:19:07.886Z] ====== als (apache-spark) [default], iteration 15 completed (64855.202 ms) ====== [2025-09-25T05:19:07.886Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-09-25T05:19:07.886Z] GC before operation: completed in 462.316 ms, heap usage 672.044 MB -> 68.730 MB. [2025-09-25T05:20:01.062Z] ====== als (apache-spark) [default], iteration 16 completed (48941.229 ms) ====== [2025-09-25T05:20:01.062Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-09-25T05:20:01.062Z] GC before operation: completed in 407.009 ms, heap usage 1.395 GB -> 69.933 MB. [2025-09-25T05:21:05.659Z] ====== als (apache-spark) [default], iteration 17 completed (59727.834 ms) ====== [2025-09-25T05:21:05.659Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-09-25T05:21:05.659Z] GC before operation: completed in 563.516 ms, heap usage 735.538 MB -> 69.321 MB. [2025-09-25T05:21:49.996Z] ====== als (apache-spark) [default], iteration 18 completed (46167.748 ms) ====== [2025-09-25T05:21:49.996Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-09-25T05:21:49.996Z] GC before operation: completed in 311.390 ms, heap usage 439.770 MB -> 69.146 MB. [2025-09-25T05:22:36.375Z] ====== als (apache-spark) [default], iteration 19 completed (44983.463 ms) ====== [2025-09-25T05:22:36.375Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-09-25T05:22:36.375Z] GC before operation: completed in 782.634 ms, heap usage 710.430 MB -> 69.819 MB. [2025-09-25T05:23:40.572Z] ====== als (apache-spark) [default], iteration 20 completed (58585.123 ms) ====== [2025-09-25T05:23:40.572Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-09-25T05:23:40.572Z] GC before operation: completed in 2005.709 ms, heap usage 516.212 MB -> 69.803 MB. [2025-09-25T05:24:44.288Z] ====== als (apache-spark) [default], iteration 21 completed (60600.654 ms) ====== [2025-09-25T05:24:44.288Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-09-25T05:24:44.288Z] GC before operation: completed in 1592.776 ms, heap usage 994.281 MB -> 70.578 MB. [2025-09-25T05:25:38.879Z] ====== als (apache-spark) [default], iteration 22 completed (54295.423 ms) ====== [2025-09-25T05:25:38.879Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-09-25T05:25:38.879Z] GC before operation: completed in 347.765 ms, heap usage 611.414 MB -> 70.604 MB. [2025-09-25T05:26:32.997Z] ====== als (apache-spark) [default], iteration 23 completed (57088.299 ms) ====== [2025-09-25T05:26:32.997Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-09-25T05:26:32.997Z] GC before operation: completed in 413.994 ms, heap usage 713.594 MB -> 70.910 MB. [2025-09-25T05:27:38.134Z] ====== als (apache-spark) [default], iteration 24 completed (59169.441 ms) ====== [2025-09-25T05:27:38.134Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-09-25T05:27:38.134Z] GC before operation: completed in 348.211 ms, heap usage 464.758 MB -> 70.834 MB. [2025-09-25T05:28:41.731Z] ====== als (apache-spark) [default], iteration 25 completed (66813.658 ms) ====== [2025-09-25T05:28:41.731Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-09-25T05:28:41.731Z] GC before operation: completed in 474.654 ms, heap usage 1.437 GB -> 72.091 MB. [2025-09-25T05:29:57.062Z] ====== als (apache-spark) [default], iteration 26 completed (57944.243 ms) ====== [2025-09-25T05:29:57.062Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-09-25T05:29:57.062Z] GC before operation: completed in 457.276 ms, heap usage 633.504 MB -> 71.649 MB. [2025-09-25T05:30:52.067Z] ====== als (apache-spark) [default], iteration 27 completed (58421.592 ms) ====== [2025-09-25T05:30:52.067Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-09-25T05:30:52.067Z] GC before operation: completed in 440.839 ms, heap usage 416.893 MB -> 71.494 MB. [2025-09-25T05:31:39.182Z] ====== als (apache-spark) [default], iteration 28 completed (50224.887 ms) ====== [2025-09-25T05:31:39.182Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-09-25T05:31:39.182Z] GC before operation: completed in 317.653 ms, heap usage 190.996 MB -> 72.178 MB. [2025-09-25T05:32:43.651Z] ====== als (apache-spark) [default], iteration 29 completed (62608.843 ms) ====== [2025-09-25T05:32:45.648Z] ----------------------------------- [2025-09-25T05:32:45.648Z] renaissance-als_0_PASSED [2025-09-25T05:32:45.648Z] ----------------------------------- [2025-09-25T05:32:45.648Z] [2025-09-25T05:32:45.648Z] TEST TEARDOWN: [2025-09-25T05:32:45.648Z] Nothing to be done for teardown. [2025-09-25T05:32:47.252Z] renaissance-als_0 Finish Time: Thu Sep 25 01:32:46 2025 Epoch Time (ms): 1758778366422