[2025-05-30T00:48:33.142Z] Running test renaissance-als_0 ... [2025-05-30T00:48:33.142Z] =============================================== [2025-05-30T00:48:33.142Z] renaissance-als_0 Start Time: Thu May 29 17:48:32 2025 Epoch Time (ms): 1748566112399 [2025-05-30T00:48:33.142Z] variation: NoOptions [2025-05-30T00:48:33.142Z] JVM_OPTIONS: [2025-05-30T00:48:33.142Z] { \ [2025-05-30T00:48:33.142Z] echo ""; echo "TEST SETUP:"; \ [2025-05-30T00:48:33.142Z] echo "Nothing to be done for setup."; \ [2025-05-30T00:48:33.142Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17485661111589/renaissance-als_0"; \ [2025-05-30T00:48:33.142Z] cd "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17485661111589/renaissance-als_0"; \ [2025-05-30T00:48:33.142Z] echo ""; echo "TESTING:"; \ [2025-05-30T00:48:33.142Z] "/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_17485661111589/renaissance-als_0"/als.json" als; \ [2025-05-30T00:48:33.142Z] 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_17485661111589/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-30T00:48:33.142Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-30T00:48:33.142Z] echo "Nothing to be done for teardown."; \ [2025-05-30T00:48:33.142Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17485661111589/TestTargetResult"; [2025-05-30T00:48:33.142Z] [2025-05-30T00:48:33.142Z] TEST SETUP: [2025-05-30T00:48:33.142Z] Nothing to be done for setup. [2025-05-30T00:48:33.142Z] [2025-05-30T00:48:33.142Z] TESTING: [2025-05-30T00:48:34.156Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-05-30T00:48:34.156Z] 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_17485661111589/renaissance-als_0/launcher-174832-11670575052564895090/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-05-30T00:48:34.156Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-05-30T00:48:34.156Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-05-30T00:48:42.856Z] NOTE: 'als' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-05-30T00:48:48.846Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-05-30T00:48:48.846Z] GC before operation: completed in 53.657 ms, heap usage 208.000 MB -> 34.910 MB. [2025-05-30T00:49:32.185Z] ====== als (apache-spark) [default], iteration 0 completed (39400.853 ms) ====== [2025-05-30T00:49:32.185Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-05-30T00:49:32.185Z] GC before operation: completed in 227.684 ms, heap usage 734.770 MB -> 62.098 MB. [2025-05-30T00:49:50.786Z] ====== als (apache-spark) [default], iteration 1 completed (20757.471 ms) ====== [2025-05-30T00:49:50.786Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-05-30T00:49:50.786Z] GC before operation: completed in 259.101 ms, heap usage 445.108 MB -> 62.636 MB. [2025-05-30T00:50:12.907Z] ====== als (apache-spark) [default], iteration 2 completed (19417.062 ms) ====== [2025-05-30T00:50:12.907Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-05-30T00:50:12.907Z] GC before operation: completed in 107.995 ms, heap usage 285.292 MB -> 63.143 MB. [2025-05-30T00:50:32.373Z] ====== als (apache-spark) [default], iteration 3 completed (20063.733 ms) ====== [2025-05-30T00:50:32.373Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-05-30T00:50:32.373Z] GC before operation: completed in 218.018 ms, heap usage 730.155 MB -> 64.464 MB. [2025-05-30T00:50:50.542Z] ====== als (apache-spark) [default], iteration 4 completed (16850.083 ms) ====== [2025-05-30T00:50:50.543Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-05-30T00:50:50.543Z] GC before operation: completed in 222.394 ms, heap usage 1.015 GB -> 65.145 MB. [2025-05-30T00:51:08.849Z] ====== als (apache-spark) [default], iteration 5 completed (19018.795 ms) ====== [2025-05-30T00:51:08.849Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-05-30T00:51:08.849Z] GC before operation: completed in 120.575 ms, heap usage 245.283 MB -> 64.730 MB. [2025-05-30T00:51:27.205Z] ====== als (apache-spark) [default], iteration 6 completed (17543.722 ms) ====== [2025-05-30T00:51:27.205Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-05-30T00:51:27.205Z] GC before operation: completed in 197.896 ms, heap usage 157.496 MB -> 65.229 MB. [2025-05-30T00:51:45.427Z] ====== als (apache-spark) [default], iteration 7 completed (18347.201 ms) ====== [2025-05-30T00:51:45.427Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-05-30T00:51:45.427Z] GC before operation: completed in 162.252 ms, heap usage 184.211 MB -> 65.753 MB. [2025-05-30T00:52:03.896Z] ====== als (apache-spark) [default], iteration 8 completed (18453.609 ms) ====== [2025-05-30T00:52:03.896Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-05-30T00:52:03.896Z] GC before operation: completed in 139.097 ms, heap usage 253.913 MB -> 66.455 MB. [2025-05-30T00:52:22.226Z] ====== als (apache-spark) [default], iteration 9 completed (17618.964 ms) ====== [2025-05-30T00:52:22.226Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-05-30T00:52:22.226Z] GC before operation: completed in 185.154 ms, heap usage 444.452 MB -> 66.833 MB. [2025-05-30T00:52:41.020Z] ====== als (apache-spark) [default], iteration 10 completed (17165.214 ms) ====== [2025-05-30T00:52:41.020Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-05-30T00:52:41.020Z] GC before operation: completed in 154.023 ms, heap usage 481.124 MB -> 67.091 MB. [2025-05-30T00:52:56.358Z] ====== als (apache-spark) [default], iteration 11 completed (17026.353 ms) ====== [2025-05-30T00:52:56.358Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-05-30T00:52:56.358Z] GC before operation: completed in 215.192 ms, heap usage 752.105 MB -> 67.728 MB. [2025-05-30T00:53:14.521Z] ====== als (apache-spark) [default], iteration 12 completed (16355.667 ms) ====== [2025-05-30T00:53:14.522Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-05-30T00:53:14.522Z] GC before operation: completed in 220.200 ms, heap usage 545.655 MB -> 67.836 MB. [2025-05-30T00:53:30.004Z] ====== als (apache-spark) [default], iteration 13 completed (16161.962 ms) ====== [2025-05-30T00:53:30.004Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-05-30T00:53:30.004Z] GC before operation: completed in 327.476 ms, heap usage 711.705 MB -> 68.341 MB. [2025-05-30T00:53:51.446Z] ====== als (apache-spark) [default], iteration 14 completed (19412.997 ms) ====== [2025-05-30T00:53:51.446Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-05-30T00:53:51.446Z] GC before operation: completed in 191.576 ms, heap usage 521.507 MB -> 68.273 MB. [2025-05-30T00:54:17.158Z] ====== als (apache-spark) [default], iteration 15 completed (22645.651 ms) ====== [2025-05-30T00:54:17.158Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-05-30T00:54:17.158Z] GC before operation: completed in 170.564 ms, heap usage 192.757 MB -> 67.955 MB. [2025-05-30T00:54:39.272Z] ====== als (apache-spark) [default], iteration 16 completed (22998.456 ms) ====== [2025-05-30T00:54:39.272Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-05-30T00:54:39.272Z] GC before operation: completed in 146.102 ms, heap usage 474.954 MB -> 68.590 MB. [2025-05-30T00:54:57.538Z] ====== als (apache-spark) [default], iteration 17 completed (19421.523 ms) ====== [2025-05-30T00:54:57.538Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-05-30T00:54:57.538Z] GC before operation: completed in 108.878 ms, heap usage 366.064 MB -> 68.621 MB. [2025-05-30T00:55:18.973Z] ====== als (apache-spark) [default], iteration 18 completed (21941.307 ms) ====== [2025-05-30T00:55:18.973Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-05-30T00:55:18.973Z] GC before operation: completed in 259.100 ms, heap usage 351.412 MB -> 68.959 MB. [2025-05-30T00:55:40.535Z] ====== als (apache-spark) [default], iteration 19 completed (18976.764 ms) ====== [2025-05-30T00:55:40.535Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-05-30T00:55:40.535Z] GC before operation: completed in 156.482 ms, heap usage 374.401 MB -> 69.160 MB. [2025-05-30T00:56:02.607Z] ====== als (apache-spark) [default], iteration 20 completed (21091.332 ms) ====== [2025-05-30T00:56:02.607Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-05-30T00:56:02.607Z] GC before operation: completed in 206.059 ms, heap usage 269.289 MB -> 69.427 MB. [2025-05-30T00:56:15.016Z] ====== als (apache-spark) [default], iteration 21 completed (13250.195 ms) ====== [2025-05-30T00:56:15.016Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-05-30T00:56:15.016Z] GC before operation: completed in 92.412 ms, heap usage 270.005 MB -> 69.806 MB. [2025-05-30T00:56:30.320Z] ====== als (apache-spark) [default], iteration 22 completed (16032.225 ms) ====== [2025-05-30T00:56:30.320Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-05-30T00:56:30.320Z] GC before operation: completed in 189.534 ms, heap usage 294.167 MB -> 69.926 MB. [2025-05-30T00:56:55.943Z] ====== als (apache-spark) [default], iteration 23 completed (22946.063 ms) ====== [2025-05-30T00:56:55.943Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-05-30T00:56:55.943Z] GC before operation: completed in 208.504 ms, heap usage 227.827 MB -> 69.973 MB. [2025-05-30T00:57:17.534Z] ====== als (apache-spark) [default], iteration 24 completed (23368.147 ms) ====== [2025-05-30T00:57:17.534Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-05-30T00:57:17.534Z] GC before operation: completed in 185.705 ms, heap usage 459.054 MB -> 70.788 MB. [2025-05-30T00:57:43.462Z] ====== als (apache-spark) [default], iteration 25 completed (22170.330 ms) ====== [2025-05-30T00:57:43.462Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-05-30T00:57:43.462Z] GC before operation: completed in 190.910 ms, heap usage 308.377 MB -> 70.692 MB. [2025-05-30T00:58:02.043Z] ====== als (apache-spark) [default], iteration 26 completed (21914.578 ms) ====== [2025-05-30T00:58:02.043Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-05-30T00:58:02.531Z] GC before operation: completed in 150.001 ms, heap usage 469.657 MB -> 71.186 MB. [2025-05-30T00:58:24.475Z] ====== als (apache-spark) [default], iteration 27 completed (20724.256 ms) ====== [2025-05-30T00:58:24.475Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-05-30T00:58:24.475Z] GC before operation: completed in 116.511 ms, heap usage 137.773 MB -> 70.868 MB. [2025-05-30T00:58:50.017Z] ====== als (apache-spark) [default], iteration 28 completed (22723.536 ms) ====== [2025-05-30T00:58:50.017Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-05-30T00:58:50.017Z] GC before operation: completed in 216.984 ms, heap usage 355.367 MB -> 71.085 MB. [2025-05-30T00:59:11.658Z] ====== als (apache-spark) [default], iteration 29 completed (23547.188 ms) ====== [2025-05-30T00:59:11.658Z] ----------------------------------- [2025-05-30T00:59:11.658Z] renaissance-als_0_PASSED [2025-05-30T00:59:11.658Z] ----------------------------------- [2025-05-30T00:59:11.658Z] [2025-05-30T00:59:11.658Z] TEST TEARDOWN: [2025-05-30T00:59:11.658Z] Nothing to be done for teardown. [2025-05-30T00:59:11.658Z] renaissance-als_0 Finish Time: Thu May 29 17:59:10 2025 Epoch Time (ms): 1748566750706