[2025-08-14T14:06:26.269Z] Running test renaissance-log-regression_0 ... [2025-08-14T14:06:26.269Z] =============================================== [2025-08-14T14:06:26.269Z] renaissance-log-regression_0 Start Time: Thu Aug 14 14:06:26 2025 Epoch Time (ms): 1755180386188 [2025-08-14T14:06:26.589Z] variation: NoOptions [2025-08-14T14:06:26.589Z] JVM_OPTIONS: [2025-08-14T14:06:26.589Z] { \ [2025-08-14T14:06:26.589Z] echo ""; echo "TEST SETUP:"; \ [2025-08-14T14:06:26.589Z] echo "Nothing to be done for setup."; \ [2025-08-14T14:06:26.589Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17551788759177\\renaissance-log-regression_0"; \ [2025-08-14T14:06:26.589Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17551788759177\\renaissance-log-regression_0"; \ [2025-08-14T14:06:26.589Z] echo ""; echo "TESTING:"; \ [2025-08-14T14:06:26.590Z] "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_17551788759177\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2025-08-14T14:06:26.590Z] 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_17551788759177\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-14T14:06:26.590Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-14T14:06:26.590Z] echo "Nothing to be done for teardown."; \ [2025-08-14T14:06:26.590Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17551788759177\\TestTargetResult"; [2025-08-14T14:06:26.590Z] [2025-08-14T14:06:26.590Z] TEST SETUP: [2025-08-14T14:06:26.590Z] Nothing to be done for setup. [2025-08-14T14:06:26.590Z] [2025-08-14T14:06:26.590Z] TESTING: [2025-08-14T14:06:42.156Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-14T14:06:42.835Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-14T14:06:42.835Z] GC before operation: completed in 39.783 ms, heap usage 47.272 MB -> 35.551 MB. [2025-08-14T14:06:53.756Z] ====== log-regression (apache-spark) [default], iteration 0 completed (10949.819 ms) ====== [2025-08-14T14:06:53.756Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-14T14:06:54.291Z] GC before operation: completed in 109.933 ms, heap usage 294.906 MB -> 120.015 MB. [2025-08-14T14:06:56.603Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2439.979 ms) ====== [2025-08-14T14:06:56.603Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-14T14:06:56.603Z] GC before operation: completed in 70.339 ms, heap usage 612.288 MB -> 121.598 MB. [2025-08-14T14:06:58.353Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1928.224 ms) ====== [2025-08-14T14:06:58.353Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-14T14:06:58.353Z] GC before operation: completed in 69.955 ms, heap usage 410.899 MB -> 121.463 MB. [2025-08-14T14:07:00.249Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1662.822 ms) ====== [2025-08-14T14:07:00.249Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-14T14:07:00.249Z] GC before operation: completed in 74.249 ms, heap usage 383.298 MB -> 121.633 MB. [2025-08-14T14:07:01.926Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1602.053 ms) ====== [2025-08-14T14:07:01.926Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-14T14:07:01.926Z] GC before operation: completed in 76.035 ms, heap usage 668.476 MB -> 122.823 MB. [2025-08-14T14:07:03.569Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1532.652 ms) ====== [2025-08-14T14:07:03.569Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-14T14:07:03.569Z] GC before operation: completed in 82.202 ms, heap usage 317.588 MB -> 121.892 MB. [2025-08-14T14:07:05.354Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1480.852 ms) ====== [2025-08-14T14:07:05.354Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-14T14:07:05.354Z] GC before operation: completed in 80.878 ms, heap usage 729.504 MB -> 123.360 MB. [2025-08-14T14:07:06.477Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1379.706 ms) ====== [2025-08-14T14:07:06.477Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-14T14:07:06.477Z] GC before operation: completed in 81.147 ms, heap usage 641.651 MB -> 123.360 MB. [2025-08-14T14:07:08.351Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1432.604 ms) ====== [2025-08-14T14:07:08.351Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-14T14:07:08.351Z] GC before operation: completed in 82.541 ms, heap usage 451.623 MB -> 123.036 MB. [2025-08-14T14:07:09.592Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1425.886 ms) ====== [2025-08-14T14:07:09.592Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-14T14:07:09.592Z] GC before operation: completed in 82.928 ms, heap usage 744.778 MB -> 124.429 MB. [2025-08-14T14:07:11.337Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1430.251 ms) ====== [2025-08-14T14:07:11.337Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-14T14:07:11.337Z] GC before operation: completed in 86.463 ms, heap usage 537.675 MB -> 123.626 MB. [2025-08-14T14:07:12.602Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1429.265 ms) ====== [2025-08-14T14:07:12.602Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-14T14:07:12.602Z] GC before operation: completed in 85.900 ms, heap usage 697.447 MB -> 124.428 MB. [2025-08-14T14:07:14.300Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1415.858 ms) ====== [2025-08-14T14:07:14.300Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-14T14:07:14.300Z] GC before operation: completed in 84.775 ms, heap usage 473.937 MB -> 123.913 MB. [2025-08-14T14:07:15.562Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1354.939 ms) ====== [2025-08-14T14:07:15.562Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-14T14:07:15.562Z] GC before operation: completed in 84.661 ms, heap usage 708.424 MB -> 124.826 MB. [2025-08-14T14:07:17.173Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1450.916 ms) ====== [2025-08-14T14:07:17.173Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-14T14:07:17.173Z] GC before operation: completed in 88.451 ms, heap usage 572.321 MB -> 124.589 MB. [2025-08-14T14:07:18.333Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1401.694 ms) ====== [2025-08-14T14:07:18.333Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-14T14:07:18.681Z] GC before operation: completed in 92.003 ms, heap usage 279.877 MB -> 123.906 MB. [2025-08-14T14:07:20.438Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1462.361 ms) ====== [2025-08-14T14:07:20.438Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-14T14:07:20.438Z] GC before operation: completed in 91.772 ms, heap usage 255.494 MB -> 124.067 MB. [2025-08-14T14:07:21.579Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1558.954 ms) ====== [2025-08-14T14:07:21.579Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-14T14:07:21.579Z] GC before operation: completed in 85.880 ms, heap usage 445.894 MB -> 124.886 MB. [2025-08-14T14:07:23.356Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1393.537 ms) ====== [2025-08-14T14:07:23.356Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-14T14:07:23.356Z] GC before operation: completed in 87.317 ms, heap usage 624.202 MB -> 125.684 MB. [2025-08-14T14:07:24.475Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1420.789 ms) ====== [2025-08-14T14:07:25.143Z] ----------------------------------- [2025-08-14T14:07:25.143Z] renaissance-log-regression_0_PASSED [2025-08-14T14:07:25.143Z] ----------------------------------- [2025-08-14T14:07:25.143Z] [2025-08-14T14:07:25.144Z] TEST TEARDOWN: [2025-08-14T14:07:25.144Z] Nothing to be done for teardown. [2025-08-14T14:07:25.144Z] renaissance-log-regression_0 Finish Time: Thu Aug 14 14:07:25 2025 Epoch Time (ms): 1755180445002