[2024-10-31T03:07:46.896Z] Running test renaissance-log-regression_0 ... [2024-10-31T03:07:46.896Z] =============================================== [2024-10-31T03:07:47.201Z] renaissance-log-regression_0 Start Time: Thu Oct 31 03:07:46 2024 Epoch Time (ms): 1730344066935 [2024-10-31T03:07:47.201Z] variation: NoOptions [2024-10-31T03:07:47.202Z] JVM_OPTIONS: [2024-10-31T03:07:47.202Z] { \ [2024-10-31T03:07:47.202Z] echo ""; echo "TEST SETUP:"; \ [2024-10-31T03:07:47.202Z] echo "Nothing to be done for setup."; \ [2024-10-31T03:07:47.202Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17303432483900\\renaissance-log-regression_0"; \ [2024-10-31T03:07:47.202Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17303432483900\\renaissance-log-regression_0"; \ [2024-10-31T03:07:47.202Z] echo ""; echo "TESTING:"; \ [2024-10-31T03:07:47.202Z] "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_17303432483900\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2024-10-31T03:07:47.202Z] 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_17303432483900\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-10-31T03:07:47.202Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-10-31T03:07:47.202Z] echo "Nothing to be done for teardown."; \ [2024-10-31T03:07:47.202Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17303432483900\\TestTargetResult"; [2024-10-31T03:07:47.517Z] [2024-10-31T03:07:47.517Z] TEST SETUP: [2024-10-31T03:07:47.517Z] Nothing to be done for setup. [2024-10-31T03:07:47.517Z] [2024-10-31T03:07:47.517Z] TESTING: [2024-10-31T03:07:58.090Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-10-31T03:07:58.405Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-10-31T03:08:02.041Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-10-31T03:08:02.041Z] GC before operation: completed in 55.166 ms, heap usage 93.083 MB -> 35.375 MB. [2024-10-31T03:08:08.228Z] ====== log-regression (apache-spark) [default], iteration 0 completed (6404.239 ms) ====== [2024-10-31T03:08:08.228Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-10-31T03:08:08.228Z] GC before operation: completed in 60.348 ms, heap usage 483.300 MB -> 114.011 MB. [2024-10-31T03:08:10.113Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2069.009 ms) ====== [2024-10-31T03:08:10.114Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-10-31T03:08:10.114Z] GC before operation: completed in 48.809 ms, heap usage 260.907 MB -> 114.094 MB. [2024-10-31T03:08:11.754Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1564.169 ms) ====== [2024-10-31T03:08:11.754Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-10-31T03:08:11.754Z] GC before operation: completed in 54.633 ms, heap usage 398.423 MB -> 114.857 MB. [2024-10-31T03:08:12.852Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1222.197 ms) ====== [2024-10-31T03:08:12.852Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-10-31T03:08:12.852Z] GC before operation: completed in 57.867 ms, heap usage 459.519 MB -> 115.230 MB. [2024-10-31T03:08:14.559Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1161.116 ms) ====== [2024-10-31T03:08:14.559Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-10-31T03:08:14.559Z] GC before operation: completed in 56.374 ms, heap usage 396.667 MB -> 115.406 MB. [2024-10-31T03:08:15.692Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1265.628 ms) ====== [2024-10-31T03:08:15.692Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-10-31T03:08:15.692Z] GC before operation: completed in 68.455 ms, heap usage 376.627 MB -> 115.466 MB. [2024-10-31T03:08:16.842Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1226.340 ms) ====== [2024-10-31T03:08:16.842Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-10-31T03:08:16.842Z] GC before operation: completed in 60.917 ms, heap usage 383.328 MB -> 115.798 MB. [2024-10-31T03:08:17.982Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1102.658 ms) ====== [2024-10-31T03:08:17.982Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-10-31T03:08:17.982Z] GC before operation: completed in 61.329 ms, heap usage 428.792 MB -> 116.062 MB. [2024-10-31T03:08:19.095Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1178.746 ms) ====== [2024-10-31T03:08:19.095Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-10-31T03:08:19.480Z] GC before operation: completed in 62.459 ms, heap usage 373.066 MB -> 116.126 MB. [2024-10-31T03:08:20.652Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1136.871 ms) ====== [2024-10-31T03:08:20.652Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-10-31T03:08:20.652Z] GC before operation: completed in 68.369 ms, heap usage 344.487 MB -> 116.324 MB. [2024-10-31T03:08:21.739Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1135.105 ms) ====== [2024-10-31T03:08:21.739Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-10-31T03:08:21.739Z] GC before operation: completed in 71.562 ms, heap usage 352.663 MB -> 116.554 MB. [2024-10-31T03:08:22.928Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1198.380 ms) ====== [2024-10-31T03:08:22.928Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-10-31T03:08:22.928Z] GC before operation: completed in 70.247 ms, heap usage 297.598 MB -> 116.601 MB. [2024-10-31T03:08:24.120Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1149.180 ms) ====== [2024-10-31T03:08:24.120Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-10-31T03:08:24.120Z] GC before operation: completed in 61.099 ms, heap usage 259.268 MB -> 116.658 MB. [2024-10-31T03:08:25.265Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1124.772 ms) ====== [2024-10-31T03:08:25.265Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-10-31T03:08:25.265Z] GC before operation: completed in 66.386 ms, heap usage 258.724 MB -> 116.871 MB. [2024-10-31T03:08:26.451Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1112.959 ms) ====== [2024-10-31T03:08:26.452Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-10-31T03:08:26.452Z] GC before operation: completed in 67.829 ms, heap usage 463.907 MB -> 117.478 MB. [2024-10-31T03:08:27.603Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1100.422 ms) ====== [2024-10-31T03:08:27.603Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-10-31T03:08:27.603Z] GC before operation: completed in 64.639 ms, heap usage 341.808 MB -> 117.438 MB. [2024-10-31T03:08:28.781Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1046.830 ms) ====== [2024-10-31T03:08:28.781Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-10-31T03:08:28.781Z] GC before operation: completed in 64.406 ms, heap usage 324.626 MB -> 117.689 MB. [2024-10-31T03:08:29.951Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1100.491 ms) ====== [2024-10-31T03:08:29.951Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-10-31T03:08:29.951Z] GC before operation: completed in 64.337 ms, heap usage 247.109 MB -> 117.658 MB. [2024-10-31T03:08:31.083Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1102.478 ms) ====== [2024-10-31T03:08:31.083Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-10-31T03:08:31.083Z] GC before operation: completed in 69.319 ms, heap usage 537.893 MB -> 118.573 MB. [2024-10-31T03:08:32.177Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1034.123 ms) ====== [2024-10-31T03:08:32.490Z] ----------------------------------- [2024-10-31T03:08:32.490Z] renaissance-log-regression_0_PASSED [2024-10-31T03:08:32.490Z] ----------------------------------- [2024-10-31T03:08:32.490Z] [2024-10-31T03:08:32.490Z] TEST TEARDOWN: [2024-10-31T03:08:32.490Z] Nothing to be done for teardown. [2024-10-31T03:08:32.490Z] renaissance-log-regression_0 Finish Time: Thu Oct 31 03:08:32 2024 Epoch Time (ms): 1730344112455