[2024-08-02T00:08:48.023Z] Running test renaissance-log-regression_0 ... [2024-08-02T00:08:48.023Z] =============================================== [2024-08-02T00:08:48.023Z] renaissance-log-regression_0 Start Time: Fri Aug 2 00:08:47 2024 Epoch Time (ms): 1722557327902 [2024-08-02T00:08:48.342Z] variation: NoOptions [2024-08-02T00:08:48.342Z] JVM_OPTIONS: [2024-08-02T00:08:48.342Z] { \ [2024-08-02T00:08:48.342Z] echo ""; echo "TEST SETUP:"; \ [2024-08-02T00:08:48.342Z] echo "Nothing to be done for setup."; \ [2024-08-02T00:08:48.342Z] mkdir -p "C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17225562359755\\renaissance-log-regression_0"; \ [2024-08-02T00:08:48.342Z] cd "C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17225562359755\\renaissance-log-regression_0"; \ [2024-08-02T00:08:48.342Z] echo ""; echo "TESTING:"; \ [2024-08-02T00:08:48.342Z] "c:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows_testList_0/jdkbinary/j2sdk-image\\bin\\java" -jar "C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows_testList_0/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17225562359755\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2024-08-02T00:08:48.342Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17225562359755\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-02T00:08:48.342Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-02T00:08:48.342Z] echo "Nothing to be done for teardown."; \ [2024-08-02T00:08:48.342Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17225562359755\\TestTargetResult"; [2024-08-02T00:08:48.342Z] [2024-08-02T00:08:48.342Z] TEST SETUP: [2024-08-02T00:08:48.342Z] Nothing to be done for setup. [2024-08-02T00:08:48.342Z] [2024-08-02T00:08:48.342Z] TESTING: [2024-08-02T00:08:58.958Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-02T00:09:00.549Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-08-02T00:09:04.208Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-02T00:09:04.528Z] GC before operation: completed in 177.060 ms, heap usage 125.507 MB -> 28.379 MB. [2024-08-02T00:09:12.166Z] ====== log-regression (apache-spark) [default], iteration 0 completed (7701.012 ms) ====== [2024-08-02T00:09:12.166Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-02T00:09:12.614Z] GC before operation: completed in 397.086 ms, heap usage 412.365 MB -> 101.366 MB. [2024-08-02T00:09:14.247Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1671.334 ms) ====== [2024-08-02T00:09:14.247Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-02T00:09:14.572Z] GC before operation: completed in 237.668 ms, heap usage 349.746 MB -> 101.972 MB. [2024-08-02T00:09:15.685Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1374.620 ms) ====== [2024-08-02T00:09:15.685Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-02T00:09:16.004Z] GC before operation: completed in 205.275 ms, heap usage 303.188 MB -> 101.858 MB. [2024-08-02T00:09:17.118Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1166.902 ms) ====== [2024-08-02T00:09:17.118Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-02T00:09:17.557Z] GC before operation: completed in 203.174 ms, heap usage 252.100 MB -> 101.551 MB. [2024-08-02T00:09:18.773Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1142.058 ms) ====== [2024-08-02T00:09:18.773Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-02T00:09:18.773Z] GC before operation: completed in 229.139 ms, heap usage 316.607 MB -> 102.490 MB. [2024-08-02T00:09:19.892Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1176.062 ms) ====== [2024-08-02T00:09:19.892Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-02T00:09:20.305Z] GC before operation: completed in 204.481 ms, heap usage 343.095 MB -> 103.003 MB. [2024-08-02T00:09:21.407Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1075.927 ms) ====== [2024-08-02T00:09:21.407Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-02T00:09:21.407Z] GC before operation: completed in 199.908 ms, heap usage 365.397 MB -> 103.534 MB. [2024-08-02T00:09:22.609Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1024.463 ms) ====== [2024-08-02T00:09:22.609Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-02T00:09:22.609Z] GC before operation: completed in 201.115 ms, heap usage 378.338 MB -> 103.874 MB. [2024-08-02T00:09:23.796Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1021.963 ms) ====== [2024-08-02T00:09:23.796Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-02T00:09:23.796Z] GC before operation: completed in 169.728 ms, heap usage 383.235 MB -> 104.231 MB. [2024-08-02T00:09:25.005Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1028.084 ms) ====== [2024-08-02T00:09:25.005Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-02T00:09:25.005Z] GC before operation: completed in 187.391 ms, heap usage 390.520 MB -> 104.518 MB. [2024-08-02T00:09:26.183Z] ====== log-regression (apache-spark) [default], iteration 10 completed (980.204 ms) ====== [2024-08-02T00:09:26.183Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-02T00:09:26.183Z] GC before operation: completed in 182.238 ms, heap usage 390.060 MB -> 104.741 MB. [2024-08-02T00:09:27.335Z] ====== log-regression (apache-spark) [default], iteration 11 completed (986.671 ms) ====== [2024-08-02T00:09:27.335Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-02T00:09:27.335Z] GC before operation: completed in 174.846 ms, heap usage 396.011 MB -> 105.058 MB. [2024-08-02T00:09:28.511Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1001.037 ms) ====== [2024-08-02T00:09:28.511Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-02T00:09:28.511Z] GC before operation: completed in 183.700 ms, heap usage 404.879 MB -> 105.344 MB. [2024-08-02T00:09:29.763Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1006.297 ms) ====== [2024-08-02T00:09:29.763Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-02T00:09:29.763Z] GC before operation: completed in 223.243 ms, heap usage 402.723 MB -> 105.587 MB. [2024-08-02T00:09:30.865Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1076.184 ms) ====== [2024-08-02T00:09:30.865Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-02T00:09:31.180Z] GC before operation: completed in 197.263 ms, heap usage 396.556 MB -> 105.768 MB. [2024-08-02T00:09:31.888Z] ====== log-regression (apache-spark) [default], iteration 15 completed (959.851 ms) ====== [2024-08-02T00:09:31.888Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-02T00:09:32.223Z] GC before operation: completed in 183.342 ms, heap usage 387.516 MB -> 105.940 MB. [2024-08-02T00:09:33.501Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1034.468 ms) ====== [2024-08-02T00:09:33.501Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-02T00:09:33.501Z] GC before operation: completed in 142.677 ms, heap usage 390.387 MB -> 106.288 MB. [2024-08-02T00:09:34.199Z] ====== log-regression (apache-spark) [default], iteration 17 completed (951.210 ms) ====== [2024-08-02T00:09:34.199Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-02T00:09:34.526Z] GC before operation: completed in 223.048 ms, heap usage 388.190 MB -> 106.424 MB. [2024-08-02T00:09:35.690Z] ====== log-regression (apache-spark) [default], iteration 18 completed (961.284 ms) ====== [2024-08-02T00:09:35.690Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-02T00:09:35.690Z] GC before operation: completed in 209.440 ms, heap usage 399.234 MB -> 106.815 MB. [2024-08-02T00:09:36.809Z] ====== log-regression (apache-spark) [default], iteration 19 completed (998.806 ms) ====== [2024-08-02T00:09:37.130Z] ----------------------------------- [2024-08-02T00:09:37.130Z] renaissance-log-regression_0_PASSED [2024-08-02T00:09:37.130Z] ----------------------------------- [2024-08-02T00:09:37.130Z] [2024-08-02T00:09:37.130Z] TEST TEARDOWN: [2024-08-02T00:09:37.130Z] Nothing to be done for teardown. [2024-08-02T00:09:37.440Z] renaissance-log-regression_0 Finish Time: Fri Aug 2 00:09:37 2024 Epoch Time (ms): 1722557377182