[2025-01-10T21:37:44.487Z] Running test renaissance-log-regression_0 ... [2025-01-10T21:37:44.487Z] =============================================== [2025-01-10T21:37:44.487Z] renaissance-log-regression_0 Start Time: Fri Jan 10 15:37:44 2025 Epoch Time (ms): 1736545064330 [2025-01-10T21:37:44.487Z] variation: NoOptions [2025-01-10T21:37:44.487Z] JVM_OPTIONS: [2025-01-10T21:37:44.487Z] { \ [2025-01-10T21:37:44.487Z] echo ""; echo "TEST SETUP:"; \ [2025-01-10T21:37:44.487Z] echo "Nothing to be done for setup."; \ [2025-01-10T21:37:44.487Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17365445999324/renaissance-log-regression_0"; \ [2025-01-10T21:37:44.487Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17365445999324/renaissance-log-regression_0"; \ [2025-01-10T21:37:44.487Z] echo ""; echo "TESTING:"; \ [2025-01-10T21:37:44.487Z] "/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_17365445999324/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-01-10T21:37:44.487Z] 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_17365445999324/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-01-10T21:37:44.487Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-01-10T21:37:44.487Z] echo "Nothing to be done for teardown."; \ [2025-01-10T21:37:44.487Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17365445999324/TestTargetResult"; [2025-01-10T21:37:44.487Z] [2025-01-10T21:37:44.487Z] TEST SETUP: [2025-01-10T21:37:44.487Z] Nothing to be done for setup. [2025-01-10T21:37:44.487Z] [2025-01-10T21:37:44.487Z] TESTING: [2025-01-10T21:37:47.542Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-01-10T21:37:48.947Z] NOTE: 'log-regression' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-01-10T21:37:51.155Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-01-10T21:37:51.155Z] GC before operation: completed in 75.294 ms, heap usage 45.938 MB -> 35.726 MB. [2025-01-10T21:37:58.771Z] ====== log-regression (apache-spark) [default], iteration 0 completed (6655.932 ms) ====== [2025-01-10T21:37:58.771Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-01-10T21:37:58.771Z] GC before operation: completed in 103.290 ms, heap usage 380.071 MB -> 119.218 MB. [2025-01-10T21:38:00.209Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1998.328 ms) ====== [2025-01-10T21:38:00.209Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-01-10T21:38:00.209Z] GC before operation: completed in 62.868 ms, heap usage 432.323 MB -> 120.087 MB. [2025-01-10T21:38:01.624Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1772.423 ms) ====== [2025-01-10T21:38:01.624Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-01-10T21:38:01.624Z] GC before operation: completed in 61.277 ms, heap usage 390.787 MB -> 120.394 MB. [2025-01-10T21:38:03.831Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1685.307 ms) ====== [2025-01-10T21:38:03.831Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-01-10T21:38:03.831Z] GC before operation: completed in 80.972 ms, heap usage 371.225 MB -> 120.728 MB. [2025-01-10T21:38:05.242Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1710.786 ms) ====== [2025-01-10T21:38:05.242Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-01-10T21:38:05.242Z] GC before operation: completed in 63.896 ms, heap usage 285.244 MB -> 120.820 MB. [2025-01-10T21:38:07.439Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1658.263 ms) ====== [2025-01-10T21:38:07.439Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-01-10T21:38:07.439Z] GC before operation: completed in 69.690 ms, heap usage 261.372 MB -> 121.119 MB. [2025-01-10T21:38:08.854Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1635.190 ms) ====== [2025-01-10T21:38:08.854Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-01-10T21:38:08.854Z] GC before operation: completed in 101.930 ms, heap usage 500.985 MB -> 122.087 MB. [2025-01-10T21:38:10.262Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1559.512 ms) ====== [2025-01-10T21:38:10.263Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-01-10T21:38:10.263Z] GC before operation: completed in 80.754 ms, heap usage 405.261 MB -> 122.334 MB. [2025-01-10T21:38:12.722Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1649.215 ms) ====== [2025-01-10T21:38:12.722Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-01-10T21:38:12.722Z] GC before operation: completed in 63.081 ms, heap usage 452.556 MB -> 122.796 MB. [2025-01-10T21:38:13.863Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1543.851 ms) ====== [2025-01-10T21:38:13.863Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-01-10T21:38:13.863Z] GC before operation: completed in 69.994 ms, heap usage 335.988 MB -> 122.818 MB. [2025-01-10T21:38:15.293Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1477.835 ms) ====== [2025-01-10T21:38:15.293Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-01-10T21:38:15.293Z] GC before operation: completed in 73.314 ms, heap usage 331.133 MB -> 123.003 MB. [2025-01-10T21:38:16.706Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1610.844 ms) ====== [2025-01-10T21:38:16.706Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-01-10T21:38:17.389Z] GC before operation: completed in 108.166 ms, heap usage 794.894 MB -> 124.830 MB. [2025-01-10T21:38:18.801Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1511.602 ms) ====== [2025-01-10T21:38:18.801Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-01-10T21:38:18.801Z] GC before operation: completed in 67.197 ms, heap usage 307.659 MB -> 123.795 MB. [2025-01-10T21:38:20.232Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1602.151 ms) ====== [2025-01-10T21:38:20.232Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-01-10T21:38:20.232Z] GC before operation: completed in 65.810 ms, heap usage 555.101 MB -> 124.904 MB. [2025-01-10T21:38:21.645Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1498.906 ms) ====== [2025-01-10T21:38:21.645Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-01-10T21:38:21.645Z] GC before operation: completed in 70.855 ms, heap usage 400.214 MB -> 124.700 MB. [2025-01-10T21:38:23.851Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1607.911 ms) ====== [2025-01-10T21:38:23.851Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-01-10T21:38:23.851Z] GC before operation: completed in 98.921 ms, heap usage 615.071 MB -> 125.791 MB. [2025-01-10T21:38:25.269Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1582.819 ms) ====== [2025-01-10T21:38:25.269Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-01-10T21:38:25.269Z] GC before operation: completed in 77.766 ms, heap usage 388.121 MB -> 125.325 MB. [2025-01-10T21:38:26.682Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1480.312 ms) ====== [2025-01-10T21:38:26.682Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-01-10T21:38:26.682Z] GC before operation: completed in 87.106 ms, heap usage 334.007 MB -> 125.565 MB. [2025-01-10T21:38:28.106Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1511.370 ms) ====== [2025-01-10T21:38:28.106Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-01-10T21:38:28.106Z] GC before operation: completed in 92.187 ms, heap usage 509.780 MB -> 126.514 MB. [2025-01-10T21:38:30.421Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1545.674 ms) ====== [2025-01-10T21:38:30.421Z] ----------------------------------- [2025-01-10T21:38:30.421Z] renaissance-log-regression_0_PASSED [2025-01-10T21:38:30.421Z] ----------------------------------- [2025-01-10T21:38:30.421Z] [2025-01-10T21:38:30.421Z] TEST TEARDOWN: [2025-01-10T21:38:30.421Z] Nothing to be done for teardown. [2025-01-10T21:38:30.421Z] renaissance-log-regression_0 Finish Time: Fri Jan 10 15:38:30 2025 Epoch Time (ms): 1736545110122