[2025-07-31T04:45:32.046Z] Running test renaissance-log-regression_0 ... [2025-07-31T04:45:32.046Z] =============================================== [2025-07-31T04:45:32.046Z] renaissance-log-regression_0 Start Time: Thu Jul 31 04:45:31 2025 Epoch Time (ms): 1753937131964 [2025-07-31T04:45:32.372Z] variation: NoOptions [2025-07-31T04:45:32.372Z] JVM_OPTIONS: [2025-07-31T04:45:32.372Z] { \ [2025-07-31T04:45:32.372Z] echo ""; echo "TEST SETUP:"; \ [2025-07-31T04:45:32.372Z] echo "Nothing to be done for setup."; \ [2025-07-31T04:45:32.372Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17539358784423\\renaissance-log-regression_0"; \ [2025-07-31T04:45:32.372Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17539358784423\\renaissance-log-regression_0"; \ [2025-07-31T04:45:32.372Z] echo ""; echo "TESTING:"; \ [2025-07-31T04:45:32.372Z] "c:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows_rerun/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_rerun/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17539358784423\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2025-07-31T04:45:32.373Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows_rerun/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17539358784423\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-31T04:45:32.373Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-31T04:45:32.373Z] echo "Nothing to be done for teardown."; \ [2025-07-31T04:45:32.373Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows_rerun/aqa-tests/\\TKG\\output_17539358784423\\TestTargetResult"; [2025-07-31T04:45:32.373Z] [2025-07-31T04:45:32.373Z] TEST SETUP: [2025-07-31T04:45:32.373Z] Nothing to be done for setup. [2025-07-31T04:45:32.373Z] [2025-07-31T04:45:32.373Z] TESTING: [2025-07-31T04:45:47.960Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-07-31T04:45:51.614Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-07-31T04:45:51.614Z] GC before operation: completed in 52.740 ms, heap usage 55.100 MB -> 35.549 MB. [2025-07-31T04:46:02.338Z] ====== log-regression (apache-spark) [default], iteration 0 completed (11191.255 ms) ====== [2025-07-31T04:46:02.338Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-07-31T04:46:02.821Z] GC before operation: completed in 148.957 ms, heap usage 696.658 MB -> 121.185 MB. [2025-07-31T04:46:05.359Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2621.449 ms) ====== [2025-07-31T04:46:05.359Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-07-31T04:46:05.359Z] GC before operation: completed in 73.114 ms, heap usage 298.007 MB -> 120.680 MB. [2025-07-31T04:46:07.065Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1933.771 ms) ====== [2025-07-31T04:46:07.065Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-07-31T04:46:07.405Z] GC before operation: completed in 74.737 ms, heap usage 255.845 MB -> 120.952 MB. [2025-07-31T04:46:09.092Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1661.423 ms) ====== [2025-07-31T04:46:09.092Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-07-31T04:46:09.092Z] GC before operation: completed in 80.717 ms, heap usage 739.664 MB -> 122.819 MB. [2025-07-31T04:46:10.807Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1635.132 ms) ====== [2025-07-31T04:46:10.807Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-07-31T04:46:10.807Z] GC before operation: completed in 86.799 ms, heap usage 717.325 MB -> 122.952 MB. [2025-07-31T04:46:12.456Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1584.810 ms) ====== [2025-07-31T04:46:12.456Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-07-31T04:46:12.456Z] GC before operation: completed in 80.888 ms, heap usage 692.351 MB -> 123.155 MB. [2025-07-31T04:46:14.180Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1621.137 ms) ====== [2025-07-31T04:46:14.180Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-07-31T04:46:14.180Z] GC before operation: completed in 86.625 ms, heap usage 161.379 MB -> 121.858 MB. [2025-07-31T04:46:15.907Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1540.986 ms) ====== [2025-07-31T04:46:15.907Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-07-31T04:46:15.907Z] GC before operation: completed in 82.876 ms, heap usage 738.064 MB -> 123.649 MB. [2025-07-31T04:46:17.575Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1574.748 ms) ====== [2025-07-31T04:46:17.575Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-07-31T04:46:17.575Z] GC before operation: completed in 84.695 ms, heap usage 749.254 MB -> 123.880 MB. [2025-07-31T04:46:18.883Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1561.243 ms) ====== [2025-07-31T04:46:18.883Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-07-31T04:46:19.248Z] GC before operation: completed in 90.379 ms, heap usage 340.376 MB -> 122.876 MB. [2025-07-31T04:46:20.442Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1526.493 ms) ====== [2025-07-31T04:46:20.442Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-07-31T04:46:20.769Z] GC before operation: completed in 97.470 ms, heap usage 557.039 MB -> 123.691 MB. [2025-07-31T04:46:22.403Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1576.297 ms) ====== [2025-07-31T04:46:22.404Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-07-31T04:46:22.404Z] GC before operation: completed in 89.858 ms, heap usage 644.011 MB -> 124.224 MB. [2025-07-31T04:46:24.038Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1490.092 ms) ====== [2025-07-31T04:46:24.038Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-07-31T04:46:24.038Z] GC before operation: completed in 90.780 ms, heap usage 690.309 MB -> 124.485 MB. [2025-07-31T04:46:25.761Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1464.540 ms) ====== [2025-07-31T04:46:25.761Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-07-31T04:46:25.761Z] GC before operation: completed in 95.243 ms, heap usage 152.274 MB -> 123.314 MB. [2025-07-31T04:46:27.054Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1418.292 ms) ====== [2025-07-31T04:46:27.054Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-07-31T04:46:27.054Z] GC before operation: completed in 90.251 ms, heap usage 703.645 MB -> 124.971 MB. [2025-07-31T04:46:28.739Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1440.744 ms) ====== [2025-07-31T04:46:28.739Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-07-31T04:46:28.739Z] GC before operation: completed in 95.405 ms, heap usage 385.736 MB -> 124.219 MB. [2025-07-31T04:46:30.401Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1628.536 ms) ====== [2025-07-31T04:46:30.401Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-07-31T04:46:30.401Z] GC before operation: completed in 92.180 ms, heap usage 404.481 MB -> 124.426 MB. [2025-07-31T04:46:32.014Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1517.834 ms) ====== [2025-07-31T04:46:32.014Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-07-31T04:46:32.014Z] GC before operation: completed in 101.036 ms, heap usage 624.375 MB -> 125.388 MB. [2025-07-31T04:46:33.166Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1402.257 ms) ====== [2025-07-31T04:46:33.167Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-07-31T04:46:33.545Z] GC before operation: completed in 92.904 ms, heap usage 646.424 MB -> 125.690 MB. [2025-07-31T04:46:34.882Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1449.457 ms) ====== [2025-07-31T04:46:35.207Z] [2025-07-31T04:46:34.914+0000] org.renaissance.core (org.renaissance.core.DirUtils lambda$createScratchDirectory$1) [2025-07-31T04:46:35.207Z] WARNING: Error deleting scratch directory C:\jenkins\workspace\Test_openjdk21_hs_extended.perf_x86-64_windows_rerun\aqa-tests\TKG\output_17539358784423\renaissance-log-regression_0\harness-044533-15021351598451958978: harness-044533-15021351598451958978\apache-spark\lib\activation-1.1.1.jar: The process cannot access the file because it is being used by another process [2025-07-31T04:46:35.207Z] ----------------------------------- [2025-07-31T04:46:35.207Z] renaissance-log-regression_0_PASSED [2025-07-31T04:46:35.207Z] ----------------------------------- [2025-07-31T04:46:35.518Z] [2025-07-31T04:46:35.518Z] TEST TEARDOWN: [2025-07-31T04:46:35.518Z] Nothing to be done for teardown. [2025-07-31T04:46:35.518Z] renaissance-log-regression_0 Finish Time: Thu Jul 31 04:46:35 2025 Epoch Time (ms): 1753937195362