[2025-10-03T21:10:29.851Z] Running test renaissance-log-regression_0 ... [2025-10-03T21:10:29.851Z] =============================================== [2025-10-03T21:10:29.851Z] renaissance-log-regression_0 Start Time: Fri Oct 3 14:10:29 2025 Epoch Time (ms): 1759525829579 [2025-10-03T21:10:30.382Z] variation: NoOptions [2025-10-03T21:10:30.382Z] JVM_OPTIONS: [2025-10-03T21:10:30.382Z] { \ [2025-10-03T21:10:30.382Z] echo ""; echo "TEST SETUP:"; \ [2025-10-03T21:10:30.382Z] echo "Nothing to be done for setup."; \ [2025-10-03T21:10:30.382Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17595223484180/renaissance-log-regression_0"; \ [2025-10-03T21:10:30.382Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17595223484180/renaissance-log-regression_0"; \ [2025-10-03T21:10:30.382Z] echo ""; echo "TESTING:"; \ [2025-10-03T21:10:30.382Z] "/Users/admin/workspace/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17595223484180/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-10-03T21:10:30.382Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17595223484180/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-03T21:10:30.382Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-03T21:10:30.382Z] echo "Nothing to be done for teardown."; \ [2025-10-03T21:10:30.382Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17595223484180/TestTargetResult"; [2025-10-03T21:10:30.382Z] [2025-10-03T21:10:30.382Z] TEST SETUP: [2025-10-03T21:10:30.382Z] Nothing to be done for setup. [2025-10-03T21:10:30.382Z] [2025-10-03T21:10:30.382Z] TESTING: [2025-10-03T21:10:48.070Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-10-03T21:10:58.027Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-10-03T21:10:58.027Z] GC before operation: completed in 105.937 ms, heap usage 56.858 MB -> 34.970 MB. [2025-10-03T21:11:33.912Z] ====== log-regression (apache-spark) [default], iteration 0 completed (31536.045 ms) ====== [2025-10-03T21:11:33.912Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-10-03T21:11:33.912Z] GC before operation: completed in 258.714 ms, heap usage 1021.964 MB -> 118.702 MB. [2025-10-03T21:11:36.199Z] ====== log-regression (apache-spark) [default], iteration 1 completed (7520.687 ms) ====== [2025-10-03T21:11:36.199Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-10-03T21:11:36.643Z] GC before operation: completed in 333.191 ms, heap usage 299.907 MB -> 117.845 MB. [2025-10-03T21:11:44.167Z] ====== log-regression (apache-spark) [default], iteration 2 completed (7317.751 ms) ====== [2025-10-03T21:11:44.167Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-10-03T21:11:44.167Z] GC before operation: completed in 188.571 ms, heap usage 252.285 MB -> 118.101 MB. [2025-10-03T21:11:52.019Z] ====== log-regression (apache-spark) [default], iteration 3 completed (7143.404 ms) ====== [2025-10-03T21:11:52.019Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-10-03T21:11:52.019Z] GC before operation: completed in 183.096 ms, heap usage 323.305 MB -> 118.502 MB. [2025-10-03T21:11:58.313Z] ====== log-regression (apache-spark) [default], iteration 4 completed (6204.411 ms) ====== [2025-10-03T21:11:58.313Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-10-03T21:11:58.313Z] GC before operation: completed in 240.555 ms, heap usage 401.301 MB -> 119.009 MB. [2025-10-03T21:12:04.610Z] ====== log-regression (apache-spark) [default], iteration 5 completed (5985.383 ms) ====== [2025-10-03T21:12:04.610Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-10-03T21:12:04.610Z] GC before operation: completed in 252.429 ms, heap usage 275.750 MB -> 118.745 MB. [2025-10-03T21:12:09.556Z] ====== log-regression (apache-spark) [default], iteration 6 completed (5282.550 ms) ====== [2025-10-03T21:12:09.556Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-10-03T21:12:09.556Z] GC before operation: completed in 241.102 ms, heap usage 498.179 MB -> 119.650 MB. [2025-10-03T21:12:14.383Z] ====== log-regression (apache-spark) [default], iteration 7 completed (4399.405 ms) ====== [2025-10-03T21:12:14.383Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-10-03T21:12:14.383Z] GC before operation: completed in 241.217 ms, heap usage 340.736 MB -> 119.456 MB. [2025-10-03T21:12:19.168Z] ====== log-regression (apache-spark) [default], iteration 8 completed (4727.617 ms) ====== [2025-10-03T21:12:19.168Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-10-03T21:12:19.168Z] GC before operation: completed in 221.325 ms, heap usage 330.778 MB -> 119.668 MB. [2025-10-03T21:12:25.390Z] ====== log-regression (apache-spark) [default], iteration 9 completed (5421.724 ms) ====== [2025-10-03T21:12:25.390Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-10-03T21:12:25.390Z] GC before operation: completed in 310.726 ms, heap usage 843.186 MB -> 121.180 MB. [2025-10-03T21:12:30.542Z] ====== log-regression (apache-spark) [default], iteration 10 completed (5609.457 ms) ====== [2025-10-03T21:12:30.542Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-10-03T21:12:31.052Z] GC before operation: completed in 254.916 ms, heap usage 405.082 MB -> 120.323 MB. [2025-10-03T21:12:35.456Z] ====== log-regression (apache-spark) [default], iteration 11 completed (4242.598 ms) ====== [2025-10-03T21:12:35.456Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-10-03T21:12:35.456Z] GC before operation: completed in 208.318 ms, heap usage 217.119 MB -> 119.856 MB. [2025-10-03T21:12:41.551Z] ====== log-regression (apache-spark) [default], iteration 12 completed (5429.080 ms) ====== [2025-10-03T21:12:41.551Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-10-03T21:12:41.551Z] GC before operation: completed in 314.951 ms, heap usage 379.553 MB -> 120.491 MB. [2025-10-03T21:12:46.201Z] ====== log-regression (apache-spark) [default], iteration 13 completed (4671.396 ms) ====== [2025-10-03T21:12:46.201Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-10-03T21:12:46.201Z] GC before operation: completed in 310.999 ms, heap usage 363.512 MB -> 120.700 MB. [2025-10-03T21:12:51.177Z] ====== log-regression (apache-spark) [default], iteration 14 completed (5196.757 ms) ====== [2025-10-03T21:12:51.177Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-10-03T21:12:51.661Z] GC before operation: completed in 211.036 ms, heap usage 473.445 MB -> 121.239 MB. [2025-10-03T21:12:56.413Z] ====== log-regression (apache-spark) [default], iteration 15 completed (4677.156 ms) ====== [2025-10-03T21:12:56.413Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-10-03T21:12:56.413Z] GC before operation: completed in 293.969 ms, heap usage 377.007 MB -> 121.114 MB. [2025-10-03T21:13:01.229Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3984.056 ms) ====== [2025-10-03T21:13:01.229Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-10-03T21:13:01.229Z] GC before operation: completed in 268.811 ms, heap usage 295.341 MB -> 121.153 MB. [2025-10-03T21:13:06.303Z] ====== log-regression (apache-spark) [default], iteration 17 completed (5361.129 ms) ====== [2025-10-03T21:13:06.303Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-10-03T21:13:06.304Z] GC before operation: completed in 221.047 ms, heap usage 375.776 MB -> 121.478 MB. [2025-10-03T21:13:11.844Z] ====== log-regression (apache-spark) [default], iteration 18 completed (5175.871 ms) ====== [2025-10-03T21:13:11.844Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-10-03T21:13:11.844Z] GC before operation: completed in 241.162 ms, heap usage 475.185 MB -> 122.038 MB. [2025-10-03T21:13:17.907Z] ====== log-regression (apache-spark) [default], iteration 19 completed (5599.602 ms) ====== [2025-10-03T21:13:18.302Z] ----------------------------------- [2025-10-03T21:13:18.302Z] renaissance-log-regression_0_PASSED [2025-10-03T21:13:18.302Z] ----------------------------------- [2025-10-03T21:13:18.302Z] [2025-10-03T21:13:18.302Z] TEST TEARDOWN: [2025-10-03T21:13:18.302Z] Nothing to be done for teardown. [2025-10-03T21:13:18.302Z] renaissance-log-regression_0 Finish Time: Fri Oct 3 14:13:17 2025 Epoch Time (ms): 1759525997780