[2025-11-27T03:29:24.916Z] Running test renaissance-als_0 ... [2025-11-27T03:29:24.916Z] =============================================== [2025-11-27T03:29:24.916Z] renaissance-als_0 Start Time: Thu Nov 27 03:29:24 2025 Epoch Time (ms): 1764214164766 [2025-11-27T03:29:24.916Z] variation: NoOptions [2025-11-27T03:29:24.916Z] JVM_OPTIONS: [2025-11-27T03:29:24.916Z] { \ [2025-11-27T03:29:24.916Z] echo ""; echo "TEST SETUP:"; \ [2025-11-27T03:29:24.916Z] echo "Nothing to be done for setup."; \ [2025-11-27T03:29:24.916Z] mkdir -p "/Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17642141647672/renaissance-als_0"; \ [2025-11-27T03:29:24.916Z] cd "/Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17642141647672/renaissance-als_0"; \ [2025-11-27T03:29:24.916Z] echo ""; echo "TESTING:"; \ [2025-11-27T03:29:24.916Z] "/Users/jenkins/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/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17642141647672/renaissance-als_0"/als.json" als; \ [2025-11-27T03:29:24.916Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/..; rm -f -r "/Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17642141647672/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-27T03:29:24.916Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-27T03:29:24.916Z] echo "Nothing to be done for teardown."; \ [2025-11-27T03:29:24.916Z] } 2>&1 | tee -a "/Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17642141647672/TestTargetResult"; [2025-11-27T03:29:24.916Z] [2025-11-27T03:29:24.916Z] TEST SETUP: [2025-11-27T03:29:24.916Z] Nothing to be done for setup. [2025-11-27T03:29:24.916Z] [2025-11-27T03:29:24.916Z] TESTING: [2025-11-27T03:29:32.415Z] NOTE: 'als' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-11-27T03:29:36.370Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-27T03:29:36.370Z] GC before operation: completed in 42.795 ms, heap usage 243.875 MB -> 35.268 MB. [2025-11-27T03:35:16.828Z] ====== als (apache-spark) [default], iteration 0 completed (338746.132 ms) ====== [2025-11-27T03:35:16.828Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-27T03:35:16.828Z] GC before operation: completed in 90.114 ms, heap usage 581.439 MB -> 64.177 MB. [2025-11-27T03:40:57.411Z] ====== als (apache-spark) [default], iteration 1 completed (331478.147 ms) ====== [2025-11-27T03:40:57.411Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-27T03:40:57.411Z] GC before operation: completed in 89.170 ms, heap usage 982.308 MB -> 64.996 MB. [2025-11-27T03:46:22.778Z] ====== als (apache-spark) [default], iteration 2 completed (331060.037 ms) ====== [2025-11-27T03:46:22.778Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-27T03:46:22.778Z] GC before operation: completed in 83.656 ms, heap usage 453.773 MB -> 65.521 MB. [2025-11-27T03:51:49.465Z] ====== als (apache-spark) [default], iteration 3 completed (329907.553 ms) ====== [2025-11-27T03:51:49.465Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-27T03:51:49.465Z] GC before operation: completed in 90.866 ms, heap usage 508.058 MB -> 66.096 MB. [2025-11-27T03:57:29.994Z] ====== als (apache-spark) [default], iteration 4 completed (330303.652 ms) ====== [2025-11-27T03:57:29.994Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-27T03:57:29.994Z] GC before operation: completed in 89.269 ms, heap usage 1.197 GB -> 66.706 MB. [2025-11-27T04:02:55.322Z] ====== als (apache-spark) [default], iteration 5 completed (329708.465 ms) ====== [2025-11-27T04:02:55.322Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-27T04:02:55.322Z] GC before operation: completed in 89.464 ms, heap usage 957.672 MB -> 66.573 MB. [2025-11-27T04:08:20.645Z] ====== als (apache-spark) [default], iteration 6 completed (329548.422 ms) ====== [2025-11-27T04:08:20.645Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-27T04:08:20.645Z] GC before operation: completed in 98.471 ms, heap usage 818.590 MB -> 67.969 MB. [2025-11-27T04:14:01.112Z] ====== als (apache-spark) [default], iteration 7 completed (330202.595 ms) ====== [2025-11-27T04:14:01.112Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-27T04:14:01.112Z] GC before operation: completed in 96.160 ms, heap usage 989.431 MB -> 68.479 MB. [2025-11-27T04:19:26.543Z] ====== als (apache-spark) [default], iteration 8 completed (329997.265 ms) ====== [2025-11-27T04:19:26.544Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-27T04:19:26.544Z] GC before operation: completed in 94.860 ms, heap usage 723.772 MB -> 69.001 MB. [2025-11-27T04:24:51.976Z] ====== als (apache-spark) [default], iteration 9 completed (329006.848 ms) ====== [2025-11-27T04:24:51.976Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-27T04:24:51.976Z] GC before operation: completed in 92.926 ms, heap usage 1010.976 MB -> 69.166 MB. [2025-11-27T04:30:33.089Z] ====== als (apache-spark) [default], iteration 10 completed (335155.243 ms) ====== [2025-11-27T04:30:33.089Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-27T04:30:33.089Z] GC before operation: completed in 94.340 ms, heap usage 839.084 MB -> 67.923 MB. [2025-11-27T04:35:58.420Z] ====== als (apache-spark) [default], iteration 11 completed (328169.181 ms) ====== [2025-11-27T04:35:58.420Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-27T04:35:58.420Z] GC before operation: completed in 103.764 ms, heap usage 1.511 GB -> 66.628 MB. [2025-11-27T04:41:25.994Z] ====== als (apache-spark) [default], iteration 12 completed (328386.306 ms) ====== [2025-11-27T04:41:25.994Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-27T04:41:25.994Z] GC before operation: completed in 99.648 ms, heap usage 1.096 GB -> 66.404 MB. [2025-11-27T04:46:51.336Z] ====== als (apache-spark) [default], iteration 13 completed (327484.881 ms) ====== [2025-11-27T04:46:51.336Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-27T04:46:51.336Z] GC before operation: completed in 96.477 ms, heap usage 632.198 MB -> 66.835 MB. [2025-11-27T04:52:16.739Z] ====== als (apache-spark) [default], iteration 14 completed (327687.256 ms) ====== [2025-11-27T04:52:16.739Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-27T04:52:16.739Z] GC before operation: completed in 88.951 ms, heap usage 642.206 MB -> 67.148 MB. [2025-11-27T04:57:57.236Z] ====== als (apache-spark) [default], iteration 15 completed (326806.078 ms) ====== [2025-11-27T04:57:57.236Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-27T04:57:57.236Z] GC before operation: completed in 99.547 ms, heap usage 1.152 GB -> 67.529 MB. [2025-11-27T05:03:22.571Z] ====== als (apache-spark) [default], iteration 16 completed (326875.169 ms) ====== [2025-11-27T05:03:22.571Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-27T05:03:22.571Z] GC before operation: completed in 94.417 ms, heap usage 671.269 MB -> 67.351 MB. [2025-11-27T05:08:47.943Z] ====== als (apache-spark) [default], iteration 17 completed (326117.658 ms) ====== [2025-11-27T05:08:47.943Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-27T05:08:47.943Z] GC before operation: completed in 98.973 ms, heap usage 943.662 MB -> 68.004 MB. [2025-11-27T05:14:13.378Z] ====== als (apache-spark) [default], iteration 18 completed (324501.539 ms) ====== [2025-11-27T05:14:13.378Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-27T05:14:13.378Z] GC before operation: completed in 95.693 ms, heap usage 864.223 MB -> 68.406 MB. [2025-11-27T05:19:38.680Z] ====== als (apache-spark) [default], iteration 19 completed (324795.356 ms) ====== [2025-11-27T05:19:38.680Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-27T05:19:38.680Z] GC before operation: completed in 96.871 ms, heap usage 831.967 MB -> 68.733 MB. [2025-11-27T05:25:04.141Z] ====== als (apache-spark) [default], iteration 20 completed (324387.787 ms) ====== [2025-11-27T05:25:04.141Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-27T05:25:04.141Z] GC before operation: completed in 95.587 ms, heap usage 904.745 MB -> 68.848 MB. [2025-11-27T05:30:29.789Z] ====== als (apache-spark) [default], iteration 21 completed (323937.573 ms) ====== [2025-11-27T05:30:29.789Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-27T05:30:29.789Z] GC before operation: completed in 95.412 ms, heap usage 732.028 MB -> 67.681 MB. [2025-11-27T05:35:40.078Z] ====== als (apache-spark) [default], iteration 22 completed (323492.723 ms) ====== [2025-11-27T05:35:40.078Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-27T05:35:40.078Z] GC before operation: completed in 97.545 ms, heap usage 352.864 MB -> 69.420 MB. [2025-11-27T05:41:05.407Z] ====== als (apache-spark) [default], iteration 23 completed (324971.517 ms) ====== [2025-11-27T05:41:05.407Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-27T05:41:05.407Z] GC before operation: completed in 107.056 ms, heap usage 1.563 GB -> 69.792 MB. [2025-11-27T05:46:30.774Z] ====== als (apache-spark) [default], iteration 24 completed (325928.788 ms) ====== [2025-11-27T05:46:30.774Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-27T05:46:30.774Z] GC before operation: completed in 96.304 ms, heap usage 906.246 MB -> 70.024 MB. [2025-11-27T05:51:56.150Z] ====== als (apache-spark) [default], iteration 25 completed (323999.424 ms) ====== [2025-11-27T05:51:56.150Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-27T05:51:56.150Z] GC before operation: completed in 96.483 ms, heap usage 1.385 GB -> 70.269 MB. [2025-11-27T05:57:21.510Z] ====== als (apache-spark) [default], iteration 26 completed (325232.962 ms) ====== [2025-11-27T05:57:21.510Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-27T05:57:21.510Z] GC before operation: completed in 95.633 ms, heap usage 1.223 GB -> 70.445 MB. [2025-11-27T06:02:47.075Z] ====== als (apache-spark) [default], iteration 27 completed (325961.601 ms) ====== [2025-11-27T06:02:47.075Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-27T06:02:47.075Z] GC before operation: completed in 91.697 ms, heap usage 254.423 MB -> 69.522 MB. [2025-11-27T06:08:27.710Z] ====== als (apache-spark) [default], iteration 28 completed (326656.568 ms) ====== [2025-11-27T06:08:27.710Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-27T06:08:27.710Z] GC before operation: completed in 96.768 ms, heap usage 861.188 MB -> 70.068 MB. [2025-11-27T06:13:53.175Z] ====== als (apache-spark) [default], iteration 29 completed (327754.555 ms) ====== [2025-11-27T06:13:53.175Z] ----------------------------------- [2025-11-27T06:13:53.175Z] renaissance-als_0_PASSED [2025-11-27T06:13:53.175Z] ----------------------------------- [2025-11-27T06:13:53.175Z] [2025-11-27T06:13:53.175Z] TEST TEARDOWN: [2025-11-27T06:13:53.175Z] Nothing to be done for teardown. [2025-11-27T06:13:53.175Z] renaissance-als_0 Finish Time: Thu Nov 27 06:13:41 2025 Epoch Time (ms): 1764224021600