[2025-12-17T21:55:01.315Z] Running test renaissance-als_0 ... [2025-12-17T21:55:01.315Z] =============================================== [2025-12-17T21:55:01.315Z] renaissance-als_0 Start Time: Wed Dec 17 21:55:01 2025 Epoch Time (ms): 1766008501185 [2025-12-17T21:55:01.315Z] variation: NoOptions [2025-12-17T21:55:01.315Z] JVM_OPTIONS: [2025-12-17T21:55:01.315Z] { \ [2025-12-17T21:55:01.315Z] echo ""; echo "TEST SETUP:"; \ [2025-12-17T21:55:01.315Z] echo "Nothing to be done for setup."; \ [2025-12-17T21:55:01.315Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17660083892249/renaissance-als_0"; \ [2025-12-17T21:55:01.315Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17660083892249/renaissance-als_0"; \ [2025-12-17T21:55:01.315Z] echo ""; echo "TESTING:"; \ [2025-12-17T21:55:01.315Z] "/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_17660083892249/renaissance-als_0"/als.json" als; \ [2025-12-17T21:55:01.315Z] 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_17660083892249/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-17T21:55:01.315Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-17T21:55:01.315Z] echo "Nothing to be done for teardown."; \ [2025-12-17T21:55:01.315Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17660083892249/TestTargetResult"; [2025-12-17T21:55:01.315Z] [2025-12-17T21:55:01.315Z] TEST SETUP: [2025-12-17T21:55:01.315Z] Nothing to be done for setup. [2025-12-17T21:55:01.315Z] [2025-12-17T21:55:01.315Z] TESTING: [2025-12-17T21:55:09.698Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 24) threads. [2025-12-17T21:55:14.574Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-17T21:55:14.575Z] GC before operation: completed in 46.936 ms, heap usage 239.684 MB -> 34.564 MB. [2025-12-17T21:55:28.398Z] ====== als (apache-spark) [default], iteration 0 completed (12886.996 ms) ====== [2025-12-17T21:55:28.398Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-17T21:55:28.398Z] GC before operation: completed in 124.926 ms, heap usage 640.400 MB -> 61.807 MB. [2025-12-17T21:55:32.887Z] ====== als (apache-spark) [default], iteration 1 completed (4827.596 ms) ====== [2025-12-17T21:55:32.887Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-17T21:55:32.887Z] GC before operation: completed in 121.165 ms, heap usage 196.027 MB -> 62.132 MB. [2025-12-17T21:55:38.521Z] ====== als (apache-spark) [default], iteration 2 completed (4695.241 ms) ====== [2025-12-17T21:55:38.521Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-17T21:55:38.521Z] GC before operation: completed in 109.659 ms, heap usage 878.921 MB -> 63.695 MB. [2025-12-17T21:55:43.001Z] ====== als (apache-spark) [default], iteration 3 completed (4567.401 ms) ====== [2025-12-17T21:55:43.001Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-17T21:55:43.001Z] GC before operation: completed in 110.304 ms, heap usage 547.205 MB -> 63.798 MB. [2025-12-17T21:55:47.484Z] ====== als (apache-spark) [default], iteration 4 completed (4483.212 ms) ====== [2025-12-17T21:55:47.484Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-17T21:55:47.484Z] GC before operation: completed in 105.791 ms, heap usage 937.586 MB -> 64.879 MB. [2025-12-17T21:55:51.970Z] ====== als (apache-spark) [default], iteration 5 completed (4506.460 ms) ====== [2025-12-17T21:55:51.970Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-17T21:55:52.740Z] GC before operation: completed in 120.842 ms, heap usage 1.016 GB -> 65.589 MB. [2025-12-17T21:55:57.241Z] ====== als (apache-spark) [default], iteration 6 completed (4534.340 ms) ====== [2025-12-17T21:55:57.241Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-17T21:55:57.241Z] GC before operation: completed in 109.508 ms, heap usage 939.569 MB -> 66.047 MB. [2025-12-17T21:56:01.935Z] ====== als (apache-spark) [default], iteration 7 completed (4570.686 ms) ====== [2025-12-17T21:56:01.935Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-17T21:56:01.935Z] GC before operation: completed in 123.062 ms, heap usage 1.761 GB -> 67.458 MB. [2025-12-17T21:56:07.568Z] ====== als (apache-spark) [default], iteration 8 completed (4520.880 ms) ====== [2025-12-17T21:56:07.568Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-17T21:56:07.568Z] GC before operation: completed in 121.238 ms, heap usage 870.188 MB -> 67.021 MB. [2025-12-17T21:56:12.040Z] ====== als (apache-spark) [default], iteration 9 completed (4377.690 ms) ====== [2025-12-17T21:56:12.040Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-17T21:56:12.040Z] GC before operation: completed in 134.845 ms, heap usage 1.689 GB -> 68.326 MB. [2025-12-17T21:56:16.528Z] ====== als (apache-spark) [default], iteration 10 completed (4534.780 ms) ====== [2025-12-17T21:56:16.528Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-17T21:56:16.528Z] GC before operation: completed in 130.793 ms, heap usage 171.342 MB -> 66.685 MB. [2025-12-17T21:56:21.009Z] ====== als (apache-spark) [default], iteration 11 completed (4342.187 ms) ====== [2025-12-17T21:56:21.009Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-17T21:56:21.009Z] GC before operation: completed in 116.598 ms, heap usage 598.014 MB -> 67.491 MB. [2025-12-17T21:56:25.471Z] ====== als (apache-spark) [default], iteration 12 completed (4381.498 ms) ====== [2025-12-17T21:56:25.471Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-17T21:56:25.471Z] GC before operation: completed in 119.784 ms, heap usage 779.617 MB -> 68.034 MB. [2025-12-17T21:56:31.088Z] ====== als (apache-spark) [default], iteration 13 completed (4462.974 ms) ====== [2025-12-17T21:56:31.088Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-17T21:56:31.088Z] GC before operation: completed in 114.864 ms, heap usage 1.108 GB -> 68.740 MB. [2025-12-17T21:56:35.560Z] ====== als (apache-spark) [default], iteration 14 completed (4380.160 ms) ====== [2025-12-17T21:56:35.560Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-17T21:56:35.560Z] GC before operation: completed in 126.865 ms, heap usage 1.789 GB -> 69.674 MB. [2025-12-17T21:56:40.032Z] ====== als (apache-spark) [default], iteration 15 completed (4449.834 ms) ====== [2025-12-17T21:56:40.032Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-17T21:56:40.032Z] GC before operation: completed in 131.622 ms, heap usage 1.869 GB -> 70.052 MB. [2025-12-17T21:56:44.506Z] ====== als (apache-spark) [default], iteration 16 completed (4416.988 ms) ====== [2025-12-17T21:56:44.506Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-17T21:56:44.506Z] GC before operation: completed in 125.480 ms, heap usage 2.385 GB -> 70.564 MB. [2025-12-17T21:56:49.182Z] ====== als (apache-spark) [default], iteration 17 completed (4380.011 ms) ====== [2025-12-17T21:56:49.182Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-17T21:56:49.182Z] GC before operation: completed in 125.093 ms, heap usage 1.250 GB -> 69.947 MB. [2025-12-17T21:56:53.665Z] ====== als (apache-spark) [default], iteration 18 completed (4477.174 ms) ====== [2025-12-17T21:56:53.665Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-17T21:56:54.445Z] GC before operation: completed in 118.587 ms, heap usage 647.273 MB -> 69.464 MB. [2025-12-17T21:56:58.919Z] ====== als (apache-spark) [default], iteration 19 completed (4401.032 ms) ====== [2025-12-17T21:56:58.919Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-17T21:56:58.919Z] GC before operation: completed in 134.743 ms, heap usage 166.715 MB -> 69.019 MB. [2025-12-17T21:57:03.402Z] ====== als (apache-spark) [default], iteration 20 completed (4333.649 ms) ====== [2025-12-17T21:57:03.402Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-17T21:57:03.402Z] GC before operation: completed in 123.303 ms, heap usage 695.743 MB -> 69.993 MB. [2025-12-17T21:57:07.890Z] ====== als (apache-spark) [default], iteration 21 completed (4264.597 ms) ====== [2025-12-17T21:57:07.891Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-17T21:57:07.891Z] GC before operation: completed in 122.805 ms, heap usage 886.728 MB -> 70.531 MB. [2025-12-17T21:57:12.395Z] ====== als (apache-spark) [default], iteration 22 completed (4435.453 ms) ====== [2025-12-17T21:57:12.395Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-17T21:57:12.395Z] GC before operation: completed in 136.907 ms, heap usage 560.018 MB -> 70.413 MB. [2025-12-17T21:57:16.986Z] ====== als (apache-spark) [default], iteration 23 completed (4427.056 ms) ====== [2025-12-17T21:57:16.986Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-17T21:57:17.758Z] GC before operation: completed in 131.715 ms, heap usage 962.734 MB -> 71.117 MB. [2025-12-17T21:57:22.255Z] ====== als (apache-spark) [default], iteration 24 completed (4370.049 ms) ====== [2025-12-17T21:57:22.255Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-17T21:57:22.255Z] GC before operation: completed in 120.654 ms, heap usage 1.021 GB -> 71.399 MB. [2025-12-17T21:57:26.730Z] ====== als (apache-spark) [default], iteration 25 completed (4594.403 ms) ====== [2025-12-17T21:57:26.730Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-17T21:57:26.730Z] GC before operation: completed in 130.825 ms, heap usage 198.120 MB -> 70.512 MB. [2025-12-17T21:57:31.399Z] ====== als (apache-spark) [default], iteration 26 completed (4596.271 ms) ====== [2025-12-17T21:57:31.399Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-17T21:57:32.170Z] GC before operation: completed in 124.220 ms, heap usage 307.534 MB -> 70.779 MB. [2025-12-17T21:57:36.644Z] ====== als (apache-spark) [default], iteration 27 completed (4418.496 ms) ====== [2025-12-17T21:57:36.644Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-17T21:57:36.644Z] GC before operation: completed in 134.182 ms, heap usage 700.764 MB -> 71.256 MB. [2025-12-17T21:57:41.134Z] ====== als (apache-spark) [default], iteration 28 completed (4626.312 ms) ====== [2025-12-17T21:57:41.134Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-17T21:57:41.134Z] GC before operation: completed in 129.547 ms, heap usage 511.331 MB -> 71.265 MB. [2025-12-17T21:57:46.751Z] ====== als (apache-spark) [default], iteration 29 completed (4611.539 ms) ====== [2025-12-17T21:57:46.751Z] ----------------------------------- [2025-12-17T21:57:46.751Z] renaissance-als_0_PASSED [2025-12-17T21:57:46.751Z] ----------------------------------- [2025-12-17T21:57:46.751Z] [2025-12-17T21:57:46.751Z] TEST TEARDOWN: [2025-12-17T21:57:46.751Z] Nothing to be done for teardown. [2025-12-17T21:57:46.751Z] renaissance-als_0 Finish Time: Wed Dec 17 21:57:46 2025 Epoch Time (ms): 1766008666395