[2025-11-05T22:48:05.644Z] Running test renaissance-log-regression_0 ... [2025-11-05T22:48:05.644Z] =============================================== [2025-11-05T22:48:05.644Z] renaissance-log-regression_0 Start Time: Wed Nov 5 22:48:05 2025 Epoch Time (ms): 1762382885507 [2025-11-05T22:48:05.644Z] variation: NoOptions [2025-11-05T22:48:05.644Z] JVM_OPTIONS: [2025-11-05T22:48:05.644Z] { \ [2025-11-05T22:48:05.644Z] echo ""; echo "TEST SETUP:"; \ [2025-11-05T22:48:05.644Z] echo "Nothing to be done for setup."; \ [2025-11-05T22:48:05.644Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17623812747019/renaissance-log-regression_0"; \ [2025-11-05T22:48:05.644Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17623812747019/renaissance-log-regression_0"; \ [2025-11-05T22:48:05.644Z] echo ""; echo "TESTING:"; \ [2025-11-05T22:48:05.644Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/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/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17623812747019/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-11-05T22:48:05.644Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17623812747019/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-05T22:48:05.644Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-05T22:48:05.644Z] echo "Nothing to be done for teardown."; \ [2025-11-05T22:48:05.644Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17623812747019/TestTargetResult"; [2025-11-05T22:48:05.644Z] [2025-11-05T22:48:05.644Z] TEST SETUP: [2025-11-05T22:48:05.644Z] Nothing to be done for setup. [2025-11-05T22:48:05.644Z] [2025-11-05T22:48:05.644Z] TESTING: [2025-11-05T22:48:10.455Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-11-05T22:48:13.454Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-11-05T22:48:13.454Z] GC before operation: completed in 49.626 ms, heap usage 59.560 MB -> 34.880 MB. [2025-11-05T22:48:26.749Z] ====== log-regression (apache-spark) [default], iteration 0 completed (12772.444 ms) ====== [2025-11-05T22:48:26.749Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-11-05T22:48:26.749Z] GC before operation: completed in 113.758 ms, heap usage 424.376 MB -> 117.149 MB. [2025-11-05T22:48:29.753Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3201.177 ms) ====== [2025-11-05T22:48:29.753Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-11-05T22:48:29.753Z] GC before operation: completed in 93.803 ms, heap usage 817.340 MB -> 118.614 MB. [2025-11-05T22:48:32.047Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2722.263 ms) ====== [2025-11-05T22:48:32.047Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-11-05T22:48:32.381Z] GC before operation: completed in 118.585 ms, heap usage 325.945 MB -> 117.578 MB. [2025-11-05T22:48:34.679Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2472.578 ms) ====== [2025-11-05T22:48:34.679Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-11-05T22:48:34.679Z] GC before operation: completed in 104.172 ms, heap usage 506.588 MB -> 118.719 MB. [2025-11-05T22:48:38.523Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3082.133 ms) ====== [2025-11-05T22:48:38.523Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-11-05T22:48:38.523Z] GC before operation: completed in 98.480 ms, heap usage 233.059 MB -> 117.875 MB. [2025-11-05T22:48:40.834Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2409.038 ms) ====== [2025-11-05T22:48:40.834Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-11-05T22:48:40.834Z] GC before operation: completed in 99.159 ms, heap usage 253.423 MB -> 118.148 MB. [2025-11-05T22:48:42.543Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1937.606 ms) ====== [2025-11-05T22:48:42.543Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-11-05T22:48:42.543Z] GC before operation: completed in 107.959 ms, heap usage 525.967 MB -> 119.384 MB. [2025-11-05T22:48:44.836Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1852.455 ms) ====== [2025-11-05T22:48:44.836Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-11-05T22:48:44.836Z] GC before operation: completed in 104.101 ms, heap usage 676.926 MB -> 120.075 MB. [2025-11-05T22:48:46.521Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1936.391 ms) ====== [2025-11-05T22:48:46.521Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-11-05T22:48:46.521Z] GC before operation: completed in 101.458 ms, heap usage 472.203 MB -> 119.614 MB. [2025-11-05T22:48:48.814Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1880.854 ms) ====== [2025-11-05T22:48:48.814Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-11-05T22:48:48.814Z] GC before operation: completed in 101.119 ms, heap usage 124.984 MB -> 120.593 MB. [2025-11-05T22:48:50.496Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1901.733 ms) ====== [2025-11-05T22:48:50.496Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-11-05T22:48:50.496Z] GC before operation: completed in 105.165 ms, heap usage 280.173 MB -> 119.254 MB. [2025-11-05T22:48:52.261Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1781.391 ms) ====== [2025-11-05T22:48:52.261Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-11-05T22:48:52.595Z] GC before operation: completed in 111.805 ms, heap usage 471.972 MB -> 120.145 MB. [2025-11-05T22:48:54.287Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1876.733 ms) ====== [2025-11-05T22:48:54.288Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-11-05T22:48:54.288Z] GC before operation: completed in 110.400 ms, heap usage 596.782 MB -> 121.287 MB. [2025-11-05T22:48:56.579Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1732.605 ms) ====== [2025-11-05T22:48:56.579Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-11-05T22:48:56.579Z] GC before operation: completed in 107.367 ms, heap usage 348.223 MB -> 119.885 MB. [2025-11-05T22:48:58.259Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1691.674 ms) ====== [2025-11-05T22:48:58.259Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-11-05T22:48:58.259Z] GC before operation: completed in 110.628 ms, heap usage 764.163 MB -> 121.513 MB. [2025-11-05T22:48:59.945Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1838.377 ms) ====== [2025-11-05T22:48:59.945Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-11-05T22:48:59.945Z] GC before operation: completed in 114.384 ms, heap usage 145.825 MB -> 119.846 MB. [2025-11-05T22:49:02.230Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1877.121 ms) ====== [2025-11-05T22:49:02.230Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-11-05T22:49:02.230Z] GC before operation: completed in 116.675 ms, heap usage 414.512 MB -> 120.830 MB. [2025-11-05T22:49:03.924Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1708.833 ms) ====== [2025-11-05T22:49:03.924Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-11-05T22:49:03.924Z] GC before operation: completed in 129.145 ms, heap usage 564.560 MB -> 121.583 MB. [2025-11-05T22:49:05.622Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1755.588 ms) ====== [2025-11-05T22:49:05.623Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-11-05T22:49:05.623Z] GC before operation: completed in 137.684 ms, heap usage 790.446 MB -> 122.182 MB. [2025-11-05T22:49:07.921Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1747.637 ms) ====== [2025-11-05T22:49:07.921Z] ----------------------------------- [2025-11-05T22:49:07.921Z] renaissance-log-regression_0_PASSED [2025-11-05T22:49:07.921Z] ----------------------------------- [2025-11-05T22:49:07.921Z] [2025-11-05T22:49:07.921Z] TEST TEARDOWN: [2025-11-05T22:49:07.921Z] Nothing to be done for teardown. [2025-11-05T22:49:07.921Z] renaissance-log-regression_0 Finish Time: Wed Nov 5 22:49:07 2025 Epoch Time (ms): 1762382947564