[2025-05-17T00:12:17.911Z] Running test renaissance-als_0 ... [2025-05-17T00:12:17.911Z] =============================================== [2025-05-17T00:12:17.911Z] renaissance-als_0 Start Time: Sat May 17 00:12:17 2025 Epoch Time (ms): 1747440737829 [2025-05-17T00:12:17.911Z] variation: NoOptions [2025-05-17T00:12:17.911Z] JVM_OPTIONS: [2025-05-17T00:12:17.911Z] { \ [2025-05-17T00:12:17.911Z] echo ""; echo "TEST SETUP:"; \ [2025-05-17T00:12:17.911Z] echo "Nothing to be done for setup."; \ [2025-05-17T00:12:17.911Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17474407369511/renaissance-als_0"; \ [2025-05-17T00:12:17.911Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17474407369511/renaissance-als_0"; \ [2025-05-17T00:12:17.911Z] echo ""; echo "TESTING:"; \ [2025-05-17T00:12:17.911Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/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_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17474407369511/renaissance-als_0"/als.json" als; \ [2025-05-17T00:12:17.912Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17474407369511/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-17T00:12:17.912Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-17T00:12:17.912Z] echo "Nothing to be done for teardown."; \ [2025-05-17T00:12:17.912Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_riscv64_linux_testList_1/aqa-tests/TKG/../TKG/output_17474407369511/TestTargetResult"; [2025-05-17T00:12:17.912Z] [2025-05-17T00:12:17.912Z] TEST SETUP: [2025-05-17T00:12:17.912Z] Nothing to be done for setup. [2025-05-17T00:12:17.912Z] [2025-05-17T00:12:17.912Z] TESTING: [2025-05-17T00:12:40.982Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-05-17T00:13:00.052Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-05-17T00:13:00.052Z] GC before operation: completed in 188.676 ms, heap usage 56.417 MB -> 36.371 MB. [2025-05-17T00:14:23.069Z] ====== als (apache-spark) [default], iteration 0 completed (76278.883 ms) ====== [2025-05-17T00:14:23.069Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-05-17T00:14:23.069Z] GC before operation: completed in 596.349 ms, heap usage 996.244 MB -> 77.320 MB. [2025-05-17T00:14:50.834Z] ====== als (apache-spark) [default], iteration 1 completed (28270.920 ms) ====== [2025-05-17T00:14:50.834Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-05-17T00:14:50.834Z] GC before operation: completed in 666.718 ms, heap usage 992.487 MB -> 78.087 MB. [2025-05-17T00:15:18.535Z] ====== als (apache-spark) [default], iteration 2 completed (24105.450 ms) ====== [2025-05-17T00:15:18.535Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-05-17T00:15:18.535Z] GC before operation: completed in 700.228 ms, heap usage 439.640 MB -> 78.062 MB. [2025-05-17T00:15:41.581Z] ====== als (apache-spark) [default], iteration 3 completed (23327.379 ms) ====== [2025-05-17T00:15:41.581Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-05-17T00:15:41.581Z] GC before operation: completed in 732.980 ms, heap usage 892.316 MB -> 79.264 MB. [2025-05-17T00:16:04.648Z] ====== als (apache-spark) [default], iteration 4 completed (21936.728 ms) ====== [2025-05-17T00:16:04.648Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-05-17T00:16:05.377Z] GC before operation: completed in 752.966 ms, heap usage 276.790 MB -> 79.146 MB. [2025-05-17T00:16:28.373Z] ====== als (apache-spark) [default], iteration 5 completed (21718.232 ms) ====== [2025-05-17T00:16:28.373Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-05-17T00:16:29.093Z] GC before operation: completed in 781.397 ms, heap usage 708.799 MB -> 80.031 MB. [2025-05-17T00:16:52.125Z] ====== als (apache-spark) [default], iteration 6 completed (21298.196 ms) ====== [2025-05-17T00:16:52.125Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-05-17T00:16:52.125Z] GC before operation: completed in 798.630 ms, heap usage 854.824 MB -> 81.116 MB. [2025-05-17T00:17:15.243Z] ====== als (apache-spark) [default], iteration 7 completed (20995.244 ms) ====== [2025-05-17T00:17:15.243Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-05-17T00:17:15.243Z] GC before operation: completed in 908.121 ms, heap usage 479.772 MB -> 81.108 MB. [2025-05-17T00:17:38.264Z] ====== als (apache-spark) [default], iteration 8 completed (21322.311 ms) ====== [2025-05-17T00:17:38.264Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-05-17T00:17:38.264Z] GC before operation: completed in 830.342 ms, heap usage 1.022 GB -> 82.251 MB. [2025-05-17T00:18:01.276Z] ====== als (apache-spark) [default], iteration 9 completed (21240.893 ms) ====== [2025-05-17T00:18:01.276Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-05-17T00:18:01.276Z] GC before operation: completed in 853.984 ms, heap usage 646.427 MB -> 82.071 MB. [2025-05-17T00:18:24.277Z] ====== als (apache-spark) [default], iteration 10 completed (21573.344 ms) ====== [2025-05-17T00:18:24.277Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-05-17T00:18:24.988Z] GC before operation: completed in 860.992 ms, heap usage 396.024 MB -> 82.079 MB. [2025-05-17T00:18:47.975Z] ====== als (apache-spark) [default], iteration 11 completed (20478.961 ms) ====== [2025-05-17T00:18:47.975Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-05-17T00:18:47.975Z] GC before operation: completed in 865.643 ms, heap usage 1.091 GB -> 83.231 MB. [2025-05-17T00:19:11.059Z] ====== als (apache-spark) [default], iteration 12 completed (21051.178 ms) ====== [2025-05-17T00:19:11.059Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-05-17T00:19:11.059Z] GC before operation: completed in 891.678 ms, heap usage 970.937 MB -> 83.287 MB. [2025-05-17T00:19:34.056Z] ====== als (apache-spark) [default], iteration 13 completed (20139.022 ms) ====== [2025-05-17T00:19:34.056Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-05-17T00:19:34.056Z] GC before operation: completed in 932.981 ms, heap usage 984.562 MB -> 83.634 MB. [2025-05-17T00:19:57.050Z] ====== als (apache-spark) [default], iteration 14 completed (20348.673 ms) ====== [2025-05-17T00:19:57.050Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-05-17T00:19:57.050Z] GC before operation: completed in 925.121 ms, heap usage 520.584 MB -> 83.222 MB. [2025-05-17T00:20:16.148Z] ====== als (apache-spark) [default], iteration 15 completed (20112.453 ms) ====== [2025-05-17T00:20:16.148Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-05-17T00:20:16.148Z] GC before operation: completed in 954.481 ms, heap usage 1.043 GB -> 84.051 MB. [2025-05-17T00:20:39.149Z] ====== als (apache-spark) [default], iteration 16 completed (20232.755 ms) ====== [2025-05-17T00:20:39.149Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-05-17T00:20:39.149Z] GC before operation: completed in 967.438 ms, heap usage 369.719 MB -> 83.531 MB. [2025-05-17T00:21:02.157Z] ====== als (apache-spark) [default], iteration 17 completed (20419.467 ms) ====== [2025-05-17T00:21:02.157Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-05-17T00:21:02.157Z] GC before operation: completed in 920.162 ms, heap usage 993.409 MB -> 84.560 MB. [2025-05-17T00:21:25.167Z] ====== als (apache-spark) [default], iteration 18 completed (20392.994 ms) ====== [2025-05-17T00:21:25.167Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-05-17T00:21:25.167Z] GC before operation: completed in 926.862 ms, heap usage 311.611 MB -> 84.087 MB. [2025-05-17T00:21:44.497Z] ====== als (apache-spark) [default], iteration 19 completed (20148.665 ms) ====== [2025-05-17T00:21:44.497Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-05-17T00:21:44.828Z] GC before operation: completed in 954.190 ms, heap usage 554.094 MB -> 84.638 MB. [2025-05-17T00:22:07.811Z] ====== als (apache-spark) [default], iteration 20 completed (20698.591 ms) ====== [2025-05-17T00:22:07.811Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-05-17T00:22:07.811Z] GC before operation: completed in 951.255 ms, heap usage 241.481 MB -> 84.458 MB. [2025-05-17T00:22:30.819Z] ====== als (apache-spark) [default], iteration 21 completed (19857.341 ms) ====== [2025-05-17T00:22:30.819Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-05-17T00:22:30.819Z] GC before operation: completed in 965.439 ms, heap usage 186.850 MB -> 84.709 MB. [2025-05-17T00:22:49.963Z] ====== als (apache-spark) [default], iteration 22 completed (19846.088 ms) ====== [2025-05-17T00:22:49.963Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-05-17T00:22:51.124Z] GC before operation: completed in 977.330 ms, heap usage 232.381 MB -> 85.007 MB. [2025-05-17T00:23:14.104Z] ====== als (apache-spark) [default], iteration 23 completed (19555.809 ms) ====== [2025-05-17T00:23:14.104Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-05-17T00:23:14.104Z] GC before operation: completed in 976.437 ms, heap usage 180.208 MB -> 85.260 MB. [2025-05-17T00:23:33.180Z] ====== als (apache-spark) [default], iteration 24 completed (19928.582 ms) ====== [2025-05-17T00:23:33.180Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-05-17T00:23:34.352Z] GC before operation: completed in 993.339 ms, heap usage 834.102 MB -> 86.221 MB. [2025-05-17T00:23:57.514Z] ====== als (apache-spark) [default], iteration 25 completed (19651.338 ms) ====== [2025-05-17T00:23:57.515Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-05-17T00:23:57.515Z] GC before operation: completed in 960.980 ms, heap usage 169.097 MB -> 85.901 MB. [2025-05-17T00:24:16.567Z] ====== als (apache-spark) [default], iteration 26 completed (19103.823 ms) ====== [2025-05-17T00:24:16.567Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-05-17T00:24:16.567Z] GC before operation: completed in 982.206 ms, heap usage 1015.147 MB -> 87.078 MB. [2025-05-17T00:24:39.581Z] ====== als (apache-spark) [default], iteration 27 completed (19415.039 ms) ====== [2025-05-17T00:24:39.581Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-05-17T00:24:39.581Z] GC before operation: completed in 981.989 ms, heap usage 482.736 MB -> 86.823 MB. [2025-05-17T00:24:58.806Z] ====== als (apache-spark) [default], iteration 28 completed (20035.258 ms) ====== [2025-05-17T00:24:58.806Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-05-17T00:24:59.587Z] GC before operation: completed in 1028.881 ms, heap usage 717.154 MB -> 86.801 MB. [2025-05-17T00:25:22.580Z] ====== als (apache-spark) [default], iteration 29 completed (19219.337 ms) ====== [2025-05-17T00:25:22.580Z] ----------------------------------- [2025-05-17T00:25:22.580Z] renaissance-als_0_PASSED [2025-05-17T00:25:22.580Z] ----------------------------------- [2025-05-17T00:25:22.580Z] [2025-05-17T00:25:22.580Z] TEST TEARDOWN: [2025-05-17T00:25:22.580Z] Nothing to be done for teardown. [2025-05-17T00:25:22.580Z] renaissance-als_0 Finish Time: Sat May 17 00:25:22 2025 Epoch Time (ms): 1747441522209