[2025-10-31T15:46:19.775Z] Running test renaissance-als_0 ... [2025-10-31T15:46:19.775Z] =============================================== [2025-10-31T15:46:19.775Z] renaissance-als_0 Start Time: Fri Oct 31 15:46:19 2025 Epoch Time (ms): 1761925579053 [2025-10-31T15:46:19.775Z] variation: NoOptions [2025-10-31T15:46:19.775Z] JVM_OPTIONS: [2025-10-31T15:46:19.775Z] { \ [2025-10-31T15:46:19.775Z] echo ""; echo "TEST SETUP:"; \ [2025-10-31T15:46:19.775Z] echo "Nothing to be done for setup."; \ [2025-10-31T15:46:19.775Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17619255785224/renaissance-als_0"; \ [2025-10-31T15:46:19.775Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17619255785224/renaissance-als_0"; \ [2025-10-31T15:46:19.775Z] echo ""; echo "TESTING:"; \ [2025-10-31T15:46:19.775Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-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_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17619255785224/renaissance-als_0"/als.json" als; \ [2025-10-31T15:46:19.775Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17619255785224/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-31T15:46:19.775Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-31T15:46:19.775Z] echo "Nothing to be done for teardown."; \ [2025-10-31T15:46:19.775Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17619255785224/TestTargetResult"; [2025-10-31T15:46:19.775Z] [2025-10-31T15:46:19.775Z] TEST SETUP: [2025-10-31T15:46:19.775Z] Nothing to be done for setup. [2025-10-31T15:46:19.775Z] [2025-10-31T15:46:19.775Z] TESTING: [2025-10-31T15:46:19.775Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-10-31T15:46:19.776Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/output_17619255785224/renaissance-als_0/launcher-154619-9026860337171784341/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-10-31T15:46:19.776Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-10-31T15:46:19.776Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-10-31T15:46:29.603Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-10-31T15:46:34.935Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-10-31T15:46:35.658Z] GC before operation: completed in 57.977 ms, heap usage 244.500 MB -> 35.111 MB. [2025-10-31T15:47:10.459Z] ====== als (apache-spark) [default], iteration 0 completed (33039.684 ms) ====== [2025-10-31T15:47:10.459Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-10-31T15:47:10.459Z] GC before operation: completed in 97.848 ms, heap usage 138.234 MB -> 61.662 MB. [2025-10-31T15:47:18.409Z] ====== als (apache-spark) [default], iteration 1 completed (8414.913 ms) ====== [2025-10-31T15:47:18.409Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-10-31T15:47:18.409Z] GC before operation: completed in 85.975 ms, heap usage 475.248 MB -> 63.009 MB. [2025-10-31T15:47:26.401Z] ====== als (apache-spark) [default], iteration 2 completed (7775.576 ms) ====== [2025-10-31T15:47:26.401Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-10-31T15:47:26.401Z] GC before operation: completed in 94.777 ms, heap usage 190.858 MB -> 63.136 MB. [2025-10-31T15:47:34.368Z] ====== als (apache-spark) [default], iteration 3 completed (7767.885 ms) ====== [2025-10-31T15:47:34.368Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-10-31T15:47:34.368Z] GC before operation: completed in 114.162 ms, heap usage 462.273 MB -> 64.129 MB. [2025-10-31T15:47:42.805Z] ====== als (apache-spark) [default], iteration 4 completed (7285.434 ms) ====== [2025-10-31T15:47:42.805Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-10-31T15:47:42.805Z] GC before operation: completed in 94.589 ms, heap usage 216.472 MB -> 64.420 MB. [2025-10-31T15:47:50.763Z] ====== als (apache-spark) [default], iteration 5 completed (7491.708 ms) ====== [2025-10-31T15:47:50.763Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-10-31T15:47:50.763Z] GC before operation: completed in 97.249 ms, heap usage 336.025 MB -> 65.036 MB. [2025-10-31T15:47:57.349Z] ====== als (apache-spark) [default], iteration 6 completed (7323.674 ms) ====== [2025-10-31T15:47:57.349Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-10-31T15:47:57.349Z] GC before operation: completed in 102.534 ms, heap usage 498.995 MB -> 65.835 MB. [2025-10-31T15:48:05.310Z] ====== als (apache-spark) [default], iteration 7 completed (7289.174 ms) ====== [2025-10-31T15:48:05.310Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-10-31T15:48:05.310Z] GC before operation: completed in 122.592 ms, heap usage 101.232 MB -> 65.979 MB. [2025-10-31T15:48:13.281Z] ====== als (apache-spark) [default], iteration 8 completed (7386.472 ms) ====== [2025-10-31T15:48:13.281Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-10-31T15:48:13.281Z] GC before operation: completed in 103.046 ms, heap usage 491.353 MB -> 66.914 MB. [2025-10-31T15:48:19.869Z] ====== als (apache-spark) [default], iteration 9 completed (7122.705 ms) ====== [2025-10-31T15:48:19.869Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-10-31T15:48:20.597Z] GC before operation: completed in 101.367 ms, heap usage 319.433 MB -> 66.936 MB. [2025-10-31T15:48:27.816Z] ====== als (apache-spark) [default], iteration 10 completed (7000.015 ms) ====== [2025-10-31T15:48:27.816Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-10-31T15:48:27.816Z] GC before operation: completed in 118.793 ms, heap usage 163.003 MB -> 67.008 MB. [2025-10-31T15:48:35.813Z] ====== als (apache-spark) [default], iteration 11 completed (7146.454 ms) ====== [2025-10-31T15:48:35.813Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-10-31T15:48:35.813Z] GC before operation: completed in 94.518 ms, heap usage 322.347 MB -> 67.469 MB. [2025-10-31T15:48:42.418Z] ====== als (apache-spark) [default], iteration 12 completed (6657.479 ms) ====== [2025-10-31T15:48:42.418Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-10-31T15:48:42.418Z] GC before operation: completed in 99.724 ms, heap usage 189.446 MB -> 67.482 MB. [2025-10-31T15:48:49.026Z] ====== als (apache-spark) [default], iteration 13 completed (6953.407 ms) ====== [2025-10-31T15:48:49.027Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-10-31T15:48:49.027Z] GC before operation: completed in 96.336 ms, heap usage 74.654 MB -> 67.817 MB. [2025-10-31T15:48:55.614Z] ====== als (apache-spark) [default], iteration 14 completed (6607.605 ms) ====== [2025-10-31T15:48:55.614Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-10-31T15:48:55.614Z] GC before operation: completed in 102.694 ms, heap usage 354.051 MB -> 68.310 MB. [2025-10-31T15:49:03.578Z] ====== als (apache-spark) [default], iteration 15 completed (6759.627 ms) ====== [2025-10-31T15:49:03.578Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-10-31T15:49:03.578Z] GC before operation: completed in 99.386 ms, heap usage 297.858 MB -> 68.609 MB. [2025-10-31T15:49:10.174Z] ====== als (apache-spark) [default], iteration 16 completed (6678.793 ms) ====== [2025-10-31T15:49:10.174Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-10-31T15:49:10.174Z] GC before operation: completed in 105.270 ms, heap usage 213.182 MB -> 68.494 MB. [2025-10-31T15:49:17.269Z] ====== als (apache-spark) [default], iteration 17 completed (6792.397 ms) ====== [2025-10-31T15:49:17.269Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-10-31T15:49:17.269Z] GC before operation: completed in 104.486 ms, heap usage 384.403 MB -> 68.975 MB. [2025-10-31T15:49:23.851Z] ====== als (apache-spark) [default], iteration 18 completed (6905.390 ms) ====== [2025-10-31T15:49:23.851Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-10-31T15:49:23.851Z] GC before operation: completed in 99.496 ms, heap usage 538.701 MB -> 69.711 MB. [2025-10-31T15:49:31.819Z] ====== als (apache-spark) [default], iteration 19 completed (7172.747 ms) ====== [2025-10-31T15:49:31.819Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-10-31T15:49:31.819Z] GC before operation: completed in 104.428 ms, heap usage 490.178 MB -> 69.788 MB. [2025-10-31T15:49:38.390Z] ====== als (apache-spark) [default], iteration 20 completed (6964.172 ms) ====== [2025-10-31T15:49:38.390Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-10-31T15:49:39.118Z] GC before operation: completed in 110.778 ms, heap usage 386.954 MB -> 69.835 MB. [2025-10-31T15:49:47.066Z] ====== als (apache-spark) [default], iteration 21 completed (7188.701 ms) ====== [2025-10-31T15:49:47.066Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-10-31T15:49:47.066Z] GC before operation: completed in 114.876 ms, heap usage 187.283 MB -> 69.800 MB. [2025-10-31T15:49:53.647Z] ====== als (apache-spark) [default], iteration 22 completed (6661.359 ms) ====== [2025-10-31T15:49:53.647Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-10-31T15:49:53.647Z] GC before operation: completed in 100.331 ms, heap usage 429.464 MB -> 70.529 MB. [2025-10-31T15:50:00.346Z] ====== als (apache-spark) [default], iteration 23 completed (6769.194 ms) ====== [2025-10-31T15:50:00.346Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-10-31T15:50:00.346Z] GC before operation: completed in 111.382 ms, heap usage 518.841 MB -> 70.770 MB. [2025-10-31T15:50:06.937Z] ====== als (apache-spark) [default], iteration 24 completed (6941.480 ms) ====== [2025-10-31T15:50:06.937Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-10-31T15:50:07.664Z] GC before operation: completed in 115.716 ms, heap usage 258.866 MB -> 70.770 MB. [2025-10-31T15:50:14.758Z] ====== als (apache-spark) [default], iteration 25 completed (6630.896 ms) ====== [2025-10-31T15:50:14.758Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-10-31T15:50:14.758Z] GC before operation: completed in 101.241 ms, heap usage 273.152 MB -> 71.016 MB. [2025-10-31T15:50:21.346Z] ====== als (apache-spark) [default], iteration 26 completed (7104.606 ms) ====== [2025-10-31T15:50:21.346Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-10-31T15:50:21.346Z] GC before operation: completed in 103.713 ms, heap usage 226.261 MB -> 71.244 MB. [2025-10-31T15:50:29.316Z] ====== als (apache-spark) [default], iteration 27 completed (7704.369 ms) ====== [2025-10-31T15:50:29.316Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-10-31T15:50:29.316Z] GC before operation: completed in 103.096 ms, heap usage 496.578 MB -> 71.899 MB. [2025-10-31T15:50:35.907Z] ====== als (apache-spark) [default], iteration 28 completed (6387.301 ms) ====== [2025-10-31T15:50:35.907Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-10-31T15:50:35.907Z] GC before operation: completed in 92.568 ms, heap usage 390.666 MB -> 71.940 MB. [2025-10-31T15:50:43.862Z] ====== als (apache-spark) [default], iteration 29 completed (6574.445 ms) ====== [2025-10-31T15:50:43.862Z] ----------------------------------- [2025-10-31T15:50:43.862Z] renaissance-als_0_PASSED [2025-10-31T15:50:43.862Z] ----------------------------------- [2025-10-31T15:50:43.862Z] [2025-10-31T15:50:43.862Z] TEST TEARDOWN: [2025-10-31T15:50:43.862Z] Nothing to be done for teardown. [2025-10-31T15:50:43.862Z] renaissance-als_0 Finish Time: Fri Oct 31 15:50:42 2025 Epoch Time (ms): 1761925842875