[2025-07-16T03:02:14.233Z] Running test renaissance-log-regression_0 ... [2025-07-16T03:02:14.233Z] =============================================== [2025-07-16T03:02:14.233Z] renaissance-log-regression_0 Start Time: Wed Jul 16 04:02:13 2025 Epoch Time (ms): 1752634933936 [2025-07-16T03:02:14.233Z] variation: NoOptions [2025-07-16T03:02:14.233Z] JVM_OPTIONS: [2025-07-16T03:02:14.233Z] { \ [2025-07-16T03:02:14.233Z] echo ""; echo "TEST SETUP:"; \ [2025-07-16T03:02:14.233Z] echo "Nothing to be done for setup."; \ [2025-07-16T03:02:14.233Z] mkdir -p "/Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17526234221457/renaissance-log-regression_0"; \ [2025-07-16T03:02:14.233Z] cd "/Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17526234221457/renaissance-log-regression_0"; \ [2025-07-16T03:02:14.233Z] echo ""; echo "TESTING:"; \ [2025-07-16T03:02:14.233Z] "/Users/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17526234221457/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-07-16T03:02:14.233Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17526234221457/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-16T03:02:14.233Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-16T03:02:14.233Z] echo "Nothing to be done for teardown."; \ [2025-07-16T03:02:14.233Z] } 2>&1 | tee -a "/Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17526234221457/TestTargetResult"; [2025-07-16T03:02:14.233Z] [2025-07-16T03:02:14.233Z] TEST SETUP: [2025-07-16T03:02:14.233Z] Nothing to be done for setup. [2025-07-16T03:02:14.233Z] [2025-07-16T03:02:14.233Z] TESTING: [2025-07-16T03:02:20.394Z] NOTE: 'log-regression' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-07-16T03:02:23.517Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-07-16T03:02:23.517Z] GC before operation: completed in 40.174 ms, heap usage 53.237 MB -> 35.375 MB. [2025-07-16T03:02:39.816Z] ====== log-regression (apache-spark) [default], iteration 0 completed (14637.250 ms) ====== [2025-07-16T03:02:39.816Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-07-16T03:02:39.816Z] GC before operation: completed in 93.182 ms, heap usage 406.392 MB -> 126.212 MB. [2025-07-16T03:02:42.947Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4785.404 ms) ====== [2025-07-16T03:02:42.947Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-07-16T03:02:42.947Z] GC before operation: completed in 86.291 ms, heap usage 557.769 MB -> 126.952 MB. [2025-07-16T03:02:46.951Z] ====== log-regression (apache-spark) [default], iteration 2 completed (4027.775 ms) ====== [2025-07-16T03:02:46.951Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-07-16T03:02:46.951Z] GC before operation: completed in 81.661 ms, heap usage 432.172 MB -> 127.418 MB. [2025-07-16T03:02:50.935Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3777.258 ms) ====== [2025-07-16T03:02:50.935Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-07-16T03:02:50.935Z] GC before operation: completed in 90.453 ms, heap usage 476.742 MB -> 127.746 MB. [2025-07-16T03:02:54.934Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3779.191 ms) ====== [2025-07-16T03:02:54.934Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-07-16T03:02:54.934Z] GC before operation: completed in 82.156 ms, heap usage 369.729 MB -> 128.165 MB. [2025-07-16T03:02:58.919Z] ====== log-regression (apache-spark) [default], iteration 5 completed (4016.990 ms) ====== [2025-07-16T03:02:58.919Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-07-16T03:02:58.919Z] GC before operation: completed in 91.552 ms, heap usage 416.021 MB -> 128.488 MB. [2025-07-16T03:03:02.914Z] ====== log-regression (apache-spark) [default], iteration 6 completed (3945.642 ms) ====== [2025-07-16T03:03:02.914Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-07-16T03:03:02.914Z] GC before operation: completed in 94.751 ms, heap usage 589.375 MB -> 128.973 MB. [2025-07-16T03:03:06.934Z] ====== log-regression (apache-spark) [default], iteration 7 completed (4027.236 ms) ====== [2025-07-16T03:03:06.934Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-07-16T03:03:06.934Z] GC before operation: completed in 94.163 ms, heap usage 444.967 MB -> 129.211 MB. [2025-07-16T03:03:10.923Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3981.101 ms) ====== [2025-07-16T03:03:10.923Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-07-16T03:03:10.923Z] GC before operation: completed in 114.768 ms, heap usage 493.294 MB -> 129.640 MB. [2025-07-16T03:03:14.904Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3970.293 ms) ====== [2025-07-16T03:03:14.904Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-07-16T03:03:14.904Z] GC before operation: completed in 94.804 ms, heap usage 310.470 MB -> 129.899 MB. [2025-07-16T03:03:18.912Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3870.326 ms) ====== [2025-07-16T03:03:18.912Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-07-16T03:03:18.912Z] GC before operation: completed in 97.811 ms, heap usage 450.061 MB -> 130.293 MB. [2025-07-16T03:03:22.895Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3987.818 ms) ====== [2025-07-16T03:03:22.895Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-07-16T03:03:22.895Z] GC before operation: completed in 101.179 ms, heap usage 790.284 MB -> 130.837 MB. [2025-07-16T03:03:26.888Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3606.881 ms) ====== [2025-07-16T03:03:26.888Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-07-16T03:03:26.888Z] GC before operation: completed in 93.505 ms, heap usage 342.999 MB -> 131.058 MB. [2025-07-16T03:03:30.013Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3371.647 ms) ====== [2025-07-16T03:03:30.013Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-07-16T03:03:30.013Z] GC before operation: completed in 102.773 ms, heap usage 346.712 MB -> 131.457 MB. [2025-07-16T03:03:34.478Z] ====== log-regression (apache-spark) [default], iteration 14 completed (4503.040 ms) ====== [2025-07-16T03:03:34.478Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-07-16T03:03:34.852Z] GC before operation: completed in 103.720 ms, heap usage 317.906 MB -> 131.807 MB. [2025-07-16T03:03:38.887Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3468.944 ms) ====== [2025-07-16T03:03:38.887Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-07-16T03:03:38.887Z] GC before operation: completed in 100.671 ms, heap usage 223.795 MB -> 132.058 MB. [2025-07-16T03:03:42.031Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3410.994 ms) ====== [2025-07-16T03:03:42.031Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-07-16T03:03:42.031Z] GC before operation: completed in 100.227 ms, heap usage 669.838 MB -> 132.692 MB. [2025-07-16T03:03:45.176Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3309.285 ms) ====== [2025-07-16T03:03:45.176Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-07-16T03:03:45.176Z] GC before operation: completed in 99.948 ms, heap usage 1002.062 MB -> 133.327 MB. [2025-07-16T03:03:49.160Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3369.662 ms) ====== [2025-07-16T03:03:49.160Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-07-16T03:03:49.160Z] GC before operation: completed in 98.306 ms, heap usage 733.780 MB -> 133.423 MB. [2025-07-16T03:03:52.292Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3343.353 ms) ====== [2025-07-16T03:03:52.650Z] ----------------------------------- [2025-07-16T03:03:52.650Z] renaissance-log-regression_0_PASSED [2025-07-16T03:03:52.650Z] ----------------------------------- [2025-07-16T03:03:52.650Z] [2025-07-16T03:03:52.650Z] TEST TEARDOWN: [2025-07-16T03:03:52.650Z] Nothing to be done for teardown. [2025-07-16T03:03:52.650Z] renaissance-log-regression_0 Finish Time: Wed Jul 16 04:03:52 2025 Epoch Time (ms): 1752635032362