[2025-12-16T08:45:41.711Z] Running test renaissance-als_0 ... [2025-12-16T08:45:41.711Z] =============================================== [2025-12-16T08:45:41.711Z] renaissance-als_0 Start Time: Tue Dec 16 08:45:41 2025 Epoch Time (ms): 1765874741579 [2025-12-16T08:45:41.711Z] variation: NoOptions [2025-12-16T08:45:41.711Z] JVM_OPTIONS: [2025-12-16T08:45:41.711Z] { \ [2025-12-16T08:45:41.711Z] echo ""; echo "TEST SETUP:"; \ [2025-12-16T08:45:41.711Z] echo "Nothing to be done for setup."; \ [2025-12-16T08:45:41.711Z] mkdir -p "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17658747417873/renaissance-als_0"; \ [2025-12-16T08:45:41.711Z] cd "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17658747417873/renaissance-als_0"; \ [2025-12-16T08:45:41.711Z] echo ""; echo "TESTING:"; \ [2025-12-16T08:45:41.711Z] "/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_17658747417873/renaissance-als_0"/als.json" als; \ [2025-12-16T08:45:41.711Z] 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_17658747417873/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-16T08:45:41.711Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-16T08:45:41.711Z] echo "Nothing to be done for teardown."; \ [2025-12-16T08:45:41.711Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk25_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17658747417873/TestTargetResult"; [2025-12-16T08:45:41.711Z] [2025-12-16T08:45:41.711Z] TEST SETUP: [2025-12-16T08:45:41.711Z] Nothing to be done for setup. [2025-12-16T08:45:41.711Z] [2025-12-16T08:45:41.711Z] TESTING: [2025-12-16T08:45:42.561Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-16T08:45:42.561Z] 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_17658747417873/renaissance-als_0/launcher-084541-13712398127905423874/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-16T08:45:42.561Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-16T08:45:42.561Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-16T08:45:49.715Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-16T08:45:53.240Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-16T08:45:53.240Z] GC before operation: completed in 39.885 ms, heap usage 53.000 MB -> 35.089 MB. [2025-12-16T08:46:12.941Z] ====== als (apache-spark) [default], iteration 0 completed (17353.536 ms) ====== [2025-12-16T08:46:12.941Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-16T08:46:12.941Z] GC before operation: completed in 99.417 ms, heap usage 388.914 MB -> 61.723 MB. [2025-12-16T08:46:19.182Z] ====== als (apache-spark) [default], iteration 1 completed (7658.797 ms) ====== [2025-12-16T08:46:19.182Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-16T08:46:19.182Z] GC before operation: completed in 76.496 ms, heap usage 161.313 MB -> 62.409 MB. [2025-12-16T08:46:27.717Z] ====== als (apache-spark) [default], iteration 2 completed (7368.915 ms) ====== [2025-12-16T08:46:27.717Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-16T08:46:27.717Z] GC before operation: completed in 78.445 ms, heap usage 104.411 MB -> 63.043 MB. [2025-12-16T08:46:35.090Z] ====== als (apache-spark) [default], iteration 3 completed (7559.293 ms) ====== [2025-12-16T08:46:35.090Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-16T08:46:35.090Z] GC before operation: completed in 82.948 ms, heap usage 535.947 MB -> 64.166 MB. [2025-12-16T08:46:42.169Z] ====== als (apache-spark) [default], iteration 4 completed (7296.138 ms) ====== [2025-12-16T08:46:42.169Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-16T08:46:42.169Z] GC before operation: completed in 84.912 ms, heap usage 248.261 MB -> 64.303 MB. [2025-12-16T08:46:51.349Z] ====== als (apache-spark) [default], iteration 5 completed (8515.958 ms) ====== [2025-12-16T08:46:51.350Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-16T08:46:51.350Z] GC before operation: completed in 85.519 ms, heap usage 351.599 MB -> 65.024 MB. [2025-12-16T08:47:01.472Z] ====== als (apache-spark) [default], iteration 6 completed (9308.616 ms) ====== [2025-12-16T08:47:01.472Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-16T08:47:01.472Z] GC before operation: completed in 88.257 ms, heap usage 111.663 MB -> 65.323 MB. [2025-12-16T08:47:10.618Z] ====== als (apache-spark) [default], iteration 7 completed (8299.147 ms) ====== [2025-12-16T08:47:10.618Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-16T08:47:10.618Z] GC before operation: completed in 107.550 ms, heap usage 197.233 MB -> 65.889 MB. [2025-12-16T08:47:19.037Z] ====== als (apache-spark) [default], iteration 8 completed (8623.443 ms) ====== [2025-12-16T08:47:19.037Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-16T08:47:19.037Z] GC before operation: completed in 91.587 ms, heap usage 494.248 MB -> 66.786 MB. [2025-12-16T08:47:28.803Z] ====== als (apache-spark) [default], iteration 9 completed (8694.083 ms) ====== [2025-12-16T08:47:28.803Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-16T08:47:28.803Z] GC before operation: completed in 91.041 ms, heap usage 281.307 MB -> 66.682 MB. [2025-12-16T08:47:38.201Z] ====== als (apache-spark) [default], iteration 10 completed (10099.705 ms) ====== [2025-12-16T08:47:38.201Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-16T08:47:38.201Z] GC before operation: completed in 93.018 ms, heap usage 263.749 MB -> 66.844 MB. [2025-12-16T08:47:53.891Z] ====== als (apache-spark) [default], iteration 11 completed (16494.321 ms) ====== [2025-12-16T08:47:53.891Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-16T08:47:53.891Z] GC before operation: completed in 120.350 ms, heap usage 536.330 MB -> 67.609 MB. [2025-12-16T08:48:13.912Z] ====== als (apache-spark) [default], iteration 12 completed (17258.159 ms) ====== [2025-12-16T08:48:13.912Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-16T08:48:13.912Z] GC before operation: completed in 90.221 ms, heap usage 324.693 MB -> 67.481 MB. [2025-12-16T08:48:27.980Z] ====== als (apache-spark) [default], iteration 13 completed (14860.170 ms) ====== [2025-12-16T08:48:27.980Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-16T08:48:27.980Z] GC before operation: completed in 96.025 ms, heap usage 466.423 MB -> 68.007 MB. [2025-12-16T08:48:37.227Z] ====== als (apache-spark) [default], iteration 14 completed (9795.341 ms) ====== [2025-12-16T08:48:37.227Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-16T08:48:37.227Z] GC before operation: completed in 77.759 ms, heap usage 342.557 MB -> 67.988 MB. [2025-12-16T08:48:44.297Z] ====== als (apache-spark) [default], iteration 15 completed (7606.994 ms) ====== [2025-12-16T08:48:44.297Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-16T08:48:44.297Z] GC before operation: completed in 80.638 ms, heap usage 287.548 MB -> 68.196 MB. [2025-12-16T08:48:53.464Z] ====== als (apache-spark) [default], iteration 16 completed (8746.668 ms) ====== [2025-12-16T08:48:53.464Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-16T08:48:53.464Z] GC before operation: completed in 100.562 ms, heap usage 504.899 MB -> 68.825 MB. [2025-12-16T08:49:02.516Z] ====== als (apache-spark) [default], iteration 17 completed (8506.870 ms) ====== [2025-12-16T08:49:02.516Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-16T08:49:02.516Z] GC before operation: completed in 85.280 ms, heap usage 436.568 MB -> 68.969 MB. [2025-12-16T08:49:09.985Z] ====== als (apache-spark) [default], iteration 18 completed (6572.343 ms) ====== [2025-12-16T08:49:09.985Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-16T08:49:09.985Z] GC before operation: completed in 80.671 ms, heap usage 235.832 MB -> 68.876 MB. [2025-12-16T08:49:16.183Z] ====== als (apache-spark) [default], iteration 19 completed (6563.276 ms) ====== [2025-12-16T08:49:16.183Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-16T08:49:16.183Z] GC before operation: completed in 80.714 ms, heap usage 477.761 MB -> 69.517 MB. [2025-12-16T08:49:22.770Z] ====== als (apache-spark) [default], iteration 20 completed (6709.431 ms) ====== [2025-12-16T08:49:22.770Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-16T08:49:22.770Z] GC before operation: completed in 85.601 ms, heap usage 490.452 MB -> 69.784 MB. [2025-12-16T08:49:30.135Z] ====== als (apache-spark) [default], iteration 21 completed (6507.481 ms) ====== [2025-12-16T08:49:30.135Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-16T08:49:30.135Z] GC before operation: completed in 82.023 ms, heap usage 238.817 MB -> 69.693 MB. [2025-12-16T08:49:37.557Z] ====== als (apache-spark) [default], iteration 22 completed (7698.394 ms) ====== [2025-12-16T08:49:37.557Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-16T08:49:37.557Z] GC before operation: completed in 88.187 ms, heap usage 346.246 MB -> 70.108 MB. [2025-12-16T08:49:45.259Z] ====== als (apache-spark) [default], iteration 23 completed (6603.535 ms) ====== [2025-12-16T08:49:45.259Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-16T08:49:45.259Z] GC before operation: completed in 88.796 ms, heap usage 233.849 MB -> 70.311 MB. [2025-12-16T08:49:54.042Z] ====== als (apache-spark) [default], iteration 24 completed (9504.975 ms) ====== [2025-12-16T08:49:54.042Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-16T08:49:54.042Z] GC before operation: completed in 82.049 ms, heap usage 529.345 MB -> 71.031 MB. [2025-12-16T08:50:04.121Z] ====== als (apache-spark) [default], iteration 25 completed (10057.607 ms) ====== [2025-12-16T08:50:04.121Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-16T08:50:04.121Z] GC before operation: completed in 93.064 ms, heap usage 504.269 MB -> 71.130 MB. [2025-12-16T08:50:16.092Z] ====== als (apache-spark) [default], iteration 26 completed (9388.940 ms) ====== [2025-12-16T08:50:16.092Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-16T08:50:16.092Z] GC before operation: completed in 79.684 ms, heap usage 200.517 MB -> 70.954 MB. [2025-12-16T08:50:33.393Z] ====== als (apache-spark) [default], iteration 27 completed (18699.852 ms) ====== [2025-12-16T08:50:33.393Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-16T08:50:33.393Z] GC before operation: completed in 95.536 ms, heap usage 567.432 MB -> 71.705 MB. [2025-12-16T08:50:45.427Z] ====== als (apache-spark) [default], iteration 28 completed (8196.435 ms) ====== [2025-12-16T08:50:45.427Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-16T08:50:45.427Z] GC before operation: completed in 83.485 ms, heap usage 118.345 MB -> 71.392 MB. [2025-12-16T08:50:51.865Z] ====== als (apache-spark) [default], iteration 29 completed (10681.358 ms) ====== [2025-12-16T08:50:52.666Z] ----------------------------------- [2025-12-16T08:50:52.666Z] renaissance-als_0_PASSED [2025-12-16T08:50:52.666Z] ----------------------------------- [2025-12-16T08:50:52.666Z] [2025-12-16T08:50:52.666Z] TEST TEARDOWN: [2025-12-16T08:50:52.666Z] Nothing to be done for teardown. [2025-12-16T08:50:52.666Z] renaissance-als_0 Finish Time: Tue Dec 16 08:50:51 2025 Epoch Time (ms): 1765875051968