[2026-04-14T17:28:53.947Z] Running test renaissance-log-regression_0 ... [2026-04-14T17:28:53.947Z] =============================================== [2026-04-14T17:28:53.947Z] renaissance-log-regression_0 Start Time: Tue Apr 14 17:28:53 2026 Epoch Time (ms): 1776187733798 [2026-04-14T17:28:53.947Z] variation: NoOptions [2026-04-14T17:28:54.294Z] JVM_OPTIONS: [2026-04-14T17:28:54.294Z] { \ [2026-04-14T17:28:54.294Z] echo ""; echo "TEST SETUP:"; \ [2026-04-14T17:28:54.294Z] echo "Nothing to be done for setup."; \ [2026-04-14T17:28:54.294Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17761863689289\\renaissance-log-regression_0"; \ [2026-04-14T17:28:54.294Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17761863689289\\renaissance-log-regression_0"; \ [2026-04-14T17:28:54.294Z] echo ""; echo "TESTING:"; \ [2026-04-14T17:28:54.295Z] "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_17761863689289\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2026-04-14T17:28:54.295Z] 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_17761863689289\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2026-04-14T17:28:54.295Z] echo ""; echo "TEST TEARDOWN:"; \ [2026-04-14T17:28:54.295Z] echo "Nothing to be done for teardown."; \ [2026-04-14T17:28:54.295Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17761863689289\\TestTargetResult"; [2026-04-14T17:28:54.295Z] [2026-04-14T17:28:54.295Z] TEST SETUP: [2026-04-14T17:28:54.295Z] Nothing to be done for setup. [2026-04-14T17:28:54.295Z] [2026-04-14T17:28:54.295Z] TESTING: [2026-04-14T17:29:07.530Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2026-04-14T17:29:08.336Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2026-04-14T17:29:08.336Z] GC before operation: completed in 57.418 ms, heap usage 62.240 MB -> 34.933 MB. [2026-04-14T17:29:20.533Z] ====== log-regression (apache-spark) [default], iteration 0 completed (11915.391 ms) ====== [2026-04-14T17:29:20.533Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2026-04-14T17:29:20.533Z] GC before operation: completed in 123.526 ms, heap usage 369.927 MB -> 118.150 MB. [2026-04-14T17:29:24.091Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3072.878 ms) ====== [2026-04-14T17:29:24.091Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2026-04-14T17:29:24.091Z] GC before operation: completed in 82.477 ms, heap usage 296.501 MB -> 117.186 MB. [2026-04-14T17:29:25.955Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2301.659 ms) ====== [2026-04-14T17:29:25.955Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2026-04-14T17:29:25.955Z] GC before operation: completed in 72.857 ms, heap usage 325.608 MB -> 117.750 MB. [2026-04-14T17:29:28.154Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1908.318 ms) ====== [2026-04-14T17:29:28.154Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2026-04-14T17:29:28.154Z] GC before operation: completed in 73.453 ms, heap usage 285.008 MB -> 117.861 MB. [2026-04-14T17:29:29.974Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1797.451 ms) ====== [2026-04-14T17:29:29.974Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2026-04-14T17:29:29.974Z] GC before operation: completed in 76.952 ms, heap usage 274.094 MB -> 118.039 MB. [2026-04-14T17:29:31.840Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1768.334 ms) ====== [2026-04-14T17:29:31.840Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2026-04-14T17:29:31.840Z] GC before operation: completed in 75.852 ms, heap usage 477.352 MB -> 119.024 MB. [2026-04-14T17:29:33.645Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1717.571 ms) ====== [2026-04-14T17:29:33.645Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2026-04-14T17:29:33.645Z] GC before operation: completed in 83.631 ms, heap usage 553.686 MB -> 119.510 MB. [2026-04-14T17:29:35.485Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1552.854 ms) ====== [2026-04-14T17:29:35.485Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2026-04-14T17:29:35.485Z] GC before operation: completed in 83.597 ms, heap usage 262.901 MB -> 118.675 MB. [2026-04-14T17:29:36.862Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1467.690 ms) ====== [2026-04-14T17:29:36.862Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2026-04-14T17:29:36.862Z] GC before operation: completed in 79.470 ms, heap usage 270.296 MB -> 118.937 MB. [2026-04-14T17:29:38.214Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1585.162 ms) ====== [2026-04-14T17:29:38.214Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2026-04-14T17:29:38.686Z] GC before operation: completed in 83.071 ms, heap usage 455.630 MB -> 119.739 MB. [2026-04-14T17:29:40.010Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1489.302 ms) ====== [2026-04-14T17:29:40.010Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2026-04-14T17:29:40.010Z] GC before operation: completed in 82.426 ms, heap usage 251.446 MB -> 119.118 MB. [2026-04-14T17:29:41.342Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1462.797 ms) ====== [2026-04-14T17:29:41.342Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2026-04-14T17:29:41.918Z] GC before operation: completed in 84.018 ms, heap usage 368.614 MB -> 119.827 MB. [2026-04-14T17:29:43.446Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1415.019 ms) ====== [2026-04-14T17:29:43.446Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2026-04-14T17:29:43.446Z] GC before operation: completed in 82.948 ms, heap usage 252.158 MB -> 119.571 MB. [2026-04-14T17:29:44.688Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1487.127 ms) ====== [2026-04-14T17:29:44.688Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2026-04-14T17:29:44.688Z] GC before operation: completed in 86.469 ms, heap usage 251.399 MB -> 119.772 MB. [2026-04-14T17:29:46.014Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1417.360 ms) ====== [2026-04-14T17:29:46.014Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2026-04-14T17:29:46.014Z] GC before operation: completed in 89.338 ms, heap usage 460.724 MB -> 120.649 MB. [2026-04-14T17:29:47.427Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1451.586 ms) ====== [2026-04-14T17:29:47.427Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2026-04-14T17:29:47.781Z] GC before operation: completed in 117.720 ms, heap usage 251.430 MB -> 120.034 MB. [2026-04-14T17:29:49.718Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1681.164 ms) ====== [2026-04-14T17:29:49.718Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2026-04-14T17:29:49.718Z] GC before operation: completed in 84.265 ms, heap usage 384.411 MB -> 120.861 MB. [2026-04-14T17:29:50.989Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1423.190 ms) ====== [2026-04-14T17:29:50.989Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2026-04-14T17:29:50.989Z] GC before operation: completed in 91.733 ms, heap usage 305.125 MB -> 120.759 MB. [2026-04-14T17:29:52.261Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1431.877 ms) ====== [2026-04-14T17:29:52.261Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2026-04-14T17:29:52.677Z] GC before operation: completed in 86.803 ms, heap usage 437.839 MB -> 121.365 MB. [2026-04-14T17:29:53.952Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1476.672 ms) ====== [2026-04-14T17:29:54.313Z] 17:29:54.012 WARN [Thread-1] org.renaissance.core - Error deleting scratch directory C:\jenkins\workspace\Test_openjdk17_hs_extended.perf_x86-64_windows\aqa-tests\TKG\output_17761863689289\renaissance-log-regression_0\harness-172854-3791088859295726389: harness-172854-3791088859295726389\apache-spark\lib\activation-1.1.1.jar: The process cannot access the file because it is being used by another process [2026-04-14T17:29:54.313Z] [2026-04-14T17:29:53.952+0000] org.renaissance.core (org.renaissance.core.DirUtils lambda$createScratchDirectory$1) [2026-04-14T17:29:54.313Z] WARNING: Error deleting scratch directory C:\jenkins\workspace\Test_openjdk17_hs_extended.perf_x86-64_windows\aqa-tests\TKG\output_17761863689289\renaissance-log-regression_0\harness-172854-3791088859295726389: harness-172854-3791088859295726389\apache-spark\lib\activation-1.1.1.jar: The process cannot access the file because it is being used by another process [2026-04-14T17:29:54.313Z] ----------------------------------- [2026-04-14T17:29:54.313Z] renaissance-log-regression_0_PASSED [2026-04-14T17:29:54.313Z] ----------------------------------- [2026-04-14T17:29:54.651Z] [2026-04-14T17:29:54.651Z] TEST TEARDOWN: [2026-04-14T17:29:54.651Z] Nothing to be done for teardown. [2026-04-14T17:29:54.651Z] renaissance-log-regression_0 Finish Time: Tue Apr 14 17:29:54 2026 Epoch Time (ms): 1776187794409