[2025-06-26T22:59:08.461Z] Running test renaissance-als_0 ... [2025-06-26T22:59:08.461Z] =============================================== [2025-06-26T22:59:09.215Z] renaissance-als_0 Start Time: Thu Jun 26 22:59:08 2025 Epoch Time (ms): 1750978748407 [2025-06-26T22:59:09.215Z] variation: NoOptions [2025-06-26T22:59:09.215Z] JVM_OPTIONS: [2025-06-26T22:59:09.215Z] { \ [2025-06-26T22:59:09.215Z] echo ""; echo "TEST SETUP:"; \ [2025-06-26T22:59:09.215Z] echo "Nothing to be done for setup."; \ [2025-06-26T22:59:09.216Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17509785128378/renaissance-als_0"; \ [2025-06-26T22:59:09.216Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17509785128378/renaissance-als_0"; \ [2025-06-26T22:59:09.216Z] echo ""; echo "TESTING:"; \ [2025-06-26T22:59:09.216Z] "/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_17509785128378/renaissance-als_0"/als.json" als; \ [2025-06-26T22:59:09.216Z] 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_17509785128378/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-26T22:59:09.216Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-26T22:59:09.216Z] echo "Nothing to be done for teardown."; \ [2025-06-26T22:59:09.216Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17509785128378/TestTargetResult"; [2025-06-26T22:59:09.216Z] [2025-06-26T22:59:09.216Z] TEST SETUP: [2025-06-26T22:59:09.216Z] Nothing to be done for setup. [2025-06-26T22:59:09.216Z] [2025-06-26T22:59:09.216Z] TESTING: [2025-06-26T22:59:16.964Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-06-26T22:59:27.233Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-26T22:59:27.233Z] GC before operation: completed in 319.804 ms, heap usage 169.000 MB -> 35.206 MB. [2025-06-26T23:00:36.943Z] ====== als (apache-spark) [default], iteration 0 completed (68163.557 ms) ====== [2025-06-26T23:00:36.943Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-26T23:00:36.943Z] GC before operation: completed in 373.866 ms, heap usage 254.551 MB -> 62.085 MB. [2025-06-26T23:01:01.841Z] ====== als (apache-spark) [default], iteration 1 completed (23109.301 ms) ====== [2025-06-26T23:01:01.841Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-26T23:01:01.841Z] GC before operation: completed in 318.843 ms, heap usage 174.673 MB -> 62.499 MB. [2025-06-26T23:01:36.585Z] ====== als (apache-spark) [default], iteration 2 completed (33199.079 ms) ====== [2025-06-26T23:01:36.585Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-26T23:01:36.585Z] GC before operation: completed in 258.146 ms, heap usage 207.121 MB -> 63.129 MB. [2025-06-26T23:02:08.003Z] ====== als (apache-spark) [default], iteration 3 completed (31149.464 ms) ====== [2025-06-26T23:02:08.003Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-26T23:02:08.003Z] GC before operation: completed in 829.656 ms, heap usage 198.009 MB -> 63.872 MB. [2025-06-26T23:02:32.508Z] ====== als (apache-spark) [default], iteration 4 completed (22451.784 ms) ====== [2025-06-26T23:02:32.508Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-26T23:02:32.508Z] GC before operation: completed in 236.370 ms, heap usage 329.712 MB -> 64.545 MB. [2025-06-26T23:02:52.412Z] ====== als (apache-spark) [default], iteration 5 completed (17937.231 ms) ====== [2025-06-26T23:02:52.412Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-26T23:02:52.412Z] GC before operation: completed in 218.190 ms, heap usage 447.588 MB -> 65.372 MB. [2025-06-26T23:03:10.064Z] ====== als (apache-spark) [default], iteration 6 completed (18514.661 ms) ====== [2025-06-26T23:03:10.064Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-26T23:03:10.064Z] GC before operation: completed in 187.279 ms, heap usage 262.491 MB -> 65.439 MB. [2025-06-26T23:03:30.745Z] ====== als (apache-spark) [default], iteration 7 completed (20493.942 ms) ====== [2025-06-26T23:03:30.745Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-26T23:03:30.745Z] GC before operation: completed in 333.359 ms, heap usage 305.970 MB -> 66.106 MB. [2025-06-26T23:03:55.327Z] ====== als (apache-spark) [default], iteration 8 completed (21026.979 ms) ====== [2025-06-26T23:03:55.327Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-26T23:03:55.327Z] GC before operation: completed in 315.258 ms, heap usage 356.577 MB -> 66.689 MB. [2025-06-26T23:04:16.464Z] ====== als (apache-spark) [default], iteration 9 completed (23042.591 ms) ====== [2025-06-26T23:04:16.464Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-26T23:04:16.464Z] GC before operation: completed in 164.641 ms, heap usage 191.016 MB -> 67.081 MB. [2025-06-26T23:04:45.878Z] ====== als (apache-spark) [default], iteration 10 completed (26240.798 ms) ====== [2025-06-26T23:04:45.878Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-26T23:04:45.878Z] GC before operation: completed in 222.866 ms, heap usage 189.382 MB -> 66.863 MB. [2025-06-26T23:05:15.858Z] ====== als (apache-spark) [default], iteration 11 completed (32189.324 ms) ====== [2025-06-26T23:05:15.858Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-26T23:05:15.858Z] GC before operation: completed in 182.298 ms, heap usage 216.560 MB -> 67.140 MB. [2025-06-26T23:05:46.909Z] ====== als (apache-spark) [default], iteration 12 completed (29087.162 ms) ====== [2025-06-26T23:05:46.909Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-26T23:05:46.909Z] GC before operation: completed in 421.999 ms, heap usage 121.886 MB -> 67.622 MB. [2025-06-26T23:06:07.706Z] ====== als (apache-spark) [default], iteration 13 completed (20965.222 ms) ====== [2025-06-26T23:06:07.706Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-26T23:06:07.706Z] GC before operation: completed in 189.825 ms, heap usage 123.608 MB -> 67.736 MB. [2025-06-26T23:06:43.548Z] ====== als (apache-spark) [default], iteration 14 completed (33783.514 ms) ====== [2025-06-26T23:06:43.548Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-26T23:06:43.548Z] GC before operation: completed in 393.399 ms, heap usage 474.564 MB -> 68.363 MB. [2025-06-26T23:07:12.749Z] ====== als (apache-spark) [default], iteration 15 completed (27768.378 ms) ====== [2025-06-26T23:07:12.749Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-26T23:07:12.749Z] GC before operation: completed in 317.823 ms, heap usage 223.738 MB -> 68.261 MB. [2025-06-26T23:07:49.475Z] ====== als (apache-spark) [default], iteration 16 completed (32554.083 ms) ====== [2025-06-26T23:07:49.475Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-26T23:07:49.475Z] GC before operation: completed in 222.583 ms, heap usage 229.639 MB -> 68.770 MB. [2025-06-26T23:08:14.917Z] ====== als (apache-spark) [default], iteration 17 completed (30381.338 ms) ====== [2025-06-26T23:08:14.917Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-26T23:08:15.818Z] GC before operation: completed in 371.901 ms, heap usage 345.344 MB -> 68.871 MB. [2025-06-26T23:08:41.986Z] ====== als (apache-spark) [default], iteration 18 completed (24847.177 ms) ====== [2025-06-26T23:08:41.986Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-26T23:08:41.986Z] GC before operation: completed in 207.278 ms, heap usage 214.974 MB -> 68.908 MB. [2025-06-26T23:09:17.315Z] ====== als (apache-spark) [default], iteration 19 completed (31939.847 ms) ====== [2025-06-26T23:09:17.315Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-26T23:09:17.315Z] GC before operation: completed in 365.432 ms, heap usage 183.109 MB -> 69.181 MB. [2025-06-26T23:09:48.255Z] ====== als (apache-spark) [default], iteration 20 completed (30277.867 ms) ====== [2025-06-26T23:09:48.255Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-26T23:09:48.255Z] GC before operation: completed in 224.176 ms, heap usage 381.343 MB -> 69.713 MB. [2025-06-26T23:10:13.881Z] ====== als (apache-spark) [default], iteration 21 completed (29107.593 ms) ====== [2025-06-26T23:10:13.881Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-26T23:10:13.881Z] GC before operation: completed in 263.357 ms, heap usage 192.982 MB -> 69.694 MB. [2025-06-26T23:10:43.991Z] ====== als (apache-spark) [default], iteration 22 completed (27211.474 ms) ====== [2025-06-26T23:10:43.991Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-26T23:10:43.991Z] GC before operation: completed in 480.495 ms, heap usage 438.258 MB -> 70.362 MB. [2025-06-26T23:11:24.111Z] ====== als (apache-spark) [default], iteration 23 completed (37418.147 ms) ====== [2025-06-26T23:11:24.111Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-26T23:11:24.111Z] GC before operation: completed in 408.068 ms, heap usage 118.876 MB -> 70.206 MB. [2025-06-26T23:12:01.164Z] ====== als (apache-spark) [default], iteration 24 completed (39909.708 ms) ====== [2025-06-26T23:12:01.165Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-26T23:12:01.875Z] GC before operation: completed in 288.560 ms, heap usage 143.238 MB -> 70.515 MB. [2025-06-26T23:12:43.687Z] ====== als (apache-spark) [default], iteration 25 completed (36812.672 ms) ====== [2025-06-26T23:12:43.687Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-26T23:12:43.687Z] GC before operation: completed in 207.438 ms, heap usage 120.806 MB -> 71.051 MB. [2025-06-26T23:13:14.217Z] ====== als (apache-spark) [default], iteration 26 completed (34704.037 ms) ====== [2025-06-26T23:13:14.217Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-26T23:13:14.217Z] GC before operation: completed in 236.320 ms, heap usage 117.355 MB -> 71.036 MB. [2025-06-26T23:13:39.594Z] ====== als (apache-spark) [default], iteration 27 completed (23071.973 ms) ====== [2025-06-26T23:13:39.594Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-26T23:13:39.594Z] GC before operation: completed in 391.169 ms, heap usage 348.500 MB -> 70.669 MB. [2025-06-26T23:14:04.128Z] ====== als (apache-spark) [default], iteration 28 completed (23983.369 ms) ====== [2025-06-26T23:14:04.128Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-26T23:14:04.128Z] GC before operation: completed in 370.169 ms, heap usage 180.580 MB -> 69.440 MB. [2025-06-26T23:14:40.176Z] ====== als (apache-spark) [default], iteration 29 completed (32079.371 ms) ====== [2025-06-26T23:14:40.176Z] ----------------------------------- [2025-06-26T23:14:40.176Z] renaissance-als_0_PASSED [2025-06-26T23:14:40.176Z] ----------------------------------- [2025-06-26T23:14:40.176Z] [2025-06-26T23:14:40.176Z] TEST TEARDOWN: [2025-06-26T23:14:40.176Z] Nothing to be done for teardown. [2025-06-26T23:14:40.176Z] renaissance-als_0 Finish Time: Thu Jun 26 23:14:36 2025 Epoch Time (ms): 1750979676119