[2026-01-21T00:28:58.473Z] Running test renaissance-als_0 ... [2026-01-21T00:28:58.473Z] =============================================== [2026-01-21T00:28:58.473Z] renaissance-als_0 Start Time: Wed Jan 21 00:28:57 2026 Epoch Time (ms): 1768955337997 [2026-01-21T00:28:58.473Z] variation: NoOptions [2026-01-21T00:28:58.473Z] JVM_OPTIONS: [2026-01-21T00:28:58.473Z] { \ [2026-01-21T00:28:58.473Z] echo ""; echo "TEST SETUP:"; \ [2026-01-21T00:28:58.473Z] echo "Nothing to be done for setup."; \ [2026-01-21T00:28:58.473Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17689551492547/renaissance-als_0"; \ [2026-01-21T00:28:58.473Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17689551492547/renaissance-als_0"; \ [2026-01-21T00:28:58.473Z] echo ""; echo "TESTING:"; \ [2026-01-21T00:28:58.473Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/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 "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17689551492547/renaissance-als_0"/als.json" als; \ [2026-01-21T00:28:58.473Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17689551492547/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-21T00:28:58.473Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-21T00:28:58.473Z] echo "Nothing to be done for teardown."; \ [2026-01-21T00:28:58.473Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17689551492547/TestTargetResult"; [2026-01-21T00:28:58.473Z] [2026-01-21T00:28:58.473Z] TEST SETUP: [2026-01-21T00:28:58.473Z] Nothing to be done for setup. [2026-01-21T00:28:58.473Z] [2026-01-21T00:28:58.473Z] TESTING: [2026-01-21T00:29:10.957Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-01-21T00:29:14.800Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-21T00:29:14.800Z] GC before operation: completed in 60.898 ms, heap usage 146.000 MB -> 34.918 MB. [2026-01-21T00:29:42.981Z] ====== als (apache-spark) [default], iteration 0 completed (26920.710 ms) ====== [2026-01-21T00:29:42.982Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-21T00:29:42.982Z] GC before operation: completed in 109.827 ms, heap usage 181.049 MB -> 61.531 MB. [2026-01-21T00:29:57.604Z] ====== als (apache-spark) [default], iteration 1 completed (13936.914 ms) ====== [2026-01-21T00:29:57.604Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-21T00:29:57.604Z] GC before operation: completed in 103.071 ms, heap usage 329.482 MB -> 62.605 MB. [2026-01-21T00:30:10.079Z] ====== als (apache-spark) [default], iteration 2 completed (12712.587 ms) ====== [2026-01-21T00:30:10.079Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-21T00:30:10.079Z] GC before operation: completed in 101.522 ms, heap usage 145.425 MB -> 63.013 MB. [2026-01-21T00:30:24.698Z] ====== als (apache-spark) [default], iteration 3 completed (12743.399 ms) ====== [2026-01-21T00:30:24.698Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-21T00:30:24.698Z] GC before operation: completed in 99.526 ms, heap usage 258.902 MB -> 63.738 MB. [2026-01-21T00:30:37.063Z] ====== als (apache-spark) [default], iteration 4 completed (12028.001 ms) ====== [2026-01-21T00:30:37.063Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-21T00:30:37.063Z] GC before operation: completed in 110.714 ms, heap usage 438.672 MB -> 64.580 MB. [2026-01-21T00:30:49.464Z] ====== als (apache-spark) [default], iteration 5 completed (11905.845 ms) ====== [2026-01-21T00:30:49.464Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-21T00:30:49.464Z] GC before operation: completed in 105.407 ms, heap usage 84.929 MB -> 64.665 MB. [2026-01-21T00:31:01.945Z] ====== als (apache-spark) [default], iteration 6 completed (12055.549 ms) ====== [2026-01-21T00:31:01.945Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-21T00:31:01.945Z] GC before operation: completed in 146.468 ms, heap usage 95.851 MB -> 65.239 MB. [2026-01-21T00:31:12.361Z] ====== als (apache-spark) [default], iteration 7 completed (11822.815 ms) ====== [2026-01-21T00:31:12.361Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-21T00:31:13.411Z] GC before operation: completed in 150.626 ms, heap usage 388.237 MB -> 65.999 MB. [2026-01-21T00:31:25.780Z] ====== als (apache-spark) [default], iteration 8 completed (11738.239 ms) ====== [2026-01-21T00:31:25.780Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-21T00:31:25.780Z] GC before operation: completed in 107.842 ms, heap usage 105.664 MB -> 66.396 MB. [2026-01-21T00:31:38.136Z] ====== als (apache-spark) [default], iteration 9 completed (11937.820 ms) ====== [2026-01-21T00:31:38.136Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-21T00:31:38.136Z] GC before operation: completed in 119.448 ms, heap usage 309.172 MB -> 66.673 MB. [2026-01-21T00:31:50.490Z] ====== als (apache-spark) [default], iteration 10 completed (11828.552 ms) ====== [2026-01-21T00:31:50.490Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-21T00:31:50.490Z] GC before operation: completed in 117.533 ms, heap usage 468.001 MB -> 67.182 MB. [2026-01-21T00:32:02.854Z] ====== als (apache-spark) [default], iteration 11 completed (11729.939 ms) ====== [2026-01-21T00:32:02.854Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-21T00:32:02.854Z] GC before operation: completed in 118.991 ms, heap usage 233.776 MB -> 67.066 MB. [2026-01-21T00:32:13.320Z] ====== als (apache-spark) [default], iteration 12 completed (11697.576 ms) ====== [2026-01-21T00:32:13.320Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-21T00:32:13.963Z] GC before operation: completed in 117.285 ms, heap usage 232.362 MB -> 67.476 MB. [2026-01-21T00:32:26.320Z] ====== als (apache-spark) [default], iteration 13 completed (11640.871 ms) ====== [2026-01-21T00:32:26.320Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-21T00:32:26.320Z] GC before operation: completed in 120.675 ms, heap usage 495.689 MB -> 67.976 MB. [2026-01-21T00:32:38.675Z] ====== als (apache-spark) [default], iteration 14 completed (11603.648 ms) ====== [2026-01-21T00:32:38.675Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-21T00:32:38.675Z] GC before operation: completed in 119.542 ms, heap usage 458.969 MB -> 68.302 MB. [2026-01-21T00:32:49.086Z] ====== als (apache-spark) [default], iteration 15 completed (11605.175 ms) ====== [2026-01-21T00:32:49.086Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-21T00:32:49.730Z] GC before operation: completed in 117.240 ms, heap usage 296.792 MB -> 68.348 MB. [2026-01-21T00:33:02.398Z] ====== als (apache-spark) [default], iteration 16 completed (11670.769 ms) ====== [2026-01-21T00:33:02.398Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-21T00:33:02.398Z] GC before operation: completed in 181.556 ms, heap usage 248.034 MB -> 68.476 MB. [2026-01-21T00:33:14.897Z] ====== als (apache-spark) [default], iteration 17 completed (11661.894 ms) ====== [2026-01-21T00:33:14.897Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-21T00:33:14.897Z] GC before operation: completed in 104.329 ms, heap usage 371.643 MB -> 68.875 MB. [2026-01-21T00:33:25.306Z] ====== als (apache-spark) [default], iteration 18 completed (11710.104 ms) ====== [2026-01-21T00:33:25.306Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-21T00:33:25.953Z] GC before operation: completed in 110.983 ms, heap usage 531.254 MB -> 69.397 MB. [2026-01-21T00:33:38.319Z] ====== als (apache-spark) [default], iteration 19 completed (11497.311 ms) ====== [2026-01-21T00:33:38.319Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-21T00:33:38.319Z] GC before operation: completed in 108.870 ms, heap usage 143.074 MB -> 69.142 MB. [2026-01-21T00:33:50.667Z] ====== als (apache-spark) [default], iteration 20 completed (11917.713 ms) ====== [2026-01-21T00:33:50.667Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-21T00:33:50.667Z] GC before operation: completed in 109.185 ms, heap usage 208.036 MB -> 69.348 MB. [2026-01-21T00:34:03.012Z] ====== als (apache-spark) [default], iteration 21 completed (11573.660 ms) ====== [2026-01-21T00:34:03.012Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-21T00:34:03.012Z] GC before operation: completed in 106.571 ms, heap usage 217.765 MB -> 69.769 MB. [2026-01-21T00:34:13.449Z] ====== als (apache-spark) [default], iteration 22 completed (11749.013 ms) ====== [2026-01-21T00:34:13.449Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-21T00:34:14.105Z] GC before operation: completed in 110.609 ms, heap usage 211.561 MB -> 69.959 MB. [2026-01-21T00:34:26.456Z] ====== als (apache-spark) [default], iteration 23 completed (11782.739 ms) ====== [2026-01-21T00:34:26.456Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-21T00:34:26.456Z] GC before operation: completed in 111.988 ms, heap usage 393.582 MB -> 70.433 MB. [2026-01-21T00:34:38.815Z] ====== als (apache-spark) [default], iteration 24 completed (11541.035 ms) ====== [2026-01-21T00:34:38.815Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-21T00:34:38.815Z] GC before operation: completed in 112.325 ms, heap usage 209.158 MB -> 70.385 MB. [2026-01-21T00:34:49.253Z] ====== als (apache-spark) [default], iteration 25 completed (11517.344 ms) ====== [2026-01-21T00:34:49.253Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-21T00:34:49.895Z] GC before operation: completed in 117.313 ms, heap usage 292.743 MB -> 70.742 MB. [2026-01-21T00:35:02.657Z] ====== als (apache-spark) [default], iteration 26 completed (11443.568 ms) ====== [2026-01-21T00:35:02.657Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-21T00:35:02.657Z] GC before operation: completed in 110.678 ms, heap usage 234.755 MB -> 71.008 MB. [2026-01-21T00:35:13.086Z] ====== als (apache-spark) [default], iteration 27 completed (11464.215 ms) ====== [2026-01-21T00:35:13.086Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-21T00:35:13.086Z] GC before operation: completed in 111.789 ms, heap usage 454.333 MB -> 71.628 MB. [2026-01-21T00:35:25.442Z] ====== als (apache-spark) [default], iteration 28 completed (11558.847 ms) ====== [2026-01-21T00:35:25.442Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-21T00:35:25.442Z] GC before operation: completed in 110.013 ms, heap usage 201.353 MB -> 71.367 MB. [2026-01-21T00:35:37.881Z] ====== als (apache-spark) [default], iteration 29 completed (11395.085 ms) ====== [2026-01-21T00:35:37.881Z] ----------------------------------- [2026-01-21T00:35:37.881Z] renaissance-als_0_PASSED [2026-01-21T00:35:37.881Z] ----------------------------------- [2026-01-21T00:35:37.881Z] [2026-01-21T00:35:37.881Z] TEST TEARDOWN: [2026-01-21T00:35:37.881Z] Nothing to be done for teardown. [2026-01-21T00:35:37.881Z] renaissance-als_0 Finish Time: Wed Jan 21 00:35:36 2026 Epoch Time (ms): 1768955736717