[2026-03-05T23:50:56.588Z] Running test renaissance-als_0 ... [2026-03-05T23:50:56.588Z] =============================================== [2026-03-05T23:50:56.588Z] renaissance-als_0 Start Time: Thu Mar 5 23:50:56 2026 Epoch Time (ms): 1772754656510 [2026-03-05T23:50:56.588Z] variation: NoOptions [2026-03-05T23:50:56.588Z] JVM_OPTIONS: [2026-03-05T23:50:56.588Z] { \ [2026-03-05T23:50:56.588Z] echo ""; echo "TEST SETUP:"; \ [2026-03-05T23:50:56.588Z] echo "Nothing to be done for setup."; \ [2026-03-05T23:50:56.588Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_1772754458636/renaissance-als_0"; \ [2026-03-05T23:50:56.588Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_1772754458636/renaissance-als_0"; \ [2026-03-05T23:50:56.588Z] echo ""; echo "TESTING:"; \ [2026-03-05T23:50:56.588Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_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_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_1772754458636/renaissance-als_0"/als.json" als; \ [2026-03-05T23:50:56.588Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_1772754458636/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-03-05T23:50:56.588Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-03-05T23:50:56.588Z] echo "Nothing to be done for teardown."; \ [2026-03-05T23:50:56.588Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_1772754458636/TestTargetResult"; [2026-03-05T23:50:56.588Z] [2026-03-05T23:50:56.588Z] TEST SETUP: [2026-03-05T23:50:56.588Z] Nothing to be done for setup. [2026-03-05T23:50:56.588Z] [2026-03-05T23:50:56.588Z] TESTING: [2026-03-05T23:50:57.321Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-03-05T23:50:57.321Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/output_1772754458636/renaissance-als_0/launcher-235056-3586596422971189181/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-03-05T23:50:57.321Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-03-05T23:50:57.321Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-03-05T23:51:03.319Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-03-05T23:51:07.178Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-03-05T23:51:07.178Z] GC before operation: completed in 90.298 ms, heap usage 152.016 MB -> 35.005 MB. [2026-03-05T23:51:40.932Z] ====== als (apache-spark) [default], iteration 0 completed (29589.017 ms) ====== [2026-03-05T23:51:40.932Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-03-05T23:51:40.932Z] GC before operation: completed in 146.662 ms, heap usage 156.107 MB -> 61.568 MB. [2026-03-05T23:51:54.311Z] ====== als (apache-spark) [default], iteration 1 completed (14370.767 ms) ====== [2026-03-05T23:51:54.311Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-03-05T23:51:54.311Z] GC before operation: completed in 109.251 ms, heap usage 99.398 MB -> 62.305 MB. [2026-03-05T23:52:07.634Z] ====== als (apache-spark) [default], iteration 2 completed (13331.560 ms) ====== [2026-03-05T23:52:07.635Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-03-05T23:52:07.635Z] GC before operation: completed in 106.212 ms, heap usage 80.401 MB -> 62.949 MB. [2026-03-05T23:52:20.955Z] ====== als (apache-spark) [default], iteration 3 completed (13409.433 ms) ====== [2026-03-05T23:52:20.955Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-03-05T23:52:20.955Z] GC before operation: completed in 112.307 ms, heap usage 326.480 MB -> 63.738 MB. [2026-03-05T23:52:34.209Z] ====== als (apache-spark) [default], iteration 4 completed (12587.714 ms) ====== [2026-03-05T23:52:34.209Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-03-05T23:52:34.209Z] GC before operation: completed in 110.413 ms, heap usage 194.507 MB -> 64.116 MB. [2026-03-05T23:52:47.522Z] ====== als (apache-spark) [default], iteration 5 completed (12289.846 ms) ====== [2026-03-05T23:52:47.522Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-03-05T23:52:47.522Z] GC before operation: completed in 113.901 ms, heap usage 447.464 MB -> 65.176 MB. [2026-03-05T23:52:58.604Z] ====== als (apache-spark) [default], iteration 6 completed (12090.114 ms) ====== [2026-03-05T23:52:58.605Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-03-05T23:52:58.605Z] GC before operation: completed in 122.270 ms, heap usage 503.278 MB -> 65.647 MB. [2026-03-05T23:53:11.881Z] ====== als (apache-spark) [default], iteration 7 completed (12566.977 ms) ====== [2026-03-05T23:53:11.882Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-03-05T23:53:11.882Z] GC before operation: completed in 117.858 ms, heap usage 323.441 MB -> 65.950 MB. [2026-03-05T23:53:25.170Z] ====== als (apache-spark) [default], iteration 8 completed (12058.595 ms) ====== [2026-03-05T23:53:25.171Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-03-05T23:53:25.171Z] GC before operation: completed in 116.291 ms, heap usage 111.306 MB -> 66.344 MB. [2026-03-05T23:53:36.165Z] ====== als (apache-spark) [default], iteration 9 completed (11797.226 ms) ====== [2026-03-05T23:53:36.165Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-03-05T23:53:36.165Z] GC before operation: completed in 124.219 ms, heap usage 310.383 MB -> 66.767 MB. [2026-03-05T23:53:49.488Z] ====== als (apache-spark) [default], iteration 10 completed (12080.918 ms) ====== [2026-03-05T23:53:49.488Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-03-05T23:53:49.488Z] GC before operation: completed in 129.841 ms, heap usage 447.412 MB -> 67.195 MB. [2026-03-05T23:54:00.442Z] ====== als (apache-spark) [default], iteration 11 completed (11682.535 ms) ====== [2026-03-05T23:54:00.442Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-03-05T23:54:00.442Z] GC before operation: completed in 122.404 ms, heap usage 370.400 MB -> 67.234 MB. [2026-03-05T23:54:13.750Z] ====== als (apache-spark) [default], iteration 12 completed (11660.908 ms) ====== [2026-03-05T23:54:13.750Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-03-05T23:54:13.750Z] GC before operation: completed in 123.500 ms, heap usage 204.076 MB -> 67.423 MB. [2026-03-05T23:54:24.837Z] ====== als (apache-spark) [default], iteration 13 completed (11925.701 ms) ====== [2026-03-05T23:54:24.837Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-03-05T23:54:24.837Z] GC before operation: completed in 118.687 ms, heap usage 123.640 MB -> 67.555 MB. [2026-03-05T23:54:38.174Z] ====== als (apache-spark) [default], iteration 14 completed (11664.417 ms) ====== [2026-03-05T23:54:38.174Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-03-05T23:54:38.174Z] GC before operation: completed in 125.320 ms, heap usage 214.998 MB -> 67.934 MB. [2026-03-05T23:54:49.262Z] ====== als (apache-spark) [default], iteration 15 completed (11505.129 ms) ====== [2026-03-05T23:54:49.262Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-03-05T23:54:49.262Z] GC before operation: completed in 127.329 ms, heap usage 482.542 MB -> 68.574 MB. [2026-03-05T23:55:00.216Z] ====== als (apache-spark) [default], iteration 16 completed (11658.951 ms) ====== [2026-03-05T23:55:00.216Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-03-05T23:55:00.558Z] GC before operation: completed in 132.938 ms, heap usage 294.539 MB -> 68.337 MB. [2026-03-05T23:55:14.000Z] ====== als (apache-spark) [default], iteration 17 completed (11654.807 ms) ====== [2026-03-05T23:55:14.000Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-03-05T23:55:14.000Z] GC before operation: completed in 126.199 ms, heap usage 318.953 MB -> 68.772 MB. [2026-03-05T23:55:24.963Z] ====== als (apache-spark) [default], iteration 18 completed (11784.531 ms) ====== [2026-03-05T23:55:24.963Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-03-05T23:55:24.963Z] GC before operation: completed in 125.578 ms, heap usage 214.503 MB -> 68.836 MB. [2026-03-05T23:55:37.981Z] ====== als (apache-spark) [default], iteration 19 completed (11679.255 ms) ====== [2026-03-05T23:55:37.981Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-03-05T23:55:37.981Z] GC before operation: completed in 126.591 ms, heap usage 335.298 MB -> 69.293 MB. [2026-03-05T23:55:48.979Z] ====== als (apache-spark) [default], iteration 20 completed (12087.902 ms) ====== [2026-03-05T23:55:48.979Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-03-05T23:55:48.979Z] GC before operation: completed in 221.466 ms, heap usage 154.752 MB -> 69.327 MB. [2026-03-05T23:56:02.356Z] ====== als (apache-spark) [default], iteration 21 completed (11964.788 ms) ====== [2026-03-05T23:56:02.357Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-03-05T23:56:02.357Z] GC before operation: completed in 129.500 ms, heap usage 504.865 MB -> 70.084 MB. [2026-03-05T23:56:13.380Z] ====== als (apache-spark) [default], iteration 22 completed (11825.114 ms) ====== [2026-03-05T23:56:13.380Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-03-05T23:56:13.380Z] GC before operation: completed in 127.885 ms, heap usage 398.062 MB -> 70.088 MB. [2026-03-05T23:56:29.414Z] ====== als (apache-spark) [default], iteration 23 completed (11729.095 ms) ====== [2026-03-05T23:56:29.414Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-03-05T23:56:29.414Z] GC before operation: completed in 125.260 ms, heap usage 138.241 MB -> 70.136 MB. [2026-03-05T23:56:38.581Z] ====== als (apache-spark) [default], iteration 24 completed (11626.155 ms) ====== [2026-03-05T23:56:38.581Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-03-05T23:56:38.581Z] GC before operation: completed in 128.029 ms, heap usage 426.041 MB -> 70.739 MB. [2026-03-05T23:56:49.646Z] ====== als (apache-spark) [default], iteration 25 completed (11604.779 ms) ====== [2026-03-05T23:56:49.646Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-03-05T23:56:49.646Z] GC before operation: completed in 125.240 ms, heap usage 198.185 MB -> 70.751 MB. [2026-03-05T23:57:03.015Z] ====== als (apache-spark) [default], iteration 26 completed (11692.588 ms) ====== [2026-03-05T23:57:03.015Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-03-05T23:57:03.015Z] GC before operation: completed in 128.224 ms, heap usage 387.044 MB -> 71.081 MB. [2026-03-05T23:57:14.118Z] ====== als (apache-spark) [default], iteration 27 completed (11583.747 ms) ====== [2026-03-05T23:57:14.118Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-03-05T23:57:14.118Z] GC before operation: completed in 127.404 ms, heap usage 248.319 MB -> 71.282 MB. [2026-03-05T23:57:27.511Z] ====== als (apache-spark) [default], iteration 28 completed (11989.554 ms) ====== [2026-03-05T23:57:27.511Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-03-05T23:57:27.511Z] GC before operation: completed in 127.392 ms, heap usage 322.742 MB -> 71.606 MB. [2026-03-05T23:57:38.473Z] ====== als (apache-spark) [default], iteration 29 completed (11364.514 ms) ====== [2026-03-05T23:57:38.473Z] ----------------------------------- [2026-03-05T23:57:38.473Z] renaissance-als_0_PASSED [2026-03-05T23:57:38.473Z] ----------------------------------- [2026-03-05T23:57:38.473Z] [2026-03-05T23:57:38.473Z] TEST TEARDOWN: [2026-03-05T23:57:38.473Z] Nothing to be done for teardown. [2026-03-05T23:57:38.474Z] renaissance-als_0 Finish Time: Thu Mar 5 23:57:37 2026 Epoch Time (ms): 1772755057702