[2025-06-18T23:27:28.179Z] Running test renaissance-als_0 ... [2025-06-18T23:27:28.179Z] =============================================== [2025-06-18T23:27:28.179Z] renaissance-als_0 Start Time: Wed Jun 18 23:27:27 2025 Epoch Time (ms): 1750289247750 [2025-06-18T23:27:28.179Z] variation: NoOptions [2025-06-18T23:27:28.179Z] JVM_OPTIONS: [2025-06-18T23:27:28.179Z] { \ [2025-06-18T23:27:28.179Z] echo ""; echo "TEST SETUP:"; \ [2025-06-18T23:27:28.179Z] echo "Nothing to be done for setup."; \ [2025-06-18T23:27:28.179Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17502892034604/renaissance-als_0"; \ [2025-06-18T23:27:28.179Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17502892034604/renaissance-als_0"; \ [2025-06-18T23:27:28.179Z] echo ""; echo "TESTING:"; \ [2025-06-18T23:27:28.179Z] "/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_17502892034604/renaissance-als_0"/als.json" als; \ [2025-06-18T23:27:28.179Z] 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_17502892034604/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-18T23:27:28.179Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-18T23:27:28.179Z] echo "Nothing to be done for teardown."; \ [2025-06-18T23:27:28.179Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17502892034604/TestTargetResult"; [2025-06-18T23:27:28.179Z] [2025-06-18T23:27:28.179Z] TEST SETUP: [2025-06-18T23:27:28.179Z] Nothing to be done for setup. [2025-06-18T23:27:28.179Z] [2025-06-18T23:27:28.179Z] TESTING: [2025-06-18T23:27:34.807Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-18T23:27:37.790Z] WARNING: An illegal reflective access operation has occurred [2025-06-18T23:27:37.790Z] 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_17502892034604/renaissance-als_0/harness-232728-13677745115832078757/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-06-18T23:27:37.790Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-06-18T23:27:37.790Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-06-18T23:27:37.790Z] WARNING: All illegal access operations will be denied in a future release [2025-06-18T23:27:39.726Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-18T23:27:39.726Z] GC before operation: completed in 51.496 ms, heap usage 366.025 MB -> 34.496 MB. [2025-06-18T23:28:00.999Z] ====== als (apache-spark) [default], iteration 0 completed (19222.811 ms) ====== [2025-06-18T23:28:00.999Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-18T23:28:00.999Z] GC before operation: completed in 115.355 ms, heap usage 354.456 MB -> 60.599 MB. [2025-06-18T23:28:09.065Z] ====== als (apache-spark) [default], iteration 1 completed (8623.914 ms) ====== [2025-06-18T23:28:09.065Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-18T23:28:09.065Z] GC before operation: completed in 105.128 ms, heap usage 426.835 MB -> 61.474 MB. [2025-06-18T23:28:17.139Z] ====== als (apache-spark) [default], iteration 2 completed (8200.622 ms) ====== [2025-06-18T23:28:17.139Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-18T23:28:17.139Z] GC before operation: completed in 115.725 ms, heap usage 256.372 MB -> 61.936 MB. [2025-06-18T23:28:26.244Z] ====== als (apache-spark) [default], iteration 3 completed (8530.238 ms) ====== [2025-06-18T23:28:26.244Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-18T23:28:26.244Z] GC before operation: completed in 131.458 ms, heap usage 173.883 MB -> 62.265 MB. [2025-06-18T23:28:34.346Z] ====== als (apache-spark) [default], iteration 4 completed (8308.653 ms) ====== [2025-06-18T23:28:34.346Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-18T23:28:34.346Z] GC before operation: completed in 112.693 ms, heap usage 196.744 MB -> 62.784 MB. [2025-06-18T23:28:44.053Z] ====== als (apache-spark) [default], iteration 5 completed (8701.077 ms) ====== [2025-06-18T23:28:44.053Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-18T23:28:44.053Z] GC before operation: completed in 116.202 ms, heap usage 108.368 MB -> 63.299 MB. [2025-06-18T23:28:52.120Z] ====== als (apache-spark) [default], iteration 6 completed (8360.972 ms) ====== [2025-06-18T23:28:52.120Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-18T23:28:52.120Z] GC before operation: completed in 107.580 ms, heap usage 261.493 MB -> 63.945 MB. [2025-06-18T23:29:01.918Z] ====== als (apache-spark) [default], iteration 7 completed (8308.395 ms) ====== [2025-06-18T23:29:01.918Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-18T23:29:01.918Z] GC before operation: completed in 112.663 ms, heap usage 175.086 MB -> 64.393 MB. [2025-06-18T23:29:10.031Z] ====== als (apache-spark) [default], iteration 8 completed (7853.073 ms) ====== [2025-06-18T23:29:10.031Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-18T23:29:10.031Z] GC before operation: completed in 106.529 ms, heap usage 229.687 MB -> 64.998 MB. [2025-06-18T23:29:18.168Z] ====== als (apache-spark) [default], iteration 9 completed (7841.100 ms) ====== [2025-06-18T23:29:18.168Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-18T23:29:18.168Z] GC before operation: completed in 109.949 ms, heap usage 273.967 MB -> 65.395 MB. [2025-06-18T23:29:26.245Z] ====== als (apache-spark) [default], iteration 10 completed (7902.170 ms) ====== [2025-06-18T23:29:26.245Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-18T23:29:26.245Z] GC before operation: completed in 123.634 ms, heap usage 240.454 MB -> 65.581 MB. [2025-06-18T23:29:33.912Z] ====== als (apache-spark) [default], iteration 11 completed (8243.750 ms) ====== [2025-06-18T23:29:33.912Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-18T23:29:34.854Z] GC before operation: completed in 121.177 ms, heap usage 223.799 MB -> 65.668 MB. [2025-06-18T23:29:42.925Z] ====== als (apache-spark) [default], iteration 12 completed (8221.598 ms) ====== [2025-06-18T23:29:42.925Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-18T23:29:42.925Z] GC before operation: completed in 120.426 ms, heap usage 388.532 MB -> 66.140 MB. [2025-06-18T23:29:50.992Z] ====== als (apache-spark) [default], iteration 13 completed (7820.726 ms) ====== [2025-06-18T23:29:50.992Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-18T23:29:50.992Z] GC before operation: completed in 131.507 ms, heap usage 669.732 MB -> 66.760 MB. [2025-06-18T23:29:59.184Z] ====== als (apache-spark) [default], iteration 14 completed (7918.346 ms) ====== [2025-06-18T23:29:59.184Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-18T23:29:59.184Z] GC before operation: completed in 123.817 ms, heap usage 273.327 MB -> 66.512 MB. [2025-06-18T23:30:08.864Z] ====== als (apache-spark) [default], iteration 15 completed (8232.217 ms) ====== [2025-06-18T23:30:08.864Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-18T23:30:08.864Z] GC before operation: completed in 119.892 ms, heap usage 279.161 MB -> 66.773 MB. [2025-06-18T23:30:16.971Z] ====== als (apache-spark) [default], iteration 16 completed (7937.258 ms) ====== [2025-06-18T23:30:16.971Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-18T23:30:16.971Z] GC before operation: completed in 136.264 ms, heap usage 334.153 MB -> 67.129 MB. [2025-06-18T23:30:25.039Z] ====== als (apache-spark) [default], iteration 17 completed (8122.176 ms) ====== [2025-06-18T23:30:25.039Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-18T23:30:25.039Z] GC before operation: completed in 126.112 ms, heap usage 140.749 MB -> 67.176 MB. [2025-06-18T23:30:34.721Z] ====== als (apache-spark) [default], iteration 18 completed (8394.799 ms) ====== [2025-06-18T23:30:34.721Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-18T23:30:34.721Z] GC before operation: completed in 133.830 ms, heap usage 174.409 MB -> 67.425 MB. [2025-06-18T23:30:42.820Z] ====== als (apache-spark) [default], iteration 19 completed (8270.832 ms) ====== [2025-06-18T23:30:42.820Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-18T23:30:42.820Z] GC before operation: completed in 123.437 ms, heap usage 120.275 MB -> 67.667 MB. [2025-06-18T23:30:50.387Z] ====== als (apache-spark) [default], iteration 20 completed (7740.474 ms) ====== [2025-06-18T23:30:50.387Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-18T23:30:50.387Z] GC before operation: completed in 117.917 ms, heap usage 273.211 MB -> 68.041 MB. [2025-06-18T23:30:58.504Z] ====== als (apache-spark) [default], iteration 21 completed (7968.672 ms) ====== [2025-06-18T23:30:58.504Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-18T23:30:58.504Z] GC before operation: completed in 113.332 ms, heap usage 431.370 MB -> 68.369 MB. [2025-06-18T23:31:06.579Z] ====== als (apache-spark) [default], iteration 22 completed (7928.399 ms) ====== [2025-06-18T23:31:06.579Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-18T23:31:07.521Z] GC before operation: completed in 135.819 ms, heap usage 250.650 MB -> 68.536 MB. [2025-06-18T23:31:15.637Z] ====== als (apache-spark) [default], iteration 23 completed (8294.969 ms) ====== [2025-06-18T23:31:15.637Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-18T23:31:15.637Z] GC before operation: completed in 122.999 ms, heap usage 382.883 MB -> 68.894 MB. [2025-06-18T23:31:23.733Z] ====== als (apache-spark) [default], iteration 24 completed (7998.241 ms) ====== [2025-06-18T23:31:23.733Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-18T23:31:23.733Z] GC before operation: completed in 119.507 ms, heap usage 299.885 MB -> 69.027 MB. [2025-06-18T23:31:33.402Z] ====== als (apache-spark) [default], iteration 25 completed (8031.267 ms) ====== [2025-06-18T23:31:33.402Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-18T23:31:33.402Z] GC before operation: completed in 113.560 ms, heap usage 164.231 MB -> 69.156 MB. [2025-06-18T23:31:40.026Z] ====== als (apache-spark) [default], iteration 26 completed (7739.645 ms) ====== [2025-06-18T23:31:40.026Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-18T23:31:40.966Z] GC before operation: completed in 117.591 ms, heap usage 384.837 MB -> 69.634 MB. [2025-06-18T23:31:49.226Z] ====== als (apache-spark) [default], iteration 27 completed (7920.682 ms) ====== [2025-06-18T23:31:49.226Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-18T23:31:49.226Z] GC before operation: completed in 110.491 ms, heap usage 379.848 MB -> 69.878 MB. [2025-06-18T23:31:56.640Z] ====== als (apache-spark) [default], iteration 28 completed (7604.074 ms) ====== [2025-06-18T23:31:56.640Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-18T23:31:56.640Z] GC before operation: completed in 116.397 ms, heap usage 217.868 MB -> 69.561 MB. [2025-06-18T23:32:03.990Z] ====== als (apache-spark) [default], iteration 29 completed (7495.640 ms) ====== [2025-06-18T23:32:04.933Z] ----------------------------------- [2025-06-18T23:32:04.933Z] renaissance-als_0_PASSED [2025-06-18T23:32:04.933Z] ----------------------------------- [2025-06-18T23:32:04.933Z] [2025-06-18T23:32:04.933Z] TEST TEARDOWN: [2025-06-18T23:32:04.933Z] Nothing to be done for teardown. [2025-06-18T23:32:04.933Z] renaissance-als_0 Finish Time: Wed Jun 18 23:32:04 2025 Epoch Time (ms): 1750289524320