[2025-05-16T21:04:54.053Z] Running test renaissance-als_0 ... [2025-05-16T21:04:54.053Z] =============================================== [2025-05-16T21:04:54.053Z] renaissance-als_0 Start Time: Fri May 16 21:04:53 2025 Epoch Time (ms): 1747429493174 [2025-05-16T21:04:54.053Z] variation: NoOptions [2025-05-16T21:04:54.053Z] JVM_OPTIONS: [2025-05-16T21:04:54.053Z] { \ [2025-05-16T21:04:54.053Z] echo ""; echo "TEST SETUP:"; \ [2025-05-16T21:04:54.053Z] echo "Nothing to be done for setup."; \ [2025-05-16T21:04:54.053Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1747429417991/renaissance-als_0"; \ [2025-05-16T21:04:54.053Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1747429417991/renaissance-als_0"; \ [2025-05-16T21:04:54.053Z] echo ""; echo "TESTING:"; \ [2025-05-16T21:04:54.053Z] "/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_1747429417991/renaissance-als_0"/als.json" als; \ [2025-05-16T21:04:54.053Z] 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_1747429417991/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-16T21:04:54.053Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-16T21:04:54.053Z] echo "Nothing to be done for teardown."; \ [2025-05-16T21:04:54.053Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1747429417991/TestTargetResult"; [2025-05-16T21:04:54.053Z] [2025-05-16T21:04:54.053Z] TEST SETUP: [2025-05-16T21:04:54.053Z] Nothing to be done for setup. [2025-05-16T21:04:54.053Z] [2025-05-16T21:04:54.053Z] TESTING: [2025-05-16T21:05:09.307Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-05-16T21:05:18.007Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-05-16T21:05:18.007Z] GC before operation: completed in 559.616 ms, heap usage 52.005 MB -> 34.986 MB. [2025-05-16T21:06:25.386Z] ====== als (apache-spark) [default], iteration 0 completed (54104.710 ms) ====== [2025-05-16T21:06:25.386Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-05-16T21:06:25.386Z] GC before operation: completed in 872.083 ms, heap usage 405.574 MB -> 61.968 MB. [2025-05-16T21:06:54.882Z] ====== als (apache-spark) [default], iteration 1 completed (36045.893 ms) ====== [2025-05-16T21:06:54.882Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-05-16T21:06:54.882Z] GC before operation: completed in 228.644 ms, heap usage 204.912 MB -> 62.641 MB. [2025-05-16T21:07:19.511Z] ====== als (apache-spark) [default], iteration 2 completed (21950.884 ms) ====== [2025-05-16T21:07:19.511Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-05-16T21:07:19.511Z] GC before operation: completed in 140.321 ms, heap usage 146.370 MB -> 63.102 MB. [2025-05-16T21:07:41.369Z] ====== als (apache-spark) [default], iteration 3 completed (21806.606 ms) ====== [2025-05-16T21:07:41.369Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-05-16T21:07:41.369Z] GC before operation: completed in 192.993 ms, heap usage 203.692 MB -> 63.746 MB. [2025-05-16T21:08:06.185Z] ====== als (apache-spark) [default], iteration 4 completed (23242.393 ms) ====== [2025-05-16T21:08:06.185Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-05-16T21:08:06.185Z] GC before operation: completed in 170.384 ms, heap usage 338.835 MB -> 64.493 MB. [2025-05-16T21:08:27.896Z] ====== als (apache-spark) [default], iteration 5 completed (22107.188 ms) ====== [2025-05-16T21:08:27.896Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-05-16T21:08:27.896Z] GC before operation: completed in 235.694 ms, heap usage 326.546 MB -> 65.040 MB. [2025-05-16T21:08:53.363Z] ====== als (apache-spark) [default], iteration 6 completed (25478.307 ms) ====== [2025-05-16T21:08:53.363Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-05-16T21:08:53.363Z] GC before operation: completed in 260.354 ms, heap usage 161.231 MB -> 65.284 MB. [2025-05-16T21:09:29.044Z] ====== als (apache-spark) [default], iteration 7 completed (30486.909 ms) ====== [2025-05-16T21:09:29.044Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-05-16T21:09:29.044Z] GC before operation: completed in 163.724 ms, heap usage 119.709 MB -> 66.119 MB. [2025-05-16T21:09:50.376Z] ====== als (apache-spark) [default], iteration 8 completed (22150.332 ms) ====== [2025-05-16T21:09:50.376Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-05-16T21:09:50.376Z] GC before operation: completed in 198.567 ms, heap usage 380.381 MB -> 66.594 MB. [2025-05-16T21:10:11.750Z] ====== als (apache-spark) [default], iteration 9 completed (23338.008 ms) ====== [2025-05-16T21:10:11.750Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-05-16T21:10:11.750Z] GC before operation: completed in 232.779 ms, heap usage 324.240 MB -> 67.336 MB. [2025-05-16T21:10:36.278Z] ====== als (apache-spark) [default], iteration 10 completed (21042.704 ms) ====== [2025-05-16T21:10:36.278Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-05-16T21:10:36.278Z] GC before operation: completed in 459.432 ms, heap usage 107.919 MB -> 66.767 MB. [2025-05-16T21:11:05.567Z] ====== als (apache-spark) [default], iteration 11 completed (31004.702 ms) ====== [2025-05-16T21:11:05.567Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-05-16T21:11:05.567Z] GC before operation: completed in 161.733 ms, heap usage 282.031 MB -> 67.223 MB. [2025-05-16T21:11:31.043Z] ====== als (apache-spark) [default], iteration 12 completed (24855.936 ms) ====== [2025-05-16T21:11:31.043Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-05-16T21:11:31.043Z] GC before operation: completed in 263.285 ms, heap usage 135.011 MB -> 67.328 MB. [2025-05-16T21:11:56.056Z] ====== als (apache-spark) [default], iteration 13 completed (21961.771 ms) ====== [2025-05-16T21:11:56.056Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-05-16T21:11:56.056Z] GC before operation: completed in 357.632 ms, heap usage 157.044 MB -> 67.524 MB. [2025-05-16T21:12:18.018Z] ====== als (apache-spark) [default], iteration 14 completed (22999.134 ms) ====== [2025-05-16T21:12:18.018Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-05-16T21:12:18.018Z] GC before operation: completed in 251.172 ms, heap usage 339.696 MB -> 68.085 MB. [2025-05-16T21:12:42.726Z] ====== als (apache-spark) [default], iteration 15 completed (22069.405 ms) ====== [2025-05-16T21:12:42.726Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-05-16T21:12:42.726Z] GC before operation: completed in 265.911 ms, heap usage 136.690 MB -> 68.140 MB. [2025-05-16T21:13:05.641Z] ====== als (apache-spark) [default], iteration 16 completed (24190.920 ms) ====== [2025-05-16T21:13:05.641Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-05-16T21:13:05.641Z] GC before operation: completed in 167.916 ms, heap usage 211.321 MB -> 68.403 MB. [2025-05-16T21:13:23.216Z] ====== als (apache-spark) [default], iteration 17 completed (18332.124 ms) ====== [2025-05-16T21:13:23.216Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-05-16T21:13:23.904Z] GC before operation: completed in 355.573 ms, heap usage 136.343 MB -> 68.615 MB. [2025-05-16T21:13:53.671Z] ====== als (apache-spark) [default], iteration 18 completed (25609.259 ms) ====== [2025-05-16T21:13:53.671Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-05-16T21:13:53.671Z] GC before operation: completed in 268.948 ms, heap usage 128.312 MB -> 68.867 MB. [2025-05-16T21:14:16.907Z] ====== als (apache-spark) [default], iteration 19 completed (26052.490 ms) ====== [2025-05-16T21:14:16.907Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-05-16T21:14:16.907Z] GC before operation: completed in 202.766 ms, heap usage 275.867 MB -> 69.199 MB. [2025-05-16T21:14:51.922Z] ====== als (apache-spark) [default], iteration 20 completed (31617.379 ms) ====== [2025-05-16T21:14:51.922Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-05-16T21:14:51.922Z] GC before operation: completed in 646.082 ms, heap usage 143.520 MB -> 69.399 MB. [2025-05-16T21:15:33.317Z] ====== als (apache-spark) [default], iteration 21 completed (40393.484 ms) ====== [2025-05-16T21:15:33.317Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-05-16T21:15:33.317Z] GC before operation: completed in 1109.498 ms, heap usage 116.052 MB -> 69.651 MB. [2025-05-16T21:16:03.323Z] ====== als (apache-spark) [default], iteration 22 completed (27629.770 ms) ====== [2025-05-16T21:16:03.323Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-05-16T21:16:03.323Z] GC before operation: completed in 296.247 ms, heap usage 104.380 MB -> 69.982 MB. [2025-05-16T21:16:52.909Z] ====== als (apache-spark) [default], iteration 23 completed (47888.280 ms) ====== [2025-05-16T21:16:52.909Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-05-16T21:16:52.909Z] GC before operation: completed in 352.629 ms, heap usage 250.166 MB -> 70.284 MB. [2025-05-16T21:17:22.605Z] ====== als (apache-spark) [default], iteration 24 completed (31549.365 ms) ====== [2025-05-16T21:17:22.605Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-05-16T21:17:22.605Z] GC before operation: completed in 193.461 ms, heap usage 269.203 MB -> 70.564 MB. [2025-05-16T21:18:16.548Z] ====== als (apache-spark) [default], iteration 25 completed (52222.540 ms) ====== [2025-05-16T21:18:16.548Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-05-16T21:18:16.548Z] GC before operation: completed in 513.513 ms, heap usage 159.580 MB -> 70.561 MB. [2025-05-16T21:18:51.397Z] ====== als (apache-spark) [default], iteration 26 completed (33385.433 ms) ====== [2025-05-16T21:18:51.397Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-05-16T21:18:51.397Z] GC before operation: completed in 269.772 ms, heap usage 268.276 MB -> 71.103 MB. [2025-05-16T21:19:16.628Z] ====== als (apache-spark) [default], iteration 27 completed (24689.878 ms) ====== [2025-05-16T21:19:16.628Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-05-16T21:19:16.628Z] GC before operation: completed in 266.965 ms, heap usage 155.194 MB -> 71.171 MB. [2025-05-16T21:19:46.739Z] ====== als (apache-spark) [default], iteration 28 completed (27710.210 ms) ====== [2025-05-16T21:19:46.739Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-05-16T21:19:46.739Z] GC before operation: completed in 242.609 ms, heap usage 248.542 MB -> 71.617 MB. [2025-05-16T21:20:28.581Z] ====== als (apache-spark) [default], iteration 29 completed (37992.129 ms) ====== [2025-05-16T21:20:28.581Z] ----------------------------------- [2025-05-16T21:20:28.581Z] renaissance-als_0_PASSED [2025-05-16T21:20:28.581Z] ----------------------------------- [2025-05-16T21:20:28.581Z] [2025-05-16T21:20:28.581Z] TEST TEARDOWN: [2025-05-16T21:20:28.581Z] Nothing to be done for teardown. [2025-05-16T21:20:28.581Z] renaissance-als_0 Finish Time: Fri May 16 21:20:25 2025 Epoch Time (ms): 1747430425445