[2024-11-28T03:20:24.416Z] Running test renaissance-log-regression_0 ... [2024-11-28T03:20:24.416Z] =============================================== [2024-11-28T03:20:24.416Z] renaissance-log-regression_0 Start Time: Thu Nov 28 03:20:23 2024 Epoch Time (ms): 1732764023753 [2024-11-28T03:20:24.416Z] variation: NoOptions [2024-11-28T03:20:24.416Z] JVM_OPTIONS: [2024-11-28T03:20:24.416Z] { \ [2024-11-28T03:20:24.416Z] echo ""; echo "TEST SETUP:"; \ [2024-11-28T03:20:24.416Z] echo "Nothing to be done for setup."; \ [2024-11-28T03:20:24.416Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17327592569882/renaissance-log-regression_0"; \ [2024-11-28T03:20:24.416Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17327592569882/renaissance-log-regression_0"; \ [2024-11-28T03:20:24.416Z] echo ""; echo "TESTING:"; \ [2024-11-28T03:20:24.416Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-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_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17327592569882/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-11-28T03:20:24.416Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17327592569882/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-28T03:20:24.416Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-28T03:20:24.416Z] echo "Nothing to be done for teardown."; \ [2024-11-28T03:20:24.416Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17327592569882/TestTargetResult"; [2024-11-28T03:20:24.416Z] [2024-11-28T03:20:24.416Z] TEST SETUP: [2024-11-28T03:20:24.416Z] Nothing to be done for setup. [2024-11-28T03:20:24.416Z] [2024-11-28T03:20:24.416Z] TESTING: [2024-11-28T03:20:44.260Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-28T03:20:59.386Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-11-28T03:21:30.674Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-11-28T03:21:30.674Z] GC before operation: completed in 348.998 ms, heap usage 53.692 MB -> 35.066 MB. [2024-11-28T03:22:52.281Z] ====== log-regression (apache-spark) [default], iteration 0 completed (71501.993 ms) ====== [2024-11-28T03:22:52.281Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-11-28T03:22:52.281Z] GC before operation: completed in 948.022 ms, heap usage 465.596 MB -> 113.543 MB. [2024-11-28T03:23:04.775Z] ====== log-regression (apache-spark) [default], iteration 1 completed (21257.520 ms) ====== [2024-11-28T03:23:04.775Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-11-28T03:23:04.775Z] GC before operation: completed in 514.264 ms, heap usage 382.707 MB -> 113.940 MB. [2024-11-28T03:23:24.467Z] ====== log-regression (apache-spark) [default], iteration 2 completed (18863.930 ms) ====== [2024-11-28T03:23:24.467Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-11-28T03:23:24.467Z] GC before operation: completed in 720.116 ms, heap usage 243.054 MB -> 114.054 MB. [2024-11-28T03:23:41.853Z] ====== log-regression (apache-spark) [default], iteration 3 completed (17087.223 ms) ====== [2024-11-28T03:23:41.853Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-11-28T03:23:41.853Z] GC before operation: completed in 501.389 ms, heap usage 379.010 MB -> 114.576 MB. [2024-11-28T03:23:56.769Z] ====== log-regression (apache-spark) [default], iteration 4 completed (15082.525 ms) ====== [2024-11-28T03:23:56.769Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-11-28T03:23:57.596Z] GC before operation: completed in 633.844 ms, heap usage 351.053 MB -> 114.706 MB. [2024-11-28T03:24:15.790Z] ====== log-regression (apache-spark) [default], iteration 5 completed (17248.478 ms) ====== [2024-11-28T03:24:15.790Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-11-28T03:24:15.790Z] GC before operation: completed in 651.347 ms, heap usage 302.109 MB -> 114.719 MB. [2024-11-28T03:24:30.555Z] ====== log-regression (apache-spark) [default], iteration 6 completed (15147.837 ms) ====== [2024-11-28T03:24:30.555Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-11-28T03:24:31.442Z] GC before operation: completed in 642.488 ms, heap usage 315.425 MB -> 114.993 MB. [2024-11-28T03:24:44.152Z] ====== log-regression (apache-spark) [default], iteration 7 completed (11652.557 ms) ====== [2024-11-28T03:24:44.152Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-11-28T03:24:44.152Z] GC before operation: completed in 569.739 ms, heap usage 298.489 MB -> 115.211 MB. [2024-11-28T03:24:59.005Z] ====== log-regression (apache-spark) [default], iteration 8 completed (14112.164 ms) ====== [2024-11-28T03:24:59.005Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-11-28T03:24:59.005Z] GC before operation: completed in 647.570 ms, heap usage 288.247 MB -> 115.377 MB. [2024-11-28T03:25:11.541Z] ====== log-regression (apache-spark) [default], iteration 9 completed (12853.522 ms) ====== [2024-11-28T03:25:11.541Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-11-28T03:25:11.541Z] GC before operation: completed in 655.714 ms, heap usage 466.370 MB -> 115.978 MB. [2024-11-28T03:25:24.342Z] ====== log-regression (apache-spark) [default], iteration 10 completed (12096.545 ms) ====== [2024-11-28T03:25:24.342Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-11-28T03:25:24.342Z] GC before operation: completed in 416.186 ms, heap usage 318.483 MB -> 115.805 MB. [2024-11-28T03:25:36.988Z] ====== log-regression (apache-spark) [default], iteration 11 completed (12741.888 ms) ====== [2024-11-28T03:25:36.988Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-11-28T03:25:37.870Z] GC before operation: completed in 661.931 ms, heap usage 314.386 MB -> 116.249 MB. [2024-11-28T03:25:50.376Z] ====== log-regression (apache-spark) [default], iteration 12 completed (12529.599 ms) ====== [2024-11-28T03:25:50.376Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-11-28T03:25:51.229Z] GC before operation: completed in 878.687 ms, heap usage 358.874 MB -> 116.356 MB. [2024-11-28T03:26:03.984Z] ====== log-regression (apache-spark) [default], iteration 13 completed (11910.961 ms) ====== [2024-11-28T03:26:03.984Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-11-28T03:26:03.984Z] GC before operation: completed in 651.952 ms, heap usage 371.435 MB -> 116.575 MB. [2024-11-28T03:26:16.737Z] ====== log-regression (apache-spark) [default], iteration 14 completed (11371.572 ms) ====== [2024-11-28T03:26:16.737Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-11-28T03:26:16.737Z] GC before operation: completed in 873.602 ms, heap usage 306.714 MB -> 116.734 MB. [2024-11-28T03:26:27.774Z] ====== log-regression (apache-spark) [default], iteration 15 completed (11133.520 ms) ====== [2024-11-28T03:26:27.774Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-11-28T03:26:27.774Z] GC before operation: completed in 763.458 ms, heap usage 397.012 MB -> 117.059 MB. [2024-11-28T03:26:38.588Z] ====== log-regression (apache-spark) [default], iteration 16 completed (10854.382 ms) ====== [2024-11-28T03:26:38.588Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-11-28T03:26:39.560Z] GC before operation: completed in 706.131 ms, heap usage 370.721 MB -> 117.239 MB. [2024-11-28T03:26:51.996Z] ====== log-regression (apache-spark) [default], iteration 17 completed (10905.215 ms) ====== [2024-11-28T03:26:51.996Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-11-28T03:26:51.996Z] GC before operation: completed in 697.431 ms, heap usage 332.355 MB -> 117.331 MB. [2024-11-28T03:27:04.257Z] ====== log-regression (apache-spark) [default], iteration 18 completed (11870.492 ms) ====== [2024-11-28T03:27:04.257Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-11-28T03:27:04.257Z] GC before operation: completed in 758.202 ms, heap usage 567.176 MB -> 118.031 MB. [2024-11-28T03:27:14.723Z] ====== log-regression (apache-spark) [default], iteration 19 completed (10782.317 ms) ====== [2024-11-28T03:27:17.414Z] ----------------------------------- [2024-11-28T03:27:17.414Z] renaissance-log-regression_0_PASSED [2024-11-28T03:27:17.414Z] ----------------------------------- [2024-11-28T03:27:17.414Z] [2024-11-28T03:27:17.414Z] TEST TEARDOWN: [2024-11-28T03:27:17.414Z] Nothing to be done for teardown. [2024-11-28T03:27:17.414Z] renaissance-log-regression_0 Finish Time: Thu Nov 28 03:27:16 2024 Epoch Time (ms): 1732764436790