[2025-11-20T07:29:38.305Z] Running test renaissance-als_0 ... [2025-11-20T07:29:38.305Z] =============================================== [2025-11-20T07:29:38.305Z] renaissance-als_0 Start Time: Thu Nov 20 07:29:37 2025 Epoch Time (ms): 1763623777504 [2025-11-20T07:29:38.305Z] variation: NoOptions [2025-11-20T07:29:38.305Z] JVM_OPTIONS: [2025-11-20T07:29:38.305Z] { \ [2025-11-20T07:29:38.305Z] echo ""; echo "TEST SETUP:"; \ [2025-11-20T07:29:38.305Z] echo "Nothing to be done for setup."; \ [2025-11-20T07:29:38.305Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_176362377776/renaissance-als_0"; \ [2025-11-20T07:29:38.305Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_176362377776/renaissance-als_0"; \ [2025-11-20T07:29:38.305Z] echo ""; echo "TESTING:"; \ [2025-11-20T07:29:38.305Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_0/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_alpine-linux_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_176362377776/renaissance-als_0"/als.json" als; \ [2025-11-20T07:29:38.305Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_176362377776/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-20T07:29:38.305Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-20T07:29:38.305Z] echo "Nothing to be done for teardown."; \ [2025-11-20T07:29:38.305Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_176362377776/TestTargetResult"; [2025-11-20T07:29:38.305Z] [2025-11-20T07:29:38.305Z] TEST SETUP: [2025-11-20T07:29:38.305Z] Nothing to be done for setup. [2025-11-20T07:29:38.305Z] [2025-11-20T07:29:38.305Z] TESTING: [2025-11-20T07:29:42.486Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-20T07:29:47.223Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-20T07:29:47.223Z] GC before operation: completed in 45.157 ms, heap usage 47.970 MB -> 35.149 MB. [2025-11-20T07:30:08.550Z] ====== als (apache-spark) [default], iteration 0 completed (19166.717 ms) ====== [2025-11-20T07:30:08.550Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-20T07:30:08.550Z] GC before operation: completed in 81.785 ms, heap usage 110.046 MB -> 61.814 MB. [2025-11-20T07:30:15.059Z] ====== als (apache-spark) [default], iteration 1 completed (8144.570 ms) ====== [2025-11-20T07:30:15.059Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-20T07:30:15.060Z] GC before operation: completed in 83.620 ms, heap usage 299.235 MB -> 62.782 MB. [2025-11-20T07:30:23.001Z] ====== als (apache-spark) [default], iteration 2 completed (7551.389 ms) ====== [2025-11-20T07:30:23.001Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-20T07:30:23.001Z] GC before operation: completed in 80.506 ms, heap usage 265.711 MB -> 63.342 MB. [2025-11-20T07:30:30.950Z] ====== als (apache-spark) [default], iteration 3 completed (7236.204 ms) ====== [2025-11-20T07:30:30.950Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-20T07:30:30.950Z] GC before operation: completed in 84.643 ms, heap usage 428.374 MB -> 64.292 MB. [2025-11-20T07:30:37.536Z] ====== als (apache-spark) [default], iteration 4 completed (7066.609 ms) ====== [2025-11-20T07:30:37.537Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-20T07:30:38.265Z] GC before operation: completed in 86.491 ms, heap usage 192.130 MB -> 64.350 MB. [2025-11-20T07:30:44.848Z] ====== als (apache-spark) [default], iteration 5 completed (6894.406 ms) ====== [2025-11-20T07:30:44.848Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-20T07:30:44.848Z] GC before operation: completed in 82.603 ms, heap usage 241.969 MB -> 65.000 MB. [2025-11-20T07:30:52.804Z] ====== als (apache-spark) [default], iteration 6 completed (6953.916 ms) ====== [2025-11-20T07:30:52.804Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-20T07:30:52.804Z] GC before operation: completed in 89.034 ms, heap usage 266.691 MB -> 65.602 MB. [2025-11-20T07:30:59.382Z] ====== als (apache-spark) [default], iteration 7 completed (6835.446 ms) ====== [2025-11-20T07:30:59.382Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-20T07:30:59.383Z] GC before operation: completed in 90.164 ms, heap usage 289.355 MB -> 66.103 MB. [2025-11-20T07:31:07.332Z] ====== als (apache-spark) [default], iteration 8 completed (6871.333 ms) ====== [2025-11-20T07:31:07.332Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-20T07:31:07.332Z] GC before operation: completed in 85.777 ms, heap usage 214.298 MB -> 66.558 MB. [2025-11-20T07:31:14.390Z] ====== als (apache-spark) [default], iteration 9 completed (7235.717 ms) ====== [2025-11-20T07:31:14.391Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-20T07:31:14.391Z] GC before operation: completed in 94.627 ms, heap usage 126.979 MB -> 66.811 MB. [2025-11-20T07:31:20.993Z] ====== als (apache-spark) [default], iteration 10 completed (6815.539 ms) ====== [2025-11-20T07:31:20.993Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-20T07:31:20.993Z] GC before operation: completed in 89.162 ms, heap usage 120.295 MB -> 67.092 MB. [2025-11-20T07:31:28.955Z] ====== als (apache-spark) [default], iteration 11 completed (6782.742 ms) ====== [2025-11-20T07:31:28.955Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-20T07:31:28.955Z] GC before operation: completed in 88.505 ms, heap usage 355.334 MB -> 67.520 MB. [2025-11-20T07:31:35.528Z] ====== als (apache-spark) [default], iteration 12 completed (6605.404 ms) ====== [2025-11-20T07:31:35.528Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-20T07:31:35.528Z] GC before operation: completed in 93.263 ms, heap usage 561.114 MB -> 68.645 MB. [2025-11-20T07:31:42.112Z] ====== als (apache-spark) [default], iteration 13 completed (6642.373 ms) ====== [2025-11-20T07:31:42.112Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-20T07:31:42.112Z] GC before operation: completed in 97.233 ms, heap usage 539.620 MB -> 68.280 MB. [2025-11-20T07:31:48.699Z] ====== als (apache-spark) [default], iteration 14 completed (6596.690 ms) ====== [2025-11-20T07:31:48.699Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-20T07:31:48.699Z] GC before operation: completed in 94.516 ms, heap usage 443.217 MB -> 68.482 MB. [2025-11-20T07:31:55.287Z] ====== als (apache-spark) [default], iteration 15 completed (6638.970 ms) ====== [2025-11-20T07:31:55.287Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-20T07:31:56.014Z] GC before operation: completed in 91.896 ms, heap usage 352.736 MB -> 68.547 MB. [2025-11-20T07:32:02.596Z] ====== als (apache-spark) [default], iteration 16 completed (6677.452 ms) ====== [2025-11-20T07:32:02.596Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-20T07:32:02.596Z] GC before operation: completed in 92.241 ms, heap usage 358.787 MB -> 68.756 MB. [2025-11-20T07:32:10.549Z] ====== als (apache-spark) [default], iteration 17 completed (6771.119 ms) ====== [2025-11-20T07:32:10.550Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-20T07:32:10.550Z] GC before operation: completed in 97.313 ms, heap usage 343.197 MB -> 68.977 MB. [2025-11-20T07:32:17.146Z] ====== als (apache-spark) [default], iteration 18 completed (6508.664 ms) ====== [2025-11-20T07:32:17.146Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-20T07:32:17.146Z] GC before operation: completed in 82.956 ms, heap usage 325.369 MB -> 69.242 MB. [2025-11-20T07:32:24.233Z] ====== als (apache-spark) [default], iteration 19 completed (6813.288 ms) ====== [2025-11-20T07:32:24.233Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-20T07:32:24.233Z] GC before operation: completed in 86.629 ms, heap usage 559.778 MB -> 69.882 MB. [2025-11-20T07:32:30.818Z] ====== als (apache-spark) [default], iteration 20 completed (6828.257 ms) ====== [2025-11-20T07:32:30.818Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-20T07:32:30.818Z] GC before operation: completed in 84.075 ms, heap usage 201.651 MB -> 69.638 MB. [2025-11-20T07:32:37.407Z] ====== als (apache-spark) [default], iteration 21 completed (6553.567 ms) ====== [2025-11-20T07:32:37.407Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-20T07:32:37.407Z] GC before operation: completed in 112.037 ms, heap usage 130.630 MB -> 69.809 MB. [2025-11-20T07:32:45.382Z] ====== als (apache-spark) [default], iteration 22 completed (6841.225 ms) ====== [2025-11-20T07:32:45.382Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-20T07:32:45.382Z] GC before operation: completed in 84.770 ms, heap usage 471.542 MB -> 70.170 MB. [2025-11-20T07:32:51.957Z] ====== als (apache-spark) [default], iteration 23 completed (6770.737 ms) ====== [2025-11-20T07:32:51.957Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-20T07:32:51.957Z] GC before operation: completed in 93.933 ms, heap usage 263.348 MB -> 70.486 MB. [2025-11-20T07:32:58.529Z] ====== als (apache-spark) [default], iteration 24 completed (6418.942 ms) ====== [2025-11-20T07:32:58.529Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-20T07:32:58.529Z] GC before operation: completed in 87.438 ms, heap usage 235.055 MB -> 70.735 MB. [2025-11-20T07:33:05.108Z] ====== als (apache-spark) [default], iteration 25 completed (6569.166 ms) ====== [2025-11-20T07:33:05.108Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-20T07:33:05.108Z] GC before operation: completed in 86.317 ms, heap usage 167.634 MB -> 70.918 MB. [2025-11-20T07:33:11.689Z] ====== als (apache-spark) [default], iteration 26 completed (6520.459 ms) ====== [2025-11-20T07:33:11.689Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-20T07:33:11.689Z] GC before operation: completed in 85.126 ms, heap usage 443.534 MB -> 72.226 MB. [2025-11-20T07:33:18.274Z] ====== als (apache-spark) [default], iteration 27 completed (6571.758 ms) ====== [2025-11-20T07:33:18.274Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-20T07:33:18.274Z] GC before operation: completed in 95.926 ms, heap usage 264.629 MB -> 71.595 MB. [2025-11-20T07:33:26.230Z] ====== als (apache-spark) [default], iteration 28 completed (6632.993 ms) ====== [2025-11-20T07:33:26.230Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-20T07:33:26.230Z] GC before operation: completed in 89.762 ms, heap usage 332.664 MB -> 71.502 MB. [2025-11-20T07:33:32.811Z] ====== als (apache-spark) [default], iteration 29 completed (6428.613 ms) ====== [2025-11-20T07:33:32.811Z] ----------------------------------- [2025-11-20T07:33:32.811Z] renaissance-als_0_PASSED [2025-11-20T07:33:32.811Z] ----------------------------------- [2025-11-20T07:33:32.811Z] [2025-11-20T07:33:32.811Z] TEST TEARDOWN: [2025-11-20T07:33:32.811Z] Nothing to be done for teardown. [2025-11-20T07:33:32.811Z] renaissance-als_0 Finish Time: Thu Nov 20 07:33:32 2025 Epoch Time (ms): 1763624012052