[2025-12-06T14:11:10.846Z] Running test renaissance-als_0 ... [2025-12-06T14:11:10.846Z] =============================================== [2025-12-06T14:11:10.846Z] renaissance-als_0 Start Time: Sat Dec 6 14:11:10 2025 Epoch Time (ms): 1765030270212 [2025-12-06T14:11:10.846Z] variation: NoOptions [2025-12-06T14:11:10.846Z] JVM_OPTIONS: [2025-12-06T14:11:10.846Z] { \ [2025-12-06T14:11:10.846Z] echo ""; echo "TEST SETUP:"; \ [2025-12-06T14:11:10.846Z] echo "Nothing to be done for setup."; \ [2025-12-06T14:11:10.846Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17650302696965/renaissance-als_0"; \ [2025-12-06T14:11:10.846Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17650302696965/renaissance-als_0"; \ [2025-12-06T14:11:10.846Z] echo ""; echo "TESTING:"; \ [2025-12-06T14:11:10.846Z] "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_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_aarch64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17650302696965/renaissance-als_0"/als.json" als; \ [2025-12-06T14:11:10.846Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17650302696965/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-06T14:11:10.846Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-06T14:11:10.846Z] echo "Nothing to be done for teardown."; \ [2025-12-06T14:11:10.846Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/../TKG/output_17650302696965/TestTargetResult"; [2025-12-06T14:11:10.846Z] [2025-12-06T14:11:10.846Z] TEST SETUP: [2025-12-06T14:11:10.846Z] Nothing to be done for setup. [2025-12-06T14:11:10.846Z] [2025-12-06T14:11:10.846Z] TESTING: [2025-12-06T14:11:12.089Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-06T14:11:12.089Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_aarch64_alpine-linux/aqa-tests/TKG/output_17650302696965/renaissance-als_0/launcher-141110-14518770315315611418/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-06T14:11:12.089Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-06T14:11:12.089Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-06T14:11:17.482Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-06T14:11:22.971Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-06T14:11:22.971Z] GC before operation: completed in 62.652 ms, heap usage 52.845 MB -> 35.096 MB. [2025-12-06T14:11:44.297Z] ====== als (apache-spark) [default], iteration 0 completed (19991.620 ms) ====== [2025-12-06T14:11:44.297Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-06T14:11:44.297Z] GC before operation: completed in 128.598 ms, heap usage 356.365 MB -> 61.671 MB. [2025-12-06T14:11:51.042Z] ====== als (apache-spark) [default], iteration 1 completed (6511.523 ms) ====== [2025-12-06T14:11:51.042Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-06T14:11:51.042Z] GC before operation: completed in 96.493 ms, heap usage 183.129 MB -> 62.430 MB. [2025-12-06T14:11:56.446Z] ====== als (apache-spark) [default], iteration 2 completed (5701.991 ms) ====== [2025-12-06T14:11:56.446Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-06T14:11:56.446Z] GC before operation: completed in 97.962 ms, heap usage 352.647 MB -> 63.214 MB. [2025-12-06T14:12:03.195Z] ====== als (apache-spark) [default], iteration 3 completed (5881.623 ms) ====== [2025-12-06T14:12:03.195Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-06T14:12:03.195Z] GC before operation: completed in 96.559 ms, heap usage 300.900 MB -> 63.769 MB. [2025-12-06T14:12:08.601Z] ====== als (apache-spark) [default], iteration 4 completed (5574.979 ms) ====== [2025-12-06T14:12:08.601Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-06T14:12:08.601Z] GC before operation: completed in 104.102 ms, heap usage 349.653 MB -> 64.376 MB. [2025-12-06T14:12:14.003Z] ====== als (apache-spark) [default], iteration 5 completed (5375.776 ms) ====== [2025-12-06T14:12:14.003Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-06T14:12:14.003Z] GC before operation: completed in 106.841 ms, heap usage 452.815 MB -> 65.121 MB. [2025-12-06T14:12:20.732Z] ====== als (apache-spark) [default], iteration 6 completed (5309.639 ms) ====== [2025-12-06T14:12:20.732Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-06T14:12:20.732Z] GC before operation: completed in 118.397 ms, heap usage 451.155 MB -> 65.671 MB. [2025-12-06T14:12:25.615Z] ====== als (apache-spark) [default], iteration 7 completed (5177.792 ms) ====== [2025-12-06T14:12:25.615Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-06T14:12:25.615Z] GC before operation: completed in 114.778 ms, heap usage 159.457 MB -> 65.829 MB. [2025-12-06T14:12:31.029Z] ====== als (apache-spark) [default], iteration 8 completed (5036.333 ms) ====== [2025-12-06T14:12:31.029Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-06T14:12:31.029Z] GC before operation: completed in 113.290 ms, heap usage 575.684 MB -> 66.923 MB. [2025-12-06T14:12:36.537Z] ====== als (apache-spark) [default], iteration 9 completed (5196.255 ms) ====== [2025-12-06T14:12:36.537Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-06T14:12:36.537Z] GC before operation: completed in 121.059 ms, heap usage 94.727 MB -> 67.051 MB. [2025-12-06T14:12:41.964Z] ====== als (apache-spark) [default], iteration 10 completed (5146.718 ms) ====== [2025-12-06T14:12:41.964Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-06T14:12:41.964Z] GC before operation: completed in 129.878 ms, heap usage 463.095 MB -> 67.252 MB. [2025-12-06T14:12:47.371Z] ====== als (apache-spark) [default], iteration 11 completed (4917.791 ms) ====== [2025-12-06T14:12:47.371Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-06T14:12:47.371Z] GC before operation: completed in 119.019 ms, heap usage 144.712 MB -> 67.095 MB. [2025-12-06T14:12:52.781Z] ====== als (apache-spark) [default], iteration 12 completed (5079.607 ms) ====== [2025-12-06T14:12:52.781Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-06T14:12:52.781Z] GC before operation: completed in 129.980 ms, heap usage 272.213 MB -> 67.415 MB. [2025-12-06T14:12:58.181Z] ====== als (apache-spark) [default], iteration 13 completed (5002.515 ms) ====== [2025-12-06T14:12:58.181Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-06T14:12:58.181Z] GC before operation: completed in 130.550 ms, heap usage 494.949 MB -> 68.022 MB. [2025-12-06T14:13:03.598Z] ====== als (apache-spark) [default], iteration 14 completed (5047.918 ms) ====== [2025-12-06T14:13:03.598Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-06T14:13:03.598Z] GC before operation: completed in 116.710 ms, heap usage 460.840 MB -> 68.330 MB. [2025-12-06T14:13:07.904Z] ====== als (apache-spark) [default], iteration 15 completed (4991.081 ms) ====== [2025-12-06T14:13:07.904Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-06T14:13:07.904Z] GC before operation: completed in 121.903 ms, heap usage 322.109 MB -> 68.295 MB. [2025-12-06T14:13:13.312Z] ====== als (apache-spark) [default], iteration 16 completed (5000.229 ms) ====== [2025-12-06T14:13:13.312Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-06T14:13:13.313Z] GC before operation: completed in 116.764 ms, heap usage 448.951 MB -> 68.764 MB. [2025-12-06T14:13:18.376Z] ====== als (apache-spark) [default], iteration 17 completed (4884.158 ms) ====== [2025-12-06T14:13:18.376Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-06T14:13:18.376Z] GC before operation: completed in 130.813 ms, heap usage 299.944 MB -> 68.863 MB. [2025-12-06T14:13:23.774Z] ====== als (apache-spark) [default], iteration 18 completed (5033.857 ms) ====== [2025-12-06T14:13:23.774Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-06T14:13:23.774Z] GC before operation: completed in 117.305 ms, heap usage 128.011 MB -> 68.831 MB. [2025-12-06T14:13:29.183Z] ====== als (apache-spark) [default], iteration 19 completed (4907.123 ms) ====== [2025-12-06T14:13:29.183Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-06T14:13:29.183Z] GC before operation: completed in 133.766 ms, heap usage 291.730 MB -> 69.375 MB. [2025-12-06T14:13:34.589Z] ====== als (apache-spark) [default], iteration 20 completed (4976.553 ms) ====== [2025-12-06T14:13:34.589Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-06T14:13:34.589Z] GC before operation: completed in 138.842 ms, heap usage 622.293 MB -> 69.958 MB. [2025-12-06T14:13:40.003Z] ====== als (apache-spark) [default], iteration 21 completed (4813.708 ms) ====== [2025-12-06T14:13:40.003Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-06T14:13:40.003Z] GC before operation: completed in 124.822 ms, heap usage 205.048 MB -> 69.710 MB. [2025-12-06T14:13:45.411Z] ====== als (apache-spark) [default], iteration 22 completed (4978.687 ms) ====== [2025-12-06T14:13:45.411Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-06T14:13:45.411Z] GC before operation: completed in 138.544 ms, heap usage 298.659 MB -> 70.216 MB. [2025-12-06T14:13:49.584Z] ====== als (apache-spark) [default], iteration 23 completed (4795.393 ms) ====== [2025-12-06T14:13:49.584Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-06T14:13:49.584Z] GC before operation: completed in 132.432 ms, heap usage 112.545 MB -> 70.221 MB. [2025-12-06T14:13:55.001Z] ====== als (apache-spark) [default], iteration 24 completed (4870.617 ms) ====== [2025-12-06T14:13:55.001Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-06T14:13:55.001Z] GC before operation: completed in 129.816 ms, heap usage 103.150 MB -> 70.745 MB. [2025-12-06T14:14:00.415Z] ====== als (apache-spark) [default], iteration 25 completed (4683.363 ms) ====== [2025-12-06T14:14:00.415Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-06T14:14:00.415Z] GC before operation: completed in 134.884 ms, heap usage 193.274 MB -> 70.823 MB. [2025-12-06T14:14:04.596Z] ====== als (apache-spark) [default], iteration 26 completed (4751.908 ms) ====== [2025-12-06T14:14:04.596Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-06T14:14:05.554Z] GC before operation: completed in 133.210 ms, heap usage 593.809 MB -> 71.634 MB. [2025-12-06T14:14:09.735Z] ====== als (apache-spark) [default], iteration 27 completed (4713.034 ms) ====== [2025-12-06T14:14:09.735Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-06T14:14:09.735Z] GC before operation: completed in 126.037 ms, heap usage 189.218 MB -> 71.452 MB. [2025-12-06T14:14:14.804Z] ====== als (apache-spark) [default], iteration 28 completed (4651.414 ms) ====== [2025-12-06T14:14:14.804Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-06T14:14:14.804Z] GC before operation: completed in 136.601 ms, heap usage 451.204 MB -> 72.054 MB. [2025-12-06T14:14:20.208Z] ====== als (apache-spark) [default], iteration 29 completed (4778.601 ms) ====== [2025-12-06T14:14:20.208Z] ----------------------------------- [2025-12-06T14:14:20.208Z] renaissance-als_0_PASSED [2025-12-06T14:14:20.208Z] ----------------------------------- [2025-12-06T14:14:20.208Z] [2025-12-06T14:14:20.208Z] TEST TEARDOWN: [2025-12-06T14:14:20.208Z] Nothing to be done for teardown. [2025-12-06T14:14:20.208Z] renaissance-als_0 Finish Time: Sat Dec 6 14:14:19 2025 Epoch Time (ms): 1765030459988