[2026-01-14T21:49:30.705Z] Running test renaissance-als_0 ... [2026-01-14T21:49:30.705Z] =============================================== [2026-01-14T21:49:30.705Z] renaissance-als_0 Start Time: Wed Jan 14 21:49:29 2026 Epoch Time (ms): 1768427369908 [2026-01-14T21:49:30.705Z] variation: NoOptions [2026-01-14T21:49:30.705Z] JVM_OPTIONS: [2026-01-14T21:49:30.705Z] { \ [2026-01-14T21:49:30.705Z] echo ""; echo "TEST SETUP:"; \ [2026-01-14T21:49:30.705Z] echo "Nothing to be done for setup."; \ [2026-01-14T21:49:30.705Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17684272575766/renaissance-als_0"; \ [2026-01-14T21:49:30.705Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17684272575766/renaissance-als_0"; \ [2026-01-14T21:49:30.705Z] echo ""; echo "TESTING:"; \ [2026-01-14T21:49:30.705Z] "/home/jenkins/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17684272575766/renaissance-als_0"/als.json" als; \ [2026-01-14T21:49:30.705Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17684272575766/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-14T21:49:30.705Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-14T21:49:30.705Z] echo "Nothing to be done for teardown."; \ [2026-01-14T21:49:30.705Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17684272575766/TestTargetResult"; [2026-01-14T21:49:30.705Z] [2026-01-14T21:49:30.705Z] TEST SETUP: [2026-01-14T21:49:30.705Z] Nothing to be done for setup. [2026-01-14T21:49:30.705Z] [2026-01-14T21:49:30.705Z] TESTING: [2026-01-14T21:49:39.011Z] NOTE: 'als' benchmark uses Spark local executor with 12 (out of 24) threads. [2026-01-14T21:49:44.629Z] WARNING: An illegal reflective access operation has occurred [2026-01-14T21:49:44.629Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/output_17684272575766/renaissance-als_0/harness-214930-16187839624178118787/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-01-14T21:49:44.629Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-01-14T21:49:44.629Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-01-14T21:49:44.629Z] WARNING: All illegal access operations will be denied in a future release [2026-01-14T21:49:46.235Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-14T21:49:46.235Z] GC before operation: completed in 162.463 ms, heap usage 191.681 MB -> 35.369 MB. [2026-01-14T21:50:02.396Z] ====== als (apache-spark) [default], iteration 0 completed (14402.145 ms) ====== [2026-01-14T21:50:02.396Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-14T21:50:02.396Z] GC before operation: completed in 122.968 ms, heap usage 847.373 MB -> 62.196 MB. [2026-01-14T21:50:08.032Z] ====== als (apache-spark) [default], iteration 1 completed (6042.259 ms) ====== [2026-01-14T21:50:08.032Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-14T21:50:08.032Z] GC before operation: completed in 143.305 ms, heap usage 1.638 GB -> 63.825 MB. [2026-01-14T21:50:14.925Z] ====== als (apache-spark) [default], iteration 2 completed (5852.528 ms) ====== [2026-01-14T21:50:14.925Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-14T21:50:14.925Z] GC before operation: completed in 130.103 ms, heap usage 645.339 MB -> 63.418 MB. [2026-01-14T21:50:20.715Z] ====== als (apache-spark) [default], iteration 3 completed (5574.469 ms) ====== [2026-01-14T21:50:20.715Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-14T21:50:20.715Z] GC before operation: completed in 129.601 ms, heap usage 463.587 MB -> 63.548 MB. [2026-01-14T21:50:26.349Z] ====== als (apache-spark) [default], iteration 4 completed (5630.095 ms) ====== [2026-01-14T21:50:26.349Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-14T21:50:26.349Z] GC before operation: completed in 137.682 ms, heap usage 861.304 MB -> 64.586 MB. [2026-01-14T21:50:31.971Z] ====== als (apache-spark) [default], iteration 5 completed (5550.067 ms) ====== [2026-01-14T21:50:31.971Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-14T21:50:31.971Z] GC before operation: completed in 134.768 ms, heap usage 504.359 MB -> 64.816 MB. [2026-01-14T21:50:37.601Z] ====== als (apache-spark) [default], iteration 6 completed (5435.955 ms) ====== [2026-01-14T21:50:37.601Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-14T21:50:38.377Z] GC before operation: completed in 149.869 ms, heap usage 443.212 MB -> 65.281 MB. [2026-01-14T21:50:44.007Z] ====== als (apache-spark) [default], iteration 7 completed (5283.231 ms) ====== [2026-01-14T21:50:44.007Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-14T21:50:44.007Z] GC before operation: completed in 146.516 ms, heap usage 1.354 GB -> 66.726 MB. [2026-01-14T21:50:49.638Z] ====== als (apache-spark) [default], iteration 8 completed (5437.193 ms) ====== [2026-01-14T21:50:49.638Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-14T21:50:49.638Z] GC before operation: completed in 159.606 ms, heap usage 1021.184 MB -> 66.942 MB. [2026-01-14T21:50:55.274Z] ====== als (apache-spark) [default], iteration 9 completed (5788.530 ms) ====== [2026-01-14T21:50:55.275Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-14T21:50:56.048Z] GC before operation: completed in 146.553 ms, heap usage 666.146 MB -> 66.872 MB. [2026-01-14T21:51:01.689Z] ====== als (apache-spark) [default], iteration 10 completed (5481.473 ms) ====== [2026-01-14T21:51:01.689Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-14T21:51:01.689Z] GC before operation: completed in 157.721 ms, heap usage 1.147 GB -> 67.610 MB. [2026-01-14T21:51:08.114Z] ====== als (apache-spark) [default], iteration 11 completed (5431.230 ms) ====== [2026-01-14T21:51:08.114Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-14T21:51:08.114Z] GC before operation: completed in 214.487 ms, heap usage 467.651 MB -> 67.105 MB. [2026-01-14T21:51:13.334Z] ====== als (apache-spark) [default], iteration 12 completed (5575.803 ms) ====== [2026-01-14T21:51:13.334Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-14T21:51:13.334Z] GC before operation: completed in 155.836 ms, heap usage 925.157 MB -> 67.837 MB. [2026-01-14T21:51:19.005Z] ====== als (apache-spark) [default], iteration 13 completed (5458.644 ms) ====== [2026-01-14T21:51:19.005Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-14T21:51:19.005Z] GC before operation: completed in 135.638 ms, heap usage 131.627 MB -> 67.509 MB. [2026-01-14T21:51:24.666Z] ====== als (apache-spark) [default], iteration 14 completed (5367.806 ms) ====== [2026-01-14T21:51:24.666Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-14T21:51:24.666Z] GC before operation: completed in 165.715 ms, heap usage 1014.625 MB -> 68.425 MB. [2026-01-14T21:51:30.317Z] ====== als (apache-spark) [default], iteration 15 completed (5439.295 ms) ====== [2026-01-14T21:51:30.317Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-14T21:51:31.090Z] GC before operation: completed in 207.932 ms, heap usage 895.713 MB -> 68.596 MB. [2026-01-14T21:51:36.727Z] ====== als (apache-spark) [default], iteration 16 completed (5443.025 ms) ====== [2026-01-14T21:51:36.727Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-14T21:51:36.727Z] GC before operation: completed in 160.500 ms, heap usage 1.321 GB -> 69.311 MB. [2026-01-14T21:51:42.431Z] ====== als (apache-spark) [default], iteration 17 completed (5482.618 ms) ====== [2026-01-14T21:51:42.431Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-14T21:51:42.431Z] GC before operation: completed in 183.541 ms, heap usage 447.065 MB -> 68.627 MB. [2026-01-14T21:51:48.056Z] ====== als (apache-spark) [default], iteration 18 completed (5320.755 ms) ====== [2026-01-14T21:51:48.056Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-14T21:51:48.056Z] GC before operation: completed in 194.891 ms, heap usage 845.228 MB -> 69.264 MB. [2026-01-14T21:51:54.956Z] ====== als (apache-spark) [default], iteration 19 completed (5568.713 ms) ====== [2026-01-14T21:51:54.956Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-14T21:51:54.956Z] GC before operation: completed in 143.487 ms, heap usage 147.647 MB -> 69.233 MB. [2026-01-14T21:52:00.598Z] ====== als (apache-spark) [default], iteration 20 completed (5461.966 ms) ====== [2026-01-14T21:52:00.598Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-14T21:52:00.598Z] GC before operation: completed in 154.641 ms, heap usage 711.693 MB -> 69.740 MB. [2026-01-14T21:52:06.220Z] ====== als (apache-spark) [default], iteration 21 completed (5465.179 ms) ====== [2026-01-14T21:52:06.220Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-14T21:52:06.220Z] GC before operation: completed in 154.231 ms, heap usage 451.503 MB -> 69.541 MB. [2026-01-14T21:52:11.838Z] ====== als (apache-spark) [default], iteration 22 completed (5432.891 ms) ====== [2026-01-14T21:52:11.838Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-14T21:52:11.838Z] GC before operation: completed in 148.589 ms, heap usage 202.422 MB -> 69.557 MB. [2026-01-14T21:52:17.487Z] ====== als (apache-spark) [default], iteration 23 completed (5350.692 ms) ====== [2026-01-14T21:52:17.487Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-14T21:52:17.487Z] GC before operation: completed in 157.781 ms, heap usage 249.760 MB -> 69.945 MB. [2026-01-14T21:52:23.121Z] ====== als (apache-spark) [default], iteration 24 completed (5284.487 ms) ====== [2026-01-14T21:52:23.121Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-14T21:52:23.121Z] GC before operation: completed in 159.135 ms, heap usage 446.000 MB -> 70.359 MB. [2026-01-14T21:52:28.876Z] ====== als (apache-spark) [default], iteration 25 completed (5297.064 ms) ====== [2026-01-14T21:52:28.876Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-14T21:52:28.876Z] GC before operation: completed in 162.388 ms, heap usage 351.837 MB -> 70.513 MB. [2026-01-14T21:52:34.498Z] ====== als (apache-spark) [default], iteration 26 completed (5283.205 ms) ====== [2026-01-14T21:52:34.498Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-14T21:52:34.498Z] GC before operation: completed in 226.587 ms, heap usage 1.352 GB -> 71.927 MB. [2026-01-14T21:52:40.302Z] ====== als (apache-spark) [default], iteration 27 completed (5226.867 ms) ====== [2026-01-14T21:52:40.302Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-14T21:52:40.302Z] GC before operation: completed in 167.281 ms, heap usage 898.165 MB -> 71.734 MB. [2026-01-14T21:52:45.986Z] ====== als (apache-spark) [default], iteration 28 completed (5318.276 ms) ====== [2026-01-14T21:52:45.986Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-14T21:52:45.986Z] GC before operation: completed in 153.859 ms, heap usage 504.159 MB -> 71.586 MB. [2026-01-14T21:52:51.624Z] ====== als (apache-spark) [default], iteration 29 completed (5444.002 ms) ====== [2026-01-14T21:52:52.397Z] ----------------------------------- [2026-01-14T21:52:52.397Z] renaissance-als_0_PASSED [2026-01-14T21:52:52.397Z] ----------------------------------- [2026-01-14T21:52:52.397Z] [2026-01-14T21:52:52.397Z] TEST TEARDOWN: [2026-01-14T21:52:52.397Z] Nothing to be done for teardown. [2026-01-14T21:52:52.397Z] renaissance-als_0 Finish Time: Wed Jan 14 21:52:51 2026 Epoch Time (ms): 1768427571681