[2025-10-02T03:36:11.988Z] Running test renaissance-als_0 ... [2025-10-02T03:36:11.988Z] =============================================== [2025-10-02T03:36:11.988Z] renaissance-als_0 Start Time: Thu Oct 2 03:36:11 2025 Epoch Time (ms): 1759376171534 [2025-10-02T03:36:11.988Z] variation: NoOptions [2025-10-02T03:36:11.988Z] JVM_OPTIONS: [2025-10-02T03:36:11.988Z] { \ [2025-10-02T03:36:11.988Z] echo ""; echo "TEST SETUP:"; \ [2025-10-02T03:36:11.988Z] echo "Nothing to be done for setup."; \ [2025-10-02T03:36:11.988Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17593759068396/renaissance-als_0"; \ [2025-10-02T03:36:11.988Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17593759068396/renaissance-als_0"; \ [2025-10-02T03:36:11.988Z] echo ""; echo "TESTING:"; \ [2025-10-02T03:36:11.988Z] "/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_17593759068396/renaissance-als_0"/als.json" als; \ [2025-10-02T03:36:11.989Z] 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_17593759068396/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-02T03:36:11.989Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-02T03:36:11.989Z] echo "Nothing to be done for teardown."; \ [2025-10-02T03:36:11.989Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17593759068396/TestTargetResult"; [2025-10-02T03:36:11.989Z] [2025-10-02T03:36:11.989Z] TEST SETUP: [2025-10-02T03:36:11.989Z] Nothing to be done for setup. [2025-10-02T03:36:11.989Z] [2025-10-02T03:36:11.989Z] TESTING: [2025-10-02T03:36:18.425Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-10-02T03:36:22.220Z] WARNING: An illegal reflective access operation has occurred [2025-10-02T03:36:22.220Z] 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_17593759068396/renaissance-als_0/harness-033612-13437877409882243231/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-10-02T03:36:22.220Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-10-02T03:36:22.220Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-10-02T03:36:22.220Z] WARNING: All illegal access operations will be denied in a future release [2025-10-02T03:36:23.572Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-10-02T03:36:23.572Z] GC before operation: completed in 188.562 ms, heap usage 354.002 MB -> 34.517 MB. [2025-10-02T03:37:02.769Z] ====== als (apache-spark) [default], iteration 0 completed (35913.412 ms) ====== [2025-10-02T03:37:02.769Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-10-02T03:37:02.769Z] GC before operation: completed in 226.304 ms, heap usage 188.142 MB -> 60.240 MB. [2025-10-02T03:37:26.845Z] ====== als (apache-spark) [default], iteration 1 completed (23355.391 ms) ====== [2025-10-02T03:37:26.845Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-10-02T03:37:26.845Z] GC before operation: completed in 371.511 ms, heap usage 186.892 MB -> 61.059 MB. [2025-10-02T03:37:51.078Z] ====== als (apache-spark) [default], iteration 2 completed (22282.201 ms) ====== [2025-10-02T03:37:51.078Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-10-02T03:37:51.078Z] GC before operation: completed in 380.335 ms, heap usage 110.706 MB -> 61.596 MB. [2025-10-02T03:38:15.172Z] ====== als (apache-spark) [default], iteration 3 completed (23496.163 ms) ====== [2025-10-02T03:38:15.172Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-10-02T03:38:15.172Z] GC before operation: completed in 515.943 ms, heap usage 453.234 MB -> 62.603 MB. [2025-10-02T03:38:35.556Z] ====== als (apache-spark) [default], iteration 4 completed (21798.470 ms) ====== [2025-10-02T03:38:35.556Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-10-02T03:38:35.556Z] GC before operation: completed in 324.304 ms, heap usage 154.598 MB -> 62.650 MB. [2025-10-02T03:38:59.740Z] ====== als (apache-spark) [default], iteration 5 completed (21659.536 ms) ====== [2025-10-02T03:38:59.740Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-10-02T03:38:59.740Z] GC before operation: completed in 232.975 ms, heap usage 257.202 MB -> 63.306 MB. [2025-10-02T03:39:20.366Z] ====== als (apache-spark) [default], iteration 6 completed (20241.867 ms) ====== [2025-10-02T03:39:20.367Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-10-02T03:39:20.367Z] GC before operation: completed in 432.570 ms, heap usage 278.135 MB -> 63.842 MB. [2025-10-02T03:39:40.619Z] ====== als (apache-spark) [default], iteration 7 completed (18704.324 ms) ====== [2025-10-02T03:39:40.619Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-10-02T03:39:40.619Z] GC before operation: completed in 331.295 ms, heap usage 164.407 MB -> 64.255 MB. [2025-10-02T03:39:57.705Z] ====== als (apache-spark) [default], iteration 8 completed (17738.864 ms) ====== [2025-10-02T03:39:57.705Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-10-02T03:39:57.705Z] GC before operation: completed in 367.017 ms, heap usage 138.034 MB -> 64.984 MB. [2025-10-02T03:40:21.536Z] ====== als (apache-spark) [default], iteration 9 completed (22501.058 ms) ====== [2025-10-02T03:40:21.537Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-10-02T03:40:21.537Z] GC before operation: completed in 315.608 ms, heap usage 158.443 MB -> 64.870 MB. [2025-10-02T03:40:45.675Z] ====== als (apache-spark) [default], iteration 10 completed (21772.713 ms) ====== [2025-10-02T03:40:45.675Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-10-02T03:40:45.675Z] GC before operation: completed in 291.811 ms, heap usage 81.331 MB -> 65.295 MB. [2025-10-02T03:41:03.293Z] ====== als (apache-spark) [default], iteration 11 completed (20025.823 ms) ====== [2025-10-02T03:41:03.294Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-10-02T03:41:03.973Z] GC before operation: completed in 437.044 ms, heap usage 211.850 MB -> 65.672 MB. [2025-10-02T03:41:24.188Z] ====== als (apache-spark) [default], iteration 12 completed (19209.597 ms) ====== [2025-10-02T03:41:24.188Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-10-02T03:41:24.188Z] GC before operation: completed in 312.864 ms, heap usage 158.402 MB -> 65.802 MB. [2025-10-02T03:41:45.112Z] ====== als (apache-spark) [default], iteration 13 completed (19880.512 ms) ====== [2025-10-02T03:41:45.112Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-10-02T03:41:45.112Z] GC before operation: completed in 283.448 ms, heap usage 115.726 MB -> 66.045 MB. [2025-10-02T03:42:05.451Z] ====== als (apache-spark) [default], iteration 14 completed (19988.066 ms) ====== [2025-10-02T03:42:05.451Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-10-02T03:42:05.451Z] GC before operation: completed in 258.771 ms, heap usage 265.815 MB -> 66.407 MB. [2025-10-02T03:42:25.585Z] ====== als (apache-spark) [default], iteration 15 completed (19568.363 ms) ====== [2025-10-02T03:42:25.585Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-10-02T03:42:25.585Z] GC before operation: completed in 925.377 ms, heap usage 302.229 MB -> 66.701 MB. [2025-10-02T03:42:45.935Z] ====== als (apache-spark) [default], iteration 16 completed (19624.213 ms) ====== [2025-10-02T03:42:45.935Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-10-02T03:42:45.935Z] GC before operation: completed in 382.103 ms, heap usage 168.378 MB -> 66.794 MB. [2025-10-02T03:43:07.574Z] ====== als (apache-spark) [default], iteration 17 completed (20905.903 ms) ====== [2025-10-02T03:43:07.574Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-10-02T03:43:08.277Z] GC before operation: completed in 327.664 ms, heap usage 198.585 MB -> 67.029 MB. [2025-10-02T03:43:29.059Z] ====== als (apache-spark) [default], iteration 18 completed (20602.609 ms) ====== [2025-10-02T03:43:29.059Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-10-02T03:43:29.059Z] GC before operation: completed in 296.637 ms, heap usage 163.181 MB -> 67.280 MB. [2025-10-02T03:43:53.081Z] ====== als (apache-spark) [default], iteration 19 completed (20951.042 ms) ====== [2025-10-02T03:43:53.081Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-10-02T03:43:53.081Z] GC before operation: completed in 372.703 ms, heap usage 108.699 MB -> 67.524 MB. [2025-10-02T03:44:17.421Z] ====== als (apache-spark) [default], iteration 20 completed (23752.496 ms) ====== [2025-10-02T03:44:17.422Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-10-02T03:44:17.422Z] GC before operation: completed in 411.460 ms, heap usage 118.525 MB -> 67.876 MB. [2025-10-02T03:44:41.436Z] ====== als (apache-spark) [default], iteration 21 completed (23280.347 ms) ====== [2025-10-02T03:44:41.436Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-10-02T03:44:41.436Z] GC before operation: completed in 390.337 ms, heap usage 228.497 MB -> 68.129 MB. [2025-10-02T03:45:05.638Z] ====== als (apache-spark) [default], iteration 22 completed (23851.750 ms) ====== [2025-10-02T03:45:05.638Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-10-02T03:45:05.638Z] GC before operation: completed in 221.388 ms, heap usage 267.235 MB -> 68.381 MB. [2025-10-02T03:45:31.165Z] ====== als (apache-spark) [default], iteration 23 completed (23069.769 ms) ====== [2025-10-02T03:45:31.165Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-10-02T03:45:31.165Z] GC before operation: completed in 376.548 ms, heap usage 227.402 MB -> 68.663 MB. [2025-10-02T03:45:51.738Z] ====== als (apache-spark) [default], iteration 24 completed (22097.334 ms) ====== [2025-10-02T03:45:51.738Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-10-02T03:45:51.738Z] GC before operation: completed in 415.950 ms, heap usage 170.759 MB -> 68.775 MB. [2025-10-02T03:46:12.132Z] ====== als (apache-spark) [default], iteration 25 completed (18461.696 ms) ====== [2025-10-02T03:46:12.132Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-10-02T03:46:12.132Z] GC before operation: completed in 281.252 ms, heap usage 364.635 MB -> 69.281 MB. [2025-10-02T03:46:32.389Z] ====== als (apache-spark) [default], iteration 26 completed (21135.952 ms) ====== [2025-10-02T03:46:32.389Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-10-02T03:46:32.389Z] GC before operation: completed in 302.832 ms, heap usage 263.707 MB -> 69.391 MB. [2025-10-02T03:46:56.427Z] ====== als (apache-spark) [default], iteration 27 completed (21151.471 ms) ====== [2025-10-02T03:46:56.427Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-10-02T03:46:56.427Z] GC before operation: completed in 269.750 ms, heap usage 158.231 MB -> 69.549 MB. [2025-10-02T03:47:17.057Z] ====== als (apache-spark) [default], iteration 28 completed (21648.475 ms) ====== [2025-10-02T03:47:17.057Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-10-02T03:47:17.057Z] GC before operation: completed in 365.293 ms, heap usage 90.487 MB -> 69.324 MB. [2025-10-02T03:47:37.842Z] ====== als (apache-spark) [default], iteration 29 completed (20107.066 ms) ====== [2025-10-02T03:47:37.842Z] ----------------------------------- [2025-10-02T03:47:37.842Z] renaissance-als_0_PASSED [2025-10-02T03:47:37.842Z] ----------------------------------- [2025-10-02T03:47:37.842Z] [2025-10-02T03:47:37.842Z] TEST TEARDOWN: [2025-10-02T03:47:37.842Z] Nothing to be done for teardown. [2025-10-02T03:47:37.842Z] renaissance-als_0 Finish Time: Thu Oct 2 03:47:37 2025 Epoch Time (ms): 1759376857440