[2026-04-22T14:38:34.780Z] Running test renaissance-als_0 ... [2026-04-22T14:38:34.780Z] =============================================== [2026-04-22T14:38:34.780Z] renaissance-als_0 Start Time: Wed Apr 22 14:38:34 2026 Epoch Time (ms): 1776868714118 [2026-04-22T14:38:35.445Z] variation: NoOptions [2026-04-22T14:38:35.445Z] JVM_OPTIONS: [2026-04-22T14:38:35.445Z] { \ [2026-04-22T14:38:35.445Z] echo ""; echo "TEST SETUP:"; \ [2026-04-22T14:38:35.445Z] echo "Nothing to be done for setup."; \ [2026-04-22T14:38:35.445Z] mkdir -p "/Users/runner/agent/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17768687131744/renaissance-als_0"; \ [2026-04-22T14:38:35.445Z] cd "/Users/runner/agent/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17768687131744/renaissance-als_0"; \ [2026-04-22T14:38:35.445Z] echo ""; echo "TESTING:"; \ [2026-04-22T14:38:35.445Z] "/Users/runner/agent/workspace/Test_openjdk17_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/runner/agent/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/runner/agent/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17768687131744/renaissance-als_0"/als.json" als; \ [2026-04-22T14:38:35.445Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /Users/runner/agent/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/runner/agent/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17768687131744/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-22T14:38:35.445Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-22T14:38:35.445Z] echo "Nothing to be done for teardown."; \ [2026-04-22T14:38:35.445Z] } 2>&1 | tee -a "/Users/runner/agent/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17768687131744/TestTargetResult"; [2026-04-22T14:38:35.445Z] [2026-04-22T14:38:35.445Z] TEST SETUP: [2026-04-22T14:38:35.445Z] Nothing to be done for setup. [2026-04-22T14:38:35.445Z] [2026-04-22T14:38:35.445Z] TESTING: [2026-04-22T14:38:52.478Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-04-22T14:38:54.723Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-04-22T14:38:54.724Z] GC before operation: completed in 267.265 ms, heap usage 313.495 MB -> 34.732 MB. [2026-04-22T14:39:26.176Z] ====== als (apache-spark) [default], iteration 0 completed (27938.461 ms) ====== [2026-04-22T14:39:26.176Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-04-22T14:39:26.176Z] GC before operation: completed in 96.365 ms, heap usage 322.811 MB -> 61.888 MB. [2026-04-22T14:39:36.992Z] ====== als (apache-spark) [default], iteration 1 completed (11835.487 ms) ====== [2026-04-22T14:39:36.992Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-04-22T14:39:36.992Z] GC before operation: completed in 93.169 ms, heap usage 228.826 MB -> 62.618 MB. [2026-04-22T14:39:46.824Z] ====== als (apache-spark) [default], iteration 2 completed (9602.923 ms) ====== [2026-04-22T14:39:46.824Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-04-22T14:39:46.824Z] GC before operation: completed in 87.896 ms, heap usage 680.813 MB -> 64.030 MB. [2026-04-22T14:39:59.569Z] ====== als (apache-spark) [default], iteration 3 completed (12104.316 ms) ====== [2026-04-22T14:39:59.569Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-04-22T14:39:59.569Z] GC before operation: completed in 76.541 ms, heap usage 605.581 MB -> 64.438 MB. [2026-04-22T14:40:10.556Z] ====== als (apache-spark) [default], iteration 4 completed (10925.592 ms) ====== [2026-04-22T14:40:10.556Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-04-22T14:40:10.556Z] GC before operation: completed in 93.720 ms, heap usage 458.531 MB -> 64.900 MB. [2026-04-22T14:40:21.483Z] ====== als (apache-spark) [default], iteration 5 completed (10655.585 ms) ====== [2026-04-22T14:40:21.483Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-04-22T14:40:21.483Z] GC before operation: completed in 117.566 ms, heap usage 867.669 MB -> 65.891 MB. [2026-04-22T14:40:32.791Z] ====== als (apache-spark) [default], iteration 6 completed (11513.476 ms) ====== [2026-04-22T14:40:32.791Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-04-22T14:40:32.791Z] GC before operation: completed in 124.714 ms, heap usage 1.053 GB -> 66.711 MB. [2026-04-22T14:40:48.177Z] ====== als (apache-spark) [default], iteration 7 completed (13657.091 ms) ====== [2026-04-22T14:40:48.177Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-04-22T14:40:48.177Z] GC before operation: completed in 140.049 ms, heap usage 928.541 MB -> 67.076 MB. [2026-04-22T14:41:00.993Z] ====== als (apache-spark) [default], iteration 8 completed (12496.246 ms) ====== [2026-04-22T14:41:00.993Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-04-22T14:41:00.993Z] GC before operation: completed in 130.017 ms, heap usage 475.646 MB -> 67.198 MB. [2026-04-22T14:41:11.718Z] ====== als (apache-spark) [default], iteration 9 completed (11113.836 ms) ====== [2026-04-22T14:41:11.718Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-04-22T14:41:11.718Z] GC before operation: completed in 111.277 ms, heap usage 152.299 MB -> 66.702 MB. [2026-04-22T14:41:20.826Z] ====== als (apache-spark) [default], iteration 10 completed (9697.621 ms) ====== [2026-04-22T14:41:20.826Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-04-22T14:41:21.489Z] GC before operation: completed in 112.262 ms, heap usage 1.259 GB -> 68.403 MB. [2026-04-22T14:41:32.269Z] ====== als (apache-spark) [default], iteration 11 completed (10081.859 ms) ====== [2026-04-22T14:41:32.269Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-04-22T14:41:32.269Z] GC before operation: completed in 108.689 ms, heap usage 145.986 MB -> 67.302 MB. [2026-04-22T14:41:42.971Z] ====== als (apache-spark) [default], iteration 12 completed (10726.520 ms) ====== [2026-04-22T14:41:42.971Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-04-22T14:41:42.971Z] GC before operation: completed in 106.066 ms, heap usage 754.024 MB -> 68.425 MB. [2026-04-22T14:41:53.767Z] ====== als (apache-spark) [default], iteration 13 completed (10626.115 ms) ====== [2026-04-22T14:41:53.767Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-04-22T14:41:53.767Z] GC before operation: completed in 134.063 ms, heap usage 852.647 MB -> 68.671 MB. [2026-04-22T14:42:06.353Z] ====== als (apache-spark) [default], iteration 14 completed (11716.264 ms) ====== [2026-04-22T14:42:06.353Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-04-22T14:42:06.353Z] GC before operation: completed in 98.054 ms, heap usage 337.064 MB -> 68.310 MB. [2026-04-22T14:42:19.112Z] ====== als (apache-spark) [default], iteration 15 completed (12342.640 ms) ====== [2026-04-22T14:42:19.112Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-04-22T14:42:19.112Z] GC before operation: completed in 124.191 ms, heap usage 271.912 MB -> 68.421 MB. [2026-04-22T14:42:30.093Z] ====== als (apache-spark) [default], iteration 16 completed (11029.714 ms) ====== [2026-04-22T14:42:30.094Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-04-22T14:42:30.094Z] GC before operation: completed in 103.405 ms, heap usage 781.062 MB -> 69.416 MB. [2026-04-22T14:42:42.779Z] ====== als (apache-spark) [default], iteration 17 completed (11186.968 ms) ====== [2026-04-22T14:42:42.779Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-04-22T14:42:42.779Z] GC before operation: completed in 148.268 ms, heap usage 712.039 MB -> 69.722 MB. [2026-04-22T14:42:53.406Z] ====== als (apache-spark) [default], iteration 18 completed (10643.463 ms) ====== [2026-04-22T14:42:53.406Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-04-22T14:42:53.406Z] GC before operation: completed in 265.890 ms, heap usage 750.325 MB -> 72.129 MB. [2026-04-22T14:43:04.178Z] ====== als (apache-spark) [default], iteration 19 completed (11573.174 ms) ====== [2026-04-22T14:43:04.178Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-04-22T14:43:04.840Z] GC before operation: completed in 117.553 ms, heap usage 487.141 MB -> 69.927 MB. [2026-04-22T14:43:17.509Z] ====== als (apache-spark) [default], iteration 20 completed (12015.464 ms) ====== [2026-04-22T14:43:17.509Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-04-22T14:43:17.509Z] GC before operation: completed in 128.765 ms, heap usage 781.441 MB -> 70.493 MB. [2026-04-22T14:43:30.166Z] ====== als (apache-spark) [default], iteration 21 completed (12115.301 ms) ====== [2026-04-22T14:43:30.166Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-04-22T14:43:30.166Z] GC before operation: completed in 124.190 ms, heap usage 487.194 MB -> 70.578 MB. [2026-04-22T14:43:40.972Z] ====== als (apache-spark) [default], iteration 22 completed (11013.321 ms) ====== [2026-04-22T14:43:40.972Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-04-22T14:43:40.972Z] GC before operation: completed in 95.851 ms, heap usage 913.912 MB -> 71.227 MB. [2026-04-22T14:43:51.806Z] ====== als (apache-spark) [default], iteration 23 completed (10480.704 ms) ====== [2026-04-22T14:43:51.806Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-04-22T14:43:51.806Z] GC before operation: completed in 165.152 ms, heap usage 1.234 GB -> 71.885 MB. [2026-04-22T14:44:00.848Z] ====== als (apache-spark) [default], iteration 24 completed (8352.774 ms) ====== [2026-04-22T14:44:00.848Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-04-22T14:44:00.848Z] GC before operation: completed in 282.432 ms, heap usage 740.974 MB -> 71.576 MB. [2026-04-22T14:44:15.737Z] ====== als (apache-spark) [default], iteration 25 completed (13167.594 ms) ====== [2026-04-22T14:44:15.737Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-04-22T14:44:15.737Z] GC before operation: completed in 146.132 ms, heap usage 1.008 GB -> 72.134 MB. [2026-04-22T14:44:28.386Z] ====== als (apache-spark) [default], iteration 26 completed (12823.125 ms) ====== [2026-04-22T14:44:28.386Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-04-22T14:44:28.386Z] GC before operation: completed in 126.538 ms, heap usage 207.342 MB -> 71.299 MB. [2026-04-22T14:44:38.936Z] ====== als (apache-spark) [default], iteration 27 completed (11803.300 ms) ====== [2026-04-22T14:44:38.936Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-04-22T14:44:39.614Z] GC before operation: completed in 121.282 ms, heap usage 598.307 MB -> 72.311 MB. [2026-04-22T14:44:50.178Z] ====== als (apache-spark) [default], iteration 28 completed (10473.309 ms) ====== [2026-04-22T14:44:50.178Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-04-22T14:44:50.178Z] GC before operation: completed in 110.377 ms, heap usage 658.823 MB -> 71.952 MB. [2026-04-22T14:45:02.717Z] ====== als (apache-spark) [default], iteration 29 completed (11339.589 ms) ====== [2026-04-22T14:45:02.717Z] ----------------------------------- [2026-04-22T14:45:02.717Z] renaissance-als_0_PASSED [2026-04-22T14:45:02.717Z] ----------------------------------- [2026-04-22T14:45:02.717Z] [2026-04-22T14:45:02.717Z] TEST TEARDOWN: [2026-04-22T14:45:02.717Z] Nothing to be done for teardown. [2026-04-22T14:45:02.717Z] renaissance-als_0 Finish Time: Wed Apr 22 14:45:01 2026 Epoch Time (ms): 1776869101963