[2025-12-24T22:28:33.158Z] Running test renaissance-als_0 ... [2025-12-24T22:28:33.158Z] =============================================== [2025-12-24T22:28:33.158Z] renaissance-als_0 Start Time: Wed Dec 24 22:28:33 2025 Epoch Time (ms): 1766615313023 [2025-12-24T22:28:33.158Z] variation: NoOptions [2025-12-24T22:28:33.158Z] JVM_OPTIONS: [2025-12-24T22:28:33.158Z] { \ [2025-12-24T22:28:33.158Z] echo ""; echo "TEST SETUP:"; \ [2025-12-24T22:28:33.158Z] echo "Nothing to be done for setup."; \ [2025-12-24T22:28:33.158Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17666147635981/renaissance-als_0"; \ [2025-12-24T22:28:33.158Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17666147635981/renaissance-als_0"; \ [2025-12-24T22:28:33.158Z] echo ""; echo "TESTING:"; \ [2025-12-24T22:28:33.158Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_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_riscv64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17666147635981/renaissance-als_0"/als.json" als; \ [2025-12-24T22:28:33.158Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17666147635981/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-24T22:28:33.158Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-24T22:28:33.158Z] echo "Nothing to be done for teardown."; \ [2025-12-24T22:28:33.158Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_17666147635981/TestTargetResult"; [2025-12-24T22:28:33.158Z] [2025-12-24T22:28:33.158Z] TEST SETUP: [2025-12-24T22:28:33.158Z] Nothing to be done for setup. [2025-12-24T22:28:33.158Z] [2025-12-24T22:28:33.158Z] TESTING: [2025-12-24T22:28:56.404Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-24T22:29:15.634Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-24T22:29:15.974Z] GC before operation: completed in 209.792 ms, heap usage 71.063 MB -> 35.502 MB. [2025-12-24T22:30:39.447Z] ====== als (apache-spark) [default], iteration 0 completed (67786.160 ms) ====== [2025-12-24T22:30:39.447Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-24T22:30:39.447Z] GC before operation: completed in 725.549 ms, heap usage 431.731 MB -> 75.892 MB. [2025-12-24T22:30:58.901Z] ====== als (apache-spark) [default], iteration 1 completed (27237.998 ms) ====== [2025-12-24T22:30:58.901Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-24T22:30:58.901Z] GC before operation: completed in 715.608 ms, heap usage 309.996 MB -> 76.556 MB. [2025-12-24T22:31:22.028Z] ====== als (apache-spark) [default], iteration 2 completed (23174.789 ms) ====== [2025-12-24T22:31:22.028Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-24T22:31:22.363Z] GC before operation: completed in 753.973 ms, heap usage 628.560 MB -> 77.645 MB. [2025-12-24T22:31:50.242Z] ====== als (apache-spark) [default], iteration 3 completed (23288.549 ms) ====== [2025-12-24T22:31:50.242Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-24T22:31:50.242Z] GC before operation: completed in 736.856 ms, heap usage 459.603 MB -> 78.000 MB. [2025-12-24T22:32:13.400Z] ====== als (apache-spark) [default], iteration 4 completed (22077.242 ms) ====== [2025-12-24T22:32:13.401Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-24T22:32:13.401Z] GC before operation: completed in 759.048 ms, heap usage 1006.157 MB -> 79.207 MB. [2025-12-24T22:32:36.637Z] ====== als (apache-spark) [default], iteration 5 completed (21797.333 ms) ====== [2025-12-24T22:32:36.638Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-24T22:32:36.638Z] GC before operation: completed in 749.356 ms, heap usage 177.370 MB -> 78.846 MB. [2025-12-24T22:32:59.773Z] ====== als (apache-spark) [default], iteration 6 completed (21595.430 ms) ====== [2025-12-24T22:32:59.773Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-24T22:32:59.773Z] GC before operation: completed in 785.626 ms, heap usage 874.919 MB -> 80.258 MB. [2025-12-24T22:33:22.975Z] ====== als (apache-spark) [default], iteration 7 completed (21719.176 ms) ====== [2025-12-24T22:33:22.975Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-24T22:33:22.975Z] GC before operation: completed in 843.957 ms, heap usage 1.228 GB -> 81.249 MB. [2025-12-24T22:33:46.215Z] ====== als (apache-spark) [default], iteration 8 completed (21911.588 ms) ====== [2025-12-24T22:33:46.215Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-24T22:33:46.215Z] GC before operation: completed in 818.006 ms, heap usage 653.273 MB -> 81.093 MB. [2025-12-24T22:34:09.446Z] ====== als (apache-spark) [default], iteration 9 completed (21042.245 ms) ====== [2025-12-24T22:34:09.446Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-24T22:34:09.446Z] GC before operation: completed in 807.377 ms, heap usage 569.489 MB -> 81.028 MB. [2025-12-24T22:34:32.609Z] ====== als (apache-spark) [default], iteration 10 completed (20419.024 ms) ====== [2025-12-24T22:34:32.609Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-24T22:34:32.609Z] GC before operation: completed in 814.200 ms, heap usage 129.225 MB -> 80.906 MB. [2025-12-24T22:34:51.825Z] ====== als (apache-spark) [default], iteration 11 completed (19721.693 ms) ====== [2025-12-24T22:34:51.825Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-24T22:34:52.169Z] GC before operation: completed in 908.182 ms, heap usage 276.233 MB -> 81.340 MB. [2025-12-24T22:35:15.559Z] ====== als (apache-spark) [default], iteration 12 completed (20223.631 ms) ====== [2025-12-24T22:35:15.559Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-24T22:35:15.559Z] GC before operation: completed in 839.576 ms, heap usage 182.092 MB -> 81.588 MB. [2025-12-24T22:35:38.697Z] ====== als (apache-spark) [default], iteration 13 completed (20095.403 ms) ====== [2025-12-24T22:35:38.697Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-24T22:35:38.697Z] GC before operation: completed in 855.672 ms, heap usage 252.272 MB -> 81.746 MB. [2025-12-24T22:35:57.923Z] ====== als (apache-spark) [default], iteration 14 completed (20385.905 ms) ====== [2025-12-24T22:35:57.923Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-24T22:35:58.257Z] GC before operation: completed in 764.902 ms, heap usage 707.907 MB -> 82.557 MB. [2025-12-24T22:36:21.391Z] ====== als (apache-spark) [default], iteration 15 completed (19867.864 ms) ====== [2025-12-24T22:36:21.391Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-24T22:36:21.391Z] GC before operation: completed in 798.611 ms, heap usage 1016.891 MB -> 83.378 MB. [2025-12-24T22:36:40.677Z] ====== als (apache-spark) [default], iteration 16 completed (19642.348 ms) ====== [2025-12-24T22:36:40.677Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-24T22:36:41.015Z] GC before operation: completed in 802.882 ms, heap usage 696.381 MB -> 83.219 MB. [2025-12-24T22:37:04.172Z] ====== als (apache-spark) [default], iteration 17 completed (20189.807 ms) ====== [2025-12-24T22:37:04.172Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-24T22:37:04.172Z] GC before operation: completed in 857.719 ms, heap usage 1.121 GB -> 83.946 MB. [2025-12-24T22:37:27.329Z] ====== als (apache-spark) [default], iteration 18 completed (20204.534 ms) ====== [2025-12-24T22:37:27.329Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-24T22:37:27.329Z] GC before operation: completed in 870.354 ms, heap usage 1.351 GB -> 84.522 MB. [2025-12-24T22:37:46.601Z] ====== als (apache-spark) [default], iteration 19 completed (20299.648 ms) ====== [2025-12-24T22:37:46.601Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-24T22:37:46.937Z] GC before operation: completed in 841.208 ms, heap usage 1.433 GB -> 84.864 MB. [2025-12-24T22:38:10.135Z] ====== als (apache-spark) [default], iteration 20 completed (21020.358 ms) ====== [2025-12-24T22:38:10.135Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-24T22:38:10.135Z] GC before operation: completed in 802.536 ms, heap usage 277.235 MB -> 83.714 MB. [2025-12-24T22:38:33.276Z] ====== als (apache-spark) [default], iteration 21 completed (20108.132 ms) ====== [2025-12-24T22:38:33.276Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-24T22:38:33.276Z] GC before operation: completed in 817.410 ms, heap usage 660.943 MB -> 84.486 MB. [2025-12-24T22:38:52.639Z] ====== als (apache-spark) [default], iteration 22 completed (20151.589 ms) ====== [2025-12-24T22:38:52.639Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-24T22:38:52.982Z] GC before operation: completed in 793.834 ms, heap usage 182.136 MB -> 84.182 MB. [2025-12-24T22:39:16.302Z] ====== als (apache-spark) [default], iteration 23 completed (20567.256 ms) ====== [2025-12-24T22:39:16.302Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-24T22:39:16.302Z] GC before operation: completed in 845.270 ms, heap usage 990.423 MB -> 85.178 MB. [2025-12-24T22:39:39.463Z] ====== als (apache-spark) [default], iteration 24 completed (19917.023 ms) ====== [2025-12-24T22:39:39.463Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-24T22:39:39.463Z] GC before operation: completed in 821.005 ms, heap usage 964.638 MB -> 85.611 MB. [2025-12-24T22:39:58.686Z] ====== als (apache-spark) [default], iteration 25 completed (19708.672 ms) ====== [2025-12-24T22:39:58.686Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-24T22:39:58.686Z] GC before operation: completed in 799.997 ms, heap usage 514.435 MB -> 85.185 MB. [2025-12-24T22:40:21.860Z] ====== als (apache-spark) [default], iteration 26 completed (20448.387 ms) ====== [2025-12-24T22:40:21.860Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-24T22:40:21.860Z] GC before operation: completed in 793.087 ms, heap usage 378.556 MB -> 85.295 MB. [2025-12-24T22:40:41.089Z] ====== als (apache-spark) [default], iteration 27 completed (19296.899 ms) ====== [2025-12-24T22:40:41.089Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-24T22:40:41.422Z] GC before operation: completed in 829.985 ms, heap usage 139.819 MB -> 84.833 MB. [2025-12-24T22:41:04.546Z] ====== als (apache-spark) [default], iteration 28 completed (20644.177 ms) ====== [2025-12-24T22:41:04.546Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-24T22:41:04.546Z] GC before operation: completed in 813.031 ms, heap usage 590.391 MB -> 85.571 MB. [2025-12-24T22:41:27.675Z] ====== als (apache-spark) [default], iteration 29 completed (19360.338 ms) ====== [2025-12-24T22:41:27.675Z] ----------------------------------- [2025-12-24T22:41:27.675Z] renaissance-als_0_PASSED [2025-12-24T22:41:27.675Z] ----------------------------------- [2025-12-24T22:41:27.675Z] [2025-12-24T22:41:27.675Z] TEST TEARDOWN: [2025-12-24T22:41:27.675Z] Nothing to be done for teardown. [2025-12-24T22:41:27.675Z] renaissance-als_0 Finish Time: Wed Dec 24 22:41:26 2025 Epoch Time (ms): 1766616086706