[2026-01-21T07:05:41.973Z] Running test renaissance-als_0 ... [2026-01-21T07:05:41.973Z] =============================================== [2026-01-21T07:05:41.973Z] renaissance-als_0 Start Time: Wed Jan 21 07:05:41 2026 Epoch Time (ms): 1768979141748 [2026-01-21T07:05:41.973Z] variation: NoOptions [2026-01-21T07:05:41.973Z] JVM_OPTIONS: [2026-01-21T07:05:41.973Z] { \ [2026-01-21T07:05:41.973Z] echo ""; echo "TEST SETUP:"; \ [2026-01-21T07:05:41.973Z] echo "Nothing to be done for setup."; \ [2026-01-21T07:05:41.973Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17689789463679/renaissance-als_0"; \ [2026-01-21T07:05:41.973Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17689789463679/renaissance-als_0"; \ [2026-01-21T07:05:41.973Z] echo ""; echo "TESTING:"; \ [2026-01-21T07:05:41.973Z] "/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_17689789463679/renaissance-als_0"/als.json" als; \ [2026-01-21T07:05:41.973Z] 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_17689789463679/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-21T07:05:41.973Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-21T07:05:41.973Z] echo "Nothing to be done for teardown."; \ [2026-01-21T07:05:41.973Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17689789463679/TestTargetResult"; [2026-01-21T07:05:41.973Z] [2026-01-21T07:05:41.973Z] TEST SETUP: [2026-01-21T07:05:41.973Z] Nothing to be done for setup. [2026-01-21T07:05:41.973Z] [2026-01-21T07:05:41.973Z] TESTING: [2026-01-21T07:05:46.691Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-01-21T07:05:49.603Z] WARNING: An illegal reflective access operation has occurred [2026-01-21T07:05:49.603Z] 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_17689789463679/renaissance-als_0/harness-070542-10929225529776958568/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-01-21T07:05:49.603Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-01-21T07:05:49.603Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-01-21T07:05:49.603Z] WARNING: All illegal access operations will be denied in a future release [2026-01-21T07:05:50.231Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-21T07:05:50.850Z] GC before operation: completed in 93.800 ms, heap usage 348.501 MB -> 34.488 MB. [2026-01-21T07:06:14.928Z] ====== als (apache-spark) [default], iteration 0 completed (21992.103 ms) ====== [2026-01-21T07:06:14.928Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-21T07:06:14.928Z] GC before operation: completed in 307.469 ms, heap usage 339.519 MB -> 60.474 MB. [2026-01-21T07:06:29.437Z] ====== als (apache-spark) [default], iteration 1 completed (14754.124 ms) ====== [2026-01-21T07:06:29.437Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-21T07:06:29.437Z] GC before operation: completed in 149.261 ms, heap usage 139.626 MB -> 61.091 MB. [2026-01-21T07:06:43.864Z] ====== als (apache-spark) [default], iteration 2 completed (14423.396 ms) ====== [2026-01-21T07:06:43.864Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-21T07:06:43.864Z] GC before operation: completed in 154.541 ms, heap usage 259.242 MB -> 61.749 MB. [2026-01-21T07:07:01.105Z] ====== als (apache-spark) [default], iteration 3 completed (14894.550 ms) ====== [2026-01-21T07:07:01.105Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-21T07:07:01.105Z] GC before operation: completed in 203.232 ms, heap usage 123.110 MB -> 62.168 MB. [2026-01-21T07:07:15.713Z] ====== als (apache-spark) [default], iteration 4 completed (14354.070 ms) ====== [2026-01-21T07:07:15.713Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-21T07:07:15.713Z] GC before operation: completed in 123.648 ms, heap usage 229.377 MB -> 62.805 MB. [2026-01-21T07:07:30.352Z] ====== als (apache-spark) [default], iteration 5 completed (14665.450 ms) ====== [2026-01-21T07:07:30.352Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-21T07:07:30.352Z] GC before operation: completed in 167.083 ms, heap usage 253.135 MB -> 63.355 MB. [2026-01-21T07:07:44.924Z] ====== als (apache-spark) [default], iteration 6 completed (15115.590 ms) ====== [2026-01-21T07:07:44.924Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-21T07:07:44.924Z] GC before operation: completed in 219.354 ms, heap usage 169.406 MB -> 63.760 MB. [2026-01-21T07:07:59.471Z] ====== als (apache-spark) [default], iteration 7 completed (14359.083 ms) ====== [2026-01-21T07:07:59.471Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-21T07:07:59.471Z] GC before operation: completed in 199.516 ms, heap usage 266.224 MB -> 64.406 MB. [2026-01-21T07:08:14.221Z] ====== als (apache-spark) [default], iteration 8 completed (14488.342 ms) ====== [2026-01-21T07:08:14.221Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-21T07:08:14.862Z] GC before operation: completed in 166.168 ms, heap usage 162.523 MB -> 64.820 MB. [2026-01-21T07:08:29.364Z] ====== als (apache-spark) [default], iteration 9 completed (13641.348 ms) ====== [2026-01-21T07:08:29.364Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-21T07:08:29.364Z] GC before operation: completed in 155.417 ms, heap usage 172.083 MB -> 65.105 MB. [2026-01-21T07:08:41.564Z] ====== als (apache-spark) [default], iteration 10 completed (12913.240 ms) ====== [2026-01-21T07:08:41.564Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-21T07:08:42.194Z] GC before operation: completed in 154.829 ms, heap usage 375.152 MB -> 65.695 MB. [2026-01-21T07:08:56.673Z] ====== als (apache-spark) [default], iteration 11 completed (13162.055 ms) ====== [2026-01-21T07:08:56.673Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-21T07:08:56.673Z] GC before operation: completed in 178.542 ms, heap usage 317.074 MB -> 65.727 MB. [2026-01-21T07:09:08.924Z] ====== als (apache-spark) [default], iteration 12 completed (13099.755 ms) ====== [2026-01-21T07:09:08.924Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-21T07:09:08.924Z] GC before operation: completed in 208.163 ms, heap usage 166.916 MB -> 65.852 MB. [2026-01-21T07:09:26.109Z] ====== als (apache-spark) [default], iteration 13 completed (14954.613 ms) ====== [2026-01-21T07:09:26.109Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-21T07:09:26.109Z] GC before operation: completed in 189.131 ms, heap usage 229.835 MB -> 66.314 MB. [2026-01-21T07:09:40.622Z] ====== als (apache-spark) [default], iteration 14 completed (14510.838 ms) ====== [2026-01-21T07:09:40.622Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-21T07:09:40.622Z] GC before operation: completed in 146.819 ms, heap usage 212.388 MB -> 66.321 MB. [2026-01-21T07:09:55.157Z] ====== als (apache-spark) [default], iteration 15 completed (13421.929 ms) ====== [2026-01-21T07:09:55.157Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-21T07:09:55.157Z] GC before operation: completed in 147.650 ms, heap usage 273.059 MB -> 66.701 MB. [2026-01-21T07:10:07.795Z] ====== als (apache-spark) [default], iteration 16 completed (12804.657 ms) ====== [2026-01-21T07:10:07.795Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-21T07:10:07.795Z] GC before operation: completed in 203.092 ms, heap usage 270.427 MB -> 66.942 MB. [2026-01-21T07:10:22.301Z] ====== als (apache-spark) [default], iteration 17 completed (13349.614 ms) ====== [2026-01-21T07:10:22.301Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-21T07:10:22.301Z] GC before operation: completed in 204.886 ms, heap usage 115.690 MB -> 67.070 MB. [2026-01-21T07:10:34.536Z] ====== als (apache-spark) [default], iteration 18 completed (12700.664 ms) ====== [2026-01-21T07:10:34.536Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-21T07:10:34.536Z] GC before operation: completed in 158.985 ms, heap usage 162.413 MB -> 67.317 MB. [2026-01-21T07:10:46.894Z] ====== als (apache-spark) [default], iteration 19 completed (13094.191 ms) ====== [2026-01-21T07:10:46.894Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-21T07:10:47.538Z] GC before operation: completed in 173.306 ms, heap usage 248.927 MB -> 67.675 MB. [2026-01-21T07:11:02.247Z] ====== als (apache-spark) [default], iteration 20 completed (14126.871 ms) ====== [2026-01-21T07:11:02.247Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-21T07:11:02.247Z] GC before operation: completed in 229.929 ms, heap usage 263.086 MB -> 67.918 MB. [2026-01-21T07:11:19.487Z] ====== als (apache-spark) [default], iteration 21 completed (15274.572 ms) ====== [2026-01-21T07:11:19.487Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-21T07:11:19.487Z] GC before operation: completed in 182.283 ms, heap usage 287.360 MB -> 68.160 MB. [2026-01-21T07:11:34.062Z] ====== als (apache-spark) [default], iteration 22 completed (15257.379 ms) ====== [2026-01-21T07:11:34.062Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-21T07:11:34.062Z] GC before operation: completed in 157.271 ms, heap usage 101.747 MB -> 68.315 MB. [2026-01-21T07:11:49.323Z] ====== als (apache-spark) [default], iteration 23 completed (16009.920 ms) ====== [2026-01-21T07:11:49.323Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-21T07:11:49.987Z] GC before operation: completed in 185.968 ms, heap usage 210.923 MB -> 68.703 MB. [2026-01-21T07:12:04.733Z] ====== als (apache-spark) [default], iteration 24 completed (14693.617 ms) ====== [2026-01-21T07:12:04.733Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-21T07:12:04.733Z] GC before operation: completed in 191.662 ms, heap usage 119.172 MB -> 68.833 MB. [2026-01-21T07:12:21.931Z] ====== als (apache-spark) [default], iteration 25 completed (15047.181 ms) ====== [2026-01-21T07:12:21.931Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-21T07:12:21.931Z] GC before operation: completed in 215.215 ms, heap usage 256.952 MB -> 69.208 MB. [2026-01-21T07:12:36.681Z] ====== als (apache-spark) [default], iteration 26 completed (14071.129 ms) ====== [2026-01-21T07:12:36.681Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-21T07:12:36.681Z] GC before operation: completed in 174.459 ms, heap usage 147.235 MB -> 69.302 MB. [2026-01-21T07:12:51.411Z] ====== als (apache-spark) [default], iteration 27 completed (15141.366 ms) ====== [2026-01-21T07:12:51.411Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-21T07:12:51.411Z] GC before operation: completed in 191.003 ms, heap usage 141.577 MB -> 69.548 MB. [2026-01-21T07:13:06.162Z] ====== als (apache-spark) [default], iteration 28 completed (15122.866 ms) ====== [2026-01-21T07:13:06.162Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-21T07:13:06.162Z] GC before operation: completed in 192.163 ms, heap usage 280.771 MB -> 69.443 MB. [2026-01-21T07:13:20.692Z] ====== als (apache-spark) [default], iteration 29 completed (14589.845 ms) ====== [2026-01-21T07:13:21.373Z] ----------------------------------- [2026-01-21T07:13:21.373Z] renaissance-als_0_PASSED [2026-01-21T07:13:21.373Z] ----------------------------------- [2026-01-21T07:13:21.373Z] [2026-01-21T07:13:21.373Z] TEST TEARDOWN: [2026-01-21T07:13:21.373Z] Nothing to be done for teardown. [2026-01-21T07:13:21.373Z] renaissance-als_0 Finish Time: Wed Jan 21 07:13:21 2026 Epoch Time (ms): 1768979601197