[2025-06-29T20:01:32.430Z] Running test renaissance-als_0 ... [2025-06-29T20:01:33.220Z] =============================================== [2025-06-29T20:01:33.220Z] renaissance-als_0 Start Time: Sun Jun 29 13:01:32 2025 Epoch Time (ms): 1751227292934 [2025-06-29T20:01:34.033Z] variation: NoOptions [2025-06-29T20:01:34.033Z] JVM_OPTIONS: [2025-06-29T20:01:34.033Z] { \ [2025-06-29T20:01:34.033Z] echo ""; echo "TEST SETUP:"; \ [2025-06-29T20:01:34.033Z] echo "Nothing to be done for setup."; \ [2025-06-29T20:01:34.033Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17512272867334/renaissance-als_0"; \ [2025-06-29T20:01:34.033Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17512272867334/renaissance-als_0"; \ [2025-06-29T20:01:34.033Z] echo ""; echo "TESTING:"; \ [2025-06-29T20:01:34.033Z] "/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_17512272867334/renaissance-als_0"/als.json" als; \ [2025-06-29T20:01:34.033Z] 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_17512272867334/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-29T20:01:34.033Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-29T20:01:34.033Z] echo "Nothing to be done for teardown."; \ [2025-06-29T20:01:34.033Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17512272867334/TestTargetResult"; [2025-06-29T20:01:34.033Z] [2025-06-29T20:01:34.033Z] TEST SETUP: [2025-06-29T20:01:34.033Z] Nothing to be done for setup. [2025-06-29T20:01:34.033Z] [2025-06-29T20:01:34.033Z] TESTING: [2025-06-29T20:02:00.696Z] NOTE: 'als' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-06-29T20:02:11.346Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-29T20:02:11.346Z] GC before operation: completed in 294.834 ms, heap usage 329.912 MB -> 35.203 MB. [2025-06-29T20:03:25.271Z] ====== als (apache-spark) [default], iteration 0 completed (71109.630 ms) ====== [2025-06-29T20:03:25.271Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-29T20:03:25.271Z] GC before operation: completed in 229.000 ms, heap usage 1.163 GB -> 63.047 MB. [2025-06-29T20:04:09.328Z] ====== als (apache-spark) [default], iteration 1 completed (38422.379 ms) ====== [2025-06-29T20:04:09.328Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-29T20:04:09.328Z] GC before operation: completed in 331.899 ms, heap usage 601.030 MB -> 63.589 MB. [2025-06-29T20:04:40.651Z] ====== als (apache-spark) [default], iteration 2 completed (33528.288 ms) ====== [2025-06-29T20:04:40.651Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-29T20:04:40.651Z] GC before operation: completed in 355.589 ms, heap usage 210.866 MB -> 63.484 MB. [2025-06-29T20:05:12.533Z] ====== als (apache-spark) [default], iteration 3 completed (30597.591 ms) ====== [2025-06-29T20:05:12.533Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-29T20:05:12.533Z] GC before operation: completed in 293.083 ms, heap usage 1.043 GB -> 65.116 MB. [2025-06-29T20:05:43.986Z] ====== als (apache-spark) [default], iteration 4 completed (32754.223 ms) ====== [2025-06-29T20:05:43.986Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-29T20:05:43.986Z] GC before operation: completed in 163.556 ms, heap usage 509.586 MB -> 65.082 MB. [2025-06-29T20:06:21.001Z] ====== als (apache-spark) [default], iteration 5 completed (35341.551 ms) ====== [2025-06-29T20:06:21.001Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-29T20:06:21.001Z] GC before operation: completed in 376.782 ms, heap usage 157.168 MB -> 64.775 MB. [2025-06-29T20:06:58.497Z] ====== als (apache-spark) [default], iteration 6 completed (34085.389 ms) ====== [2025-06-29T20:06:58.497Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-29T20:06:58.497Z] GC before operation: completed in 299.657 ms, heap usage 1.181 GB -> 67.877 MB. [2025-06-29T20:07:34.720Z] ====== als (apache-spark) [default], iteration 7 completed (36741.015 ms) ====== [2025-06-29T20:07:34.720Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-29T20:07:34.720Z] GC before operation: completed in 268.963 ms, heap usage 1.201 GB -> 67.434 MB. [2025-06-29T20:08:05.052Z] ====== als (apache-spark) [default], iteration 8 completed (30934.866 ms) ====== [2025-06-29T20:08:05.052Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-29T20:08:05.052Z] GC before operation: completed in 218.771 ms, heap usage 221.079 MB -> 66.950 MB. [2025-06-29T20:08:35.313Z] ====== als (apache-spark) [default], iteration 9 completed (29483.663 ms) ====== [2025-06-29T20:08:35.313Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-29T20:08:35.313Z] GC before operation: completed in 375.283 ms, heap usage 701.824 MB -> 67.738 MB. [2025-06-29T20:09:05.740Z] ====== als (apache-spark) [default], iteration 10 completed (28928.487 ms) ====== [2025-06-29T20:09:05.740Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-29T20:09:05.740Z] GC before operation: completed in 320.433 ms, heap usage 517.929 MB -> 67.678 MB. [2025-06-29T20:09:36.772Z] ====== als (apache-spark) [default], iteration 11 completed (30994.983 ms) ====== [2025-06-29T20:09:36.772Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-29T20:09:36.772Z] GC before operation: completed in 215.757 ms, heap usage 463.347 MB -> 68.165 MB. [2025-06-29T20:10:13.488Z] ====== als (apache-spark) [default], iteration 12 completed (34303.474 ms) ====== [2025-06-29T20:10:13.488Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-29T20:10:13.488Z] GC before operation: completed in 369.050 ms, heap usage 740.080 MB -> 68.568 MB. [2025-06-29T20:10:44.252Z] ====== als (apache-spark) [default], iteration 13 completed (31925.597 ms) ====== [2025-06-29T20:10:44.252Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-29T20:10:44.252Z] GC before operation: completed in 256.474 ms, heap usage 288.871 MB -> 68.222 MB. [2025-06-29T20:11:14.967Z] ====== als (apache-spark) [default], iteration 14 completed (31349.494 ms) ====== [2025-06-29T20:11:14.967Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-29T20:11:16.102Z] GC before operation: completed in 228.424 ms, heap usage 102.274 MB -> 69.422 MB. [2025-06-29T20:11:52.804Z] ====== als (apache-spark) [default], iteration 15 completed (33968.815 ms) ====== [2025-06-29T20:11:52.804Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-29T20:11:52.804Z] GC before operation: completed in 350.859 ms, heap usage 782.402 MB -> 69.447 MB. [2025-06-29T20:12:23.712Z] ====== als (apache-spark) [default], iteration 16 completed (29393.538 ms) ====== [2025-06-29T20:12:23.712Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-29T20:12:23.712Z] GC before operation: completed in 354.428 ms, heap usage 1.122 GB -> 69.896 MB. [2025-06-29T20:12:55.032Z] ====== als (apache-spark) [default], iteration 17 completed (31898.914 ms) ====== [2025-06-29T20:12:55.032Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-29T20:12:55.032Z] GC before operation: completed in 288.080 ms, heap usage 1.098 GB -> 70.188 MB. [2025-06-29T20:13:25.725Z] ====== als (apache-spark) [default], iteration 18 completed (30196.344 ms) ====== [2025-06-29T20:13:25.725Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-29T20:13:25.725Z] GC before operation: completed in 235.225 ms, heap usage 1.185 GB -> 70.583 MB. [2025-06-29T20:13:55.910Z] ====== als (apache-spark) [default], iteration 19 completed (29074.064 ms) ====== [2025-06-29T20:13:55.910Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-29T20:13:55.910Z] GC before operation: completed in 165.045 ms, heap usage 1.108 GB -> 70.661 MB. [2025-06-29T20:14:26.202Z] ====== als (apache-spark) [default], iteration 20 completed (28923.898 ms) ====== [2025-06-29T20:14:26.202Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-29T20:14:26.202Z] GC before operation: completed in 215.604 ms, heap usage 480.028 MB -> 70.258 MB. [2025-06-29T20:14:56.830Z] ====== als (apache-spark) [default], iteration 21 completed (30516.970 ms) ====== [2025-06-29T20:14:56.830Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-29T20:14:56.830Z] GC before operation: completed in 303.581 ms, heap usage 374.800 MB -> 70.314 MB. [2025-06-29T20:15:27.499Z] ====== als (apache-spark) [default], iteration 22 completed (28067.021 ms) ====== [2025-06-29T20:15:27.499Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-29T20:15:27.499Z] GC before operation: completed in 350.946 ms, heap usage 790.324 MB -> 71.197 MB. [2025-06-29T20:15:58.907Z] ====== als (apache-spark) [default], iteration 23 completed (31887.986 ms) ====== [2025-06-29T20:15:58.907Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-29T20:15:58.907Z] GC before operation: completed in 343.926 ms, heap usage 255.258 MB -> 70.783 MB. [2025-06-29T20:16:35.060Z] ====== als (apache-spark) [default], iteration 24 completed (34196.734 ms) ====== [2025-06-29T20:16:35.060Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-29T20:16:35.060Z] GC before operation: completed in 443.294 ms, heap usage 805.672 MB -> 71.665 MB. [2025-06-29T20:17:06.292Z] ====== als (apache-spark) [default], iteration 25 completed (31873.512 ms) ====== [2025-06-29T20:17:06.292Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-29T20:17:06.292Z] GC before operation: completed in 257.347 ms, heap usage 198.124 MB -> 71.361 MB. [2025-06-29T20:17:42.290Z] ====== als (apache-spark) [default], iteration 26 completed (32443.636 ms) ====== [2025-06-29T20:17:42.290Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-29T20:17:42.290Z] GC before operation: completed in 324.719 ms, heap usage 196.227 MB -> 71.567 MB. [2025-06-29T20:18:03.870Z] ====== als (apache-spark) [default], iteration 27 completed (25660.799 ms) ====== [2025-06-29T20:18:03.871Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-29T20:18:03.871Z] GC before operation: completed in 135.937 ms, heap usage 123.647 MB -> 72.971 MB. [2025-06-29T20:18:29.813Z] ====== als (apache-spark) [default], iteration 28 completed (23008.045 ms) ====== [2025-06-29T20:18:29.813Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-29T20:18:29.813Z] GC before operation: completed in 212.334 ms, heap usage 1.256 GB -> 72.804 MB. [2025-06-29T20:18:55.598Z] ====== als (apache-spark) [default], iteration 29 completed (26383.656 ms) ====== [2025-06-29T20:18:55.598Z] ----------------------------------- [2025-06-29T20:18:55.598Z] renaissance-als_0_PASSED [2025-06-29T20:18:55.598Z] ----------------------------------- [2025-06-29T20:18:55.598Z] [2025-06-29T20:18:55.598Z] TEST TEARDOWN: [2025-06-29T20:18:55.598Z] Nothing to be done for teardown. [2025-06-29T20:18:55.598Z] renaissance-als_0 Finish Time: Sun Jun 29 13:18:54 2025 Epoch Time (ms): 1751228334796