[2025-09-26T12:54:23.604Z] Running test renaissance-als_0 ... [2025-09-26T12:54:23.907Z] =============================================== [2025-09-26T12:54:23.907Z] renaissance-als_0 Start Time: Fri Sep 26 12:54:23 2025 Epoch Time (ms): 1758891263610 [2025-09-26T12:54:23.907Z] variation: NoOptions [2025-09-26T12:54:23.907Z] JVM_OPTIONS: [2025-09-26T12:54:23.907Z] { \ [2025-09-26T12:54:23.907Z] echo ""; echo "TEST SETUP:"; \ [2025-09-26T12:54:23.907Z] echo "Nothing to be done for setup."; \ [2025-09-26T12:54:23.907Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17588910811969/renaissance-als_0"; \ [2025-09-26T12:54:23.907Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17588910811969/renaissance-als_0"; \ [2025-09-26T12:54:23.907Z] echo ""; echo "TESTING:"; \ [2025-09-26T12:54:23.907Z] "/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_17588910811969/renaissance-als_0"/als.json" als; \ [2025-09-26T12:54:23.907Z] 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_17588910811969/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-26T12:54:23.907Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-26T12:54:23.907Z] echo "Nothing to be done for teardown."; \ [2025-09-26T12:54:23.907Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17588910811969/TestTargetResult"; [2025-09-26T12:54:23.907Z] [2025-09-26T12:54:23.907Z] TEST SETUP: [2025-09-26T12:54:23.907Z] Nothing to be done for setup. [2025-09-26T12:54:23.907Z] [2025-09-26T12:54:23.907Z] TESTING: [2025-09-26T12:54:32.477Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-09-26T12:54:36.058Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-09-26T12:54:36.058Z] GC before operation: completed in 40.663 ms, heap usage 51.938 MB -> 34.904 MB. [2025-09-26T12:55:03.334Z] ====== als (apache-spark) [default], iteration 0 completed (25741.116 ms) ====== [2025-09-26T12:55:03.334Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-09-26T12:55:03.334Z] GC before operation: completed in 108.344 ms, heap usage 301.762 MB -> 61.791 MB. [2025-09-26T12:55:16.174Z] ====== als (apache-spark) [default], iteration 1 completed (12170.770 ms) ====== [2025-09-26T12:55:16.174Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-09-26T12:55:16.174Z] GC before operation: completed in 95.939 ms, heap usage 124.701 MB -> 62.534 MB. [2025-09-26T12:55:26.652Z] ====== als (apache-spark) [default], iteration 2 completed (10227.141 ms) ====== [2025-09-26T12:55:26.652Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-09-26T12:55:26.652Z] GC before operation: completed in 95.884 ms, heap usage 284.615 MB -> 63.177 MB. [2025-09-26T12:55:37.135Z] ====== als (apache-spark) [default], iteration 3 completed (9979.918 ms) ====== [2025-09-26T12:55:37.135Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-09-26T12:55:37.135Z] GC before operation: completed in 103.750 ms, heap usage 536.398 MB -> 64.216 MB. [2025-09-26T12:55:47.631Z] ====== als (apache-spark) [default], iteration 4 completed (10106.158 ms) ====== [2025-09-26T12:55:47.631Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-09-26T12:55:47.631Z] GC before operation: completed in 100.028 ms, heap usage 258.188 MB -> 64.358 MB. [2025-09-26T12:55:58.110Z] ====== als (apache-spark) [default], iteration 5 completed (9775.063 ms) ====== [2025-09-26T12:55:58.110Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-09-26T12:55:58.110Z] GC before operation: completed in 107.771 ms, heap usage 452.002 MB -> 65.232 MB. [2025-09-26T12:56:06.703Z] ====== als (apache-spark) [default], iteration 6 completed (9414.980 ms) ====== [2025-09-26T12:56:06.703Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-09-26T12:56:06.703Z] GC before operation: completed in 137.957 ms, heap usage 391.516 MB -> 65.533 MB. [2025-09-26T12:56:17.177Z] ====== als (apache-spark) [default], iteration 7 completed (9508.813 ms) ====== [2025-09-26T12:56:17.177Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-09-26T12:56:17.177Z] GC before operation: completed in 99.510 ms, heap usage 508.436 MB -> 66.373 MB. [2025-09-26T12:56:27.673Z] ====== als (apache-spark) [default], iteration 8 completed (9917.596 ms) ====== [2025-09-26T12:56:27.674Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-09-26T12:56:27.674Z] GC before operation: completed in 104.644 ms, heap usage 449.487 MB -> 67.120 MB. [2025-09-26T12:56:38.276Z] ====== als (apache-spark) [default], iteration 9 completed (9742.511 ms) ====== [2025-09-26T12:56:38.276Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-09-26T12:56:38.276Z] GC before operation: completed in 92.287 ms, heap usage 434.922 MB -> 66.939 MB. [2025-09-26T12:56:46.860Z] ====== als (apache-spark) [default], iteration 10 completed (9649.182 ms) ====== [2025-09-26T12:56:46.860Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-09-26T12:56:46.860Z] GC before operation: completed in 99.641 ms, heap usage 243.695 MB -> 66.907 MB. [2025-09-26T12:56:57.345Z] ====== als (apache-spark) [default], iteration 11 completed (9277.028 ms) ====== [2025-09-26T12:56:57.345Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-09-26T12:56:57.345Z] GC before operation: completed in 95.247 ms, heap usage 149.664 MB -> 67.141 MB. [2025-09-26T12:57:05.926Z] ====== als (apache-spark) [default], iteration 12 completed (9499.180 ms) ====== [2025-09-26T12:57:05.926Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-09-26T12:57:05.926Z] GC before operation: completed in 91.597 ms, heap usage 195.054 MB -> 67.298 MB. [2025-09-26T12:57:16.418Z] ====== als (apache-spark) [default], iteration 13 completed (9450.546 ms) ====== [2025-09-26T12:57:16.418Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-09-26T12:57:16.418Z] GC before operation: completed in 86.677 ms, heap usage 201.992 MB -> 67.560 MB. [2025-09-26T12:57:26.896Z] ====== als (apache-spark) [default], iteration 14 completed (9434.212 ms) ====== [2025-09-26T12:57:26.896Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-09-26T12:57:26.896Z] GC before operation: completed in 89.459 ms, heap usage 431.935 MB -> 68.237 MB. [2025-09-26T12:57:35.519Z] ====== als (apache-spark) [default], iteration 15 completed (9493.102 ms) ====== [2025-09-26T12:57:35.519Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-09-26T12:57:35.519Z] GC before operation: completed in 87.931 ms, heap usage 77.394 MB -> 67.618 MB. [2025-09-26T12:57:46.003Z] ====== als (apache-spark) [default], iteration 16 completed (9223.552 ms) ====== [2025-09-26T12:57:46.003Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-09-26T12:57:46.003Z] GC before operation: completed in 89.276 ms, heap usage 104.681 MB -> 68.309 MB. [2025-09-26T12:57:54.574Z] ====== als (apache-spark) [default], iteration 17 completed (9543.829 ms) ====== [2025-09-26T12:57:54.574Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-09-26T12:57:54.574Z] GC before operation: completed in 99.460 ms, heap usage 496.511 MB -> 69.063 MB. [2025-09-26T12:58:05.057Z] ====== als (apache-spark) [default], iteration 18 completed (9285.920 ms) ====== [2025-09-26T12:58:05.057Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-09-26T12:58:05.057Z] GC before operation: completed in 93.125 ms, heap usage 224.557 MB -> 68.963 MB. [2025-09-26T12:58:13.641Z] ====== als (apache-spark) [default], iteration 19 completed (9344.283 ms) ====== [2025-09-26T12:58:13.641Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-09-26T12:58:13.641Z] GC before operation: completed in 93.526 ms, heap usage 484.649 MB -> 69.496 MB. [2025-09-26T12:58:24.112Z] ====== als (apache-spark) [default], iteration 20 completed (9789.305 ms) ====== [2025-09-26T12:58:24.112Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-09-26T12:58:24.112Z] GC before operation: completed in 99.206 ms, heap usage 184.604 MB -> 69.389 MB. [2025-09-26T12:58:34.646Z] ====== als (apache-spark) [default], iteration 21 completed (9946.247 ms) ====== [2025-09-26T12:58:34.646Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-09-26T12:58:34.646Z] GC before operation: completed in 90.631 ms, heap usage 186.413 MB -> 69.601 MB. [2025-09-26T12:58:45.119Z] ====== als (apache-spark) [default], iteration 22 completed (9364.425 ms) ====== [2025-09-26T12:58:45.119Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-09-26T12:58:45.119Z] GC before operation: completed in 95.207 ms, heap usage 314.005 MB -> 70.023 MB. [2025-09-26T12:58:53.682Z] ====== als (apache-spark) [default], iteration 23 completed (9608.432 ms) ====== [2025-09-26T12:58:53.682Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-09-26T12:58:53.682Z] GC before operation: completed in 92.741 ms, heap usage 303.996 MB -> 70.409 MB. [2025-09-26T12:59:04.211Z] ====== als (apache-spark) [default], iteration 24 completed (9904.278 ms) ====== [2025-09-26T12:59:04.211Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-09-26T12:59:04.211Z] GC before operation: completed in 95.936 ms, heap usage 202.370 MB -> 70.413 MB. [2025-09-26T12:59:14.677Z] ====== als (apache-spark) [default], iteration 25 completed (9096.708 ms) ====== [2025-09-26T12:59:14.677Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-09-26T12:59:14.677Z] GC before operation: completed in 95.611 ms, heap usage 234.323 MB -> 70.794 MB. [2025-09-26T12:59:23.316Z] ====== als (apache-spark) [default], iteration 26 completed (9641.204 ms) ====== [2025-09-26T12:59:23.316Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-09-26T12:59:23.316Z] GC before operation: completed in 90.500 ms, heap usage 248.944 MB -> 71.043 MB. [2025-09-26T12:59:33.791Z] ====== als (apache-spark) [default], iteration 27 completed (9545.692 ms) ====== [2025-09-26T12:59:33.791Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-09-26T12:59:33.791Z] GC before operation: completed in 94.573 ms, heap usage 254.324 MB -> 71.284 MB. [2025-09-26T12:59:44.274Z] ====== als (apache-spark) [default], iteration 28 completed (9618.141 ms) ====== [2025-09-26T12:59:44.274Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-09-26T12:59:44.274Z] GC before operation: completed in 112.962 ms, heap usage 334.852 MB -> 71.237 MB. [2025-09-26T12:59:52.840Z] ====== als (apache-spark) [default], iteration 29 completed (9033.011 ms) ====== [2025-09-26T12:59:52.841Z] ----------------------------------- [2025-09-26T12:59:52.841Z] renaissance-als_0_PASSED [2025-09-26T12:59:52.841Z] ----------------------------------- [2025-09-26T12:59:52.841Z] [2025-09-26T12:59:52.841Z] TEST TEARDOWN: [2025-09-26T12:59:52.841Z] Nothing to be done for teardown. [2025-09-26T12:59:52.841Z] renaissance-als_0 Finish Time: Fri Sep 26 12:59:52 2025 Epoch Time (ms): 1758891592687