[2025-07-16T13:15:21.577Z] Running test renaissance-als_0 ... [2025-07-16T13:15:21.577Z] =============================================== [2025-07-16T13:15:21.577Z] renaissance-als_0 Start Time: Wed Jul 16 13:15:21 2025 Epoch Time (ms): 1752671721485 [2025-07-16T13:15:21.577Z] variation: NoOptions [2025-07-16T13:15:21.577Z] JVM_OPTIONS: [2025-07-16T13:15:21.577Z] { \ [2025-07-16T13:15:21.577Z] echo ""; echo "TEST SETUP:"; \ [2025-07-16T13:15:21.577Z] echo "Nothing to be done for setup."; \ [2025-07-16T13:15:21.577Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17526716471321/renaissance-als_0"; \ [2025-07-16T13:15:21.578Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17526716471321/renaissance-als_0"; \ [2025-07-16T13:15:21.578Z] echo ""; echo "TESTING:"; \ [2025-07-16T13:15:21.578Z] "/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_17526716471321/renaissance-als_0"/als.json" als; \ [2025-07-16T13:15:21.578Z] 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_17526716471321/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-16T13:15:21.578Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-16T13:15:21.578Z] echo "Nothing to be done for teardown."; \ [2025-07-16T13:15:21.578Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17526716471321/TestTargetResult"; [2025-07-16T13:15:22.378Z] [2025-07-16T13:15:22.378Z] TEST SETUP: [2025-07-16T13:15:22.378Z] Nothing to be done for setup. [2025-07-16T13:15:22.378Z] [2025-07-16T13:15:22.378Z] TESTING: [2025-07-16T13:15:34.416Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-07-16T13:15:40.396Z] WARNING: An illegal reflective access operation has occurred [2025-07-16T13:15:40.396Z] 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_17526716471321/renaissance-als_0/harness-131523-2888038405089451256/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-07-16T13:15:40.396Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-07-16T13:15:40.396Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-07-16T13:15:40.396Z] WARNING: All illegal access operations will be denied in a future release [2025-07-16T13:15:41.195Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-07-16T13:15:41.949Z] GC before operation: completed in 233.920 ms, heap usage 263.502 MB -> 34.464 MB. [2025-07-16T13:16:30.043Z] ====== als (apache-spark) [default], iteration 0 completed (41489.074 ms) ====== [2025-07-16T13:16:30.043Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-07-16T13:16:30.043Z] GC before operation: completed in 283.169 ms, heap usage 109.510 MB -> 60.305 MB. [2025-07-16T13:16:51.628Z] ====== als (apache-spark) [default], iteration 1 completed (23799.317 ms) ====== [2025-07-16T13:16:51.628Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-07-16T13:16:51.628Z] GC before operation: completed in 281.098 ms, heap usage 155.842 MB -> 60.629 MB. [2025-07-16T13:17:13.205Z] ====== als (apache-spark) [default], iteration 2 completed (21613.173 ms) ====== [2025-07-16T13:17:13.205Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-07-16T13:17:13.205Z] GC before operation: completed in 258.553 ms, heap usage 232.791 MB -> 61.747 MB. [2025-07-16T13:17:34.597Z] ====== als (apache-spark) [default], iteration 3 completed (20109.030 ms) ====== [2025-07-16T13:17:34.597Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-07-16T13:17:34.597Z] GC before operation: completed in 265.282 ms, heap usage 389.712 MB -> 62.379 MB. [2025-07-16T13:17:52.891Z] ====== als (apache-spark) [default], iteration 4 completed (19134.985 ms) ====== [2025-07-16T13:17:52.891Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-07-16T13:17:52.891Z] GC before operation: completed in 276.347 ms, heap usage 167.455 MB -> 62.684 MB. [2025-07-16T13:18:14.333Z] ====== als (apache-spark) [default], iteration 5 completed (20468.707 ms) ====== [2025-07-16T13:18:14.333Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-07-16T13:18:14.333Z] GC before operation: completed in 236.107 ms, heap usage 129.673 MB -> 62.870 MB. [2025-07-16T13:18:35.834Z] ====== als (apache-spark) [default], iteration 6 completed (19122.259 ms) ====== [2025-07-16T13:18:35.834Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-07-16T13:18:35.834Z] GC before operation: completed in 354.058 ms, heap usage 123.234 MB -> 63.756 MB. [2025-07-16T13:18:57.809Z] ====== als (apache-spark) [default], iteration 7 completed (21003.935 ms) ====== [2025-07-16T13:18:57.809Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-07-16T13:18:57.809Z] GC before operation: completed in 355.159 ms, heap usage 231.222 MB -> 64.421 MB. [2025-07-16T13:19:23.726Z] ====== als (apache-spark) [default], iteration 8 completed (24318.290 ms) ====== [2025-07-16T13:19:23.726Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-07-16T13:19:23.726Z] GC before operation: completed in 301.486 ms, heap usage 155.965 MB -> 64.804 MB. [2025-07-16T13:19:42.069Z] ====== als (apache-spark) [default], iteration 9 completed (20776.899 ms) ====== [2025-07-16T13:19:42.069Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-07-16T13:19:42.069Z] GC before operation: completed in 349.957 ms, heap usage 168.671 MB -> 65.131 MB. [2025-07-16T13:20:07.317Z] ====== als (apache-spark) [default], iteration 10 completed (22802.469 ms) ====== [2025-07-16T13:20:07.317Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-07-16T13:20:07.317Z] GC before operation: completed in 222.689 ms, heap usage 225.339 MB -> 65.450 MB. [2025-07-16T13:20:28.745Z] ====== als (apache-spark) [default], iteration 11 completed (23053.648 ms) ====== [2025-07-16T13:20:28.745Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-07-16T13:20:29.516Z] GC before operation: completed in 309.646 ms, heap usage 231.357 MB -> 65.707 MB. [2025-07-16T13:20:54.910Z] ====== als (apache-spark) [default], iteration 12 completed (24082.784 ms) ====== [2025-07-16T13:20:54.910Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-07-16T13:20:54.910Z] GC before operation: completed in 309.487 ms, heap usage 204.822 MB -> 65.950 MB. [2025-07-16T13:21:20.028Z] ====== als (apache-spark) [default], iteration 13 completed (22374.725 ms) ====== [2025-07-16T13:21:20.028Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-07-16T13:21:20.028Z] GC before operation: completed in 343.312 ms, heap usage 119.759 MB -> 66.072 MB. [2025-07-16T13:21:41.810Z] ====== als (apache-spark) [default], iteration 14 completed (21268.379 ms) ====== [2025-07-16T13:21:41.810Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-07-16T13:21:41.810Z] GC before operation: completed in 278.685 ms, heap usage 223.874 MB -> 66.451 MB. [2025-07-16T13:22:01.142Z] ====== als (apache-spark) [default], iteration 15 completed (18819.825 ms) ====== [2025-07-16T13:22:01.142Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-07-16T13:22:01.142Z] GC before operation: completed in 345.051 ms, heap usage 214.360 MB -> 66.712 MB. [2025-07-16T13:22:22.657Z] ====== als (apache-spark) [default], iteration 16 completed (22808.904 ms) ====== [2025-07-16T13:22:22.657Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-07-16T13:22:23.406Z] GC before operation: completed in 537.209 ms, heap usage 124.377 MB -> 66.846 MB. [2025-07-16T13:22:53.480Z] ====== als (apache-spark) [default], iteration 17 completed (26818.208 ms) ====== [2025-07-16T13:22:53.480Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-07-16T13:22:53.480Z] GC before operation: completed in 349.887 ms, heap usage 383.232 MB -> 67.305 MB. [2025-07-16T13:23:23.361Z] ====== als (apache-spark) [default], iteration 18 completed (30708.033 ms) ====== [2025-07-16T13:23:23.361Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-07-16T13:23:23.361Z] GC before operation: completed in 665.346 ms, heap usage 195.271 MB -> 67.317 MB. [2025-07-16T13:23:54.282Z] ====== als (apache-spark) [default], iteration 19 completed (30057.287 ms) ====== [2025-07-16T13:23:54.282Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-07-16T13:23:54.282Z] GC before operation: completed in 224.347 ms, heap usage 89.379 MB -> 67.668 MB. [2025-07-16T13:24:19.707Z] ====== als (apache-spark) [default], iteration 20 completed (23928.120 ms) ====== [2025-07-16T13:24:19.707Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-07-16T13:24:19.707Z] GC before operation: completed in 559.931 ms, heap usage 90.660 MB -> 67.817 MB. [2025-07-16T13:24:50.268Z] ====== als (apache-spark) [default], iteration 21 completed (30076.227 ms) ====== [2025-07-16T13:24:50.268Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-07-16T13:24:50.268Z] GC before operation: completed in 300.596 ms, heap usage 153.147 MB -> 68.053 MB. [2025-07-16T13:25:20.306Z] ====== als (apache-spark) [default], iteration 22 completed (28341.832 ms) ====== [2025-07-16T13:25:20.306Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-07-16T13:25:20.306Z] GC before operation: completed in 550.179 ms, heap usage 122.760 MB -> 67.870 MB. [2025-07-16T13:25:46.372Z] ====== als (apache-spark) [default], iteration 23 completed (24345.472 ms) ====== [2025-07-16T13:25:46.372Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-07-16T13:25:46.372Z] GC before operation: completed in 379.477 ms, heap usage 271.964 MB -> 68.723 MB. [2025-07-16T13:26:07.953Z] ====== als (apache-spark) [default], iteration 24 completed (23135.404 ms) ====== [2025-07-16T13:26:07.953Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-07-16T13:26:07.953Z] GC before operation: completed in 155.931 ms, heap usage 240.043 MB -> 68.954 MB. [2025-07-16T13:26:29.536Z] ====== als (apache-spark) [default], iteration 25 completed (20759.988 ms) ====== [2025-07-16T13:26:29.536Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-07-16T13:26:29.536Z] GC before operation: completed in 244.348 ms, heap usage 253.422 MB -> 69.229 MB. [2025-07-16T13:26:51.286Z] ====== als (apache-spark) [default], iteration 26 completed (19675.254 ms) ====== [2025-07-16T13:26:51.286Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-07-16T13:26:51.286Z] GC before operation: completed in 228.574 ms, heap usage 257.767 MB -> 69.446 MB. [2025-07-16T13:27:09.387Z] ====== als (apache-spark) [default], iteration 27 completed (18671.472 ms) ====== [2025-07-16T13:27:09.387Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-07-16T13:27:09.387Z] GC before operation: completed in 246.391 ms, heap usage 266.316 MB -> 69.686 MB. [2025-07-16T13:27:27.463Z] ====== als (apache-spark) [default], iteration 28 completed (17647.566 ms) ====== [2025-07-16T13:27:27.463Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-07-16T13:27:27.463Z] GC before operation: completed in 284.430 ms, heap usage 158.686 MB -> 69.812 MB. [2025-07-16T13:27:48.939Z] ====== als (apache-spark) [default], iteration 29 completed (19349.845 ms) ====== [2025-07-16T13:27:48.939Z] ----------------------------------- [2025-07-16T13:27:48.939Z] renaissance-als_0_PASSED [2025-07-16T13:27:48.939Z] ----------------------------------- [2025-07-16T13:27:48.939Z] [2025-07-16T13:27:48.939Z] TEST TEARDOWN: [2025-07-16T13:27:48.939Z] Nothing to be done for teardown. [2025-07-16T13:27:48.939Z] renaissance-als_0 Finish Time: Wed Jul 16 13:27:47 2025 Epoch Time (ms): 1752672467658