[2026-02-12T22:28:34.601Z] Running test renaissance-als_0 ... [2026-02-12T22:28:34.602Z] =============================================== [2026-02-12T22:28:34.602Z] renaissance-als_0 Start Time: Thu Feb 12 22:28:34 2026 Epoch Time (ms): 1770935314554 [2026-02-12T22:28:34.602Z] variation: NoOptions [2026-02-12T22:28:34.602Z] JVM_OPTIONS: [2026-02-12T22:28:34.602Z] { \ [2026-02-12T22:28:34.602Z] echo ""; echo "TEST SETUP:"; \ [2026-02-12T22:28:34.602Z] echo "Nothing to be done for setup."; \ [2026-02-12T22:28:34.602Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17709351957367/renaissance-als_0"; \ [2026-02-12T22:28:34.602Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17709351957367/renaissance-als_0"; \ [2026-02-12T22:28:34.602Z] echo ""; echo "TESTING:"; \ [2026-02-12T22:28:34.602Z] "/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_17709351957367/renaissance-als_0"/als.json" als; \ [2026-02-12T22:28:34.602Z] 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_17709351957367/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-12T22:28:34.602Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-12T22:28:34.602Z] echo "Nothing to be done for teardown."; \ [2026-02-12T22:28:34.602Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17709351957367/TestTargetResult"; [2026-02-12T22:28:34.602Z] [2026-02-12T22:28:34.602Z] TEST SETUP: [2026-02-12T22:28:34.602Z] Nothing to be done for setup. [2026-02-12T22:28:34.602Z] [2026-02-12T22:28:34.602Z] TESTING: [2026-02-12T22:28:35.308Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-02-12T22:28:35.308Z] 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_17709351957367/renaissance-als_0/launcher-222834-9919948796482577521/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-02-12T22:28:35.308Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-02-12T22:28:35.308Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-02-12T22:28:42.578Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-02-12T22:28:45.531Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-12T22:28:45.861Z] GC before operation: completed in 62.662 ms, heap usage 77.197 MB -> 35.176 MB. [2026-02-12T22:29:05.018Z] ====== als (apache-spark) [default], iteration 0 completed (15663.506 ms) ====== [2026-02-12T22:29:05.018Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-12T22:29:05.018Z] GC before operation: completed in 106.128 ms, heap usage 262.459 MB -> 62.062 MB. [2026-02-12T22:29:13.918Z] ====== als (apache-spark) [default], iteration 1 completed (11339.176 ms) ====== [2026-02-12T22:29:13.918Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-12T22:29:13.918Z] GC before operation: completed in 138.015 ms, heap usage 624.525 MB -> 63.228 MB. [2026-02-12T22:29:21.213Z] ====== als (apache-spark) [default], iteration 2 completed (6522.605 ms) ====== [2026-02-12T22:29:21.213Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-12T22:29:21.213Z] GC before operation: completed in 102.538 ms, heap usage 199.832 MB -> 63.236 MB. [2026-02-12T22:29:28.487Z] ====== als (apache-spark) [default], iteration 3 completed (6617.626 ms) ====== [2026-02-12T22:29:28.487Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-12T22:29:28.487Z] GC before operation: completed in 111.341 ms, heap usage 446.347 MB -> 64.339 MB. [2026-02-12T22:29:34.411Z] ====== als (apache-spark) [default], iteration 4 completed (6304.214 ms) ====== [2026-02-12T22:29:34.411Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-12T22:29:34.411Z] GC before operation: completed in 115.707 ms, heap usage 160.761 MB -> 64.501 MB. [2026-02-12T22:29:41.682Z] ====== als (apache-spark) [default], iteration 5 completed (6282.190 ms) ====== [2026-02-12T22:29:41.682Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-12T22:29:41.682Z] GC before operation: completed in 113.486 ms, heap usage 365.515 MB -> 65.049 MB. [2026-02-12T22:29:47.588Z] ====== als (apache-spark) [default], iteration 6 completed (6173.952 ms) ====== [2026-02-12T22:29:47.588Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-12T22:29:47.588Z] GC before operation: completed in 100.116 ms, heap usage 470.518 MB -> 65.925 MB. [2026-02-12T22:29:53.499Z] ====== als (apache-spark) [default], iteration 7 completed (6203.326 ms) ====== [2026-02-12T22:29:53.499Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-12T22:29:53.828Z] GC before operation: completed in 113.739 ms, heap usage 1.525 GB -> 67.603 MB. [2026-02-12T22:29:59.734Z] ====== als (apache-spark) [default], iteration 8 completed (5868.166 ms) ====== [2026-02-12T22:29:59.734Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-12T22:30:00.063Z] GC before operation: completed in 118.779 ms, heap usage 425.568 MB -> 67.017 MB. [2026-02-12T22:30:06.022Z] ====== als (apache-spark) [default], iteration 9 completed (5979.198 ms) ====== [2026-02-12T22:30:06.022Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-12T22:30:06.022Z] GC before operation: completed in 106.981 ms, heap usage 1.394 GB -> 68.348 MB. [2026-02-12T22:30:13.280Z] ====== als (apache-spark) [default], iteration 10 completed (6079.376 ms) ====== [2026-02-12T22:30:13.280Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-12T22:30:13.280Z] GC before operation: completed in 104.307 ms, heap usage 219.694 MB -> 67.028 MB. [2026-02-12T22:30:19.229Z] ====== als (apache-spark) [default], iteration 11 completed (6050.287 ms) ====== [2026-02-12T22:30:19.229Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-12T22:30:19.229Z] GC before operation: completed in 100.117 ms, heap usage 351.862 MB -> 67.499 MB. [2026-02-12T22:30:25.138Z] ====== als (apache-spark) [default], iteration 12 completed (5838.643 ms) ====== [2026-02-12T22:30:25.138Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-12T22:30:25.138Z] GC before operation: completed in 101.607 ms, heap usage 370.193 MB -> 67.644 MB. [2026-02-12T22:30:31.050Z] ====== als (apache-spark) [default], iteration 13 completed (5916.123 ms) ====== [2026-02-12T22:30:31.050Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-12T22:30:31.050Z] GC before operation: completed in 123.478 ms, heap usage 242.318 MB -> 67.686 MB. [2026-02-12T22:30:38.315Z] ====== als (apache-spark) [default], iteration 14 completed (5953.591 ms) ====== [2026-02-12T22:30:38.315Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-12T22:30:38.315Z] GC before operation: completed in 105.849 ms, heap usage 128.424 MB -> 68.156 MB. [2026-02-12T22:30:44.207Z] ====== als (apache-spark) [default], iteration 15 completed (5844.460 ms) ====== [2026-02-12T22:30:44.207Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-12T22:30:44.207Z] GC before operation: completed in 104.509 ms, heap usage 161.342 MB -> 68.394 MB. [2026-02-12T22:30:50.125Z] ====== als (apache-spark) [default], iteration 16 completed (5897.217 ms) ====== [2026-02-12T22:30:50.125Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-12T22:30:50.125Z] GC before operation: completed in 125.666 ms, heap usage 344.393 MB -> 68.741 MB. [2026-02-12T22:30:56.045Z] ====== als (apache-spark) [default], iteration 17 completed (6089.741 ms) ====== [2026-02-12T22:30:56.045Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-12T22:30:56.045Z] GC before operation: completed in 104.689 ms, heap usage 450.675 MB -> 69.261 MB. [2026-02-12T22:31:02.175Z] ====== als (apache-spark) [default], iteration 18 completed (6003.057 ms) ====== [2026-02-12T22:31:02.175Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-12T22:31:02.175Z] GC before operation: completed in 118.849 ms, heap usage 1.468 GB -> 70.562 MB. [2026-02-12T22:31:09.450Z] ====== als (apache-spark) [default], iteration 19 completed (6182.458 ms) ====== [2026-02-12T22:31:09.450Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-12T22:31:09.450Z] GC before operation: completed in 118.925 ms, heap usage 449.137 MB -> 69.727 MB. [2026-02-12T22:31:15.403Z] ====== als (apache-spark) [default], iteration 20 completed (6070.136 ms) ====== [2026-02-12T22:31:15.403Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-12T22:31:15.403Z] GC before operation: completed in 108.076 ms, heap usage 141.218 MB -> 69.731 MB. [2026-02-12T22:31:21.319Z] ====== als (apache-spark) [default], iteration 21 completed (5913.937 ms) ====== [2026-02-12T22:31:21.319Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-12T22:31:21.319Z] GC before operation: completed in 116.757 ms, heap usage 467.221 MB -> 70.338 MB. [2026-02-12T22:31:27.236Z] ====== als (apache-spark) [default], iteration 22 completed (6013.661 ms) ====== [2026-02-12T22:31:27.236Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-12T22:31:27.236Z] GC before operation: completed in 113.608 ms, heap usage 449.242 MB -> 70.683 MB. [2026-02-12T22:31:34.514Z] ====== als (apache-spark) [default], iteration 23 completed (5891.088 ms) ====== [2026-02-12T22:31:34.514Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-12T22:31:34.514Z] GC before operation: completed in 114.496 ms, heap usage 422.903 MB -> 70.500 MB. [2026-02-12T22:31:40.453Z] ====== als (apache-spark) [default], iteration 24 completed (6065.142 ms) ====== [2026-02-12T22:31:40.453Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-12T22:31:40.453Z] GC before operation: completed in 114.825 ms, heap usage 442.848 MB -> 70.987 MB. [2026-02-12T22:31:46.374Z] ====== als (apache-spark) [default], iteration 25 completed (5856.926 ms) ====== [2026-02-12T22:31:46.374Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-12T22:31:46.374Z] GC before operation: completed in 117.481 ms, heap usage 224.241 MB -> 70.835 MB. [2026-02-12T22:31:52.285Z] ====== als (apache-spark) [default], iteration 26 completed (5867.004 ms) ====== [2026-02-12T22:31:52.285Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-12T22:31:52.285Z] GC before operation: completed in 115.398 ms, heap usage 528.632 MB -> 71.644 MB. [2026-02-12T22:31:58.199Z] ====== als (apache-spark) [default], iteration 27 completed (5765.520 ms) ====== [2026-02-12T22:31:58.199Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-12T22:31:58.199Z] GC before operation: completed in 116.952 ms, heap usage 575.861 MB -> 71.447 MB. [2026-02-12T22:32:04.109Z] ====== als (apache-spark) [default], iteration 28 completed (5667.598 ms) ====== [2026-02-12T22:32:04.109Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-12T22:32:04.109Z] GC before operation: completed in 119.329 ms, heap usage 257.950 MB -> 71.281 MB. [2026-02-12T22:32:10.126Z] ====== als (apache-spark) [default], iteration 29 completed (5710.585 ms) ====== [2026-02-12T22:32:10.456Z] ----------------------------------- [2026-02-12T22:32:10.457Z] renaissance-als_0_PASSED [2026-02-12T22:32:10.457Z] ----------------------------------- [2026-02-12T22:32:10.457Z] [2026-02-12T22:32:10.457Z] TEST TEARDOWN: [2026-02-12T22:32:10.457Z] Nothing to be done for teardown. [2026-02-12T22:32:10.457Z] renaissance-als_0 Finish Time: Thu Feb 12 22:32:10 2026 Epoch Time (ms): 1770935530282