[2025-08-27T21:31:13.645Z] Running test renaissance-log-regression_0 ... [2025-08-27T21:31:13.645Z] =============================================== [2025-08-27T21:31:13.645Z] renaissance-log-regression_0 Start Time: Wed Aug 27 22:31:13 2025 Epoch Time (ms): 1756330273375 [2025-08-27T21:31:13.645Z] variation: NoOptions [2025-08-27T21:31:13.645Z] JVM_OPTIONS: [2025-08-27T21:31:13.645Z] { \ [2025-08-27T21:31:13.645Z] echo ""; echo "TEST SETUP:"; \ [2025-08-27T21:31:13.645Z] echo "Nothing to be done for setup."; \ [2025-08-27T21:31:13.645Z] mkdir -p "/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17563245742158/renaissance-log-regression_0"; \ [2025-08-27T21:31:13.645Z] cd "/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17563245742158/renaissance-log-regression_0"; \ [2025-08-27T21:31:13.645Z] echo ""; echo "TESTING:"; \ [2025-08-27T21:31:13.646Z] "/Users/jenkins/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/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17563245742158/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-27T21:31:13.646Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17563245742158/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-27T21:31:13.646Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-27T21:31:13.646Z] echo "Nothing to be done for teardown."; \ [2025-08-27T21:31:13.646Z] } 2>&1 | tee -a "/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17563245742158/TestTargetResult"; [2025-08-27T21:31:13.646Z] [2025-08-27T21:31:13.646Z] TEST SETUP: [2025-08-27T21:31:13.646Z] Nothing to be done for setup. [2025-08-27T21:31:13.646Z] [2025-08-27T21:31:13.646Z] TESTING: [2025-08-27T21:31:21.194Z] NOTE: 'log-regression' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-08-27T21:31:23.563Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-27T21:31:23.563Z] GC before operation: completed in 46.067 ms, heap usage 44.523 MB -> 35.491 MB. [2025-08-27T21:31:39.797Z] ====== log-regression (apache-spark) [default], iteration 0 completed (14391.177 ms) ====== [2025-08-27T21:31:39.797Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-27T21:31:39.797Z] GC before operation: completed in 101.264 ms, heap usage 1.056 GB -> 126.982 MB. [2025-08-27T21:31:42.178Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4007.026 ms) ====== [2025-08-27T21:31:42.178Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-27T21:31:42.640Z] GC before operation: completed in 77.060 ms, heap usage 651.610 MB -> 127.382 MB. [2025-08-27T21:31:45.019Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2920.662 ms) ====== [2025-08-27T21:31:45.019Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-27T21:31:45.019Z] GC before operation: completed in 80.213 ms, heap usage 535.313 MB -> 127.479 MB. [2025-08-27T21:31:48.125Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3092.413 ms) ====== [2025-08-27T21:31:48.125Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-27T21:31:48.125Z] GC before operation: completed in 83.844 ms, heap usage 263.187 MB -> 127.729 MB. [2025-08-27T21:31:51.278Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2640.378 ms) ====== [2025-08-27T21:31:51.278Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-27T21:31:51.278Z] GC before operation: completed in 89.590 ms, heap usage 328.468 MB -> 128.090 MB. [2025-08-27T21:31:53.676Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2594.841 ms) ====== [2025-08-27T21:31:53.676Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-27T21:31:53.676Z] GC before operation: completed in 85.903 ms, heap usage 713.338 MB -> 128.855 MB. [2025-08-27T21:31:56.781Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2705.548 ms) ====== [2025-08-27T21:31:56.781Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-27T21:31:56.781Z] GC before operation: completed in 92.651 ms, heap usage 278.556 MB -> 128.936 MB. [2025-08-27T21:31:59.171Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2590.426 ms) ====== [2025-08-27T21:31:59.171Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-27T21:31:59.171Z] GC before operation: completed in 89.679 ms, heap usage 259.258 MB -> 129.353 MB. [2025-08-27T21:32:01.541Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2542.245 ms) ====== [2025-08-27T21:32:01.541Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-27T21:32:01.895Z] GC before operation: completed in 86.374 ms, heap usage 505.173 MB -> 129.906 MB. [2025-08-27T21:32:04.308Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2442.117 ms) ====== [2025-08-27T21:32:04.308Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-27T21:32:04.308Z] GC before operation: completed in 101.731 ms, heap usage 654.024 MB -> 130.367 MB. [2025-08-27T21:32:07.438Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2793.135 ms) ====== [2025-08-27T21:32:07.438Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-27T21:32:07.438Z] GC before operation: completed in 99.995 ms, heap usage 211.617 MB -> 130.220 MB. [2025-08-27T21:32:10.606Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2905.223 ms) ====== [2025-08-27T21:32:10.606Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-27T21:32:10.606Z] GC before operation: completed in 85.176 ms, heap usage 553.785 MB -> 130.962 MB. [2025-08-27T21:32:12.979Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2619.137 ms) ====== [2025-08-27T21:32:12.979Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-27T21:32:12.979Z] GC before operation: completed in 91.154 ms, heap usage 448.714 MB -> 131.286 MB. [2025-08-27T21:32:15.381Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2488.121 ms) ====== [2025-08-27T21:32:15.381Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-27T21:32:15.381Z] GC before operation: completed in 93.816 ms, heap usage 425.401 MB -> 131.598 MB. [2025-08-27T21:32:18.475Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2536.027 ms) ====== [2025-08-27T21:32:18.475Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-27T21:32:18.475Z] GC before operation: completed in 97.717 ms, heap usage 447.057 MB -> 132.067 MB. [2025-08-27T21:32:21.160Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3035.134 ms) ====== [2025-08-27T21:32:21.160Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-27T21:32:21.160Z] GC before operation: completed in 97.270 ms, heap usage 1.030 GB -> 132.988 MB. [2025-08-27T21:32:24.265Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2513.891 ms) ====== [2025-08-27T21:32:24.265Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-27T21:32:24.265Z] GC before operation: completed in 105.355 ms, heap usage 259.188 MB -> 132.669 MB. [2025-08-27T21:32:26.682Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2466.864 ms) ====== [2025-08-27T21:32:26.682Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-27T21:32:26.682Z] GC before operation: completed in 95.777 ms, heap usage 810.814 MB -> 133.532 MB. [2025-08-27T21:32:29.072Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2505.537 ms) ====== [2025-08-27T21:32:29.072Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-27T21:32:29.072Z] GC before operation: completed in 94.062 ms, heap usage 452.602 MB -> 133.651 MB. [2025-08-27T21:32:31.491Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2562.023 ms) ====== [2025-08-27T21:32:31.843Z] ----------------------------------- [2025-08-27T21:32:31.843Z] renaissance-log-regression_0_PASSED [2025-08-27T21:32:31.843Z] ----------------------------------- [2025-08-27T21:32:31.843Z] [2025-08-27T21:32:31.843Z] TEST TEARDOWN: [2025-08-27T21:32:31.843Z] Nothing to be done for teardown. [2025-08-27T21:32:31.843Z] renaissance-log-regression_0 Finish Time: Wed Aug 27 22:32:31 2025 Epoch Time (ms): 1756330351798