[2025-05-28T21:14:59.585Z] Running test renaissance-als_0 ... [2025-05-28T21:14:59.585Z] =============================================== [2025-05-28T21:14:59.585Z] renaissance-als_0 Start Time: Wed May 28 14:14:59 2025 Epoch Time (ms): 1748466899481 [2025-05-28T21:14:59.999Z] variation: NoOptions [2025-05-28T21:14:59.999Z] JVM_OPTIONS: [2025-05-28T21:14:59.999Z] { \ [2025-05-28T21:14:59.999Z] echo ""; echo "TEST SETUP:"; \ [2025-05-28T21:14:59.999Z] echo "Nothing to be done for setup."; \ [2025-05-28T21:14:59.999Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17484668972134/renaissance-als_0"; \ [2025-05-28T21:14:59.999Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17484668972134/renaissance-als_0"; \ [2025-05-28T21:14:59.999Z] echo ""; echo "TESTING:"; \ [2025-05-28T21:14:59.999Z] "/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_17484668972134/renaissance-als_0"/als.json" als; \ [2025-05-28T21:14:59.999Z] 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_17484668972134/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-28T21:14:59.999Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-28T21:14:59.999Z] echo "Nothing to be done for teardown."; \ [2025-05-28T21:14:59.999Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_0/aqa-tests/TKG/../TKG/output_17484668972134/TestTargetResult"; [2025-05-28T21:14:59.999Z] [2025-05-28T21:14:59.999Z] TEST SETUP: [2025-05-28T21:14:59.999Z] Nothing to be done for setup. [2025-05-28T21:14:59.999Z] [2025-05-28T21:14:59.999Z] TESTING: [2025-05-28T21:15:17.324Z] NOTE: 'als' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-05-28T21:15:27.721Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-05-28T21:15:27.721Z] GC before operation: completed in 109.908 ms, heap usage 270.000 MB -> 34.933 MB. [2025-05-28T21:16:40.393Z] ====== als (apache-spark) [default], iteration 0 completed (63200.258 ms) ====== [2025-05-28T21:16:40.393Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-05-28T21:16:40.393Z] GC before operation: completed in 577.597 ms, heap usage 496.574 MB -> 61.965 MB. [2025-05-28T21:17:06.261Z] ====== als (apache-spark) [default], iteration 1 completed (32840.733 ms) ====== [2025-05-28T21:17:06.261Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-05-28T21:17:06.261Z] GC before operation: completed in 159.421 ms, heap usage 775.633 MB -> 63.351 MB. [2025-05-28T21:17:36.598Z] ====== als (apache-spark) [default], iteration 2 completed (28271.683 ms) ====== [2025-05-28T21:17:36.598Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-05-28T21:17:36.598Z] GC before operation: completed in 225.026 ms, heap usage 1.208 GB -> 64.315 MB. [2025-05-28T21:18:02.028Z] ====== als (apache-spark) [default], iteration 3 completed (26862.945 ms) ====== [2025-05-28T21:18:02.028Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-05-28T21:18:02.028Z] GC before operation: completed in 205.863 ms, heap usage 632.608 MB -> 64.332 MB. [2025-05-28T21:18:27.781Z] ====== als (apache-spark) [default], iteration 4 completed (25403.178 ms) ====== [2025-05-28T21:18:27.781Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-05-28T21:18:28.151Z] GC before operation: completed in 279.020 ms, heap usage 1.062 GB -> 65.443 MB. [2025-05-28T21:18:58.318Z] ====== als (apache-spark) [default], iteration 5 completed (25271.707 ms) ====== [2025-05-28T21:18:58.318Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-05-28T21:18:58.318Z] GC before operation: completed in 220.465 ms, heap usage 252.533 MB -> 65.051 MB. [2025-05-28T21:19:19.922Z] ====== als (apache-spark) [default], iteration 6 completed (24439.936 ms) ====== [2025-05-28T21:19:19.922Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-05-28T21:19:19.922Z] GC before operation: completed in 201.785 ms, heap usage 703.133 MB -> 66.144 MB. [2025-05-28T21:19:45.377Z] ====== als (apache-spark) [default], iteration 7 completed (24413.218 ms) ====== [2025-05-28T21:19:45.377Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-05-28T21:19:45.377Z] GC before operation: completed in 218.890 ms, heap usage 160.041 MB -> 66.603 MB. [2025-05-28T21:20:15.640Z] ====== als (apache-spark) [default], iteration 8 completed (26048.393 ms) ====== [2025-05-28T21:20:15.640Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-05-28T21:20:15.640Z] GC before operation: completed in 231.289 ms, heap usage 614.895 MB -> 67.126 MB. [2025-05-28T21:20:41.514Z] ====== als (apache-spark) [default], iteration 9 completed (29252.812 ms) ====== [2025-05-28T21:20:41.514Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-05-28T21:20:42.084Z] GC before operation: completed in 459.456 ms, heap usage 1.079 GB -> 67.874 MB. [2025-05-28T21:21:12.000Z] ====== als (apache-spark) [default], iteration 10 completed (27256.670 ms) ====== [2025-05-28T21:21:12.000Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-05-28T21:21:12.000Z] GC before operation: completed in 212.951 ms, heap usage 785.693 MB -> 67.804 MB. [2025-05-28T21:21:37.666Z] ====== als (apache-spark) [default], iteration 11 completed (25440.929 ms) ====== [2025-05-28T21:21:37.666Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-05-28T21:21:37.666Z] GC before operation: completed in 243.349 ms, heap usage 182.263 MB -> 67.311 MB. [2025-05-28T21:22:03.544Z] ====== als (apache-spark) [default], iteration 12 completed (25749.949 ms) ====== [2025-05-28T21:22:03.544Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-05-28T21:22:03.544Z] GC before operation: completed in 173.168 ms, heap usage 327.258 MB -> 67.704 MB. [2025-05-28T21:22:25.268Z] ====== als (apache-spark) [default], iteration 13 completed (21383.629 ms) ====== [2025-05-28T21:22:25.268Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-05-28T21:22:25.268Z] GC before operation: completed in 154.520 ms, heap usage 275.124 MB -> 67.914 MB. [2025-05-28T21:22:50.243Z] ====== als (apache-spark) [default], iteration 14 completed (25083.788 ms) ====== [2025-05-28T21:22:50.243Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-05-28T21:22:50.695Z] GC before operation: completed in 295.886 ms, heap usage 467.843 MB -> 68.501 MB. [2025-05-28T21:23:21.010Z] ====== als (apache-spark) [default], iteration 15 completed (26783.208 ms) ====== [2025-05-28T21:23:21.010Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-05-28T21:23:21.010Z] GC before operation: completed in 238.683 ms, heap usage 776.343 MB -> 69.098 MB. [2025-05-28T21:23:42.582Z] ====== als (apache-spark) [default], iteration 16 completed (23633.123 ms) ====== [2025-05-28T21:23:42.582Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-05-28T21:23:42.582Z] GC before operation: completed in 209.048 ms, heap usage 613.189 MB -> 69.274 MB. [2025-05-28T21:24:08.300Z] ====== als (apache-spark) [default], iteration 17 completed (22087.784 ms) ====== [2025-05-28T21:24:08.300Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-05-28T21:24:08.300Z] GC before operation: completed in 181.558 ms, heap usage 915.541 MB -> 69.806 MB. [2025-05-28T21:24:29.340Z] ====== als (apache-spark) [default], iteration 18 completed (23706.995 ms) ====== [2025-05-28T21:24:29.340Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-05-28T21:24:29.340Z] GC before operation: completed in 160.499 ms, heap usage 913.479 MB -> 69.997 MB. [2025-05-28T21:24:54.775Z] ====== als (apache-spark) [default], iteration 19 completed (22749.745 ms) ====== [2025-05-28T21:24:54.775Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-05-28T21:24:54.775Z] GC before operation: completed in 211.071 ms, heap usage 718.005 MB -> 70.105 MB. [2025-05-28T21:25:20.178Z] ====== als (apache-spark) [default], iteration 20 completed (25392.156 ms) ====== [2025-05-28T21:25:20.179Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-05-28T21:25:20.179Z] GC before operation: completed in 255.115 ms, heap usage 361.324 MB -> 69.944 MB. [2025-05-28T21:25:41.475Z] ====== als (apache-spark) [default], iteration 21 completed (22009.621 ms) ====== [2025-05-28T21:25:41.475Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-05-28T21:25:41.475Z] GC before operation: completed in 173.050 ms, heap usage 786.051 MB -> 70.711 MB. [2025-05-28T21:26:06.680Z] ====== als (apache-spark) [default], iteration 22 completed (24110.099 ms) ====== [2025-05-28T21:26:06.680Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-05-28T21:26:06.680Z] GC before operation: completed in 249.509 ms, heap usage 223.866 MB -> 70.284 MB. [2025-05-28T21:26:28.621Z] ====== als (apache-spark) [default], iteration 23 completed (22828.046 ms) ====== [2025-05-28T21:26:28.621Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-05-28T21:26:28.621Z] GC before operation: completed in 113.426 ms, heap usage 211.210 MB -> 70.503 MB. [2025-05-28T21:26:49.832Z] ====== als (apache-spark) [default], iteration 24 completed (19644.014 ms) ====== [2025-05-28T21:26:49.832Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-05-28T21:26:49.832Z] GC before operation: completed in 186.726 ms, heap usage 679.344 MB -> 71.464 MB. [2025-05-28T21:27:15.434Z] ====== als (apache-spark) [default], iteration 25 completed (23328.301 ms) ====== [2025-05-28T21:27:15.434Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-05-28T21:27:15.434Z] GC before operation: completed in 238.027 ms, heap usage 312.240 MB -> 71.132 MB. [2025-05-28T21:27:41.377Z] ====== als (apache-spark) [default], iteration 26 completed (25525.844 ms) ====== [2025-05-28T21:27:41.377Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-05-28T21:27:41.377Z] GC before operation: completed in 209.570 ms, heap usage 1.014 GB -> 72.366 MB. [2025-05-28T21:28:02.836Z] ====== als (apache-spark) [default], iteration 27 completed (22737.491 ms) ====== [2025-05-28T21:28:02.836Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-05-28T21:28:02.836Z] GC before operation: completed in 195.175 ms, heap usage 307.784 MB -> 71.706 MB. [2025-05-28T21:28:24.044Z] ====== als (apache-spark) [default], iteration 28 completed (18943.219 ms) ====== [2025-05-28T21:28:24.044Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-05-28T21:28:24.044Z] GC before operation: completed in 194.550 ms, heap usage 328.019 MB -> 71.447 MB. [2025-05-28T21:28:53.844Z] ====== als (apache-spark) [default], iteration 29 completed (27963.634 ms) ====== [2025-05-28T21:28:53.844Z] ----------------------------------- [2025-05-28T21:28:53.844Z] renaissance-als_0_PASSED [2025-05-28T21:28:53.844Z] ----------------------------------- [2025-05-28T21:28:53.844Z] [2025-05-28T21:28:53.844Z] TEST TEARDOWN: [2025-05-28T21:28:53.844Z] Nothing to be done for teardown. [2025-05-28T21:28:53.844Z] renaissance-als_0 Finish Time: Wed May 28 14:28:50 2025 Epoch Time (ms): 1748467730755