[2025-12-27T13:49:57.335Z] Running test renaissance-als_0 ... [2025-12-27T13:49:57.335Z] =============================================== [2025-12-27T13:49:57.335Z] renaissance-als_0 Start Time: Sat Dec 27 05:49:57 2025 Epoch Time (ms): 1766843397123 [2025-12-27T13:49:57.335Z] variation: NoOptions [2025-12-27T13:49:57.765Z] JVM_OPTIONS: [2025-12-27T13:49:57.765Z] { \ [2025-12-27T13:49:57.765Z] echo ""; echo "TEST SETUP:"; \ [2025-12-27T13:49:57.765Z] echo "Nothing to be done for setup."; \ [2025-12-27T13:49:57.765Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1766843395313/renaissance-als_0"; \ [2025-12-27T13:49:57.765Z] cd "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1766843395313/renaissance-als_0"; \ [2025-12-27T13:49:57.765Z] echo ""; echo "TESTING:"; \ [2025-12-27T13:49:57.765Z] "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/jdkbinary/j2sdk-image/Contents/Home/bin/..//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 "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1766843395313/renaissance-als_0"/als.json" als; \ [2025-12-27T13:49:57.765Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1766843395313/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-27T13:49:57.765Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-27T13:49:57.765Z] echo "Nothing to be done for teardown."; \ [2025-12-27T13:49:57.765Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_1766843395313/TestTargetResult"; [2025-12-27T13:49:57.765Z] [2025-12-27T13:49:57.765Z] TEST SETUP: [2025-12-27T13:49:57.765Z] Nothing to be done for setup. [2025-12-27T13:49:57.765Z] [2025-12-27T13:49:57.765Z] TESTING: [2025-12-27T13:49:59.173Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-27T13:49:59.173Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/output_1766843395313/renaissance-als_0/launcher-054958-3831782513336092972/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-27T13:49:59.173Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-27T13:49:59.173Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-27T13:50:11.565Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-27T13:50:21.806Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-27T13:50:21.806Z] GC before operation: completed in 92.671 ms, heap usage 330.441 MB -> 34.284 MB. [2025-12-27T13:51:22.779Z] ====== als (apache-spark) [default], iteration 0 completed (53296.315 ms) ====== [2025-12-27T13:51:22.779Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-27T13:51:22.779Z] GC before operation: completed in 185.413 ms, heap usage 144.072 MB -> 60.673 MB. [2025-12-27T13:52:00.200Z] ====== als (apache-spark) [default], iteration 1 completed (37336.573 ms) ====== [2025-12-27T13:52:00.200Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-27T13:52:00.200Z] GC before operation: completed in 136.190 ms, heap usage 176.403 MB -> 61.462 MB. [2025-12-27T13:52:23.029Z] ====== als (apache-spark) [default], iteration 2 completed (26874.203 ms) ====== [2025-12-27T13:52:23.029Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-27T13:52:23.029Z] GC before operation: completed in 575.649 ms, heap usage 187.438 MB -> 62.172 MB. [2025-12-27T13:52:54.564Z] ====== als (apache-spark) [default], iteration 3 completed (30145.685 ms) ====== [2025-12-27T13:52:54.564Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-27T13:52:54.564Z] GC before operation: completed in 152.560 ms, heap usage 369.457 MB -> 63.072 MB. [2025-12-27T13:53:20.902Z] ====== als (apache-spark) [default], iteration 4 completed (25810.016 ms) ====== [2025-12-27T13:53:20.902Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-27T13:53:20.902Z] GC before operation: completed in 134.316 ms, heap usage 301.424 MB -> 63.270 MB. [2025-12-27T13:53:47.260Z] ====== als (apache-spark) [default], iteration 5 completed (26177.405 ms) ====== [2025-12-27T13:53:47.260Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-27T13:53:47.260Z] GC before operation: completed in 116.886 ms, heap usage 160.000 MB -> 64.090 MB. [2025-12-27T13:54:17.956Z] ====== als (apache-spark) [default], iteration 6 completed (27241.528 ms) ====== [2025-12-27T13:54:17.956Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-27T13:54:17.956Z] GC before operation: completed in 282.851 ms, heap usage 257.293 MB -> 64.355 MB. [2025-12-27T13:54:45.163Z] ====== als (apache-spark) [default], iteration 7 completed (26900.032 ms) ====== [2025-12-27T13:54:45.163Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-27T13:54:45.163Z] GC before operation: completed in 460.518 ms, heap usage 149.001 MB -> 65.163 MB. [2025-12-27T13:55:10.878Z] ====== als (apache-spark) [default], iteration 8 completed (25035.218 ms) ====== [2025-12-27T13:55:10.878Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-27T13:55:10.878Z] GC before operation: completed in 152.679 ms, heap usage 349.808 MB -> 65.877 MB. [2025-12-27T13:55:41.569Z] ====== als (apache-spark) [default], iteration 9 completed (28005.581 ms) ====== [2025-12-27T13:55:41.569Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-27T13:55:41.569Z] GC before operation: completed in 143.379 ms, heap usage 251.034 MB -> 65.698 MB. [2025-12-27T13:56:03.130Z] ====== als (apache-spark) [default], iteration 10 completed (21930.586 ms) ====== [2025-12-27T13:56:03.130Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-27T13:56:03.130Z] GC before operation: completed in 164.796 ms, heap usage 171.636 MB -> 65.916 MB. [2025-12-27T13:56:25.026Z] ====== als (apache-spark) [default], iteration 11 completed (23471.881 ms) ====== [2025-12-27T13:56:25.026Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-27T13:56:25.026Z] GC before operation: completed in 138.880 ms, heap usage 316.457 MB -> 66.590 MB. [2025-12-27T13:56:46.758Z] ====== als (apache-spark) [default], iteration 12 completed (19955.945 ms) ====== [2025-12-27T13:56:46.758Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-27T13:56:46.758Z] GC before operation: completed in 310.106 ms, heap usage 397.228 MB -> 66.987 MB. [2025-12-27T13:57:24.030Z] ====== als (apache-spark) [default], iteration 13 completed (36402.272 ms) ====== [2025-12-27T13:57:24.031Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-27T13:57:24.031Z] GC before operation: completed in 203.953 ms, heap usage 153.424 MB -> 66.946 MB. [2025-12-27T13:57:54.351Z] ====== als (apache-spark) [default], iteration 14 completed (27678.759 ms) ====== [2025-12-27T13:57:54.351Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-27T13:57:54.351Z] GC before operation: completed in 767.278 ms, heap usage 136.815 MB -> 67.490 MB. [2025-12-27T13:58:19.994Z] ====== als (apache-spark) [default], iteration 15 completed (26506.864 ms) ====== [2025-12-27T13:58:19.994Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-27T13:58:19.994Z] GC before operation: completed in 215.262 ms, heap usage 356.821 MB -> 67.548 MB. [2025-12-27T13:58:57.421Z] ====== als (apache-spark) [default], iteration 16 completed (36623.821 ms) ====== [2025-12-27T13:58:57.421Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-27T13:58:57.421Z] GC before operation: completed in 289.891 ms, heap usage 264.186 MB -> 67.547 MB. [2025-12-27T13:59:23.211Z] ====== als (apache-spark) [default], iteration 17 completed (27606.580 ms) ====== [2025-12-27T13:59:23.211Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-27T13:59:23.211Z] GC before operation: completed in 134.813 ms, heap usage 401.214 MB -> 68.275 MB. [2025-12-27T14:00:00.129Z] ====== als (apache-spark) [default], iteration 18 completed (34273.339 ms) ====== [2025-12-27T14:00:00.129Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-27T14:00:00.129Z] GC before operation: completed in 183.741 ms, heap usage 257.519 MB -> 68.298 MB. [2025-12-27T14:00:31.536Z] ====== als (apache-spark) [default], iteration 19 completed (29747.447 ms) ====== [2025-12-27T14:00:31.537Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-27T14:00:31.537Z] GC before operation: completed in 162.579 ms, heap usage 355.198 MB -> 68.679 MB. [2025-12-27T14:01:02.488Z] ====== als (apache-spark) [default], iteration 20 completed (33293.900 ms) ====== [2025-12-27T14:01:02.489Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-27T14:01:02.489Z] GC before operation: completed in 142.148 ms, heap usage 1.042 GB -> 69.793 MB. [2025-12-27T14:01:34.678Z] ====== als (apache-spark) [default], iteration 21 completed (32008.795 ms) ====== [2025-12-27T14:01:34.678Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-27T14:01:35.178Z] GC before operation: completed in 219.824 ms, heap usage 394.711 MB -> 69.217 MB. [2025-12-27T14:02:12.826Z] ====== als (apache-spark) [default], iteration 22 completed (34807.487 ms) ====== [2025-12-27T14:02:12.827Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-27T14:02:12.827Z] GC before operation: completed in 205.674 ms, heap usage 130.821 MB -> 69.071 MB. [2025-12-27T14:02:43.703Z] ====== als (apache-spark) [default], iteration 23 completed (29771.124 ms) ====== [2025-12-27T14:02:43.703Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-27T14:02:43.703Z] GC before operation: completed in 209.880 ms, heap usage 344.346 MB -> 69.654 MB. [2025-12-27T14:03:09.737Z] ====== als (apache-spark) [default], iteration 24 completed (28394.339 ms) ====== [2025-12-27T14:03:09.737Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-27T14:03:09.737Z] GC before operation: completed in 186.079 ms, heap usage 749.860 MB -> 70.518 MB. [2025-12-27T14:03:40.668Z] ====== als (apache-spark) [default], iteration 25 completed (26917.411 ms) ====== [2025-12-27T14:03:40.668Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-27T14:03:40.668Z] GC before operation: completed in 259.291 ms, heap usage 567.523 MB -> 70.520 MB. [2025-12-27T14:04:06.569Z] ====== als (apache-spark) [default], iteration 26 completed (26978.207 ms) ====== [2025-12-27T14:04:06.569Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-27T14:04:06.569Z] GC before operation: completed in 208.357 ms, heap usage 513.954 MB -> 70.673 MB. [2025-12-27T14:04:27.912Z] ====== als (apache-spark) [default], iteration 27 completed (20687.511 ms) ====== [2025-12-27T14:04:27.912Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-27T14:04:27.912Z] GC before operation: completed in 225.575 ms, heap usage 278.673 MB -> 69.957 MB. [2025-12-27T14:04:45.963Z] ====== als (apache-spark) [default], iteration 28 completed (19783.382 ms) ====== [2025-12-27T14:04:45.963Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-27T14:04:45.963Z] GC before operation: completed in 147.161 ms, heap usage 254.329 MB -> 70.210 MB. [2025-12-27T14:05:00.597Z] ====== als (apache-spark) [default], iteration 29 completed (12508.823 ms) ====== [2025-12-27T14:05:00.597Z] ----------------------------------- [2025-12-27T14:05:00.597Z] renaissance-als_0_PASSED [2025-12-27T14:05:00.597Z] ----------------------------------- [2025-12-27T14:05:00.597Z] [2025-12-27T14:05:00.597Z] TEST TEARDOWN: [2025-12-27T14:05:00.597Z] Nothing to be done for teardown. [2025-12-27T14:05:00.597Z] renaissance-als_0 Finish Time: Sat Dec 27 06:04:59 2025 Epoch Time (ms): 1766844299780