[2025-08-13T21:24:56.506Z] Running test renaissance-log-regression_0 ... [2025-08-13T21:24:56.506Z] =============================================== [2025-08-13T21:24:56.876Z] renaissance-log-regression_0 Start Time: Wed Aug 13 14:24:56 2025 Epoch Time (ms): 1755120296295 [2025-08-13T21:24:56.876Z] variation: NoOptions [2025-08-13T21:24:56.876Z] JVM_OPTIONS: [2025-08-13T21:24:56.876Z] { \ [2025-08-13T21:24:56.876Z] echo ""; echo "TEST SETUP:"; \ [2025-08-13T21:24:56.876Z] echo "Nothing to be done for setup."; \ [2025-08-13T21:24:56.876Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17551174266516/renaissance-log-regression_0"; \ [2025-08-13T21:24:56.876Z] cd "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17551174266516/renaissance-log-regression_0"; \ [2025-08-13T21:24:56.876Z] echo ""; echo "TESTING:"; \ [2025-08-13T21:24:56.876Z] "/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_17551174266516/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-13T21:24:56.876Z] 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_17551174266516/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-13T21:24:56.876Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-13T21:24:56.876Z] echo "Nothing to be done for teardown."; \ [2025-08-13T21:24:56.876Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17551174266516/TestTargetResult"; [2025-08-13T21:24:56.876Z] [2025-08-13T21:24:56.876Z] TEST SETUP: [2025-08-13T21:24:56.876Z] Nothing to be done for setup. [2025-08-13T21:24:56.876Z] [2025-08-13T21:24:56.876Z] TESTING: [2025-08-13T21:25:11.631Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-08-13T21:25:17.305Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-13T21:25:17.305Z] GC before operation: completed in 131.073 ms, heap usage 62.000 MB -> 34.902 MB. [2025-08-13T21:25:48.171Z] ====== log-regression (apache-spark) [default], iteration 0 completed (28700.596 ms) ====== [2025-08-13T21:25:48.171Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-13T21:25:48.171Z] GC before operation: completed in 179.787 ms, heap usage 454.792 MB -> 117.359 MB. [2025-08-13T21:25:54.311Z] ====== log-regression (apache-spark) [default], iteration 1 completed (7603.073 ms) ====== [2025-08-13T21:25:54.311Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-13T21:25:54.311Z] GC before operation: completed in 152.675 ms, heap usage 278.678 MB -> 117.251 MB. [2025-08-13T21:26:00.321Z] ====== log-regression (apache-spark) [default], iteration 2 completed (6019.778 ms) ====== [2025-08-13T21:26:00.321Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-13T21:26:00.321Z] GC before operation: completed in 315.681 ms, heap usage 549.293 MB -> 118.558 MB. [2025-08-13T21:26:07.681Z] ====== log-regression (apache-spark) [default], iteration 3 completed (6443.867 ms) ====== [2025-08-13T21:26:07.681Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-13T21:26:07.681Z] GC before operation: completed in 214.968 ms, heap usage 505.785 MB -> 118.713 MB. [2025-08-13T21:26:12.608Z] ====== log-regression (apache-spark) [default], iteration 4 completed (4853.961 ms) ====== [2025-08-13T21:26:12.608Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-13T21:26:12.608Z] GC before operation: completed in 190.079 ms, heap usage 358.822 MB -> 118.495 MB. [2025-08-13T21:26:17.664Z] ====== log-regression (apache-spark) [default], iteration 5 completed (5224.990 ms) ====== [2025-08-13T21:26:17.664Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-13T21:26:17.664Z] GC before operation: completed in 197.856 ms, heap usage 278.275 MB -> 118.522 MB. [2025-08-13T21:26:22.528Z] ====== log-regression (apache-spark) [default], iteration 6 completed (4351.372 ms) ====== [2025-08-13T21:26:22.528Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-13T21:26:22.528Z] GC before operation: completed in 271.722 ms, heap usage 274.139 MB -> 118.619 MB. [2025-08-13T21:26:27.222Z] ====== log-regression (apache-spark) [default], iteration 7 completed (4924.867 ms) ====== [2025-08-13T21:26:27.222Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-13T21:26:27.222Z] GC before operation: completed in 179.845 ms, heap usage 493.420 MB -> 119.641 MB. [2025-08-13T21:26:31.777Z] ====== log-regression (apache-spark) [default], iteration 8 completed (4218.169 ms) ====== [2025-08-13T21:26:31.777Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-13T21:26:31.777Z] GC before operation: completed in 199.094 ms, heap usage 387.977 MB -> 119.408 MB. [2025-08-13T21:26:35.601Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3868.440 ms) ====== [2025-08-13T21:26:35.601Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-13T21:26:36.112Z] GC before operation: completed in 228.763 ms, heap usage 339.780 MB -> 119.471 MB. [2025-08-13T21:26:40.892Z] ====== log-regression (apache-spark) [default], iteration 10 completed (4696.186 ms) ====== [2025-08-13T21:26:40.892Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-13T21:26:40.892Z] GC before operation: completed in 254.494 ms, heap usage 676.343 MB -> 120.738 MB. [2025-08-13T21:26:45.876Z] ====== log-regression (apache-spark) [default], iteration 11 completed (4259.464 ms) ====== [2025-08-13T21:26:45.876Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-13T21:26:45.876Z] GC before operation: completed in 222.015 ms, heap usage 279.589 MB -> 119.711 MB. [2025-08-13T21:26:51.040Z] ====== log-regression (apache-spark) [default], iteration 12 completed (5748.584 ms) ====== [2025-08-13T21:26:51.040Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-13T21:26:51.631Z] GC before operation: completed in 274.342 ms, heap usage 457.006 MB -> 120.387 MB. [2025-08-13T21:26:56.639Z] ====== log-regression (apache-spark) [default], iteration 13 completed (5036.522 ms) ====== [2025-08-13T21:26:56.639Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-13T21:26:56.639Z] GC before operation: completed in 194.209 ms, heap usage 440.995 MB -> 120.582 MB. [2025-08-13T21:27:00.297Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3616.059 ms) ====== [2025-08-13T21:27:00.297Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-13T21:27:00.297Z] GC before operation: completed in 204.300 ms, heap usage 554.433 MB -> 121.010 MB. [2025-08-13T21:27:04.414Z] ====== log-regression (apache-spark) [default], iteration 15 completed (4092.774 ms) ====== [2025-08-13T21:27:04.414Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-13T21:27:04.953Z] GC before operation: completed in 235.070 ms, heap usage 641.334 MB -> 121.650 MB. [2025-08-13T21:27:09.058Z] ====== log-regression (apache-spark) [default], iteration 16 completed (4475.622 ms) ====== [2025-08-13T21:27:09.058Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-13T21:27:09.605Z] GC before operation: completed in 254.724 ms, heap usage 271.509 MB -> 120.583 MB. [2025-08-13T21:27:14.445Z] ====== log-regression (apache-spark) [default], iteration 17 completed (4552.940 ms) ====== [2025-08-13T21:27:14.445Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-13T21:27:14.445Z] GC before operation: completed in 185.844 ms, heap usage 388.742 MB -> 121.032 MB. [2025-08-13T21:27:19.209Z] ====== log-regression (apache-spark) [default], iteration 18 completed (4879.732 ms) ====== [2025-08-13T21:27:19.209Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-13T21:27:19.209Z] GC before operation: completed in 247.980 ms, heap usage 204.143 MB -> 120.999 MB. [2025-08-13T21:27:24.955Z] ====== log-regression (apache-spark) [default], iteration 19 completed (5094.158 ms) ====== [2025-08-13T21:27:24.955Z] ----------------------------------- [2025-08-13T21:27:24.955Z] renaissance-log-regression_0_PASSED [2025-08-13T21:27:24.955Z] ----------------------------------- [2025-08-13T21:27:24.955Z] [2025-08-13T21:27:24.955Z] TEST TEARDOWN: [2025-08-13T21:27:24.955Z] Nothing to be done for teardown. [2025-08-13T21:27:24.955Z] renaissance-log-regression_0 Finish Time: Wed Aug 13 14:27:24 2025 Epoch Time (ms): 1755120444591