[2026-01-03T13:41:28.856Z] Running test renaissance-als_0 ... [2026-01-03T13:41:28.856Z] =============================================== [2026-01-03T13:41:28.856Z] renaissance-als_0 Start Time: Sat Jan 3 13:41:28 2026 Epoch Time (ms): 1767447688624 [2026-01-03T13:41:28.856Z] variation: NoOptions [2026-01-03T13:41:28.856Z] JVM_OPTIONS: [2026-01-03T13:41:28.856Z] { \ [2026-01-03T13:41:28.856Z] echo ""; echo "TEST SETUP:"; \ [2026-01-03T13:41:28.856Z] echo "Nothing to be done for setup."; \ [2026-01-03T13:41:28.856Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17674476888358/renaissance-als_0"; \ [2026-01-03T13:41:28.856Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17674476888358/renaissance-als_0"; \ [2026-01-03T13:41:28.856Z] echo ""; echo "TESTING:"; \ [2026-01-03T13:41:28.856Z] "/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_17674476888358/renaissance-als_0"/als.json" als; \ [2026-01-03T13:41:28.856Z] 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_17674476888358/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-01-03T13:41:28.856Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-01-03T13:41:28.856Z] echo "Nothing to be done for teardown."; \ [2026-01-03T13:41:28.856Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17674476888358/TestTargetResult"; [2026-01-03T13:41:28.856Z] [2026-01-03T13:41:28.856Z] TEST SETUP: [2026-01-03T13:41:28.856Z] Nothing to be done for setup. [2026-01-03T13:41:28.856Z] [2026-01-03T13:41:28.856Z] TESTING: [2026-01-03T13:41:30.393Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-01-03T13:41:30.393Z] 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_17674476888358/renaissance-als_0/launcher-134128-633099871968921881/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-01-03T13:41:30.393Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-01-03T13:41:30.393Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-01-03T13:41:38.479Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-01-03T13:41:49.911Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-01-03T13:41:49.911Z] GC before operation: completed in 99.975 ms, heap usage 48.359 MB -> 35.002 MB. [2026-01-03T13:42:25.188Z] ====== als (apache-spark) [default], iteration 0 completed (32043.155 ms) ====== [2026-01-03T13:42:25.188Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-01-03T13:42:25.188Z] GC before operation: completed in 165.819 ms, heap usage 259.281 MB -> 61.670 MB. [2026-01-03T13:42:36.816Z] ====== als (apache-spark) [default], iteration 1 completed (12147.626 ms) ====== [2026-01-03T13:42:36.816Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-01-03T13:42:36.816Z] GC before operation: completed in 237.449 ms, heap usage 351.754 MB -> 62.521 MB. [2026-01-03T13:42:48.360Z] ====== als (apache-spark) [default], iteration 2 completed (10901.010 ms) ====== [2026-01-03T13:42:48.360Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-01-03T13:42:48.360Z] GC before operation: completed in 192.092 ms, heap usage 285.643 MB -> 63.021 MB. [2026-01-03T13:42:59.889Z] ====== als (apache-spark) [default], iteration 3 completed (10851.868 ms) ====== [2026-01-03T13:42:59.889Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-01-03T13:42:59.889Z] GC before operation: completed in 171.405 ms, heap usage 108.123 MB -> 63.490 MB. [2026-01-03T13:43:11.609Z] ====== als (apache-spark) [default], iteration 4 completed (11445.630 ms) ====== [2026-01-03T13:43:11.609Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-01-03T13:43:11.609Z] GC before operation: completed in 176.719 ms, heap usage 524.108 MB -> 64.636 MB. [2026-01-03T13:43:23.102Z] ====== als (apache-spark) [default], iteration 5 completed (11875.487 ms) ====== [2026-01-03T13:43:23.102Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-01-03T13:43:23.102Z] GC before operation: completed in 196.116 ms, heap usage 180.883 MB -> 64.675 MB. [2026-01-03T13:43:34.639Z] ====== als (apache-spark) [default], iteration 6 completed (10943.957 ms) ====== [2026-01-03T13:43:34.639Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-01-03T13:43:34.639Z] GC before operation: completed in 225.259 ms, heap usage 463.884 MB -> 65.629 MB. [2026-01-03T13:43:46.110Z] ====== als (apache-spark) [default], iteration 7 completed (10827.346 ms) ====== [2026-01-03T13:43:46.110Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-01-03T13:43:46.110Z] GC before operation: completed in 180.300 ms, heap usage 474.174 MB -> 66.175 MB. [2026-01-03T13:43:59.631Z] ====== als (apache-spark) [default], iteration 8 completed (12433.300 ms) ====== [2026-01-03T13:43:59.631Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-01-03T13:43:59.631Z] GC before operation: completed in 257.607 ms, heap usage 349.606 MB -> 66.474 MB. [2026-01-03T13:44:09.846Z] ====== als (apache-spark) [default], iteration 9 completed (10448.669 ms) ====== [2026-01-03T13:44:09.846Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-01-03T13:44:09.846Z] GC before operation: completed in 183.975 ms, heap usage 184.038 MB -> 66.593 MB. [2026-01-03T13:44:21.310Z] ====== als (apache-spark) [default], iteration 10 completed (9916.423 ms) ====== [2026-01-03T13:44:21.311Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-01-03T13:44:21.311Z] GC before operation: completed in 205.769 ms, heap usage 248.028 MB -> 66.893 MB. [2026-01-03T13:44:31.009Z] ====== als (apache-spark) [default], iteration 11 completed (9795.615 ms) ====== [2026-01-03T13:44:31.009Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-01-03T13:44:31.009Z] GC before operation: completed in 195.501 ms, heap usage 494.481 MB -> 67.502 MB. [2026-01-03T13:44:42.487Z] ====== als (apache-spark) [default], iteration 12 completed (10614.886 ms) ====== [2026-01-03T13:44:42.487Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-01-03T13:44:42.487Z] GC before operation: completed in 209.867 ms, heap usage 386.521 MB -> 67.513 MB. [2026-01-03T13:44:53.970Z] ====== als (apache-spark) [default], iteration 13 completed (11365.726 ms) ====== [2026-01-03T13:44:53.970Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-01-03T13:44:53.970Z] GC before operation: completed in 204.137 ms, heap usage 277.706 MB -> 67.711 MB. [2026-01-03T13:45:07.617Z] ====== als (apache-spark) [default], iteration 14 completed (11821.470 ms) ====== [2026-01-03T13:45:07.617Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-01-03T13:45:07.617Z] GC before operation: completed in 185.695 ms, heap usage 346.867 MB -> 68.144 MB. [2026-01-03T13:45:17.483Z] ====== als (apache-spark) [default], iteration 15 completed (10953.531 ms) ====== [2026-01-03T13:45:17.483Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-01-03T13:45:17.483Z] GC before operation: completed in 198.241 ms, heap usage 355.537 MB -> 68.357 MB. [2026-01-03T13:45:29.002Z] ====== als (apache-spark) [default], iteration 16 completed (10473.164 ms) ====== [2026-01-03T13:45:29.002Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-01-03T13:45:29.002Z] GC before operation: completed in 213.241 ms, heap usage 286.728 MB -> 68.545 MB. [2026-01-03T13:45:40.448Z] ====== als (apache-spark) [default], iteration 17 completed (10409.149 ms) ====== [2026-01-03T13:45:40.448Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-01-03T13:45:40.448Z] GC before operation: completed in 161.084 ms, heap usage 331.517 MB -> 68.974 MB. [2026-01-03T13:45:50.129Z] ====== als (apache-spark) [default], iteration 18 completed (10513.941 ms) ====== [2026-01-03T13:45:50.129Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-01-03T13:45:50.872Z] GC before operation: completed in 213.746 ms, heap usage 529.600 MB -> 69.616 MB. [2026-01-03T13:46:02.550Z] ====== als (apache-spark) [default], iteration 19 completed (10781.008 ms) ====== [2026-01-03T13:46:02.550Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-01-03T13:46:02.550Z] GC before operation: completed in 232.984 ms, heap usage 590.986 MB -> 69.873 MB. [2026-01-03T13:46:14.009Z] ====== als (apache-spark) [default], iteration 20 completed (10886.229 ms) ====== [2026-01-03T13:46:14.009Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-01-03T13:46:14.009Z] GC before operation: completed in 172.281 ms, heap usage 377.886 MB -> 69.755 MB. [2026-01-03T13:46:23.715Z] ====== als (apache-spark) [default], iteration 21 completed (10580.802 ms) ====== [2026-01-03T13:46:23.715Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-01-03T13:46:24.463Z] GC before operation: completed in 183.591 ms, heap usage 330.873 MB -> 69.928 MB. [2026-01-03T13:46:36.171Z] ====== als (apache-spark) [default], iteration 22 completed (11060.082 ms) ====== [2026-01-03T13:46:36.171Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-01-03T13:46:36.171Z] GC before operation: completed in 187.568 ms, heap usage 257.329 MB -> 70.070 MB. [2026-01-03T13:46:47.677Z] ====== als (apache-spark) [default], iteration 23 completed (10888.382 ms) ====== [2026-01-03T13:46:47.677Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-01-03T13:46:47.677Z] GC before operation: completed in 204.299 ms, heap usage 246.640 MB -> 70.389 MB. [2026-01-03T13:46:57.859Z] ====== als (apache-spark) [default], iteration 24 completed (10680.886 ms) ====== [2026-01-03T13:46:57.859Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-01-03T13:46:57.859Z] GC before operation: completed in 224.041 ms, heap usage 385.876 MB -> 70.783 MB. [2026-01-03T13:47:09.338Z] ====== als (apache-spark) [default], iteration 25 completed (11079.955 ms) ====== [2026-01-03T13:47:09.338Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-01-03T13:47:10.083Z] GC before operation: completed in 258.541 ms, heap usage 228.437 MB -> 70.973 MB. [2026-01-03T13:47:21.562Z] ====== als (apache-spark) [default], iteration 26 completed (11167.317 ms) ====== [2026-01-03T13:47:21.562Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-01-03T13:47:21.562Z] GC before operation: completed in 230.366 ms, heap usage 159.909 MB -> 71.110 MB. [2026-01-03T13:47:33.074Z] ====== als (apache-spark) [default], iteration 27 completed (10530.139 ms) ====== [2026-01-03T13:47:33.074Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-01-03T13:47:33.074Z] GC before operation: completed in 216.970 ms, heap usage 202.215 MB -> 70.794 MB. [2026-01-03T13:47:44.536Z] ====== als (apache-spark) [default], iteration 28 completed (11106.346 ms) ====== [2026-01-03T13:47:44.536Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-01-03T13:47:44.536Z] GC before operation: completed in 240.703 ms, heap usage 217.712 MB -> 71.067 MB. [2026-01-03T13:47:54.779Z] ====== als (apache-spark) [default], iteration 29 completed (9843.335 ms) ====== [2026-01-03T13:47:54.779Z] ----------------------------------- [2026-01-03T13:47:54.779Z] renaissance-als_0_PASSED [2026-01-03T13:47:54.779Z] ----------------------------------- [2026-01-03T13:47:54.779Z] [2026-01-03T13:47:54.779Z] TEST TEARDOWN: [2026-01-03T13:47:54.779Z] Nothing to be done for teardown. [2026-01-03T13:47:54.779Z] renaissance-als_0 Finish Time: Sat Jan 3 13:47:54 2026 Epoch Time (ms): 1767448074511