[2025-09-25T00:16:32.959Z] Running test renaissance-als_0 ... [2025-09-25T00:16:32.959Z] =============================================== [2025-09-25T00:16:32.959Z] renaissance-als_0 Start Time: Thu Sep 25 00:16:32 2025 Epoch Time (ms): 1758759392775 [2025-09-25T00:16:32.959Z] variation: NoOptions [2025-09-25T00:16:32.959Z] JVM_OPTIONS: [2025-09-25T00:16:32.959Z] { \ [2025-09-25T00:16:32.959Z] echo ""; echo "TEST SETUP:"; \ [2025-09-25T00:16:32.959Z] echo "Nothing to be done for setup."; \ [2025-09-25T00:16:32.959Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17587592341836/renaissance-als_0"; \ [2025-09-25T00:16:32.959Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17587592341836/renaissance-als_0"; \ [2025-09-25T00:16:32.959Z] echo ""; echo "TESTING:"; \ [2025-09-25T00:16:32.959Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_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_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17587592341836/renaissance-als_0"/als.json" als; \ [2025-09-25T00:16:32.959Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17587592341836/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-25T00:16:32.959Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-25T00:16:32.959Z] echo "Nothing to be done for teardown."; \ [2025-09-25T00:16:32.959Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17587592341836/TestTargetResult"; [2025-09-25T00:16:32.959Z] [2025-09-25T00:16:32.959Z] TEST SETUP: [2025-09-25T00:16:32.959Z] Nothing to be done for setup. [2025-09-25T00:16:32.959Z] [2025-09-25T00:16:32.959Z] TESTING: [2025-09-25T00:16:38.287Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-09-25T00:16:42.401Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-09-25T00:16:42.401Z] GC before operation: completed in 43.991 ms, heap usage 150.390 MB -> 35.300 MB. [2025-09-25T00:17:00.618Z] ====== als (apache-spark) [default], iteration 0 completed (16709.125 ms) ====== [2025-09-25T00:17:00.618Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-09-25T00:17:00.618Z] GC before operation: completed in 99.660 ms, heap usage 198.032 MB -> 62.290 MB. [2025-09-25T00:17:08.735Z] ====== als (apache-spark) [default], iteration 1 completed (7845.325 ms) ====== [2025-09-25T00:17:08.735Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-09-25T00:17:08.735Z] GC before operation: completed in 86.149 ms, heap usage 214.421 MB -> 62.806 MB. [2025-09-25T00:17:15.369Z] ====== als (apache-spark) [default], iteration 2 completed (7066.311 ms) ====== [2025-09-25T00:17:15.369Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-09-25T00:17:15.369Z] GC before operation: completed in 94.937 ms, heap usage 477.870 MB -> 63.853 MB. [2025-09-25T00:17:22.608Z] ====== als (apache-spark) [default], iteration 3 completed (6975.277 ms) ====== [2025-09-25T00:17:22.608Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-09-25T00:17:23.554Z] GC before operation: completed in 88.887 ms, heap usage 203.485 MB -> 64.132 MB. [2025-09-25T00:17:30.205Z] ====== als (apache-spark) [default], iteration 4 completed (6714.522 ms) ====== [2025-09-25T00:17:30.205Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-09-25T00:17:30.205Z] GC before operation: completed in 101.962 ms, heap usage 125.879 MB -> 64.615 MB. [2025-09-25T00:17:36.855Z] ====== als (apache-spark) [default], iteration 5 completed (6753.416 ms) ====== [2025-09-25T00:17:36.855Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-09-25T00:17:36.855Z] GC before operation: completed in 105.836 ms, heap usage 543.100 MB -> 65.654 MB. [2025-09-25T00:17:44.945Z] ====== als (apache-spark) [default], iteration 6 completed (6655.465 ms) ====== [2025-09-25T00:17:44.945Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-09-25T00:17:44.945Z] GC before operation: completed in 94.147 ms, heap usage 465.869 MB -> 66.090 MB. [2025-09-25T00:17:51.741Z] ====== als (apache-spark) [default], iteration 7 completed (6651.798 ms) ====== [2025-09-25T00:17:51.741Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-09-25T00:17:51.741Z] GC before operation: completed in 118.710 ms, heap usage 244.933 MB -> 66.322 MB. [2025-09-25T00:17:58.391Z] ====== als (apache-spark) [default], iteration 8 completed (6739.683 ms) ====== [2025-09-25T00:17:58.391Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-09-25T00:17:58.392Z] GC before operation: completed in 100.563 ms, heap usage 357.843 MB -> 66.966 MB. [2025-09-25T00:18:05.037Z] ====== als (apache-spark) [default], iteration 9 completed (6640.845 ms) ====== [2025-09-25T00:18:05.037Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-09-25T00:18:05.037Z] GC before operation: completed in 102.356 ms, heap usage 359.659 MB -> 67.208 MB. [2025-09-25T00:18:12.686Z] ====== als (apache-spark) [default], iteration 10 completed (6728.882 ms) ====== [2025-09-25T00:18:12.686Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-09-25T00:18:12.686Z] GC before operation: completed in 97.309 ms, heap usage 546.348 MB -> 67.785 MB. [2025-09-25T00:18:19.334Z] ====== als (apache-spark) [default], iteration 11 completed (6586.388 ms) ====== [2025-09-25T00:18:19.334Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-09-25T00:18:19.334Z] GC before operation: completed in 100.241 ms, heap usage 544.835 MB -> 68.087 MB. [2025-09-25T00:18:25.991Z] ====== als (apache-spark) [default], iteration 12 completed (6664.519 ms) ====== [2025-09-25T00:18:25.991Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-09-25T00:18:25.991Z] GC before operation: completed in 100.387 ms, heap usage 115.592 MB -> 67.809 MB. [2025-09-25T00:18:34.070Z] ====== als (apache-spark) [default], iteration 13 completed (6731.001 ms) ====== [2025-09-25T00:18:34.070Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-09-25T00:18:34.070Z] GC before operation: completed in 103.914 ms, heap usage 499.694 MB -> 68.484 MB. [2025-09-25T00:18:40.725Z] ====== als (apache-spark) [default], iteration 14 completed (6560.235 ms) ====== [2025-09-25T00:18:40.725Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-09-25T00:18:40.725Z] GC before operation: completed in 102.949 ms, heap usage 289.549 MB -> 68.499 MB. [2025-09-25T00:18:47.392Z] ====== als (apache-spark) [default], iteration 15 completed (6548.863 ms) ====== [2025-09-25T00:18:47.392Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-09-25T00:18:47.392Z] GC before operation: completed in 105.286 ms, heap usage 402.307 MB -> 68.778 MB. [2025-09-25T00:18:54.053Z] ====== als (apache-spark) [default], iteration 16 completed (6474.542 ms) ====== [2025-09-25T00:18:54.053Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-09-25T00:18:54.053Z] GC before operation: completed in 102.483 ms, heap usage 500.587 MB -> 69.232 MB. [2025-09-25T00:19:00.872Z] ====== als (apache-spark) [default], iteration 17 completed (6657.518 ms) ====== [2025-09-25T00:19:00.872Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-09-25T00:19:00.872Z] GC before operation: completed in 105.742 ms, heap usage 499.541 MB -> 69.543 MB. [2025-09-25T00:19:07.046Z] ====== als (apache-spark) [default], iteration 18 completed (6555.576 ms) ====== [2025-09-25T00:19:07.046Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-09-25T00:19:07.046Z] GC before operation: completed in 102.596 ms, heap usage 467.621 MB -> 69.770 MB. [2025-09-25T00:19:15.128Z] ====== als (apache-spark) [default], iteration 19 completed (6558.650 ms) ====== [2025-09-25T00:19:15.128Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-09-25T00:19:15.128Z] GC before operation: completed in 100.226 ms, heap usage 293.666 MB -> 69.734 MB. [2025-09-25T00:19:21.849Z] ====== als (apache-spark) [default], iteration 20 completed (6730.181 ms) ====== [2025-09-25T00:19:21.849Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-09-25T00:19:21.849Z] GC before operation: completed in 104.763 ms, heap usage 516.506 MB -> 70.280 MB. [2025-09-25T00:19:28.492Z] ====== als (apache-spark) [default], iteration 21 completed (6497.515 ms) ====== [2025-09-25T00:19:28.492Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-09-25T00:19:28.492Z] GC before operation: completed in 108.127 ms, heap usage 159.335 MB -> 70.135 MB. [2025-09-25T00:19:35.147Z] ====== als (apache-spark) [default], iteration 22 completed (6530.466 ms) ====== [2025-09-25T00:19:35.147Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-09-25T00:19:35.147Z] GC before operation: completed in 112.587 ms, heap usage 252.007 MB -> 70.592 MB. [2025-09-25T00:19:41.790Z] ====== als (apache-spark) [default], iteration 23 completed (6486.287 ms) ====== [2025-09-25T00:19:41.790Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-09-25T00:19:41.790Z] GC before operation: completed in 121.844 ms, heap usage 477.621 MB -> 71.198 MB. [2025-09-25T00:19:48.442Z] ====== als (apache-spark) [default], iteration 24 completed (6474.852 ms) ====== [2025-09-25T00:19:48.443Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-09-25T00:19:48.443Z] GC before operation: completed in 128.451 ms, heap usage 202.165 MB -> 70.866 MB. [2025-09-25T00:19:55.091Z] ====== als (apache-spark) [default], iteration 25 completed (6530.033 ms) ====== [2025-09-25T00:19:55.091Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-09-25T00:19:55.091Z] GC before operation: completed in 102.616 ms, heap usage 397.764 MB -> 71.337 MB. [2025-09-25T00:20:02.861Z] ====== als (apache-spark) [default], iteration 26 completed (6428.904 ms) ====== [2025-09-25T00:20:02.861Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-09-25T00:20:02.861Z] GC before operation: completed in 121.067 ms, heap usage 254.040 MB -> 71.515 MB. [2025-09-25T00:20:09.623Z] ====== als (apache-spark) [default], iteration 27 completed (6391.892 ms) ====== [2025-09-25T00:20:09.623Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-09-25T00:20:09.623Z] GC before operation: completed in 105.727 ms, heap usage 295.059 MB -> 71.910 MB. [2025-09-25T00:20:14.944Z] ====== als (apache-spark) [default], iteration 28 completed (6298.547 ms) ====== [2025-09-25T00:20:14.944Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-09-25T00:20:15.886Z] GC before operation: completed in 105.292 ms, heap usage 793.375 MB -> 72.746 MB. [2025-09-25T00:20:22.539Z] ====== als (apache-spark) [default], iteration 29 completed (6307.234 ms) ====== [2025-09-25T00:20:22.539Z] ----------------------------------- [2025-09-25T00:20:22.539Z] renaissance-als_0_PASSED [2025-09-25T00:20:22.539Z] ----------------------------------- [2025-09-25T00:20:22.539Z] [2025-09-25T00:20:22.539Z] TEST TEARDOWN: [2025-09-25T00:20:22.539Z] Nothing to be done for teardown. [2025-09-25T00:20:22.539Z] renaissance-als_0 Finish Time: Thu Sep 25 00:20:21 2025 Epoch Time (ms): 1758759621895