[2024-06-27T10:17:48.346Z] Running test renaissance-log-regression_0 ... [2024-06-27T10:17:48.346Z] =============================================== [2024-06-27T10:17:48.674Z] renaissance-log-regression_0 Start Time: Thu Jun 27 10:17:48 2024 Epoch Time (ms): 1719483468386 [2024-06-27T10:17:48.674Z] variation: NoOptions [2024-06-27T10:17:48.991Z] JVM_OPTIONS: [2024-06-27T10:17:48.991Z] { \ [2024-06-27T10:17:48.991Z] echo ""; echo "TEST SETUP:"; \ [2024-06-27T10:17:48.991Z] echo "Nothing to be done for setup."; \ [2024-06-27T10:17:48.991Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17194825947502\\renaissance-log-regression_0"; \ [2024-06-27T10:17:48.991Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17194825947502\\renaissance-log-regression_0"; \ [2024-06-27T10:17:48.991Z] echo ""; echo "TESTING:"; \ [2024-06-27T10:17:48.991Z] "c:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/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_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17194825947502\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2024-06-27T10:17:48.991Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17194825947502\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-06-27T10:17:48.991Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-06-27T10:17:48.991Z] echo "Nothing to be done for teardown."; \ [2024-06-27T10:17:48.991Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17194825947502\\TestTargetResult"; [2024-06-27T10:17:49.319Z] [2024-06-27T10:17:49.319Z] TEST SETUP: [2024-06-27T10:17:49.319Z] Nothing to be done for setup. [2024-06-27T10:17:49.319Z] [2024-06-27T10:17:49.319Z] TESTING: [2024-06-27T10:18:00.029Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-06-27T10:18:00.722Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-06-27T10:18:04.434Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-06-27T10:18:04.434Z] GC before operation: completed in 56.057 ms, heap usage 92.939 MB -> 35.382 MB. [2024-06-27T10:18:10.628Z] ====== log-regression (apache-spark) [default], iteration 0 completed (6336.494 ms) ====== [2024-06-27T10:18:10.628Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-06-27T10:18:10.628Z] GC before operation: completed in 68.615 ms, heap usage 487.910 MB -> 113.998 MB. [2024-06-27T10:18:12.537Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2288.726 ms) ====== [2024-06-27T10:18:12.537Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-06-27T10:18:12.537Z] GC before operation: completed in 57.775 ms, heap usage 440.798 MB -> 114.590 MB. [2024-06-27T10:18:14.595Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1844.614 ms) ====== [2024-06-27T10:18:14.595Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-06-27T10:18:14.595Z] GC before operation: completed in 55.422 ms, heap usage 289.025 MB -> 114.625 MB. [2024-06-27T10:18:16.352Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1485.638 ms) ====== [2024-06-27T10:18:16.352Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-06-27T10:18:16.352Z] GC before operation: completed in 54.689 ms, heap usage 393.200 MB -> 115.139 MB. [2024-06-27T10:18:17.509Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1296.973 ms) ====== [2024-06-27T10:18:17.509Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-06-27T10:18:17.509Z] GC before operation: completed in 67.257 ms, heap usage 405.131 MB -> 115.391 MB. [2024-06-27T10:18:18.736Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1203.431 ms) ====== [2024-06-27T10:18:18.736Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-06-27T10:18:18.736Z] GC before operation: completed in 62.505 ms, heap usage 415.384 MB -> 115.632 MB. [2024-06-27T10:18:19.985Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1172.428 ms) ====== [2024-06-27T10:18:19.985Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-06-27T10:18:19.985Z] GC before operation: completed in 56.817 ms, heap usage 383.678 MB -> 115.753 MB. [2024-06-27T10:18:21.187Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1189.093 ms) ====== [2024-06-27T10:18:21.187Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-06-27T10:18:21.187Z] GC before operation: completed in 57.095 ms, heap usage 381.920 MB -> 115.972 MB. [2024-06-27T10:18:22.388Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1150.904 ms) ====== [2024-06-27T10:18:22.388Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-06-27T10:18:22.388Z] GC before operation: completed in 59.222 ms, heap usage 372.360 MB -> 116.158 MB. [2024-06-27T10:18:23.586Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1095.005 ms) ====== [2024-06-27T10:18:23.586Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-06-27T10:18:23.586Z] GC before operation: completed in 67.668 ms, heap usage 356.297 MB -> 116.299 MB. [2024-06-27T10:18:24.753Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1124.090 ms) ====== [2024-06-27T10:18:24.753Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-06-27T10:18:24.753Z] GC before operation: completed in 66.888 ms, heap usage 333.578 MB -> 116.276 MB. [2024-06-27T10:18:25.956Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1112.335 ms) ====== [2024-06-27T10:18:25.956Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-06-27T10:18:25.956Z] GC before operation: completed in 63.515 ms, heap usage 341.216 MB -> 116.559 MB. [2024-06-27T10:18:27.204Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1162.549 ms) ====== [2024-06-27T10:18:27.204Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-06-27T10:18:27.204Z] GC before operation: completed in 61.952 ms, heap usage 322.124 MB -> 116.749 MB. [2024-06-27T10:18:28.357Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1140.865 ms) ====== [2024-06-27T10:18:28.357Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-06-27T10:18:28.357Z] GC before operation: completed in 63.036 ms, heap usage 313.520 MB -> 116.971 MB. [2024-06-27T10:18:29.535Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1079.099 ms) ====== [2024-06-27T10:18:29.535Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-06-27T10:18:29.535Z] GC before operation: completed in 76.748 ms, heap usage 322.141 MB -> 117.113 MB. [2024-06-27T10:18:30.714Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1130.653 ms) ====== [2024-06-27T10:18:30.714Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-06-27T10:18:30.714Z] GC before operation: completed in 67.250 ms, heap usage 290.576 MB -> 117.308 MB. [2024-06-27T10:18:31.886Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1091.655 ms) ====== [2024-06-27T10:18:31.886Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-06-27T10:18:31.886Z] GC before operation: completed in 69.130 ms, heap usage 551.366 MB -> 118.130 MB. [2024-06-27T10:18:33.076Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1136.704 ms) ====== [2024-06-27T10:18:33.076Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-06-27T10:18:33.076Z] GC before operation: completed in 71.902 ms, heap usage 359.625 MB -> 117.989 MB. [2024-06-27T10:18:34.208Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1048.858 ms) ====== [2024-06-27T10:18:34.208Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-06-27T10:18:34.208Z] GC before operation: completed in 70.081 ms, heap usage 295.367 MB -> 117.907 MB. [2024-06-27T10:18:35.340Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1150.338 ms) ====== [2024-06-27T10:18:35.667Z] ----------------------------------- [2024-06-27T10:18:35.667Z] renaissance-log-regression_0_PASSED [2024-06-27T10:18:35.667Z] ----------------------------------- [2024-06-27T10:18:35.994Z] [2024-06-27T10:18:35.994Z] TEST TEARDOWN: [2024-06-27T10:18:35.994Z] Nothing to be done for teardown. [2024-06-27T10:18:35.994Z] renaissance-log-regression_0 Finish Time: Thu Jun 27 10:18:35 2024 Epoch Time (ms): 1719483515803