[2025-08-30T01:08:14.867Z] Running test renaissance-log-regression_0 ... [2025-08-30T01:08:14.867Z] =============================================== [2025-08-30T01:08:15.248Z] renaissance-log-regression_0 Start Time: Fri Aug 29 18:08:14 2025 Epoch Time (ms): 1756516094627 [2025-08-30T01:08:15.248Z] variation: NoOptions [2025-08-30T01:08:15.248Z] JVM_OPTIONS: [2025-08-30T01:08:15.248Z] { \ [2025-08-30T01:08:15.248Z] echo ""; echo "TEST SETUP:"; \ [2025-08-30T01:08:15.248Z] echo "Nothing to be done for setup."; \ [2025-08-30T01:08:15.248Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17565123298003/renaissance-log-regression_0"; \ [2025-08-30T01:08:15.248Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17565123298003/renaissance-log-regression_0"; \ [2025-08-30T01:08:15.248Z] echo ""; echo "TESTING:"; \ [2025-08-30T01:08:15.248Z] "/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_17565123298003/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-30T01:08:15.248Z] 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_17565123298003/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-30T01:08:15.248Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-30T01:08:15.248Z] echo "Nothing to be done for teardown."; \ [2025-08-30T01:08:15.248Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17565123298003/TestTargetResult"; [2025-08-30T01:08:15.248Z] [2025-08-30T01:08:15.248Z] TEST SETUP: [2025-08-30T01:08:15.248Z] Nothing to be done for setup. [2025-08-30T01:08:15.248Z] [2025-08-30T01:08:15.248Z] TESTING: [2025-08-30T01:08:30.058Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-08-30T01:08:39.670Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-30T01:08:39.670Z] GC before operation: completed in 160.907 ms, heap usage 83.562 MB -> 35.099 MB. [2025-08-30T01:09:09.762Z] ====== log-regression (apache-spark) [default], iteration 0 completed (27584.998 ms) ====== [2025-08-30T01:09:09.762Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-30T01:09:09.762Z] GC before operation: completed in 319.502 ms, heap usage 380.921 MB -> 117.416 MB. [2025-08-30T01:09:14.544Z] ====== log-regression (apache-spark) [default], iteration 1 completed (6583.456 ms) ====== [2025-08-30T01:09:14.544Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-30T01:09:14.544Z] GC before operation: completed in 220.606 ms, heap usage 424.748 MB -> 118.014 MB. [2025-08-30T01:09:20.171Z] ====== log-regression (apache-spark) [default], iteration 2 completed (5644.894 ms) ====== [2025-08-30T01:09:20.171Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-30T01:09:20.754Z] GC before operation: completed in 281.964 ms, heap usage 487.995 MB -> 118.769 MB. [2025-08-30T01:09:25.702Z] ====== log-regression (apache-spark) [default], iteration 3 completed (4627.669 ms) ====== [2025-08-30T01:09:25.702Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-30T01:09:25.702Z] GC before operation: completed in 169.472 ms, heap usage 233.182 MB -> 118.203 MB. [2025-08-30T01:09:30.276Z] ====== log-regression (apache-spark) [default], iteration 4 completed (4686.758 ms) ====== [2025-08-30T01:09:30.276Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-30T01:09:30.276Z] GC before operation: completed in 184.938 ms, heap usage 457.108 MB -> 119.107 MB. [2025-08-30T01:09:34.991Z] ====== log-regression (apache-spark) [default], iteration 5 completed (4470.475 ms) ====== [2025-08-30T01:09:34.991Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-30T01:09:34.991Z] GC before operation: completed in 158.654 ms, heap usage 427.155 MB -> 119.331 MB. [2025-08-30T01:09:39.614Z] ====== log-regression (apache-spark) [default], iteration 6 completed (4363.737 ms) ====== [2025-08-30T01:09:39.614Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-30T01:09:39.614Z] GC before operation: completed in 246.681 ms, heap usage 424.021 MB -> 119.537 MB. [2025-08-30T01:09:43.547Z] ====== log-regression (apache-spark) [default], iteration 7 completed (4198.551 ms) ====== [2025-08-30T01:09:43.547Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-30T01:09:44.067Z] GC before operation: completed in 230.615 ms, heap usage 408.196 MB -> 119.649 MB. [2025-08-30T01:09:47.933Z] ====== log-regression (apache-spark) [default], iteration 8 completed (4262.501 ms) ====== [2025-08-30T01:09:47.933Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-30T01:09:48.467Z] GC before operation: completed in 224.530 ms, heap usage 259.886 MB -> 119.358 MB. [2025-08-30T01:09:52.094Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3520.171 ms) ====== [2025-08-30T01:09:52.094Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-30T01:09:52.094Z] GC before operation: completed in 171.975 ms, heap usage 436.013 MB -> 120.109 MB. [2025-08-30T01:09:54.951Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3150.464 ms) ====== [2025-08-30T01:09:54.951Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-30T01:09:55.451Z] GC before operation: completed in 216.386 ms, heap usage 430.722 MB -> 120.313 MB. [2025-08-30T01:09:59.356Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3922.579 ms) ====== [2025-08-30T01:09:59.356Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-30T01:09:59.808Z] GC before operation: completed in 353.831 ms, heap usage 383.005 MB -> 120.341 MB. [2025-08-30T01:10:04.643Z] ====== log-regression (apache-spark) [default], iteration 12 completed (4516.480 ms) ====== [2025-08-30T01:10:04.643Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-30T01:10:04.643Z] GC before operation: completed in 226.979 ms, heap usage 319.716 MB -> 120.360 MB. [2025-08-30T01:10:08.404Z] ====== log-regression (apache-spark) [default], iteration 13 completed (4051.205 ms) ====== [2025-08-30T01:10:08.404Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-30T01:10:08.826Z] GC before operation: completed in 243.727 ms, heap usage 370.746 MB -> 120.713 MB. [2025-08-30T01:10:13.569Z] ====== log-regression (apache-spark) [default], iteration 14 completed (4273.959 ms) ====== [2025-08-30T01:10:13.569Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-30T01:10:13.569Z] GC before operation: completed in 174.456 ms, heap usage 221.937 MB -> 120.414 MB. [2025-08-30T01:10:16.803Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3812.394 ms) ====== [2025-08-30T01:10:16.803Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-30T01:10:17.246Z] GC before operation: completed in 310.536 ms, heap usage 277.112 MB -> 120.767 MB. [2025-08-30T01:10:22.092Z] ====== log-regression (apache-spark) [default], iteration 16 completed (4382.956 ms) ====== [2025-08-30T01:10:22.092Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-30T01:10:22.092Z] GC before operation: completed in 272.114 ms, heap usage 719.107 MB -> 122.286 MB. [2025-08-30T01:10:26.966Z] ====== log-regression (apache-spark) [default], iteration 17 completed (4640.876 ms) ====== [2025-08-30T01:10:26.966Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-30T01:10:26.966Z] GC before operation: completed in 294.833 ms, heap usage 353.274 MB -> 121.345 MB. [2025-08-30T01:10:30.677Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3479.066 ms) ====== [2025-08-30T01:10:30.677Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-30T01:10:30.677Z] GC before operation: completed in 238.792 ms, heap usage 526.925 MB -> 122.197 MB. [2025-08-30T01:10:35.770Z] ====== log-regression (apache-spark) [default], iteration 19 completed (4666.599 ms) ====== [2025-08-30T01:10:35.770Z] ----------------------------------- [2025-08-30T01:10:35.770Z] renaissance-log-regression_0_PASSED [2025-08-30T01:10:35.770Z] ----------------------------------- [2025-08-30T01:10:35.770Z] [2025-08-30T01:10:35.770Z] TEST TEARDOWN: [2025-08-30T01:10:35.770Z] Nothing to be done for teardown. [2025-08-30T01:10:36.198Z] renaissance-log-regression_0 Finish Time: Fri Aug 29 18:10:35 2025 Epoch Time (ms): 1756516235556