[2025-05-30T01:22:53.960Z] Running test renaissance-log-regression_0 ... [2025-05-30T01:22:53.960Z] =============================================== [2025-05-30T01:22:54.402Z] renaissance-log-regression_0 Start Time: Thu May 29 18:22:53 2025 Epoch Time (ms): 1748568173345 [2025-05-30T01:22:54.402Z] variation: NoOptions [2025-05-30T01:22:54.402Z] JVM_OPTIONS: [2025-05-30T01:22:54.402Z] { \ [2025-05-30T01:22:54.402Z] echo ""; echo "TEST SETUP:"; \ [2025-05-30T01:22:54.402Z] echo "Nothing to be done for setup."; \ [2025-05-30T01:22:54.402Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17485661111589/renaissance-log-regression_0"; \ [2025-05-30T01:22:54.402Z] cd "/Users/admin/workspace/workspace/Test_openjdk25_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17485661111589/renaissance-log-regression_0"; \ [2025-05-30T01:22:54.402Z] echo ""; echo "TESTING:"; \ [2025-05-30T01:22:54.402Z] "/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-log-regression_0"/log-regression.json" log-regression; \ [2025-05-30T01:22:54.402Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_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-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-30T01:22:54.402Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-30T01:22:54.402Z] echo "Nothing to be done for teardown."; \ [2025-05-30T01:22:54.402Z] } 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-30T01:22:54.402Z] [2025-05-30T01:22:54.402Z] TEST SETUP: [2025-05-30T01:22:54.402Z] Nothing to be done for setup. [2025-05-30T01:22:54.402Z] [2025-05-30T01:22:54.402Z] TESTING: [2025-05-30T01:22:55.928Z] WARNING: A terminally deprecated method in sun.misc.Unsafe has been called [2025-05-30T01:22:55.928Z] 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-log-regression_0/launcher-182253-2112233855865759369/renaissance-harness_3/lib/scala3-library_3-3.3.4.jar) [2025-05-30T01:22:55.928Z] WARNING: Please consider reporting this to the maintainers of class scala.runtime.LazyVals$ [2025-05-30T01:22:55.928Z] WARNING: sun.misc.Unsafe::objectFieldOffset will be removed in a future release [2025-05-30T01:23:06.463Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-05-30T01:23:13.487Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-05-30T01:23:13.487Z] GC before operation: completed in 192.543 ms, heap usage 59.701 MB -> 34.811 MB. [2025-05-30T01:23:39.012Z] ====== log-regression (apache-spark) [default], iteration 0 completed (22356.897 ms) ====== [2025-05-30T01:23:39.012Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-05-30T01:23:39.012Z] GC before operation: completed in 296.201 ms, heap usage 264.308 MB -> 116.552 MB. [2025-05-30T01:23:42.942Z] ====== log-regression (apache-spark) [default], iteration 1 completed (6157.109 ms) ====== [2025-05-30T01:23:42.942Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-05-30T01:23:42.942Z] GC before operation: completed in 197.680 ms, heap usage 532.545 MB -> 117.906 MB. [2025-05-30T01:23:47.024Z] ====== log-regression (apache-spark) [default], iteration 2 completed (4197.419 ms) ====== [2025-05-30T01:23:47.024Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-05-30T01:23:47.024Z] GC before operation: completed in 229.950 ms, heap usage 222.449 MB -> 117.509 MB. [2025-05-30T01:23:52.890Z] ====== log-regression (apache-spark) [default], iteration 3 completed (5477.566 ms) ====== [2025-05-30T01:23:52.890Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-05-30T01:23:52.890Z] GC before operation: completed in 158.299 ms, heap usage 599.140 MB -> 118.808 MB. [2025-05-30T01:23:56.663Z] ====== log-regression (apache-spark) [default], iteration 4 completed (4216.119 ms) ====== [2025-05-30T01:23:56.663Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-05-30T01:23:57.156Z] GC before operation: completed in 209.872 ms, heap usage 414.517 MB -> 118.538 MB. [2025-05-30T01:24:01.926Z] ====== log-regression (apache-spark) [default], iteration 5 completed (4495.355 ms) ====== [2025-05-30T01:24:01.926Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-05-30T01:24:01.926Z] GC before operation: completed in 154.828 ms, heap usage 1.205 GB -> 119.902 MB. [2025-05-30T01:24:05.896Z] ====== log-regression (apache-spark) [default], iteration 6 completed (3665.136 ms) ====== [2025-05-30T01:24:05.896Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-05-30T01:24:05.896Z] GC before operation: completed in 172.839 ms, heap usage 477.864 MB -> 119.203 MB. [2025-05-30T01:24:09.717Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3866.882 ms) ====== [2025-05-30T01:24:09.717Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-05-30T01:24:09.717Z] GC before operation: completed in 194.337 ms, heap usage 572.773 MB -> 119.642 MB. [2025-05-30T01:24:13.527Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3397.484 ms) ====== [2025-05-30T01:24:13.527Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-05-30T01:24:13.527Z] GC before operation: completed in 139.165 ms, heap usage 284.146 MB -> 118.878 MB. [2025-05-30T01:24:17.445Z] ====== log-regression (apache-spark) [default], iteration 9 completed (4031.032 ms) ====== [2025-05-30T01:24:17.445Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-05-30T01:24:17.445Z] GC before operation: completed in 152.301 ms, heap usage 739.137 MB -> 120.662 MB. [2025-05-30T01:24:21.333Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3811.630 ms) ====== [2025-05-30T01:24:21.333Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-05-30T01:24:21.333Z] GC before operation: completed in 169.664 ms, heap usage 519.330 MB -> 120.156 MB. [2025-05-30T01:24:24.474Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3216.601 ms) ====== [2025-05-30T01:24:24.474Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-05-30T01:24:25.019Z] GC before operation: completed in 191.205 ms, heap usage 315.120 MB -> 119.748 MB. [2025-05-30T01:24:28.182Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3593.949 ms) ====== [2025-05-30T01:24:28.182Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-05-30T01:24:28.624Z] GC before operation: completed in 225.699 ms, heap usage 453.980 MB -> 120.365 MB. [2025-05-30T01:24:32.392Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3373.190 ms) ====== [2025-05-30T01:24:32.392Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-05-30T01:24:32.392Z] GC before operation: completed in 98.524 ms, heap usage 479.752 MB -> 120.643 MB. [2025-05-30T01:24:35.336Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3223.241 ms) ====== [2025-05-30T01:24:35.336Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-05-30T01:24:35.336Z] GC before operation: completed in 197.631 ms, heap usage 274.717 MB -> 120.295 MB. [2025-05-30T01:24:38.994Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3051.264 ms) ====== [2025-05-30T01:24:38.994Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-05-30T01:24:38.994Z] GC before operation: completed in 122.904 ms, heap usage 631.848 MB -> 121.558 MB. [2025-05-30T01:24:41.228Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2683.897 ms) ====== [2025-05-30T01:24:41.228Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-05-30T01:24:41.719Z] GC before operation: completed in 186.598 ms, heap usage 398.871 MB -> 120.829 MB. [2025-05-30T01:24:44.651Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3151.039 ms) ====== [2025-05-30T01:24:44.651Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-05-30T01:24:44.651Z] GC before operation: completed in 162.773 ms, heap usage 227.340 MB -> 120.540 MB. [2025-05-30T01:24:48.602Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3927.101 ms) ====== [2025-05-30T01:24:48.602Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-05-30T01:24:49.116Z] GC before operation: completed in 135.945 ms, heap usage 947.315 MB -> 122.365 MB. [2025-05-30T01:24:52.206Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3038.682 ms) ====== [2025-05-30T01:24:52.206Z] ----------------------------------- [2025-05-30T01:24:52.206Z] renaissance-log-regression_0_PASSED [2025-05-30T01:24:52.206Z] ----------------------------------- [2025-05-30T01:24:52.206Z] [2025-05-30T01:24:52.206Z] TEST TEARDOWN: [2025-05-30T01:24:52.206Z] Nothing to be done for teardown. [2025-05-30T01:24:52.652Z] renaissance-log-regression_0 Finish Time: Thu May 29 18:24:51 2025 Epoch Time (ms): 1748568291522