[2025-11-05T23:27:10.294Z] Running test renaissance-log-regression_0 ... [2025-11-05T23:27:10.294Z] =============================================== [2025-11-05T23:27:10.637Z] renaissance-log-regression_0 Start Time: Wed Nov 5 23:27:10 2025 Epoch Time (ms): 1762385230411 [2025-11-05T23:27:10.637Z] variation: NoOptions [2025-11-05T23:27:10.637Z] JVM_OPTIONS: [2025-11-05T23:27:10.637Z] { \ [2025-11-05T23:27:10.637Z] echo ""; echo "TEST SETUP:"; \ [2025-11-05T23:27:10.637Z] echo "Nothing to be done for setup."; \ [2025-11-05T23:27:10.637Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17623836678970\\renaissance-log-regression_0"; \ [2025-11-05T23:27:10.637Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17623836678970\\renaissance-log-regression_0"; \ [2025-11-05T23:27:10.637Z] echo ""; echo "TESTING:"; \ [2025-11-05T23:27:10.637Z] "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_17623836678970\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2025-11-05T23:27:10.637Z] 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_17623836678970\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-05T23:27:10.637Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-05T23:27:10.637Z] echo "Nothing to be done for teardown."; \ [2025-11-05T23:27:10.637Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17623836678970\\TestTargetResult"; [2025-11-05T23:27:10.996Z] [2025-11-05T23:27:10.996Z] TEST SETUP: [2025-11-05T23:27:10.996Z] Nothing to be done for setup. [2025-11-05T23:27:10.996Z] [2025-11-05T23:27:10.996Z] TESTING: [2025-11-05T23:27:24.328Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-05T23:27:27.364Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-11-05T23:27:27.364Z] GC before operation: completed in 38.599 ms, heap usage 50.000 MB -> 35.435 MB. [2025-11-05T23:27:38.782Z] ====== log-regression (apache-spark) [default], iteration 0 completed (10222.331 ms) ====== [2025-11-05T23:27:38.782Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-11-05T23:27:38.782Z] GC before operation: completed in 119.106 ms, heap usage 384.944 MB -> 120.226 MB. [2025-11-05T23:27:40.131Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2447.067 ms) ====== [2025-11-05T23:27:40.131Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-11-05T23:27:40.131Z] GC before operation: completed in 70.662 ms, heap usage 268.631 MB -> 120.436 MB. [2025-11-05T23:27:42.009Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1840.924 ms) ====== [2025-11-05T23:27:42.010Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-11-05T23:27:42.010Z] GC before operation: completed in 74.696 ms, heap usage 293.604 MB -> 120.880 MB. [2025-11-05T23:27:43.881Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1817.180 ms) ====== [2025-11-05T23:27:43.881Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-11-05T23:27:43.881Z] GC before operation: completed in 90.465 ms, heap usage 850.299 MB -> 122.959 MB. [2025-11-05T23:27:45.694Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1693.799 ms) ====== [2025-11-05T23:27:45.694Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-11-05T23:27:45.694Z] GC before operation: completed in 81.716 ms, heap usage 454.333 MB -> 121.987 MB. [2025-11-05T23:27:47.465Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1573.248 ms) ====== [2025-11-05T23:27:47.465Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-11-05T23:27:47.465Z] GC before operation: completed in 80.492 ms, heap usage 713.211 MB -> 123.077 MB. [2025-11-05T23:27:48.901Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1460.303 ms) ====== [2025-11-05T23:27:48.901Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-11-05T23:27:48.901Z] GC before operation: completed in 79.515 ms, heap usage 682.452 MB -> 123.176 MB. [2025-11-05T23:27:50.169Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1438.940 ms) ====== [2025-11-05T23:27:50.169Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-11-05T23:27:50.169Z] GC before operation: completed in 84.774 ms, heap usage 593.760 MB -> 123.075 MB. [2025-11-05T23:27:52.274Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1466.141 ms) ====== [2025-11-05T23:27:52.274Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-11-05T23:27:52.274Z] GC before operation: completed in 81.499 ms, heap usage 311.256 MB -> 122.500 MB. [2025-11-05T23:27:53.547Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1529.293 ms) ====== [2025-11-05T23:27:53.547Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-11-05T23:27:53.547Z] GC before operation: completed in 80.282 ms, heap usage 336.164 MB -> 122.786 MB. [2025-11-05T23:27:54.787Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1376.193 ms) ====== [2025-11-05T23:27:54.787Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-11-05T23:27:54.787Z] GC before operation: completed in 82.526 ms, heap usage 649.694 MB -> 123.956 MB. [2025-11-05T23:27:56.248Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1399.412 ms) ====== [2025-11-05T23:27:56.248Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-11-05T23:27:56.662Z] GC before operation: completed in 98.205 ms, heap usage 256.282 MB -> 122.960 MB. [2025-11-05T23:27:58.030Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1407.689 ms) ====== [2025-11-05T23:27:58.030Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-11-05T23:27:58.030Z] GC before operation: completed in 86.262 ms, heap usage 561.954 MB -> 124.023 MB. [2025-11-05T23:27:59.304Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1462.516 ms) ====== [2025-11-05T23:27:59.304Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-11-05T23:27:59.736Z] GC before operation: completed in 95.517 ms, heap usage 415.077 MB -> 123.855 MB. [2025-11-05T23:28:01.014Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1353.419 ms) ====== [2025-11-05T23:28:01.014Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-11-05T23:28:01.014Z] GC before operation: completed in 91.545 ms, heap usage 145.067 MB -> 123.464 MB. [2025-11-05T23:28:02.279Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1398.490 ms) ====== [2025-11-05T23:28:02.279Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-11-05T23:28:02.621Z] GC before operation: completed in 95.881 ms, heap usage 298.695 MB -> 123.838 MB. [2025-11-05T23:28:04.380Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1589.472 ms) ====== [2025-11-05T23:28:04.380Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-11-05T23:28:04.380Z] GC before operation: completed in 89.541 ms, heap usage 246.173 MB -> 123.895 MB. [2025-11-05T23:28:05.697Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1401.416 ms) ====== [2025-11-05T23:28:05.697Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-11-05T23:28:05.697Z] GC before operation: completed in 91.355 ms, heap usage 321.151 MB -> 124.369 MB. [2025-11-05T23:28:06.913Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1359.951 ms) ====== [2025-11-05T23:28:06.913Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-11-05T23:28:06.913Z] GC before operation: completed in 93.838 ms, heap usage 702.483 MB -> 125.740 MB. [2025-11-05T23:28:08.786Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1414.550 ms) ====== [2025-11-05T23:28:08.786Z] ----------------------------------- [2025-11-05T23:28:08.787Z] renaissance-log-regression_0_PASSED [2025-11-05T23:28:08.787Z] ----------------------------------- [2025-11-05T23:28:09.142Z] [2025-11-05T23:28:09.142Z] TEST TEARDOWN: [2025-11-05T23:28:09.142Z] Nothing to be done for teardown. [2025-11-05T23:28:09.142Z] renaissance-log-regression_0 Finish Time: Wed Nov 5 23:28:08 2025 Epoch Time (ms): 1762385288910