[2025-06-26T16:45:37.170Z] Running test renaissance-log-regression_0 ... [2025-06-26T16:45:37.170Z] =============================================== [2025-06-26T16:45:37.170Z] renaissance-log-regression_0 Start Time: Thu Jun 26 12:45:37 2025 Epoch Time (ms): 1750956337081 [2025-06-26T16:45:37.170Z] variation: NoOptions [2025-06-26T16:45:37.170Z] JVM_OPTIONS: [2025-06-26T16:45:37.170Z] { \ [2025-06-26T16:45:37.170Z] echo ""; echo "TEST SETUP:"; \ [2025-06-26T16:45:37.170Z] echo "Nothing to be done for setup."; \ [2025-06-26T16:45:37.170Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17509557086572/renaissance-log-regression_0"; \ [2025-06-26T16:45:37.170Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17509557086572/renaissance-log-regression_0"; \ [2025-06-26T16:45:37.170Z] echo ""; echo "TESTING:"; \ [2025-06-26T16:45:37.170Z] "/Users/admin/workspace/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17509557086572/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-06-26T16:45:37.170Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17509557086572/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-26T16:45:37.170Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-26T16:45:37.170Z] echo "Nothing to be done for teardown."; \ [2025-06-26T16:45:37.170Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17509557086572/TestTargetResult"; [2025-06-26T16:45:37.170Z] [2025-06-26T16:45:37.170Z] TEST SETUP: [2025-06-26T16:45:37.170Z] Nothing to be done for setup. [2025-06-26T16:45:37.170Z] [2025-06-26T16:45:37.170Z] TESTING: [2025-06-26T16:45:41.211Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-06-26T16:45:41.996Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-06-26T16:45:41.996Z] GC before operation: completed in 27.383 ms, heap usage 58.897 MB -> 35.222 MB. [2025-06-26T16:45:48.174Z] ====== log-regression (apache-spark) [default], iteration 0 completed (5192.135 ms) ====== [2025-06-26T16:45:48.174Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-06-26T16:45:48.174Z] GC before operation: completed in 54.459 ms, heap usage 298.757 MB -> 116.911 MB. [2025-06-26T16:45:48.561Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1169.372 ms) ====== [2025-06-26T16:45:48.561Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-06-26T16:45:48.561Z] GC before operation: completed in 50.669 ms, heap usage 287.417 MB -> 117.429 MB. [2025-06-26T16:45:49.792Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1043.260 ms) ====== [2025-06-26T16:45:49.792Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-06-26T16:45:49.792Z] GC before operation: completed in 47.581 ms, heap usage 345.359 MB -> 118.109 MB. [2025-06-26T16:45:50.560Z] ====== log-regression (apache-spark) [default], iteration 3 completed (946.521 ms) ====== [2025-06-26T16:45:50.560Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-06-26T16:45:50.560Z] GC before operation: completed in 55.191 ms, heap usage 601.056 MB -> 119.267 MB. [2025-06-26T16:45:51.790Z] ====== log-regression (apache-spark) [default], iteration 4 completed (972.613 ms) ====== [2025-06-26T16:45:51.790Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-06-26T16:45:51.790Z] GC before operation: completed in 49.134 ms, heap usage 291.058 MB -> 118.504 MB. [2025-06-26T16:45:52.561Z] ====== log-regression (apache-spark) [default], iteration 5 completed (816.218 ms) ====== [2025-06-26T16:45:52.561Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-06-26T16:45:52.561Z] GC before operation: completed in 40.524 ms, heap usage 358.587 MB -> 118.954 MB. [2025-06-26T16:45:53.324Z] ====== log-regression (apache-spark) [default], iteration 6 completed (625.043 ms) ====== [2025-06-26T16:45:53.324Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-06-26T16:45:53.324Z] GC before operation: completed in 36.222 ms, heap usage 375.425 MB -> 119.193 MB. [2025-06-26T16:45:54.081Z] ====== log-regression (apache-spark) [default], iteration 7 completed (654.898 ms) ====== [2025-06-26T16:45:54.081Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-06-26T16:45:54.081Z] GC before operation: completed in 40.102 ms, heap usage 577.499 MB -> 120.138 MB. [2025-06-26T16:45:54.454Z] ====== log-regression (apache-spark) [default], iteration 8 completed (591.266 ms) ====== [2025-06-26T16:45:54.454Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-06-26T16:45:54.454Z] GC before operation: completed in 36.711 ms, heap usage 469.545 MB -> 119.929 MB. [2025-06-26T16:45:55.381Z] ====== log-regression (apache-spark) [default], iteration 9 completed (666.080 ms) ====== [2025-06-26T16:45:55.381Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-06-26T16:45:55.381Z] GC before operation: completed in 47.086 ms, heap usage 487.264 MB -> 120.250 MB. [2025-06-26T16:45:56.163Z] ====== log-regression (apache-spark) [default], iteration 10 completed (703.111 ms) ====== [2025-06-26T16:45:56.163Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-06-26T16:45:56.163Z] GC before operation: completed in 55.440 ms, heap usage 627.921 MB -> 120.910 MB. [2025-06-26T16:45:56.927Z] ====== log-regression (apache-spark) [default], iteration 11 completed (770.986 ms) ====== [2025-06-26T16:45:56.927Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-06-26T16:45:56.927Z] GC before operation: completed in 60.834 ms, heap usage 316.120 MB -> 120.075 MB. [2025-06-26T16:45:57.746Z] ====== log-regression (apache-spark) [default], iteration 12 completed (839.476 ms) ====== [2025-06-26T16:45:57.746Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-06-26T16:45:57.746Z] GC before operation: completed in 53.332 ms, heap usage 563.935 MB -> 121.078 MB. [2025-06-26T16:45:58.546Z] ====== log-regression (apache-spark) [default], iteration 13 completed (820.476 ms) ====== [2025-06-26T16:45:58.546Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-06-26T16:45:58.546Z] GC before operation: completed in 59.967 ms, heap usage 264.307 MB -> 120.304 MB. [2025-06-26T16:45:59.362Z] ====== log-regression (apache-spark) [default], iteration 14 completed (811.745 ms) ====== [2025-06-26T16:45:59.362Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-06-26T16:45:59.738Z] GC before operation: completed in 63.192 ms, heap usage 573.602 MB -> 121.527 MB. [2025-06-26T16:46:02.679Z] ====== log-regression (apache-spark) [default], iteration 15 completed (854.545 ms) ====== [2025-06-26T16:46:02.679Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-06-26T16:46:02.679Z] GC before operation: completed in 51.398 ms, heap usage 635.138 MB -> 121.895 MB. [2025-06-26T16:46:02.679Z] ====== log-regression (apache-spark) [default], iteration 16 completed (814.053 ms) ====== [2025-06-26T16:46:02.679Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-06-26T16:46:02.679Z] GC before operation: completed in 64.232 ms, heap usage 560.669 MB -> 121.860 MB. [2025-06-26T16:46:02.679Z] ====== log-regression (apache-spark) [default], iteration 17 completed (533.296 ms) ====== [2025-06-26T16:46:02.679Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-06-26T16:46:02.679Z] GC before operation: completed in 40.744 ms, heap usage 296.801 MB -> 121.106 MB. [2025-06-26T16:46:02.679Z] ====== log-regression (apache-spark) [default], iteration 18 completed (525.593 ms) ====== [2025-06-26T16:46:02.679Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-06-26T16:46:02.679Z] GC before operation: completed in 43.776 ms, heap usage 605.719 MB -> 122.401 MB. [2025-06-26T16:46:03.042Z] ====== log-regression (apache-spark) [default], iteration 19 completed (523.360 ms) ====== [2025-06-26T16:46:03.042Z] ----------------------------------- [2025-06-26T16:46:03.042Z] renaissance-log-regression_0_PASSED [2025-06-26T16:46:03.042Z] ----------------------------------- [2025-06-26T16:46:03.042Z] [2025-06-26T16:46:03.042Z] TEST TEARDOWN: [2025-06-26T16:46:03.042Z] Nothing to be done for teardown. [2025-06-26T16:46:03.042Z] renaissance-log-regression_0 Finish Time: Thu Jun 26 12:46:02 2025 Epoch Time (ms): 1750956362912