[2025-08-30T00:58:38.391Z] Running test renaissance-als_0 ... [2025-08-30T00:58:38.391Z] =============================================== [2025-08-30T00:58:38.391Z] renaissance-als_0 Start Time: Sat Aug 30 00:58:38 2025 Epoch Time (ms): 1756515518105 [2025-08-30T00:58:38.391Z] variation: NoOptions [2025-08-30T00:58:38.391Z] JVM_OPTIONS: [2025-08-30T00:58:38.391Z] { \ [2025-08-30T00:58:38.391Z] echo ""; echo "TEST SETUP:"; \ [2025-08-30T00:58:38.391Z] echo "Nothing to be done for setup."; \ [2025-08-30T00:58:38.391Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17565154209047/renaissance-als_0"; \ [2025-08-30T00:58:38.391Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17565154209047/renaissance-als_0"; \ [2025-08-30T00:58:38.391Z] echo ""; echo "TESTING:"; \ [2025-08-30T00:58:38.391Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17565154209047/renaissance-als_0"/als.json" als; \ [2025-08-30T00:58:38.391Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17565154209047/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-30T00:58:38.391Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-30T00:58:38.391Z] echo "Nothing to be done for teardown."; \ [2025-08-30T00:58:38.391Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17565154209047/TestTargetResult"; [2025-08-30T00:58:38.391Z] [2025-08-30T00:58:38.391Z] TEST SETUP: [2025-08-30T00:58:38.391Z] Nothing to be done for setup. [2025-08-30T00:58:38.391Z] [2025-08-30T00:58:38.391Z] TESTING: [2025-08-30T00:58:46.747Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 24) threads. [2025-08-30T00:58:51.250Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-08-30T00:58:51.250Z] GC before operation: completed in 69.894 ms, heap usage 321.227 MB -> 34.772 MB. [2025-08-30T00:59:05.127Z] ====== als (apache-spark) [default], iteration 0 completed (12989.962 ms) ====== [2025-08-30T00:59:05.128Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-08-30T00:59:05.128Z] GC before operation: completed in 113.443 ms, heap usage 220.113 MB -> 61.856 MB. [2025-08-30T00:59:09.638Z] ====== als (apache-spark) [default], iteration 1 completed (4998.067 ms) ====== [2025-08-30T00:59:09.638Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-08-30T00:59:10.416Z] GC before operation: completed in 109.456 ms, heap usage 414.358 MB -> 62.937 MB. [2025-08-30T00:59:14.939Z] ====== als (apache-spark) [default], iteration 2 completed (4737.919 ms) ====== [2025-08-30T00:59:14.939Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-08-30T00:59:14.939Z] GC before operation: completed in 105.468 ms, heap usage 158.301 MB -> 63.055 MB. [2025-08-30T00:59:20.615Z] ====== als (apache-spark) [default], iteration 3 completed (4575.961 ms) ====== [2025-08-30T00:59:20.615Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-08-30T00:59:20.615Z] GC before operation: completed in 123.094 ms, heap usage 331.882 MB -> 63.943 MB. [2025-08-30T00:59:25.141Z] ====== als (apache-spark) [default], iteration 4 completed (4503.941 ms) ====== [2025-08-30T00:59:25.141Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-08-30T00:59:25.141Z] GC before operation: completed in 112.288 ms, heap usage 321.857 MB -> 64.634 MB. [2025-08-30T00:59:29.650Z] ====== als (apache-spark) [default], iteration 5 completed (4535.839 ms) ====== [2025-08-30T00:59:29.650Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-08-30T00:59:29.650Z] GC before operation: completed in 127.417 ms, heap usage 254.070 MB -> 64.973 MB. [2025-08-30T00:59:34.172Z] ====== als (apache-spark) [default], iteration 6 completed (4449.147 ms) ====== [2025-08-30T00:59:34.172Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-08-30T00:59:34.172Z] GC before operation: completed in 112.939 ms, heap usage 171.609 MB -> 65.425 MB. [2025-08-30T00:59:38.691Z] ====== als (apache-spark) [default], iteration 7 completed (4345.246 ms) ====== [2025-08-30T00:59:38.691Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-08-30T00:59:39.470Z] GC before operation: completed in 113.797 ms, heap usage 119.978 MB -> 66.078 MB. [2025-08-30T00:59:43.984Z] ====== als (apache-spark) [default], iteration 8 completed (4460.491 ms) ====== [2025-08-30T00:59:43.984Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-08-30T00:59:43.984Z] GC before operation: completed in 122.067 ms, heap usage 480.305 MB -> 67.339 MB. [2025-08-30T00:59:48.499Z] ====== als (apache-spark) [default], iteration 9 completed (4452.533 ms) ====== [2025-08-30T00:59:48.500Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-08-30T00:59:48.500Z] GC before operation: completed in 114.398 ms, heap usage 383.516 MB -> 67.529 MB. [2025-08-30T00:59:53.010Z] ====== als (apache-spark) [default], iteration 10 completed (4391.381 ms) ====== [2025-08-30T00:59:53.010Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-08-30T00:59:53.010Z] GC before operation: completed in 104.423 ms, heap usage 155.790 MB -> 67.047 MB. [2025-08-30T00:59:57.528Z] ====== als (apache-spark) [default], iteration 11 completed (4389.654 ms) ====== [2025-08-30T00:59:57.528Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-08-30T00:59:58.306Z] GC before operation: completed in 93.576 ms, heap usage 301.306 MB -> 67.696 MB. [2025-08-30T01:00:02.903Z] ====== als (apache-spark) [default], iteration 12 completed (4428.007 ms) ====== [2025-08-30T01:00:02.909Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-08-30T01:00:02.909Z] GC before operation: completed in 97.277 ms, heap usage 148.117 MB -> 67.730 MB. [2025-08-30T01:00:07.432Z] ====== als (apache-spark) [default], iteration 13 completed (4318.093 ms) ====== [2025-08-30T01:00:07.432Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-08-30T01:00:07.432Z] GC before operation: completed in 100.355 ms, heap usage 456.238 MB -> 68.411 MB. [2025-08-30T01:00:11.940Z] ====== als (apache-spark) [default], iteration 14 completed (4228.538 ms) ====== [2025-08-30T01:00:11.940Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-08-30T01:00:11.940Z] GC before operation: completed in 96.190 ms, heap usage 251.152 MB -> 68.310 MB. [2025-08-30T01:00:16.655Z] ====== als (apache-spark) [default], iteration 15 completed (4363.631 ms) ====== [2025-08-30T01:00:16.655Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-08-30T01:00:16.655Z] GC before operation: completed in 102.275 ms, heap usage 338.525 MB -> 68.843 MB. [2025-08-30T01:00:21.156Z] ====== als (apache-spark) [default], iteration 16 completed (4286.163 ms) ====== [2025-08-30T01:00:21.156Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-08-30T01:00:21.156Z] GC before operation: completed in 96.626 ms, heap usage 1.160 GB -> 70.212 MB. [2025-08-30T01:00:25.682Z] ====== als (apache-spark) [default], iteration 17 completed (4294.532 ms) ====== [2025-08-30T01:00:25.682Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-08-30T01:00:25.682Z] GC before operation: completed in 92.569 ms, heap usage 2.336 GB -> 71.509 MB. [2025-08-30T01:00:30.244Z] ====== als (apache-spark) [default], iteration 18 completed (4244.047 ms) ====== [2025-08-30T01:00:30.244Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-08-30T01:00:30.244Z] GC before operation: completed in 98.329 ms, heap usage 879.746 MB -> 70.438 MB. [2025-08-30T01:00:34.749Z] ====== als (apache-spark) [default], iteration 19 completed (4218.046 ms) ====== [2025-08-30T01:00:34.749Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-08-30T01:00:34.749Z] GC before operation: completed in 105.763 ms, heap usage 2.669 GB -> 71.825 MB. [2025-08-30T01:00:39.284Z] ====== als (apache-spark) [default], iteration 20 completed (4321.386 ms) ====== [2025-08-30T01:00:39.284Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-08-30T01:00:39.284Z] GC before operation: completed in 93.914 ms, heap usage 250.513 MB -> 70.043 MB. [2025-08-30T01:00:43.806Z] ====== als (apache-spark) [default], iteration 21 completed (4265.890 ms) ====== [2025-08-30T01:00:43.806Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-08-30T01:00:43.806Z] GC before operation: completed in 110.896 ms, heap usage 2.425 GB -> 72.607 MB. [2025-08-30T01:00:48.321Z] ====== als (apache-spark) [default], iteration 22 completed (4260.793 ms) ====== [2025-08-30T01:00:48.321Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-08-30T01:00:48.321Z] GC before operation: completed in 98.820 ms, heap usage 440.448 MB -> 70.986 MB. [2025-08-30T01:00:52.831Z] ====== als (apache-spark) [default], iteration 23 completed (4223.067 ms) ====== [2025-08-30T01:00:52.831Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-08-30T01:00:52.831Z] GC before operation: completed in 103.529 ms, heap usage 346.500 MB -> 71.286 MB. [2025-08-30T01:00:57.340Z] ====== als (apache-spark) [default], iteration 24 completed (4303.176 ms) ====== [2025-08-30T01:00:57.340Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-08-30T01:00:57.340Z] GC before operation: completed in 98.854 ms, heap usage 701.588 MB -> 71.937 MB. [2025-08-30T01:01:01.842Z] ====== als (apache-spark) [default], iteration 25 completed (4293.701 ms) ====== [2025-08-30T01:01:01.842Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-08-30T01:01:01.842Z] GC before operation: completed in 99.855 ms, heap usage 1.035 GB -> 72.687 MB. [2025-08-30T01:01:06.347Z] ====== als (apache-spark) [default], iteration 26 completed (4184.851 ms) ====== [2025-08-30T01:01:06.347Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-08-30T01:01:06.347Z] GC before operation: completed in 98.575 ms, heap usage 1.203 GB -> 73.173 MB. [2025-08-30T01:01:10.882Z] ====== als (apache-spark) [default], iteration 27 completed (4279.594 ms) ====== [2025-08-30T01:01:10.882Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-08-30T01:01:10.882Z] GC before operation: completed in 95.570 ms, heap usage 194.728 MB -> 71.998 MB. [2025-08-30T01:01:15.426Z] ====== als (apache-spark) [default], iteration 28 completed (4100.680 ms) ====== [2025-08-30T01:01:15.426Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-08-30T01:01:15.426Z] GC before operation: completed in 102.337 ms, heap usage 230.632 MB -> 72.382 MB. [2025-08-30T01:01:19.947Z] ====== als (apache-spark) [default], iteration 29 completed (4320.192 ms) ====== [2025-08-30T01:01:19.947Z] ----------------------------------- [2025-08-30T01:01:19.947Z] renaissance-als_0_PASSED [2025-08-30T01:01:19.947Z] ----------------------------------- [2025-08-30T01:01:19.947Z] [2025-08-30T01:01:19.947Z] TEST TEARDOWN: [2025-08-30T01:01:19.947Z] Nothing to be done for teardown. [2025-08-30T01:01:19.947Z] renaissance-als_0 Finish Time: Sat Aug 30 01:01:19 2025 Epoch Time (ms): 1756515679635