[2026-01-14T22:03:14.089Z] Running test renaissance-als_0 ... [2026-01-14T22:03:14.089Z] =============================================== [2026-01-14T22:03:14.089Z] renaissance-als_0 Start Time: Wed Jan 14 22:03:13 2026 Epoch Time (ms): 1768428193505 [2026-01-14T22:03:14.089Z] variation: NoOptions [2026-01-14T22:03:14.089Z] JVM_OPTIONS: [2026-01-14T22:03:14.089Z] { \ [2026-01-14T22:03:14.089Z] echo ""; echo "TEST SETUP:"; \ [2026-01-14T22:03:14.089Z] echo "Nothing to be done for setup."; \ [2026-01-14T22:03:14.089Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17684281936813/renaissance-als_0"; \ [2026-01-14T22:03:14.089Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17684281936813/renaissance-als_0"; \ [2026-01-14T22:03:14.089Z] echo ""; echo "TESTING:"; \ [2026-01-14T22:03:14.089Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_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_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17684281936813/renaissance-als_0"/als.json" als; \ [2026-01-14T22:03:14.089Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17684281936813/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-14T22:03:14.089Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-14T22:03:14.089Z] echo "Nothing to be done for teardown."; \ [2026-01-14T22:03:14.089Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17684281936813/TestTargetResult"; [2026-01-14T22:03:14.089Z] [2026-01-14T22:03:14.089Z] TEST SETUP: [2026-01-14T22:03:14.089Z] Nothing to be done for setup. [2026-01-14T22:03:14.089Z] [2026-01-14T22:03:14.089Z] TESTING: [2026-01-14T22:03:21.612Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-01-14T22:03:24.792Z] WARNING: An illegal reflective access operation has occurred [2026-01-14T22:03:24.792Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/output_17684281936813/renaissance-als_0/harness-220314-11450287712860201941/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-01-14T22:03:24.793Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-01-14T22:03:24.793Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-01-14T22:03:24.793Z] WARNING: All illegal access operations will be denied in a future release [2026-01-14T22:03:26.154Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-14T22:03:26.801Z] GC before operation: completed in 156.959 ms, heap usage 393.502 MB -> 34.525 MB. [2026-01-14T22:03:55.143Z] ====== als (apache-spark) [default], iteration 0 completed (26250.288 ms) ====== [2026-01-14T22:03:55.143Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-14T22:03:55.143Z] GC before operation: completed in 238.326 ms, heap usage 368.221 MB -> 60.479 MB. [2026-01-14T22:04:12.834Z] ====== als (apache-spark) [default], iteration 1 completed (17286.059 ms) ====== [2026-01-14T22:04:12.834Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-14T22:04:12.834Z] GC before operation: completed in 212.322 ms, heap usage 163.936 MB -> 61.062 MB. [2026-01-14T22:04:27.510Z] ====== als (apache-spark) [default], iteration 2 completed (14318.452 ms) ====== [2026-01-14T22:04:27.510Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-14T22:04:27.510Z] GC before operation: completed in 210.344 ms, heap usage 214.371 MB -> 61.741 MB. [2026-01-14T22:04:42.186Z] ====== als (apache-spark) [default], iteration 3 completed (14242.597 ms) ====== [2026-01-14T22:04:42.186Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-14T22:04:42.186Z] GC before operation: completed in 314.873 ms, heap usage 387.575 MB -> 62.390 MB. [2026-01-14T22:04:56.796Z] ====== als (apache-spark) [default], iteration 4 completed (14075.497 ms) ====== [2026-01-14T22:04:56.796Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-14T22:04:56.796Z] GC before operation: completed in 213.993 ms, heap usage 254.890 MB -> 62.785 MB. [2026-01-14T22:05:11.531Z] ====== als (apache-spark) [default], iteration 5 completed (13535.263 ms) ====== [2026-01-14T22:05:11.531Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-14T22:05:11.531Z] GC before operation: completed in 239.199 ms, heap usage 212.293 MB -> 63.338 MB. [2026-01-14T22:05:26.144Z] ====== als (apache-spark) [default], iteration 6 completed (14095.731 ms) ====== [2026-01-14T22:05:26.144Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-14T22:05:26.144Z] GC before operation: completed in 232.789 ms, heap usage 79.959 MB -> 63.258 MB. [2026-01-14T22:05:38.452Z] ====== als (apache-spark) [default], iteration 7 completed (12090.333 ms) ====== [2026-01-14T22:05:38.452Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-14T22:05:38.452Z] GC before operation: completed in 200.108 ms, heap usage 270.592 MB -> 64.369 MB. [2026-01-14T22:05:50.719Z] ====== als (apache-spark) [default], iteration 8 completed (12861.423 ms) ====== [2026-01-14T22:05:50.719Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-14T22:05:51.357Z] GC before operation: completed in 262.197 ms, heap usage 207.560 MB -> 64.889 MB. [2026-01-14T22:06:03.576Z] ====== als (apache-spark) [default], iteration 9 completed (11940.710 ms) ====== [2026-01-14T22:06:03.576Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-14T22:06:03.576Z] GC before operation: completed in 232.015 ms, heap usage 219.502 MB -> 65.814 MB. [2026-01-14T22:06:15.869Z] ====== als (apache-spark) [default], iteration 10 completed (11467.436 ms) ====== [2026-01-14T22:06:15.869Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-14T22:06:15.869Z] GC before operation: completed in 227.331 ms, heap usage 253.163 MB -> 65.307 MB. [2026-01-14T22:06:28.403Z] ====== als (apache-spark) [default], iteration 11 completed (11292.036 ms) ====== [2026-01-14T22:06:28.403Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-14T22:06:28.403Z] GC before operation: completed in 288.870 ms, heap usage 280.542 MB -> 65.686 MB. [2026-01-14T22:06:40.665Z] ====== als (apache-spark) [default], iteration 12 completed (12093.092 ms) ====== [2026-01-14T22:06:40.665Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-14T22:06:40.665Z] GC before operation: completed in 228.226 ms, heap usage 98.935 MB -> 65.820 MB. [2026-01-14T22:06:52.870Z] ====== als (apache-spark) [default], iteration 13 completed (12471.275 ms) ====== [2026-01-14T22:06:52.870Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-14T22:06:52.870Z] GC before operation: completed in 219.504 ms, heap usage 211.989 MB -> 66.164 MB. [2026-01-14T22:07:06.122Z] ====== als (apache-spark) [default], iteration 14 completed (12599.619 ms) ====== [2026-01-14T22:07:06.122Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-14T22:07:06.122Z] GC before operation: completed in 185.573 ms, heap usage 240.674 MB -> 66.412 MB. [2026-01-14T22:07:18.335Z] ====== als (apache-spark) [default], iteration 15 completed (12381.778 ms) ====== [2026-01-14T22:07:18.335Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-14T22:07:18.957Z] GC before operation: completed in 183.517 ms, heap usage 238.071 MB -> 66.676 MB. [2026-01-14T22:07:33.427Z] ====== als (apache-spark) [default], iteration 16 completed (13409.172 ms) ====== [2026-01-14T22:07:33.427Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-14T22:07:33.427Z] GC before operation: completed in 203.459 ms, heap usage 304.069 MB -> 66.928 MB. [2026-01-14T22:07:45.709Z] ====== als (apache-spark) [default], iteration 17 completed (12965.241 ms) ====== [2026-01-14T22:07:45.709Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-14T22:07:46.336Z] GC before operation: completed in 212.837 ms, heap usage 258.160 MB -> 67.168 MB. [2026-01-14T22:07:58.541Z] ====== als (apache-spark) [default], iteration 18 completed (12168.109 ms) ====== [2026-01-14T22:07:58.541Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-14T22:07:58.541Z] GC before operation: completed in 228.659 ms, heap usage 164.103 MB -> 67.301 MB. [2026-01-14T22:08:13.046Z] ====== als (apache-spark) [default], iteration 19 completed (12217.684 ms) ====== [2026-01-14T22:08:13.046Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-14T22:08:13.046Z] GC before operation: completed in 296.752 ms, heap usage 254.940 MB -> 67.663 MB. [2026-01-14T22:08:25.449Z] ====== als (apache-spark) [default], iteration 20 completed (13303.069 ms) ====== [2026-01-14T22:08:25.449Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-14T22:08:25.449Z] GC before operation: completed in 229.650 ms, heap usage 241.931 MB -> 67.907 MB. [2026-01-14T22:08:39.845Z] ====== als (apache-spark) [default], iteration 21 completed (12272.462 ms) ====== [2026-01-14T22:08:39.845Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-14T22:08:39.845Z] GC before operation: completed in 179.436 ms, heap usage 240.402 MB -> 68.152 MB. [2026-01-14T22:08:51.991Z] ====== als (apache-spark) [default], iteration 22 completed (12260.322 ms) ====== [2026-01-14T22:08:51.992Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-14T22:08:51.992Z] GC before operation: completed in 218.429 ms, heap usage 247.711 MB -> 68.427 MB. [2026-01-14T22:09:04.131Z] ====== als (apache-spark) [default], iteration 23 completed (12515.689 ms) ====== [2026-01-14T22:09:04.131Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-14T22:09:04.131Z] GC before operation: completed in 255.413 ms, heap usage 219.220 MB -> 68.699 MB. [2026-01-14T22:09:16.313Z] ====== als (apache-spark) [default], iteration 24 completed (11970.965 ms) ====== [2026-01-14T22:09:16.313Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-14T22:09:16.313Z] GC before operation: completed in 226.766 ms, heap usage 214.805 MB -> 68.942 MB. [2026-01-14T22:09:28.451Z] ====== als (apache-spark) [default], iteration 25 completed (11584.005 ms) ====== [2026-01-14T22:09:28.452Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-14T22:09:28.452Z] GC before operation: completed in 203.459 ms, heap usage 147.041 MB -> 69.074 MB. [2026-01-14T22:09:42.876Z] ====== als (apache-spark) [default], iteration 26 completed (12711.132 ms) ====== [2026-01-14T22:09:42.876Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-14T22:09:42.876Z] GC before operation: completed in 179.278 ms, heap usage 249.123 MB -> 69.436 MB. [2026-01-14T22:09:55.053Z] ====== als (apache-spark) [default], iteration 27 completed (13374.692 ms) ====== [2026-01-14T22:09:55.053Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-14T22:09:55.685Z] GC before operation: completed in 226.628 ms, heap usage 212.539 MB -> 69.712 MB. [2026-01-14T22:10:10.210Z] ====== als (apache-spark) [default], iteration 28 completed (12807.994 ms) ====== [2026-01-14T22:10:10.210Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-14T22:10:10.210Z] GC before operation: completed in 211.562 ms, heap usage 199.782 MB -> 69.805 MB. [2026-01-14T22:10:22.516Z] ====== als (apache-spark) [default], iteration 29 completed (13284.429 ms) ====== [2026-01-14T22:10:23.156Z] ----------------------------------- [2026-01-14T22:10:23.156Z] renaissance-als_0_PASSED [2026-01-14T22:10:23.156Z] ----------------------------------- [2026-01-14T22:10:23.156Z] [2026-01-14T22:10:23.156Z] TEST TEARDOWN: [2026-01-14T22:10:23.156Z] Nothing to be done for teardown. [2026-01-14T22:10:23.156Z] renaissance-als_0 Finish Time: Wed Jan 14 22:10:22 2026 Epoch Time (ms): 1768428622683