[2024-11-08T18:19:44.271Z] Running test renaissance-log-regression_0 ... [2024-11-08T18:19:44.271Z] =============================================== [2024-11-08T18:19:44.590Z] renaissance-log-regression_0 Start Time: Fri Nov 8 18:19:44 2024 Epoch Time (ms): 1731089984287 [2024-11-08T18:19:44.590Z] variation: NoOptions [2024-11-08T18:19:44.590Z] JVM_OPTIONS: [2024-11-08T18:19:44.590Z] { \ [2024-11-08T18:19:44.590Z] echo ""; echo "TEST SETUP:"; \ [2024-11-08T18:19:44.590Z] echo "Nothing to be done for setup."; \ [2024-11-08T18:19:44.590Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17310891697444\\renaissance-log-regression_0"; \ [2024-11-08T18:19:44.590Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17310891697444\\renaissance-log-regression_0"; \ [2024-11-08T18:19:44.590Z] echo ""; echo "TESTING:"; \ [2024-11-08T18:19:44.590Z] "c:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/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/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17310891697444\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2024-11-08T18:19:44.590Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17310891697444\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-08T18:19:44.590Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-08T18:19:44.590Z] echo "Nothing to be done for teardown."; \ [2024-11-08T18:19:44.590Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17310891697444\\TestTargetResult"; [2024-11-08T18:19:44.590Z] [2024-11-08T18:19:44.590Z] TEST SETUP: [2024-11-08T18:19:44.590Z] Nothing to be done for setup. [2024-11-08T18:19:44.590Z] [2024-11-08T18:19:44.590Z] TESTING: [2024-11-08T18:19:55.206Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-08T18:19:55.530Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-11-08T18:19:59.194Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-11-08T18:19:59.194Z] GC before operation: completed in 47.422 ms, heap usage 61.838 MB -> 35.337 MB. [2024-11-08T18:20:05.268Z] ====== log-regression (apache-spark) [default], iteration 0 completed (6189.945 ms) ====== [2024-11-08T18:20:05.268Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-11-08T18:20:05.268Z] GC before operation: completed in 56.798 ms, heap usage 309.763 MB -> 113.604 MB. [2024-11-08T18:20:06.956Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2008.180 ms) ====== [2024-11-08T18:20:06.956Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-11-08T18:20:06.956Z] GC before operation: completed in 52.823 ms, heap usage 426.935 MB -> 114.353 MB. [2024-11-08T18:20:08.749Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1555.317 ms) ====== [2024-11-08T18:20:08.749Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-11-08T18:20:08.749Z] GC before operation: completed in 57.075 ms, heap usage 441.730 MB -> 114.853 MB. [2024-11-08T18:20:10.407Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1359.744 ms) ====== [2024-11-08T18:20:10.407Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-11-08T18:20:10.407Z] GC before operation: completed in 61.635 ms, heap usage 400.606 MB -> 115.035 MB. [2024-11-08T18:20:11.629Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1182.592 ms) ====== [2024-11-08T18:20:11.629Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-11-08T18:20:11.629Z] GC before operation: completed in 56.687 ms, heap usage 397.852 MB -> 115.339 MB. [2024-11-08T18:20:12.791Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1168.302 ms) ====== [2024-11-08T18:20:12.791Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-11-08T18:20:12.792Z] GC before operation: completed in 58.600 ms, heap usage 386.109 MB -> 115.516 MB. [2024-11-08T18:20:13.928Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1197.750 ms) ====== [2024-11-08T18:20:13.928Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-11-08T18:20:13.928Z] GC before operation: completed in 57.151 ms, heap usage 381.343 MB -> 115.600 MB. [2024-11-08T18:20:15.195Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1285.175 ms) ====== [2024-11-08T18:20:15.195Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-11-08T18:20:15.195Z] GC before operation: completed in 62.029 ms, heap usage 374.432 MB -> 115.853 MB. [2024-11-08T18:20:16.344Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1089.689 ms) ====== [2024-11-08T18:20:16.344Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-11-08T18:20:16.344Z] GC before operation: completed in 62.502 ms, heap usage 428.184 MB -> 116.216 MB. [2024-11-08T18:20:17.580Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1127.880 ms) ====== [2024-11-08T18:20:17.580Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-11-08T18:20:17.580Z] GC before operation: completed in 69.268 ms, heap usage 371.275 MB -> 116.267 MB. [2024-11-08T18:20:18.787Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1163.024 ms) ====== [2024-11-08T18:20:18.787Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-11-08T18:20:18.787Z] GC before operation: completed in 61.922 ms, heap usage 371.654 MB -> 116.560 MB. [2024-11-08T18:20:19.981Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1137.353 ms) ====== [2024-11-08T18:20:19.981Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-11-08T18:20:19.981Z] GC before operation: completed in 67.423 ms, heap usage 379.301 MB -> 116.618 MB. [2024-11-08T18:20:21.142Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1101.682 ms) ====== [2024-11-08T18:20:21.142Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-11-08T18:20:21.142Z] GC before operation: completed in 60.150 ms, heap usage 351.540 MB -> 116.808 MB. [2024-11-08T18:20:22.333Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1116.068 ms) ====== [2024-11-08T18:20:22.333Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-11-08T18:20:22.333Z] GC before operation: completed in 68.242 ms, heap usage 383.579 MB -> 117.128 MB. [2024-11-08T18:20:23.485Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1079.534 ms) ====== [2024-11-08T18:20:23.485Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-11-08T18:20:23.485Z] GC before operation: completed in 67.870 ms, heap usage 365.966 MB -> 117.248 MB. [2024-11-08T18:20:24.652Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1091.145 ms) ====== [2024-11-08T18:20:24.652Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-11-08T18:20:24.652Z] GC before operation: completed in 62.445 ms, heap usage 294.445 MB -> 117.279 MB. [2024-11-08T18:20:26.004Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1338.081 ms) ====== [2024-11-08T18:20:26.004Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-11-08T18:20:26.004Z] GC before operation: completed in 72.080 ms, heap usage 314.182 MB -> 117.593 MB. [2024-11-08T18:20:27.185Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1079.490 ms) ====== [2024-11-08T18:20:27.185Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-11-08T18:20:27.185Z] GC before operation: completed in 64.604 ms, heap usage 296.322 MB -> 117.756 MB. [2024-11-08T18:20:28.332Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1080.763 ms) ====== [2024-11-08T18:20:28.332Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-11-08T18:20:28.332Z] GC before operation: completed in 65.612 ms, heap usage 546.419 MB -> 118.623 MB. [2024-11-08T18:20:29.442Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1071.205 ms) ====== [2024-11-08T18:20:29.763Z] ----------------------------------- [2024-11-08T18:20:29.763Z] renaissance-log-regression_0_PASSED [2024-11-08T18:20:29.763Z] ----------------------------------- [2024-11-08T18:20:30.084Z] [2024-11-08T18:20:30.084Z] TEST TEARDOWN: [2024-11-08T18:20:30.084Z] Nothing to be done for teardown. [2024-11-08T18:20:30.084Z] renaissance-log-regression_0 Finish Time: Fri Nov 8 18:20:29 2024 Epoch Time (ms): 1731090029829