[2025-09-03T21:36:11.506Z] Running test renaissance-als_0 ... [2025-09-03T21:36:11.506Z] =============================================== [2025-09-03T21:36:11.506Z] renaissance-als_0 Start Time: Wed Sep 3 21:36:11 2025 Epoch Time (ms): 1756935371043 [2025-09-03T21:36:11.506Z] variation: NoOptions [2025-09-03T21:36:11.506Z] JVM_OPTIONS: [2025-09-03T21:36:11.506Z] { \ [2025-09-03T21:36:11.506Z] echo ""; echo "TEST SETUP:"; \ [2025-09-03T21:36:11.506Z] echo "Nothing to be done for setup."; \ [2025-09-03T21:36:11.506Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17569353705799/renaissance-als_0"; \ [2025-09-03T21:36:11.506Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17569353705799/renaissance-als_0"; \ [2025-09-03T21:36:11.506Z] echo ""; echo "TESTING:"; \ [2025-09-03T21:36:11.506Z] "/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_17569353705799/renaissance-als_0"/als.json" als; \ [2025-09-03T21:36:11.506Z] 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_17569353705799/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-03T21:36:11.506Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-03T21:36:11.506Z] echo "Nothing to be done for teardown."; \ [2025-09-03T21:36:11.506Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17569353705799/TestTargetResult"; [2025-09-03T21:36:11.506Z] [2025-09-03T21:36:11.506Z] TEST SETUP: [2025-09-03T21:36:11.506Z] Nothing to be done for setup. [2025-09-03T21:36:11.506Z] [2025-09-03T21:36:11.506Z] TESTING: [2025-09-03T21:36:21.491Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-09-03T21:36:27.301Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-09-03T21:36:27.301Z] GC before operation: completed in 49.266 ms, heap usage 51.920 MB -> 35.290 MB. [2025-09-03T21:36:54.317Z] ====== als (apache-spark) [default], iteration 0 completed (22763.165 ms) ====== [2025-09-03T21:36:54.317Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-09-03T21:36:54.317Z] GC before operation: completed in 100.069 ms, heap usage 195.241 MB -> 62.174 MB. [2025-09-03T21:37:01.887Z] ====== als (apache-spark) [default], iteration 1 completed (9665.919 ms) ====== [2025-09-03T21:37:01.887Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-09-03T21:37:01.887Z] GC before operation: completed in 89.984 ms, heap usage 361.041 MB -> 63.238 MB. [2025-09-03T21:37:10.626Z] ====== als (apache-spark) [default], iteration 2 completed (8722.138 ms) ====== [2025-09-03T21:37:10.626Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-09-03T21:37:10.626Z] GC before operation: completed in 93.734 ms, heap usage 481.780 MB -> 64.065 MB. [2025-09-03T21:37:23.301Z] ====== als (apache-spark) [default], iteration 3 completed (11022.703 ms) ====== [2025-09-03T21:37:23.301Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-09-03T21:37:23.301Z] GC before operation: completed in 98.706 ms, heap usage 184.630 MB -> 64.176 MB. [2025-09-03T21:37:30.267Z] ====== als (apache-spark) [default], iteration 4 completed (8582.197 ms) ====== [2025-09-03T21:37:30.267Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-09-03T21:37:30.267Z] GC before operation: completed in 91.298 ms, heap usage 508.233 MB -> 65.178 MB. [2025-09-03T21:37:43.746Z] ====== als (apache-spark) [default], iteration 5 completed (11096.491 ms) ====== [2025-09-03T21:37:43.746Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-09-03T21:37:43.746Z] GC before operation: completed in 91.449 ms, heap usage 376.145 MB -> 65.509 MB. [2025-09-03T21:37:53.803Z] ====== als (apache-spark) [default], iteration 6 completed (11672.753 ms) ====== [2025-09-03T21:37:53.803Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-09-03T21:37:53.803Z] GC before operation: completed in 111.455 ms, heap usage 354.657 MB -> 66.206 MB. [2025-09-03T21:38:02.176Z] ====== als (apache-spark) [default], iteration 7 completed (8193.172 ms) ====== [2025-09-03T21:38:02.177Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-09-03T21:38:02.177Z] GC before operation: completed in 118.135 ms, heap usage 434.238 MB -> 66.907 MB. [2025-09-03T21:38:12.195Z] ====== als (apache-spark) [default], iteration 8 completed (8380.424 ms) ====== [2025-09-03T21:38:12.195Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-09-03T21:38:12.195Z] GC before operation: completed in 112.845 ms, heap usage 294.976 MB -> 67.076 MB. [2025-09-03T21:38:19.807Z] ====== als (apache-spark) [default], iteration 9 completed (8638.587 ms) ====== [2025-09-03T21:38:19.807Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-09-03T21:38:19.807Z] GC before operation: completed in 105.695 ms, heap usage 175.218 MB -> 67.204 MB. [2025-09-03T21:38:30.019Z] ====== als (apache-spark) [default], iteration 10 completed (9022.162 ms) ====== [2025-09-03T21:38:30.020Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-09-03T21:38:30.020Z] GC before operation: completed in 132.147 ms, heap usage 492.009 MB -> 67.938 MB. [2025-09-03T21:38:40.015Z] ====== als (apache-spark) [default], iteration 11 completed (9357.295 ms) ====== [2025-09-03T21:38:40.015Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-09-03T21:38:40.015Z] GC before operation: completed in 116.858 ms, heap usage 214.867 MB -> 67.943 MB. [2025-09-03T21:38:48.387Z] ====== als (apache-spark) [default], iteration 12 completed (8513.895 ms) ====== [2025-09-03T21:38:48.387Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-09-03T21:38:48.387Z] GC before operation: completed in 112.999 ms, heap usage 181.784 MB -> 67.977 MB. [2025-09-03T21:38:57.541Z] ====== als (apache-spark) [default], iteration 13 completed (7532.707 ms) ====== [2025-09-03T21:38:57.541Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-09-03T21:38:57.541Z] GC before operation: completed in 108.497 ms, heap usage 487.501 MB -> 68.739 MB. [2025-09-03T21:39:04.547Z] ====== als (apache-spark) [default], iteration 14 completed (8632.453 ms) ====== [2025-09-03T21:39:04.547Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-09-03T21:39:04.547Z] GC before operation: completed in 95.567 ms, heap usage 130.072 MB -> 68.547 MB. [2025-09-03T21:39:15.297Z] ====== als (apache-spark) [default], iteration 15 completed (6903.549 ms) ====== [2025-09-03T21:39:15.297Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-09-03T21:39:15.297Z] GC before operation: completed in 99.582 ms, heap usage 134.256 MB -> 68.747 MB. [2025-09-03T21:39:23.026Z] ====== als (apache-spark) [default], iteration 16 completed (9419.324 ms) ====== [2025-09-03T21:39:23.026Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-09-03T21:39:23.026Z] GC before operation: completed in 108.793 ms, heap usage 216.982 MB -> 69.029 MB. [2025-09-03T21:39:33.355Z] ====== als (apache-spark) [default], iteration 17 completed (10567.904 ms) ====== [2025-09-03T21:39:33.355Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-09-03T21:39:33.355Z] GC before operation: completed in 91.760 ms, heap usage 285.333 MB -> 69.404 MB. [2025-09-03T21:39:40.325Z] ====== als (apache-spark) [default], iteration 18 completed (7351.420 ms) ====== [2025-09-03T21:39:40.325Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-09-03T21:39:40.325Z] GC before operation: completed in 101.094 ms, heap usage 294.810 MB -> 69.652 MB. [2025-09-03T21:39:50.905Z] ====== als (apache-spark) [default], iteration 19 completed (7789.561 ms) ====== [2025-09-03T21:39:50.905Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-09-03T21:39:50.905Z] GC before operation: completed in 121.337 ms, heap usage 152.940 MB -> 69.826 MB. [2025-09-03T21:40:02.677Z] ====== als (apache-spark) [default], iteration 20 completed (14407.432 ms) ====== [2025-09-03T21:40:02.677Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-09-03T21:40:02.677Z] GC before operation: completed in 125.217 ms, heap usage 283.371 MB -> 70.190 MB. [2025-09-03T21:40:11.064Z] ====== als (apache-spark) [default], iteration 21 completed (7833.127 ms) ====== [2025-09-03T21:40:11.064Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-09-03T21:40:11.064Z] GC before operation: completed in 100.684 ms, heap usage 484.580 MB -> 70.818 MB. [2025-09-03T21:40:23.078Z] ====== als (apache-spark) [default], iteration 22 completed (11281.050 ms) ====== [2025-09-03T21:40:23.078Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-09-03T21:40:23.078Z] GC before operation: completed in 90.334 ms, heap usage 260.272 MB -> 70.750 MB. [2025-09-03T21:40:30.026Z] ====== als (apache-spark) [default], iteration 23 completed (6939.265 ms) ====== [2025-09-03T21:40:30.026Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-09-03T21:40:30.026Z] GC before operation: completed in 107.795 ms, heap usage 599.210 MB -> 71.928 MB. [2025-09-03T21:40:37.086Z] ====== als (apache-spark) [default], iteration 24 completed (7256.053 ms) ====== [2025-09-03T21:40:37.086Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-09-03T21:40:37.086Z] GC before operation: completed in 111.722 ms, heap usage 466.423 MB -> 71.564 MB. [2025-09-03T21:40:44.507Z] ====== als (apache-spark) [default], iteration 25 completed (7098.183 ms) ====== [2025-09-03T21:40:44.507Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-09-03T21:40:44.507Z] GC before operation: completed in 123.888 ms, heap usage 403.979 MB -> 71.577 MB. [2025-09-03T21:40:52.943Z] ====== als (apache-spark) [default], iteration 26 completed (7015.239 ms) ====== [2025-09-03T21:40:52.943Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-09-03T21:40:52.943Z] GC before operation: completed in 119.934 ms, heap usage 270.411 MB -> 71.702 MB. [2025-09-03T21:40:59.946Z] ====== als (apache-spark) [default], iteration 27 completed (7069.005 ms) ====== [2025-09-03T21:40:59.946Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-09-03T21:40:59.946Z] GC before operation: completed in 99.538 ms, heap usage 329.587 MB -> 72.102 MB. [2025-09-03T21:41:06.923Z] ====== als (apache-spark) [default], iteration 28 completed (7318.109 ms) ====== [2025-09-03T21:41:06.923Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-09-03T21:41:06.923Z] GC before operation: completed in 92.271 ms, heap usage 460.101 MB -> 73.132 MB. [2025-09-03T21:41:13.978Z] ====== als (apache-spark) [default], iteration 29 completed (7099.334 ms) ====== [2025-09-03T21:41:14.753Z] ----------------------------------- [2025-09-03T21:41:14.753Z] renaissance-als_0_PASSED [2025-09-03T21:41:14.753Z] ----------------------------------- [2025-09-03T21:41:14.753Z] [2025-09-03T21:41:14.753Z] TEST TEARDOWN: [2025-09-03T21:41:14.753Z] Nothing to be done for teardown. [2025-09-03T21:41:14.753Z] renaissance-als_0 Finish Time: Wed Sep 3 21:41:14 2025 Epoch Time (ms): 1756935674574