[2025-05-29T01:50:11.192Z] Running test renaissance-als_0 ... [2025-05-29T01:50:11.192Z] =============================================== [2025-05-29T01:50:11.192Z] renaissance-als_0 Start Time: Thu May 29 01:50:11 2025 Epoch Time (ms): 1748483411043 [2025-05-29T01:50:11.193Z] variation: NoOptions [2025-05-29T01:50:11.193Z] JVM_OPTIONS: [2025-05-29T01:50:11.193Z] { \ [2025-05-29T01:50:11.193Z] echo ""; echo "TEST SETUP:"; \ [2025-05-29T01:50:11.193Z] echo "Nothing to be done for setup."; \ [2025-05-29T01:50:11.193Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17484833661900/renaissance-als_0"; \ [2025-05-29T01:50:11.193Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17484833661900/renaissance-als_0"; \ [2025-05-29T01:50:11.193Z] echo ""; echo "TESTING:"; \ [2025-05-29T01:50:11.193Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/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_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17484833661900/renaissance-als_0"/als.json" als; \ [2025-05-29T01:50:11.193Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17484833661900/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-29T01:50:11.193Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-29T01:50:11.193Z] echo "Nothing to be done for teardown."; \ [2025-05-29T01:50:11.193Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17484833661900/TestTargetResult"; [2025-05-29T01:50:11.193Z] [2025-05-29T01:50:11.193Z] TEST SETUP: [2025-05-29T01:50:11.193Z] Nothing to be done for setup. [2025-05-29T01:50:11.193Z] [2025-05-29T01:50:11.193Z] TESTING: [2025-05-29T01:50:19.480Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 24) threads. [2025-05-29T01:50:23.946Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-05-29T01:50:23.946Z] GC before operation: completed in 58.169 ms, heap usage 275.970 MB -> 34.672 MB. [2025-05-29T01:50:37.724Z] ====== als (apache-spark) [default], iteration 0 completed (12701.879 ms) ====== [2025-05-29T01:50:37.724Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-05-29T01:50:37.724Z] GC before operation: completed in 122.939 ms, heap usage 1.318 GB -> 62.828 MB. [2025-05-29T01:50:43.348Z] ====== als (apache-spark) [default], iteration 1 completed (5167.750 ms) ====== [2025-05-29T01:50:43.348Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-05-29T01:50:43.348Z] GC before operation: completed in 119.340 ms, heap usage 136.045 MB -> 63.768 MB. [2025-05-29T01:50:47.922Z] ====== als (apache-spark) [default], iteration 2 completed (4768.640 ms) ====== [2025-05-29T01:50:47.922Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-05-29T01:50:47.922Z] GC before operation: completed in 109.309 ms, heap usage 608.052 MB -> 63.727 MB. [2025-05-29T01:50:53.541Z] ====== als (apache-spark) [default], iteration 3 completed (4609.636 ms) ====== [2025-05-29T01:50:53.541Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-05-29T01:50:53.541Z] GC before operation: completed in 128.270 ms, heap usage 747.444 MB -> 64.462 MB. [2025-05-29T01:50:58.006Z] ====== als (apache-spark) [default], iteration 4 completed (4467.974 ms) ====== [2025-05-29T01:50:58.006Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-05-29T01:50:58.006Z] GC before operation: completed in 123.513 ms, heap usage 1.819 GB -> 66.162 MB. [2025-05-29T01:51:02.476Z] ====== als (apache-spark) [default], iteration 5 completed (4635.565 ms) ====== [2025-05-29T01:51:02.476Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-05-29T01:51:02.476Z] GC before operation: completed in 125.810 ms, heap usage 1.379 GB -> 66.191 MB. [2025-05-29T01:51:08.087Z] ====== als (apache-spark) [default], iteration 6 completed (4393.253 ms) ====== [2025-05-29T01:51:08.087Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-05-29T01:51:08.087Z] GC before operation: completed in 122.756 ms, heap usage 139.855 MB -> 65.132 MB. [2025-05-29T01:51:12.570Z] ====== als (apache-spark) [default], iteration 7 completed (4436.387 ms) ====== [2025-05-29T01:51:12.570Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-05-29T01:51:12.570Z] GC before operation: completed in 117.995 ms, heap usage 689.844 MB -> 66.421 MB. [2025-05-29T01:51:17.051Z] ====== als (apache-spark) [default], iteration 8 completed (4443.190 ms) ====== [2025-05-29T01:51:17.051Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-05-29T01:51:17.051Z] GC before operation: completed in 124.570 ms, heap usage 704.107 MB -> 67.083 MB. [2025-05-29T01:51:21.540Z] ====== als (apache-spark) [default], iteration 9 completed (4325.936 ms) ====== [2025-05-29T01:51:21.540Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-05-29T01:51:21.540Z] GC before operation: completed in 127.329 ms, heap usage 905.659 MB -> 67.584 MB. [2025-05-29T01:51:26.005Z] ====== als (apache-spark) [default], iteration 10 completed (4420.753 ms) ====== [2025-05-29T01:51:26.005Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-05-29T01:51:26.005Z] GC before operation: completed in 119.116 ms, heap usage 525.086 MB -> 67.531 MB. [2025-05-29T01:51:30.476Z] ====== als (apache-spark) [default], iteration 11 completed (4174.042 ms) ====== [2025-05-29T01:51:30.476Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-05-29T01:51:30.476Z] GC before operation: completed in 128.961 ms, heap usage 555.690 MB -> 67.768 MB. [2025-05-29T01:51:36.094Z] ====== als (apache-spark) [default], iteration 12 completed (4424.945 ms) ====== [2025-05-29T01:51:36.094Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-05-29T01:51:36.094Z] GC before operation: completed in 133.782 ms, heap usage 2.014 GB -> 69.610 MB. [2025-05-29T01:51:40.561Z] ====== als (apache-spark) [default], iteration 13 completed (4392.294 ms) ====== [2025-05-29T01:51:40.561Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-05-29T01:51:40.561Z] GC before operation: completed in 127.932 ms, heap usage 686.381 MB -> 68.393 MB. [2025-05-29T01:51:45.084Z] ====== als (apache-spark) [default], iteration 14 completed (4275.032 ms) ====== [2025-05-29T01:51:45.084Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-05-29T01:51:45.084Z] GC before operation: completed in 130.261 ms, heap usage 374.412 MB -> 68.275 MB. [2025-05-29T01:51:49.564Z] ====== als (apache-spark) [default], iteration 15 completed (4445.942 ms) ====== [2025-05-29T01:51:49.564Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-05-29T01:51:49.564Z] GC before operation: completed in 125.820 ms, heap usage 350.834 MB -> 68.536 MB. [2025-05-29T01:51:54.045Z] ====== als (apache-spark) [default], iteration 16 completed (4298.382 ms) ====== [2025-05-29T01:51:54.045Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-05-29T01:51:54.045Z] GC before operation: completed in 131.805 ms, heap usage 572.463 MB -> 69.057 MB. [2025-05-29T01:51:58.512Z] ====== als (apache-spark) [default], iteration 17 completed (4183.628 ms) ====== [2025-05-29T01:51:58.512Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-05-29T01:51:58.512Z] GC before operation: completed in 125.199 ms, heap usage 515.757 MB -> 69.350 MB. [2025-05-29T01:52:02.977Z] ====== als (apache-spark) [default], iteration 18 completed (4431.759 ms) ====== [2025-05-29T01:52:02.977Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-05-29T01:52:02.977Z] GC before operation: completed in 139.394 ms, heap usage 719.298 MB -> 69.747 MB. [2025-05-29T01:52:07.454Z] ====== als (apache-spark) [default], iteration 19 completed (4393.707 ms) ====== [2025-05-29T01:52:07.454Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-05-29T01:52:08.225Z] GC before operation: completed in 133.400 ms, heap usage 550.946 MB -> 70.067 MB. [2025-05-29T01:52:12.714Z] ====== als (apache-spark) [default], iteration 20 completed (4462.451 ms) ====== [2025-05-29T01:52:12.714Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-05-29T01:52:12.714Z] GC before operation: completed in 134.360 ms, heap usage 1.818 GB -> 71.465 MB. [2025-05-29T01:52:17.214Z] ====== als (apache-spark) [default], iteration 21 completed (4368.003 ms) ====== [2025-05-29T01:52:17.214Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-05-29T01:52:17.214Z] GC before operation: completed in 127.667 ms, heap usage 347.884 MB -> 70.071 MB. [2025-05-29T01:52:21.682Z] ====== als (apache-spark) [default], iteration 22 completed (4301.445 ms) ====== [2025-05-29T01:52:21.682Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-05-29T01:52:21.682Z] GC before operation: completed in 137.455 ms, heap usage 595.169 MB -> 70.631 MB. [2025-05-29T01:52:26.193Z] ====== als (apache-spark) [default], iteration 23 completed (4299.276 ms) ====== [2025-05-29T01:52:26.193Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-05-29T01:52:26.193Z] GC before operation: completed in 130.047 ms, heap usage 340.282 MB -> 70.528 MB. [2025-05-29T01:52:30.658Z] ====== als (apache-spark) [default], iteration 24 completed (4267.863 ms) ====== [2025-05-29T01:52:30.658Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-05-29T01:52:31.428Z] GC before operation: completed in 150.115 ms, heap usage 1.779 GB -> 72.519 MB. [2025-05-29T01:52:35.906Z] ====== als (apache-spark) [default], iteration 25 completed (4523.921 ms) ====== [2025-05-29T01:52:35.906Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-05-29T01:52:35.906Z] GC before operation: completed in 127.962 ms, heap usage 1.012 GB -> 71.953 MB. [2025-05-29T01:52:40.393Z] ====== als (apache-spark) [default], iteration 26 completed (4303.357 ms) ====== [2025-05-29T01:52:40.393Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-05-29T01:52:40.393Z] GC before operation: completed in 134.641 ms, heap usage 209.731 MB -> 71.157 MB. [2025-05-29T01:52:44.853Z] ====== als (apache-spark) [default], iteration 27 completed (4202.366 ms) ====== [2025-05-29T01:52:44.853Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-05-29T01:52:44.853Z] GC before operation: completed in 125.173 ms, heap usage 810.997 MB -> 72.372 MB. [2025-05-29T01:52:49.341Z] ====== als (apache-spark) [default], iteration 28 completed (4344.056 ms) ====== [2025-05-29T01:52:49.341Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-05-29T01:52:49.341Z] GC before operation: completed in 128.716 ms, heap usage 467.522 MB -> 72.121 MB. [2025-05-29T01:52:53.805Z] ====== als (apache-spark) [default], iteration 29 completed (4131.584 ms) ====== [2025-05-29T01:52:54.575Z] ----------------------------------- [2025-05-29T01:52:54.575Z] renaissance-als_0_PASSED [2025-05-29T01:52:54.575Z] ----------------------------------- [2025-05-29T01:52:54.575Z] [2025-05-29T01:52:54.575Z] TEST TEARDOWN: [2025-05-29T01:52:54.575Z] Nothing to be done for teardown. [2025-05-29T01:52:54.576Z] renaissance-als_0 Finish Time: Thu May 29 01:52:54 2025 Epoch Time (ms): 1748483574176