[2025-08-20T20:34:35.758Z] Running test renaissance-als_0 ... [2025-08-20T20:34:35.758Z] =============================================== [2025-08-20T20:34:35.758Z] renaissance-als_0 Start Time: Wed Aug 20 20:34:35 2025 Epoch Time (ms): 1755722075178 [2025-08-20T20:34:35.758Z] variation: NoOptions [2025-08-20T20:34:35.758Z] JVM_OPTIONS: [2025-08-20T20:34:35.758Z] { \ [2025-08-20T20:34:35.758Z] echo ""; echo "TEST SETUP:"; \ [2025-08-20T20:34:35.758Z] echo "Nothing to be done for setup."; \ [2025-08-20T20:34:35.758Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17557218176275/renaissance-als_0"; \ [2025-08-20T20:34:35.758Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17557218176275/renaissance-als_0"; \ [2025-08-20T20:34:35.758Z] echo ""; echo "TESTING:"; \ [2025-08-20T20:34:35.758Z] "/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_17557218176275/renaissance-als_0"/als.json" als; \ [2025-08-20T20:34:35.758Z] 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_17557218176275/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-20T20:34:35.758Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-20T20:34:35.758Z] echo "Nothing to be done for teardown."; \ [2025-08-20T20:34:35.758Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17557218176275/TestTargetResult"; [2025-08-20T20:34:35.758Z] [2025-08-20T20:34:35.758Z] TEST SETUP: [2025-08-20T20:34:35.758Z] Nothing to be done for setup. [2025-08-20T20:34:35.758Z] [2025-08-20T20:34:35.758Z] TESTING: [2025-08-20T20:34:41.767Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-08-20T20:34:47.714Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-08-20T20:34:47.714Z] GC before operation: completed in 139.771 ms, heap usage 51.000 MB -> 35.078 MB. [2025-08-20T20:35:20.935Z] ====== als (apache-spark) [default], iteration 0 completed (30000.669 ms) ====== [2025-08-20T20:35:20.935Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-08-20T20:35:20.935Z] GC before operation: completed in 132.396 ms, heap usage 284.322 MB -> 62.027 MB. [2025-08-20T20:35:36.575Z] ====== als (apache-spark) [default], iteration 1 completed (17701.618 ms) ====== [2025-08-20T20:35:36.575Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-08-20T20:35:36.575Z] GC before operation: completed in 245.438 ms, heap usage 247.216 MB -> 63.008 MB. [2025-08-20T20:36:00.693Z] ====== als (apache-spark) [default], iteration 2 completed (23262.521 ms) ====== [2025-08-20T20:36:00.693Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-08-20T20:36:00.693Z] GC before operation: completed in 237.865 ms, heap usage 127.053 MB -> 63.334 MB. [2025-08-20T20:36:29.691Z] ====== als (apache-spark) [default], iteration 3 completed (26820.529 ms) ====== [2025-08-20T20:36:29.691Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-08-20T20:36:29.691Z] GC before operation: completed in 212.793 ms, heap usage 152.188 MB -> 63.460 MB. [2025-08-20T20:36:53.953Z] ====== als (apache-spark) [default], iteration 4 completed (22519.891 ms) ====== [2025-08-20T20:36:53.953Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-08-20T20:36:53.953Z] GC before operation: completed in 189.458 ms, heap usage 242.609 MB -> 64.426 MB. [2025-08-20T20:37:14.456Z] ====== als (apache-spark) [default], iteration 5 completed (22079.587 ms) ====== [2025-08-20T20:37:14.456Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-08-20T20:37:14.456Z] GC before operation: completed in 166.930 ms, heap usage 264.276 MB -> 65.060 MB. [2025-08-20T20:37:42.888Z] ====== als (apache-spark) [default], iteration 6 completed (22703.313 ms) ====== [2025-08-20T20:37:42.888Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-08-20T20:37:42.888Z] GC before operation: completed in 300.202 ms, heap usage 377.444 MB -> 65.635 MB. [2025-08-20T20:38:05.730Z] ====== als (apache-spark) [default], iteration 7 completed (25534.812 ms) ====== [2025-08-20T20:38:05.730Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-08-20T20:38:05.730Z] GC before operation: completed in 159.748 ms, heap usage 331.047 MB -> 66.093 MB. [2025-08-20T20:38:25.960Z] ====== als (apache-spark) [default], iteration 8 completed (17991.753 ms) ====== [2025-08-20T20:38:25.960Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-08-20T20:38:25.960Z] GC before operation: completed in 287.081 ms, heap usage 389.032 MB -> 66.646 MB. [2025-08-20T20:39:01.296Z] ====== als (apache-spark) [default], iteration 9 completed (34336.617 ms) ====== [2025-08-20T20:39:01.296Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-08-20T20:39:01.296Z] GC before operation: completed in 227.550 ms, heap usage 427.579 MB -> 67.591 MB. [2025-08-20T20:39:22.080Z] ====== als (apache-spark) [default], iteration 10 completed (22169.360 ms) ====== [2025-08-20T20:39:22.080Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-08-20T20:39:22.080Z] GC before operation: completed in 158.669 ms, heap usage 263.441 MB -> 67.096 MB. [2025-08-20T20:39:55.997Z] ====== als (apache-spark) [default], iteration 11 completed (25785.589 ms) ====== [2025-08-20T20:39:55.997Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-08-20T20:39:55.997Z] GC before operation: completed in 196.507 ms, heap usage 92.191 MB -> 67.156 MB. [2025-08-20T20:40:17.138Z] ====== als (apache-spark) [default], iteration 12 completed (24961.475 ms) ====== [2025-08-20T20:40:17.138Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-08-20T20:40:17.138Z] GC before operation: completed in 245.375 ms, heap usage 201.038 MB -> 67.464 MB. [2025-08-20T20:40:49.115Z] ====== als (apache-spark) [default], iteration 13 completed (28844.479 ms) ====== [2025-08-20T20:40:49.115Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-08-20T20:40:49.115Z] GC before operation: completed in 246.670 ms, heap usage 348.300 MB -> 68.048 MB. [2025-08-20T20:41:13.913Z] ====== als (apache-spark) [default], iteration 14 completed (25176.218 ms) ====== [2025-08-20T20:41:13.913Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-08-20T20:41:13.913Z] GC before operation: completed in 182.900 ms, heap usage 222.082 MB -> 68.103 MB. [2025-08-20T20:41:43.531Z] ====== als (apache-spark) [default], iteration 15 completed (29307.552 ms) ====== [2025-08-20T20:41:43.531Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-08-20T20:41:43.531Z] GC before operation: completed in 418.877 ms, heap usage 124.078 MB -> 68.231 MB. [2025-08-20T20:42:26.688Z] ====== als (apache-spark) [default], iteration 16 completed (39623.370 ms) ====== [2025-08-20T20:42:26.688Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-08-20T20:42:26.688Z] GC before operation: completed in 899.893 ms, heap usage 192.347 MB -> 68.471 MB. [2025-08-20T20:43:09.431Z] ====== als (apache-spark) [default], iteration 17 completed (39759.279 ms) ====== [2025-08-20T20:43:09.431Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-08-20T20:43:09.431Z] GC before operation: completed in 134.491 ms, heap usage 210.733 MB -> 68.654 MB. [2025-08-20T20:43:37.357Z] ====== als (apache-spark) [default], iteration 18 completed (30460.310 ms) ====== [2025-08-20T20:43:37.357Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-08-20T20:43:37.357Z] GC before operation: completed in 236.815 ms, heap usage 476.388 MB -> 69.393 MB. [2025-08-20T20:44:13.433Z] ====== als (apache-spark) [default], iteration 19 completed (33609.704 ms) ====== [2025-08-20T20:44:13.433Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-08-20T20:44:13.433Z] GC before operation: completed in 544.790 ms, heap usage 363.099 MB -> 69.533 MB. [2025-08-20T20:44:43.770Z] ====== als (apache-spark) [default], iteration 20 completed (29583.813 ms) ====== [2025-08-20T20:44:43.770Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-08-20T20:44:43.770Z] GC before operation: completed in 217.574 ms, heap usage 448.484 MB -> 69.969 MB. [2025-08-20T20:45:04.599Z] ====== als (apache-spark) [default], iteration 21 completed (21395.114 ms) ====== [2025-08-20T20:45:04.599Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-08-20T20:45:04.599Z] GC before operation: completed in 297.704 ms, heap usage 228.897 MB -> 69.963 MB. [2025-08-20T20:45:33.779Z] ====== als (apache-spark) [default], iteration 22 completed (30218.281 ms) ====== [2025-08-20T20:45:33.779Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-08-20T20:45:33.779Z] GC before operation: completed in 178.183 ms, heap usage 449.026 MB -> 70.449 MB. [2025-08-20T20:46:02.422Z] ====== als (apache-spark) [default], iteration 23 completed (22717.777 ms) ====== [2025-08-20T20:46:02.422Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-08-20T20:46:02.422Z] GC before operation: completed in 1776.666 ms, heap usage 251.006 MB -> 70.406 MB. [2025-08-20T20:46:39.909Z] ====== als (apache-spark) [default], iteration 24 completed (33590.963 ms) ====== [2025-08-20T20:46:39.909Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-08-20T20:46:39.909Z] GC before operation: completed in 390.493 ms, heap usage 204.851 MB -> 70.494 MB. [2025-08-20T20:47:05.589Z] ====== als (apache-spark) [default], iteration 25 completed (27512.363 ms) ====== [2025-08-20T20:47:05.589Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-08-20T20:47:05.589Z] GC before operation: completed in 351.496 ms, heap usage 180.978 MB -> 70.821 MB. [2025-08-20T20:47:54.308Z] ====== als (apache-spark) [default], iteration 26 completed (42999.851 ms) ====== [2025-08-20T20:47:54.308Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-08-20T20:47:54.308Z] GC before operation: completed in 651.993 ms, heap usage 295.427 MB -> 71.198 MB. [2025-08-20T20:48:15.593Z] ====== als (apache-spark) [default], iteration 27 completed (23458.753 ms) ====== [2025-08-20T20:48:15.593Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-08-20T20:48:15.593Z] GC before operation: completed in 245.871 ms, heap usage 108.338 MB -> 71.272 MB. [2025-08-20T20:48:44.197Z] ====== als (apache-spark) [default], iteration 28 completed (25606.000 ms) ====== [2025-08-20T20:48:44.197Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-08-20T20:48:44.197Z] GC before operation: completed in 223.631 ms, heap usage 446.200 MB -> 72.034 MB. [2025-08-20T20:49:15.799Z] ====== als (apache-spark) [default], iteration 29 completed (30392.924 ms) ====== [2025-08-20T20:49:15.799Z] ----------------------------------- [2025-08-20T20:49:15.799Z] renaissance-als_0_PASSED [2025-08-20T20:49:15.799Z] ----------------------------------- [2025-08-20T20:49:15.799Z] [2025-08-20T20:49:15.799Z] TEST TEARDOWN: [2025-08-20T20:49:15.799Z] Nothing to be done for teardown. [2025-08-20T20:49:15.799Z] renaissance-als_0 Finish Time: Wed Aug 20 20:49:12 2025 Epoch Time (ms): 1755722952485