[2025-12-17T17:57:33.487Z] Running test renaissance-als_0 ... [2025-12-17T17:57:33.487Z] =============================================== [2025-12-17T17:57:34.092Z] renaissance-als_0 Start Time: Wed Dec 17 09:57:33 2025 Epoch Time (ms): 1765994253500 [2025-12-17T17:57:34.092Z] variation: NoOptions [2025-12-17T17:57:34.092Z] JVM_OPTIONS: [2025-12-17T17:57:34.092Z] { \ [2025-12-17T17:57:34.092Z] echo ""; echo "TEST SETUP:"; \ [2025-12-17T17:57:34.092Z] echo "Nothing to be done for setup."; \ [2025-12-17T17:57:34.092Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_176599424512/renaissance-als_0"; \ [2025-12-17T17:57:34.092Z] cd "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_176599424512/renaissance-als_0"; \ [2025-12-17T17:57:34.092Z] echo ""; echo "TESTING:"; \ [2025-12-17T17:57:34.092Z] "/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_176599424512/renaissance-als_0"/als.json" als; \ [2025-12-17T17:57:34.092Z] 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_176599424512/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-17T17:57:34.092Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-17T17:57:34.092Z] echo "Nothing to be done for teardown."; \ [2025-12-17T17:57:34.092Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_176599424512/TestTargetResult"; [2025-12-17T17:57:34.092Z] [2025-12-17T17:57:34.092Z] TEST SETUP: [2025-12-17T17:57:34.092Z] Nothing to be done for setup. [2025-12-17T17:57:34.092Z] [2025-12-17T17:57:34.092Z] TESTING: [2025-12-17T17:57:38.853Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-12-17T17:57:38.853Z] 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_176599424512/renaissance-als_0/launcher-095735-17207804423569827182/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-12-17T17:57:38.853Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-12-17T17:57:38.853Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-12-17T17:58:17.315Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-17T17:58:37.320Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-12-17T17:58:37.320Z] GC before operation: completed in 437.913 ms, heap usage 342.954 MB -> 34.196 MB. [2025-12-17T18:00:22.661Z] ====== als (apache-spark) [default], iteration 0 completed (89855.492 ms) ====== [2025-12-17T18:00:22.662Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-12-17T18:00:22.662Z] GC before operation: completed in 263.613 ms, heap usage 358.290 MB -> 61.167 MB. [2025-12-17T18:00:45.214Z] ====== als (apache-spark) [default], iteration 1 completed (33532.574 ms) ====== [2025-12-17T18:00:45.214Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-12-17T18:00:45.214Z] GC before operation: completed in 265.839 ms, heap usage 1023.652 MB -> 62.746 MB. [2025-12-17T18:01:29.892Z] ====== als (apache-spark) [default], iteration 2 completed (43002.400 ms) ====== [2025-12-17T18:01:29.892Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-12-17T18:01:29.892Z] GC before operation: completed in 287.070 ms, heap usage 726.858 MB -> 62.954 MB. [2025-12-17T18:02:00.415Z] ====== als (apache-spark) [default], iteration 3 completed (30388.403 ms) ====== [2025-12-17T18:02:00.415Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-12-17T18:02:00.415Z] GC before operation: completed in 702.789 ms, heap usage 875.865 MB -> 63.883 MB. [2025-12-17T18:02:38.575Z] ====== als (apache-spark) [default], iteration 4 completed (33434.367 ms) ====== [2025-12-17T18:02:38.575Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-12-17T18:02:38.575Z] GC before operation: completed in 295.742 ms, heap usage 231.029 MB -> 63.420 MB. [2025-12-17T18:03:31.098Z] ====== als (apache-spark) [default], iteration 5 completed (53037.252 ms) ====== [2025-12-17T18:03:31.098Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-12-17T18:03:31.098Z] GC before operation: completed in 452.344 ms, heap usage 1.394 GB -> 65.567 MB. [2025-12-17T18:04:33.727Z] ====== als (apache-spark) [default], iteration 6 completed (58149.385 ms) ====== [2025-12-17T18:04:33.727Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-12-17T18:04:33.727Z] GC before operation: completed in 213.964 ms, heap usage 831.899 MB -> 65.527 MB. [2025-12-17T18:05:00.199Z] ====== als (apache-spark) [default], iteration 7 completed (29721.530 ms) ====== [2025-12-17T18:05:00.199Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-12-17T18:05:00.199Z] GC before operation: completed in 314.414 ms, heap usage 610.863 MB -> 66.051 MB. [2025-12-17T18:05:36.669Z] ====== als (apache-spark) [default], iteration 8 completed (31882.933 ms) ====== [2025-12-17T18:05:36.669Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-12-17T18:05:36.669Z] GC before operation: completed in 302.084 ms, heap usage 430.584 MB -> 66.286 MB. [2025-12-17T18:06:07.319Z] ====== als (apache-spark) [default], iteration 9 completed (32254.469 ms) ====== [2025-12-17T18:06:07.319Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-12-17T18:06:07.319Z] GC before operation: completed in 706.917 ms, heap usage 150.182 MB -> 66.428 MB. [2025-12-17T18:06:46.035Z] ====== als (apache-spark) [default], iteration 10 completed (38513.533 ms) ====== [2025-12-17T18:06:46.036Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-12-17T18:06:46.036Z] GC before operation: completed in 1011.570 ms, heap usage 994.337 MB -> 66.917 MB. [2025-12-17T18:07:22.437Z] ====== als (apache-spark) [default], iteration 11 completed (34346.109 ms) ====== [2025-12-17T18:07:22.437Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-12-17T18:07:22.437Z] GC before operation: completed in 301.693 ms, heap usage 455.569 MB -> 66.702 MB. [2025-12-17T18:08:05.873Z] ====== als (apache-spark) [default], iteration 12 completed (38474.177 ms) ====== [2025-12-17T18:08:05.874Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-12-17T18:08:05.874Z] GC before operation: completed in 160.642 ms, heap usage 210.158 MB -> 66.429 MB. [2025-12-17T18:08:25.425Z] ====== als (apache-spark) [default], iteration 13 completed (20114.804 ms) ====== [2025-12-17T18:08:25.425Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-12-17T18:08:25.425Z] GC before operation: completed in 186.935 ms, heap usage 900.263 MB -> 67.695 MB. [2025-12-17T18:08:50.399Z] ====== als (apache-spark) [default], iteration 14 completed (28278.822 ms) ====== [2025-12-17T18:08:50.399Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-12-17T18:08:50.399Z] GC before operation: completed in 126.448 ms, heap usage 459.256 MB -> 67.532 MB. [2025-12-17T18:09:05.000Z] ====== als (apache-spark) [default], iteration 15 completed (13776.513 ms) ====== [2025-12-17T18:09:05.000Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-12-17T18:09:05.000Z] GC before operation: completed in 133.546 ms, heap usage 570.918 MB -> 67.822 MB. [2025-12-17T18:09:22.779Z] ====== als (apache-spark) [default], iteration 16 completed (15672.603 ms) ====== [2025-12-17T18:09:22.779Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-12-17T18:09:22.779Z] GC before operation: completed in 297.764 ms, heap usage 692.624 MB -> 68.361 MB. [2025-12-17T18:09:40.265Z] ====== als (apache-spark) [default], iteration 17 completed (18245.020 ms) ====== [2025-12-17T18:09:40.265Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-12-17T18:09:40.265Z] GC before operation: completed in 184.983 ms, heap usage 235.539 MB -> 67.854 MB. [2025-12-17T18:10:01.757Z] ====== als (apache-spark) [default], iteration 18 completed (21958.984 ms) ====== [2025-12-17T18:10:01.757Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-12-17T18:10:02.998Z] GC before operation: completed in 1411.449 ms, heap usage 377.559 MB -> 68.378 MB. [2025-12-17T18:10:24.604Z] ====== als (apache-spark) [default], iteration 19 completed (20723.740 ms) ====== [2025-12-17T18:10:24.604Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-12-17T18:10:24.604Z] GC before operation: completed in 127.299 ms, heap usage 700.940 MB -> 69.090 MB. [2025-12-17T18:10:42.492Z] ====== als (apache-spark) [default], iteration 20 completed (17920.239 ms) ====== [2025-12-17T18:10:42.492Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-12-17T18:10:42.492Z] GC before operation: completed in 211.319 ms, heap usage 705.748 MB -> 69.384 MB. [2025-12-17T18:11:12.205Z] ====== als (apache-spark) [default], iteration 21 completed (25590.880 ms) ====== [2025-12-17T18:11:12.205Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-12-17T18:11:12.205Z] GC before operation: completed in 242.722 ms, heap usage 1.033 GB -> 70.087 MB. [2025-12-17T18:11:26.949Z] ====== als (apache-spark) [default], iteration 22 completed (18199.867 ms) ====== [2025-12-17T18:11:26.949Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-12-17T18:11:26.949Z] GC before operation: completed in 154.788 ms, heap usage 601.550 MB -> 69.741 MB. [2025-12-17T18:11:52.578Z] ====== als (apache-spark) [default], iteration 23 completed (23479.716 ms) ====== [2025-12-17T18:11:52.578Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-12-17T18:11:52.578Z] GC before operation: completed in 188.259 ms, heap usage 481.580 MB -> 69.891 MB. [2025-12-17T18:12:10.289Z] ====== als (apache-spark) [default], iteration 24 completed (17931.330 ms) ====== [2025-12-17T18:12:10.289Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-12-17T18:12:10.289Z] GC before operation: completed in 176.114 ms, heap usage 448.603 MB -> 70.090 MB. [2025-12-17T18:12:25.039Z] ====== als (apache-spark) [default], iteration 25 completed (15253.346 ms) ====== [2025-12-17T18:12:25.039Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-12-17T18:12:25.039Z] GC before operation: completed in 122.437 ms, heap usage 833.139 MB -> 70.892 MB. [2025-12-17T18:12:50.353Z] ====== als (apache-spark) [default], iteration 26 completed (24900.818 ms) ====== [2025-12-17T18:12:50.353Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-12-17T18:12:50.353Z] GC before operation: completed in 965.032 ms, heap usage 256.147 MB -> 70.297 MB. [2025-12-17T18:13:12.696Z] ====== als (apache-spark) [default], iteration 27 completed (19510.272 ms) ====== [2025-12-17T18:13:12.696Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-12-17T18:13:12.696Z] GC before operation: completed in 190.125 ms, heap usage 433.271 MB -> 70.957 MB. [2025-12-17T18:13:43.723Z] ====== als (apache-spark) [default], iteration 28 completed (29345.865 ms) ====== [2025-12-17T18:13:43.723Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-12-17T18:13:43.723Z] GC before operation: completed in 291.395 ms, heap usage 783.262 MB -> 71.056 MB. [2025-12-17T18:14:14.332Z] ====== als (apache-spark) [default], iteration 29 completed (32598.184 ms) ====== [2025-12-17T18:14:18.076Z] ----------------------------------- [2025-12-17T18:14:18.076Z] renaissance-als_0_PASSED [2025-12-17T18:14:18.076Z] ----------------------------------- [2025-12-17T18:14:18.076Z] [2025-12-17T18:14:18.076Z] TEST TEARDOWN: [2025-12-17T18:14:18.076Z] Nothing to be done for teardown. [2025-12-17T18:14:18.076Z] renaissance-als_0 Finish Time: Wed Dec 17 10:14:17 2025 Epoch Time (ms): 1765995257786