[2025-01-22T02:29:21.928Z] Running test renaissance-log-regression_0 ... [2025-01-22T02:29:21.928Z] =============================================== [2025-01-22T02:29:22.250Z] renaissance-log-regression_0 Start Time: Wed Jan 22 02:29:21 2025 Epoch Time (ms): 1737512961945 [2025-01-22T02:29:22.250Z] variation: NoOptions [2025-01-22T02:29:22.250Z] JVM_OPTIONS: [2025-01-22T02:29:22.250Z] { \ [2025-01-22T02:29:22.250Z] echo ""; echo "TEST SETUP:"; \ [2025-01-22T02:29:22.250Z] echo "Nothing to be done for setup."; \ [2025-01-22T02:29:22.250Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17375120408358\\renaissance-log-regression_0"; \ [2025-01-22T02:29:22.250Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17375120408358\\renaissance-log-regression_0"; \ [2025-01-22T02:29:22.250Z] echo ""; echo "TESTING:"; \ [2025-01-22T02:29:22.250Z] "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_17375120408358\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2025-01-22T02:29:22.250Z] 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_17375120408358\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-01-22T02:29:22.250Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-01-22T02:29:22.250Z] echo "Nothing to be done for teardown."; \ [2025-01-22T02:29:22.250Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17375120408358\\TestTargetResult"; [2025-01-22T02:29:22.576Z] [2025-01-22T02:29:22.576Z] TEST SETUP: [2025-01-22T02:29:22.576Z] Nothing to be done for setup. [2025-01-22T02:29:22.576Z] [2025-01-22T02:29:22.576Z] TESTING: [2025-01-22T02:29:35.476Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-01-22T02:29:35.811Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-01-22T02:29:39.475Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-01-22T02:29:39.475Z] GC before operation: completed in 43.627 ms, heap usage 46.689 MB -> 35.322 MB. [2025-01-22T02:29:46.882Z] ====== log-regression (apache-spark) [default], iteration 0 completed (6713.998 ms) ====== [2025-01-22T02:29:46.882Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-01-22T02:29:46.882Z] GC before operation: completed in 72.661 ms, heap usage 485.604 MB -> 113.966 MB. [2025-01-22T02:29:48.657Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2239.247 ms) ====== [2025-01-22T02:29:48.658Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-01-22T02:29:48.658Z] GC before operation: completed in 57.239 ms, heap usage 263.226 MB -> 114.042 MB. [2025-01-22T02:29:50.383Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1811.456 ms) ====== [2025-01-22T02:29:50.383Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-01-22T02:29:50.383Z] GC before operation: completed in 57.773 ms, heap usage 412.577 MB -> 114.888 MB. [2025-01-22T02:29:52.002Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1563.572 ms) ====== [2025-01-22T02:29:52.002Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-01-22T02:29:52.002Z] GC before operation: completed in 60.460 ms, heap usage 586.219 MB -> 115.487 MB. [2025-01-22T02:29:53.157Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1324.936 ms) ====== [2025-01-22T02:29:53.157Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-01-22T02:29:53.542Z] GC before operation: completed in 61.567 ms, heap usage 387.772 MB -> 115.297 MB. [2025-01-22T02:29:54.682Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1249.068 ms) ====== [2025-01-22T02:29:54.682Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-01-22T02:29:54.682Z] GC before operation: completed in 62.839 ms, heap usage 419.629 MB -> 115.543 MB. [2025-01-22T02:29:55.822Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1263.596 ms) ====== [2025-01-22T02:29:55.822Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-01-22T02:29:56.196Z] GC before operation: completed in 63.655 ms, heap usage 373.874 MB -> 115.619 MB. [2025-01-22T02:29:57.405Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1358.498 ms) ====== [2025-01-22T02:29:57.405Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-01-22T02:29:57.405Z] GC before operation: completed in 70.599 ms, heap usage 373.028 MB -> 115.783 MB. [2025-01-22T02:29:58.545Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1214.680 ms) ====== [2025-01-22T02:29:58.545Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-01-22T02:29:58.926Z] GC before operation: completed in 64.513 ms, heap usage 324.007 MB -> 115.998 MB. [2025-01-22T02:30:00.054Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1261.388 ms) ====== [2025-01-22T02:30:00.054Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-01-22T02:30:00.054Z] GC before operation: completed in 78.024 ms, heap usage 269.390 MB -> 115.977 MB. [2025-01-22T02:30:01.266Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1209.533 ms) ====== [2025-01-22T02:30:01.266Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-01-22T02:30:01.266Z] GC before operation: completed in 66.429 ms, heap usage 562.277 MB -> 116.933 MB. [2025-01-22T02:30:02.422Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1231.431 ms) ====== [2025-01-22T02:30:02.422Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-01-22T02:30:02.795Z] GC before operation: completed in 67.589 ms, heap usage 358.492 MB -> 116.655 MB. [2025-01-22T02:30:03.941Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1256.110 ms) ====== [2025-01-22T02:30:03.941Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-01-22T02:30:03.941Z] GC before operation: completed in 71.086 ms, heap usage 313.621 MB -> 116.618 MB. [2025-01-22T02:30:05.064Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1223.613 ms) ====== [2025-01-22T02:30:05.064Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-01-22T02:30:05.460Z] GC before operation: completed in 69.693 ms, heap usage 229.427 MB -> 116.740 MB. [2025-01-22T02:30:06.619Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1281.071 ms) ====== [2025-01-22T02:30:06.619Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-01-22T02:30:06.619Z] GC before operation: completed in 73.281 ms, heap usage 513.337 MB -> 117.610 MB. [2025-01-22T02:30:07.831Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1185.282 ms) ====== [2025-01-22T02:30:07.831Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-01-22T02:30:07.831Z] GC before operation: completed in 70.185 ms, heap usage 322.246 MB -> 117.364 MB. [2025-01-22T02:30:09.515Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1287.037 ms) ====== [2025-01-22T02:30:09.515Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-01-22T02:30:09.515Z] GC before operation: completed in 77.599 ms, heap usage 294.486 MB -> 117.459 MB. [2025-01-22T02:30:10.683Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1209.895 ms) ====== [2025-01-22T02:30:10.683Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-01-22T02:30:10.683Z] GC before operation: completed in 70.653 ms, heap usage 275.586 MB -> 117.527 MB. [2025-01-22T02:30:11.818Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1151.415 ms) ====== [2025-01-22T02:30:11.818Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-01-22T02:30:11.818Z] GC before operation: completed in 73.696 ms, heap usage 294.857 MB -> 117.761 MB. [2025-01-22T02:30:12.945Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1157.150 ms) ====== [2025-01-22T02:30:13.272Z] ----------------------------------- [2025-01-22T02:30:13.272Z] renaissance-log-regression_0_PASSED [2025-01-22T02:30:13.272Z] ----------------------------------- [2025-01-22T02:30:13.272Z] [2025-01-22T02:30:13.272Z] TEST TEARDOWN: [2025-01-22T02:30:13.272Z] Nothing to be done for teardown. [2025-01-22T02:30:13.272Z] renaissance-log-regression_0 Finish Time: Wed Jan 22 02:30:13 2025 Epoch Time (ms): 1737513013240