[2025-11-20T01:38:18.411Z] Running test renaissance-als_0 ... [2025-11-20T01:38:18.411Z] =============================================== [2025-11-20T01:38:18.411Z] renaissance-als_0 Start Time: Thu Nov 20 01:38:18 2025 Epoch Time (ms): 1763602698124 [2025-11-20T01:38:18.411Z] variation: NoOptions [2025-11-20T01:38:18.411Z] JVM_OPTIONS: [2025-11-20T01:38:18.411Z] { \ [2025-11-20T01:38:18.411Z] echo ""; echo "TEST SETUP:"; \ [2025-11-20T01:38:18.411Z] echo "Nothing to be done for setup."; \ [2025-11-20T01:38:18.411Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17636025014975/renaissance-als_0"; \ [2025-11-20T01:38:18.411Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17636025014975/renaissance-als_0"; \ [2025-11-20T01:38:18.411Z] echo ""; echo "TESTING:"; \ [2025-11-20T01:38:18.411Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_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_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17636025014975/renaissance-als_0"/als.json" als; \ [2025-11-20T01:38:18.411Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17636025014975/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-20T01:38:18.411Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-20T01:38:18.411Z] echo "Nothing to be done for teardown."; \ [2025-11-20T01:38:18.411Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17636025014975/TestTargetResult"; [2025-11-20T01:38:18.411Z] [2025-11-20T01:38:18.411Z] TEST SETUP: [2025-11-20T01:38:18.411Z] Nothing to be done for setup. [2025-11-20T01:38:18.411Z] [2025-11-20T01:38:18.411Z] TESTING: [2025-11-20T01:38:26.070Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-20T01:38:31.184Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-20T01:38:31.184Z] GC before operation: completed in 43.727 ms, heap usage 52.272 MB -> 34.959 MB. [2025-11-20T01:38:59.896Z] ====== als (apache-spark) [default], iteration 0 completed (25762.230 ms) ====== [2025-11-20T01:38:59.896Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-20T01:38:59.896Z] GC before operation: completed in 95.583 ms, heap usage 587.823 MB -> 62.346 MB. [2025-11-20T01:39:09.448Z] ====== als (apache-spark) [default], iteration 1 completed (10070.534 ms) ====== [2025-11-20T01:39:09.448Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-20T01:39:09.448Z] GC before operation: completed in 85.674 ms, heap usage 453.779 MB -> 63.253 MB. [2025-11-20T01:39:18.961Z] ====== als (apache-spark) [default], iteration 2 completed (9059.513 ms) ====== [2025-11-20T01:39:18.961Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-20T01:39:18.961Z] GC before operation: completed in 81.661 ms, heap usage 200.777 MB -> 63.456 MB. [2025-11-20T01:39:28.475Z] ====== als (apache-spark) [default], iteration 3 completed (9511.766 ms) ====== [2025-11-20T01:39:28.475Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-20T01:39:28.475Z] GC before operation: completed in 85.531 ms, heap usage 229.670 MB -> 63.940 MB. [2025-11-20T01:39:38.060Z] ====== als (apache-spark) [default], iteration 4 completed (9053.964 ms) ====== [2025-11-20T01:39:38.060Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-20T01:39:38.060Z] GC before operation: completed in 96.169 ms, heap usage 449.606 MB -> 64.884 MB. [2025-11-20T01:39:47.629Z] ====== als (apache-spark) [default], iteration 5 completed (8811.547 ms) ====== [2025-11-20T01:39:47.629Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-20T01:39:47.629Z] GC before operation: completed in 88.164 ms, heap usage 143.648 MB -> 64.550 MB. [2025-11-20T01:39:57.360Z] ====== als (apache-spark) [default], iteration 6 completed (9031.709 ms) ====== [2025-11-20T01:39:57.360Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-20T01:39:57.360Z] GC before operation: completed in 90.023 ms, heap usage 487.509 MB -> 66.024 MB. [2025-11-20T01:40:05.189Z] ====== als (apache-spark) [default], iteration 7 completed (8663.743 ms) ====== [2025-11-20T01:40:05.189Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-20T01:40:05.189Z] GC before operation: completed in 110.915 ms, heap usage 125.167 MB -> 66.245 MB. [2025-11-20T01:40:14.656Z] ====== als (apache-spark) [default], iteration 8 completed (8718.302 ms) ====== [2025-11-20T01:40:14.656Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-20T01:40:14.656Z] GC before operation: completed in 100.453 ms, heap usage 215.151 MB -> 66.813 MB. [2025-11-20T01:40:24.284Z] ====== als (apache-spark) [default], iteration 9 completed (8711.837 ms) ====== [2025-11-20T01:40:24.284Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-20T01:40:24.284Z] GC before operation: completed in 95.381 ms, heap usage 533.369 MB -> 67.470 MB. [2025-11-20T01:40:33.740Z] ====== als (apache-spark) [default], iteration 10 completed (8826.288 ms) ====== [2025-11-20T01:40:33.740Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-20T01:40:33.740Z] GC before operation: completed in 92.651 ms, heap usage 168.369 MB -> 67.243 MB. [2025-11-20T01:40:41.619Z] ====== als (apache-spark) [default], iteration 11 completed (8672.029 ms) ====== [2025-11-20T01:40:41.619Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-20T01:40:41.619Z] GC before operation: completed in 108.280 ms, heap usage 250.817 MB -> 67.363 MB. [2025-11-20T01:40:51.288Z] ====== als (apache-spark) [default], iteration 12 completed (8626.047 ms) ====== [2025-11-20T01:40:51.288Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-20T01:40:51.288Z] GC before operation: completed in 94.897 ms, heap usage 185.633 MB -> 67.903 MB. [2025-11-20T01:41:00.768Z] ====== als (apache-spark) [default], iteration 13 completed (8479.100 ms) ====== [2025-11-20T01:41:00.768Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-20T01:41:00.768Z] GC before operation: completed in 108.931 ms, heap usage 298.651 MB -> 68.136 MB. [2025-11-20T01:41:08.657Z] ====== als (apache-spark) [default], iteration 14 completed (8746.930 ms) ====== [2025-11-20T01:41:08.658Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-20T01:41:08.658Z] GC before operation: completed in 97.376 ms, heap usage 252.186 MB -> 68.438 MB. [2025-11-20T01:41:18.165Z] ====== als (apache-spark) [default], iteration 15 completed (8538.950 ms) ====== [2025-11-20T01:41:18.165Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-20T01:41:18.165Z] GC before operation: completed in 101.145 ms, heap usage 470.580 MB -> 68.870 MB. [2025-11-20T01:41:27.679Z] ====== als (apache-spark) [default], iteration 16 completed (8646.107 ms) ====== [2025-11-20T01:41:27.679Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-20T01:41:27.679Z] GC before operation: completed in 103.632 ms, heap usage 512.611 MB -> 69.133 MB. [2025-11-20T01:41:35.688Z] ====== als (apache-spark) [default], iteration 17 completed (8757.366 ms) ====== [2025-11-20T01:41:35.688Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-20T01:41:35.688Z] GC before operation: completed in 97.982 ms, heap usage 162.709 MB -> 68.960 MB. [2025-11-20T01:41:45.229Z] ====== als (apache-spark) [default], iteration 18 completed (8657.534 ms) ====== [2025-11-20T01:41:45.229Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-20T01:41:45.229Z] GC before operation: completed in 98.280 ms, heap usage 271.864 MB -> 69.513 MB. [2025-11-20T01:41:54.795Z] ====== als (apache-spark) [default], iteration 19 completed (8614.505 ms) ====== [2025-11-20T01:41:54.795Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-20T01:41:54.795Z] GC before operation: completed in 96.670 ms, heap usage 204.282 MB -> 69.654 MB. [2025-11-20T01:42:02.591Z] ====== als (apache-spark) [default], iteration 20 completed (8717.804 ms) ====== [2025-11-20T01:42:02.591Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-20T01:42:02.591Z] GC before operation: completed in 98.430 ms, heap usage 263.040 MB -> 69.907 MB. [2025-11-20T01:42:12.079Z] ====== als (apache-spark) [default], iteration 21 completed (8599.416 ms) ====== [2025-11-20T01:42:12.079Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-20T01:42:12.079Z] GC before operation: completed in 99.617 ms, heap usage 366.458 MB -> 70.445 MB. [2025-11-20T01:42:20.048Z] ====== als (apache-spark) [default], iteration 22 completed (8488.018 ms) ====== [2025-11-20T01:42:20.048Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-20T01:42:20.456Z] GC before operation: completed in 97.886 ms, heap usage 244.243 MB -> 70.481 MB. [2025-11-20T01:42:28.806Z] ====== als (apache-spark) [default], iteration 23 completed (8361.611 ms) ====== [2025-11-20T01:42:28.806Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-20T01:42:29.177Z] GC before operation: completed in 101.763 ms, heap usage 406.565 MB -> 71.094 MB. [2025-11-20T01:42:38.619Z] ====== als (apache-spark) [default], iteration 24 completed (8607.095 ms) ====== [2025-11-20T01:42:38.619Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-20T01:42:38.619Z] GC before operation: completed in 102.640 ms, heap usage 560.116 MB -> 71.441 MB. [2025-11-20T01:42:46.449Z] ====== als (apache-spark) [default], iteration 25 completed (8465.561 ms) ====== [2025-11-20T01:42:46.449Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-20T01:42:46.824Z] GC before operation: completed in 109.783 ms, heap usage 596.490 MB -> 71.607 MB. [2025-11-20T01:42:56.309Z] ====== als (apache-spark) [default], iteration 26 completed (8355.078 ms) ====== [2025-11-20T01:42:56.309Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-20T01:42:56.309Z] GC before operation: completed in 103.095 ms, heap usage 295.124 MB -> 71.632 MB. [2025-11-20T01:43:04.330Z] ====== als (apache-spark) [default], iteration 27 completed (8535.164 ms) ====== [2025-11-20T01:43:04.330Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-20T01:43:04.330Z] GC before operation: completed in 101.727 ms, heap usage 196.108 MB -> 71.737 MB. [2025-11-20T01:43:13.933Z] ====== als (apache-spark) [default], iteration 28 completed (8236.930 ms) ====== [2025-11-20T01:43:13.933Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-20T01:43:13.933Z] GC before operation: completed in 103.591 ms, heap usage 560.712 MB -> 72.499 MB. [2025-11-20T01:43:21.760Z] ====== als (apache-spark) [default], iteration 29 completed (8419.231 ms) ====== [2025-11-20T01:43:21.760Z] ----------------------------------- [2025-11-20T01:43:21.760Z] renaissance-als_0_PASSED [2025-11-20T01:43:21.760Z] ----------------------------------- [2025-11-20T01:43:21.760Z] [2025-11-20T01:43:21.760Z] TEST TEARDOWN: [2025-11-20T01:43:21.760Z] Nothing to be done for teardown. [2025-11-20T01:43:21.760Z] renaissance-als_0 Finish Time: Thu Nov 20 01:43:21 2025 Epoch Time (ms): 1763603001695