[2025-10-31T12:27:31.568Z] Running test renaissance-log-regression_0 ... [2025-10-31T12:27:31.568Z] =============================================== [2025-10-31T12:27:31.568Z] renaissance-log-regression_0 Start Time: Fri Oct 31 08:27:29 2025 Epoch Time (ms): 1761913649146 [2025-10-31T12:27:31.568Z] variation: NoOptions [2025-10-31T12:27:31.568Z] JVM_OPTIONS: [2025-10-31T12:27:31.568Z] { \ [2025-10-31T12:27:31.568Z] echo ""; echo "TEST SETUP:"; \ [2025-10-31T12:27:31.568Z] echo "Nothing to be done for setup."; \ [2025-10-31T12:27:31.568Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17619130938873/renaissance-log-regression_0"; \ [2025-10-31T12:27:31.568Z] cd "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17619130938873/renaissance-log-regression_0"; \ [2025-10-31T12:27:31.568Z] echo ""; echo "TESTING:"; \ [2025-10-31T12:27:31.568Z] "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_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_aarch64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17619130938873/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-10-31T12:27:31.568Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17619130938873/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-31T12:27:31.568Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-31T12:27:31.568Z] echo "Nothing to be done for teardown."; \ [2025-10-31T12:27:31.569Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17619130938873/TestTargetResult"; [2025-10-31T12:27:31.569Z] [2025-10-31T12:27:31.569Z] TEST SETUP: [2025-10-31T12:27:31.569Z] Nothing to be done for setup. [2025-10-31T12:27:31.569Z] [2025-10-31T12:27:31.569Z] TESTING: [2025-10-31T12:27:31.926Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-10-31T12:27:31.926Z] WARNING: sun.misc.Unsafe::objectFieldOffset has been called by scala.runtime.LazyVals$ (file:/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/output_17619130938873/renaissance-log-regression_0/launcher-082729-2023378047355748791/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-10-31T12:27:31.926Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-10-31T12:27:31.926Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-10-31T12:27:35.921Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-10-31T12:27:37.157Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-10-31T12:27:37.157Z] GC before operation: completed in 35.593 ms, heap usage 59.098 MB -> 35.396 MB. [2025-10-31T12:27:43.359Z] ====== log-regression (apache-spark) [default], iteration 0 completed (6076.655 ms) ====== [2025-10-31T12:27:43.359Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-10-31T12:27:43.359Z] GC before operation: completed in 56.606 ms, heap usage 225.344 MB -> 119.470 MB. [2025-10-31T12:27:44.604Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1244.560 ms) ====== [2025-10-31T12:27:44.604Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-10-31T12:27:44.604Z] GC before operation: completed in 46.694 ms, heap usage 251.263 MB -> 120.000 MB. [2025-10-31T12:27:45.874Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1062.228 ms) ====== [2025-10-31T12:27:45.874Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-10-31T12:27:45.874Z] GC before operation: completed in 54.843 ms, heap usage 809.048 MB -> 122.090 MB. [2025-10-31T12:27:46.674Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1016.644 ms) ====== [2025-10-31T12:27:46.674Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-10-31T12:27:46.674Z] GC before operation: completed in 48.707 ms, heap usage 420.245 MB -> 121.194 MB. [2025-10-31T12:27:47.915Z] ====== log-regression (apache-spark) [default], iteration 4 completed (935.031 ms) ====== [2025-10-31T12:27:47.915Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-10-31T12:27:47.915Z] GC before operation: completed in 58.200 ms, heap usage 802.958 MB -> 122.729 MB. [2025-10-31T12:27:48.720Z] ====== log-regression (apache-spark) [default], iteration 5 completed (989.340 ms) ====== [2025-10-31T12:27:48.720Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-10-31T12:27:48.720Z] GC before operation: completed in 58.268 ms, heap usage 536.814 MB -> 122.060 MB. [2025-10-31T12:27:49.980Z] ====== log-regression (apache-spark) [default], iteration 6 completed (982.289 ms) ====== [2025-10-31T12:27:49.980Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-10-31T12:27:49.980Z] GC before operation: completed in 58.030 ms, heap usage 310.518 MB -> 121.590 MB. [2025-10-31T12:27:50.767Z] ====== log-regression (apache-spark) [default], iteration 7 completed (932.772 ms) ====== [2025-10-31T12:27:50.767Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-10-31T12:27:50.767Z] GC before operation: completed in 51.379 ms, heap usage 267.841 MB -> 121.678 MB. [2025-10-31T12:27:51.556Z] ====== log-regression (apache-spark) [default], iteration 8 completed (895.346 ms) ====== [2025-10-31T12:27:51.556Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-10-31T12:27:51.556Z] GC before operation: completed in 45.607 ms, heap usage 729.345 MB -> 123.308 MB. [2025-10-31T12:27:52.822Z] ====== log-regression (apache-spark) [default], iteration 9 completed (947.568 ms) ====== [2025-10-31T12:27:52.822Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-10-31T12:27:52.822Z] GC before operation: completed in 57.024 ms, heap usage 282.788 MB -> 122.134 MB. [2025-10-31T12:27:53.600Z] ====== log-regression (apache-spark) [default], iteration 10 completed (872.976 ms) ====== [2025-10-31T12:27:53.600Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-10-31T12:27:53.600Z] GC before operation: completed in 60.986 ms, heap usage 461.879 MB -> 123.014 MB. [2025-10-31T12:27:54.839Z] ====== log-regression (apache-spark) [default], iteration 11 completed (913.828 ms) ====== [2025-10-31T12:27:54.839Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-10-31T12:27:54.839Z] GC before operation: completed in 59.618 ms, heap usage 314.847 MB -> 122.664 MB. [2025-10-31T12:27:55.623Z] ====== log-regression (apache-spark) [default], iteration 12 completed (863.259 ms) ====== [2025-10-31T12:27:55.623Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-10-31T12:27:55.623Z] GC before operation: completed in 60.752 ms, heap usage 286.477 MB -> 122.792 MB. [2025-10-31T12:27:56.397Z] ====== log-regression (apache-spark) [default], iteration 13 completed (883.640 ms) ====== [2025-10-31T12:27:56.397Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-10-31T12:27:56.397Z] GC before operation: completed in 65.048 ms, heap usage 713.131 MB -> 124.265 MB. [2025-10-31T12:27:57.660Z] ====== log-regression (apache-spark) [default], iteration 14 completed (895.703 ms) ====== [2025-10-31T12:27:57.660Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-10-31T12:27:57.661Z] GC before operation: completed in 58.804 ms, heap usage 656.226 MB -> 124.344 MB. [2025-10-31T12:27:58.442Z] ====== log-regression (apache-spark) [default], iteration 15 completed (864.947 ms) ====== [2025-10-31T12:27:58.443Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-10-31T12:27:58.443Z] GC before operation: completed in 61.159 ms, heap usage 342.814 MB -> 123.478 MB. [2025-10-31T12:27:59.247Z] ====== log-regression (apache-spark) [default], iteration 16 completed (877.855 ms) ====== [2025-10-31T12:27:59.247Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-10-31T12:27:59.247Z] GC before operation: completed in 61.632 ms, heap usage 241.559 MB -> 123.510 MB. [2025-10-31T12:28:00.070Z] ====== log-regression (apache-spark) [default], iteration 17 completed (877.756 ms) ====== [2025-10-31T12:28:00.070Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-10-31T12:28:00.442Z] GC before operation: completed in 65.727 ms, heap usage 250.431 MB -> 123.691 MB. [2025-10-31T12:28:01.239Z] ====== log-regression (apache-spark) [default], iteration 18 completed (862.487 ms) ====== [2025-10-31T12:28:01.239Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-10-31T12:28:01.239Z] GC before operation: completed in 62.910 ms, heap usage 744.531 MB -> 125.393 MB. [2025-10-31T12:28:02.056Z] ====== log-regression (apache-spark) [default], iteration 19 completed (846.723 ms) ====== [2025-10-31T12:28:02.056Z] ----------------------------------- [2025-10-31T12:28:02.056Z] renaissance-log-regression_0_PASSED [2025-10-31T12:28:02.056Z] ----------------------------------- [2025-10-31T12:28:02.056Z] [2025-10-31T12:28:02.056Z] TEST TEARDOWN: [2025-10-31T12:28:02.056Z] Nothing to be done for teardown. [2025-10-31T12:28:02.422Z] renaissance-log-regression_0 Finish Time: Fri Oct 31 08:27:59 2025 Epoch Time (ms): 1761913679824