[2024-09-06T02:06:02.756Z] Running test renaissance-log-regression_0 ... [2024-09-06T02:06:02.756Z] =============================================== [2024-09-06T02:06:03.067Z] renaissance-log-regression_0 Start Time: Fri Sep 6 02:06:02 2024 Epoch Time (ms): 1725588362936 [2024-09-06T02:06:03.381Z] variation: NoOptions [2024-09-06T02:06:03.694Z] JVM_OPTIONS: [2024-09-06T02:06:03.694Z] { \ [2024-09-06T02:06:03.694Z] echo ""; echo "TEST SETUP:"; \ [2024-09-06T02:06:03.694Z] echo "Nothing to be done for setup."; \ [2024-09-06T02:06:03.694Z] mkdir -p "C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1725587276451\\renaissance-log-regression_0"; \ [2024-09-06T02:06:03.694Z] cd "C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1725587276451\\renaissance-log-regression_0"; \ [2024-09-06T02:06:03.694Z] echo ""; echo "TESTING:"; \ [2024-09-06T02:06:03.694Z] "c:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/jdkbinary/j2sdk-image\\bin\\java" -jar "C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1725587276451\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2024-09-06T02:06:03.694Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1725587276451\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-06T02:06:03.694Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-06T02:06:03.694Z] echo "Nothing to be done for teardown."; \ [2024-09-06T02:06:03.694Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1725587276451\\TestTargetResult"; [2024-09-06T02:06:03.694Z] [2024-09-06T02:06:03.694Z] TEST SETUP: [2024-09-06T02:06:03.694Z] Nothing to be done for setup. [2024-09-06T02:06:03.694Z] [2024-09-06T02:06:03.694Z] TESTING: [2024-09-06T02:06:14.232Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-06T02:06:15.317Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-09-06T02:06:19.002Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-09-06T02:06:19.002Z] GC before operation: completed in 154.433 ms, heap usage 123.443 MB -> 28.378 MB. [2024-09-06T02:06:27.909Z] ====== log-regression (apache-spark) [default], iteration 0 completed (7597.792 ms) ====== [2024-09-06T02:06:27.909Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-09-06T02:06:27.909Z] GC before operation: completed in 348.075 ms, heap usage 329.661 MB -> 100.518 MB. [2024-09-06T02:06:28.610Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1671.869 ms) ====== [2024-09-06T02:06:28.610Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-09-06T02:06:28.610Z] GC before operation: completed in 223.168 ms, heap usage 351.957 MB -> 101.866 MB. [2024-09-06T02:06:30.289Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1378.770 ms) ====== [2024-09-06T02:06:30.289Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-09-06T02:06:30.289Z] GC before operation: completed in 169.378 ms, heap usage 324.769 MB -> 101.815 MB. [2024-09-06T02:06:31.386Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1148.983 ms) ====== [2024-09-06T02:06:31.386Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-09-06T02:06:31.831Z] GC before operation: completed in 190.422 ms, heap usage 111.162 MB -> 102.023 MB. [2024-09-06T02:06:32.951Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1132.092 ms) ====== [2024-09-06T02:06:32.951Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-09-06T02:06:32.951Z] GC before operation: completed in 201.442 ms, heap usage 221.519 MB -> 101.677 MB. [2024-09-06T02:06:34.038Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1088.958 ms) ====== [2024-09-06T02:06:34.038Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-09-06T02:06:34.546Z] GC before operation: completed in 160.669 ms, heap usage 112.050 MB -> 102.560 MB. [2024-09-06T02:06:35.249Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1148.943 ms) ====== [2024-09-06T02:06:35.249Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-09-06T02:06:35.659Z] GC before operation: completed in 139.947 ms, heap usage 201.403 MB -> 102.049 MB. [2024-09-06T02:06:36.796Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1042.405 ms) ====== [2024-09-06T02:06:36.797Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-09-06T02:06:36.797Z] GC before operation: completed in 170.179 ms, heap usage 186.242 MB -> 102.236 MB. [2024-09-06T02:06:38.003Z] ====== log-regression (apache-spark) [default], iteration 8 completed (996.943 ms) ====== [2024-09-06T02:06:38.003Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-09-06T02:06:38.003Z] GC before operation: completed in 150.055 ms, heap usage 703.067 MB -> 105.630 MB. [2024-09-06T02:06:39.144Z] ====== log-regression (apache-spark) [default], iteration 9 completed (992.826 ms) ====== [2024-09-06T02:06:39.144Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-09-06T02:06:39.144Z] GC before operation: completed in 156.241 ms, heap usage 704.188 MB -> 105.879 MB. [2024-09-06T02:06:40.269Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1016.070 ms) ====== [2024-09-06T02:06:40.269Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-09-06T02:06:40.269Z] GC before operation: completed in 177.914 ms, heap usage 693.662 MB -> 106.152 MB. [2024-09-06T02:06:41.454Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1024.963 ms) ====== [2024-09-06T02:06:41.454Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-09-06T02:06:41.454Z] GC before operation: completed in 185.914 ms, heap usage 709.773 MB -> 106.374 MB. [2024-09-06T02:06:42.562Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1102.059 ms) ====== [2024-09-06T02:06:42.562Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-09-06T02:06:42.563Z] GC before operation: completed in 172.664 ms, heap usage 697.086 MB -> 106.621 MB. [2024-09-06T02:06:43.798Z] ====== log-regression (apache-spark) [default], iteration 13 completed (930.174 ms) ====== [2024-09-06T02:06:43.798Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-09-06T02:06:43.798Z] GC before operation: completed in 211.459 ms, heap usage 704.955 MB -> 106.865 MB. [2024-09-06T02:06:44.912Z] ====== log-regression (apache-spark) [default], iteration 14 completed (939.518 ms) ====== [2024-09-06T02:06:44.912Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-09-06T02:06:44.912Z] GC before operation: completed in 152.779 ms, heap usage 703.509 MB -> 107.110 MB. [2024-09-06T02:06:46.012Z] ====== log-regression (apache-spark) [default], iteration 15 completed (917.947 ms) ====== [2024-09-06T02:06:46.012Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-09-06T02:06:46.012Z] GC before operation: completed in 164.541 ms, heap usage 706.553 MB -> 107.381 MB. [2024-09-06T02:06:47.236Z] ====== log-regression (apache-spark) [default], iteration 16 completed (996.264 ms) ====== [2024-09-06T02:06:47.236Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-09-06T02:06:47.236Z] GC before operation: completed in 212.861 ms, heap usage 704.259 MB -> 107.750 MB. [2024-09-06T02:06:48.377Z] ====== log-regression (apache-spark) [default], iteration 17 completed (924.838 ms) ====== [2024-09-06T02:06:48.377Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-09-06T02:06:48.377Z] GC before operation: completed in 235.612 ms, heap usage 703.057 MB -> 107.917 MB. [2024-09-06T02:06:49.503Z] ====== log-regression (apache-spark) [default], iteration 18 completed (925.462 ms) ====== [2024-09-06T02:06:49.503Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-09-06T02:06:49.503Z] GC before operation: completed in 138.169 ms, heap usage 707.619 MB -> 108.158 MB. [2024-09-06T02:06:50.640Z] ====== log-regression (apache-spark) [default], iteration 19 completed (948.850 ms) ====== [2024-09-06T02:06:50.640Z] ----------------------------------- [2024-09-06T02:06:50.640Z] renaissance-log-regression_0_PASSED [2024-09-06T02:06:50.640Z] ----------------------------------- [2024-09-06T02:06:50.943Z] [2024-09-06T02:06:50.943Z] TEST TEARDOWN: [2024-09-06T02:06:50.943Z] Nothing to be done for teardown. [2024-09-06T02:06:50.943Z] renaissance-log-regression_0 Finish Time: Fri Sep 6 02:06:50 2024 Epoch Time (ms): 1725588410753