[2025-12-03T22:17:51.742Z] Running test renaissance-als_0 ... [2025-12-03T22:17:51.742Z] =============================================== [2025-12-03T22:17:51.742Z] renaissance-als_0 Start Time: Wed Dec 3 14:17:51 2025 Epoch Time (ms): 1764800271398 [2025-12-03T22:17:51.742Z] variation: NoOptions [2025-12-03T22:17:51.742Z] JVM_OPTIONS: [2025-12-03T22:17:51.742Z] { \ [2025-12-03T22:17:51.742Z] echo ""; echo "TEST SETUP:"; \ [2025-12-03T22:17:51.742Z] echo "Nothing to be done for setup."; \ [2025-12-03T22:17:51.742Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1764800269149/renaissance-als_0"; \ [2025-12-03T22:17:51.742Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1764800269149/renaissance-als_0"; \ [2025-12-03T22:17:51.742Z] echo ""; echo "TESTING:"; \ [2025-12-03T22:17:51.742Z] "/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_1764800269149/renaissance-als_0"/als.json" als; \ [2025-12-03T22:17:51.742Z] 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_1764800269149/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-03T22:17:51.742Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-03T22:17:51.742Z] echo "Nothing to be done for teardown."; \ [2025-12-03T22:17:51.742Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1764800269149/TestTargetResult"; [2025-12-03T22:17:51.742Z] [2025-12-03T22:17:51.742Z] TEST SETUP: [2025-12-03T22:17:51.742Z] Nothing to be done for setup. [2025-12-03T22:17:51.742Z] [2025-12-03T22:17:51.742Z] TESTING: [2025-12-03T22:18:10.211Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-03T22:18:17.061Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-03T22:18:17.560Z] GC before operation: completed in 77.824 ms, heap usage 443.780 MB -> 34.082 MB. [2025-12-03T22:18:55.284Z] ====== als (apache-spark) [default], iteration 0 completed (33376.755 ms) ====== [2025-12-03T22:18:55.284Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-03T22:18:55.284Z] GC before operation: completed in 58.474 ms, heap usage 277.055 MB -> 61.028 MB. [2025-12-03T22:19:10.039Z] ====== als (apache-spark) [default], iteration 1 completed (17864.096 ms) ====== [2025-12-03T22:19:10.039Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-03T22:19:10.039Z] GC before operation: completed in 79.888 ms, heap usage 146.990 MB -> 61.653 MB. [2025-12-03T22:19:40.809Z] ====== als (apache-spark) [default], iteration 2 completed (28979.123 ms) ====== [2025-12-03T22:19:40.809Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-03T22:19:40.809Z] GC before operation: completed in 162.370 ms, heap usage 153.905 MB -> 62.228 MB. [2025-12-03T22:20:11.442Z] ====== als (apache-spark) [default], iteration 3 completed (29776.718 ms) ====== [2025-12-03T22:20:11.442Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-03T22:20:11.442Z] GC before operation: completed in 147.770 ms, heap usage 775.906 MB -> 63.750 MB. [2025-12-03T22:20:37.343Z] ====== als (apache-spark) [default], iteration 4 completed (24391.734 ms) ====== [2025-12-03T22:20:37.343Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-03T22:20:37.343Z] GC before operation: completed in 103.777 ms, heap usage 352.529 MB -> 63.721 MB. [2025-12-03T22:21:02.830Z] ====== als (apache-spark) [default], iteration 5 completed (25996.266 ms) ====== [2025-12-03T22:21:02.830Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-03T22:21:02.830Z] GC before operation: completed in 290.737 ms, heap usage 156.075 MB -> 64.174 MB. [2025-12-03T22:21:39.406Z] ====== als (apache-spark) [default], iteration 6 completed (32609.093 ms) ====== [2025-12-03T22:21:39.406Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-03T22:21:39.406Z] GC before operation: completed in 235.775 ms, heap usage 353.792 MB -> 64.672 MB. [2025-12-03T22:22:05.827Z] ====== als (apache-spark) [default], iteration 7 completed (30142.643 ms) ====== [2025-12-03T22:22:05.827Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-03T22:22:06.346Z] GC before operation: completed in 275.427 ms, heap usage 133.363 MB -> 65.053 MB. [2025-12-03T22:22:37.340Z] ====== als (apache-spark) [default], iteration 8 completed (29092.319 ms) ====== [2025-12-03T22:22:37.340Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-03T22:22:37.340Z] GC before operation: completed in 121.880 ms, heap usage 157.895 MB -> 65.505 MB. [2025-12-03T22:23:03.541Z] ====== als (apache-spark) [default], iteration 9 completed (24876.897 ms) ====== [2025-12-03T22:23:03.541Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-03T22:23:03.541Z] GC before operation: completed in 282.771 ms, heap usage 357.821 MB -> 66.381 MB. [2025-12-03T22:23:25.605Z] ====== als (apache-spark) [default], iteration 10 completed (23298.972 ms) ====== [2025-12-03T22:23:25.605Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-03T22:23:25.605Z] GC before operation: completed in 315.126 ms, heap usage 469.854 MB -> 66.851 MB. [2025-12-03T22:23:52.094Z] ====== als (apache-spark) [default], iteration 11 completed (24145.451 ms) ====== [2025-12-03T22:23:52.094Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-03T22:23:52.094Z] GC before operation: completed in 330.191 ms, heap usage 352.139 MB -> 66.924 MB. [2025-12-03T22:24:23.127Z] ====== als (apache-spark) [default], iteration 12 completed (28500.706 ms) ====== [2025-12-03T22:24:23.127Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-03T22:24:23.127Z] GC before operation: completed in 655.723 ms, heap usage 497.925 MB -> 67.267 MB. [2025-12-03T22:24:44.958Z] ====== als (apache-spark) [default], iteration 13 completed (24309.772 ms) ====== [2025-12-03T22:24:44.958Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-03T22:24:44.958Z] GC before operation: completed in 208.776 ms, heap usage 892.492 MB -> 68.059 MB. [2025-12-03T22:25:11.368Z] ====== als (apache-spark) [default], iteration 14 completed (26038.365 ms) ====== [2025-12-03T22:25:11.368Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-03T22:25:11.368Z] GC before operation: completed in 246.765 ms, heap usage 921.153 MB -> 68.561 MB. [2025-12-03T22:25:37.888Z] ====== als (apache-spark) [default], iteration 15 completed (23277.590 ms) ====== [2025-12-03T22:25:37.888Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-03T22:25:37.888Z] GC before operation: completed in 204.968 ms, heap usage 741.789 MB -> 68.539 MB. [2025-12-03T22:25:59.770Z] ====== als (apache-spark) [default], iteration 16 completed (23025.687 ms) ====== [2025-12-03T22:25:59.770Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-03T22:25:59.770Z] GC before operation: completed in 243.859 ms, heap usage 1001.343 MB -> 69.181 MB. [2025-12-03T22:26:25.829Z] ====== als (apache-spark) [default], iteration 17 completed (24781.093 ms) ====== [2025-12-03T22:26:25.829Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-03T22:26:25.829Z] GC before operation: completed in 225.607 ms, heap usage 1.120 GB -> 69.560 MB. [2025-12-03T22:26:52.433Z] ====== als (apache-spark) [default], iteration 18 completed (23454.806 ms) ====== [2025-12-03T22:26:52.433Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-03T22:26:52.433Z] GC before operation: completed in 322.013 ms, heap usage 896.897 MB -> 69.399 MB. [2025-12-03T22:27:14.700Z] ====== als (apache-spark) [default], iteration 19 completed (23641.772 ms) ====== [2025-12-03T22:27:14.700Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-03T22:27:14.700Z] GC before operation: completed in 201.025 ms, heap usage 510.472 MB -> 69.118 MB. [2025-12-03T22:27:40.622Z] ====== als (apache-spark) [default], iteration 20 completed (25030.884 ms) ====== [2025-12-03T22:27:40.622Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-03T22:27:40.622Z] GC before operation: completed in 239.087 ms, heap usage 513.054 MB -> 69.507 MB. [2025-12-03T22:28:06.483Z] ====== als (apache-spark) [default], iteration 21 completed (26169.547 ms) ====== [2025-12-03T22:28:06.483Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-03T22:28:06.483Z] GC before operation: completed in 324.223 ms, heap usage 888.459 MB -> 70.098 MB. [2025-12-03T22:28:36.664Z] ====== als (apache-spark) [default], iteration 22 completed (30095.431 ms) ====== [2025-12-03T22:28:36.664Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-03T22:28:36.664Z] GC before operation: completed in 159.355 ms, heap usage 380.642 MB -> 74.049 MB. [2025-12-03T22:29:08.083Z] ====== als (apache-spark) [default], iteration 23 completed (31040.108 ms) ====== [2025-12-03T22:29:08.083Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-03T22:29:08.083Z] GC before operation: completed in 378.197 ms, heap usage 184.208 MB -> 69.553 MB. [2025-12-03T22:29:45.425Z] ====== als (apache-spark) [default], iteration 24 completed (32977.842 ms) ====== [2025-12-03T22:29:45.425Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-03T22:29:45.425Z] GC before operation: completed in 214.167 ms, heap usage 524.132 MB -> 70.418 MB. [2025-12-03T22:30:15.799Z] ====== als (apache-spark) [default], iteration 25 completed (31324.731 ms) ====== [2025-12-03T22:30:15.799Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-03T22:30:15.799Z] GC before operation: completed in 199.803 ms, heap usage 593.694 MB -> 70.770 MB. [2025-12-03T22:30:46.805Z] ====== als (apache-spark) [default], iteration 26 completed (32040.028 ms) ====== [2025-12-03T22:30:46.805Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-03T22:30:46.805Z] GC before operation: completed in 242.407 ms, heap usage 1.169 GB -> 71.694 MB. [2025-12-03T22:31:23.305Z] ====== als (apache-spark) [default], iteration 27 completed (32441.128 ms) ====== [2025-12-03T22:31:23.305Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-03T22:31:23.305Z] GC before operation: completed in 458.120 ms, heap usage 1.027 GB -> 72.203 MB. [2025-12-03T22:32:00.548Z] ====== als (apache-spark) [default], iteration 28 completed (35406.884 ms) ====== [2025-12-03T22:32:00.548Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-03T22:32:00.548Z] GC before operation: completed in 256.983 ms, heap usage 1.184 GB -> 72.127 MB. [2025-12-03T22:32:32.795Z] ====== als (apache-spark) [default], iteration 29 completed (32342.347 ms) ====== [2025-12-03T22:32:32.795Z] ----------------------------------- [2025-12-03T22:32:32.795Z] renaissance-als_0_PASSED [2025-12-03T22:32:32.795Z] ----------------------------------- [2025-12-03T22:32:32.795Z] [2025-12-03T22:32:32.795Z] TEST TEARDOWN: [2025-12-03T22:32:32.795Z] Nothing to be done for teardown. [2025-12-03T22:32:32.795Z] renaissance-als_0 Finish Time: Wed Dec 3 14:32:30 2025 Epoch Time (ms): 1764801150517