[2025-11-13T00:05:45.500Z] Running test renaissance-log-regression_0 ... [2025-11-13T00:05:45.500Z] =============================================== [2025-11-13T00:05:45.500Z] renaissance-log-regression_0 Start Time: Thu Nov 13 00:05:45 2025 Epoch Time (ms): 1762992345425 [2025-11-13T00:05:45.821Z] variation: NoOptions [2025-11-13T00:05:45.821Z] JVM_OPTIONS: [2025-11-13T00:05:45.821Z] { \ [2025-11-13T00:05:45.821Z] echo ""; echo "TEST SETUP:"; \ [2025-11-13T00:05:45.821Z] echo "Nothing to be done for setup."; \ [2025-11-13T00:05:45.821Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17629898278816\\renaissance-log-regression_0"; \ [2025-11-13T00:05:45.821Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17629898278816\\renaissance-log-regression_0"; \ [2025-11-13T00:05:45.821Z] echo ""; echo "TESTING:"; \ [2025-11-13T00:05:45.822Z] "c:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/jdkbinary/j2sdk-image\\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 "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17629898278816\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2025-11-13T00:05:45.822Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17629898278816\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-13T00:05:45.822Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-13T00:05:45.822Z] echo "Nothing to be done for teardown."; \ [2025-11-13T00:05:45.822Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17629898278816\\TestTargetResult"; [2025-11-13T00:05:45.822Z] [2025-11-13T00:05:45.822Z] TEST SETUP: [2025-11-13T00:05:45.822Z] Nothing to be done for setup. [2025-11-13T00:05:45.822Z] [2025-11-13T00:05:45.822Z] TESTING: [2025-11-13T00:06:01.381Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-11-13T00:06:06.446Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-11-13T00:06:06.446Z] GC before operation: completed in 53.325 ms, heap usage 45.666 MB -> 35.240 MB. [2025-11-13T00:06:23.497Z] ====== log-regression (apache-spark) [default], iteration 0 completed (17023.444 ms) ====== [2025-11-13T00:06:23.497Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-11-13T00:06:23.497Z] GC before operation: completed in 152.938 ms, heap usage 279.094 MB -> 117.883 MB. [2025-11-13T00:06:27.467Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4245.726 ms) ====== [2025-11-13T00:06:27.467Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-11-13T00:06:27.467Z] GC before operation: completed in 115.263 ms, heap usage 550.838 MB -> 118.381 MB. [2025-11-13T00:06:30.742Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3317.145 ms) ====== [2025-11-13T00:06:30.742Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-11-13T00:06:30.742Z] GC before operation: completed in 170.638 ms, heap usage 334.100 MB -> 117.965 MB. [2025-11-13T00:06:33.537Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2820.935 ms) ====== [2025-11-13T00:06:33.537Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-11-13T00:06:33.988Z] GC before operation: completed in 124.128 ms, heap usage 334.334 MB -> 118.295 MB. [2025-11-13T00:06:36.753Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2871.159 ms) ====== [2025-11-13T00:06:36.753Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-11-13T00:06:36.753Z] GC before operation: completed in 109.647 ms, heap usage 268.328 MB -> 118.373 MB. [2025-11-13T00:06:39.341Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2556.750 ms) ====== [2025-11-13T00:06:39.341Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-11-13T00:06:39.341Z] GC before operation: completed in 119.662 ms, heap usage 422.009 MB -> 119.041 MB. [2025-11-13T00:06:41.952Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2483.274 ms) ====== [2025-11-13T00:06:41.952Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-11-13T00:06:41.952Z] GC before operation: completed in 130.283 ms, heap usage 507.929 MB -> 119.628 MB. [2025-11-13T00:06:45.454Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3317.768 ms) ====== [2025-11-13T00:06:45.454Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-11-13T00:06:45.454Z] GC before operation: completed in 116.679 ms, heap usage 260.712 MB -> 118.959 MB. [2025-11-13T00:06:47.921Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2140.741 ms) ====== [2025-11-13T00:06:47.921Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-11-13T00:06:47.921Z] GC before operation: completed in 118.512 ms, heap usage 292.234 MB -> 119.161 MB. [2025-11-13T00:06:50.311Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2353.895 ms) ====== [2025-11-13T00:06:50.311Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-11-13T00:06:50.311Z] GC before operation: completed in 140.260 ms, heap usage 329.139 MB -> 119.500 MB. [2025-11-13T00:06:52.891Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2386.658 ms) ====== [2025-11-13T00:06:52.891Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-11-13T00:06:52.891Z] GC before operation: completed in 159.107 ms, heap usage 409.900 MB -> 120.062 MB. [2025-11-13T00:06:55.422Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2414.309 ms) ====== [2025-11-13T00:06:55.422Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-11-13T00:06:55.422Z] GC before operation: completed in 131.090 ms, heap usage 262.692 MB -> 119.624 MB. [2025-11-13T00:06:58.406Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2465.405 ms) ====== [2025-11-13T00:06:58.406Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-11-13T00:06:58.406Z] GC before operation: completed in 122.588 ms, heap usage 295.627 MB -> 119.923 MB. [2025-11-13T00:07:00.441Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2385.240 ms) ====== [2025-11-13T00:07:00.441Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-11-13T00:07:01.416Z] GC before operation: completed in 139.328 ms, heap usage 670.240 MB -> 121.569 MB. [2025-11-13T00:07:03.273Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2726.361 ms) ====== [2025-11-13T00:07:03.273Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-11-13T00:07:03.273Z] GC before operation: completed in 126.040 ms, heap usage 521.994 MB -> 121.169 MB. [2025-11-13T00:07:05.762Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2386.549 ms) ====== [2025-11-13T00:07:05.762Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-11-13T00:07:05.762Z] GC before operation: completed in 121.460 ms, heap usage 283.391 MB -> 120.487 MB. [2025-11-13T00:07:08.222Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2279.185 ms) ====== [2025-11-13T00:07:08.222Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-11-13T00:07:08.222Z] GC before operation: completed in 175.367 ms, heap usage 126.207 MB -> 120.397 MB. [2025-11-13T00:07:11.364Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3373.611 ms) ====== [2025-11-13T00:07:11.364Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-11-13T00:07:12.239Z] GC before operation: completed in 150.194 ms, heap usage 470.596 MB -> 121.570 MB. [2025-11-13T00:07:14.627Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2615.164 ms) ====== [2025-11-13T00:07:14.627Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-11-13T00:07:15.218Z] GC before operation: completed in 153.219 ms, heap usage 414.961 MB -> 121.469 MB. [2025-11-13T00:07:16.542Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2345.018 ms) ====== [2025-11-13T00:07:17.199Z] ----------------------------------- [2025-11-13T00:07:17.199Z] renaissance-log-regression_0_PASSED [2025-11-13T00:07:17.199Z] ----------------------------------- [2025-11-13T00:07:17.199Z] [2025-11-13T00:07:17.199Z] TEST TEARDOWN: [2025-11-13T00:07:17.199Z] Nothing to be done for teardown. [2025-11-13T00:07:17.199Z] renaissance-log-regression_0 Finish Time: Thu Nov 13 00:07:17 2025 Epoch Time (ms): 1762992437121