renaissance-als_0
[2025-06-19T03:20:25.813Z] Running test renaissance-als_0 ...
[2025-06-19T03:20:25.813Z] ===============================================
[2025-06-19T03:20:25.813Z] renaissance-als_0 Start Time: Thu Jun 19 03:20:25 2025 Epoch Time (ms): 1750303225491
[2025-06-19T03:20:25.813Z] variation: NoOptions
[2025-06-19T03:20:25.813Z] JVM_OPTIONS:
[2025-06-19T03:20:25.813Z] { \
[2025-06-19T03:20:25.813Z] echo ""; echo "TEST SETUP:"; \
[2025-06-19T03:20:25.813Z] echo "Nothing to be done for setup."; \
[2025-06-19T03:20:25.813Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17503031877704/renaissance-als_0"; \
[2025-06-19T03:20:25.813Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17503031877704/renaissance-als_0"; \
[2025-06-19T03:20:25.813Z] echo ""; echo "TESTING:"; \
[2025-06-19T03:20:25.813Z] "/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_17503031877704/renaissance-als_0"/als.json" als; \
[2025-06-19T03:20:25.813Z] 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_17503031877704/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \
[2025-06-19T03:20:25.813Z] echo ""; echo "TEST TEARDOWN:"; \
[2025-06-19T03:20:25.813Z] echo "Nothing to be done for teardown."; \
[2025-06-19T03:20:25.813Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17503031877704/TestTargetResult";
[2025-06-19T03:20:25.813Z]
[2025-06-19T03:20:25.813Z] TEST SETUP:
[2025-06-19T03:20:25.813Z] Nothing to be done for setup.
[2025-06-19T03:20:25.813Z]
[2025-06-19T03:20:25.813Z] TESTING:
[2025-06-19T03:20:31.781Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads.
[2025-06-19T03:20:35.694Z] ====== als (apache-spark) [default], iteration 0 started ======
[2025-06-19T03:20:35.694Z] GC before operation: completed in 45.139 ms, heap usage 59.918 MB -> 34.934 MB.
[2025-06-19T03:21:03.863Z] ====== als (apache-spark) [default], iteration 0 completed (27432.773 ms) ======
[2025-06-19T03:21:03.863Z] ====== als (apache-spark) [default], iteration 1 started ======
[2025-06-19T03:21:03.863Z] GC before operation: completed in 146.333 ms, heap usage 226.260 MB -> 61.934 MB.
[2025-06-19T03:21:21.246Z] ====== als (apache-spark) [default], iteration 1 completed (15605.639 ms) ======
[2025-06-19T03:21:21.246Z] ====== als (apache-spark) [default], iteration 2 started ======
[2025-06-19T03:21:21.246Z] GC before operation: completed in 150.810 ms, heap usage 291.999 MB -> 62.732 MB.
[2025-06-19T03:21:35.867Z] ====== als (apache-spark) [default], iteration 2 completed (14547.394 ms) ======
[2025-06-19T03:21:35.867Z] ====== als (apache-spark) [default], iteration 3 started ======
[2025-06-19T03:21:35.867Z] GC before operation: completed in 113.023 ms, heap usage 409.054 MB -> 63.479 MB.
[2025-06-19T03:21:48.454Z] ====== als (apache-spark) [default], iteration 3 completed (13748.125 ms) ======
[2025-06-19T03:21:48.454Z] ====== als (apache-spark) [default], iteration 4 started ======
[2025-06-19T03:21:49.107Z] GC before operation: completed in 115.904 ms, heap usage 262.835 MB -> 63.890 MB.
[2025-06-19T03:22:03.750Z] ====== als (apache-spark) [default], iteration 4 completed (13102.138 ms) ======
[2025-06-19T03:22:03.750Z] ====== als (apache-spark) [default], iteration 5 started ======
[2025-06-19T03:22:03.750Z] GC before operation: completed in 132.626 ms, heap usage 112.366 MB -> 64.392 MB.
[2025-06-19T03:22:16.449Z] ====== als (apache-spark) [default], iteration 5 completed (12619.361 ms) ======
[2025-06-19T03:22:16.449Z] ====== als (apache-spark) [default], iteration 6 started ======
[2025-06-19T03:22:16.449Z] GC before operation: completed in 113.757 ms, heap usage 362.143 MB -> 65.111 MB.
[2025-06-19T03:22:28.824Z] ====== als (apache-spark) [default], iteration 6 completed (13408.534 ms) ======
[2025-06-19T03:22:28.824Z] ====== als (apache-spark) [default], iteration 7 started ======
[2025-06-19T03:22:29.464Z] GC before operation: completed in 150.814 ms, heap usage 131.349 MB -> 65.482 MB.
[2025-06-19T03:22:44.158Z] ====== als (apache-spark) [default], iteration 7 completed (13316.013 ms) ======
[2025-06-19T03:22:44.158Z] ====== als (apache-spark) [default], iteration 8 started ======
[2025-06-19T03:22:44.158Z] GC before operation: completed in 124.913 ms, heap usage 245.237 MB -> 66.119 MB.
[2025-06-19T03:22:56.562Z] ====== als (apache-spark) [default], iteration 8 completed (13243.288 ms) ======
[2025-06-19T03:22:56.562Z] ====== als (apache-spark) [default], iteration 9 started ======
[2025-06-19T03:22:56.562Z] GC before operation: completed in 169.221 ms, heap usage 508.570 MB -> 66.994 MB.
[2025-06-19T03:23:11.168Z] ====== als (apache-spark) [default], iteration 9 completed (13312.729 ms) ======
[2025-06-19T03:23:11.168Z] ====== als (apache-spark) [default], iteration 10 started ======
[2025-06-19T03:23:11.168Z] GC before operation: completed in 130.652 ms, heap usage 130.559 MB -> 66.411 MB.
[2025-06-19T03:23:23.416Z] ====== als (apache-spark) [default], iteration 10 completed (11546.157 ms) ======
[2025-06-19T03:23:23.416Z] ====== als (apache-spark) [default], iteration 11 started ======
[2025-06-19T03:23:23.416Z] GC before operation: completed in 98.373 ms, heap usage 450.564 MB -> 67.439 MB.
[2025-06-19T03:23:33.669Z] ====== als (apache-spark) [default], iteration 11 completed (11450.277 ms) ======
[2025-06-19T03:23:33.669Z] ====== als (apache-spark) [default], iteration 12 started ======
[2025-06-19T03:23:33.669Z] GC before operation: completed in 119.904 ms, heap usage 224.650 MB -> 67.308 MB.
[2025-06-19T03:23:48.814Z] ====== als (apache-spark) [default], iteration 12 completed (12732.448 ms) ======
[2025-06-19T03:23:48.814Z] ====== als (apache-spark) [default], iteration 13 started ======
[2025-06-19T03:23:48.814Z] GC before operation: completed in 155.819 ms, heap usage 207.480 MB -> 67.571 MB.
[2025-06-19T03:23:59.154Z] ====== als (apache-spark) [default], iteration 13 completed (11798.043 ms) ======
[2025-06-19T03:23:59.154Z] ====== als (apache-spark) [default], iteration 14 started ======
[2025-06-19T03:23:59.154Z] GC before operation: completed in 99.304 ms, heap usage 387.111 MB -> 68.126 MB.
[2025-06-19T03:24:11.456Z] ====== als (apache-spark) [default], iteration 14 completed (11163.620 ms) ======
[2025-06-19T03:24:11.456Z] ====== als (apache-spark) [default], iteration 15 started ======
[2025-06-19T03:24:11.456Z] GC before operation: completed in 146.597 ms, heap usage 242.744 MB -> 68.144 MB.
[2025-06-19T03:24:24.280Z] ====== als (apache-spark) [default], iteration 15 completed (13328.088 ms) ======
[2025-06-19T03:24:24.280Z] ====== als (apache-spark) [default], iteration 16 started ======
[2025-06-19T03:24:24.280Z] GC before operation: completed in 147.646 ms, heap usage 469.572 MB -> 68.724 MB.
[2025-06-19T03:24:39.066Z] ====== als (apache-spark) [default], iteration 16 completed (12933.458 ms) ======
[2025-06-19T03:24:39.066Z] ====== als (apache-spark) [default], iteration 17 started ======
[2025-06-19T03:24:39.066Z] GC before operation: completed in 127.552 ms, heap usage 467.832 MB -> 69.105 MB.
[2025-06-19T03:24:51.505Z] ====== als (apache-spark) [default], iteration 17 completed (12192.316 ms) ======
[2025-06-19T03:24:51.505Z] ====== als (apache-spark) [default], iteration 18 started ======
[2025-06-19T03:24:51.505Z] GC before operation: completed in 139.593 ms, heap usage 275.147 MB -> 68.975 MB.
[2025-06-19T03:25:03.824Z] ====== als (apache-spark) [default], iteration 18 completed (12456.521 ms) ======
[2025-06-19T03:25:03.824Z] ====== als (apache-spark) [default], iteration 19 started ======
[2025-06-19T03:25:03.824Z] GC before operation: completed in 145.582 ms, heap usage 244.588 MB -> 68.935 MB.
[2025-06-19T03:25:16.191Z] ====== als (apache-spark) [default], iteration 19 completed (12333.969 ms) ======
[2025-06-19T03:25:16.191Z] ====== als (apache-spark) [default], iteration 20 started ======
[2025-06-19T03:25:16.191Z] GC before operation: completed in 131.695 ms, heap usage 108.293 MB -> 69.787 MB.
[2025-06-19T03:25:30.809Z] ====== als (apache-spark) [default], iteration 20 completed (13406.416 ms) ======
[2025-06-19T03:25:30.809Z] ====== als (apache-spark) [default], iteration 21 started ======
[2025-06-19T03:25:30.809Z] GC before operation: completed in 134.278 ms, heap usage 255.574 MB -> 69.730 MB.
[2025-06-19T03:25:43.617Z] ====== als (apache-spark) [default], iteration 21 completed (14061.626 ms) ======
[2025-06-19T03:25:43.617Z] ====== als (apache-spark) [default], iteration 22 started ======
[2025-06-19T03:25:44.242Z] GC before operation: completed in 114.054 ms, heap usage 157.655 MB -> 69.958 MB.
[2025-06-19T03:25:58.925Z] ====== als (apache-spark) [default], iteration 22 completed (13326.347 ms) ======
[2025-06-19T03:25:58.925Z] ====== als (apache-spark) [default], iteration 23 started ======
[2025-06-19T03:25:58.925Z] GC before operation: completed in 129.382 ms, heap usage 148.020 MB -> 70.086 MB.
[2025-06-19T03:26:16.445Z] ====== als (apache-spark) [default], iteration 23 completed (18721.411 ms) ======
[2025-06-19T03:26:16.445Z] ====== als (apache-spark) [default], iteration 24 started ======
[2025-06-19T03:26:16.445Z] GC before operation: completed in 185.426 ms, heap usage 277.307 MB -> 70.552 MB.
[2025-06-19T03:26:31.066Z] ====== als (apache-spark) [default], iteration 24 completed (14127.668 ms) ======
[2025-06-19T03:26:31.067Z] ====== als (apache-spark) [default], iteration 25 started ======
[2025-06-19T03:26:31.067Z] GC before operation: completed in 141.844 ms, heap usage 237.458 MB -> 70.663 MB.
[2025-06-19T03:26:46.240Z] ====== als (apache-spark) [default], iteration 25 completed (13951.780 ms) ======
[2025-06-19T03:26:46.240Z] ====== als (apache-spark) [default], iteration 26 started ======
[2025-06-19T03:26:46.240Z] GC before operation: completed in 150.982 ms, heap usage 187.029 MB -> 71.026 MB.
[2025-06-19T03:27:03.533Z] ====== als (apache-spark) [default], iteration 26 completed (15690.380 ms) ======
[2025-06-19T03:27:03.533Z] ====== als (apache-spark) [default], iteration 27 started ======
[2025-06-19T03:27:03.533Z] GC before operation: completed in 179.500 ms, heap usage 218.736 MB -> 71.225 MB.
[2025-06-19T03:27:16.056Z] ====== als (apache-spark) [default], iteration 27 completed (13642.275 ms) ======
[2025-06-19T03:27:16.056Z] ====== als (apache-spark) [default], iteration 28 started ======
[2025-06-19T03:27:16.056Z] GC before operation: completed in 153.679 ms, heap usage 193.629 MB -> 71.506 MB.
[2025-06-19T03:27:30.422Z] ====== als (apache-spark) [default], iteration 28 completed (13703.799 ms) ======
[2025-06-19T03:27:30.422Z] ====== als (apache-spark) [default], iteration 29 started ======
[2025-06-19T03:27:30.422Z] GC before operation: completed in 165.427 ms, heap usage 206.634 MB -> 71.636 MB.
[2025-06-19T03:27:45.413Z] ====== als (apache-spark) [default], iteration 29 completed (13186.725 ms) ======
[2025-06-19T03:27:45.413Z] -----------------------------------
[2025-06-19T03:27:45.413Z] renaissance-als_0_PASSED
[2025-06-19T03:27:45.413Z] -----------------------------------
[2025-06-19T03:27:45.413Z]
[2025-06-19T03:27:45.413Z] TEST TEARDOWN:
[2025-06-19T03:27:45.413Z] Nothing to be done for teardown.
[2025-06-19T03:27:45.413Z] renaissance-als_0 Finish Time: Thu Jun 19 03:27:43 2025 Epoch Time (ms): 1750303663424