[2026-04-25T14:35:58.958Z] Running test renaissance-als_0 ... [2026-04-25T14:35:58.958Z] =============================================== [2026-04-25T14:35:58.958Z] renaissance-als_0 Start Time: Sat Apr 25 14:35:58 2026 Epoch Time (ms): 1777127758185 [2026-04-25T14:35:58.958Z] variation: NoOptions [2026-04-25T14:35:58.958Z] JVM_OPTIONS: [2026-04-25T14:35:58.958Z] { \ [2026-04-25T14:35:58.958Z] echo ""; echo "TEST SETUP:"; \ [2026-04-25T14:35:58.958Z] echo "Nothing to be done for setup."; \ [2026-04-25T14:35:58.958Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1777127575267/renaissance-als_0"; \ [2026-04-25T14:35:58.958Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1777127575267/renaissance-als_0"; \ [2026-04-25T14:35:58.958Z] echo ""; echo "TESTING:"; \ [2026-04-25T14:35:58.958Z] "/home/jenkins/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1777127575267/renaissance-als_0"/als.json" als; \ [2026-04-25T14:35:58.958Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1777127575267/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-25T14:35:58.958Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-25T14:35:58.958Z] echo "Nothing to be done for teardown."; \ [2026-04-25T14:35:58.958Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1777127575267/TestTargetResult"; [2026-04-25T14:35:58.958Z] [2026-04-25T14:35:58.958Z] TEST SETUP: [2026-04-25T14:35:58.958Z] Nothing to be done for setup. [2026-04-25T14:35:58.958Z] [2026-04-25T14:35:58.958Z] TESTING: [2026-04-25T14:36:05.623Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-04-25T14:36:08.643Z] WARNING: An illegal reflective access operation has occurred [2026-04-25T14:36:08.643Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/output_1777127575267/renaissance-als_0/harness-143558-2986508003234784998/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-04-25T14:36:08.643Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-04-25T14:36:08.643Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-04-25T14:36:08.643Z] WARNING: All illegal access operations will be denied in a future release [2026-04-25T14:36:09.591Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-04-25T14:36:09.591Z] GC before operation: completed in 65.072 ms, heap usage 277.500 MB -> 34.468 MB. [2026-04-25T14:36:30.757Z] ====== als (apache-spark) [default], iteration 0 completed (20500.687 ms) ====== [2026-04-25T14:36:30.757Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-04-25T14:36:31.703Z] GC before operation: completed in 110.425 ms, heap usage 262.369 MB -> 60.474 MB. [2026-04-25T14:36:42.171Z] ====== als (apache-spark) [default], iteration 1 completed (9727.120 ms) ====== [2026-04-25T14:36:42.171Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-04-25T14:36:42.171Z] GC before operation: completed in 106.803 ms, heap usage 245.405 MB -> 61.290 MB. [2026-04-25T14:36:50.285Z] ====== als (apache-spark) [default], iteration 2 completed (8711.491 ms) ====== [2026-04-25T14:36:50.285Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-04-25T14:36:50.285Z] GC before operation: completed in 110.059 ms, heap usage 283.365 MB -> 61.838 MB. [2026-04-25T14:36:59.984Z] ====== als (apache-spark) [default], iteration 3 completed (8241.189 ms) ====== [2026-04-25T14:36:59.984Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-04-25T14:36:59.984Z] GC before operation: completed in 119.564 ms, heap usage 383.661 MB -> 62.568 MB. [2026-04-25T14:37:08.094Z] ====== als (apache-spark) [default], iteration 4 completed (8067.078 ms) ====== [2026-04-25T14:37:08.094Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-04-25T14:37:08.094Z] GC before operation: completed in 114.876 ms, heap usage 201.366 MB -> 63.011 MB. [2026-04-25T14:37:16.980Z] ====== als (apache-spark) [default], iteration 5 completed (8311.595 ms) ====== [2026-04-25T14:37:16.980Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-04-25T14:37:16.980Z] GC before operation: completed in 123.318 ms, heap usage 232.427 MB -> 63.407 MB. [2026-04-25T14:37:25.105Z] ====== als (apache-spark) [default], iteration 6 completed (8428.700 ms) ====== [2026-04-25T14:37:25.105Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-04-25T14:37:25.105Z] GC before operation: completed in 124.537 ms, heap usage 278.205 MB -> 63.934 MB. [2026-04-25T14:37:34.817Z] ====== als (apache-spark) [default], iteration 7 completed (8154.923 ms) ====== [2026-04-25T14:37:34.817Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-04-25T14:37:34.817Z] GC before operation: completed in 131.125 ms, heap usage 189.562 MB -> 64.362 MB. [2026-04-25T14:37:42.938Z] ====== als (apache-spark) [default], iteration 8 completed (8561.190 ms) ====== [2026-04-25T14:37:42.938Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-04-25T14:37:42.938Z] GC before operation: completed in 117.292 ms, heap usage 253.559 MB -> 64.996 MB. [2026-04-25T14:37:51.050Z] ====== als (apache-spark) [default], iteration 9 completed (7993.137 ms) ====== [2026-04-25T14:37:51.050Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-04-25T14:37:51.050Z] GC before operation: completed in 131.324 ms, heap usage 337.797 MB -> 65.389 MB. [2026-04-25T14:37:59.463Z] ====== als (apache-spark) [default], iteration 10 completed (8040.414 ms) ====== [2026-04-25T14:37:59.463Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-04-25T14:37:59.463Z] GC before operation: completed in 126.758 ms, heap usage 350.766 MB -> 65.650 MB. [2026-04-25T14:38:08.265Z] ====== als (apache-spark) [default], iteration 11 completed (8407.785 ms) ====== [2026-04-25T14:38:08.265Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-04-25T14:38:08.265Z] GC before operation: completed in 148.292 ms, heap usage 318.563 MB -> 65.800 MB. [2026-04-25T14:38:16.488Z] ====== als (apache-spark) [default], iteration 12 completed (7907.788 ms) ====== [2026-04-25T14:38:16.488Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-04-25T14:38:16.488Z] GC before operation: completed in 124.396 ms, heap usage 305.171 MB -> 66.187 MB. [2026-04-25T14:38:26.195Z] ====== als (apache-spark) [default], iteration 13 completed (8388.074 ms) ====== [2026-04-25T14:38:26.195Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-04-25T14:38:26.195Z] GC before operation: completed in 123.228 ms, heap usage 331.039 MB -> 66.386 MB. [2026-04-25T14:38:34.316Z] ====== als (apache-spark) [default], iteration 14 completed (7702.970 ms) ====== [2026-04-25T14:38:34.316Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-04-25T14:38:34.316Z] GC before operation: completed in 124.621 ms, heap usage 160.914 MB -> 66.406 MB. [2026-04-25T14:38:42.442Z] ====== als (apache-spark) [default], iteration 15 completed (8435.436 ms) ====== [2026-04-25T14:38:42.442Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-04-25T14:38:42.442Z] GC before operation: completed in 118.510 ms, heap usage 265.433 MB -> 66.788 MB. [2026-04-25T14:38:51.271Z] ====== als (apache-spark) [default], iteration 16 completed (7835.818 ms) ====== [2026-04-25T14:38:51.271Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-04-25T14:38:51.271Z] GC before operation: completed in 115.239 ms, heap usage 172.650 MB -> 66.911 MB. [2026-04-25T14:38:57.992Z] ====== als (apache-spark) [default], iteration 17 completed (7608.117 ms) ====== [2026-04-25T14:38:57.992Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-04-25T14:38:58.951Z] GC before operation: completed in 119.681 ms, heap usage 281.990 MB -> 67.301 MB. [2026-04-25T14:39:07.078Z] ====== als (apache-spark) [default], iteration 18 completed (8153.019 ms) ====== [2026-04-25T14:39:07.078Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-04-25T14:39:07.078Z] GC before operation: completed in 125.389 ms, heap usage 257.596 MB -> 67.548 MB. [2026-04-25T14:39:15.344Z] ====== als (apache-spark) [default], iteration 19 completed (8352.691 ms) ====== [2026-04-25T14:39:15.344Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-04-25T14:39:16.292Z] GC before operation: completed in 116.542 ms, heap usage 230.096 MB -> 67.792 MB. [2026-04-25T14:39:24.418Z] ====== als (apache-spark) [default], iteration 20 completed (7797.901 ms) ====== [2026-04-25T14:39:24.418Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-04-25T14:39:24.418Z] GC before operation: completed in 120.760 ms, heap usage 275.910 MB -> 68.050 MB. [2026-04-25T14:39:32.541Z] ====== als (apache-spark) [default], iteration 21 completed (8466.198 ms) ====== [2026-04-25T14:39:32.541Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-04-25T14:39:32.541Z] GC before operation: completed in 125.251 ms, heap usage 224.321 MB -> 68.290 MB. [2026-04-25T14:39:40.653Z] ====== als (apache-spark) [default], iteration 22 completed (7872.546 ms) ====== [2026-04-25T14:39:40.653Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-04-25T14:39:41.604Z] GC before operation: completed in 116.772 ms, heap usage 334.977 MB -> 68.676 MB. [2026-04-25T14:39:49.725Z] ====== als (apache-spark) [default], iteration 23 completed (8480.535 ms) ====== [2026-04-25T14:39:49.726Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-04-25T14:39:49.726Z] GC before operation: completed in 118.636 ms, heap usage 105.218 MB -> 68.701 MB. [2026-04-25T14:39:57.844Z] ====== als (apache-spark) [default], iteration 24 completed (7845.287 ms) ====== [2026-04-25T14:39:57.844Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-04-25T14:39:57.844Z] GC before operation: completed in 123.007 ms, heap usage 287.530 MB -> 69.065 MB. [2026-04-25T14:40:07.537Z] ====== als (apache-spark) [default], iteration 25 completed (8213.066 ms) ====== [2026-04-25T14:40:07.537Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-04-25T14:40:07.537Z] GC before operation: completed in 113.184 ms, heap usage 411.255 MB -> 69.411 MB. [2026-04-25T14:40:15.712Z] ====== als (apache-spark) [default], iteration 26 completed (7940.860 ms) ====== [2026-04-25T14:40:15.712Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-04-25T14:40:15.712Z] GC before operation: completed in 123.704 ms, heap usage 91.956 MB -> 69.430 MB. [2026-04-25T14:40:24.021Z] ====== als (apache-spark) [default], iteration 27 completed (8034.125 ms) ====== [2026-04-25T14:40:24.021Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-04-25T14:40:24.021Z] GC before operation: completed in 121.134 ms, heap usage 247.792 MB -> 69.804 MB. [2026-04-25T14:40:32.123Z] ====== als (apache-spark) [default], iteration 28 completed (8006.786 ms) ====== [2026-04-25T14:40:32.123Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-04-25T14:40:32.123Z] GC before operation: completed in 116.184 ms, heap usage 101.332 MB -> 69.929 MB. [2026-04-25T14:40:40.227Z] ====== als (apache-spark) [default], iteration 29 completed (7635.770 ms) ====== [2026-04-25T14:40:40.227Z] ----------------------------------- [2026-04-25T14:40:40.227Z] renaissance-als_0_PASSED [2026-04-25T14:40:40.227Z] ----------------------------------- [2026-04-25T14:40:40.227Z] [2026-04-25T14:40:40.227Z] TEST TEARDOWN: [2026-04-25T14:40:40.227Z] Nothing to be done for teardown. [2026-04-25T14:40:40.227Z] renaissance-als_0 Finish Time: Sat Apr 25 14:40:39 2026 Epoch Time (ms): 1777128039634