[2026-02-05T22:49:18.141Z] Running test renaissance-als_0 ... [2026-02-05T22:49:18.141Z] =============================================== [2026-02-05T22:49:18.141Z] renaissance-als_0 Start Time: Thu Feb 5 22:49:17 2026 Epoch Time (ms): 1770331757549 [2026-02-05T22:49:18.141Z] variation: NoOptions [2026-02-05T22:49:18.141Z] JVM_OPTIONS: [2026-02-05T22:49:18.141Z] { \ [2026-02-05T22:49:18.142Z] echo ""; echo "TEST SETUP:"; \ [2026-02-05T22:49:18.142Z] echo "Nothing to be done for setup."; \ [2026-02-05T22:49:18.142Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17703317572739/renaissance-als_0"; \ [2026-02-05T22:49:18.142Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17703317572739/renaissance-als_0"; \ [2026-02-05T22:49:18.142Z] echo ""; echo "TESTING:"; \ [2026-02-05T22:49:18.142Z] "/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_17703317572739/renaissance-als_0"/als.json" als; \ [2026-02-05T22:49:18.142Z] 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_17703317572739/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-02-05T22:49:18.142Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-02-05T22:49:18.142Z] echo "Nothing to be done for teardown."; \ [2026-02-05T22:49:18.142Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17703317572739/TestTargetResult"; [2026-02-05T22:49:18.142Z] [2026-02-05T22:49:18.142Z] TEST SETUP: [2026-02-05T22:49:18.142Z] Nothing to be done for setup. [2026-02-05T22:49:18.142Z] [2026-02-05T22:49:18.142Z] TESTING: [2026-02-05T22:49:18.866Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2026-02-05T22:49:18.866Z] 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_17703317572739/renaissance-als_0/launcher-224917-3201606547735695448/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2026-02-05T22:49:18.866Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2026-02-05T22:49:18.866Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2026-02-05T22:49:27.120Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2026-02-05T22:49:35.056Z] ====== als (apache-spark) [default], iteration 0 started ====== [2026-02-05T22:49:35.056Z] GC before operation: completed in 146.302 ms, heap usage 62.500 MB -> 35.003 MB. [2026-02-05T22:50:22.819Z] ====== als (apache-spark) [default], iteration 0 completed (40669.144 ms) ====== [2026-02-05T22:50:22.819Z] ====== als (apache-spark) [default], iteration 1 started ====== [2026-02-05T22:50:22.819Z] GC before operation: completed in 198.753 ms, heap usage 185.487 MB -> 61.594 MB. [2026-02-05T22:50:38.489Z] ====== als (apache-spark) [default], iteration 1 completed (19817.490 ms) ====== [2026-02-05T22:50:38.489Z] ====== als (apache-spark) [default], iteration 2 started ====== [2026-02-05T22:50:38.489Z] GC before operation: completed in 230.951 ms, heap usage 326.542 MB -> 62.641 MB. [2026-02-05T22:50:57.188Z] ====== als (apache-spark) [default], iteration 2 completed (16800.251 ms) ====== [2026-02-05T22:50:57.188Z] ====== als (apache-spark) [default], iteration 3 started ====== [2026-02-05T22:50:57.188Z] GC before operation: completed in 256.348 ms, heap usage 494.144 MB -> 63.427 MB. [2026-02-05T22:51:12.810Z] ====== als (apache-spark) [default], iteration 3 completed (16944.534 ms) ====== [2026-02-05T22:51:12.810Z] ====== als (apache-spark) [default], iteration 4 started ====== [2026-02-05T22:51:12.810Z] GC before operation: completed in 209.881 ms, heap usage 522.473 MB -> 64.117 MB. [2026-02-05T22:51:31.182Z] ====== als (apache-spark) [default], iteration 4 completed (17011.959 ms) ====== [2026-02-05T22:51:31.182Z] ====== als (apache-spark) [default], iteration 5 started ====== [2026-02-05T22:51:31.182Z] GC before operation: completed in 201.082 ms, heap usage 475.264 MB -> 64.563 MB. [2026-02-05T22:51:46.988Z] ====== als (apache-spark) [default], iteration 5 completed (15743.426 ms) ====== [2026-02-05T22:51:46.988Z] ====== als (apache-spark) [default], iteration 6 started ====== [2026-02-05T22:51:46.988Z] GC before operation: completed in 208.647 ms, heap usage 279.055 MB -> 64.730 MB. [2026-02-05T22:52:05.961Z] ====== als (apache-spark) [default], iteration 6 completed (16525.272 ms) ====== [2026-02-05T22:52:05.961Z] ====== als (apache-spark) [default], iteration 7 started ====== [2026-02-05T22:52:05.961Z] GC before operation: completed in 188.261 ms, heap usage 283.404 MB -> 65.329 MB. [2026-02-05T22:52:21.711Z] ====== als (apache-spark) [default], iteration 7 completed (15978.588 ms) ====== [2026-02-05T22:52:21.711Z] ====== als (apache-spark) [default], iteration 8 started ====== [2026-02-05T22:52:21.711Z] GC before operation: completed in 362.667 ms, heap usage 182.385 MB -> 65.748 MB. [2026-02-05T22:52:40.178Z] ====== als (apache-spark) [default], iteration 8 completed (17648.025 ms) ====== [2026-02-05T22:52:40.178Z] ====== als (apache-spark) [default], iteration 9 started ====== [2026-02-05T22:52:40.178Z] GC before operation: completed in 278.919 ms, heap usage 159.617 MB -> 66.208 MB. [2026-02-05T22:52:55.878Z] ====== als (apache-spark) [default], iteration 9 completed (16281.985 ms) ====== [2026-02-05T22:52:55.878Z] ====== als (apache-spark) [default], iteration 10 started ====== [2026-02-05T22:52:55.878Z] GC before operation: completed in 243.823 ms, heap usage 163.415 MB -> 66.495 MB. [2026-02-05T22:53:14.568Z] ====== als (apache-spark) [default], iteration 10 completed (17244.955 ms) ====== [2026-02-05T22:53:14.568Z] ====== als (apache-spark) [default], iteration 11 started ====== [2026-02-05T22:53:14.568Z] GC before operation: completed in 204.500 ms, heap usage 454.745 MB -> 67.313 MB. [2026-02-05T22:53:30.326Z] ====== als (apache-spark) [default], iteration 11 completed (15098.468 ms) ====== [2026-02-05T22:53:30.326Z] ====== als (apache-spark) [default], iteration 12 started ====== [2026-02-05T22:53:30.326Z] GC before operation: completed in 222.056 ms, heap usage 199.373 MB -> 67.174 MB. [2026-02-05T22:53:46.078Z] ====== als (apache-spark) [default], iteration 12 completed (16064.097 ms) ====== [2026-02-05T22:53:46.078Z] ====== als (apache-spark) [default], iteration 13 started ====== [2026-02-05T22:53:46.835Z] GC before operation: completed in 230.510 ms, heap usage 112.553 MB -> 67.369 MB. [2026-02-05T22:54:02.657Z] ====== als (apache-spark) [default], iteration 13 completed (14851.442 ms) ====== [2026-02-05T22:54:02.657Z] ====== als (apache-spark) [default], iteration 14 started ====== [2026-02-05T22:54:02.657Z] GC before operation: completed in 226.616 ms, heap usage 454.477 MB -> 68.162 MB. [2026-02-05T22:54:18.950Z] ====== als (apache-spark) [default], iteration 14 completed (15807.680 ms) ====== [2026-02-05T22:54:18.950Z] ====== als (apache-spark) [default], iteration 15 started ====== [2026-02-05T22:54:18.950Z] GC before operation: completed in 237.105 ms, heap usage 220.748 MB -> 68.038 MB. [2026-02-05T22:54:34.834Z] ====== als (apache-spark) [default], iteration 15 completed (14125.133 ms) ====== [2026-02-05T22:54:34.834Z] ====== als (apache-spark) [default], iteration 16 started ====== [2026-02-05T22:54:34.834Z] GC before operation: completed in 194.590 ms, heap usage 207.819 MB -> 68.372 MB. [2026-02-05T22:54:50.616Z] ====== als (apache-spark) [default], iteration 16 completed (16450.190 ms) ====== [2026-02-05T22:54:50.616Z] ====== als (apache-spark) [default], iteration 17 started ====== [2026-02-05T22:54:50.616Z] GC before operation: completed in 410.620 ms, heap usage 478.550 MB -> 68.863 MB. [2026-02-05T22:55:06.485Z] ====== als (apache-spark) [default], iteration 17 completed (15401.754 ms) ====== [2026-02-05T22:55:06.485Z] ====== als (apache-spark) [default], iteration 18 started ====== [2026-02-05T22:55:06.485Z] GC before operation: completed in 254.822 ms, heap usage 435.053 MB -> 69.061 MB. [2026-02-05T22:55:22.738Z] ====== als (apache-spark) [default], iteration 18 completed (15201.837 ms) ====== [2026-02-05T22:55:22.738Z] ====== als (apache-spark) [default], iteration 19 started ====== [2026-02-05T22:55:22.738Z] GC before operation: completed in 307.764 ms, heap usage 353.828 MB -> 69.204 MB. [2026-02-05T22:55:38.499Z] ====== als (apache-spark) [default], iteration 19 completed (14571.027 ms) ====== [2026-02-05T22:55:38.499Z] ====== als (apache-spark) [default], iteration 20 started ====== [2026-02-05T22:55:38.499Z] GC before operation: completed in 206.923 ms, heap usage 258.267 MB -> 69.329 MB. [2026-02-05T22:55:54.262Z] ====== als (apache-spark) [default], iteration 20 completed (14759.532 ms) ====== [2026-02-05T22:55:54.262Z] ====== als (apache-spark) [default], iteration 21 started ====== [2026-02-05T22:55:54.262Z] GC before operation: completed in 211.454 ms, heap usage 265.675 MB -> 69.589 MB. [2026-02-05T22:56:10.067Z] ====== als (apache-spark) [default], iteration 21 completed (15234.860 ms) ====== [2026-02-05T22:56:10.067Z] ====== als (apache-spark) [default], iteration 22 started ====== [2026-02-05T22:56:10.067Z] GC before operation: completed in 244.492 ms, heap usage 115.302 MB -> 70.003 MB. [2026-02-05T22:56:25.768Z] ====== als (apache-spark) [default], iteration 22 completed (14627.880 ms) ====== [2026-02-05T22:56:25.768Z] ====== als (apache-spark) [default], iteration 23 started ====== [2026-02-05T22:56:25.768Z] GC before operation: completed in 224.920 ms, heap usage 229.957 MB -> 70.198 MB. [2026-02-05T22:56:39.568Z] ====== als (apache-spark) [default], iteration 23 completed (14412.491 ms) ====== [2026-02-05T22:56:39.569Z] ====== als (apache-spark) [default], iteration 24 started ====== [2026-02-05T22:56:39.569Z] GC before operation: completed in 214.505 ms, heap usage 333.014 MB -> 70.527 MB. [2026-02-05T22:56:55.190Z] ====== als (apache-spark) [default], iteration 24 completed (14569.906 ms) ====== [2026-02-05T22:56:55.190Z] ====== als (apache-spark) [default], iteration 25 started ====== [2026-02-05T22:56:55.190Z] GC before operation: completed in 218.970 ms, heap usage 342.387 MB -> 70.791 MB. [2026-02-05T22:57:10.853Z] ====== als (apache-spark) [default], iteration 25 completed (14907.044 ms) ====== [2026-02-05T22:57:10.853Z] ====== als (apache-spark) [default], iteration 26 started ====== [2026-02-05T22:57:10.853Z] GC before operation: completed in 246.211 ms, heap usage 188.375 MB -> 70.828 MB. [2026-02-05T22:57:26.553Z] ====== als (apache-spark) [default], iteration 26 completed (15416.083 ms) ====== [2026-02-05T22:57:26.553Z] ====== als (apache-spark) [default], iteration 27 started ====== [2026-02-05T22:57:26.553Z] GC before operation: completed in 259.552 ms, heap usage 379.072 MB -> 71.300 MB. [2026-02-05T22:57:40.444Z] ====== als (apache-spark) [default], iteration 27 completed (14570.615 ms) ====== [2026-02-05T22:57:40.444Z] ====== als (apache-spark) [default], iteration 28 started ====== [2026-02-05T22:57:41.191Z] GC before operation: completed in 234.185 ms, heap usage 115.824 MB -> 71.368 MB. [2026-02-05T22:57:56.849Z] ====== als (apache-spark) [default], iteration 28 completed (14941.113 ms) ====== [2026-02-05T22:57:56.849Z] ====== als (apache-spark) [default], iteration 29 started ====== [2026-02-05T22:57:56.849Z] GC before operation: completed in 266.134 ms, heap usage 184.025 MB -> 71.625 MB. [2026-02-05T22:58:12.541Z] ====== als (apache-spark) [default], iteration 29 completed (14578.841 ms) ====== [2026-02-05T22:58:12.541Z] ----------------------------------- [2026-02-05T22:58:12.541Z] renaissance-als_0_PASSED [2026-02-05T22:58:12.541Z] ----------------------------------- [2026-02-05T22:58:12.541Z] [2026-02-05T22:58:12.541Z] TEST TEARDOWN: [2026-02-05T22:58:12.541Z] Nothing to be done for teardown. [2026-02-05T22:58:12.541Z] renaissance-als_0 Finish Time: Thu Feb 5 22:58:11 2026 Epoch Time (ms): 1770332291846