[2024-08-16T19:27:12.429Z] Running test renaissance-log-regression_0 ... [2024-08-16T19:27:12.429Z] =============================================== [2024-08-16T19:27:12.738Z] renaissance-log-regression_0 Start Time: Fri Aug 16 19:27:12 2024 Epoch Time (ms): 1723836432551 [2024-08-16T19:27:13.056Z] variation: NoOptions [2024-08-16T19:27:13.056Z] JVM_OPTIONS: [2024-08-16T19:27:13.056Z] { \ [2024-08-16T19:27:13.056Z] echo ""; echo "TEST SETUP:"; \ [2024-08-16T19:27:13.056Z] echo "Nothing to be done for setup."; \ [2024-08-16T19:27:13.056Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17238356015779\\renaissance-log-regression_0"; \ [2024-08-16T19:27:13.056Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17238356015779\\renaissance-log-regression_0"; \ [2024-08-16T19:27:13.056Z] echo ""; echo "TESTING:"; \ [2024-08-16T19:27:13.056Z] "c:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/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_testList_0/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17238356015779\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2024-08-16T19:27:13.056Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17238356015779\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-16T19:27:13.056Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-16T19:27:13.056Z] echo "Nothing to be done for teardown."; \ [2024-08-16T19:27:13.056Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17238356015779\\TestTargetResult"; [2024-08-16T19:27:13.377Z] [2024-08-16T19:27:13.377Z] TEST SETUP: [2024-08-16T19:27:13.377Z] Nothing to be done for setup. [2024-08-16T19:27:13.377Z] [2024-08-16T19:27:13.377Z] TESTING: [2024-08-16T19:27:23.971Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-16T19:27:25.075Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-08-16T19:27:27.943Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-16T19:27:27.943Z] GC before operation: completed in 43.304 ms, heap usage 61.598 MB -> 35.424 MB. [2024-08-16T19:27:35.331Z] ====== log-regression (apache-spark) [default], iteration 0 completed (6294.119 ms) ====== [2024-08-16T19:27:35.331Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-16T19:27:35.331Z] GC before operation: completed in 71.811 ms, heap usage 476.776 MB -> 113.965 MB. [2024-08-16T19:27:36.466Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1909.483 ms) ====== [2024-08-16T19:27:36.466Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-16T19:27:36.466Z] GC before operation: completed in 54.151 ms, heap usage 416.474 MB -> 114.433 MB. [2024-08-16T19:27:38.115Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1673.732 ms) ====== [2024-08-16T19:27:38.115Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-16T19:27:38.115Z] GC before operation: completed in 52.313 ms, heap usage 413.716 MB -> 114.898 MB. [2024-08-16T19:27:39.806Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1373.918 ms) ====== [2024-08-16T19:27:39.806Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-16T19:27:39.806Z] GC before operation: completed in 55.564 ms, heap usage 384.864 MB -> 115.115 MB. [2024-08-16T19:27:40.921Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1289.520 ms) ====== [2024-08-16T19:27:40.921Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-16T19:27:40.921Z] GC before operation: completed in 58.214 ms, heap usage 399.972 MB -> 115.272 MB. [2024-08-16T19:27:42.173Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1248.566 ms) ====== [2024-08-16T19:27:42.173Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-16T19:27:42.173Z] GC before operation: completed in 56.327 ms, heap usage 410.823 MB -> 115.588 MB. [2024-08-16T19:27:43.363Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1178.546 ms) ====== [2024-08-16T19:27:43.363Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-16T19:27:43.363Z] GC before operation: completed in 57.215 ms, heap usage 383.323 MB -> 115.712 MB. [2024-08-16T19:27:44.502Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1186.844 ms) ====== [2024-08-16T19:27:44.502Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-16T19:27:44.917Z] GC before operation: completed in 63.943 ms, heap usage 382.267 MB -> 115.956 MB. [2024-08-16T19:27:46.093Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1162.233 ms) ====== [2024-08-16T19:27:46.093Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-16T19:27:46.093Z] GC before operation: completed in 66.463 ms, heap usage 372.449 MB -> 116.160 MB. [2024-08-16T19:27:47.214Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1129.944 ms) ====== [2024-08-16T19:27:47.214Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-16T19:27:47.214Z] GC before operation: completed in 62.536 ms, heap usage 372.822 MB -> 116.323 MB. [2024-08-16T19:27:48.422Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1100.270 ms) ====== [2024-08-16T19:27:48.422Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-16T19:27:48.422Z] GC before operation: completed in 59.918 ms, heap usage 371.827 MB -> 116.542 MB. [2024-08-16T19:27:49.589Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1150.732 ms) ====== [2024-08-16T19:27:49.589Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-16T19:27:49.589Z] GC before operation: completed in 73.858 ms, heap usage 341.482 MB -> 116.817 MB. [2024-08-16T19:27:50.835Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1136.413 ms) ====== [2024-08-16T19:27:50.835Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-16T19:27:50.835Z] GC before operation: completed in 64.248 ms, heap usage 324.048 MB -> 116.850 MB. [2024-08-16T19:27:52.042Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1142.431 ms) ====== [2024-08-16T19:27:52.042Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-16T19:27:52.042Z] GC before operation: completed in 68.831 ms, heap usage 324.104 MB -> 116.982 MB. [2024-08-16T19:27:53.264Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1145.591 ms) ====== [2024-08-16T19:27:53.264Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-16T19:27:53.264Z] GC before operation: completed in 63.251 ms, heap usage 285.922 MB -> 117.168 MB. [2024-08-16T19:27:54.515Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1124.756 ms) ====== [2024-08-16T19:27:54.515Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-16T19:27:54.515Z] GC before operation: completed in 64.057 ms, heap usage 268.043 MB -> 117.316 MB. [2024-08-16T19:27:55.746Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1163.105 ms) ====== [2024-08-16T19:27:55.746Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-16T19:27:55.746Z] GC before operation: completed in 81.847 ms, heap usage 260.512 MB -> 117.490 MB. [2024-08-16T19:27:56.468Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1079.777 ms) ====== [2024-08-16T19:27:56.468Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-16T19:27:56.836Z] GC before operation: completed in 72.061 ms, heap usage 539.984 MB -> 118.376 MB. [2024-08-16T19:27:58.054Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1111.134 ms) ====== [2024-08-16T19:27:58.055Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-16T19:27:58.055Z] GC before operation: completed in 66.169 ms, heap usage 362.610 MB -> 118.139 MB. [2024-08-16T19:27:58.738Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1038.370 ms) ====== [2024-08-16T19:27:59.059Z] ----------------------------------- [2024-08-16T19:27:59.059Z] renaissance-log-regression_0_PASSED [2024-08-16T19:27:59.059Z] ----------------------------------- [2024-08-16T19:27:59.381Z] [2024-08-16T19:27:59.381Z] TEST TEARDOWN: [2024-08-16T19:27:59.381Z] Nothing to be done for teardown. [2024-08-16T19:27:59.381Z] renaissance-log-regression_0 Finish Time: Fri Aug 16 19:27:59 2024 Epoch Time (ms): 1723836479196