[2025-12-15T13:16:33.723Z] Running test renaissance-als_0 ... [2025-12-15T13:16:33.723Z] =============================================== [2025-12-15T13:16:33.723Z] renaissance-als_0 Start Time: Mon Dec 15 13:16:33 2025 Epoch Time (ms): 1765804593568 [2025-12-15T13:16:33.723Z] variation: NoOptions [2025-12-15T13:16:33.723Z] JVM_OPTIONS: [2025-12-15T13:16:33.723Z] { \ [2025-12-15T13:16:33.723Z] echo ""; echo "TEST SETUP:"; \ [2025-12-15T13:16:33.723Z] echo "Nothing to be done for setup."; \ [2025-12-15T13:16:33.723Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17658044159558/renaissance-als_0"; \ [2025-12-15T13:16:33.723Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17658044159558/renaissance-als_0"; \ [2025-12-15T13:16:33.723Z] echo ""; echo "TESTING:"; \ [2025-12-15T13:16:33.723Z] "/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_17658044159558/renaissance-als_0"/als.json" als; \ [2025-12-15T13:16:33.723Z] 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_17658044159558/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-15T13:16:33.723Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-15T13:16:33.723Z] echo "Nothing to be done for teardown."; \ [2025-12-15T13:16:33.723Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17658044159558/TestTargetResult"; [2025-12-15T13:16:33.723Z] [2025-12-15T13:16:33.723Z] TEST SETUP: [2025-12-15T13:16:33.723Z] Nothing to be done for setup. [2025-12-15T13:16:33.723Z] [2025-12-15T13:16:33.723Z] TESTING: [2025-12-15T13:16:34.370Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-15T13:16:34.370Z] 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_17658044159558/renaissance-als_0/launcher-131633-8159732683670374240/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-15T13:16:34.370Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-15T13:16:34.370Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-15T13:16:42.941Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-12-15T13:16:46.519Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-15T13:16:46.519Z] GC before operation: completed in 58.054 ms, heap usage 161.516 MB -> 35.034 MB. [2025-12-15T13:17:13.823Z] ====== als (apache-spark) [default], iteration 0 completed (25733.078 ms) ====== [2025-12-15T13:17:13.823Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-15T13:17:13.823Z] GC before operation: completed in 98.545 ms, heap usage 145.501 MB -> 61.387 MB. [2025-12-15T13:17:26.544Z] ====== als (apache-spark) [default], iteration 1 completed (12370.304 ms) ====== [2025-12-15T13:17:26.545Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-15T13:17:26.545Z] GC before operation: completed in 91.581 ms, heap usage 439.957 MB -> 62.828 MB. [2025-12-15T13:17:37.038Z] ====== als (apache-spark) [default], iteration 2 completed (10998.268 ms) ====== [2025-12-15T13:17:37.038Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-15T13:17:37.038Z] GC before operation: completed in 90.597 ms, heap usage 101.077 MB -> 63.090 MB. [2025-12-15T13:17:49.797Z] ====== als (apache-spark) [default], iteration 3 completed (11631.027 ms) ====== [2025-12-15T13:17:49.797Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-15T13:17:49.797Z] GC before operation: completed in 92.379 ms, heap usage 262.790 MB -> 63.562 MB. [2025-12-15T13:18:00.231Z] ====== als (apache-spark) [default], iteration 4 completed (10532.407 ms) ====== [2025-12-15T13:18:00.231Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-15T13:18:00.231Z] GC before operation: completed in 98.717 ms, heap usage 125.048 MB -> 64.024 MB. [2025-12-15T13:18:10.794Z] ====== als (apache-spark) [default], iteration 5 completed (10186.595 ms) ====== [2025-12-15T13:18:10.794Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-15T13:18:10.794Z] GC before operation: completed in 96.961 ms, heap usage 417.585 MB -> 64.964 MB. [2025-12-15T13:18:21.231Z] ====== als (apache-spark) [default], iteration 6 completed (9947.217 ms) ====== [2025-12-15T13:18:21.231Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-15T13:18:21.231Z] GC before operation: completed in 93.169 ms, heap usage 170.921 MB -> 65.054 MB. [2025-12-15T13:18:31.687Z] ====== als (apache-spark) [default], iteration 7 completed (10227.160 ms) ====== [2025-12-15T13:18:31.687Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-15T13:18:31.687Z] GC before operation: completed in 98.542 ms, heap usage 105.076 MB -> 65.665 MB. [2025-12-15T13:18:42.185Z] ====== als (apache-spark) [default], iteration 8 completed (9919.942 ms) ====== [2025-12-15T13:18:42.185Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-15T13:18:42.185Z] GC before operation: completed in 102.382 ms, heap usage 365.191 MB -> 66.415 MB. [2025-12-15T13:18:52.628Z] ====== als (apache-spark) [default], iteration 9 completed (9852.744 ms) ====== [2025-12-15T13:18:52.628Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-15T13:18:52.628Z] GC before operation: completed in 116.778 ms, heap usage 241.359 MB -> 66.604 MB. [2025-12-15T13:19:03.080Z] ====== als (apache-spark) [default], iteration 10 completed (9845.768 ms) ====== [2025-12-15T13:19:03.080Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-15T13:19:03.080Z] GC before operation: completed in 104.560 ms, heap usage 116.435 MB -> 66.659 MB. [2025-12-15T13:19:13.518Z] ====== als (apache-spark) [default], iteration 11 completed (9887.507 ms) ====== [2025-12-15T13:19:13.518Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-15T13:19:13.518Z] GC before operation: completed in 102.124 ms, heap usage 258.193 MB -> 67.009 MB. [2025-12-15T13:19:22.109Z] ====== als (apache-spark) [default], iteration 12 completed (9796.311 ms) ====== [2025-12-15T13:19:22.109Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-15T13:19:22.109Z] GC before operation: completed in 101.220 ms, heap usage 248.681 MB -> 67.317 MB. [2025-12-15T13:19:32.635Z] ====== als (apache-spark) [default], iteration 13 completed (9807.097 ms) ====== [2025-12-15T13:19:32.635Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-15T13:19:32.635Z] GC before operation: completed in 104.885 ms, heap usage 381.382 MB -> 67.663 MB. [2025-12-15T13:19:43.081Z] ====== als (apache-spark) [default], iteration 14 completed (9789.807 ms) ====== [2025-12-15T13:19:43.081Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-15T13:19:43.081Z] GC before operation: completed in 105.576 ms, heap usage 500.444 MB -> 68.080 MB. [2025-12-15T13:19:53.573Z] ====== als (apache-spark) [default], iteration 15 completed (9557.191 ms) ====== [2025-12-15T13:19:53.573Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-15T13:19:53.573Z] GC before operation: completed in 108.763 ms, heap usage 228.978 MB -> 68.118 MB. [2025-12-15T13:20:02.177Z] ====== als (apache-spark) [default], iteration 16 completed (9705.802 ms) ====== [2025-12-15T13:20:02.177Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-15T13:20:02.177Z] GC before operation: completed in 166.767 ms, heap usage 507.362 MB -> 68.615 MB. [2025-12-15T13:20:12.640Z] ====== als (apache-spark) [default], iteration 17 completed (9725.875 ms) ====== [2025-12-15T13:20:12.640Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-15T13:20:12.640Z] GC before operation: completed in 106.195 ms, heap usage 460.521 MB -> 68.785 MB. [2025-12-15T13:20:23.080Z] ====== als (apache-spark) [default], iteration 18 completed (9636.284 ms) ====== [2025-12-15T13:20:23.080Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-15T13:20:23.080Z] GC before operation: completed in 110.519 ms, heap usage 502.474 MB -> 69.167 MB. [2025-12-15T13:20:33.535Z] ====== als (apache-spark) [default], iteration 19 completed (9695.830 ms) ====== [2025-12-15T13:20:33.535Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-15T13:20:33.535Z] GC before operation: completed in 106.330 ms, heap usage 351.725 MB -> 69.165 MB. [2025-12-15T13:20:42.075Z] ====== als (apache-spark) [default], iteration 20 completed (9635.310 ms) ====== [2025-12-15T13:20:42.075Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-15T13:20:42.075Z] GC before operation: completed in 108.111 ms, heap usage 475.303 MB -> 69.700 MB. [2025-12-15T13:20:52.508Z] ====== als (apache-spark) [default], iteration 21 completed (10116.925 ms) ====== [2025-12-15T13:20:52.508Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-15T13:20:52.508Z] GC before operation: completed in 109.364 ms, heap usage 227.026 MB -> 69.615 MB. [2025-12-15T13:21:02.952Z] ====== als (apache-spark) [default], iteration 22 completed (9505.408 ms) ====== [2025-12-15T13:21:02.952Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-15T13:21:02.952Z] GC before operation: completed in 107.733 ms, heap usage 214.591 MB -> 69.678 MB. [2025-12-15T13:21:13.396Z] ====== als (apache-spark) [default], iteration 23 completed (9752.352 ms) ====== [2025-12-15T13:21:13.396Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-15T13:21:13.396Z] GC before operation: completed in 107.019 ms, heap usage 337.573 MB -> 70.203 MB. [2025-12-15T13:21:23.952Z] ====== als (apache-spark) [default], iteration 24 completed (9768.212 ms) ====== [2025-12-15T13:21:23.952Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-15T13:21:23.952Z] GC before operation: completed in 110.276 ms, heap usage 502.006 MB -> 70.719 MB. [2025-12-15T13:21:32.529Z] ====== als (apache-spark) [default], iteration 25 completed (9632.470 ms) ====== [2025-12-15T13:21:32.529Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-15T13:21:32.529Z] GC before operation: completed in 110.813 ms, heap usage 498.305 MB -> 71.028 MB. [2025-12-15T13:21:42.966Z] ====== als (apache-spark) [default], iteration 26 completed (9598.444 ms) ====== [2025-12-15T13:21:42.966Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-15T13:21:42.966Z] GC before operation: completed in 112.457 ms, heap usage 336.084 MB -> 71.126 MB. [2025-12-15T13:21:53.404Z] ====== als (apache-spark) [default], iteration 27 completed (9511.858 ms) ====== [2025-12-15T13:21:53.404Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-15T13:21:53.404Z] GC before operation: completed in 107.603 ms, heap usage 322.716 MB -> 71.247 MB. [2025-12-15T13:22:01.952Z] ====== als (apache-spark) [default], iteration 28 completed (9664.738 ms) ====== [2025-12-15T13:22:01.952Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-15T13:22:01.952Z] GC before operation: completed in 115.913 ms, heap usage 367.736 MB -> 71.493 MB. [2025-12-15T13:22:12.410Z] ====== als (apache-spark) [default], iteration 29 completed (9462.088 ms) ====== [2025-12-15T13:22:12.410Z] ----------------------------------- [2025-12-15T13:22:12.410Z] renaissance-als_0_PASSED [2025-12-15T13:22:12.410Z] ----------------------------------- [2025-12-15T13:22:12.410Z] [2025-12-15T13:22:12.410Z] TEST TEARDOWN: [2025-12-15T13:22:12.410Z] Nothing to be done for teardown. [2025-12-15T13:22:12.410Z] renaissance-als_0 Finish Time: Mon Dec 15 13:22:11 2025 Epoch Time (ms): 1765804931758