[2025-08-30T10:25:38.319Z] Running test renaissance-log-regression_0 ... [2025-08-30T10:25:38.319Z] =============================================== [2025-08-30T10:25:38.319Z] renaissance-log-regression_0 Start Time: Sat Aug 30 10:25:37 2025 Epoch Time (ms): 1756549537514 [2025-08-30T10:25:38.319Z] variation: NoOptions [2025-08-30T10:25:38.319Z] JVM_OPTIONS: [2025-08-30T10:25:38.319Z] { \ [2025-08-30T10:25:38.319Z] echo ""; echo "TEST SETUP:"; \ [2025-08-30T10:25:38.319Z] echo "Nothing to be done for setup."; \ [2025-08-30T10:25:38.319Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_17565486695062/renaissance-log-regression_0"; \ [2025-08-30T10:25:38.319Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_17565486695062/renaissance-log-regression_0"; \ [2025-08-30T10:25:38.319Z] echo ""; echo "TESTING:"; \ [2025-08-30T10:25:38.319Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux_testList_1/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 "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_17565486695062/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-30T10:25:38.319Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_17565486695062/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-30T10:25:38.319Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-30T10:25:38.319Z] echo "Nothing to be done for teardown."; \ [2025-08-30T10:25:38.319Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux_testList_1/aqa-tests/TKG/../TKG/output_17565486695062/TestTargetResult"; [2025-08-30T10:25:38.319Z] [2025-08-30T10:25:38.319Z] TEST SETUP: [2025-08-30T10:25:38.319Z] Nothing to be done for setup. [2025-08-30T10:25:38.319Z] [2025-08-30T10:25:38.319Z] TESTING: [2025-08-30T10:25:46.571Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-08-30T10:25:51.023Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-30T10:25:51.023Z] GC before operation: completed in 130.476 ms, heap usage 75.614 MB -> 35.304 MB. [2025-08-30T10:26:09.892Z] ====== log-regression (apache-spark) [default], iteration 0 completed (17547.721 ms) ====== [2025-08-30T10:26:09.892Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-30T10:26:09.892Z] GC before operation: completed in 184.827 ms, heap usage 294.757 MB -> 119.534 MB. [2025-08-30T10:26:12.484Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3980.337 ms) ====== [2025-08-30T10:26:12.484Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-30T10:26:13.259Z] GC before operation: completed in 139.452 ms, heap usage 718.873 MB -> 121.332 MB. [2025-08-30T10:26:16.667Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3576.550 ms) ====== [2025-08-30T10:26:16.667Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-30T10:26:16.667Z] GC before operation: completed in 233.442 ms, heap usage 363.301 MB -> 120.674 MB. [2025-08-30T10:26:20.077Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3239.150 ms) ====== [2025-08-30T10:26:20.077Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-30T10:26:20.077Z] GC before operation: completed in 188.015 ms, heap usage 321.856 MB -> 120.888 MB. [2025-08-30T10:26:23.505Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3476.759 ms) ====== [2025-08-30T10:26:23.505Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-30T10:26:23.505Z] GC before operation: completed in 166.478 ms, heap usage 346.012 MB -> 121.244 MB. [2025-08-30T10:26:26.931Z] ====== log-regression (apache-spark) [default], iteration 5 completed (3307.547 ms) ====== [2025-08-30T10:26:26.931Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-30T10:26:27.700Z] GC before operation: completed in 183.736 ms, heap usage 665.567 MB -> 122.381 MB. [2025-08-30T10:26:31.105Z] ====== log-regression (apache-spark) [default], iteration 6 completed (3458.957 ms) ====== [2025-08-30T10:26:31.105Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-30T10:26:31.105Z] GC before operation: completed in 142.876 ms, heap usage 614.305 MB -> 122.464 MB. [2025-08-30T10:26:33.566Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2850.968 ms) ====== [2025-08-30T10:26:33.567Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-30T10:26:34.347Z] GC before operation: completed in 184.352 ms, heap usage 643.123 MB -> 122.720 MB. [2025-08-30T10:26:36.819Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2775.679 ms) ====== [2025-08-30T10:26:36.819Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-30T10:26:36.819Z] GC before operation: completed in 161.336 ms, heap usage 549.553 MB -> 122.682 MB. [2025-08-30T10:26:40.246Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2978.738 ms) ====== [2025-08-30T10:26:40.246Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-30T10:26:40.246Z] GC before operation: completed in 199.346 ms, heap usage 375.000 MB -> 122.354 MB. [2025-08-30T10:26:42.705Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2738.992 ms) ====== [2025-08-30T10:26:42.705Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-30T10:26:42.705Z] GC before operation: completed in 246.227 ms, heap usage 737.882 MB -> 123.729 MB. [2025-08-30T10:26:46.162Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2800.343 ms) ====== [2025-08-30T10:26:46.162Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-30T10:26:46.162Z] GC before operation: completed in 228.830 ms, heap usage 560.507 MB -> 123.300 MB. [2025-08-30T10:26:48.637Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2890.870 ms) ====== [2025-08-30T10:26:48.637Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-30T10:26:49.403Z] GC before operation: completed in 198.394 ms, heap usage 707.149 MB -> 124.015 MB. [2025-08-30T10:26:51.877Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2787.997 ms) ====== [2025-08-30T10:26:51.877Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-30T10:26:51.877Z] GC before operation: completed in 186.393 ms, heap usage 629.681 MB -> 123.983 MB. [2025-08-30T10:26:55.351Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2754.144 ms) ====== [2025-08-30T10:26:55.351Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-30T10:26:55.351Z] GC before operation: completed in 196.057 ms, heap usage 400.674 MB -> 123.449 MB. [2025-08-30T10:26:57.809Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2808.885 ms) ====== [2025-08-30T10:26:57.809Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-30T10:26:57.809Z] GC before operation: completed in 204.528 ms, heap usage 1.040 GB -> 124.931 MB. [2025-08-30T10:27:01.260Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3271.960 ms) ====== [2025-08-30T10:27:01.260Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-30T10:27:01.260Z] GC before operation: completed in 184.047 ms, heap usage 348.856 MB -> 123.755 MB. [2025-08-30T10:27:04.682Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2669.232 ms) ====== [2025-08-30T10:27:04.682Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-30T10:27:04.682Z] GC before operation: completed in 199.962 ms, heap usage 575.047 MB -> 124.560 MB. [2025-08-30T10:27:07.149Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2595.011 ms) ====== [2025-08-30T10:27:07.149Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-30T10:27:07.149Z] GC before operation: completed in 155.239 ms, heap usage 656.489 MB -> 124.970 MB. [2025-08-30T10:27:10.556Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2848.796 ms) ====== [2025-08-30T10:27:10.556Z] ----------------------------------- [2025-08-30T10:27:10.556Z] renaissance-log-regression_0_PASSED [2025-08-30T10:27:10.556Z] ----------------------------------- [2025-08-30T10:27:10.556Z] [2025-08-30T10:27:10.556Z] TEST TEARDOWN: [2025-08-30T10:27:10.556Z] Nothing to be done for teardown. [2025-08-30T10:27:10.556Z] renaissance-log-regression_0 Finish Time: Sat Aug 30 10:27:10 2025 Epoch Time (ms): 1756549630146