[2025-02-17T18:15:25.371Z] Running test renaissance-log-regression_0 ... [2025-02-17T18:15:25.371Z] =============================================== [2025-02-17T18:15:25.700Z] renaissance-log-regression_0 Start Time: Mon Feb 17 18:15:25 2025 Epoch Time (ms): 1739816125373 [2025-02-17T18:15:25.700Z] variation: NoOptions [2025-02-17T18:15:25.700Z] JVM_OPTIONS: [2025-02-17T18:15:25.700Z] { \ [2025-02-17T18:15:25.700Z] echo ""; echo "TEST SETUP:"; \ [2025-02-17T18:15:25.700Z] echo "Nothing to be done for setup."; \ [2025-02-17T18:15:25.700Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17398151574076\\renaissance-log-regression_0"; \ [2025-02-17T18:15:25.700Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17398151574076\\renaissance-log-regression_0"; \ [2025-02-17T18:15:25.700Z] echo ""; echo "TESTING:"; \ [2025-02-17T18:15:25.700Z] "c:/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17398151574076\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2025-02-17T18:15:25.700Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17398151574076\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-17T18:15:25.700Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-17T18:15:25.700Z] echo "Nothing to be done for teardown."; \ [2025-02-17T18:15:25.700Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17398151574076\\TestTargetResult"; [2025-02-17T18:15:26.034Z] [2025-02-17T18:15:26.034Z] TEST SETUP: [2025-02-17T18:15:26.034Z] Nothing to be done for setup. [2025-02-17T18:15:26.034Z] [2025-02-17T18:15:26.034Z] TESTING: [2025-02-17T18:15:39.081Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-17T18:15:40.714Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-02-17T18:15:44.433Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-02-17T18:15:44.433Z] GC before operation: completed in 53.094 ms, heap usage 38.460 MB -> 35.470 MB. [2025-02-17T18:15:51.814Z] ====== log-regression (apache-spark) [default], iteration 0 completed (7004.042 ms) ====== [2025-02-17T18:15:51.814Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-02-17T18:15:51.814Z] GC before operation: completed in 62.807 ms, heap usage 305.850 MB -> 113.599 MB. [2025-02-17T18:15:53.544Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2219.235 ms) ====== [2025-02-17T18:15:53.544Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-02-17T18:15:53.544Z] GC before operation: completed in 59.702 ms, heap usage 476.761 MB -> 114.571 MB. [2025-02-17T18:15:55.347Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1848.705 ms) ====== [2025-02-17T18:15:55.347Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-02-17T18:15:55.347Z] GC before operation: completed in 70.558 ms, heap usage 406.764 MB -> 114.842 MB. [2025-02-17T18:15:56.983Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1553.309 ms) ====== [2025-02-17T18:15:56.983Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-02-17T18:15:56.983Z] GC before operation: completed in 67.327 ms, heap usage 410.404 MB -> 115.221 MB. [2025-02-17T18:15:58.672Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1404.081 ms) ====== [2025-02-17T18:15:58.672Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-02-17T18:15:58.672Z] GC before operation: completed in 68.043 ms, heap usage 377.552 MB -> 115.298 MB. [2025-02-17T18:15:59.976Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1450.471 ms) ====== [2025-02-17T18:15:59.976Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-02-17T18:15:59.976Z] GC before operation: completed in 66.214 ms, heap usage 376.657 MB -> 115.491 MB. [2025-02-17T18:16:01.158Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1334.317 ms) ====== [2025-02-17T18:16:01.158Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-02-17T18:16:01.578Z] GC before operation: completed in 75.651 ms, heap usage 374.153 MB -> 115.695 MB. [2025-02-17T18:16:02.727Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1449.853 ms) ====== [2025-02-17T18:16:02.727Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-02-17T18:16:02.727Z] GC before operation: completed in 82.429 ms, heap usage 346.494 MB -> 115.991 MB. [2025-02-17T18:16:04.502Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1393.682 ms) ====== [2025-02-17T18:16:04.502Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-02-17T18:16:04.502Z] GC before operation: completed in 72.664 ms, heap usage 279.293 MB -> 115.835 MB. [2025-02-17T18:16:05.766Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1306.214 ms) ====== [2025-02-17T18:16:05.766Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-02-17T18:16:05.766Z] GC before operation: completed in 90.417 ms, heap usage 249.667 MB -> 115.971 MB. [2025-02-17T18:16:06.940Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1293.751 ms) ====== [2025-02-17T18:16:06.941Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-02-17T18:16:07.299Z] GC before operation: completed in 66.994 ms, heap usage 297.133 MB -> 116.288 MB. [2025-02-17T18:16:08.489Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1278.752 ms) ====== [2025-02-17T18:16:08.489Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-02-17T18:16:08.489Z] GC before operation: completed in 85.807 ms, heap usage 354.448 MB -> 116.683 MB. [2025-02-17T18:16:09.652Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1294.379 ms) ====== [2025-02-17T18:16:09.652Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-02-17T18:16:10.086Z] GC before operation: completed in 76.052 ms, heap usage 333.877 MB -> 116.856 MB. [2025-02-17T18:16:11.262Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1199.281 ms) ====== [2025-02-17T18:16:11.262Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-02-17T18:16:11.262Z] GC before operation: completed in 72.067 ms, heap usage 406.847 MB -> 117.227 MB. [2025-02-17T18:16:12.462Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1230.775 ms) ====== [2025-02-17T18:16:12.462Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-02-17T18:16:12.462Z] GC before operation: completed in 75.150 ms, heap usage 342.579 MB -> 117.236 MB. [2025-02-17T18:16:13.618Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1315.438 ms) ====== [2025-02-17T18:16:13.618Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-02-17T18:16:14.033Z] GC before operation: completed in 74.892 ms, heap usage 322.336 MB -> 117.338 MB. [2025-02-17T18:16:15.207Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1289.058 ms) ====== [2025-02-17T18:16:15.207Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-02-17T18:16:15.207Z] GC before operation: completed in 91.629 ms, heap usage 575.669 MB -> 118.137 MB. [2025-02-17T18:16:16.461Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1170.755 ms) ====== [2025-02-17T18:16:16.461Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-02-17T18:16:16.461Z] GC before operation: completed in 74.927 ms, heap usage 335.382 MB -> 117.815 MB. [2025-02-17T18:16:17.623Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1284.668 ms) ====== [2025-02-17T18:16:17.623Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-02-17T18:16:18.037Z] GC before operation: completed in 78.181 ms, heap usage 303.978 MB -> 117.954 MB. [2025-02-17T18:16:19.173Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1184.739 ms) ====== [2025-02-17T18:16:19.491Z] ----------------------------------- [2025-02-17T18:16:19.491Z] renaissance-log-regression_0_PASSED [2025-02-17T18:16:19.491Z] ----------------------------------- [2025-02-17T18:16:19.491Z] [2025-02-17T18:16:19.491Z] TEST TEARDOWN: [2025-02-17T18:16:19.491Z] Nothing to be done for teardown. [2025-02-17T18:16:19.491Z] renaissance-log-regression_0 Finish Time: Mon Feb 17 18:16:19 2025 Epoch Time (ms): 1739816179424