[2025-09-03T22:37:47.898Z] Running test renaissance-log-regression_0 ... [2025-09-03T22:37:47.898Z] =============================================== [2025-09-03T22:37:48.205Z] renaissance-log-regression_0 Start Time: Wed Sep 3 22:37:47 2025 Epoch Time (ms): 1756939067948 [2025-09-03T22:37:48.205Z] variation: NoOptions [2025-09-03T22:37:48.526Z] JVM_OPTIONS: [2025-09-03T22:37:48.526Z] { \ [2025-09-03T22:37:48.526Z] echo ""; echo "TEST SETUP:"; \ [2025-09-03T22:37:48.526Z] echo "Nothing to be done for setup."; \ [2025-09-03T22:37:48.526Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17569375734488\\renaissance-log-regression_0"; \ [2025-09-03T22:37:48.526Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17569375734488\\renaissance-log-regression_0"; \ [2025-09-03T22:37:48.526Z] echo ""; echo "TESTING:"; \ [2025-09-03T22:37:48.526Z] "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_17569375734488\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2025-09-03T22:37:48.526Z] 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_17569375734488\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-03T22:37:48.526Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-03T22:37:48.526Z] echo "Nothing to be done for teardown."; \ [2025-09-03T22:37:48.526Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17569375734488\\TestTargetResult"; [2025-09-03T22:37:48.526Z] [2025-09-03T22:37:48.526Z] TEST SETUP: [2025-09-03T22:37:48.526Z] Nothing to be done for setup. [2025-09-03T22:37:48.526Z] [2025-09-03T22:37:48.526Z] TESTING: [2025-09-03T22:38:01.400Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-09-03T22:38:04.249Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-09-03T22:38:04.249Z] GC before operation: completed in 43.969 ms, heap usage 49.042 MB -> 35.444 MB. [2025-09-03T22:38:15.047Z] ====== log-regression (apache-spark) [default], iteration 0 completed (10651.243 ms) ====== [2025-09-03T22:38:15.048Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-09-03T22:38:15.048Z] GC before operation: completed in 71.623 ms, heap usage 489.465 MB -> 120.533 MB. [2025-09-03T22:38:17.544Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2455.183 ms) ====== [2025-09-03T22:38:17.544Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-09-03T22:38:17.544Z] GC before operation: completed in 70.508 ms, heap usage 465.048 MB -> 121.041 MB. [2025-09-03T22:38:19.276Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1837.786 ms) ====== [2025-09-03T22:38:19.276Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-09-03T22:38:19.276Z] GC before operation: completed in 70.491 ms, heap usage 417.492 MB -> 121.290 MB. [2025-09-03T22:38:20.961Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1680.607 ms) ====== [2025-09-03T22:38:20.961Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-09-03T22:38:20.961Z] GC before operation: completed in 73.030 ms, heap usage 270.288 MB -> 121.167 MB. [2025-09-03T22:38:22.655Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1607.867 ms) ====== [2025-09-03T22:38:22.655Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-09-03T22:38:22.655Z] GC before operation: completed in 75.150 ms, heap usage 634.504 MB -> 122.592 MB. [2025-09-03T22:38:24.279Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1586.220 ms) ====== [2025-09-03T22:38:24.279Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-09-03T22:38:24.279Z] GC before operation: completed in 78.798 ms, heap usage 341.401 MB -> 121.822 MB. [2025-09-03T22:38:25.889Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1481.102 ms) ====== [2025-09-03T22:38:25.889Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-09-03T22:38:25.889Z] GC before operation: completed in 75.977 ms, heap usage 668.408 MB -> 123.041 MB. [2025-09-03T22:38:27.567Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1427.803 ms) ====== [2025-09-03T22:38:27.567Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-09-03T22:38:27.567Z] GC before operation: completed in 76.988 ms, heap usage 500.860 MB -> 122.814 MB. [2025-09-03T22:38:28.770Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1349.018 ms) ====== [2025-09-03T22:38:28.770Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-09-03T22:38:28.770Z] GC before operation: completed in 78.875 ms, heap usage 734.589 MB -> 123.757 MB. [2025-09-03T22:38:30.472Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1386.211 ms) ====== [2025-09-03T22:38:30.472Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-09-03T22:38:30.472Z] GC before operation: completed in 78.819 ms, heap usage 626.443 MB -> 123.569 MB. [2025-09-03T22:38:31.677Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1380.018 ms) ====== [2025-09-03T22:38:31.677Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-09-03T22:38:31.677Z] GC before operation: completed in 80.419 ms, heap usage 708.045 MB -> 124.050 MB. [2025-09-03T22:38:33.342Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1431.204 ms) ====== [2025-09-03T22:38:33.342Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-09-03T22:38:33.342Z] GC before operation: completed in 101.717 ms, heap usage 512.703 MB -> 123.630 MB. [2025-09-03T22:38:34.485Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1374.649 ms) ====== [2025-09-03T22:38:34.485Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-09-03T22:38:34.485Z] GC before operation: completed in 80.984 ms, heap usage 702.054 MB -> 124.457 MB. [2025-09-03T22:38:36.275Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1354.809 ms) ====== [2025-09-03T22:38:36.275Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-09-03T22:38:36.275Z] GC before operation: completed in 79.513 ms, heap usage 441.729 MB -> 123.872 MB. [2025-09-03T22:38:37.509Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1289.672 ms) ====== [2025-09-03T22:38:37.509Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-09-03T22:38:37.509Z] GC before operation: completed in 86.640 ms, heap usage 311.868 MB -> 123.700 MB. [2025-09-03T22:38:39.116Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1483.402 ms) ====== [2025-09-03T22:38:39.116Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-09-03T22:38:39.116Z] GC before operation: completed in 84.395 ms, heap usage 315.542 MB -> 123.864 MB. [2025-09-03T22:38:40.892Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1420.986 ms) ====== [2025-09-03T22:38:40.892Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-09-03T22:38:40.892Z] GC before operation: completed in 89.254 ms, heap usage 640.854 MB -> 125.191 MB. [2025-09-03T22:38:42.016Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1509.202 ms) ====== [2025-09-03T22:38:42.016Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-09-03T22:38:42.016Z] GC before operation: completed in 87.868 ms, heap usage 259.201 MB -> 124.186 MB. [2025-09-03T22:38:43.257Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1239.388 ms) ====== [2025-09-03T22:38:43.257Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-09-03T22:38:43.671Z] GC before operation: completed in 91.475 ms, heap usage 322.075 MB -> 124.648 MB. [2025-09-03T22:38:44.915Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1444.652 ms) ====== [2025-09-03T22:38:45.234Z] [2025-09-03T22:38:44.981+0000] org.renaissance.core (org.renaissance.core.DirUtils lambda$createScratchDirectory$1) [2025-09-03T22:38:45.234Z] WARNING: Error deleting scratch directory C:\jenkins\workspace\Test_openjdk21_hs_extended.perf_x86-64_windows\aqa-tests\TKG\output_17569375734488\renaissance-log-regression_0\harness-223749-6294980412884455994: harness-223749-6294980412884455994\apache-spark\lib\activation-1.1.1.jar: The process cannot access the file because it is being used by another process [2025-09-03T22:38:45.234Z] ----------------------------------- [2025-09-03T22:38:45.234Z] renaissance-log-regression_0_PASSED [2025-09-03T22:38:45.234Z] ----------------------------------- [2025-09-03T22:38:45.543Z] [2025-09-03T22:38:45.543Z] TEST TEARDOWN: [2025-09-03T22:38:45.543Z] Nothing to be done for teardown. [2025-09-03T22:38:45.543Z] renaissance-log-regression_0 Finish Time: Wed Sep 3 22:38:45 2025 Epoch Time (ms): 1756939125405