[2026-01-10T04:55:21.790Z] Running test renaissance-als_0 ... [2026-01-10T04:55:21.790Z] =============================================== [2026-01-10T04:55:21.790Z] renaissance-als_0 Start Time: Sat Jan 10 04:55:21 2026 Epoch Time (ms): 1768020921485 [2026-01-10T04:55:21.790Z] variation: NoOptions [2026-01-10T04:55:21.790Z] JVM_OPTIONS: [2026-01-10T04:55:21.790Z] { \ [2026-01-10T04:55:21.790Z] echo ""; echo "TEST SETUP:"; \ [2026-01-10T04:55:21.790Z] echo "Nothing to be done for setup."; \ [2026-01-10T04:55:21.790Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17680209217074/renaissance-als_0"; \ [2026-01-10T04:55:21.790Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17680209217074/renaissance-als_0"; \ [2026-01-10T04:55:21.790Z] echo ""; echo "TESTING:"; \ [2026-01-10T04:55:21.790Z] "/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_17680209217074/renaissance-als_0"/als.json" als; \ [2026-01-10T04:55:21.790Z] 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_17680209217074/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-10T04:55:21.790Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-10T04:55:21.790Z] echo "Nothing to be done for teardown."; \ [2026-01-10T04:55:21.790Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17680209217074/TestTargetResult"; [2026-01-10T04:55:21.790Z] [2026-01-10T04:55:21.790Z] TEST SETUP: [2026-01-10T04:55:21.790Z] Nothing to be done for setup. [2026-01-10T04:55:21.790Z] [2026-01-10T04:55:21.790Z] TESTING: [2026-01-10T04:55:26.504Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-01-10T04:55:29.313Z] WARNING: An illegal reflective access operation has occurred [2026-01-10T04:55:29.313Z] 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_17680209217074/renaissance-als_0/harness-045522-4700541845910949594/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2026-01-10T04:55:29.313Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2026-01-10T04:55:29.313Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2026-01-10T04:55:29.313Z] WARNING: All illegal access operations will be denied in a future release [2026-01-10T04:55:29.940Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-10T04:55:29.940Z] GC before operation: completed in 100.684 ms, heap usage 414.500 MB -> 34.570 MB. [2026-01-10T04:55:49.845Z] ====== als (apache-spark) [default], iteration 0 completed (19706.810 ms) ====== [2026-01-10T04:55:49.845Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-10T04:55:50.461Z] GC before operation: completed in 225.499 ms, heap usage 507.356 MB -> 60.549 MB. [2026-01-10T04:56:04.762Z] ====== als (apache-spark) [default], iteration 1 completed (12341.757 ms) ====== [2026-01-10T04:56:04.762Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-10T04:56:04.762Z] GC before operation: completed in 201.057 ms, heap usage 146.077 MB -> 61.049 MB. [2026-01-10T04:56:15.289Z] ====== als (apache-spark) [default], iteration 2 completed (11361.003 ms) ====== [2026-01-10T04:56:15.289Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-10T04:56:15.289Z] GC before operation: completed in 172.988 ms, heap usage 153.042 MB -> 61.594 MB. [2026-01-10T04:56:27.372Z] ====== als (apache-spark) [default], iteration 3 completed (10767.094 ms) ====== [2026-01-10T04:56:27.372Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-10T04:56:27.372Z] GC before operation: completed in 195.477 ms, heap usage 253.415 MB -> 62.248 MB. [2026-01-10T04:56:37.529Z] ====== als (apache-spark) [default], iteration 4 completed (10652.904 ms) ====== [2026-01-10T04:56:37.530Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-10T04:56:37.530Z] GC before operation: completed in 175.778 ms, heap usage 283.779 MB -> 62.774 MB. [2026-01-10T04:56:49.609Z] ====== als (apache-spark) [default], iteration 5 completed (11092.353 ms) ====== [2026-01-10T04:56:49.609Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-10T04:56:49.609Z] GC before operation: completed in 189.384 ms, heap usage 85.929 MB -> 62.732 MB. [2026-01-10T04:56:59.778Z] ====== als (apache-spark) [default], iteration 6 completed (10861.120 ms) ====== [2026-01-10T04:56:59.778Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-10T04:56:59.778Z] GC before operation: completed in 180.692 ms, heap usage 97.531 MB -> 63.718 MB. [2026-01-10T04:57:14.153Z] ====== als (apache-spark) [default], iteration 7 completed (11955.527 ms) ====== [2026-01-10T04:57:14.153Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-10T04:57:14.153Z] GC before operation: completed in 188.095 ms, heap usage 270.608 MB -> 64.364 MB. [2026-01-10T04:57:24.311Z] ====== als (apache-spark) [default], iteration 8 completed (10853.158 ms) ====== [2026-01-10T04:57:24.311Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-10T04:57:24.311Z] GC before operation: completed in 184.491 ms, heap usage 245.354 MB -> 64.881 MB. [2026-01-10T04:57:34.466Z] ====== als (apache-spark) [default], iteration 9 completed (10260.150 ms) ====== [2026-01-10T04:57:34.466Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-10T04:57:34.466Z] GC before operation: completed in 157.869 ms, heap usage 237.007 MB -> 65.491 MB. [2026-01-10T04:57:44.612Z] ====== als (apache-spark) [default], iteration 10 completed (9749.279 ms) ====== [2026-01-10T04:57:44.612Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-10T04:57:44.612Z] GC before operation: completed in 158.313 ms, heap usage 149.954 MB -> 65.301 MB. [2026-01-10T04:57:55.290Z] ====== als (apache-spark) [default], iteration 11 completed (10060.992 ms) ====== [2026-01-10T04:57:55.290Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-10T04:57:55.290Z] GC before operation: completed in 245.719 ms, heap usage 100.097 MB -> 65.592 MB. [2026-01-10T04:58:05.495Z] ====== als (apache-spark) [default], iteration 12 completed (10348.737 ms) ====== [2026-01-10T04:58:05.495Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-10T04:58:05.495Z] GC before operation: completed in 168.534 ms, heap usage 272.686 MB -> 65.919 MB. [2026-01-10T04:58:17.567Z] ====== als (apache-spark) [default], iteration 13 completed (10389.030 ms) ====== [2026-01-10T04:58:17.567Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-10T04:58:17.567Z] GC before operation: completed in 130.918 ms, heap usage 263.736 MB -> 66.176 MB. [2026-01-10T04:58:27.718Z] ====== als (apache-spark) [default], iteration 14 completed (11094.388 ms) ====== [2026-01-10T04:58:27.718Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-10T04:58:27.718Z] GC before operation: completed in 150.580 ms, heap usage 235.691 MB -> 66.530 MB. [2026-01-10T04:58:37.875Z] ====== als (apache-spark) [default], iteration 15 completed (10073.697 ms) ====== [2026-01-10T04:58:37.875Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-10T04:58:37.875Z] GC before operation: completed in 124.103 ms, heap usage 231.244 MB -> 66.682 MB. [2026-01-10T04:58:48.022Z] ====== als (apache-spark) [default], iteration 16 completed (9240.728 ms) ====== [2026-01-10T04:58:48.022Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-10T04:58:48.022Z] GC before operation: completed in 280.240 ms, heap usage 248.829 MB -> 66.924 MB. [2026-01-10T04:58:58.174Z] ====== als (apache-spark) [default], iteration 17 completed (9597.314 ms) ====== [2026-01-10T04:58:58.174Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-10T04:58:58.174Z] GC before operation: completed in 160.467 ms, heap usage 114.277 MB -> 67.045 MB. [2026-01-10T04:59:08.365Z] ====== als (apache-spark) [default], iteration 18 completed (10417.871 ms) ====== [2026-01-10T04:59:08.365Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-10T04:59:08.983Z] GC before operation: completed in 136.605 ms, heap usage 252.506 MB -> 67.438 MB. [2026-01-10T04:59:19.207Z] ====== als (apache-spark) [default], iteration 19 completed (9731.466 ms) ====== [2026-01-10T04:59:19.207Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-10T04:59:19.207Z] GC before operation: completed in 210.705 ms, heap usage 233.035 MB -> 67.677 MB. [2026-01-10T04:59:29.358Z] ====== als (apache-spark) [default], iteration 20 completed (10415.039 ms) ====== [2026-01-10T04:59:29.358Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-10T04:59:29.358Z] GC before operation: completed in 151.905 ms, heap usage 133.729 MB -> 67.803 MB. [2026-01-10T04:59:39.501Z] ====== als (apache-spark) [default], iteration 21 completed (9369.982 ms) ====== [2026-01-10T04:59:39.501Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-10T04:59:39.501Z] GC before operation: completed in 188.545 ms, heap usage 158.777 MB -> 68.074 MB. [2026-01-10T04:59:49.667Z] ====== als (apache-spark) [default], iteration 22 completed (9251.850 ms) ====== [2026-01-10T04:59:49.668Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-10T04:59:49.668Z] GC before operation: completed in 228.012 ms, heap usage 135.709 MB -> 68.314 MB. [2026-01-10T04:59:59.840Z] ====== als (apache-spark) [default], iteration 23 completed (9804.101 ms) ====== [2026-01-10T04:59:59.840Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-10T04:59:59.840Z] GC before operation: completed in 156.732 ms, heap usage 232.731 MB -> 68.708 MB. [2026-01-10T05:00:10.086Z] ====== als (apache-spark) [default], iteration 24 completed (10195.352 ms) ====== [2026-01-10T05:00:10.086Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-10T05:00:10.086Z] GC before operation: completed in 107.555 ms, heap usage 213.003 MB -> 68.961 MB. [2026-01-10T05:00:20.237Z] ====== als (apache-spark) [default], iteration 25 completed (10230.512 ms) ====== [2026-01-10T05:00:20.237Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-10T05:00:20.237Z] GC before operation: completed in 140.263 ms, heap usage 247.477 MB -> 69.203 MB. [2026-01-10T05:00:30.487Z] ====== als (apache-spark) [default], iteration 26 completed (10121.768 ms) ====== [2026-01-10T05:00:30.487Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-10T05:00:30.487Z] GC before operation: completed in 191.645 ms, heap usage 158.846 MB -> 69.334 MB. [2026-01-10T05:00:43.030Z] ====== als (apache-spark) [default], iteration 27 completed (10639.105 ms) ====== [2026-01-10T05:00:43.030Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-10T05:00:43.030Z] GC before operation: completed in 145.015 ms, heap usage 232.034 MB -> 69.694 MB. [2026-01-10T05:00:53.192Z] ====== als (apache-spark) [default], iteration 28 completed (10885.956 ms) ====== [2026-01-10T05:00:53.192Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-10T05:00:53.192Z] GC before operation: completed in 155.579 ms, heap usage 251.173 MB -> 69.936 MB. [2026-01-10T05:01:03.400Z] ====== als (apache-spark) [default], iteration 29 completed (10090.047 ms) ====== [2026-01-10T05:01:04.018Z] ----------------------------------- [2026-01-10T05:01:04.018Z] renaissance-als_0_PASSED [2026-01-10T05:01:04.018Z] ----------------------------------- [2026-01-10T05:01:04.018Z] [2026-01-10T05:01:04.018Z] TEST TEARDOWN: [2026-01-10T05:01:04.018Z] Nothing to be done for teardown. [2026-01-10T05:01:04.018Z] renaissance-als_0 Finish Time: Sat Jan 10 05:01:03 2026 Epoch Time (ms): 1768021263356