[2025-11-13T01:43:41.687Z] Running test renaissance-als_0 ... [2025-11-13T01:43:41.687Z] =============================================== [2025-11-13T01:43:41.687Z] renaissance-als_0 Start Time: Thu Nov 13 01:43:41 2025 Epoch Time (ms): 1762998221574 [2025-11-13T01:43:41.687Z] variation: NoOptions [2025-11-13T01:43:41.687Z] JVM_OPTIONS: [2025-11-13T01:43:41.687Z] { \ [2025-11-13T01:43:41.687Z] echo ""; echo "TEST SETUP:"; \ [2025-11-13T01:43:41.687Z] echo "Nothing to be done for setup."; \ [2025-11-13T01:43:41.687Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17629980493844/renaissance-als_0"; \ [2025-11-13T01:43:41.687Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17629980493844/renaissance-als_0"; \ [2025-11-13T01:43:41.687Z] echo ""; echo "TESTING:"; \ [2025-11-13T01:43:41.687Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17629980493844/renaissance-als_0"/als.json" als; \ [2025-11-13T01:43:41.687Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17629980493844/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-13T01:43:41.687Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-13T01:43:41.687Z] echo "Nothing to be done for teardown."; \ [2025-11-13T01:43:41.687Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17629980493844/TestTargetResult"; [2025-11-13T01:43:41.687Z] [2025-11-13T01:43:41.687Z] TEST SETUP: [2025-11-13T01:43:41.687Z] Nothing to be done for setup. [2025-11-13T01:43:41.687Z] [2025-11-13T01:43:41.687Z] TESTING: [2025-11-13T01:43:47.108Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-13T01:43:51.294Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-13T01:43:51.294Z] GC before operation: completed in 34.292 ms, heap usage 51.425 MB -> 35.114 MB. [2025-11-13T01:44:07.154Z] ====== als (apache-spark) [default], iteration 0 completed (15780.394 ms) ====== [2025-11-13T01:44:07.154Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-13T01:44:07.154Z] GC before operation: completed in 93.501 ms, heap usage 545.094 MB -> 62.446 MB. [2025-11-13T01:44:15.392Z] ====== als (apache-spark) [default], iteration 1 completed (7606.695 ms) ====== [2025-11-13T01:44:15.392Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-13T01:44:15.392Z] GC before operation: completed in 70.756 ms, heap usage 620.070 MB -> 63.536 MB. [2025-11-13T01:44:22.350Z] ====== als (apache-spark) [default], iteration 2 completed (7001.728 ms) ====== [2025-11-13T01:44:22.350Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-13T01:44:23.312Z] GC before operation: completed in 73.379 ms, heap usage 605.074 MB -> 64.124 MB. [2025-11-13T01:44:30.066Z] ====== als (apache-spark) [default], iteration 3 completed (6787.903 ms) ====== [2025-11-13T01:44:30.066Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-13T01:44:30.066Z] GC before operation: completed in 73.242 ms, heap usage 282.409 MB -> 64.299 MB. [2025-11-13T01:44:36.835Z] ====== als (apache-spark) [default], iteration 4 completed (6745.578 ms) ====== [2025-11-13T01:44:36.835Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-13T01:44:36.835Z] GC before operation: completed in 75.820 ms, heap usage 305.471 MB -> 64.696 MB. [2025-11-13T01:44:45.207Z] ====== als (apache-spark) [default], iteration 5 completed (6843.863 ms) ====== [2025-11-13T01:44:45.207Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-13T01:44:45.207Z] GC before operation: completed in 77.034 ms, heap usage 349.845 MB -> 65.532 MB. [2025-11-13T01:44:50.627Z] ====== als (apache-spark) [default], iteration 6 completed (6617.306 ms) ====== [2025-11-13T01:44:50.627Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-13T01:44:50.627Z] GC before operation: completed in 73.824 ms, heap usage 159.000 MB -> 65.842 MB. [2025-11-13T01:44:57.540Z] ====== als (apache-spark) [default], iteration 7 completed (6627.964 ms) ====== [2025-11-13T01:44:57.540Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-13T01:44:57.540Z] GC before operation: completed in 74.117 ms, heap usage 332.055 MB -> 66.647 MB. [2025-11-13T01:45:05.755Z] ====== als (apache-spark) [default], iteration 8 completed (6598.562 ms) ====== [2025-11-13T01:45:05.755Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-13T01:45:05.755Z] GC before operation: completed in 106.008 ms, heap usage 130.898 MB -> 66.929 MB. [2025-11-13T01:45:12.517Z] ====== als (apache-spark) [default], iteration 9 completed (7000.681 ms) ====== [2025-11-13T01:45:12.517Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-13T01:45:12.517Z] GC before operation: completed in 102.717 ms, heap usage 489.500 MB -> 67.876 MB. [2025-11-13T01:45:19.320Z] ====== als (apache-spark) [default], iteration 10 completed (6820.823 ms) ====== [2025-11-13T01:45:19.320Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-13T01:45:19.320Z] GC before operation: completed in 92.071 ms, heap usage 142.753 MB -> 67.294 MB. [2025-11-13T01:45:26.076Z] ====== als (apache-spark) [default], iteration 11 completed (6952.125 ms) ====== [2025-11-13T01:45:26.076Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-13T01:45:27.039Z] GC before operation: completed in 96.117 ms, heap usage 482.638 MB -> 68.058 MB. [2025-11-13T01:45:33.799Z] ====== als (apache-spark) [default], iteration 12 completed (7010.104 ms) ====== [2025-11-13T01:45:33.799Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-13T01:45:33.799Z] GC before operation: completed in 91.410 ms, heap usage 401.951 MB -> 68.053 MB. [2025-11-13T01:45:42.017Z] ====== als (apache-spark) [default], iteration 13 completed (6973.871 ms) ====== [2025-11-13T01:45:42.017Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-13T01:45:42.017Z] GC before operation: completed in 131.536 ms, heap usage 184.683 MB -> 68.284 MB. [2025-11-13T01:45:48.935Z] ====== als (apache-spark) [default], iteration 14 completed (7123.094 ms) ====== [2025-11-13T01:45:48.935Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-13T01:45:48.935Z] GC before operation: completed in 128.248 ms, heap usage 214.874 MB -> 68.557 MB. [2025-11-13T01:45:57.336Z] ====== als (apache-spark) [default], iteration 15 completed (8232.549 ms) ====== [2025-11-13T01:45:57.336Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-13T01:45:57.336Z] GC before operation: completed in 131.536 ms, heap usage 329.598 MB -> 68.998 MB. [2025-11-13T01:46:04.093Z] ====== als (apache-spark) [default], iteration 16 completed (6994.203 ms) ====== [2025-11-13T01:46:04.093Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-13T01:46:05.056Z] GC before operation: completed in 129.735 ms, heap usage 391.626 MB -> 69.313 MB. [2025-11-13T01:46:11.811Z] ====== als (apache-spark) [default], iteration 17 completed (6830.322 ms) ====== [2025-11-13T01:46:11.811Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-13T01:46:11.811Z] GC before operation: completed in 103.998 ms, heap usage 235.969 MB -> 69.427 MB. [2025-11-13T01:46:18.569Z] ====== als (apache-spark) [default], iteration 18 completed (6839.558 ms) ====== [2025-11-13T01:46:18.569Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-13T01:46:18.569Z] GC before operation: completed in 105.061 ms, heap usage 151.469 MB -> 69.651 MB. [2025-11-13T01:46:27.772Z] ====== als (apache-spark) [default], iteration 19 completed (6977.796 ms) ====== [2025-11-13T01:46:27.772Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-13T01:46:27.772Z] GC before operation: completed in 96.443 ms, heap usage 244.025 MB -> 69.951 MB. [2025-11-13T01:46:33.200Z] ====== als (apache-spark) [default], iteration 20 completed (7084.743 ms) ====== [2025-11-13T01:46:33.200Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-13T01:46:34.166Z] GC before operation: completed in 107.601 ms, heap usage 223.926 MB -> 70.082 MB. [2025-11-13T01:46:42.380Z] ====== als (apache-spark) [default], iteration 21 completed (7603.277 ms) ====== [2025-11-13T01:46:42.380Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-13T01:46:42.380Z] GC before operation: completed in 96.454 ms, heap usage 525.679 MB -> 70.697 MB. [2025-11-13T01:46:49.145Z] ====== als (apache-spark) [default], iteration 22 completed (6854.682 ms) ====== [2025-11-13T01:46:49.146Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-13T01:46:49.146Z] GC before operation: completed in 97.510 ms, heap usage 465.008 MB -> 70.988 MB. [2025-11-13T01:46:55.900Z] ====== als (apache-spark) [default], iteration 23 completed (6846.657 ms) ====== [2025-11-13T01:46:55.900Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-13T01:46:55.900Z] GC before operation: completed in 95.608 ms, heap usage 299.025 MB -> 70.898 MB. [2025-11-13T01:47:02.655Z] ====== als (apache-spark) [default], iteration 24 completed (6865.275 ms) ====== [2025-11-13T01:47:02.655Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-13T01:47:02.655Z] GC before operation: completed in 97.101 ms, heap usage 185.563 MB -> 71.160 MB. [2025-11-13T01:47:10.901Z] ====== als (apache-spark) [default], iteration 25 completed (6673.857 ms) ====== [2025-11-13T01:47:10.901Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-13T01:47:10.901Z] GC before operation: completed in 98.946 ms, heap usage 160.666 MB -> 71.376 MB. [2025-11-13T01:47:17.659Z] ====== als (apache-spark) [default], iteration 26 completed (6702.097 ms) ====== [2025-11-13T01:47:17.659Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-13T01:47:17.659Z] GC before operation: completed in 92.750 ms, heap usage 153.304 MB -> 71.685 MB. [2025-11-13T01:47:24.422Z] ====== als (apache-spark) [default], iteration 27 completed (6649.350 ms) ====== [2025-11-13T01:47:24.422Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-13T01:47:24.422Z] GC before operation: completed in 96.621 ms, heap usage 328.499 MB -> 72.062 MB. [2025-11-13T01:47:31.176Z] ====== als (apache-spark) [default], iteration 28 completed (6775.058 ms) ====== [2025-11-13T01:47:31.176Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-13T01:47:31.176Z] GC before operation: completed in 99.099 ms, heap usage 326.130 MB -> 71.824 MB. [2025-11-13T01:47:37.932Z] ====== als (apache-spark) [default], iteration 29 completed (6764.745 ms) ====== [2025-11-13T01:47:38.894Z] ----------------------------------- [2025-11-13T01:47:38.894Z] renaissance-als_0_PASSED [2025-11-13T01:47:38.894Z] ----------------------------------- [2025-11-13T01:47:38.894Z] [2025-11-13T01:47:38.894Z] TEST TEARDOWN: [2025-11-13T01:47:38.894Z] Nothing to be done for teardown. [2025-11-13T01:47:38.894Z] renaissance-als_0 Finish Time: Thu Nov 13 01:47:37 2025 Epoch Time (ms): 1762998457889