[2025-02-13T23:23:00.243Z] Running test renaissance-log-regression_0 ... [2025-02-13T23:23:00.243Z] =============================================== [2025-02-13T23:23:00.243Z] renaissance-log-regression_0 Start Time: Thu Feb 13 23:23:00 2025 Epoch Time (ms): 1739488980005 [2025-02-13T23:23:00.243Z] variation: NoOptions [2025-02-13T23:23:00.243Z] JVM_OPTIONS: [2025-02-13T23:23:00.243Z] { \ [2025-02-13T23:23:00.243Z] echo ""; echo "TEST SETUP:"; \ [2025-02-13T23:23:00.243Z] echo "Nothing to be done for setup."; \ [2025-02-13T23:23:00.243Z] mkdir -p "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17394846239083/renaissance-log-regression_0"; \ [2025-02-13T23:23:00.243Z] cd "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17394846239083/renaissance-log-regression_0"; \ [2025-02-13T23:23:00.243Z] echo ""; echo "TESTING:"; \ [2025-02-13T23:23:00.243Z] "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/jdkbinary/j2sdk-image/bin/java" -jar "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17394846239083/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-02-13T23:23:00.243Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17394846239083/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-13T23:23:00.243Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-13T23:23:00.243Z] echo "Nothing to be done for teardown."; \ [2025-02-13T23:23:00.243Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17394846239083/TestTargetResult"; [2025-02-13T23:23:00.243Z] [2025-02-13T23:23:00.243Z] TEST SETUP: [2025-02-13T23:23:00.243Z] Nothing to be done for setup. [2025-02-13T23:23:00.243Z] [2025-02-13T23:23:00.243Z] TESTING: [2025-02-13T23:23:19.604Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-13T23:23:39.064Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-02-13T23:24:15.715Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-02-13T23:24:15.715Z] GC before operation: completed in 1206.198 ms, heap usage 74.680 MB -> 27.490 MB. [2025-02-13T23:25:36.231Z] ====== log-regression (apache-spark) [default], iteration 0 completed (75181.934 ms) ====== [2025-02-13T23:25:36.231Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-02-13T23:25:36.231Z] GC before operation: completed in 3694.179 ms, heap usage 340.586 MB -> 101.807 MB. [2025-02-13T23:25:53.350Z] ====== log-regression (apache-spark) [default], iteration 1 completed (20476.370 ms) ====== [2025-02-13T23:25:53.350Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-02-13T23:25:56.076Z] GC before operation: completed in 1993.987 ms, heap usage 360.162 MB -> 102.553 MB. [2025-02-13T23:26:12.949Z] ====== log-regression (apache-spark) [default], iteration 2 completed (16655.966 ms) ====== [2025-02-13T23:26:12.949Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-02-13T23:26:14.635Z] GC before operation: completed in 1932.385 ms, heap usage 330.107 MB -> 102.806 MB. [2025-02-13T23:26:28.909Z] ====== log-regression (apache-spark) [default], iteration 3 completed (13768.673 ms) ====== [2025-02-13T23:26:28.909Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-02-13T23:26:29.722Z] GC before operation: completed in 1726.256 ms, heap usage 211.826 MB -> 102.143 MB. [2025-02-13T23:26:41.944Z] ====== log-regression (apache-spark) [default], iteration 4 completed (11285.697 ms) ====== [2025-02-13T23:26:41.944Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-02-13T23:26:42.765Z] GC before operation: completed in 2026.148 ms, heap usage 223.916 MB -> 102.536 MB. [2025-02-13T23:26:52.030Z] ====== log-regression (apache-spark) [default], iteration 5 completed (9049.844 ms) ====== [2025-02-13T23:26:52.030Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-02-13T23:26:53.746Z] GC before operation: completed in 1517.262 ms, heap usage 230.597 MB -> 102.848 MB. [2025-02-13T23:27:05.158Z] ====== log-regression (apache-spark) [default], iteration 6 completed (10967.679 ms) ====== [2025-02-13T23:27:05.158Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-02-13T23:27:05.981Z] GC before operation: completed in 1324.670 ms, heap usage 234.826 MB -> 103.132 MB. [2025-02-13T23:27:16.473Z] ====== log-regression (apache-spark) [default], iteration 7 completed (10529.259 ms) ====== [2025-02-13T23:27:16.473Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-02-13T23:27:18.185Z] GC before operation: completed in 1408.656 ms, heap usage 233.573 MB -> 103.414 MB. [2025-02-13T23:27:30.629Z] ====== log-regression (apache-spark) [default], iteration 8 completed (11309.232 ms) ====== [2025-02-13T23:27:30.629Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-02-13T23:27:31.522Z] GC before operation: completed in 2309.845 ms, heap usage 233.446 MB -> 103.690 MB. [2025-02-13T23:27:43.922Z] ====== log-regression (apache-spark) [default], iteration 9 completed (11198.685 ms) ====== [2025-02-13T23:27:43.922Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-02-13T23:27:43.922Z] GC before operation: completed in 1315.729 ms, heap usage 232.308 MB -> 103.973 MB. [2025-02-13T23:27:56.456Z] ====== log-regression (apache-spark) [default], iteration 10 completed (11166.136 ms) ====== [2025-02-13T23:27:56.456Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-02-13T23:27:56.456Z] GC before operation: completed in 1407.634 ms, heap usage 225.683 MB -> 104.159 MB. [2025-02-13T23:28:08.928Z] ====== log-regression (apache-spark) [default], iteration 11 completed (10871.146 ms) ====== [2025-02-13T23:28:08.928Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-02-13T23:28:08.928Z] GC before operation: completed in 1339.200 ms, heap usage 233.300 MB -> 105.705 MB. [2025-02-13T23:28:20.009Z] ====== log-regression (apache-spark) [default], iteration 12 completed (10591.773 ms) ====== [2025-02-13T23:28:20.009Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-02-13T23:28:20.814Z] GC before operation: completed in 1364.296 ms, heap usage 232.063 MB -> 104.737 MB. [2025-02-13T23:28:31.281Z] ====== log-regression (apache-spark) [default], iteration 13 completed (9298.626 ms) ====== [2025-02-13T23:28:31.281Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-02-13T23:28:32.129Z] GC before operation: completed in 1862.921 ms, heap usage 230.517 MB -> 104.988 MB. [2025-02-13T23:28:40.900Z] ====== log-regression (apache-spark) [default], iteration 14 completed (9270.410 ms) ====== [2025-02-13T23:28:40.900Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-02-13T23:28:42.600Z] GC before operation: completed in 1222.004 ms, heap usage 233.200 MB -> 105.308 MB. [2025-02-13T23:28:53.230Z] ====== log-regression (apache-spark) [default], iteration 15 completed (9832.096 ms) ====== [2025-02-13T23:28:53.230Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-02-13T23:28:54.032Z] GC before operation: completed in 1504.888 ms, heap usage 232.059 MB -> 105.448 MB. [2025-02-13T23:29:02.923Z] ====== log-regression (apache-spark) [default], iteration 16 completed (9333.514 ms) ====== [2025-02-13T23:29:02.923Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-02-13T23:29:04.643Z] GC before operation: completed in 1575.069 ms, heap usage 232.005 MB -> 105.747 MB. [2025-02-13T23:29:13.646Z] ====== log-regression (apache-spark) [default], iteration 17 completed (9069.720 ms) ====== [2025-02-13T23:29:13.646Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-02-13T23:29:15.369Z] GC before operation: completed in 1138.416 ms, heap usage 231.838 MB -> 106.134 MB. [2025-02-13T23:29:24.790Z] ====== log-regression (apache-spark) [default], iteration 18 completed (8820.938 ms) ====== [2025-02-13T23:29:24.790Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-02-13T23:29:24.790Z] GC before operation: completed in 1105.792 ms, heap usage 227.886 MB -> 106.183 MB. [2025-02-13T23:29:35.360Z] ====== log-regression (apache-spark) [default], iteration 19 completed (9302.765 ms) ====== [2025-02-13T23:29:38.959Z] ----------------------------------- [2025-02-13T23:29:38.959Z] renaissance-log-regression_0_PASSED [2025-02-13T23:29:38.959Z] ----------------------------------- [2025-02-13T23:29:38.959Z] [2025-02-13T23:29:38.959Z] TEST TEARDOWN: [2025-02-13T23:29:38.959Z] Nothing to be done for teardown. [2025-02-13T23:29:38.959Z] renaissance-log-regression_0 Finish Time: Thu Feb 13 23:29:38 2025 Epoch Time (ms): 1739489378828