[2025-11-06T01:18:26.617Z] Running test renaissance-als_0 ... [2025-11-06T01:18:26.617Z] =============================================== [2025-11-06T01:18:26.617Z] renaissance-als_0 Start Time: Thu Nov 6 01:18:26 2025 Epoch Time (ms): 1762391906262 [2025-11-06T01:18:26.617Z] variation: NoOptions [2025-11-06T01:18:26.618Z] JVM_OPTIONS: [2025-11-06T01:18:26.618Z] { \ [2025-11-06T01:18:26.618Z] echo ""; echo "TEST SETUP:"; \ [2025-11-06T01:18:26.618Z] echo "Nothing to be done for setup."; \ [2025-11-06T01:18:26.618Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17623917381561/renaissance-als_0"; \ [2025-11-06T01:18:26.618Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17623917381561/renaissance-als_0"; \ [2025-11-06T01:18:26.618Z] echo ""; echo "TESTING:"; \ [2025-11-06T01:18:26.618Z] "/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_17623917381561/renaissance-als_0"/als.json" als; \ [2025-11-06T01:18:26.618Z] 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_17623917381561/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-06T01:18:26.618Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-06T01:18:26.618Z] echo "Nothing to be done for teardown."; \ [2025-11-06T01:18:26.618Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17623917381561/TestTargetResult"; [2025-11-06T01:18:26.618Z] [2025-11-06T01:18:26.618Z] TEST SETUP: [2025-11-06T01:18:26.618Z] Nothing to be done for setup. [2025-11-06T01:18:26.618Z] [2025-11-06T01:18:26.618Z] TESTING: [2025-11-06T01:18:31.141Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-11-06T01:18:33.854Z] WARNING: An illegal reflective access operation has occurred [2025-11-06T01:18:33.854Z] 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_17623917381561/renaissance-als_0/harness-011826-9606205967886257605/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-11-06T01:18:33.854Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-11-06T01:18:33.854Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-11-06T01:18:33.854Z] WARNING: All illegal access operations will be denied in a future release [2025-11-06T01:18:35.081Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-06T01:18:35.081Z] GC before operation: completed in 98.636 ms, heap usage 344.510 MB -> 34.490 MB. [2025-11-06T01:18:54.793Z] ====== als (apache-spark) [default], iteration 0 completed (19641.559 ms) ====== [2025-11-06T01:18:54.793Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-06T01:18:55.472Z] GC before operation: completed in 163.286 ms, heap usage 364.376 MB -> 60.516 MB. [2025-11-06T01:19:09.462Z] ====== als (apache-spark) [default], iteration 1 completed (12493.669 ms) ====== [2025-11-06T01:19:09.462Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-06T01:19:09.462Z] GC before operation: completed in 218.330 ms, heap usage 162.128 MB -> 61.074 MB. [2025-11-06T01:19:21.632Z] ====== als (apache-spark) [default], iteration 2 completed (11876.839 ms) ====== [2025-11-06T01:19:21.632Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-06T01:19:21.632Z] GC before operation: completed in 117.279 ms, heap usage 166.315 MB -> 61.619 MB. [2025-11-06T01:19:33.411Z] ====== als (apache-spark) [default], iteration 3 completed (12008.303 ms) ====== [2025-11-06T01:19:33.411Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-06T01:19:33.411Z] GC before operation: completed in 208.117 ms, heap usage 226.890 MB -> 62.159 MB. [2025-11-06T01:19:45.656Z] ====== als (apache-spark) [default], iteration 4 completed (10864.952 ms) ====== [2025-11-06T01:19:45.656Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-06T01:19:45.656Z] GC before operation: completed in 138.078 ms, heap usage 161.262 MB -> 62.683 MB. [2025-11-06T01:19:55.629Z] ====== als (apache-spark) [default], iteration 5 completed (10945.819 ms) ====== [2025-11-06T01:19:55.629Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-06T01:19:55.629Z] GC before operation: completed in 224.396 ms, heap usage 258.265 MB -> 63.329 MB. [2025-11-06T01:20:07.478Z] ====== als (apache-spark) [default], iteration 6 completed (10963.834 ms) ====== [2025-11-06T01:20:07.478Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-06T01:20:07.478Z] GC before operation: completed in 169.063 ms, heap usage 257.064 MB -> 63.898 MB. [2025-11-06T01:20:19.933Z] ====== als (apache-spark) [default], iteration 7 completed (11442.798 ms) ====== [2025-11-06T01:20:19.933Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-06T01:20:19.933Z] GC before operation: completed in 148.015 ms, heap usage 223.402 MB -> 64.379 MB. [2025-11-06T01:20:30.287Z] ====== als (apache-spark) [default], iteration 8 completed (10123.967 ms) ====== [2025-11-06T01:20:30.287Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-06T01:20:30.287Z] GC before operation: completed in 171.693 ms, heap usage 84.051 MB -> 64.775 MB. [2025-11-06T01:20:42.267Z] ====== als (apache-spark) [default], iteration 9 completed (12284.089 ms) ====== [2025-11-06T01:20:42.267Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-06T01:20:42.268Z] GC before operation: completed in 183.596 ms, heap usage 138.324 MB -> 65.048 MB. [2025-11-06T01:20:54.176Z] ====== als (apache-spark) [default], iteration 10 completed (11777.923 ms) ====== [2025-11-06T01:20:54.176Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-06T01:20:54.176Z] GC before operation: completed in 151.234 ms, heap usage 235.714 MB -> 65.423 MB. [2025-11-06T01:21:08.326Z] ====== als (apache-spark) [default], iteration 11 completed (11965.200 ms) ====== [2025-11-06T01:21:08.326Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-06T01:21:08.326Z] GC before operation: completed in 170.797 ms, heap usage 266.864 MB -> 65.708 MB. [2025-11-06T01:21:20.601Z] ====== als (apache-spark) [default], iteration 12 completed (12261.247 ms) ====== [2025-11-06T01:21:20.601Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-06T01:21:20.601Z] GC before operation: completed in 175.403 ms, heap usage 143.886 MB -> 65.805 MB. [2025-11-06T01:21:32.541Z] ====== als (apache-spark) [default], iteration 13 completed (12403.344 ms) ====== [2025-11-06T01:21:32.541Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-06T01:21:32.541Z] GC before operation: completed in 189.314 ms, heap usage 276.109 MB -> 66.169 MB. [2025-11-06T01:21:44.497Z] ====== als (apache-spark) [default], iteration 14 completed (11377.895 ms) ====== [2025-11-06T01:21:44.497Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-06T01:21:44.497Z] GC before operation: completed in 129.894 ms, heap usage 130.111 MB -> 66.299 MB. [2025-11-06T01:21:56.643Z] ====== als (apache-spark) [default], iteration 15 completed (11182.103 ms) ====== [2025-11-06T01:21:56.643Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-06T01:21:56.643Z] GC before operation: completed in 148.096 ms, heap usage 120.993 MB -> 66.567 MB. [2025-11-06T01:22:08.583Z] ====== als (apache-spark) [default], iteration 16 completed (12450.662 ms) ====== [2025-11-06T01:22:08.583Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-06T01:22:08.583Z] GC before operation: completed in 181.876 ms, heap usage 277.519 MB -> 66.935 MB. [2025-11-06T01:22:20.745Z] ====== als (apache-spark) [default], iteration 17 completed (12097.095 ms) ====== [2025-11-06T01:22:20.745Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-06T01:22:20.745Z] GC before operation: completed in 130.401 ms, heap usage 116.467 MB -> 67.060 MB. [2025-11-06T01:22:32.567Z] ====== als (apache-spark) [default], iteration 18 completed (11432.337 ms) ====== [2025-11-06T01:22:32.567Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-06T01:22:32.567Z] GC before operation: completed in 132.433 ms, heap usage 288.860 MB -> 67.450 MB. [2025-11-06T01:22:46.542Z] ====== als (apache-spark) [default], iteration 19 completed (12652.205 ms) ====== [2025-11-06T01:22:46.542Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-06T01:22:46.542Z] GC before operation: completed in 234.960 ms, heap usage 215.995 MB -> 67.698 MB. [2025-11-06T01:22:58.355Z] ====== als (apache-spark) [default], iteration 20 completed (11440.408 ms) ====== [2025-11-06T01:22:58.355Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-06T01:22:58.355Z] GC before operation: completed in 145.241 ms, heap usage 233.025 MB -> 67.914 MB. [2025-11-06T01:23:10.521Z] ====== als (apache-spark) [default], iteration 21 completed (10995.470 ms) ====== [2025-11-06T01:23:10.521Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-06T01:23:10.521Z] GC before operation: completed in 150.988 ms, heap usage 114.027 MB -> 68.144 MB. [2025-11-06T01:23:22.347Z] ====== als (apache-spark) [default], iteration 22 completed (12072.570 ms) ====== [2025-11-06T01:23:22.347Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-06T01:23:22.347Z] GC before operation: completed in 155.732 ms, heap usage 227.559 MB -> 68.395 MB. [2025-11-06T01:23:34.202Z] ====== als (apache-spark) [default], iteration 23 completed (11821.349 ms) ====== [2025-11-06T01:23:34.202Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-06T01:23:34.202Z] GC before operation: completed in 115.950 ms, heap usage 240.694 MB -> 68.673 MB. [2025-11-06T01:23:46.027Z] ====== als (apache-spark) [default], iteration 24 completed (11779.091 ms) ====== [2025-11-06T01:23:46.027Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-06T01:23:46.027Z] GC before operation: completed in 143.971 ms, heap usage 255.091 MB -> 68.917 MB. [2025-11-06T01:23:58.206Z] ====== als (apache-spark) [default], iteration 25 completed (10858.141 ms) ====== [2025-11-06T01:23:58.206Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-06T01:23:58.206Z] GC before operation: completed in 193.423 ms, heap usage 214.795 MB -> 69.199 MB. [2025-11-06T01:24:10.587Z] ====== als (apache-spark) [default], iteration 26 completed (11631.200 ms) ====== [2025-11-06T01:24:10.587Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-06T01:24:10.587Z] GC before operation: completed in 171.642 ms, heap usage 168.053 MB -> 69.297 MB. [2025-11-06T01:24:22.363Z] ====== als (apache-spark) [default], iteration 27 completed (11470.735 ms) ====== [2025-11-06T01:24:22.363Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-06T01:24:22.363Z] GC before operation: completed in 178.600 ms, heap usage 147.962 MB -> 69.546 MB. [2025-11-06T01:24:34.172Z] ====== als (apache-spark) [default], iteration 28 completed (12473.781 ms) ====== [2025-11-06T01:24:34.172Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-06T01:24:34.172Z] GC before operation: completed in 218.079 ms, heap usage 232.793 MB -> 69.446 MB. [2025-11-06T01:24:48.171Z] ====== als (apache-spark) [default], iteration 29 completed (12626.773 ms) ====== [2025-11-06T01:24:48.171Z] ----------------------------------- [2025-11-06T01:24:48.171Z] renaissance-als_0_PASSED [2025-11-06T01:24:48.171Z] ----------------------------------- [2025-11-06T01:24:48.171Z] [2025-11-06T01:24:48.171Z] TEST TEARDOWN: [2025-11-06T01:24:48.171Z] Nothing to be done for teardown. [2025-11-06T01:24:48.171Z] renaissance-als_0 Finish Time: Thu Nov 6 01:24:47 2025 Epoch Time (ms): 1762392287277