[2025-12-25T00:27:53.064Z] Running test renaissance-als_0 ... [2025-12-25T00:27:53.064Z] =============================================== [2025-12-25T00:27:53.064Z] renaissance-als_0 Start Time: Thu Dec 25 00:27:52 2025 Epoch Time (ms): 1766622472764 [2025-12-25T00:27:53.064Z] variation: NoOptions [2025-12-25T00:27:53.064Z] JVM_OPTIONS: [2025-12-25T00:27:53.064Z] { \ [2025-12-25T00:27:53.064Z] echo ""; echo "TEST SETUP:"; \ [2025-12-25T00:27:53.064Z] echo "Nothing to be done for setup."; \ [2025-12-25T00:27:53.064Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17666224729133/renaissance-als_0"; \ [2025-12-25T00:27:53.064Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17666224729133/renaissance-als_0"; \ [2025-12-25T00:27:53.064Z] echo ""; echo "TESTING:"; \ [2025-12-25T00:27:53.064Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-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_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17666224729133/renaissance-als_0"/als.json" als; \ [2025-12-25T00:27:53.064Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17666224729133/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-25T00:27:53.064Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-25T00:27:53.064Z] echo "Nothing to be done for teardown."; \ [2025-12-25T00:27:53.064Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17666224729133/TestTargetResult"; [2025-12-25T00:27:53.064Z] [2025-12-25T00:27:53.064Z] TEST SETUP: [2025-12-25T00:27:53.064Z] Nothing to be done for setup. [2025-12-25T00:27:53.064Z] [2025-12-25T00:27:53.064Z] TESTING: [2025-12-25T00:28:01.151Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-25T00:28:08.303Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-25T00:28:08.304Z] GC before operation: completed in 85.198 ms, heap usage 66.926 MB -> 34.912 MB. [2025-12-25T00:28:43.248Z] ====== als (apache-spark) [default], iteration 0 completed (32651.257 ms) ====== [2025-12-25T00:28:43.248Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-25T00:28:43.248Z] GC before operation: completed in 212.265 ms, heap usage 868.651 MB -> 62.447 MB. [2025-12-25T00:28:56.780Z] ====== als (apache-spark) [default], iteration 1 completed (12455.572 ms) ====== [2025-12-25T00:28:56.780Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-25T00:28:56.780Z] GC before operation: completed in 164.659 ms, heap usage 160.827 MB -> 62.682 MB. [2025-12-25T00:29:06.482Z] ====== als (apache-spark) [default], iteration 2 completed (10544.731 ms) ====== [2025-12-25T00:29:06.482Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-25T00:29:06.482Z] GC before operation: completed in 143.384 ms, heap usage 284.280 MB -> 63.407 MB. [2025-12-25T00:29:17.996Z] ====== als (apache-spark) [default], iteration 3 completed (10131.324 ms) ====== [2025-12-25T00:29:17.996Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-25T00:29:17.996Z] GC before operation: completed in 151.665 ms, heap usage 372.425 MB -> 64.044 MB. [2025-12-25T00:29:26.724Z] ====== als (apache-spark) [default], iteration 4 completed (9659.588 ms) ====== [2025-12-25T00:29:26.724Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-25T00:29:27.469Z] GC before operation: completed in 184.682 ms, heap usage 291.191 MB -> 64.524 MB. [2025-12-25T00:29:38.942Z] ====== als (apache-spark) [default], iteration 5 completed (10143.574 ms) ====== [2025-12-25T00:29:38.942Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-25T00:29:38.942Z] GC before operation: completed in 173.176 ms, heap usage 146.913 MB -> 64.878 MB. [2025-12-25T00:29:48.638Z] ====== als (apache-spark) [default], iteration 6 completed (10305.467 ms) ====== [2025-12-25T00:29:48.638Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-25T00:29:48.638Z] GC before operation: completed in 180.227 ms, heap usage 436.587 MB -> 65.829 MB. [2025-12-25T00:29:58.316Z] ====== als (apache-spark) [default], iteration 7 completed (9785.527 ms) ====== [2025-12-25T00:29:58.316Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-25T00:29:59.068Z] GC before operation: completed in 167.293 ms, heap usage 518.182 MB -> 66.507 MB. [2025-12-25T00:30:08.754Z] ====== als (apache-spark) [default], iteration 8 completed (9743.819 ms) ====== [2025-12-25T00:30:08.754Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-25T00:30:08.754Z] GC before operation: completed in 169.372 ms, heap usage 256.821 MB -> 66.737 MB. [2025-12-25T00:30:20.262Z] ====== als (apache-spark) [default], iteration 9 completed (9935.246 ms) ====== [2025-12-25T00:30:20.262Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-25T00:30:20.262Z] GC before operation: completed in 197.526 ms, heap usage 419.278 MB -> 67.219 MB. [2025-12-25T00:30:30.434Z] ====== als (apache-spark) [default], iteration 10 completed (9670.362 ms) ====== [2025-12-25T00:30:30.434Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-25T00:30:30.434Z] GC before operation: completed in 171.322 ms, heap usage 367.184 MB -> 67.309 MB. [2025-12-25T00:30:40.144Z] ====== als (apache-spark) [default], iteration 11 completed (9443.295 ms) ====== [2025-12-25T00:30:40.144Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-25T00:30:40.144Z] GC before operation: completed in 184.782 ms, heap usage 555.683 MB -> 67.864 MB. [2025-12-25T00:30:49.858Z] ====== als (apache-spark) [default], iteration 12 completed (10122.283 ms) ====== [2025-12-25T00:30:49.858Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-25T00:30:49.858Z] GC before operation: completed in 156.656 ms, heap usage 747.744 MB -> 68.405 MB. [2025-12-25T00:30:59.541Z] ====== als (apache-spark) [default], iteration 13 completed (9735.758 ms) ====== [2025-12-25T00:30:59.541Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-25T00:31:00.291Z] GC before operation: completed in 150.844 ms, heap usage 470.070 MB -> 68.373 MB. [2025-12-25T00:31:10.008Z] ====== als (apache-spark) [default], iteration 14 completed (9835.651 ms) ====== [2025-12-25T00:31:10.008Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-25T00:31:10.008Z] GC before operation: completed in 182.431 ms, heap usage 453.591 MB -> 68.625 MB. [2025-12-25T00:31:20.026Z] ====== als (apache-spark) [default], iteration 15 completed (9382.354 ms) ====== [2025-12-25T00:31:20.026Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-25T00:31:20.026Z] GC before operation: completed in 173.721 ms, heap usage 269.239 MB -> 68.506 MB. [2025-12-25T00:31:29.727Z] ====== als (apache-spark) [default], iteration 16 completed (9030.013 ms) ====== [2025-12-25T00:31:29.727Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-25T00:31:29.727Z] GC before operation: completed in 194.414 ms, heap usage 239.518 MB -> 68.755 MB. [2025-12-25T00:31:39.416Z] ====== als (apache-spark) [default], iteration 17 completed (9645.396 ms) ====== [2025-12-25T00:31:39.416Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-25T00:31:39.416Z] GC before operation: completed in 188.122 ms, heap usage 542.761 MB -> 69.422 MB. [2025-12-25T00:31:50.964Z] ====== als (apache-spark) [default], iteration 18 completed (9744.840 ms) ====== [2025-12-25T00:31:50.964Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-25T00:31:50.964Z] GC before operation: completed in 183.418 ms, heap usage 169.314 MB -> 69.218 MB. [2025-12-25T00:32:00.653Z] ====== als (apache-spark) [default], iteration 19 completed (9551.216 ms) ====== [2025-12-25T00:32:00.653Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-25T00:32:00.653Z] GC before operation: completed in 178.657 ms, heap usage 163.731 MB -> 69.434 MB. [2025-12-25T00:32:10.395Z] ====== als (apache-spark) [default], iteration 20 completed (9978.234 ms) ====== [2025-12-25T00:32:10.395Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-25T00:32:10.395Z] GC before operation: completed in 184.595 ms, heap usage 548.701 MB -> 70.228 MB. [2025-12-25T00:32:20.580Z] ====== als (apache-spark) [default], iteration 21 completed (9677.920 ms) ====== [2025-12-25T00:32:20.580Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-25T00:32:20.580Z] GC before operation: completed in 194.646 ms, heap usage 301.468 MB -> 70.134 MB. [2025-12-25T00:32:30.270Z] ====== als (apache-spark) [default], iteration 22 completed (9712.924 ms) ====== [2025-12-25T00:32:30.270Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-25T00:32:31.018Z] GC before operation: completed in 197.933 ms, heap usage 156.645 MB -> 70.318 MB. [2025-12-25T00:32:40.721Z] ====== als (apache-spark) [default], iteration 23 completed (9540.813 ms) ====== [2025-12-25T00:32:40.721Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-25T00:32:40.721Z] GC before operation: completed in 183.801 ms, heap usage 482.740 MB -> 70.949 MB. [2025-12-25T00:32:50.395Z] ====== als (apache-spark) [default], iteration 24 completed (9357.305 ms) ====== [2025-12-25T00:32:50.395Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-25T00:32:50.395Z] GC before operation: completed in 179.687 ms, heap usage 349.492 MB -> 71.047 MB. [2025-12-25T00:33:00.211Z] ====== als (apache-spark) [default], iteration 25 completed (9880.055 ms) ====== [2025-12-25T00:33:00.211Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-25T00:33:00.959Z] GC before operation: completed in 156.490 ms, heap usage 123.068 MB -> 70.995 MB. [2025-12-25T00:33:10.669Z] ====== als (apache-spark) [default], iteration 26 completed (9322.970 ms) ====== [2025-12-25T00:33:10.669Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-25T00:33:10.669Z] GC before operation: completed in 149.771 ms, heap usage 423.312 MB -> 71.569 MB. [2025-12-25T00:33:20.680Z] ====== als (apache-spark) [default], iteration 27 completed (8996.843 ms) ====== [2025-12-25T00:33:20.680Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-25T00:33:20.680Z] GC before operation: completed in 157.372 ms, heap usage 399.530 MB -> 71.804 MB. [2025-12-25T00:33:30.356Z] ====== als (apache-spark) [default], iteration 28 completed (9261.013 ms) ====== [2025-12-25T00:33:30.356Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-25T00:33:30.356Z] GC before operation: completed in 182.497 ms, heap usage 789.054 MB -> 72.622 MB. [2025-12-25T00:33:40.056Z] ====== als (apache-spark) [default], iteration 29 completed (9102.018 ms) ====== [2025-12-25T00:33:40.056Z] ----------------------------------- [2025-12-25T00:33:40.056Z] renaissance-als_0_PASSED [2025-12-25T00:33:40.056Z] ----------------------------------- [2025-12-25T00:33:40.056Z] [2025-12-25T00:33:40.056Z] TEST TEARDOWN: [2025-12-25T00:33:40.056Z] Nothing to be done for teardown. [2025-12-25T00:33:40.056Z] renaissance-als_0 Finish Time: Thu Dec 25 00:33:39 2025 Epoch Time (ms): 1766622819105