[2025-06-29T20:17:40.222Z] Running test renaissance-als_0 ... [2025-06-29T20:17:40.222Z] =============================================== [2025-06-29T20:17:40.222Z] renaissance-als_0 Start Time: Sun Jun 29 15:17:40 2025 Epoch Time (ms): 1751228260068 [2025-06-29T20:17:40.222Z] variation: NoOptions [2025-06-29T20:17:40.222Z] JVM_OPTIONS: [2025-06-29T20:17:40.222Z] { \ [2025-06-29T20:17:40.222Z] echo ""; echo "TEST SETUP:"; \ [2025-06-29T20:17:40.222Z] echo "Nothing to be done for setup."; \ [2025-06-29T20:17:40.222Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_175122815728/renaissance-als_0"; \ [2025-06-29T20:17:40.222Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_175122815728/renaissance-als_0"; \ [2025-06-29T20:17:40.222Z] echo ""; echo "TESTING:"; \ [2025-06-29T20:17:40.222Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_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_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_175122815728/renaissance-als_0"/als.json" als; \ [2025-06-29T20:17:40.222Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_175122815728/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-29T20:17:40.222Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-29T20:17:40.222Z] echo "Nothing to be done for teardown."; \ [2025-06-29T20:17:40.222Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_175122815728/TestTargetResult"; [2025-06-29T20:17:40.222Z] [2025-06-29T20:17:40.222Z] TEST SETUP: [2025-06-29T20:17:40.222Z] Nothing to be done for setup. [2025-06-29T20:17:40.222Z] [2025-06-29T20:17:40.222Z] TESTING: [2025-06-29T20:17:44.885Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-06-29T20:17:47.734Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-29T20:17:47.734Z] GC before operation: completed in 50.335 ms, heap usage 68.025 MB -> 35.025 MB. [2025-06-29T20:18:12.517Z] ====== als (apache-spark) [default], iteration 0 completed (21213.430 ms) ====== [2025-06-29T20:18:12.517Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-29T20:18:12.517Z] GC before operation: completed in 142.744 ms, heap usage 363.046 MB -> 61.927 MB. [2025-06-29T20:18:23.012Z] ====== als (apache-spark) [default], iteration 1 completed (12407.502 ms) ====== [2025-06-29T20:18:23.012Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-29T20:18:23.012Z] GC before operation: completed in 89.254 ms, heap usage 180.283 MB -> 62.611 MB. [2025-06-29T20:18:35.176Z] ====== als (apache-spark) [default], iteration 2 completed (11233.519 ms) ====== [2025-06-29T20:18:35.176Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-29T20:18:35.176Z] GC before operation: completed in 100.013 ms, heap usage 253.239 MB -> 63.421 MB. [2025-06-29T20:18:45.486Z] ====== als (apache-spark) [default], iteration 3 completed (11618.979 ms) ====== [2025-06-29T20:18:45.486Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-29T20:18:46.127Z] GC before operation: completed in 100.240 ms, heap usage 145.242 MB -> 63.837 MB. [2025-06-29T20:18:58.417Z] ====== als (apache-spark) [default], iteration 4 completed (11548.496 ms) ====== [2025-06-29T20:18:58.417Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-29T20:18:58.417Z] GC before operation: completed in 117.814 ms, heap usage 514.117 MB -> 64.733 MB. [2025-06-29T20:19:08.754Z] ====== als (apache-spark) [default], iteration 5 completed (10626.604 ms) ====== [2025-06-29T20:19:08.754Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-29T20:19:08.754Z] GC before operation: completed in 87.815 ms, heap usage 256.247 MB -> 64.962 MB. [2025-06-29T20:19:19.744Z] ====== als (apache-spark) [default], iteration 6 completed (10984.504 ms) ====== [2025-06-29T20:19:19.744Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-29T20:19:19.744Z] GC before operation: completed in 70.688 ms, heap usage 348.647 MB -> 65.611 MB. [2025-06-29T20:19:32.028Z] ====== als (apache-spark) [default], iteration 7 completed (10657.252 ms) ====== [2025-06-29T20:19:32.028Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-29T20:19:32.028Z] GC before operation: completed in 148.081 ms, heap usage 215.609 MB -> 65.595 MB. [2025-06-29T20:19:42.357Z] ====== als (apache-spark) [default], iteration 8 completed (11303.116 ms) ====== [2025-06-29T20:19:42.357Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-29T20:19:42.357Z] GC before operation: completed in 93.257 ms, heap usage 82.211 MB -> 66.037 MB. [2025-06-29T20:19:54.714Z] ====== als (apache-spark) [default], iteration 9 completed (11934.787 ms) ====== [2025-06-29T20:19:54.714Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-29T20:19:54.714Z] GC before operation: completed in 136.787 ms, heap usage 180.098 MB -> 66.674 MB. [2025-06-29T20:20:07.135Z] ====== als (apache-spark) [default], iteration 10 completed (11947.648 ms) ====== [2025-06-29T20:20:07.135Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-29T20:20:07.135Z] GC before operation: completed in 119.573 ms, heap usage 455.188 MB -> 67.370 MB. [2025-06-29T20:20:21.612Z] ====== als (apache-spark) [default], iteration 11 completed (12753.909 ms) ====== [2025-06-29T20:20:21.612Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-29T20:20:21.612Z] GC before operation: completed in 92.529 ms, heap usage 489.943 MB -> 67.656 MB. [2025-06-29T20:20:34.489Z] ====== als (apache-spark) [default], iteration 12 completed (12625.420 ms) ====== [2025-06-29T20:20:34.489Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-29T20:20:34.489Z] GC before operation: completed in 80.270 ms, heap usage 203.350 MB -> 67.526 MB. [2025-06-29T20:20:46.627Z] ====== als (apache-spark) [default], iteration 13 completed (12983.296 ms) ====== [2025-06-29T20:20:46.627Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-29T20:20:46.627Z] GC before operation: completed in 156.086 ms, heap usage 141.663 MB -> 67.679 MB. [2025-06-29T20:21:01.168Z] ====== als (apache-spark) [default], iteration 14 completed (13998.794 ms) ====== [2025-06-29T20:21:01.168Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-29T20:21:01.168Z] GC before operation: completed in 166.788 ms, heap usage 113.283 MB -> 67.952 MB. [2025-06-29T20:21:15.762Z] ====== als (apache-spark) [default], iteration 15 completed (14796.436 ms) ====== [2025-06-29T20:21:15.762Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-29T20:21:16.394Z] GC before operation: completed in 142.729 ms, heap usage 169.176 MB -> 68.347 MB. [2025-06-29T20:21:30.742Z] ====== als (apache-spark) [default], iteration 16 completed (14407.314 ms) ====== [2025-06-29T20:21:30.742Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-29T20:21:30.742Z] GC before operation: completed in 145.042 ms, heap usage 497.429 MB -> 68.860 MB. [2025-06-29T20:21:47.803Z] ====== als (apache-spark) [default], iteration 17 completed (15031.226 ms) ====== [2025-06-29T20:21:47.803Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-29T20:21:47.803Z] GC before operation: completed in 187.124 ms, heap usage 292.433 MB -> 68.833 MB. [2025-06-29T20:22:01.337Z] ====== als (apache-spark) [default], iteration 18 completed (14575.159 ms) ====== [2025-06-29T20:22:01.337Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-29T20:22:01.337Z] GC before operation: completed in 169.876 ms, heap usage 125.603 MB -> 69.062 MB. [2025-06-29T20:22:16.046Z] ====== als (apache-spark) [default], iteration 19 completed (13857.528 ms) ====== [2025-06-29T20:22:16.047Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-29T20:22:16.047Z] GC before operation: completed in 209.679 ms, heap usage 205.876 MB -> 69.214 MB. [2025-06-29T20:22:28.304Z] ====== als (apache-spark) [default], iteration 20 completed (12684.821 ms) ====== [2025-06-29T20:22:28.304Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-29T20:22:28.934Z] GC before operation: completed in 153.563 ms, heap usage 392.389 MB -> 69.681 MB. [2025-06-29T20:22:43.294Z] ====== als (apache-spark) [default], iteration 21 completed (13374.566 ms) ====== [2025-06-29T20:22:43.294Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-29T20:22:43.294Z] GC before operation: completed in 89.679 ms, heap usage 217.623 MB -> 69.715 MB. [2025-06-29T20:22:57.745Z] ====== als (apache-spark) [default], iteration 22 completed (14625.117 ms) ====== [2025-06-29T20:22:57.745Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-29T20:22:57.745Z] GC before operation: completed in 127.077 ms, heap usage 357.307 MB -> 70.291 MB. [2025-06-29T20:23:12.251Z] ====== als (apache-spark) [default], iteration 23 completed (14224.819 ms) ====== [2025-06-29T20:23:12.251Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-29T20:23:12.251Z] GC before operation: completed in 137.597 ms, heap usage 457.951 MB -> 70.727 MB. [2025-06-29T20:23:27.761Z] ====== als (apache-spark) [default], iteration 24 completed (14508.659 ms) ====== [2025-06-29T20:23:27.761Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-29T20:23:27.761Z] GC before operation: completed in 247.991 ms, heap usage 254.768 MB -> 70.667 MB. [2025-06-29T20:23:42.208Z] ====== als (apache-spark) [default], iteration 25 completed (13906.756 ms) ====== [2025-06-29T20:23:42.208Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-29T20:23:42.208Z] GC before operation: completed in 203.122 ms, heap usage 118.707 MB -> 70.845 MB. [2025-06-29T20:23:56.656Z] ====== als (apache-spark) [default], iteration 26 completed (13440.295 ms) ====== [2025-06-29T20:23:56.656Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-29T20:23:56.656Z] GC before operation: completed in 131.819 ms, heap usage 114.596 MB -> 71.187 MB. [2025-06-29T20:24:11.029Z] ====== als (apache-spark) [default], iteration 27 completed (14434.877 ms) ====== [2025-06-29T20:24:11.029Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-29T20:24:11.029Z] GC before operation: completed in 132.264 ms, heap usage 371.300 MB -> 71.640 MB. [2025-06-29T20:24:25.510Z] ====== als (apache-spark) [default], iteration 28 completed (14871.643 ms) ====== [2025-06-29T20:24:25.510Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-29T20:24:25.510Z] GC before operation: completed in 121.955 ms, heap usage 239.920 MB -> 71.232 MB. [2025-06-29T20:24:39.896Z] ====== als (apache-spark) [default], iteration 29 completed (13702.752 ms) ====== [2025-06-29T20:24:39.897Z] ----------------------------------- [2025-06-29T20:24:39.897Z] renaissance-als_0_PASSED [2025-06-29T20:24:39.897Z] ----------------------------------- [2025-06-29T20:24:39.897Z] [2025-06-29T20:24:39.897Z] TEST TEARDOWN: [2025-06-29T20:24:39.897Z] Nothing to be done for teardown. [2025-06-29T20:24:39.897Z] renaissance-als_0 Finish Time: Sun Jun 29 15:24:39 2025 Epoch Time (ms): 1751228679546