[2025-05-28T20:40:57.395Z] Running test renaissance-als_0 ... [2025-05-28T20:40:57.395Z] =============================================== [2025-05-28T20:40:57.395Z] renaissance-als_0 Start Time: Wed May 28 20:40:56 2025 Epoch Time (ms): 1748464856945 [2025-05-28T20:40:57.395Z] variation: NoOptions [2025-05-28T20:40:57.395Z] JVM_OPTIONS: [2025-05-28T20:40:57.395Z] { \ [2025-05-28T20:40:57.395Z] echo ""; echo "TEST SETUP:"; \ [2025-05-28T20:40:57.395Z] echo "Nothing to be done for setup."; \ [2025-05-28T20:40:57.395Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17484648193972/renaissance-als_0"; \ [2025-05-28T20:40:57.395Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17484648193972/renaissance-als_0"; \ [2025-05-28T20:40:57.395Z] echo ""; echo "TESTING:"; \ [2025-05-28T20:40:57.395Z] "/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_17484648193972/renaissance-als_0"/als.json" als; \ [2025-05-28T20:40:57.395Z] 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_17484648193972/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-28T20:40:57.395Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-28T20:40:57.395Z] echo "Nothing to be done for teardown."; \ [2025-05-28T20:40:57.395Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17484648193972/TestTargetResult"; [2025-05-28T20:40:57.395Z] [2025-05-28T20:40:57.395Z] TEST SETUP: [2025-05-28T20:40:57.395Z] Nothing to be done for setup. [2025-05-28T20:40:57.395Z] [2025-05-28T20:40:57.395Z] TESTING: [2025-05-28T20:41:05.631Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 24) threads. [2025-05-28T20:41:11.201Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-05-28T20:41:11.201Z] GC before operation: completed in 73.329 ms, heap usage 213.886 MB -> 34.894 MB. [2025-05-28T20:41:27.255Z] ====== als (apache-spark) [default], iteration 0 completed (13866.053 ms) ====== [2025-05-28T20:41:27.255Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-05-28T20:41:27.255Z] GC before operation: completed in 123.067 ms, heap usage 342.270 MB -> 61.839 MB. [2025-05-28T20:41:31.690Z] ====== als (apache-spark) [default], iteration 1 completed (5183.626 ms) ====== [2025-05-28T20:41:31.690Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-05-28T20:41:31.690Z] GC before operation: completed in 116.247 ms, heap usage 290.510 MB -> 62.920 MB. [2025-05-28T20:41:37.266Z] ====== als (apache-spark) [default], iteration 2 completed (5001.521 ms) ====== [2025-05-28T20:41:37.266Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-05-28T20:41:37.266Z] GC before operation: completed in 113.418 ms, heap usage 486.031 MB -> 63.730 MB. [2025-05-28T20:41:42.837Z] ====== als (apache-spark) [default], iteration 3 completed (5147.344 ms) ====== [2025-05-28T20:41:42.837Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-05-28T20:41:42.837Z] GC before operation: completed in 103.028 ms, heap usage 378.091 MB -> 64.112 MB. [2025-05-28T20:41:47.282Z] ====== als (apache-spark) [default], iteration 4 completed (4965.081 ms) ====== [2025-05-28T20:41:47.282Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-05-28T20:41:47.282Z] GC before operation: completed in 118.893 ms, heap usage 298.205 MB -> 64.531 MB. [2025-05-28T20:41:52.874Z] ====== als (apache-spark) [default], iteration 5 completed (4874.300 ms) ====== [2025-05-28T20:41:52.874Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-05-28T20:41:52.874Z] GC before operation: completed in 112.421 ms, heap usage 476.451 MB -> 65.569 MB. [2025-05-28T20:41:58.460Z] ====== als (apache-spark) [default], iteration 6 completed (4840.523 ms) ====== [2025-05-28T20:41:58.460Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-05-28T20:41:58.460Z] GC before operation: completed in 123.468 ms, heap usage 416.789 MB -> 66.179 MB. [2025-05-28T20:42:02.898Z] ====== als (apache-spark) [default], iteration 7 completed (4628.995 ms) ====== [2025-05-28T20:42:02.898Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-05-28T20:42:02.898Z] GC before operation: completed in 120.050 ms, heap usage 442.326 MB -> 66.820 MB. [2025-05-28T20:42:08.466Z] ====== als (apache-spark) [default], iteration 8 completed (4811.398 ms) ====== [2025-05-28T20:42:08.466Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-05-28T20:42:08.466Z] GC before operation: completed in 117.486 ms, heap usage 257.801 MB -> 66.934 MB. [2025-05-28T20:42:12.954Z] ====== als (apache-spark) [default], iteration 9 completed (4756.084 ms) ====== [2025-05-28T20:42:12.954Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-05-28T20:42:12.954Z] GC before operation: completed in 111.125 ms, heap usage 255.282 MB -> 67.620 MB. [2025-05-28T20:42:18.525Z] ====== als (apache-spark) [default], iteration 10 completed (4850.918 ms) ====== [2025-05-28T20:42:18.525Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-05-28T20:42:18.525Z] GC before operation: completed in 113.316 ms, heap usage 286.363 MB -> 67.190 MB. [2025-05-28T20:42:22.957Z] ====== als (apache-spark) [default], iteration 11 completed (4783.333 ms) ====== [2025-05-28T20:42:22.957Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-05-28T20:42:22.957Z] GC before operation: completed in 109.878 ms, heap usage 365.246 MB -> 67.727 MB. [2025-05-28T20:42:28.522Z] ====== als (apache-spark) [default], iteration 12 completed (4664.510 ms) ====== [2025-05-28T20:42:28.522Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-05-28T20:42:28.522Z] GC before operation: completed in 105.088 ms, heap usage 1.455 GB -> 69.443 MB. [2025-05-28T20:42:32.958Z] ====== als (apache-spark) [default], iteration 13 completed (4563.331 ms) ====== [2025-05-28T20:42:32.958Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-05-28T20:42:32.958Z] GC before operation: completed in 108.145 ms, heap usage 143.357 MB -> 68.021 MB. [2025-05-28T20:42:38.896Z] ====== als (apache-spark) [default], iteration 14 completed (4805.630 ms) ====== [2025-05-28T20:42:38.896Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-05-28T20:42:38.896Z] GC before operation: completed in 103.301 ms, heap usage 240.478 MB -> 68.516 MB. [2025-05-28T20:42:43.156Z] ====== als (apache-spark) [default], iteration 15 completed (4688.893 ms) ====== [2025-05-28T20:42:43.156Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-05-28T20:42:43.156Z] GC before operation: completed in 121.223 ms, heap usage 88.408 MB -> 68.533 MB. [2025-05-28T20:42:48.716Z] ====== als (apache-spark) [default], iteration 16 completed (4778.660 ms) ====== [2025-05-28T20:42:48.716Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-05-28T20:42:48.716Z] GC before operation: completed in 114.126 ms, heap usage 485.305 MB -> 69.237 MB. [2025-05-28T20:42:53.152Z] ====== als (apache-spark) [default], iteration 17 completed (4716.449 ms) ====== [2025-05-28T20:42:53.152Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-05-28T20:42:53.152Z] GC before operation: completed in 115.283 ms, heap usage 1.034 GB -> 70.345 MB. [2025-05-28T20:42:58.723Z] ====== als (apache-spark) [default], iteration 18 completed (4752.279 ms) ====== [2025-05-28T20:42:58.723Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-05-28T20:42:58.723Z] GC before operation: completed in 110.248 ms, heap usage 355.371 MB -> 69.644 MB. [2025-05-28T20:43:03.156Z] ====== als (apache-spark) [default], iteration 19 completed (4743.100 ms) ====== [2025-05-28T20:43:03.156Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-05-28T20:43:03.156Z] GC before operation: completed in 105.093 ms, heap usage 355.272 MB -> 69.833 MB. [2025-05-28T20:43:08.783Z] ====== als (apache-spark) [default], iteration 20 completed (4669.119 ms) ====== [2025-05-28T20:43:08.783Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-05-28T20:43:08.783Z] GC before operation: completed in 110.989 ms, heap usage 873.327 MB -> 70.749 MB. [2025-05-28T20:43:13.218Z] ====== als (apache-spark) [default], iteration 21 completed (4723.885 ms) ====== [2025-05-28T20:43:13.218Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-05-28T20:43:13.218Z] GC before operation: completed in 104.690 ms, heap usage 673.419 MB -> 70.831 MB. [2025-05-28T20:43:17.666Z] ====== als (apache-spark) [default], iteration 22 completed (4677.383 ms) ====== [2025-05-28T20:43:17.666Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-05-28T20:43:18.430Z] GC before operation: completed in 116.751 ms, heap usage 1.859 GB -> 72.499 MB. [2025-05-28T20:43:22.905Z] ====== als (apache-spark) [default], iteration 23 completed (4706.577 ms) ====== [2025-05-28T20:43:22.905Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-05-28T20:43:22.905Z] GC before operation: completed in 125.812 ms, heap usage 160.944 MB -> 70.613 MB. [2025-05-28T20:43:28.508Z] ====== als (apache-spark) [default], iteration 24 completed (4707.292 ms) ====== [2025-05-28T20:43:28.508Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-05-28T20:43:28.508Z] GC before operation: completed in 109.683 ms, heap usage 144.983 MB -> 70.758 MB. [2025-05-28T20:43:32.934Z] ====== als (apache-spark) [default], iteration 25 completed (4567.064 ms) ====== [2025-05-28T20:43:32.934Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-05-28T20:43:32.934Z] GC before operation: completed in 106.591 ms, heap usage 1.331 GB -> 72.550 MB. [2025-05-28T20:43:37.367Z] ====== als (apache-spark) [default], iteration 26 completed (4524.347 ms) ====== [2025-05-28T20:43:37.367Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-05-28T20:43:38.132Z] GC before operation: completed in 104.601 ms, heap usage 257.323 MB -> 71.378 MB. [2025-05-28T20:43:42.555Z] ====== als (apache-spark) [default], iteration 27 completed (4735.814 ms) ====== [2025-05-28T20:43:42.555Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-05-28T20:43:42.555Z] GC before operation: completed in 107.810 ms, heap usage 178.990 MB -> 71.056 MB. [2025-05-28T20:43:48.117Z] ====== als (apache-spark) [default], iteration 28 completed (4702.114 ms) ====== [2025-05-28T20:43:48.117Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-05-28T20:43:48.117Z] GC before operation: completed in 98.795 ms, heap usage 374.369 MB -> 71.546 MB. [2025-05-28T20:43:52.550Z] ====== als (apache-spark) [default], iteration 29 completed (4552.722 ms) ====== [2025-05-28T20:43:53.319Z] ----------------------------------- [2025-05-28T20:43:53.319Z] renaissance-als_0_PASSED [2025-05-28T20:43:53.319Z] ----------------------------------- [2025-05-28T20:43:53.319Z] [2025-05-28T20:43:53.319Z] TEST TEARDOWN: [2025-05-28T20:43:53.319Z] Nothing to be done for teardown. [2025-05-28T20:43:53.319Z] renaissance-als_0 Finish Time: Wed May 28 20:43:52 2025 Epoch Time (ms): 1748465032782