[2025-12-06T13:40:35.161Z] Running test renaissance-als_0 ... [2025-12-06T13:40:35.161Z] =============================================== [2025-12-06T13:40:35.161Z] renaissance-als_0 Start Time: Sat Dec 6 13:40:34 2025 Epoch Time (ms): 1765028434616 [2025-12-06T13:40:35.161Z] variation: NoOptions [2025-12-06T13:40:35.161Z] JVM_OPTIONS: [2025-12-06T13:40:35.161Z] { \ [2025-12-06T13:40:35.161Z] echo ""; echo "TEST SETUP:"; \ [2025-12-06T13:40:35.161Z] echo "Nothing to be done for setup."; \ [2025-12-06T13:40:35.161Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17650284343892/renaissance-als_0"; \ [2025-12-06T13:40:35.161Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17650284343892/renaissance-als_0"; \ [2025-12-06T13:40:35.161Z] echo ""; echo "TESTING:"; \ [2025-12-06T13:40:35.161Z] "/home/jenkins/workspace/Test_openjdk25_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_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17650284343892/renaissance-als_0"/als.json" als; \ [2025-12-06T13:40:35.161Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17650284343892/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-06T13:40:35.161Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-06T13:40:35.161Z] echo "Nothing to be done for teardown."; \ [2025-12-06T13:40:35.161Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17650284343892/TestTargetResult"; [2025-12-06T13:40:35.161Z] [2025-12-06T13:40:35.161Z] TEST SETUP: [2025-12-06T13:40:35.161Z] Nothing to be done for setup. [2025-12-06T13:40:35.161Z] [2025-12-06T13:40:35.161Z] TESTING: [2025-12-06T13:40:35.784Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-06T13:40:35.784Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_s390x_linux/aqa-tests/TKG/output_17650284343892/renaissance-als_0/launcher-134034-10914598449366843996/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-06T13:40:35.784Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-06T13:40:35.784Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-06T13:40:40.576Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-12-06T13:40:43.420Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-06T13:40:44.040Z] GC before operation: completed in 50.922 ms, heap usage 211.500 MB -> 35.048 MB. [2025-12-06T13:41:07.762Z] ====== als (apache-spark) [default], iteration 0 completed (22453.060 ms) ====== [2025-12-06T13:41:07.762Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-06T13:41:07.762Z] GC before operation: completed in 175.395 ms, heap usage 222.197 MB -> 61.595 MB. [2025-12-06T13:41:19.921Z] ====== als (apache-spark) [default], iteration 1 completed (12511.751 ms) ====== [2025-12-06T13:41:19.921Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-06T13:41:19.921Z] GC before operation: completed in 115.742 ms, heap usage 206.962 MB -> 62.508 MB. [2025-12-06T13:41:32.029Z] ====== als (apache-spark) [default], iteration 2 completed (11620.687 ms) ====== [2025-12-06T13:41:32.029Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-06T13:41:32.029Z] GC before operation: completed in 164.693 ms, heap usage 280.685 MB -> 63.028 MB. [2025-12-06T13:41:44.330Z] ====== als (apache-spark) [default], iteration 3 completed (11451.914 ms) ====== [2025-12-06T13:41:44.330Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-06T13:41:44.330Z] GC before operation: completed in 140.846 ms, heap usage 210.994 MB -> 63.523 MB. [2025-12-06T13:41:56.696Z] ====== als (apache-spark) [default], iteration 4 completed (11983.162 ms) ====== [2025-12-06T13:41:56.696Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-06T13:41:56.696Z] GC before operation: completed in 141.634 ms, heap usage 184.735 MB -> 64.066 MB. [2025-12-06T13:42:09.132Z] ====== als (apache-spark) [default], iteration 5 completed (11594.272 ms) ====== [2025-12-06T13:42:09.132Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-06T13:42:09.132Z] GC before operation: completed in 126.484 ms, heap usage 134.808 MB -> 64.573 MB. [2025-12-06T13:42:21.425Z] ====== als (apache-spark) [default], iteration 6 completed (11550.847 ms) ====== [2025-12-06T13:42:21.425Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-06T13:42:21.425Z] GC before operation: completed in 125.612 ms, heap usage 358.562 MB -> 65.378 MB. [2025-12-06T13:42:31.814Z] ====== als (apache-spark) [default], iteration 7 completed (11637.295 ms) ====== [2025-12-06T13:42:31.814Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-06T13:42:32.466Z] GC before operation: completed in 132.344 ms, heap usage 478.267 MB -> 66.113 MB. [2025-12-06T13:42:44.876Z] ====== als (apache-spark) [default], iteration 8 completed (11752.415 ms) ====== [2025-12-06T13:42:44.876Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-06T13:42:44.876Z] GC before operation: completed in 124.403 ms, heap usage 283.000 MB -> 66.396 MB. [2025-12-06T13:42:57.161Z] ====== als (apache-spark) [default], iteration 9 completed (11168.115 ms) ====== [2025-12-06T13:42:57.161Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-06T13:42:57.161Z] GC before operation: completed in 170.556 ms, heap usage 137.658 MB -> 66.877 MB. [2025-12-06T13:43:07.794Z] ====== als (apache-spark) [default], iteration 10 completed (10315.791 ms) ====== [2025-12-06T13:43:07.794Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-06T13:43:07.794Z] GC before operation: completed in 109.091 ms, heap usage 154.480 MB -> 66.605 MB. [2025-12-06T13:43:18.141Z] ====== als (apache-spark) [default], iteration 11 completed (10931.211 ms) ====== [2025-12-06T13:43:18.141Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-06T13:43:18.141Z] GC before operation: completed in 123.246 ms, heap usage 201.979 MB -> 66.910 MB. [2025-12-06T13:43:28.512Z] ====== als (apache-spark) [default], iteration 12 completed (10579.890 ms) ====== [2025-12-06T13:43:28.512Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-06T13:43:28.512Z] GC before operation: completed in 125.533 ms, heap usage 73.669 MB -> 66.900 MB. [2025-12-06T13:43:40.825Z] ====== als (apache-spark) [default], iteration 13 completed (10459.752 ms) ====== [2025-12-06T13:43:40.825Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-06T13:43:40.825Z] GC before operation: completed in 124.949 ms, heap usage 188.796 MB -> 67.413 MB. [2025-12-06T13:43:51.212Z] ====== als (apache-spark) [default], iteration 14 completed (11066.180 ms) ====== [2025-12-06T13:43:51.212Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-06T13:43:51.212Z] GC before operation: completed in 140.724 ms, heap usage 200.702 MB -> 67.602 MB. [2025-12-06T13:44:01.620Z] ====== als (apache-spark) [default], iteration 15 completed (10577.352 ms) ====== [2025-12-06T13:44:01.620Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-06T13:44:01.620Z] GC before operation: completed in 123.138 ms, heap usage 330.367 MB -> 68.115 MB. [2025-12-06T13:44:13.849Z] ====== als (apache-spark) [default], iteration 16 completed (10491.151 ms) ====== [2025-12-06T13:44:13.849Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-06T13:44:13.849Z] GC before operation: completed in 133.322 ms, heap usage 154.768 MB -> 68.151 MB. [2025-12-06T13:44:24.168Z] ====== als (apache-spark) [default], iteration 17 completed (10802.869 ms) ====== [2025-12-06T13:44:24.168Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-06T13:44:24.168Z] GC before operation: completed in 201.218 ms, heap usage 472.143 MB -> 68.866 MB. [2025-12-06T13:44:36.478Z] ====== als (apache-spark) [default], iteration 18 completed (10838.464 ms) ====== [2025-12-06T13:44:36.478Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-06T13:44:36.478Z] GC before operation: completed in 137.154 ms, heap usage 157.027 MB -> 68.709 MB. [2025-12-06T13:44:46.907Z] ====== als (apache-spark) [default], iteration 19 completed (10937.010 ms) ====== [2025-12-06T13:44:46.907Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-06T13:44:46.907Z] GC before operation: completed in 123.697 ms, heap usage 437.656 MB -> 69.405 MB. [2025-12-06T13:44:58.986Z] ====== als (apache-spark) [default], iteration 20 completed (11015.494 ms) ====== [2025-12-06T13:44:58.986Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-06T13:44:58.986Z] GC before operation: completed in 160.992 ms, heap usage 269.883 MB -> 69.299 MB. [2025-12-06T13:45:09.166Z] ====== als (apache-spark) [default], iteration 21 completed (10889.663 ms) ====== [2025-12-06T13:45:09.166Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-06T13:45:09.166Z] GC before operation: completed in 115.300 ms, heap usage 202.517 MB -> 69.426 MB. [2025-12-06T13:45:19.326Z] ====== als (apache-spark) [default], iteration 22 completed (10289.075 ms) ====== [2025-12-06T13:45:19.326Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-06T13:45:19.326Z] GC before operation: completed in 150.822 ms, heap usage 130.835 MB -> 69.762 MB. [2025-12-06T13:45:31.398Z] ====== als (apache-spark) [default], iteration 23 completed (10573.166 ms) ====== [2025-12-06T13:45:31.398Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-06T13:45:31.398Z] GC before operation: completed in 138.288 ms, heap usage 378.567 MB -> 70.265 MB. [2025-12-06T13:45:43.633Z] ====== als (apache-spark) [default], iteration 24 completed (11342.871 ms) ====== [2025-12-06T13:45:43.633Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-06T13:45:43.633Z] GC before operation: completed in 172.207 ms, heap usage 102.425 MB -> 69.820 MB. [2025-12-06T13:45:53.821Z] ====== als (apache-spark) [default], iteration 25 completed (10922.474 ms) ====== [2025-12-06T13:45:53.821Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-06T13:45:53.821Z] GC before operation: completed in 154.854 ms, heap usage 248.420 MB -> 70.621 MB. [2025-12-06T13:46:03.984Z] ====== als (apache-spark) [default], iteration 26 completed (9330.834 ms) ====== [2025-12-06T13:46:03.984Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-06T13:46:03.984Z] GC before operation: completed in 99.666 ms, heap usage 199.315 MB -> 70.720 MB. [2025-12-06T13:46:14.181Z] ====== als (apache-spark) [default], iteration 27 completed (9627.941 ms) ====== [2025-12-06T13:46:14.182Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-06T13:46:14.182Z] GC before operation: completed in 104.984 ms, heap usage 340.689 MB -> 71.224 MB. [2025-12-06T13:46:22.960Z] ====== als (apache-spark) [default], iteration 28 completed (10001.973 ms) ====== [2025-12-06T13:46:22.960Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-06T13:46:23.585Z] GC before operation: completed in 118.926 ms, heap usage 229.144 MB -> 71.350 MB. [2025-12-06T13:46:33.730Z] ====== als (apache-spark) [default], iteration 29 completed (9701.925 ms) ====== [2025-12-06T13:46:33.730Z] ----------------------------------- [2025-12-06T13:46:33.730Z] renaissance-als_0_PASSED [2025-12-06T13:46:33.730Z] ----------------------------------- [2025-12-06T13:46:33.730Z] [2025-12-06T13:46:33.730Z] TEST TEARDOWN: [2025-12-06T13:46:33.730Z] Nothing to be done for teardown. [2025-12-06T13:46:33.730Z] renaissance-als_0 Finish Time: Sat Dec 6 13:46:33 2025 Epoch Time (ms): 1765028793287