[2025-11-13T00:32:10.736Z] Running test renaissance-als_0 ... [2025-11-13T00:32:10.736Z] =============================================== [2025-11-13T00:32:10.736Z] renaissance-als_0 Start Time: Thu Nov 13 00:32:10 2025 Epoch Time (ms): 1762993930589 [2025-11-13T00:32:10.736Z] variation: NoOptions [2025-11-13T00:32:10.736Z] JVM_OPTIONS: [2025-11-13T00:32:10.736Z] { \ [2025-11-13T00:32:10.736Z] echo ""; echo "TEST SETUP:"; \ [2025-11-13T00:32:10.736Z] echo "Nothing to be done for setup."; \ [2025-11-13T00:32:10.736Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17629937313482/renaissance-als_0"; \ [2025-11-13T00:32:10.736Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17629937313482/renaissance-als_0"; \ [2025-11-13T00:32:10.736Z] echo ""; echo "TESTING:"; \ [2025-11-13T00:32:10.737Z] "/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_17629937313482/renaissance-als_0"/als.json" als; \ [2025-11-13T00:32:10.737Z] 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_17629937313482/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-13T00:32:10.737Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-13T00:32:10.737Z] echo "Nothing to be done for teardown."; \ [2025-11-13T00:32:10.737Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17629937313482/TestTargetResult"; [2025-11-13T00:32:10.737Z] [2025-11-13T00:32:10.737Z] TEST SETUP: [2025-11-13T00:32:10.737Z] Nothing to be done for setup. [2025-11-13T00:32:10.737Z] [2025-11-13T00:32:10.737Z] TESTING: [2025-11-13T00:32:16.792Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-11-13T00:32:22.731Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-13T00:32:22.731Z] GC before operation: completed in 59.897 ms, heap usage 284.889 MB -> 34.880 MB. [2025-11-13T00:32:56.269Z] ====== als (apache-spark) [default], iteration 0 completed (30140.112 ms) ====== [2025-11-13T00:32:56.269Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-13T00:32:56.269Z] GC before operation: completed in 141.878 ms, heap usage 489.771 MB -> 61.841 MB. [2025-11-13T00:33:09.446Z] ====== als (apache-spark) [default], iteration 1 completed (14168.350 ms) ====== [2025-11-13T00:33:09.446Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-13T00:33:09.446Z] GC before operation: completed in 117.218 ms, heap usage 298.783 MB -> 62.516 MB. [2025-11-13T00:33:22.669Z] ====== als (apache-spark) [default], iteration 2 completed (12959.256 ms) ====== [2025-11-13T00:33:22.670Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-13T00:33:22.670Z] GC before operation: completed in 121.735 ms, heap usage 310.271 MB -> 63.229 MB. [2025-11-13T00:33:35.932Z] ====== als (apache-spark) [default], iteration 3 completed (13107.659 ms) ====== [2025-11-13T00:33:35.932Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-13T00:33:35.932Z] GC before operation: completed in 115.666 ms, heap usage 373.019 MB -> 63.916 MB. [2025-11-13T00:33:49.201Z] ====== als (apache-spark) [default], iteration 4 completed (12405.146 ms) ====== [2025-11-13T00:33:49.201Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-13T00:33:49.201Z] GC before operation: completed in 118.199 ms, heap usage 466.053 MB -> 64.654 MB. [2025-11-13T00:34:00.091Z] ====== als (apache-spark) [default], iteration 5 completed (11642.359 ms) ====== [2025-11-13T00:34:00.091Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-13T00:34:00.091Z] GC before operation: completed in 108.432 ms, heap usage 237.306 MB -> 64.761 MB. [2025-11-13T00:34:10.942Z] ====== als (apache-spark) [default], iteration 6 completed (11437.423 ms) ====== [2025-11-13T00:34:10.942Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-13T00:34:10.942Z] GC before operation: completed in 111.258 ms, heap usage 240.557 MB -> 65.324 MB. [2025-11-13T00:34:24.116Z] ====== als (apache-spark) [default], iteration 7 completed (11966.325 ms) ====== [2025-11-13T00:34:24.116Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-13T00:34:24.116Z] GC before operation: completed in 138.425 ms, heap usage 449.783 MB -> 66.199 MB. [2025-11-13T00:34:37.309Z] ====== als (apache-spark) [default], iteration 8 completed (11576.125 ms) ====== [2025-11-13T00:34:37.309Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-13T00:34:37.309Z] GC before operation: completed in 115.225 ms, heap usage 253.944 MB -> 66.415 MB. [2025-11-13T00:34:48.202Z] ====== als (apache-spark) [default], iteration 9 completed (11545.598 ms) ====== [2025-11-13T00:34:48.202Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-13T00:34:48.202Z] GC before operation: completed in 125.091 ms, heap usage 635.655 MB -> 67.225 MB. [2025-11-13T00:35:01.465Z] ====== als (apache-spark) [default], iteration 10 completed (12088.511 ms) ====== [2025-11-13T00:35:01.465Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-13T00:35:01.465Z] GC before operation: completed in 116.857 ms, heap usage 102.842 MB -> 66.775 MB. [2025-11-13T00:35:12.327Z] ====== als (apache-spark) [default], iteration 11 completed (11528.689 ms) ====== [2025-11-13T00:35:12.328Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-13T00:35:12.328Z] GC before operation: completed in 112.050 ms, heap usage 202.002 MB -> 67.174 MB. [2025-11-13T00:35:25.503Z] ====== als (apache-spark) [default], iteration 12 completed (11519.971 ms) ====== [2025-11-13T00:35:25.503Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-13T00:35:25.503Z] GC before operation: completed in 106.937 ms, heap usage 90.256 MB -> 67.250 MB. [2025-11-13T00:35:36.396Z] ====== als (apache-spark) [default], iteration 13 completed (11614.493 ms) ====== [2025-11-13T00:35:36.396Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-13T00:35:36.396Z] GC before operation: completed in 112.062 ms, heap usage 126.271 MB -> 67.435 MB. [2025-11-13T00:35:49.553Z] ====== als (apache-spark) [default], iteration 14 completed (11751.312 ms) ====== [2025-11-13T00:35:49.553Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-13T00:35:49.553Z] GC before operation: completed in 112.343 ms, heap usage 500.635 MB -> 68.202 MB. [2025-11-13T00:36:00.412Z] ====== als (apache-spark) [default], iteration 15 completed (11435.132 ms) ====== [2025-11-13T00:36:00.412Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-13T00:36:00.412Z] GC before operation: completed in 109.617 ms, heap usage 184.381 MB -> 68.021 MB. [2025-11-13T00:36:11.364Z] ====== als (apache-spark) [default], iteration 16 completed (11635.775 ms) ====== [2025-11-13T00:36:11.364Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-13T00:36:11.364Z] GC before operation: completed in 112.268 ms, heap usage 475.363 MB -> 68.748 MB. [2025-11-13T00:36:24.543Z] ====== als (apache-spark) [default], iteration 17 completed (11521.571 ms) ====== [2025-11-13T00:36:24.543Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-13T00:36:24.543Z] GC before operation: completed in 110.115 ms, heap usage 341.380 MB -> 68.802 MB. [2025-11-13T00:36:35.409Z] ====== als (apache-spark) [default], iteration 18 completed (11469.998 ms) ====== [2025-11-13T00:36:35.409Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-13T00:36:35.409Z] GC before operation: completed in 115.711 ms, heap usage 219.629 MB -> 68.963 MB. [2025-11-13T00:36:48.576Z] ====== als (apache-spark) [default], iteration 19 completed (11191.260 ms) ====== [2025-11-13T00:36:48.576Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-13T00:36:48.576Z] GC before operation: completed in 130.994 ms, heap usage 497.195 MB -> 69.665 MB. [2025-11-13T00:36:59.486Z] ====== als (apache-spark) [default], iteration 20 completed (11458.696 ms) ====== [2025-11-13T00:36:59.486Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-13T00:36:59.486Z] GC before operation: completed in 124.981 ms, heap usage 373.062 MB -> 69.655 MB. [2025-11-13T00:37:10.510Z] ====== als (apache-spark) [default], iteration 21 completed (11951.945 ms) ====== [2025-11-13T00:37:10.510Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-13T00:37:10.510Z] GC before operation: completed in 116.410 ms, heap usage 476.270 MB -> 69.917 MB. [2025-11-13T00:37:23.669Z] ====== als (apache-spark) [default], iteration 22 completed (11478.347 ms) ====== [2025-11-13T00:37:23.669Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-13T00:37:23.669Z] GC before operation: completed in 176.500 ms, heap usage 401.050 MB -> 70.051 MB. [2025-11-13T00:37:34.621Z] ====== als (apache-spark) [default], iteration 23 completed (11558.262 ms) ====== [2025-11-13T00:37:34.621Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-13T00:37:34.621Z] GC before operation: completed in 117.177 ms, heap usage 474.357 MB -> 70.522 MB. [2025-11-13T00:37:47.790Z] ====== als (apache-spark) [default], iteration 24 completed (11623.625 ms) ====== [2025-11-13T00:37:47.790Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-13T00:37:47.790Z] GC before operation: completed in 118.355 ms, heap usage 469.195 MB -> 70.685 MB. [2025-11-13T00:37:58.663Z] ====== als (apache-spark) [default], iteration 25 completed (11460.466 ms) ====== [2025-11-13T00:37:58.663Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-13T00:37:58.663Z] GC before operation: completed in 115.594 ms, heap usage 178.720 MB -> 70.494 MB. [2025-11-13T00:38:11.849Z] ====== als (apache-spark) [default], iteration 26 completed (12169.389 ms) ====== [2025-11-13T00:38:11.849Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-13T00:38:11.849Z] GC before operation: completed in 119.629 ms, heap usage 155.566 MB -> 70.941 MB. [2025-11-13T00:38:22.714Z] ====== als (apache-spark) [default], iteration 27 completed (11379.900 ms) ====== [2025-11-13T00:38:22.714Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-13T00:38:22.714Z] GC before operation: completed in 112.628 ms, heap usage 163.191 MB -> 71.050 MB. [2025-11-13T00:38:35.985Z] ====== als (apache-spark) [default], iteration 28 completed (12017.005 ms) ====== [2025-11-13T00:38:35.985Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-13T00:38:35.985Z] GC before operation: completed in 117.394 ms, heap usage 229.102 MB -> 71.690 MB. [2025-11-13T00:38:47.035Z] ====== als (apache-spark) [default], iteration 29 completed (11357.502 ms) ====== [2025-11-13T00:38:47.035Z] ----------------------------------- [2025-11-13T00:38:47.035Z] renaissance-als_0_PASSED [2025-11-13T00:38:47.035Z] ----------------------------------- [2025-11-13T00:38:47.035Z] [2025-11-13T00:38:47.035Z] TEST TEARDOWN: [2025-11-13T00:38:47.035Z] Nothing to be done for teardown. [2025-11-13T00:38:47.035Z] renaissance-als_0 Finish Time: Thu Nov 13 00:38:46 2025 Epoch Time (ms): 1762994326775