[2025-05-11T07:59:57.031Z] Running test renaissance-log-regression_0 ... [2025-05-11T07:59:57.031Z] =============================================== [2025-05-11T07:59:57.352Z] renaissance-log-regression_0 Start Time: Sun May 11 07:59:57 2025 Epoch Time (ms): 1746950397067 [2025-05-11T07:59:57.352Z] variation: NoOptions [2025-05-11T07:59:57.352Z] JVM_OPTIONS: [2025-05-11T07:59:57.352Z] { \ [2025-05-11T07:59:57.352Z] echo ""; echo "TEST SETUP:"; \ [2025-05-11T07:59:57.352Z] echo "Nothing to be done for setup."; \ [2025-05-11T07:59:57.352Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17469489429544\\renaissance-log-regression_0"; \ [2025-05-11T07:59:57.352Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17469489429544\\renaissance-log-regression_0"; \ [2025-05-11T07:59:57.352Z] echo ""; echo "TESTING:"; \ [2025-05-11T07:59:57.352Z] "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_17469489429544\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2025-05-11T07:59:57.352Z] 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_17469489429544\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-11T07:59:57.352Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-11T07:59:57.352Z] echo "Nothing to be done for teardown."; \ [2025-05-11T07:59:57.352Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17469489429544\\TestTargetResult"; [2025-05-11T07:59:57.352Z] [2025-05-11T07:59:57.352Z] TEST SETUP: [2025-05-11T07:59:57.352Z] Nothing to be done for setup. [2025-05-11T07:59:57.352Z] [2025-05-11T07:59:57.352Z] TESTING: [2025-05-11T08:00:10.149Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-05-11T08:00:12.310Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-05-11T08:00:12.310Z] GC before operation: completed in 44.241 ms, heap usage 53.594 MB -> 35.246 MB. [2025-05-11T08:00:23.358Z] ====== log-regression (apache-spark) [default], iteration 0 completed (9999.480 ms) ====== [2025-05-11T08:00:23.358Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-05-11T08:00:23.358Z] GC before operation: completed in 90.944 ms, heap usage 317.403 MB -> 119.862 MB. [2025-05-11T08:00:24.612Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2193.217 ms) ====== [2025-05-11T08:00:24.612Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-05-11T08:00:24.612Z] GC before operation: completed in 63.822 ms, heap usage 447.589 MB -> 120.766 MB. [2025-05-11T08:00:26.291Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1747.399 ms) ====== [2025-05-11T08:00:26.291Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-05-11T08:00:26.291Z] GC before operation: completed in 63.630 ms, heap usage 418.813 MB -> 121.193 MB. [2025-05-11T08:00:28.027Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1540.461 ms) ====== [2025-05-11T08:00:28.027Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-05-11T08:00:28.027Z] GC before operation: completed in 65.175 ms, heap usage 435.024 MB -> 121.435 MB. [2025-05-11T08:00:29.633Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1515.763 ms) ====== [2025-05-11T08:00:29.633Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-05-11T08:00:29.633Z] GC before operation: completed in 63.623 ms, heap usage 265.104 MB -> 121.239 MB. [2025-05-11T08:00:31.298Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1383.699 ms) ====== [2025-05-11T08:00:31.298Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-05-11T08:00:31.298Z] GC before operation: completed in 63.589 ms, heap usage 237.710 MB -> 121.428 MB. [2025-05-11T08:00:32.418Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1277.834 ms) ====== [2025-05-11T08:00:32.418Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-05-11T08:00:32.418Z] GC before operation: completed in 69.954 ms, heap usage 344.090 MB -> 121.860 MB. [2025-05-11T08:00:33.551Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1283.386 ms) ====== [2025-05-11T08:00:33.551Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-05-11T08:00:33.886Z] GC before operation: completed in 70.468 ms, heap usage 418.550 MB -> 122.333 MB. [2025-05-11T08:00:35.070Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1337.334 ms) ====== [2025-05-11T08:00:35.070Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-05-11T08:00:35.070Z] GC before operation: completed in 69.378 ms, heap usage 449.664 MB -> 122.608 MB. [2025-05-11T08:00:36.811Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1306.009 ms) ====== [2025-05-11T08:00:36.811Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-05-11T08:00:36.811Z] GC before operation: completed in 88.542 ms, heap usage 427.102 MB -> 122.874 MB. [2025-05-11T08:00:37.967Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1262.775 ms) ====== [2025-05-11T08:00:37.967Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-05-11T08:00:37.967Z] GC before operation: completed in 77.721 ms, heap usage 436.218 MB -> 122.986 MB. [2025-05-11T08:00:39.227Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1259.914 ms) ====== [2025-05-11T08:00:39.227Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-05-11T08:00:39.227Z] GC before operation: completed in 71.270 ms, heap usage 343.711 MB -> 123.017 MB. [2025-05-11T08:00:40.883Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1310.638 ms) ====== [2025-05-11T08:00:40.883Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-05-11T08:00:40.883Z] GC before operation: completed in 73.821 ms, heap usage 313.349 MB -> 123.054 MB. [2025-05-11T08:00:41.995Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1244.127 ms) ====== [2025-05-11T08:00:41.995Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-05-11T08:00:41.995Z] GC before operation: completed in 72.954 ms, heap usage 340.325 MB -> 123.312 MB. [2025-05-11T08:00:43.112Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1294.784 ms) ====== [2025-05-11T08:00:43.112Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-05-11T08:00:43.112Z] GC before operation: completed in 75.552 ms, heap usage 372.641 MB -> 123.681 MB. [2025-05-11T08:00:44.942Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1298.611 ms) ====== [2025-05-11T08:00:44.943Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-05-11T08:00:44.943Z] GC before operation: completed in 72.596 ms, heap usage 404.013 MB -> 123.999 MB. [2025-05-11T08:00:46.093Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1337.019 ms) ====== [2025-05-11T08:00:46.093Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-05-11T08:00:46.093Z] GC before operation: completed in 72.556 ms, heap usage 396.838 MB -> 124.228 MB. [2025-05-11T08:00:47.257Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1293.273 ms) ====== [2025-05-11T08:00:47.257Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-05-11T08:00:47.709Z] GC before operation: completed in 87.659 ms, heap usage 385.564 MB -> 124.292 MB. [2025-05-11T08:00:48.797Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1234.506 ms) ====== [2025-05-11T08:00:48.797Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-05-11T08:00:48.797Z] GC before operation: completed in 74.450 ms, heap usage 404.124 MB -> 124.558 MB. [2025-05-11T08:00:49.913Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1243.752 ms) ====== [2025-05-11T08:00:50.233Z] ----------------------------------- [2025-05-11T08:00:50.233Z] renaissance-log-regression_0_PASSED [2025-05-11T08:00:50.233Z] ----------------------------------- [2025-05-11T08:00:50.538Z] [2025-05-11T08:00:50.538Z] TEST TEARDOWN: [2025-05-11T08:00:50.538Z] Nothing to be done for teardown. [2025-05-11T08:00:50.538Z] renaissance-log-regression_0 Finish Time: Sun May 11 08:00:50 2025 Epoch Time (ms): 1746950450364