[2025-06-19T01:48:59.866Z] Running test renaissance-log-regression_0 ... [2025-06-19T01:48:59.866Z] =============================================== [2025-06-19T01:49:00.187Z] renaissance-log-regression_0 Start Time: Thu Jun 19 01:48:59 2025 Epoch Time (ms): 1750297739858 [2025-06-19T01:49:00.187Z] variation: NoOptions [2025-06-19T01:49:00.187Z] JVM_OPTIONS: [2025-06-19T01:49:00.187Z] { \ [2025-06-19T01:49:00.187Z] echo ""; echo "TEST SETUP:"; \ [2025-06-19T01:49:00.187Z] echo "Nothing to be done for setup."; \ [2025-06-19T01:49:00.187Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17502955176649/renaissance-log-regression_0"; \ [2025-06-19T01:49:00.187Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17502955176649/renaissance-log-regression_0"; \ [2025-06-19T01:49:00.187Z] echo ""; echo "TESTING:"; \ [2025-06-19T01:49:00.187Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17502955176649/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-06-19T01:49:00.187Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17502955176649/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-19T01:49:00.187Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-19T01:49:00.187Z] echo "Nothing to be done for teardown."; \ [2025-06-19T01:49:00.187Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17502955176649/TestTargetResult"; [2025-06-19T01:49:00.187Z] [2025-06-19T01:49:00.187Z] TEST SETUP: [2025-06-19T01:49:00.187Z] Nothing to be done for setup. [2025-06-19T01:49:00.187Z] [2025-06-19T01:49:00.187Z] TESTING: [2025-06-19T01:49:10.910Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-06-19T01:49:13.848Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-06-19T01:49:13.848Z] GC before operation: completed in 47.643 ms, heap usage 60.718 MB -> 34.697 MB. [2025-06-19T01:49:29.674Z] ====== log-regression (apache-spark) [default], iteration 0 completed (15102.716 ms) ====== [2025-06-19T01:49:29.674Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-06-19T01:49:29.674Z] GC before operation: completed in 100.769 ms, heap usage 296.338 MB -> 116.459 MB. [2025-06-19T01:49:33.417Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3805.393 ms) ====== [2025-06-19T01:49:33.417Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-06-19T01:49:33.418Z] GC before operation: completed in 84.240 ms, heap usage 264.353 MB -> 116.799 MB. [2025-06-19T01:49:36.333Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3188.387 ms) ====== [2025-06-19T01:49:36.333Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-06-19T01:49:36.333Z] GC before operation: completed in 86.531 ms, heap usage 277.914 MB -> 117.208 MB. [2025-06-19T01:49:39.261Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2803.977 ms) ====== [2025-06-19T01:49:39.261Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-06-19T01:49:39.261Z] GC before operation: completed in 91.695 ms, heap usage 345.209 MB -> 117.740 MB. [2025-06-19T01:49:42.215Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2633.050 ms) ====== [2025-06-19T01:49:42.215Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-06-19T01:49:42.215Z] GC before operation: completed in 94.607 ms, heap usage 237.153 MB -> 117.564 MB. [2025-06-19T01:49:44.433Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2688.930 ms) ====== [2025-06-19T01:49:44.433Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-06-19T01:49:44.755Z] GC before operation: completed in 98.199 ms, heap usage 306.834 MB -> 118.055 MB. [2025-06-19T01:49:47.666Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2555.846 ms) ====== [2025-06-19T01:49:47.666Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-06-19T01:49:47.666Z] GC before operation: completed in 96.554 ms, heap usage 318.021 MB -> 118.331 MB. [2025-06-19T01:49:49.886Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2359.873 ms) ====== [2025-06-19T01:49:49.886Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-06-19T01:49:49.886Z] GC before operation: completed in 103.340 ms, heap usage 412.994 MB -> 118.734 MB. [2025-06-19T01:49:52.104Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2169.310 ms) ====== [2025-06-19T01:49:52.104Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-06-19T01:49:52.104Z] GC before operation: completed in 101.155 ms, heap usage 388.122 MB -> 118.959 MB. [2025-06-19T01:49:54.323Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2167.473 ms) ====== [2025-06-19T01:49:54.323Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-06-19T01:49:54.323Z] GC before operation: completed in 99.949 ms, heap usage 376.747 MB -> 119.020 MB. [2025-06-19T01:49:56.575Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2108.276 ms) ====== [2025-06-19T01:49:56.575Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-06-19T01:49:56.575Z] GC before operation: completed in 98.750 ms, heap usage 229.714 MB -> 118.715 MB. [2025-06-19T01:49:58.797Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2145.806 ms) ====== [2025-06-19T01:49:58.797Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-06-19T01:49:58.797Z] GC before operation: completed in 118.402 ms, heap usage 237.622 MB -> 118.913 MB. [2025-06-19T01:50:01.015Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2041.158 ms) ====== [2025-06-19T01:50:01.015Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-06-19T01:50:01.015Z] GC before operation: completed in 103.333 ms, heap usage 266.413 MB -> 119.294 MB. [2025-06-19T01:50:03.232Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2244.310 ms) ====== [2025-06-19T01:50:03.232Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-06-19T01:50:03.232Z] GC before operation: completed in 105.332 ms, heap usage 362.569 MB -> 119.749 MB. [2025-06-19T01:50:05.447Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2129.774 ms) ====== [2025-06-19T01:50:05.447Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-06-19T01:50:05.447Z] GC before operation: completed in 107.575 ms, heap usage 273.163 MB -> 119.637 MB. [2025-06-19T01:50:07.666Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2162.030 ms) ====== [2025-06-19T01:50:07.666Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-06-19T01:50:07.666Z] GC before operation: completed in 107.906 ms, heap usage 300.332 MB -> 120.079 MB. [2025-06-19T01:50:09.883Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1995.127 ms) ====== [2025-06-19T01:50:09.883Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-06-19T01:50:09.883Z] GC before operation: completed in 104.360 ms, heap usage 313.409 MB -> 120.245 MB. [2025-06-19T01:50:12.100Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1990.413 ms) ====== [2025-06-19T01:50:12.100Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-06-19T01:50:12.100Z] GC before operation: completed in 108.039 ms, heap usage 315.915 MB -> 120.335 MB. [2025-06-19T01:50:14.315Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1964.357 ms) ====== [2025-06-19T01:50:14.315Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-06-19T01:50:14.315Z] GC before operation: completed in 103.159 ms, heap usage 319.997 MB -> 120.549 MB. [2025-06-19T01:50:15.943Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1995.788 ms) ====== [2025-06-19T01:50:16.268Z] ----------------------------------- [2025-06-19T01:50:16.268Z] renaissance-log-regression_0_PASSED [2025-06-19T01:50:16.268Z] ----------------------------------- [2025-06-19T01:50:16.268Z] [2025-06-19T01:50:16.268Z] TEST TEARDOWN: [2025-06-19T01:50:16.268Z] Nothing to be done for teardown. [2025-06-19T01:50:16.268Z] renaissance-log-regression_0 Finish Time: Thu Jun 19 01:50:16 2025 Epoch Time (ms): 1750297816146