[2025-06-12T22:19:06.803Z] Running test renaissance-log-regression_0 ... [2025-06-12T22:19:07.109Z] =============================================== [2025-06-12T22:19:07.109Z] renaissance-log-regression_0 Start Time: Thu Jun 12 22:19:06 2025 Epoch Time (ms): 1749766746955 [2025-06-12T22:19:07.109Z] variation: NoOptions [2025-06-12T22:19:07.434Z] JVM_OPTIONS: [2025-06-12T22:19:07.434Z] { \ [2025-06-12T22:19:07.434Z] echo ""; echo "TEST SETUP:"; \ [2025-06-12T22:19:07.434Z] echo "Nothing to be done for setup."; \ [2025-06-12T22:19:07.434Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17497652166408\\renaissance-log-regression_0"; \ [2025-06-12T22:19:07.434Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17497652166408\\renaissance-log-regression_0"; \ [2025-06-12T22:19:07.434Z] echo ""; echo "TESTING:"; \ [2025-06-12T22:19:07.434Z] "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_17497652166408\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2025-06-12T22:19:07.434Z] 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_17497652166408\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-12T22:19:07.434Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-12T22:19:07.434Z] echo "Nothing to be done for teardown."; \ [2025-06-12T22:19:07.434Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17497652166408\\TestTargetResult"; [2025-06-12T22:19:07.434Z] [2025-06-12T22:19:07.434Z] TEST SETUP: [2025-06-12T22:19:07.434Z] Nothing to be done for setup. [2025-06-12T22:19:07.434Z] [2025-06-12T22:19:07.434Z] TESTING: [2025-06-12T22:19:20.291Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-12T22:19:23.130Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-06-12T22:19:23.130Z] GC before operation: completed in 40.017 ms, heap usage 46.908 MB -> 35.264 MB. [2025-06-12T22:19:34.191Z] ====== log-regression (apache-spark) [default], iteration 0 completed (10288.772 ms) ====== [2025-06-12T22:19:34.191Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-06-12T22:19:34.191Z] GC before operation: completed in 92.384 ms, heap usage 330.176 MB -> 119.961 MB. [2025-06-12T22:19:35.908Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2308.852 ms) ====== [2025-06-12T22:19:35.908Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-06-12T22:19:35.908Z] GC before operation: completed in 71.411 ms, heap usage 279.218 MB -> 120.294 MB. [2025-06-12T22:19:38.238Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1948.754 ms) ====== [2025-06-12T22:19:38.238Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-06-12T22:19:38.238Z] GC before operation: completed in 70.425 ms, heap usage 250.306 MB -> 120.727 MB. [2025-06-12T22:19:39.456Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1532.695 ms) ====== [2025-06-12T22:19:39.456Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-06-12T22:19:39.456Z] GC before operation: completed in 72.019 ms, heap usage 350.561 MB -> 121.181 MB. [2025-06-12T22:19:41.151Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1465.861 ms) ====== [2025-06-12T22:19:41.151Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-06-12T22:19:41.151Z] GC before operation: completed in 66.665 ms, heap usage 437.344 MB -> 121.756 MB. [2025-06-12T22:19:42.468Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1376.863 ms) ====== [2025-06-12T22:19:42.468Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-06-12T22:19:42.468Z] GC before operation: completed in 66.991 ms, heap usage 263.420 MB -> 121.430 MB. [2025-06-12T22:19:44.171Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1380.930 ms) ====== [2025-06-12T22:19:44.171Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-06-12T22:19:44.171Z] GC before operation: completed in 69.749 ms, heap usage 379.261 MB -> 121.968 MB. [2025-06-12T22:19:45.305Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1314.687 ms) ====== [2025-06-12T22:19:45.305Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-06-12T22:19:45.305Z] GC before operation: completed in 71.904 ms, heap usage 429.761 MB -> 122.386 MB. [2025-06-12T22:19:46.451Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1297.169 ms) ====== [2025-06-12T22:19:46.451Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-06-12T22:19:46.844Z] GC before operation: completed in 74.332 ms, heap usage 426.643 MB -> 122.544 MB. [2025-06-12T22:19:48.024Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1274.737 ms) ====== [2025-06-12T22:19:48.024Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-06-12T22:19:48.024Z] GC before operation: completed in 73.183 ms, heap usage 426.145 MB -> 122.721 MB. [2025-06-12T22:19:49.715Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1322.085 ms) ====== [2025-06-12T22:19:49.715Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-06-12T22:19:49.715Z] GC before operation: completed in 78.400 ms, heap usage 438.160 MB -> 122.992 MB. [2025-06-12T22:19:50.835Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1297.732 ms) ====== [2025-06-12T22:19:50.835Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-06-12T22:19:50.835Z] GC before operation: completed in 77.348 ms, heap usage 349.462 MB -> 123.014 MB. [2025-06-12T22:19:51.982Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1272.944 ms) ====== [2025-06-12T22:19:51.982Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-06-12T22:19:51.982Z] GC before operation: completed in 77.909 ms, heap usage 341.343 MB -> 123.041 MB. [2025-06-12T22:19:53.734Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1274.722 ms) ====== [2025-06-12T22:19:53.734Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-06-12T22:19:53.734Z] GC before operation: completed in 74.869 ms, heap usage 363.200 MB -> 123.313 MB. [2025-06-12T22:19:54.875Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1288.864 ms) ====== [2025-06-12T22:19:54.875Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-06-12T22:19:54.875Z] GC before operation: completed in 76.288 ms, heap usage 384.003 MB -> 123.574 MB. [2025-06-12T22:19:56.009Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1267.260 ms) ====== [2025-06-12T22:19:56.009Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-06-12T22:19:56.401Z] GC before operation: completed in 80.169 ms, heap usage 474.236 MB -> 124.124 MB. [2025-06-12T22:19:57.625Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1370.174 ms) ====== [2025-06-12T22:19:57.625Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-06-12T22:19:57.625Z] GC before operation: completed in 80.934 ms, heap usage 422.453 MB -> 124.242 MB. [2025-06-12T22:19:58.903Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1375.683 ms) ====== [2025-06-12T22:19:58.903Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-06-12T22:19:59.261Z] GC before operation: completed in 79.715 ms, heap usage 409.402 MB -> 124.365 MB. [2025-06-12T22:20:00.474Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1222.509 ms) ====== [2025-06-12T22:20:00.474Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-06-12T22:20:00.474Z] GC before operation: completed in 80.241 ms, heap usage 400.197 MB -> 124.601 MB. [2025-06-12T22:20:02.086Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1448.330 ms) ====== [2025-06-12T22:20:02.086Z] ----------------------------------- [2025-06-12T22:20:02.086Z] renaissance-log-regression_0_PASSED [2025-06-12T22:20:02.086Z] ----------------------------------- [2025-06-12T22:20:02.391Z] [2025-06-12T22:20:02.391Z] TEST TEARDOWN: [2025-06-12T22:20:02.391Z] Nothing to be done for teardown. [2025-06-12T22:20:02.391Z] renaissance-log-regression_0 Finish Time: Thu Jun 12 22:20:02 2025 Epoch Time (ms): 1749766802313