[2026-01-30T19:07:46.628Z] Running test renaissance-als_0 ... [2026-01-30T19:07:46.974Z] =============================================== [2026-01-30T19:07:46.974Z] renaissance-als_0 Start Time: Fri Jan 30 19:07:46 2026 Epoch Time (ms): 1769800066718 [2026-01-30T19:07:46.974Z] variation: NoOptions [2026-01-30T19:07:46.974Z] JVM_OPTIONS: [2026-01-30T19:07:46.974Z] { \ [2026-01-30T19:07:46.974Z] echo ""; echo "TEST SETUP:"; \ [2026-01-30T19:07:46.974Z] echo "Nothing to be done for setup."; \ [2026-01-30T19:07:46.974Z] mkdir -p "C:/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17697998804430\\renaissance-als_0"; \ [2026-01-30T19:07:46.974Z] cd "C:/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17697998804430\\renaissance-als_0"; \ [2026-01-30T19:07:46.974Z] echo ""; echo "TESTING:"; \ [2026-01-30T19:07:46.974Z] "c:/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/jdkbinary/j2sdk-image\\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 "C:/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17697998804430\\renaissance-als_0"\\als.json" als; \ [2026-01-30T19:07:46.974Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd C:/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17697998804430\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-30T19:07:46.974Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-30T19:07:46.974Z] echo "Nothing to be done for teardown."; \ [2026-01-30T19:07:46.974Z] } 2>&1 | tee -a "C:/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17697998804430\\TestTargetResult"; [2026-01-30T19:07:46.974Z] [2026-01-30T19:07:46.974Z] TEST SETUP: [2026-01-30T19:07:46.974Z] Nothing to be done for setup. [2026-01-30T19:07:46.974Z] [2026-01-30T19:07:46.974Z] TESTING: [2026-01-30T19:08:00.047Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-30T19:08:01.690Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-30T19:08:01.690Z] GC before operation: completed in 30.394 ms, heap usage 62.584 MB -> 35.460 MB. [2026-01-30T19:08:25.031Z] ====== als (apache-spark) [default], iteration 0 completed (22741.424 ms) ====== [2026-01-30T19:08:25.031Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-30T19:08:25.470Z] GC before operation: completed in 64.988 ms, heap usage 213.327 MB -> 62.518 MB. [2026-01-30T19:08:38.782Z] ====== als (apache-spark) [default], iteration 1 completed (13233.160 ms) ====== [2026-01-30T19:08:38.782Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-30T19:08:38.782Z] GC before operation: completed in 56.907 ms, heap usage 455.747 MB -> 63.866 MB. [2026-01-30T19:08:51.954Z] ====== als (apache-spark) [default], iteration 2 completed (12524.038 ms) ====== [2026-01-30T19:08:51.954Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-30T19:08:51.954Z] GC before operation: completed in 60.631 ms, heap usage 272.705 MB -> 64.153 MB. [2026-01-30T19:09:05.209Z] ====== als (apache-spark) [default], iteration 3 completed (12374.836 ms) ====== [2026-01-30T19:09:05.209Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-30T19:09:05.209Z] GC before operation: completed in 59.584 ms, heap usage 429.380 MB -> 64.827 MB. [2026-01-30T19:09:18.305Z] ====== als (apache-spark) [default], iteration 4 completed (12468.151 ms) ====== [2026-01-30T19:09:18.305Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-30T19:09:18.305Z] GC before operation: completed in 59.728 ms, heap usage 391.648 MB -> 65.279 MB. [2026-01-30T19:09:29.252Z] ====== als (apache-spark) [default], iteration 5 completed (12416.901 ms) ====== [2026-01-30T19:09:29.252Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-30T19:09:29.252Z] GC before operation: completed in 63.952 ms, heap usage 435.086 MB -> 66.016 MB. [2026-01-30T19:09:42.503Z] ====== als (apache-spark) [default], iteration 6 completed (12412.218 ms) ====== [2026-01-30T19:09:42.503Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-30T19:09:42.503Z] GC before operation: completed in 70.222 ms, heap usage 201.343 MB -> 66.179 MB. [2026-01-30T19:09:55.794Z] ====== als (apache-spark) [default], iteration 7 completed (12195.063 ms) ====== [2026-01-30T19:09:55.794Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-30T19:09:55.794Z] GC before operation: completed in 65.806 ms, heap usage 403.961 MB -> 67.119 MB. [2026-01-30T19:10:06.761Z] ====== als (apache-spark) [default], iteration 8 completed (12071.224 ms) ====== [2026-01-30T19:10:06.761Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-30T19:10:06.761Z] GC before operation: completed in 69.954 ms, heap usage 778.680 MB -> 68.166 MB. [2026-01-30T19:10:19.932Z] ====== als (apache-spark) [default], iteration 9 completed (12369.491 ms) ====== [2026-01-30T19:10:19.932Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-30T19:10:19.932Z] GC before operation: completed in 62.475 ms, heap usage 263.134 MB -> 67.585 MB. [2026-01-30T19:10:33.117Z] ====== als (apache-spark) [default], iteration 10 completed (12278.043 ms) ====== [2026-01-30T19:10:33.117Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-30T19:10:33.117Z] GC before operation: completed in 128.250 ms, heap usage 353.472 MB -> 67.962 MB. [2026-01-30T19:10:44.031Z] ====== als (apache-spark) [default], iteration 11 completed (12086.571 ms) ====== [2026-01-30T19:10:44.031Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-30T19:10:44.031Z] GC before operation: completed in 71.210 ms, heap usage 385.384 MB -> 68.230 MB. [2026-01-30T19:10:57.232Z] ====== als (apache-spark) [default], iteration 12 completed (12069.177 ms) ====== [2026-01-30T19:10:57.232Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-30T19:10:57.232Z] GC before operation: completed in 98.695 ms, heap usage 315.857 MB -> 68.420 MB. [2026-01-30T19:11:08.137Z] ====== als (apache-spark) [default], iteration 13 completed (12053.710 ms) ====== [2026-01-30T19:11:08.137Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-30T19:11:08.137Z] GC before operation: completed in 91.372 ms, heap usage 504.693 MB -> 68.953 MB. [2026-01-30T19:11:21.313Z] ====== als (apache-spark) [default], iteration 14 completed (12072.298 ms) ====== [2026-01-30T19:11:21.313Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-30T19:11:21.313Z] GC before operation: completed in 63.857 ms, heap usage 174.820 MB -> 68.851 MB. [2026-01-30T19:11:34.512Z] ====== als (apache-spark) [default], iteration 15 completed (12051.570 ms) ====== [2026-01-30T19:11:34.512Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-30T19:11:34.512Z] GC before operation: completed in 100.665 ms, heap usage 447.932 MB -> 69.529 MB. [2026-01-30T19:11:45.393Z] ====== als (apache-spark) [default], iteration 16 completed (12336.421 ms) ====== [2026-01-30T19:11:45.393Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-30T19:11:45.393Z] GC before operation: completed in 59.101 ms, heap usage 260.917 MB -> 69.480 MB. [2026-01-30T19:11:58.594Z] ====== als (apache-spark) [default], iteration 17 completed (11875.992 ms) ====== [2026-01-30T19:11:58.594Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-30T19:11:58.594Z] GC before operation: completed in 61.164 ms, heap usage 258.938 MB -> 69.827 MB. [2026-01-30T19:12:11.682Z] ====== als (apache-spark) [default], iteration 18 completed (12128.882 ms) ====== [2026-01-30T19:12:11.682Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-30T19:12:11.682Z] GC before operation: completed in 61.962 ms, heap usage 489.951 MB -> 70.299 MB. [2026-01-30T19:12:22.557Z] ====== als (apache-spark) [default], iteration 19 completed (12355.003 ms) ====== [2026-01-30T19:12:22.557Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-30T19:12:22.557Z] GC before operation: completed in 61.956 ms, heap usage 344.471 MB -> 70.367 MB. [2026-01-30T19:12:35.849Z] ====== als (apache-spark) [default], iteration 20 completed (12151.059 ms) ====== [2026-01-30T19:12:35.849Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-30T19:12:35.849Z] GC before operation: completed in 56.117 ms, heap usage 484.052 MB -> 70.892 MB. [2026-01-30T19:12:46.857Z] ====== als (apache-spark) [default], iteration 21 completed (12084.015 ms) ====== [2026-01-30T19:12:46.857Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-30T19:12:46.857Z] GC before operation: completed in 55.651 ms, heap usage 237.140 MB -> 70.713 MB. [2026-01-30T19:13:00.248Z] ====== als (apache-spark) [default], iteration 22 completed (12016.133 ms) ====== [2026-01-30T19:13:00.248Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-30T19:13:00.248Z] GC before operation: completed in 64.725 ms, heap usage 416.705 MB -> 71.190 MB. [2026-01-30T19:13:13.374Z] ====== als (apache-spark) [default], iteration 23 completed (12320.827 ms) ====== [2026-01-30T19:13:13.374Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-30T19:13:13.374Z] GC before operation: completed in 57.557 ms, heap usage 404.732 MB -> 71.459 MB. [2026-01-30T19:13:24.278Z] ====== als (apache-spark) [default], iteration 24 completed (12057.073 ms) ====== [2026-01-30T19:13:24.278Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-30T19:13:24.278Z] GC before operation: completed in 81.348 ms, heap usage 256.388 MB -> 71.576 MB. [2026-01-30T19:13:37.538Z] ====== als (apache-spark) [default], iteration 25 completed (12044.334 ms) ====== [2026-01-30T19:13:37.538Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-30T19:13:37.538Z] GC before operation: completed in 68.682 ms, heap usage 396.098 MB -> 71.961 MB. [2026-01-30T19:13:48.514Z] ====== als (apache-spark) [default], iteration 26 completed (11879.936 ms) ====== [2026-01-30T19:13:48.514Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-30T19:13:48.514Z] GC before operation: completed in 60.431 ms, heap usage 381.132 MB -> 72.242 MB. [2026-01-30T19:14:01.784Z] ====== als (apache-spark) [default], iteration 27 completed (11973.260 ms) ====== [2026-01-30T19:14:01.784Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-30T19:14:01.784Z] GC before operation: completed in 57.833 ms, heap usage 396.102 MB -> 72.436 MB. [2026-01-30T19:14:12.627Z] ====== als (apache-spark) [default], iteration 28 completed (11949.976 ms) ====== [2026-01-30T19:14:12.627Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-30T19:14:12.627Z] GC before operation: completed in 72.531 ms, heap usage 475.223 MB -> 72.838 MB. [2026-01-30T19:14:25.747Z] ====== als (apache-spark) [default], iteration 29 completed (11798.558 ms) ====== [2026-01-30T19:14:25.747Z] ----------------------------------- [2026-01-30T19:14:25.747Z] renaissance-als_0_PASSED [2026-01-30T19:14:25.747Z] ----------------------------------- [2026-01-30T19:14:25.747Z] [2026-01-30T19:14:25.747Z] TEST TEARDOWN: [2026-01-30T19:14:25.747Z] Nothing to be done for teardown. [2026-01-30T19:14:25.747Z] renaissance-als_0 Finish Time: Fri Jan 30 19:14:24 2026 Epoch Time (ms): 1769800464735