[2024-11-28T07:36:39.338Z] Running test renaissance-log-regression_0 ... [2024-11-28T07:36:39.338Z] =============================================== [2024-11-28T07:36:39.338Z] renaissance-log-regression_0 Start Time: Thu Nov 28 07:36:39 2024 Epoch Time (ms): 1732779399224 [2024-11-28T07:36:39.338Z] variation: NoOptions [2024-11-28T07:36:39.668Z] JVM_OPTIONS: [2024-11-28T07:36:39.668Z] { \ [2024-11-28T07:36:39.668Z] echo ""; echo "TEST SETUP:"; \ [2024-11-28T07:36:39.668Z] echo "Nothing to be done for setup."; \ [2024-11-28T07:36:39.668Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17327785319577\\renaissance-log-regression_0"; \ [2024-11-28T07:36:39.668Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17327785319577\\renaissance-log-regression_0"; \ [2024-11-28T07:36:39.668Z] echo ""; echo "TESTING:"; \ [2024-11-28T07:36:39.668Z] "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_17327785319577\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2024-11-28T07:36:39.668Z] 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_17327785319577\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-28T07:36:39.668Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-28T07:36:39.668Z] echo "Nothing to be done for teardown."; \ [2024-11-28T07:36:39.668Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17327785319577\\TestTargetResult"; [2024-11-28T07:36:39.668Z] [2024-11-28T07:36:39.668Z] TEST SETUP: [2024-11-28T07:36:39.668Z] Nothing to be done for setup. [2024-11-28T07:36:39.668Z] [2024-11-28T07:36:39.668Z] TESTING: [2024-11-28T07:36:50.413Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-28T07:36:52.044Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-11-28T07:36:55.737Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-11-28T07:36:55.737Z] GC before operation: completed in 53.570 ms, heap usage 93.633 MB -> 35.338 MB. [2024-11-28T07:37:01.892Z] ====== log-regression (apache-spark) [default], iteration 0 completed (6565.984 ms) ====== [2024-11-28T07:37:01.892Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-11-28T07:37:01.892Z] GC before operation: completed in 63.574 ms, heap usage 437.764 MB -> 113.778 MB. [2024-11-28T07:37:04.579Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2180.834 ms) ====== [2024-11-28T07:37:04.579Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-11-28T07:37:04.579Z] GC before operation: completed in 56.781 ms, heap usage 412.960 MB -> 114.313 MB. [2024-11-28T07:37:06.277Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1820.729 ms) ====== [2024-11-28T07:37:06.277Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-11-28T07:37:06.277Z] GC before operation: completed in 68.453 ms, heap usage 395.476 MB -> 114.799 MB. [2024-11-28T07:37:08.057Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1548.484 ms) ====== [2024-11-28T07:37:08.057Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-11-28T07:37:08.057Z] GC before operation: completed in 55.058 ms, heap usage 250.389 MB -> 114.681 MB. [2024-11-28T07:37:09.237Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1456.690 ms) ====== [2024-11-28T07:37:09.237Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-11-28T07:37:09.237Z] GC before operation: completed in 57.368 ms, heap usage 317.609 MB -> 115.149 MB. [2024-11-28T07:37:10.364Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1246.261 ms) ====== [2024-11-28T07:37:10.364Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-11-28T07:37:10.364Z] GC before operation: completed in 71.263 ms, heap usage 432.316 MB -> 115.598 MB. [2024-11-28T07:37:11.655Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1124.903 ms) ====== [2024-11-28T07:37:11.655Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-11-28T07:37:11.655Z] GC before operation: completed in 56.980 ms, heap usage 476.153 MB -> 115.960 MB. [2024-11-28T07:37:12.901Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1177.014 ms) ====== [2024-11-28T07:37:12.901Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-11-28T07:37:12.901Z] GC before operation: completed in 65.881 ms, heap usage 437.228 MB -> 116.003 MB. [2024-11-28T07:37:14.086Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1105.475 ms) ====== [2024-11-28T07:37:14.086Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-11-28T07:37:14.086Z] GC before operation: completed in 62.756 ms, heap usage 390.832 MB -> 116.058 MB. [2024-11-28T07:37:15.233Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1071.829 ms) ====== [2024-11-28T07:37:15.233Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-11-28T07:37:15.234Z] GC before operation: completed in 70.969 ms, heap usage 369.718 MB -> 116.134 MB. [2024-11-28T07:37:16.408Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1106.555 ms) ====== [2024-11-28T07:37:16.408Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-11-28T07:37:16.408Z] GC before operation: completed in 69.871 ms, heap usage 395.771 MB -> 116.481 MB. [2024-11-28T07:37:17.591Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1106.185 ms) ====== [2024-11-28T07:37:17.591Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-11-28T07:37:17.591Z] GC before operation: completed in 76.340 ms, heap usage 341.746 MB -> 116.665 MB. [2024-11-28T07:37:18.758Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1093.863 ms) ====== [2024-11-28T07:37:18.758Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-11-28T07:37:18.758Z] GC before operation: completed in 65.865 ms, heap usage 306.024 MB -> 116.739 MB. [2024-11-28T07:37:19.910Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1100.237 ms) ====== [2024-11-28T07:37:19.910Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-11-28T07:37:19.910Z] GC before operation: completed in 64.395 ms, heap usage 331.142 MB -> 116.896 MB. [2024-11-28T07:37:21.079Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1051.904 ms) ====== [2024-11-28T07:37:21.079Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-11-28T07:37:21.079Z] GC before operation: completed in 75.047 ms, heap usage 341.074 MB -> 117.177 MB. [2024-11-28T07:37:22.227Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1079.089 ms) ====== [2024-11-28T07:37:22.227Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-11-28T07:37:22.227Z] GC before operation: completed in 63.673 ms, heap usage 295.325 MB -> 117.276 MB. [2024-11-28T07:37:23.387Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1038.354 ms) ====== [2024-11-28T07:37:23.387Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-11-28T07:37:23.387Z] GC before operation: completed in 65.969 ms, heap usage 275.390 MB -> 117.382 MB. [2024-11-28T07:37:24.570Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1060.958 ms) ====== [2024-11-28T07:37:24.570Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-11-28T07:37:24.570Z] GC before operation: completed in 65.777 ms, heap usage 528.393 MB -> 118.320 MB. [2024-11-28T07:37:25.753Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1047.582 ms) ====== [2024-11-28T07:37:25.753Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-11-28T07:37:25.753Z] GC before operation: completed in 64.612 ms, heap usage 379.569 MB -> 118.133 MB. [2024-11-28T07:37:26.460Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1054.462 ms) ====== [2024-11-28T07:37:26.790Z] ----------------------------------- [2024-11-28T07:37:26.790Z] renaissance-log-regression_0_PASSED [2024-11-28T07:37:26.790Z] ----------------------------------- [2024-11-28T07:37:27.116Z] [2024-11-28T07:37:27.116Z] TEST TEARDOWN: [2024-11-28T07:37:27.116Z] Nothing to be done for teardown. [2024-11-28T07:37:27.116Z] renaissance-log-regression_0 Finish Time: Thu Nov 28 07:37:26 2024 Epoch Time (ms): 1732779446980