[2025-08-30T00:21:38.606Z] Running test renaissance-als_0 ... [2025-08-30T00:21:38.606Z] =============================================== [2025-08-30T00:21:38.606Z] renaissance-als_0 Start Time: Sat Aug 30 00:21:38 2025 Epoch Time (ms): 1756513298361 [2025-08-30T00:21:38.606Z] variation: NoOptions [2025-08-30T00:21:38.606Z] JVM_OPTIONS: [2025-08-30T00:21:38.606Z] { \ [2025-08-30T00:21:38.606Z] echo ""; echo "TEST SETUP:"; \ [2025-08-30T00:21:38.606Z] echo "Nothing to be done for setup."; \ [2025-08-30T00:21:38.606Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17565132981818/renaissance-als_0"; \ [2025-08-30T00:21:38.606Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17565132981818/renaissance-als_0"; \ [2025-08-30T00:21:38.606Z] echo ""; echo "TESTING:"; \ [2025-08-30T00:21:38.606Z] "/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_17565132981818/renaissance-als_0"/als.json" als; \ [2025-08-30T00:21:38.606Z] 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_17565132981818/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-30T00:21:38.606Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-30T00:21:38.606Z] echo "Nothing to be done for teardown."; \ [2025-08-30T00:21:38.606Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17565132981818/TestTargetResult"; [2025-08-30T00:21:38.606Z] [2025-08-30T00:21:38.606Z] TEST SETUP: [2025-08-30T00:21:38.606Z] Nothing to be done for setup. [2025-08-30T00:21:38.606Z] [2025-08-30T00:21:38.606Z] TESTING: [2025-08-30T00:21:47.250Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-08-30T00:21:51.975Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-08-30T00:21:51.975Z] GC before operation: completed in 106.868 ms, heap usage 52.500 MB -> 35.043 MB. [2025-08-30T00:22:24.977Z] ====== als (apache-spark) [default], iteration 0 completed (30589.725 ms) ====== [2025-08-30T00:22:24.977Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-08-30T00:22:24.977Z] GC before operation: completed in 158.999 ms, heap usage 268.788 MB -> 61.881 MB. [2025-08-30T00:22:41.910Z] ====== als (apache-spark) [default], iteration 1 completed (17588.716 ms) ====== [2025-08-30T00:22:41.910Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-08-30T00:22:41.910Z] GC before operation: completed in 282.063 ms, heap usage 310.472 MB -> 62.739 MB. [2025-08-30T00:22:59.067Z] ====== als (apache-spark) [default], iteration 2 completed (16308.301 ms) ====== [2025-08-30T00:22:59.067Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-08-30T00:22:59.067Z] GC before operation: completed in 244.820 ms, heap usage 461.671 MB -> 63.531 MB. [2025-08-30T00:23:14.045Z] ====== als (apache-spark) [default], iteration 3 completed (14885.990 ms) ====== [2025-08-30T00:23:14.045Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-08-30T00:23:14.045Z] GC before operation: completed in 177.597 ms, heap usage 338.551 MB -> 63.938 MB. [2025-08-30T00:23:28.775Z] ====== als (apache-spark) [default], iteration 4 completed (13712.572 ms) ====== [2025-08-30T00:23:28.775Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-08-30T00:23:28.775Z] GC before operation: completed in 121.224 ms, heap usage 320.271 MB -> 64.449 MB. [2025-08-30T00:23:43.116Z] ====== als (apache-spark) [default], iteration 5 completed (12748.334 ms) ====== [2025-08-30T00:23:43.116Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-08-30T00:23:43.116Z] GC before operation: completed in 173.620 ms, heap usage 368.553 MB -> 65.037 MB. [2025-08-30T00:23:55.297Z] ====== als (apache-spark) [default], iteration 6 completed (13059.447 ms) ====== [2025-08-30T00:23:55.297Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-08-30T00:23:55.297Z] GC before operation: completed in 183.061 ms, heap usage 457.175 MB -> 65.755 MB. [2025-08-30T00:24:09.598Z] ====== als (apache-spark) [default], iteration 7 completed (13754.658 ms) ====== [2025-08-30T00:24:09.598Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-08-30T00:24:09.598Z] GC before operation: completed in 211.616 ms, heap usage 468.709 MB -> 66.314 MB. [2025-08-30T00:24:24.846Z] ====== als (apache-spark) [default], iteration 8 completed (14458.438 ms) ====== [2025-08-30T00:24:24.846Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-08-30T00:24:24.846Z] GC before operation: completed in 208.103 ms, heap usage 212.929 MB -> 66.486 MB. [2025-08-30T00:24:39.527Z] ====== als (apache-spark) [default], iteration 9 completed (14489.196 ms) ====== [2025-08-30T00:24:39.527Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-08-30T00:24:39.527Z] GC before operation: completed in 166.767 ms, heap usage 347.653 MB -> 66.882 MB. [2025-08-30T00:24:51.529Z] ====== als (apache-spark) [default], iteration 10 completed (12268.024 ms) ====== [2025-08-30T00:24:51.529Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-08-30T00:24:52.130Z] GC before operation: completed in 179.867 ms, heap usage 199.613 MB -> 66.867 MB. [2025-08-30T00:25:04.147Z] ====== als (apache-spark) [default], iteration 11 completed (12049.150 ms) ====== [2025-08-30T00:25:04.147Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-08-30T00:25:04.147Z] GC before operation: completed in 131.061 ms, heap usage 391.188 MB -> 67.386 MB. [2025-08-30T00:25:16.077Z] ====== als (apache-spark) [default], iteration 12 completed (11381.596 ms) ====== [2025-08-30T00:25:16.077Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-08-30T00:25:16.077Z] GC before operation: completed in 147.451 ms, heap usage 235.092 MB -> 67.686 MB. [2025-08-30T00:25:28.410Z] ====== als (apache-spark) [default], iteration 13 completed (11432.992 ms) ====== [2025-08-30T00:25:28.410Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-08-30T00:25:28.410Z] GC before operation: completed in 106.421 ms, heap usage 202.364 MB -> 67.780 MB. [2025-08-30T00:25:40.331Z] ====== als (apache-spark) [default], iteration 14 completed (11468.983 ms) ====== [2025-08-30T00:25:40.331Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-08-30T00:25:40.331Z] GC before operation: completed in 140.458 ms, heap usage 513.098 MB -> 68.351 MB. [2025-08-30T00:25:52.260Z] ====== als (apache-spark) [default], iteration 15 completed (11733.536 ms) ====== [2025-08-30T00:25:52.260Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-08-30T00:25:52.260Z] GC before operation: completed in 158.866 ms, heap usage 453.305 MB -> 68.629 MB. [2025-08-30T00:26:04.221Z] ====== als (apache-spark) [default], iteration 16 completed (12065.936 ms) ====== [2025-08-30T00:26:04.221Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-08-30T00:26:04.221Z] GC before operation: completed in 119.911 ms, heap usage 214.137 MB -> 68.434 MB. [2025-08-30T00:26:16.520Z] ====== als (apache-spark) [default], iteration 17 completed (11610.256 ms) ====== [2025-08-30T00:26:16.520Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-08-30T00:26:16.520Z] GC before operation: completed in 178.510 ms, heap usage 322.454 MB -> 68.876 MB. [2025-08-30T00:26:28.463Z] ====== als (apache-spark) [default], iteration 18 completed (11959.595 ms) ====== [2025-08-30T00:26:28.463Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-08-30T00:26:28.463Z] GC before operation: completed in 198.135 ms, heap usage 435.502 MB -> 69.441 MB. [2025-08-30T00:26:40.529Z] ====== als (apache-spark) [default], iteration 19 completed (11825.194 ms) ====== [2025-08-30T00:26:40.529Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-08-30T00:26:41.189Z] GC before operation: completed in 182.838 ms, heap usage 436.785 MB -> 69.721 MB. [2025-08-30T00:26:53.222Z] ====== als (apache-spark) [default], iteration 20 completed (11834.748 ms) ====== [2025-08-30T00:26:53.222Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-08-30T00:26:53.222Z] GC before operation: completed in 114.626 ms, heap usage 500.959 MB -> 69.925 MB. [2025-08-30T00:27:05.209Z] ====== als (apache-spark) [default], iteration 21 completed (11741.845 ms) ====== [2025-08-30T00:27:05.209Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-08-30T00:27:05.209Z] GC before operation: completed in 142.759 ms, heap usage 220.898 MB -> 69.767 MB. [2025-08-30T00:27:17.151Z] ====== als (apache-spark) [default], iteration 22 completed (11562.883 ms) ====== [2025-08-30T00:27:17.151Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-08-30T00:27:17.151Z] GC before operation: completed in 150.782 ms, heap usage 241.754 MB -> 70.206 MB. [2025-08-30T00:27:29.017Z] ====== als (apache-spark) [default], iteration 23 completed (11552.041 ms) ====== [2025-08-30T00:27:29.017Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-08-30T00:27:29.017Z] GC before operation: completed in 179.486 ms, heap usage 491.450 MB -> 70.745 MB. [2025-08-30T00:27:40.861Z] ====== als (apache-spark) [default], iteration 24 completed (10966.204 ms) ====== [2025-08-30T00:27:40.861Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-08-30T00:27:40.861Z] GC before operation: completed in 144.562 ms, heap usage 197.628 MB -> 70.595 MB. [2025-08-30T00:27:52.797Z] ====== als (apache-spark) [default], iteration 25 completed (10753.994 ms) ====== [2025-08-30T00:27:52.797Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-08-30T00:27:52.797Z] GC before operation: completed in 112.734 ms, heap usage 271.052 MB -> 71.102 MB. [2025-08-30T00:28:03.157Z] ====== als (apache-spark) [default], iteration 26 completed (11217.412 ms) ====== [2025-08-30T00:28:03.157Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-08-30T00:28:03.157Z] GC before operation: completed in 115.459 ms, heap usage 307.043 MB -> 71.409 MB. [2025-08-30T00:28:15.159Z] ====== als (apache-spark) [default], iteration 27 completed (11420.920 ms) ====== [2025-08-30T00:28:15.159Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-08-30T00:28:15.159Z] GC before operation: completed in 189.689 ms, heap usage 242.499 MB -> 71.547 MB. [2025-08-30T00:28:27.054Z] ====== als (apache-spark) [default], iteration 28 completed (11057.530 ms) ====== [2025-08-30T00:28:27.054Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-08-30T00:28:27.054Z] GC before operation: completed in 112.068 ms, heap usage 196.670 MB -> 71.562 MB. [2025-08-30T00:28:38.998Z] ====== als (apache-spark) [default], iteration 29 completed (10975.878 ms) ====== [2025-08-30T00:28:38.998Z] ----------------------------------- [2025-08-30T00:28:38.998Z] renaissance-als_0_PASSED [2025-08-30T00:28:38.998Z] ----------------------------------- [2025-08-30T00:28:38.998Z] [2025-08-30T00:28:38.998Z] TEST TEARDOWN: [2025-08-30T00:28:38.998Z] Nothing to be done for teardown. [2025-08-30T00:28:38.998Z] renaissance-als_0 Finish Time: Sat Aug 30 00:28:37 2025 Epoch Time (ms): 1756513717867