[2026-02-13T00:49:12.351Z] Running test renaissance-als_0 ... [2026-02-13T00:49:12.351Z] =============================================== [2026-02-13T00:49:12.351Z] renaissance-als_0 Start Time: Fri Feb 13 00:49:12 2026 Epoch Time (ms): 1770943752273 [2026-02-13T00:49:12.351Z] variation: NoOptions [2026-02-13T00:49:12.351Z] JVM_OPTIONS: [2026-02-13T00:49:12.351Z] { \ [2026-02-13T00:49:12.351Z] echo ""; echo "TEST SETUP:"; \ [2026-02-13T00:49:12.351Z] echo "Nothing to be done for setup."; \ [2026-02-13T00:49:12.351Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17709437521084/renaissance-als_0"; \ [2026-02-13T00:49:12.351Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17709437521084/renaissance-als_0"; \ [2026-02-13T00:49:12.351Z] echo ""; echo "TESTING:"; \ [2026-02-13T00:49:12.351Z] "/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_17709437521084/renaissance-als_0"/als.json" als; \ [2026-02-13T00:49:12.352Z] 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_17709437521084/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-13T00:49:12.352Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-13T00:49:12.352Z] echo "Nothing to be done for teardown."; \ [2026-02-13T00:49:12.352Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17709437521084/TestTargetResult"; [2026-02-13T00:49:12.352Z] [2026-02-13T00:49:12.352Z] TEST SETUP: [2026-02-13T00:49:12.352Z] Nothing to be done for setup. [2026-02-13T00:49:12.352Z] [2026-02-13T00:49:12.352Z] TESTING: [2026-02-13T00:49:13.106Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-02-13T00:49:13.106Z] 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_17709437521084/renaissance-als_0/launcher-004912-5511884917721329705/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-02-13T00:49:13.106Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-02-13T00:49:13.106Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-02-13T00:49:17.026Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-02-13T00:49:21.413Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-13T00:49:21.413Z] GC before operation: completed in 40.552 ms, heap usage 50.000 MB -> 35.134 MB. [2026-02-13T00:49:40.628Z] ====== als (apache-spark) [default], iteration 0 completed (19250.855 ms) ====== [2026-02-13T00:49:40.628Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-13T00:49:40.628Z] GC before operation: completed in 118.995 ms, heap usage 401.569 MB -> 61.714 MB. [2026-02-13T00:49:48.900Z] ====== als (apache-spark) [default], iteration 1 completed (7879.968 ms) ====== [2026-02-13T00:49:48.900Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-13T00:49:48.900Z] GC before operation: completed in 80.791 ms, heap usage 481.482 MB -> 62.813 MB. [2026-02-13T00:49:57.086Z] ====== als (apache-spark) [default], iteration 2 completed (7124.502 ms) ====== [2026-02-13T00:49:57.086Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-13T00:49:57.086Z] GC before operation: completed in 80.282 ms, heap usage 191.863 MB -> 62.985 MB. [2026-02-13T00:50:05.303Z] ====== als (apache-spark) [default], iteration 3 completed (8178.704 ms) ====== [2026-02-13T00:50:05.303Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-13T00:50:05.303Z] GC before operation: completed in 78.413 ms, heap usage 279.877 MB -> 63.697 MB. [2026-02-13T00:50:12.100Z] ====== als (apache-spark) [default], iteration 4 completed (6975.715 ms) ====== [2026-02-13T00:50:12.100Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-13T00:50:12.100Z] GC before operation: completed in 90.784 ms, heap usage 501.032 MB -> 64.494 MB. [2026-02-13T00:50:20.295Z] ====== als (apache-spark) [default], iteration 5 completed (7566.335 ms) ====== [2026-02-13T00:50:20.295Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-13T00:50:20.295Z] GC before operation: completed in 84.450 ms, heap usage 556.301 MB -> 65.153 MB. [2026-02-13T00:50:27.088Z] ====== als (apache-spark) [default], iteration 6 completed (6787.700 ms) ====== [2026-02-13T00:50:27.088Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-13T00:50:27.088Z] GC before operation: completed in 87.671 ms, heap usage 356.025 MB -> 65.404 MB. [2026-02-13T00:50:33.881Z] ====== als (apache-spark) [default], iteration 7 completed (6687.261 ms) ====== [2026-02-13T00:50:33.881Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-13T00:50:33.881Z] GC before operation: completed in 116.827 ms, heap usage 493.044 MB -> 66.177 MB. [2026-02-13T00:50:41.067Z] ====== als (apache-spark) [default], iteration 8 completed (6652.814 ms) ====== [2026-02-13T00:50:41.067Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-13T00:50:41.067Z] GC before operation: completed in 90.420 ms, heap usage 206.010 MB -> 66.354 MB. [2026-02-13T00:50:48.086Z] ====== als (apache-spark) [default], iteration 9 completed (7092.511 ms) ====== [2026-02-13T00:50:48.086Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-13T00:50:48.086Z] GC before operation: completed in 108.406 ms, heap usage 280.405 MB -> 66.755 MB. [2026-02-13T00:50:54.876Z] ====== als (apache-spark) [default], iteration 10 completed (6549.328 ms) ====== [2026-02-13T00:50:54.876Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-13T00:50:54.876Z] GC before operation: completed in 96.563 ms, heap usage 537.663 MB -> 67.379 MB. [2026-02-13T00:51:03.080Z] ====== als (apache-spark) [default], iteration 11 completed (7599.082 ms) ====== [2026-02-13T00:51:03.080Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-13T00:51:03.080Z] GC before operation: completed in 97.726 ms, heap usage 490.670 MB -> 67.532 MB. [2026-02-13T00:51:09.867Z] ====== als (apache-spark) [default], iteration 12 completed (6483.786 ms) ====== [2026-02-13T00:51:09.867Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-13T00:51:09.867Z] GC before operation: completed in 96.532 ms, heap usage 492.662 MB -> 67.775 MB. [2026-02-13T00:51:16.649Z] ====== als (apache-spark) [default], iteration 13 completed (6426.696 ms) ====== [2026-02-13T00:51:16.649Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-13T00:51:16.649Z] GC before operation: completed in 98.282 ms, heap usage 213.672 MB -> 67.585 MB. [2026-02-13T00:51:23.437Z] ====== als (apache-spark) [default], iteration 14 completed (6571.430 ms) ====== [2026-02-13T00:51:23.437Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-13T00:51:23.437Z] GC before operation: completed in 98.269 ms, heap usage 326.305 MB -> 68.124 MB. [2026-02-13T00:51:30.218Z] ====== als (apache-spark) [default], iteration 15 completed (6545.059 ms) ====== [2026-02-13T00:51:30.218Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-13T00:51:30.218Z] GC before operation: completed in 126.504 ms, heap usage 186.978 MB -> 68.140 MB. [2026-02-13T00:51:37.051Z] ====== als (apache-spark) [default], iteration 16 completed (6474.165 ms) ====== [2026-02-13T00:51:37.051Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-13T00:51:37.051Z] GC before operation: completed in 96.584 ms, heap usage 130.626 MB -> 68.423 MB. [2026-02-13T00:51:43.937Z] ====== als (apache-spark) [default], iteration 17 completed (6449.800 ms) ====== [2026-02-13T00:51:43.937Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-13T00:51:43.937Z] GC before operation: completed in 98.900 ms, heap usage 323.219 MB -> 69.048 MB. [2026-02-13T00:51:50.780Z] ====== als (apache-spark) [default], iteration 18 completed (6567.581 ms) ====== [2026-02-13T00:51:50.780Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-13T00:51:50.780Z] GC before operation: completed in 99.575 ms, heap usage 456.502 MB -> 69.447 MB. [2026-02-13T00:51:57.568Z] ====== als (apache-spark) [default], iteration 19 completed (6685.670 ms) ====== [2026-02-13T00:51:57.568Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-13T00:51:57.568Z] GC before operation: completed in 96.215 ms, heap usage 243.135 MB -> 69.426 MB. [2026-02-13T00:52:04.348Z] ====== als (apache-spark) [default], iteration 20 completed (6442.264 ms) ====== [2026-02-13T00:52:04.348Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-13T00:52:04.348Z] GC before operation: completed in 101.586 ms, heap usage 475.618 MB -> 69.952 MB. [2026-02-13T00:52:11.139Z] ====== als (apache-spark) [default], iteration 21 completed (6425.438 ms) ====== [2026-02-13T00:52:11.139Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-13T00:52:11.139Z] GC before operation: completed in 99.625 ms, heap usage 438.602 MB -> 70.227 MB. [2026-02-13T00:52:17.995Z] ====== als (apache-spark) [default], iteration 22 completed (6725.833 ms) ====== [2026-02-13T00:52:17.995Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-13T00:52:17.995Z] GC before operation: completed in 97.561 ms, heap usage 163.995 MB -> 70.037 MB. [2026-02-13T00:52:24.783Z] ====== als (apache-spark) [default], iteration 23 completed (6519.175 ms) ====== [2026-02-13T00:52:24.783Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-13T00:52:24.783Z] GC before operation: completed in 98.414 ms, heap usage 324.138 MB -> 70.487 MB. [2026-02-13T00:52:31.588Z] ====== als (apache-spark) [default], iteration 24 completed (6621.947 ms) ====== [2026-02-13T00:52:31.588Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-13T00:52:31.588Z] GC before operation: completed in 96.375 ms, heap usage 286.802 MB -> 70.686 MB. [2026-02-13T00:52:44.191Z] ====== als (apache-spark) [default], iteration 25 completed (9891.058 ms) ====== [2026-02-13T00:52:44.191Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-13T00:52:44.191Z] GC before operation: completed in 97.890 ms, heap usage 495.147 MB -> 71.340 MB. [2026-02-13T00:52:56.482Z] ====== als (apache-spark) [default], iteration 26 completed (11917.616 ms) ====== [2026-02-13T00:52:56.482Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-13T00:52:56.482Z] GC before operation: completed in 101.403 ms, heap usage 161.058 MB -> 71.074 MB. [2026-02-13T00:53:03.266Z] ====== als (apache-spark) [default], iteration 27 completed (8699.575 ms) ====== [2026-02-13T00:53:03.266Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-13T00:53:03.266Z] GC before operation: completed in 103.504 ms, heap usage 200.824 MB -> 71.307 MB. [2026-02-13T00:53:10.062Z] ====== als (apache-spark) [default], iteration 28 completed (6634.504 ms) ====== [2026-02-13T00:53:10.062Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-13T00:53:10.062Z] GC before operation: completed in 102.195 ms, heap usage 223.617 MB -> 71.666 MB. [2026-02-13T00:53:15.586Z] ====== als (apache-spark) [default], iteration 29 completed (6409.949 ms) ====== [2026-02-13T00:53:16.342Z] ----------------------------------- [2026-02-13T00:53:16.342Z] renaissance-als_0_PASSED [2026-02-13T00:53:16.342Z] ----------------------------------- [2026-02-13T00:53:16.342Z] [2026-02-13T00:53:16.342Z] TEST TEARDOWN: [2026-02-13T00:53:16.342Z] Nothing to be done for teardown. [2026-02-13T00:53:16.342Z] renaissance-als_0 Finish Time: Fri Feb 13 00:53:15 2026 Epoch Time (ms): 1770943995845