[2025-05-22T22:04:34.194Z] Running test renaissance-log-regression_0 ... [2025-05-22T22:04:34.194Z] =============================================== [2025-05-22T22:04:34.194Z] renaissance-log-regression_0 Start Time: Thu May 22 18:04:33 2025 Epoch Time (ms): 1747951473903 [2025-05-22T22:04:34.194Z] variation: NoOptions [2025-05-22T22:04:34.194Z] JVM_OPTIONS: [2025-05-22T22:04:34.194Z] { \ [2025-05-22T22:04:34.194Z] echo ""; echo "TEST SETUP:"; \ [2025-05-22T22:04:34.194Z] echo "Nothing to be done for setup."; \ [2025-05-22T22:04:34.194Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17479510226563/renaissance-log-regression_0"; \ [2025-05-22T22:04:34.194Z] cd "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17479510226563/renaissance-log-regression_0"; \ [2025-05-22T22:04:34.194Z] echo ""; echo "TESTING:"; \ [2025-05-22T22:04:34.194Z] "/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_17479510226563/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-05-22T22:04:34.194Z] 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_17479510226563/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-22T22:04:34.194Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-22T22:04:34.194Z] echo "Nothing to be done for teardown."; \ [2025-05-22T22:04:34.194Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17479510226563/TestTargetResult"; [2025-05-22T22:04:34.194Z] [2025-05-22T22:04:34.194Z] TEST SETUP: [2025-05-22T22:04:34.194Z] Nothing to be done for setup. [2025-05-22T22:04:34.194Z] [2025-05-22T22:04:34.194Z] TESTING: [2025-05-22T22:04:34.581Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-05-22T22:04:34.581Z] 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_17479510226563/renaissance-log-regression_0/launcher-180434-11405375517119529130/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-05-22T22:04:34.581Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-05-22T22:04:34.581Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-05-22T22:04:37.986Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-05-22T22:04:39.310Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-05-22T22:04:39.310Z] GC before operation: completed in 32.265 ms, heap usage 67.974 MB -> 35.147 MB. [2025-05-22T22:04:45.752Z] ====== log-regression (apache-spark) [default], iteration 0 completed (5580.617 ms) ====== [2025-05-22T22:04:45.752Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-05-22T22:04:45.752Z] GC before operation: completed in 57.039 ms, heap usage 208.958 MB -> 116.561 MB. [2025-05-22T22:04:46.142Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1157.850 ms) ====== [2025-05-22T22:04:46.142Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-05-22T22:04:46.142Z] GC before operation: completed in 35.876 ms, heap usage 535.567 MB -> 117.999 MB. [2025-05-22T22:04:46.994Z] ====== log-regression (apache-spark) [default], iteration 2 completed (971.546 ms) ====== [2025-05-22T22:04:46.994Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-05-22T22:04:46.994Z] GC before operation: completed in 50.745 ms, heap usage 270.201 MB -> 117.631 MB. [2025-05-22T22:04:47.826Z] ====== log-regression (apache-spark) [default], iteration 3 completed (965.086 ms) ====== [2025-05-22T22:04:47.826Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-05-22T22:04:48.214Z] GC before operation: completed in 45.742 ms, heap usage 408.053 MB -> 118.319 MB. [2025-05-22T22:04:49.044Z] ====== log-regression (apache-spark) [default], iteration 4 completed (871.345 ms) ====== [2025-05-22T22:04:49.044Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-05-22T22:04:49.044Z] GC before operation: completed in 45.317 ms, heap usage 742.837 MB -> 119.734 MB. [2025-05-22T22:04:49.869Z] ====== log-regression (apache-spark) [default], iteration 5 completed (881.487 ms) ====== [2025-05-22T22:04:49.869Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-05-22T22:04:49.869Z] GC before operation: completed in 42.930 ms, heap usage 527.229 MB -> 119.218 MB. [2025-05-22T22:04:50.714Z] ====== log-regression (apache-spark) [default], iteration 6 completed (798.766 ms) ====== [2025-05-22T22:04:50.714Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-05-22T22:04:50.714Z] GC before operation: completed in 46.671 ms, heap usage 455.080 MB -> 119.242 MB. [2025-05-22T22:04:51.550Z] ====== log-regression (apache-spark) [default], iteration 7 completed (815.460 ms) ====== [2025-05-22T22:04:51.550Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-05-22T22:04:51.550Z] GC before operation: completed in 44.056 ms, heap usage 561.845 MB -> 119.813 MB. [2025-05-22T22:04:52.381Z] ====== log-regression (apache-spark) [default], iteration 8 completed (775.007 ms) ====== [2025-05-22T22:04:52.381Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-05-22T22:04:52.381Z] GC before operation: completed in 52.262 ms, heap usage 541.717 MB -> 119.916 MB. [2025-05-22T22:04:53.227Z] ====== log-regression (apache-spark) [default], iteration 9 completed (764.922 ms) ====== [2025-05-22T22:04:53.227Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-05-22T22:04:53.227Z] GC before operation: completed in 50.680 ms, heap usage 434.473 MB -> 119.913 MB. [2025-05-22T22:04:54.054Z] ====== log-regression (apache-spark) [default], iteration 10 completed (769.774 ms) ====== [2025-05-22T22:04:54.054Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-05-22T22:04:54.054Z] GC before operation: completed in 45.309 ms, heap usage 446.068 MB -> 120.009 MB. [2025-05-22T22:04:54.882Z] ====== log-regression (apache-spark) [default], iteration 11 completed (770.391 ms) ====== [2025-05-22T22:04:54.882Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-05-22T22:04:54.882Z] GC before operation: completed in 46.741 ms, heap usage 439.008 MB -> 120.152 MB. [2025-05-22T22:04:55.710Z] ====== log-regression (apache-spark) [default], iteration 12 completed (766.154 ms) ====== [2025-05-22T22:04:55.710Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-05-22T22:04:55.710Z] GC before operation: completed in 50.943 ms, heap usage 291.831 MB -> 119.880 MB. [2025-05-22T22:04:56.551Z] ====== log-regression (apache-spark) [default], iteration 13 completed (715.482 ms) ====== [2025-05-22T22:04:56.551Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-05-22T22:04:56.551Z] GC before operation: completed in 50.716 ms, heap usage 414.716 MB -> 120.473 MB. [2025-05-22T22:04:57.398Z] ====== log-regression (apache-spark) [default], iteration 14 completed (750.943 ms) ====== [2025-05-22T22:04:57.398Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-05-22T22:04:57.398Z] GC before operation: completed in 58.042 ms, heap usage 693.633 MB -> 121.678 MB. [2025-05-22T22:04:58.228Z] ====== log-regression (apache-spark) [default], iteration 15 completed (766.752 ms) ====== [2025-05-22T22:04:58.229Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-05-22T22:04:58.229Z] GC before operation: completed in 51.930 ms, heap usage 453.484 MB -> 121.029 MB. [2025-05-22T22:04:58.659Z] ====== log-regression (apache-spark) [default], iteration 16 completed (762.657 ms) ====== [2025-05-22T22:04:58.659Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-05-22T22:04:59.051Z] GC before operation: completed in 48.898 ms, heap usage 247.115 MB -> 120.420 MB. [2025-05-22T22:04:59.891Z] ====== log-regression (apache-spark) [default], iteration 17 completed (762.312 ms) ====== [2025-05-22T22:04:59.891Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-05-22T22:04:59.891Z] GC before operation: completed in 46.896 ms, heap usage 337.018 MB -> 120.915 MB. [2025-05-22T22:05:00.293Z] ====== log-regression (apache-spark) [default], iteration 18 completed (710.041 ms) ====== [2025-05-22T22:05:00.293Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-05-22T22:05:00.293Z] GC before operation: completed in 54.057 ms, heap usage 673.734 MB -> 122.227 MB. [2025-05-22T22:05:01.189Z] ====== log-regression (apache-spark) [default], iteration 19 completed (730.628 ms) ====== [2025-05-22T22:05:01.189Z] ----------------------------------- [2025-05-22T22:05:01.189Z] renaissance-log-regression_0_PASSED [2025-05-22T22:05:01.189Z] ----------------------------------- [2025-05-22T22:05:01.189Z] [2025-05-22T22:05:01.189Z] TEST TEARDOWN: [2025-05-22T22:05:01.189Z] Nothing to be done for teardown. [2025-05-22T22:05:01.189Z] renaissance-log-regression_0 Finish Time: Thu May 22 18:05:01 2025 Epoch Time (ms): 1747951501096