[2025-08-20T20:14:15.069Z] Running test renaissance-als_0 ... [2025-08-20T20:14:15.069Z] =============================================== [2025-08-20T20:14:15.069Z] renaissance-als_0 Start Time: Wed Aug 20 20:14:14 2025 Epoch Time (ms): 1755720854730 [2025-08-20T20:14:15.069Z] variation: NoOptions [2025-08-20T20:14:15.069Z] JVM_OPTIONS: [2025-08-20T20:14:15.069Z] { \ [2025-08-20T20:14:15.069Z] echo ""; echo "TEST SETUP:"; \ [2025-08-20T20:14:15.069Z] echo "Nothing to be done for setup."; \ [2025-08-20T20:14:15.069Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17557207599869/renaissance-als_0"; \ [2025-08-20T20:14:15.069Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17557207599869/renaissance-als_0"; \ [2025-08-20T20:14:15.069Z] echo ""; echo "TESTING:"; \ [2025-08-20T20:14:15.069Z] "/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_17557207599869/renaissance-als_0"/als.json" als; \ [2025-08-20T20:14:15.069Z] 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_17557207599869/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-20T20:14:15.069Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-20T20:14:15.069Z] echo "Nothing to be done for teardown."; \ [2025-08-20T20:14:15.069Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17557207599869/TestTargetResult"; [2025-08-20T20:14:15.069Z] [2025-08-20T20:14:15.069Z] TEST SETUP: [2025-08-20T20:14:15.069Z] Nothing to be done for setup. [2025-08-20T20:14:15.069Z] [2025-08-20T20:14:15.069Z] TESTING: [2025-08-20T20:14:23.362Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 24) threads. [2025-08-20T20:14:27.862Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-08-20T20:14:27.862Z] GC before operation: completed in 70.014 ms, heap usage 251.530 MB -> 34.700 MB. [2025-08-20T20:14:41.706Z] ====== als (apache-spark) [default], iteration 0 completed (12717.513 ms) ====== [2025-08-20T20:14:41.706Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-08-20T20:14:41.706Z] GC before operation: completed in 114.322 ms, heap usage 302.692 MB -> 61.715 MB. [2025-08-20T20:14:46.224Z] ====== als (apache-spark) [default], iteration 1 completed (4821.253 ms) ====== [2025-08-20T20:14:46.224Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-08-20T20:14:46.224Z] GC before operation: completed in 111.582 ms, heap usage 787.785 MB -> 63.300 MB. [2025-08-20T20:14:50.733Z] ====== als (apache-spark) [default], iteration 2 completed (4674.064 ms) ====== [2025-08-20T20:14:50.733Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-08-20T20:14:50.733Z] GC before operation: completed in 118.320 ms, heap usage 776.028 MB -> 63.987 MB. [2025-08-20T20:14:56.348Z] ====== als (apache-spark) [default], iteration 3 completed (4681.205 ms) ====== [2025-08-20T20:14:56.348Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-08-20T20:14:56.348Z] GC before operation: completed in 108.821 ms, heap usage 282.269 MB -> 64.951 MB. [2025-08-20T20:15:00.812Z] ====== als (apache-spark) [default], iteration 4 completed (4400.915 ms) ====== [2025-08-20T20:15:00.812Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-08-20T20:15:00.812Z] GC before operation: completed in 115.187 ms, heap usage 1.421 GB -> 65.697 MB. [2025-08-20T20:15:05.341Z] ====== als (apache-spark) [default], iteration 5 completed (4346.008 ms) ====== [2025-08-20T20:15:05.342Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-08-20T20:15:05.342Z] GC before operation: completed in 126.104 ms, heap usage 748.457 MB -> 65.628 MB. [2025-08-20T20:15:09.907Z] ====== als (apache-spark) [default], iteration 6 completed (4289.126 ms) ====== [2025-08-20T20:15:09.907Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-08-20T20:15:09.907Z] GC before operation: completed in 116.343 ms, heap usage 404.615 MB -> 65.702 MB. [2025-08-20T20:15:14.555Z] ====== als (apache-spark) [default], iteration 7 completed (4327.290 ms) ====== [2025-08-20T20:15:14.555Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-08-20T20:15:14.555Z] GC before operation: completed in 112.921 ms, heap usage 460.361 MB -> 66.307 MB. [2025-08-20T20:15:19.152Z] ====== als (apache-spark) [default], iteration 8 completed (4465.088 ms) ====== [2025-08-20T20:15:19.152Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-08-20T20:15:19.152Z] GC before operation: completed in 127.245 ms, heap usage 622.508 MB -> 66.958 MB. [2025-08-20T20:15:23.734Z] ====== als (apache-spark) [default], iteration 9 completed (4385.151 ms) ====== [2025-08-20T20:15:23.734Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-08-20T20:15:23.734Z] GC before operation: completed in 124.802 ms, heap usage 391.774 MB -> 66.913 MB. [2025-08-20T20:15:28.396Z] ====== als (apache-spark) [default], iteration 10 completed (4419.983 ms) ====== [2025-08-20T20:15:28.396Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-08-20T20:15:29.172Z] GC before operation: completed in 136.551 ms, heap usage 395.499 MB -> 67.306 MB. [2025-08-20T20:15:33.751Z] ====== als (apache-spark) [default], iteration 11 completed (4290.223 ms) ====== [2025-08-20T20:15:33.751Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-08-20T20:15:33.751Z] GC before operation: completed in 130.226 ms, heap usage 1.350 GB -> 68.851 MB. [2025-08-20T20:15:38.273Z] ====== als (apache-spark) [default], iteration 12 completed (4495.462 ms) ====== [2025-08-20T20:15:38.273Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-08-20T20:15:38.273Z] GC before operation: completed in 125.872 ms, heap usage 281.034 MB -> 67.511 MB. [2025-08-20T20:15:42.780Z] ====== als (apache-spark) [default], iteration 13 completed (4428.174 ms) ====== [2025-08-20T20:15:42.781Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-08-20T20:15:42.781Z] GC before operation: completed in 105.398 ms, heap usage 377.624 MB -> 67.748 MB. [2025-08-20T20:15:47.315Z] ====== als (apache-spark) [default], iteration 14 completed (4446.056 ms) ====== [2025-08-20T20:15:47.315Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-08-20T20:15:47.315Z] GC before operation: completed in 89.764 ms, heap usage 251.137 MB -> 67.969 MB. [2025-08-20T20:15:51.878Z] ====== als (apache-spark) [default], iteration 15 completed (4436.638 ms) ====== [2025-08-20T20:15:51.878Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-08-20T20:15:51.878Z] GC before operation: completed in 106.297 ms, heap usage 935.269 MB -> 69.524 MB. [2025-08-20T20:15:57.530Z] ====== als (apache-spark) [default], iteration 16 completed (4339.066 ms) ====== [2025-08-20T20:15:57.530Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-08-20T20:15:57.530Z] GC before operation: completed in 102.835 ms, heap usage 902.946 MB -> 69.532 MB. [2025-08-20T20:16:02.024Z] ====== als (apache-spark) [default], iteration 17 completed (4421.330 ms) ====== [2025-08-20T20:16:02.024Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-08-20T20:16:02.024Z] GC before operation: completed in 94.095 ms, heap usage 1.187 GB -> 70.147 MB. [2025-08-20T20:16:06.511Z] ====== als (apache-spark) [default], iteration 18 completed (4392.334 ms) ====== [2025-08-20T20:16:06.511Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-08-20T20:16:06.511Z] GC before operation: completed in 103.690 ms, heap usage 404.278 MB -> 69.417 MB. [2025-08-20T20:16:10.993Z] ====== als (apache-spark) [default], iteration 19 completed (4315.371 ms) ====== [2025-08-20T20:16:10.993Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-08-20T20:16:10.993Z] GC before operation: completed in 99.650 ms, heap usage 598.404 MB -> 69.932 MB. [2025-08-20T20:16:15.470Z] ====== als (apache-spark) [default], iteration 20 completed (4417.891 ms) ====== [2025-08-20T20:16:15.470Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-08-20T20:16:15.470Z] GC before operation: completed in 108.762 ms, heap usage 700.064 MB -> 70.238 MB. [2025-08-20T20:16:19.948Z] ====== als (apache-spark) [default], iteration 21 completed (4325.915 ms) ====== [2025-08-20T20:16:19.948Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-08-20T20:16:19.948Z] GC before operation: completed in 111.634 ms, heap usage 1.460 GB -> 71.521 MB. [2025-08-20T20:16:24.433Z] ====== als (apache-spark) [default], iteration 22 completed (4382.342 ms) ====== [2025-08-20T20:16:24.433Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-08-20T20:16:24.433Z] GC before operation: completed in 111.014 ms, heap usage 2.963 GB -> 72.404 MB. [2025-08-20T20:16:30.059Z] ====== als (apache-spark) [default], iteration 23 completed (4475.243 ms) ====== [2025-08-20T20:16:30.059Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-08-20T20:16:30.059Z] GC before operation: completed in 91.004 ms, heap usage 428.812 MB -> 70.785 MB. [2025-08-20T20:16:33.507Z] ====== als (apache-spark) [default], iteration 24 completed (4239.262 ms) ====== [2025-08-20T20:16:33.507Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-08-20T20:16:34.278Z] GC before operation: completed in 98.056 ms, heap usage 217.639 MB -> 70.772 MB. [2025-08-20T20:16:38.871Z] ====== als (apache-spark) [default], iteration 25 completed (4360.608 ms) ====== [2025-08-20T20:16:38.871Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-08-20T20:16:38.871Z] GC before operation: completed in 94.399 ms, heap usage 231.271 MB -> 71.032 MB. [2025-08-20T20:16:43.337Z] ====== als (apache-spark) [default], iteration 26 completed (4293.748 ms) ====== [2025-08-20T20:16:43.337Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-08-20T20:16:43.337Z] GC before operation: completed in 101.519 ms, heap usage 1.055 GB -> 72.465 MB. [2025-08-20T20:16:47.796Z] ====== als (apache-spark) [default], iteration 27 completed (4276.175 ms) ====== [2025-08-20T20:16:47.796Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-08-20T20:16:47.796Z] GC before operation: completed in 110.031 ms, heap usage 221.549 MB -> 71.240 MB. [2025-08-20T20:16:52.281Z] ====== als (apache-spark) [default], iteration 28 completed (4253.522 ms) ====== [2025-08-20T20:16:52.281Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-08-20T20:16:52.281Z] GC before operation: completed in 102.032 ms, heap usage 1.015 GB -> 72.427 MB. [2025-08-20T20:16:56.759Z] ====== als (apache-spark) [default], iteration 29 completed (4379.903 ms) ====== [2025-08-20T20:16:57.533Z] ----------------------------------- [2025-08-20T20:16:57.533Z] renaissance-als_0_PASSED [2025-08-20T20:16:57.533Z] ----------------------------------- [2025-08-20T20:16:57.533Z] [2025-08-20T20:16:57.533Z] TEST TEARDOWN: [2025-08-20T20:16:57.533Z] Nothing to be done for teardown. [2025-08-20T20:16:57.533Z] renaissance-als_0 Finish Time: Wed Aug 20 20:16:56 2025 Epoch Time (ms): 1755721016812