[2025-12-25T01:40:07.474Z] Running test renaissance-als_0 ... [2025-12-25T01:40:07.474Z] =============================================== [2025-12-25T01:40:07.474Z] renaissance-als_0 Start Time: Thu Dec 25 01:40:07 2025 Epoch Time (ms): 1766626807252 [2025-12-25T01:40:07.474Z] variation: NoOptions [2025-12-25T01:40:07.474Z] JVM_OPTIONS: [2025-12-25T01:40:07.474Z] { \ [2025-12-25T01:40:07.474Z] echo ""; echo "TEST SETUP:"; \ [2025-12-25T01:40:07.474Z] echo "Nothing to be done for setup."; \ [2025-12-25T01:40:07.474Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_1766626189955/renaissance-als_0"; \ [2025-12-25T01:40:07.474Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_1766626189955/renaissance-als_0"; \ [2025-12-25T01:40:07.474Z] echo ""; echo "TESTING:"; \ [2025-12-25T01:40:07.474Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_1766626189955/renaissance-als_0"/als.json" als; \ [2025-12-25T01:40:07.474Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_1766626189955/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-25T01:40:07.474Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-25T01:40:07.474Z] echo "Nothing to be done for teardown."; \ [2025-12-25T01:40:07.474Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux/aqa-tests/TKG/../TKG/output_1766626189955/TestTargetResult"; [2025-12-25T01:40:07.474Z] [2025-12-25T01:40:07.474Z] TEST SETUP: [2025-12-25T01:40:07.474Z] Nothing to be done for setup. [2025-12-25T01:40:07.474Z] [2025-12-25T01:40:07.474Z] TESTING: [2025-12-25T01:40:30.644Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-25T01:40:50.017Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-25T01:40:50.017Z] GC before operation: completed in 228.636 ms, heap usage 71.631 MB -> 36.246 MB. [2025-12-25T01:42:13.255Z] ====== als (apache-spark) [default], iteration 0 completed (75869.947 ms) ====== [2025-12-25T01:42:13.255Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-25T01:42:13.255Z] GC before operation: completed in 589.936 ms, heap usage 281.498 MB -> 76.388 MB. [2025-12-25T01:42:41.107Z] ====== als (apache-spark) [default], iteration 1 completed (27531.168 ms) ====== [2025-12-25T01:42:41.107Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-25T01:42:41.107Z] GC before operation: completed in 654.693 ms, heap usage 652.617 MB -> 77.838 MB. [2025-12-25T01:43:04.287Z] ====== als (apache-spark) [default], iteration 2 completed (23966.506 ms) ====== [2025-12-25T01:43:04.287Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-25T01:43:04.287Z] GC before operation: completed in 717.108 ms, heap usage 671.997 MB -> 78.578 MB. [2025-12-25T01:43:32.241Z] ====== als (apache-spark) [default], iteration 3 completed (23958.929 ms) ====== [2025-12-25T01:43:32.241Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-25T01:43:32.241Z] GC before operation: completed in 817.013 ms, heap usage 787.158 MB -> 79.244 MB. [2025-12-25T01:43:55.640Z] ====== als (apache-spark) [default], iteration 4 completed (22622.520 ms) ====== [2025-12-25T01:43:55.640Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-25T01:43:55.640Z] GC before operation: completed in 747.654 ms, heap usage 160.818 MB -> 79.091 MB. [2025-12-25T01:44:18.817Z] ====== als (apache-spark) [default], iteration 5 completed (22278.776 ms) ====== [2025-12-25T01:44:18.817Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-25T01:44:18.817Z] GC before operation: completed in 798.747 ms, heap usage 808.484 MB -> 80.421 MB. [2025-12-25T01:44:41.987Z] ====== als (apache-spark) [default], iteration 6 completed (21553.129 ms) ====== [2025-12-25T01:44:41.987Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-25T01:44:42.338Z] GC before operation: completed in 823.607 ms, heap usage 530.196 MB -> 80.574 MB. [2025-12-25T01:45:05.544Z] ====== als (apache-spark) [default], iteration 7 completed (21593.713 ms) ====== [2025-12-25T01:45:05.544Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-25T01:45:05.895Z] GC before operation: completed in 903.611 ms, heap usage 679.557 MB -> 81.471 MB. [2025-12-25T01:45:29.049Z] ====== als (apache-spark) [default], iteration 8 completed (21293.948 ms) ====== [2025-12-25T01:45:29.049Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-25T01:45:29.049Z] GC before operation: completed in 867.295 ms, heap usage 279.759 MB -> 81.487 MB. [2025-12-25T01:45:52.206Z] ====== als (apache-spark) [default], iteration 9 completed (21100.505 ms) ====== [2025-12-25T01:45:52.206Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-25T01:45:52.206Z] GC before operation: completed in 925.229 ms, heap usage 995.149 MB -> 82.329 MB. [2025-12-25T01:46:15.373Z] ====== als (apache-spark) [default], iteration 10 completed (20817.511 ms) ====== [2025-12-25T01:46:15.373Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-25T01:46:15.373Z] GC before operation: completed in 858.694 ms, heap usage 1010.717 MB -> 82.840 MB. [2025-12-25T01:46:38.813Z] ====== als (apache-spark) [default], iteration 11 completed (21866.217 ms) ====== [2025-12-25T01:46:38.814Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-25T01:46:38.814Z] GC before operation: completed in 883.138 ms, heap usage 558.911 MB -> 82.395 MB. [2025-12-25T01:47:01.954Z] ====== als (apache-spark) [default], iteration 12 completed (21112.186 ms) ====== [2025-12-25T01:47:01.954Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-25T01:47:01.954Z] GC before operation: completed in 912.003 ms, heap usage 1.529 GB -> 83.923 MB. [2025-12-25T01:47:25.091Z] ====== als (apache-spark) [default], iteration 13 completed (21083.593 ms) ====== [2025-12-25T01:47:25.091Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-25T01:47:25.091Z] GC before operation: completed in 942.832 ms, heap usage 606.303 MB -> 83.060 MB. [2025-12-25T01:47:48.237Z] ====== als (apache-spark) [default], iteration 14 completed (20620.061 ms) ====== [2025-12-25T01:47:48.237Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-25T01:47:48.237Z] GC before operation: completed in 930.543 ms, heap usage 708.782 MB -> 83.537 MB. [2025-12-25T01:48:11.479Z] ====== als (apache-spark) [default], iteration 15 completed (20282.594 ms) ====== [2025-12-25T01:48:11.479Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-25T01:48:11.479Z] GC before operation: completed in 945.864 ms, heap usage 1.110 GB -> 84.276 MB. [2025-12-25T01:48:30.735Z] ====== als (apache-spark) [default], iteration 16 completed (20476.937 ms) ====== [2025-12-25T01:48:30.735Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-25T01:48:31.478Z] GC before operation: completed in 953.640 ms, heap usage 713.965 MB -> 84.229 MB. [2025-12-25T01:48:54.630Z] ====== als (apache-spark) [default], iteration 17 completed (20553.897 ms) ====== [2025-12-25T01:48:54.630Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-25T01:48:54.630Z] GC before operation: completed in 1001.293 ms, heap usage 846.738 MB -> 84.424 MB. [2025-12-25T01:49:17.789Z] ====== als (apache-spark) [default], iteration 18 completed (20309.040 ms) ====== [2025-12-25T01:49:17.789Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-25T01:49:17.789Z] GC before operation: completed in 1020.776 ms, heap usage 778.590 MB -> 84.582 MB. [2025-12-25T01:49:41.024Z] ====== als (apache-spark) [default], iteration 19 completed (20724.502 ms) ====== [2025-12-25T01:49:41.024Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-25T01:49:41.024Z] GC before operation: completed in 946.205 ms, heap usage 1.090 GB -> 85.174 MB. [2025-12-25T01:50:00.300Z] ====== als (apache-spark) [default], iteration 20 completed (20809.301 ms) ====== [2025-12-25T01:50:00.300Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-25T01:50:01.505Z] GC before operation: completed in 954.385 ms, heap usage 685.968 MB -> 85.181 MB. [2025-12-25T01:50:24.670Z] ====== als (apache-spark) [default], iteration 21 completed (20346.670 ms) ====== [2025-12-25T01:50:24.670Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-25T01:50:24.670Z] GC before operation: completed in 1015.630 ms, heap usage 608.547 MB -> 85.270 MB. [2025-12-25T01:50:47.849Z] ====== als (apache-spark) [default], iteration 22 completed (20424.971 ms) ====== [2025-12-25T01:50:47.849Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-25T01:50:47.849Z] GC before operation: completed in 983.026 ms, heap usage 404.302 MB -> 85.229 MB. [2025-12-25T01:51:11.099Z] ====== als (apache-spark) [default], iteration 23 completed (20816.843 ms) ====== [2025-12-25T01:51:11.099Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-25T01:51:11.099Z] GC before operation: completed in 1026.890 ms, heap usage 1003.268 MB -> 86.298 MB. [2025-12-25T01:51:30.321Z] ====== als (apache-spark) [default], iteration 24 completed (19765.168 ms) ====== [2025-12-25T01:51:30.321Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-25T01:51:30.321Z] GC before operation: completed in 1049.627 ms, heap usage 1.011 GB -> 86.557 MB. [2025-12-25T01:51:53.500Z] ====== als (apache-spark) [default], iteration 25 completed (20106.349 ms) ====== [2025-12-25T01:51:53.500Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-25T01:51:53.500Z] GC before operation: completed in 1022.312 ms, heap usage 667.413 MB -> 86.399 MB. [2025-12-25T01:52:12.769Z] ====== als (apache-spark) [default], iteration 26 completed (19812.412 ms) ====== [2025-12-25T01:52:12.769Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-25T01:52:13.958Z] GC before operation: completed in 1041.666 ms, heap usage 589.391 MB -> 86.612 MB. [2025-12-25T01:52:37.292Z] ====== als (apache-spark) [default], iteration 27 completed (19948.560 ms) ====== [2025-12-25T01:52:37.292Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-25T01:52:37.292Z] GC before operation: completed in 1031.149 ms, heap usage 595.136 MB -> 86.857 MB. [2025-12-25T01:52:56.553Z] ====== als (apache-spark) [default], iteration 28 completed (19944.643 ms) ====== [2025-12-25T01:52:56.553Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-25T01:52:57.298Z] GC before operation: completed in 1030.805 ms, heap usage 994.175 MB -> 87.512 MB. [2025-12-25T01:53:20.458Z] ====== als (apache-spark) [default], iteration 29 completed (19419.960 ms) ====== [2025-12-25T01:53:20.458Z] ----------------------------------- [2025-12-25T01:53:20.458Z] renaissance-als_0_PASSED [2025-12-25T01:53:20.458Z] ----------------------------------- [2025-12-25T01:53:20.458Z] [2025-12-25T01:53:20.458Z] TEST TEARDOWN: [2025-12-25T01:53:20.458Z] Nothing to be done for teardown. [2025-12-25T01:53:20.458Z] renaissance-als_0 Finish Time: Thu Dec 25 01:53:20 2025 Epoch Time (ms): 1766627600348