[2025-11-20T03:05:38.869Z] Running test renaissance-als_0 ... [2025-11-20T03:05:38.869Z] =============================================== [2025-11-20T03:05:38.869Z] renaissance-als_0 Start Time: Thu Nov 20 03:05:38 2025 Epoch Time (ms): 1763607938625 [2025-11-20T03:05:38.869Z] variation: NoOptions [2025-11-20T03:05:38.869Z] JVM_OPTIONS: [2025-11-20T03:05:38.869Z] { \ [2025-11-20T03:05:38.869Z] echo ""; echo "TEST SETUP:"; \ [2025-11-20T03:05:38.869Z] echo "Nothing to be done for setup."; \ [2025-11-20T03:05:38.870Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17636077022410/renaissance-als_0"; \ [2025-11-20T03:05:38.870Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17636077022410/renaissance-als_0"; \ [2025-11-20T03:05:38.870Z] echo ""; echo "TESTING:"; \ [2025-11-20T03:05:38.870Z] "/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_17636077022410/renaissance-als_0"/als.json" als; \ [2025-11-20T03:05:38.870Z] 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_17636077022410/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-20T03:05:38.870Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-20T03:05:38.870Z] echo "Nothing to be done for teardown."; \ [2025-11-20T03:05:38.870Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17636077022410/TestTargetResult"; [2025-11-20T03:05:38.870Z] [2025-11-20T03:05:38.870Z] TEST SETUP: [2025-11-20T03:05:38.870Z] Nothing to be done for setup. [2025-11-20T03:05:38.870Z] [2025-11-20T03:05:38.870Z] TESTING: [2025-11-20T03:05:47.746Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-11-20T03:05:52.648Z] WARNING: An illegal reflective access operation has occurred [2025-11-20T03:05:52.648Z] 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_17636077022410/renaissance-als_0/harness-030539-1818854921054021739/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-11-20T03:05:52.648Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-11-20T03:05:52.648Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-11-20T03:05:52.648Z] WARNING: All illegal access operations will be denied in a future release [2025-11-20T03:05:54.863Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-20T03:05:54.863Z] GC before operation: completed in 143.589 ms, heap usage 285.500 MB -> 34.470 MB. [2025-11-20T03:06:28.431Z] ====== als (apache-spark) [default], iteration 0 completed (30931.969 ms) ====== [2025-11-20T03:06:28.431Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-20T03:06:28.431Z] GC before operation: completed in 262.430 ms, heap usage 355.984 MB -> 60.453 MB. [2025-11-20T03:06:43.216Z] ====== als (apache-spark) [default], iteration 1 completed (15267.095 ms) ====== [2025-11-20T03:06:43.216Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-20T03:06:43.216Z] GC before operation: completed in 277.865 ms, heap usage 364.262 MB -> 61.309 MB. [2025-11-20T03:06:58.125Z] ====== als (apache-spark) [default], iteration 2 completed (15078.965 ms) ====== [2025-11-20T03:06:58.125Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-20T03:06:58.769Z] GC before operation: completed in 248.765 ms, heap usage 374.736 MB -> 61.870 MB. [2025-11-20T03:07:13.845Z] ====== als (apache-spark) [default], iteration 3 completed (14056.858 ms) ====== [2025-11-20T03:07:13.845Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-20T03:07:13.845Z] GC before operation: completed in 211.242 ms, heap usage 379.878 MB -> 62.491 MB. [2025-11-20T03:07:28.605Z] ====== als (apache-spark) [default], iteration 4 completed (14135.128 ms) ====== [2025-11-20T03:07:28.605Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-20T03:07:28.605Z] GC before operation: completed in 205.269 ms, heap usage 253.819 MB -> 62.809 MB. [2025-11-20T03:07:40.808Z] ====== als (apache-spark) [default], iteration 5 completed (12026.330 ms) ====== [2025-11-20T03:07:40.808Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-20T03:07:40.808Z] GC before operation: completed in 229.866 ms, heap usage 169.171 MB -> 63.233 MB. [2025-11-20T03:07:53.357Z] ====== als (apache-spark) [default], iteration 6 completed (12737.796 ms) ====== [2025-11-20T03:07:53.357Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-20T03:07:53.357Z] GC before operation: completed in 211.495 ms, heap usage 141.485 MB -> 63.751 MB. [2025-11-20T03:08:08.027Z] ====== als (apache-spark) [default], iteration 7 completed (12706.334 ms) ====== [2025-11-20T03:08:08.027Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-20T03:08:08.027Z] GC before operation: completed in 241.540 ms, heap usage 242.232 MB -> 64.407 MB. [2025-11-20T03:08:18.263Z] ====== als (apache-spark) [default], iteration 8 completed (11460.986 ms) ====== [2025-11-20T03:08:18.263Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-20T03:08:18.890Z] GC before operation: completed in 248.822 ms, heap usage 206.766 MB -> 64.810 MB. [2025-11-20T03:08:33.446Z] ====== als (apache-spark) [default], iteration 9 completed (12483.505 ms) ====== [2025-11-20T03:08:33.446Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-20T03:08:33.446Z] GC before operation: completed in 246.341 ms, heap usage 164.644 MB -> 65.456 MB. [2025-11-20T03:08:45.629Z] ====== als (apache-spark) [default], iteration 10 completed (12760.776 ms) ====== [2025-11-20T03:08:45.629Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-20T03:08:45.629Z] GC before operation: completed in 252.741 ms, heap usage 127.466 MB -> 65.333 MB. [2025-11-20T03:08:57.845Z] ====== als (apache-spark) [default], iteration 11 completed (12200.797 ms) ====== [2025-11-20T03:08:57.845Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-20T03:08:57.845Z] GC before operation: completed in 180.017 ms, heap usage 206.915 MB -> 65.628 MB. [2025-11-20T03:09:10.006Z] ====== als (apache-spark) [default], iteration 12 completed (11993.246 ms) ====== [2025-11-20T03:09:10.006Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-20T03:09:10.006Z] GC before operation: completed in 208.124 ms, heap usage 244.018 MB -> 65.966 MB. [2025-11-20T03:09:24.545Z] ====== als (apache-spark) [default], iteration 13 completed (12273.381 ms) ====== [2025-11-20T03:09:24.545Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-20T03:09:24.545Z] GC before operation: completed in 272.428 ms, heap usage 211.990 MB -> 66.094 MB. [2025-11-20T03:09:36.757Z] ====== als (apache-spark) [default], iteration 14 completed (12930.744 ms) ====== [2025-11-20T03:09:36.757Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-20T03:09:36.757Z] GC before operation: completed in 218.702 ms, heap usage 259.026 MB -> 66.459 MB. [2025-11-20T03:09:48.905Z] ====== als (apache-spark) [default], iteration 15 completed (12165.769 ms) ====== [2025-11-20T03:09:48.905Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-20T03:09:48.905Z] GC before operation: completed in 210.188 ms, heap usage 225.487 MB -> 66.725 MB. [2025-11-20T03:10:01.124Z] ====== als (apache-spark) [default], iteration 16 completed (11276.375 ms) ====== [2025-11-20T03:10:01.124Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-20T03:10:01.124Z] GC before operation: completed in 169.240 ms, heap usage 106.473 MB -> 66.851 MB. [2025-11-20T03:10:13.345Z] ====== als (apache-spark) [default], iteration 17 completed (12245.421 ms) ====== [2025-11-20T03:10:13.345Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-20T03:10:13.345Z] GC before operation: completed in 201.029 ms, heap usage 157.394 MB -> 67.092 MB. [2025-11-20T03:10:27.745Z] ====== als (apache-spark) [default], iteration 18 completed (13914.245 ms) ====== [2025-11-20T03:10:27.745Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-20T03:10:27.745Z] GC before operation: completed in 267.563 ms, heap usage 95.228 MB -> 67.368 MB. [2025-11-20T03:10:42.199Z] ====== als (apache-spark) [default], iteration 19 completed (12904.616 ms) ====== [2025-11-20T03:10:42.199Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-20T03:10:42.199Z] GC before operation: completed in 238.847 ms, heap usage 162.976 MB -> 67.608 MB. [2025-11-20T03:10:54.335Z] ====== als (apache-spark) [default], iteration 20 completed (12762.052 ms) ====== [2025-11-20T03:10:54.335Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-20T03:10:54.335Z] GC before operation: completed in 251.674 ms, heap usage 143.666 MB -> 67.861 MB. [2025-11-20T03:11:06.504Z] ====== als (apache-spark) [default], iteration 21 completed (11940.407 ms) ====== [2025-11-20T03:11:06.504Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-20T03:11:07.138Z] GC before operation: completed in 220.906 ms, heap usage 117.512 MB -> 68.107 MB. [2025-11-20T03:11:19.292Z] ====== als (apache-spark) [default], iteration 22 completed (11772.367 ms) ====== [2025-11-20T03:11:19.292Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-20T03:11:19.292Z] GC before operation: completed in 208.557 ms, heap usage 221.508 MB -> 68.445 MB. [2025-11-20T03:11:33.677Z] ====== als (apache-spark) [default], iteration 23 completed (12492.479 ms) ====== [2025-11-20T03:11:33.677Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-20T03:11:33.677Z] GC before operation: completed in 237.093 ms, heap usage 152.112 MB -> 68.640 MB. [2025-11-20T03:11:43.937Z] ====== als (apache-spark) [default], iteration 24 completed (11454.390 ms) ====== [2025-11-20T03:11:43.937Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-20T03:11:43.937Z] GC before operation: completed in 220.715 ms, heap usage 239.684 MB -> 68.965 MB. [2025-11-20T03:11:56.145Z] ====== als (apache-spark) [default], iteration 25 completed (11253.351 ms) ====== [2025-11-20T03:11:56.145Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-20T03:11:56.145Z] GC before operation: completed in 222.029 ms, heap usage 249.617 MB -> 69.238 MB. [2025-11-20T03:12:08.248Z] ====== als (apache-spark) [default], iteration 26 completed (12217.946 ms) ====== [2025-11-20T03:12:08.248Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-20T03:12:08.248Z] GC before operation: completed in 236.693 ms, heap usage 206.296 MB -> 69.344 MB. [2025-11-20T03:12:20.456Z] ====== als (apache-spark) [default], iteration 27 completed (11491.660 ms) ====== [2025-11-20T03:12:20.456Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-20T03:12:20.456Z] GC before operation: completed in 288.060 ms, heap usage 266.361 MB -> 69.727 MB. [2025-11-20T03:12:32.596Z] ====== als (apache-spark) [default], iteration 28 completed (11499.284 ms) ====== [2025-11-20T03:12:32.596Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-20T03:12:32.596Z] GC before operation: completed in 207.472 ms, heap usage 123.193 MB -> 69.825 MB. [2025-11-20T03:12:44.757Z] ====== als (apache-spark) [default], iteration 29 completed (11671.440 ms) ====== [2025-11-20T03:12:45.396Z] ----------------------------------- [2025-11-20T03:12:45.396Z] renaissance-als_0_PASSED [2025-11-20T03:12:45.396Z] ----------------------------------- [2025-11-20T03:12:45.396Z] [2025-11-20T03:12:45.396Z] TEST TEARDOWN: [2025-11-20T03:12:45.396Z] Nothing to be done for teardown. [2025-11-20T03:12:45.396Z] renaissance-als_0 Finish Time: Thu Nov 20 03:12:44 2025 Epoch Time (ms): 1763608364790