[2025-11-06T00:23:15.496Z] Running test renaissance-als_0 ... [2025-11-06T00:23:15.496Z] =============================================== [2025-11-06T00:23:15.496Z] renaissance-als_0 Start Time: Thu Nov 6 00:23:15 2025 Epoch Time (ms): 1762388595446 [2025-11-06T00:23:15.496Z] variation: NoOptions [2025-11-06T00:23:15.496Z] JVM_OPTIONS: [2025-11-06T00:23:15.496Z] { \ [2025-11-06T00:23:15.496Z] echo ""; echo "TEST SETUP:"; \ [2025-11-06T00:23:15.496Z] echo "Nothing to be done for setup."; \ [2025-11-06T00:23:15.496Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17623884282767/renaissance-als_0"; \ [2025-11-06T00:23:15.496Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17623884282767/renaissance-als_0"; \ [2025-11-06T00:23:15.496Z] echo ""; echo "TESTING:"; \ [2025-11-06T00:23:15.496Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17623884282767/renaissance-als_0"/als.json" als; \ [2025-11-06T00:23:15.496Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17623884282767/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-06T00:23:15.496Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-06T00:23:15.496Z] echo "Nothing to be done for teardown."; \ [2025-11-06T00:23:15.496Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17623884282767/TestTargetResult"; [2025-11-06T00:23:16.084Z] [2025-11-06T00:23:16.084Z] TEST SETUP: [2025-11-06T00:23:16.084Z] Nothing to be done for setup. [2025-11-06T00:23:16.084Z] [2025-11-06T00:23:16.084Z] TESTING: [2025-11-06T00:23:18.779Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-11-06T00:23:21.489Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-06T00:23:21.489Z] GC before operation: completed in 31.369 ms, heap usage 62.918 MB -> 34.913 MB. [2025-11-06T00:23:38.403Z] ====== als (apache-spark) [default], iteration 0 completed (16109.403 ms) ====== [2025-11-06T00:23:38.403Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-06T00:23:38.403Z] GC before operation: completed in 128.308 ms, heap usage 370.178 MB -> 61.924 MB. [2025-11-06T00:23:50.258Z] ====== als (apache-spark) [default], iteration 1 completed (11311.225 ms) ====== [2025-11-06T00:23:50.258Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-06T00:23:50.258Z] GC before operation: completed in 83.419 ms, heap usage 522.108 MB -> 63.118 MB. [2025-11-06T00:23:58.536Z] ====== als (apache-spark) [default], iteration 2 completed (8812.587 ms) ====== [2025-11-06T00:23:58.536Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-06T00:23:58.536Z] GC before operation: completed in 79.164 ms, heap usage 487.711 MB -> 63.707 MB. [2025-11-06T00:24:08.449Z] ====== als (apache-spark) [default], iteration 3 completed (8799.473 ms) ====== [2025-11-06T00:24:08.449Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-06T00:24:08.449Z] GC before operation: completed in 74.850 ms, heap usage 292.560 MB -> 63.925 MB. [2025-11-06T00:24:16.694Z] ====== als (apache-spark) [default], iteration 4 completed (8683.454 ms) ====== [2025-11-06T00:24:16.694Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-06T00:24:16.694Z] GC before operation: completed in 76.467 ms, heap usage 224.497 MB -> 64.412 MB. [2025-11-06T00:24:26.956Z] ====== als (apache-spark) [default], iteration 5 completed (8832.422 ms) ====== [2025-11-06T00:24:26.956Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-06T00:24:26.956Z] GC before operation: completed in 66.698 ms, heap usage 310.783 MB -> 64.999 MB. [2025-11-06T00:24:36.851Z] ====== als (apache-spark) [default], iteration 6 completed (10657.690 ms) ====== [2025-11-06T00:24:36.851Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-06T00:24:36.851Z] GC before operation: completed in 79.289 ms, heap usage 312.137 MB -> 65.528 MB. [2025-11-06T00:24:48.651Z] ====== als (apache-spark) [default], iteration 7 completed (10420.349 ms) ====== [2025-11-06T00:24:48.651Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-06T00:24:48.651Z] GC before operation: completed in 94.948 ms, heap usage 268.642 MB -> 66.080 MB. [2025-11-06T00:24:57.047Z] ====== als (apache-spark) [default], iteration 8 completed (9540.756 ms) ====== [2025-11-06T00:24:57.047Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-06T00:24:57.047Z] GC before operation: completed in 98.268 ms, heap usage 152.545 MB -> 66.490 MB. [2025-11-06T00:25:07.080Z] ====== als (apache-spark) [default], iteration 9 completed (8843.131 ms) ====== [2025-11-06T00:25:07.080Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-06T00:25:07.080Z] GC before operation: completed in 88.963 ms, heap usage 268.592 MB -> 66.885 MB. [2025-11-06T00:25:15.420Z] ====== als (apache-spark) [default], iteration 10 completed (9028.640 ms) ====== [2025-11-06T00:25:15.420Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-06T00:25:15.420Z] GC before operation: completed in 81.701 ms, heap usage 187.265 MB -> 67.007 MB. [2025-11-06T00:25:25.886Z] ====== als (apache-spark) [default], iteration 11 completed (8757.969 ms) ====== [2025-11-06T00:25:25.886Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-06T00:25:25.886Z] GC before operation: completed in 106.368 ms, heap usage 246.720 MB -> 67.382 MB. [2025-11-06T00:25:35.858Z] ====== als (apache-spark) [default], iteration 12 completed (9772.571 ms) ====== [2025-11-06T00:25:35.858Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-06T00:25:35.858Z] GC before operation: completed in 82.002 ms, heap usage 270.995 MB -> 67.680 MB. [2025-11-06T00:25:45.774Z] ====== als (apache-spark) [default], iteration 13 completed (9607.170 ms) ====== [2025-11-06T00:25:45.774Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-06T00:25:45.774Z] GC before operation: completed in 94.061 ms, heap usage 373.779 MB -> 68.008 MB. [2025-11-06T00:25:57.591Z] ====== als (apache-spark) [default], iteration 14 completed (12102.487 ms) ====== [2025-11-06T00:25:57.591Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-06T00:25:57.591Z] GC before operation: completed in 83.692 ms, heap usage 283.494 MB -> 68.168 MB. [2025-11-06T00:26:07.525Z] ====== als (apache-spark) [default], iteration 15 completed (10219.061 ms) ====== [2025-11-06T00:26:07.525Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-06T00:26:07.525Z] GC before operation: completed in 100.510 ms, heap usage 158.929 MB -> 68.256 MB. [2025-11-06T00:26:19.591Z] ====== als (apache-spark) [default], iteration 16 completed (12041.623 ms) ====== [2025-11-06T00:26:19.591Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-06T00:26:19.591Z] GC before operation: completed in 85.793 ms, heap usage 295.507 MB -> 68.622 MB. [2025-11-06T00:26:31.384Z] ====== als (apache-spark) [default], iteration 17 completed (10262.640 ms) ====== [2025-11-06T00:26:31.384Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-06T00:26:31.384Z] GC before operation: completed in 83.403 ms, heap usage 258.692 MB -> 68.917 MB. [2025-11-06T00:26:39.646Z] ====== als (apache-spark) [default], iteration 18 completed (9281.512 ms) ====== [2025-11-06T00:26:39.646Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-06T00:26:39.646Z] GC before operation: completed in 139.767 ms, heap usage 267.250 MB -> 69.136 MB. [2025-11-06T00:26:49.591Z] ====== als (apache-spark) [default], iteration 19 completed (9867.351 ms) ====== [2025-11-06T00:26:49.591Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-06T00:26:49.591Z] GC before operation: completed in 93.602 ms, heap usage 282.930 MB -> 69.417 MB. [2025-11-06T00:26:59.476Z] ====== als (apache-spark) [default], iteration 20 completed (8960.524 ms) ====== [2025-11-06T00:26:59.476Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-06T00:26:59.476Z] GC before operation: completed in 76.923 ms, heap usage 270.612 MB -> 69.688 MB. [2025-11-06T00:27:09.467Z] ====== als (apache-spark) [default], iteration 21 completed (9070.895 ms) ====== [2025-11-06T00:27:09.467Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-06T00:27:09.467Z] GC before operation: completed in 86.101 ms, heap usage 307.058 MB -> 70.007 MB. [2025-11-06T00:27:19.728Z] ====== als (apache-spark) [default], iteration 22 completed (10483.847 ms) ====== [2025-11-06T00:27:19.728Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-06T00:27:19.728Z] GC before operation: completed in 86.075 ms, heap usage 266.776 MB -> 70.132 MB. [2025-11-06T00:27:29.698Z] ====== als (apache-spark) [default], iteration 23 completed (9180.208 ms) ====== [2025-11-06T00:27:29.698Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-06T00:27:29.698Z] GC before operation: completed in 86.199 ms, heap usage 523.727 MB -> 70.690 MB. [2025-11-06T00:27:39.626Z] ====== als (apache-spark) [default], iteration 24 completed (9871.116 ms) ====== [2025-11-06T00:27:39.626Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-06T00:27:39.626Z] GC before operation: completed in 81.197 ms, heap usage 102.937 MB -> 70.475 MB. [2025-11-06T00:27:51.446Z] ====== als (apache-spark) [default], iteration 25 completed (11830.173 ms) ====== [2025-11-06T00:27:51.446Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-06T00:27:51.446Z] GC before operation: completed in 87.969 ms, heap usage 485.026 MB -> 71.161 MB. [2025-11-06T00:28:01.334Z] ====== als (apache-spark) [default], iteration 26 completed (9601.121 ms) ====== [2025-11-06T00:28:01.334Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-06T00:28:01.334Z] GC before operation: completed in 95.580 ms, heap usage 360.115 MB -> 71.266 MB. [2025-11-06T00:28:13.293Z] ====== als (apache-spark) [default], iteration 27 completed (11255.674 ms) ====== [2025-11-06T00:28:13.293Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-06T00:28:13.293Z] GC before operation: completed in 84.709 ms, heap usage 238.172 MB -> 71.472 MB. [2025-11-06T00:28:23.208Z] ====== als (apache-spark) [default], iteration 28 completed (9569.683 ms) ====== [2025-11-06T00:28:23.208Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-06T00:28:23.208Z] GC before operation: completed in 102.102 ms, heap usage 254.664 MB -> 71.316 MB. [2025-11-06T00:28:33.126Z] ====== als (apache-spark) [default], iteration 29 completed (9866.653 ms) ====== [2025-11-06T00:28:33.126Z] ----------------------------------- [2025-11-06T00:28:33.126Z] renaissance-als_0_PASSED [2025-11-06T00:28:33.126Z] ----------------------------------- [2025-11-06T00:28:33.126Z] [2025-11-06T00:28:33.126Z] TEST TEARDOWN: [2025-11-06T00:28:33.126Z] Nothing to be done for teardown. [2025-11-06T00:28:33.126Z] renaissance-als_0 Finish Time: Thu Nov 6 00:28:32 2025 Epoch Time (ms): 1762388912018