[2025-11-12T23:14:03.044Z] Running test renaissance-als_0 ... [2025-11-12T23:14:03.044Z] =============================================== [2025-11-12T23:14:03.044Z] renaissance-als_0 Start Time: Wed Nov 12 18:14:02 2025 Epoch Time (ms): 1762989242870 [2025-11-12T23:14:03.044Z] variation: NoOptions [2025-11-12T23:14:03.044Z] JVM_OPTIONS: [2025-11-12T23:14:03.044Z] { \ [2025-11-12T23:14:03.044Z] echo ""; echo "TEST SETUP:"; \ [2025-11-12T23:14:03.044Z] echo "Nothing to be done for setup."; \ [2025-11-12T23:14:03.044Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17629890168349/renaissance-als_0"; \ [2025-11-12T23:14:03.044Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17629890168349/renaissance-als_0"; \ [2025-11-12T23:14:03.044Z] echo ""; echo "TESTING:"; \ [2025-11-12T23:14:03.044Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17629890168349/renaissance-als_0"/als.json" als; \ [2025-11-12T23:14:03.044Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17629890168349/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-12T23:14:03.044Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-12T23:14:03.044Z] echo "Nothing to be done for teardown."; \ [2025-11-12T23:14:03.044Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17629890168349/TestTargetResult"; [2025-11-12T23:14:03.044Z] [2025-11-12T23:14:03.044Z] TEST SETUP: [2025-11-12T23:14:03.044Z] Nothing to be done for setup. [2025-11-12T23:14:03.044Z] [2025-11-12T23:14:03.044Z] TESTING: [2025-11-12T23:14:07.855Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-11-12T23:14:11.795Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-12T23:14:11.795Z] GC before operation: completed in 69.880 ms, heap usage 52.918 MB -> 34.958 MB. [2025-11-12T23:14:40.068Z] ====== als (apache-spark) [default], iteration 0 completed (24207.780 ms) ====== [2025-11-12T23:14:40.068Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-12T23:14:40.068Z] GC before operation: completed in 122.209 ms, heap usage 167.656 MB -> 61.893 MB. [2025-11-12T23:14:52.692Z] ====== als (apache-spark) [default], iteration 1 completed (14981.625 ms) ====== [2025-11-12T23:14:52.692Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-12T23:14:52.692Z] GC before operation: completed in 192.598 ms, heap usage 103.884 MB -> 62.618 MB. [2025-11-12T23:15:07.602Z] ====== als (apache-spark) [default], iteration 2 completed (12730.605 ms) ====== [2025-11-12T23:15:07.602Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-12T23:15:07.602Z] GC before operation: completed in 110.121 ms, heap usage 261.944 MB -> 63.307 MB. [2025-11-12T23:15:19.514Z] ====== als (apache-spark) [default], iteration 3 completed (11838.399 ms) ====== [2025-11-12T23:15:19.514Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-12T23:15:19.514Z] GC before operation: completed in 123.455 ms, heap usage 616.160 MB -> 64.374 MB. [2025-11-12T23:15:30.125Z] ====== als (apache-spark) [default], iteration 4 completed (11006.702 ms) ====== [2025-11-12T23:15:30.125Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-12T23:15:30.125Z] GC before operation: completed in 133.983 ms, heap usage 622.141 MB -> 64.899 MB. [2025-11-12T23:15:42.596Z] ====== als (apache-spark) [default], iteration 5 completed (11141.987 ms) ====== [2025-11-12T23:15:42.596Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-12T23:15:42.596Z] GC before operation: completed in 104.642 ms, heap usage 250.162 MB -> 65.120 MB. [2025-11-12T23:15:53.073Z] ====== als (apache-spark) [default], iteration 6 completed (11361.624 ms) ====== [2025-11-12T23:15:53.073Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-12T23:15:53.073Z] GC before operation: completed in 120.268 ms, heap usage 609.071 MB -> 66.053 MB. [2025-11-12T23:16:03.742Z] ====== als (apache-spark) [default], iteration 7 completed (10860.227 ms) ====== [2025-11-12T23:16:03.742Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-12T23:16:04.378Z] GC before operation: completed in 108.867 ms, heap usage 563.393 MB -> 66.697 MB. [2025-11-12T23:16:15.087Z] ====== als (apache-spark) [default], iteration 8 completed (10851.466 ms) ====== [2025-11-12T23:16:15.087Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-12T23:16:15.087Z] GC before operation: completed in 111.558 ms, heap usage 156.827 MB -> 66.007 MB. [2025-11-12T23:16:27.561Z] ====== als (apache-spark) [default], iteration 9 completed (11465.986 ms) ====== [2025-11-12T23:16:27.561Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-12T23:16:27.561Z] GC before operation: completed in 103.449 ms, heap usage 112.894 MB -> 66.804 MB. [2025-11-12T23:16:38.496Z] ====== als (apache-spark) [default], iteration 10 completed (11213.335 ms) ====== [2025-11-12T23:16:38.496Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-12T23:16:38.496Z] GC before operation: completed in 136.884 ms, heap usage 286.520 MB -> 67.133 MB. [2025-11-12T23:16:51.062Z] ====== als (apache-spark) [default], iteration 11 completed (10823.089 ms) ====== [2025-11-12T23:16:51.062Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-12T23:16:51.062Z] GC before operation: completed in 89.839 ms, heap usage 120.065 MB -> 67.297 MB. [2025-11-12T23:17:01.615Z] ====== als (apache-spark) [default], iteration 12 completed (10637.656 ms) ====== [2025-11-12T23:17:01.615Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-12T23:17:01.615Z] GC before operation: completed in 111.975 ms, heap usage 547.109 MB -> 68.013 MB. [2025-11-12T23:17:12.144Z] ====== als (apache-spark) [default], iteration 13 completed (10271.129 ms) ====== [2025-11-12T23:17:12.144Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-12T23:17:12.144Z] GC before operation: completed in 128.291 ms, heap usage 110.693 MB -> 67.890 MB. [2025-11-12T23:17:21.332Z] ====== als (apache-spark) [default], iteration 14 completed (10027.860 ms) ====== [2025-11-12T23:17:21.332Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-12T23:17:21.332Z] GC before operation: completed in 108.888 ms, heap usage 300.482 MB -> 68.209 MB. [2025-11-12T23:17:33.745Z] ====== als (apache-spark) [default], iteration 15 completed (10406.123 ms) ====== [2025-11-12T23:17:33.745Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-12T23:17:33.745Z] GC before operation: completed in 96.392 ms, heap usage 370.182 MB -> 68.567 MB. [2025-11-12T23:17:42.581Z] ====== als (apache-spark) [default], iteration 16 completed (9999.922 ms) ====== [2025-11-12T23:17:42.581Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-12T23:17:42.581Z] GC before operation: completed in 153.339 ms, heap usage 124.875 MB -> 68.566 MB. [2025-11-12T23:17:53.449Z] ====== als (apache-spark) [default], iteration 17 completed (9772.739 ms) ====== [2025-11-12T23:17:53.449Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-12T23:17:53.449Z] GC before operation: completed in 140.915 ms, heap usage 114.875 MB -> 68.371 MB. [2025-11-12T23:18:03.823Z] ====== als (apache-spark) [default], iteration 18 completed (10465.515 ms) ====== [2025-11-12T23:18:03.823Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-12T23:18:03.823Z] GC before operation: completed in 99.282 ms, heap usage 175.087 MB -> 69.038 MB. [2025-11-12T23:18:14.845Z] ====== als (apache-spark) [default], iteration 19 completed (10732.881 ms) ====== [2025-11-12T23:18:14.845Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-12T23:18:14.845Z] GC before operation: completed in 120.414 ms, heap usage 119.410 MB -> 69.219 MB. [2025-11-12T23:18:25.254Z] ====== als (apache-spark) [default], iteration 20 completed (10238.229 ms) ====== [2025-11-12T23:18:25.254Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-12T23:18:25.254Z] GC before operation: completed in 115.751 ms, heap usage 253.684 MB -> 69.812 MB. [2025-11-12T23:18:37.869Z] ====== als (apache-spark) [default], iteration 21 completed (11178.002 ms) ====== [2025-11-12T23:18:37.869Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-12T23:18:37.869Z] GC before operation: completed in 185.902 ms, heap usage 465.197 MB -> 70.390 MB. [2025-11-12T23:18:48.564Z] ====== als (apache-spark) [default], iteration 22 completed (11249.315 ms) ====== [2025-11-12T23:18:48.564Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-12T23:18:48.564Z] GC before operation: completed in 134.443 ms, heap usage 477.035 MB -> 70.612 MB. [2025-11-12T23:18:59.416Z] ====== als (apache-spark) [default], iteration 23 completed (11045.928 ms) ====== [2025-11-12T23:18:59.416Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-12T23:18:59.416Z] GC before operation: completed in 122.690 ms, heap usage 231.096 MB -> 70.486 MB. [2025-11-12T23:19:10.388Z] ====== als (apache-spark) [default], iteration 24 completed (10374.962 ms) ====== [2025-11-12T23:19:10.388Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-12T23:19:10.388Z] GC before operation: completed in 102.270 ms, heap usage 129.695 MB -> 70.724 MB. [2025-11-12T23:19:20.664Z] ====== als (apache-spark) [default], iteration 25 completed (10077.819 ms) ====== [2025-11-12T23:19:20.664Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-12T23:19:20.664Z] GC before operation: completed in 137.999 ms, heap usage 140.394 MB -> 70.952 MB. [2025-11-12T23:19:31.178Z] ====== als (apache-spark) [default], iteration 26 completed (9621.787 ms) ====== [2025-11-12T23:19:31.178Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-12T23:19:31.178Z] GC before operation: completed in 134.670 ms, heap usage 239.309 MB -> 71.432 MB. [2025-11-12T23:19:41.676Z] ====== als (apache-spark) [default], iteration 27 completed (10577.815 ms) ====== [2025-11-12T23:19:41.676Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-12T23:19:41.676Z] GC before operation: completed in 103.058 ms, heap usage 199.098 MB -> 71.602 MB. [2025-11-12T23:19:56.618Z] ====== als (apache-spark) [default], iteration 28 completed (13854.519 ms) ====== [2025-11-12T23:19:56.618Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-12T23:19:56.618Z] GC before operation: completed in 108.067 ms, heap usage 168.893 MB -> 71.393 MB. [2025-11-12T23:20:07.470Z] ====== als (apache-spark) [default], iteration 29 completed (10590.891 ms) ====== [2025-11-12T23:20:07.470Z] ----------------------------------- [2025-11-12T23:20:07.470Z] renaissance-als_0_PASSED [2025-11-12T23:20:07.470Z] ----------------------------------- [2025-11-12T23:20:07.470Z] [2025-11-12T23:20:07.470Z] TEST TEARDOWN: [2025-11-12T23:20:07.470Z] Nothing to be done for teardown. [2025-11-12T23:20:07.470Z] renaissance-als_0 Finish Time: Wed Nov 12 18:20:06 2025 Epoch Time (ms): 1762989606430