[2025-07-01T18:19:17.376Z] Running test renaissance-log-regression_0 ... [2025-07-01T18:19:17.376Z] =============================================== [2025-07-01T18:19:17.685Z] renaissance-log-regression_0 Start Time: Tue Jul 1 18:19:17 2025 Epoch Time (ms): 1751393957506 [2025-07-01T18:19:17.685Z] variation: NoOptions [2025-07-01T18:19:18.012Z] JVM_OPTIONS: [2025-07-01T18:19:18.012Z] { \ [2025-07-01T18:19:18.012Z] echo ""; echo "TEST SETUP:"; \ [2025-07-01T18:19:18.012Z] echo "Nothing to be done for setup."; \ [2025-07-01T18:19:18.012Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17513926107424\\renaissance-log-regression_0"; \ [2025-07-01T18:19:18.012Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17513926107424\\renaissance-log-regression_0"; \ [2025-07-01T18:19:18.012Z] echo ""; echo "TESTING:"; \ [2025-07-01T18:19:18.012Z] "c:/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17513926107424\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2025-07-01T18:19:18.012Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17513926107424\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-01T18:19:18.012Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-01T18:19:18.012Z] echo "Nothing to be done for teardown."; \ [2025-07-01T18:19:18.012Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17513926107424\\TestTargetResult"; [2025-07-01T18:19:18.012Z] [2025-07-01T18:19:18.012Z] TEST SETUP: [2025-07-01T18:19:18.012Z] Nothing to be done for setup. [2025-07-01T18:19:18.012Z] [2025-07-01T18:19:18.012Z] TESTING: [2025-07-01T18:19:30.900Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-07-01T18:19:33.079Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-07-01T18:19:33.079Z] GC before operation: completed in 41.263 ms, heap usage 38.760 MB -> 35.530 MB. [2025-07-01T18:19:43.912Z] ====== log-regression (apache-spark) [default], iteration 0 completed (10177.780 ms) ====== [2025-07-01T18:19:43.912Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-07-01T18:19:43.912Z] GC before operation: completed in 91.219 ms, heap usage 360.424 MB -> 120.284 MB. [2025-07-01T18:19:45.574Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2299.925 ms) ====== [2025-07-01T18:19:45.574Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-07-01T18:19:45.574Z] GC before operation: completed in 64.453 ms, heap usage 630.658 MB -> 121.697 MB. [2025-07-01T18:19:47.279Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1798.856 ms) ====== [2025-07-01T18:19:47.279Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-07-01T18:19:47.279Z] GC before operation: completed in 67.382 ms, heap usage 320.630 MB -> 121.140 MB. [2025-07-01T18:19:49.030Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1612.003 ms) ====== [2025-07-01T18:19:49.030Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-07-01T18:19:49.030Z] GC before operation: completed in 68.307 ms, heap usage 427.466 MB -> 121.803 MB. [2025-07-01T18:19:50.772Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1446.594 ms) ====== [2025-07-01T18:19:50.772Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-07-01T18:19:50.772Z] GC before operation: completed in 72.879 ms, heap usage 700.607 MB -> 122.782 MB. [2025-07-01T18:19:51.971Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1392.628 ms) ====== [2025-07-01T18:19:51.971Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-07-01T18:19:51.971Z] GC before operation: completed in 73.976 ms, heap usage 262.183 MB -> 121.721 MB. [2025-07-01T18:19:53.676Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1385.546 ms) ====== [2025-07-01T18:19:53.676Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-07-01T18:19:53.676Z] GC before operation: completed in 72.332 ms, heap usage 683.876 MB -> 123.172 MB. [2025-07-01T18:19:54.905Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1337.961 ms) ====== [2025-07-01T18:19:54.905Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-07-01T18:19:54.905Z] GC before operation: completed in 90.027 ms, heap usage 730.179 MB -> 123.647 MB. [2025-07-01T18:19:56.596Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1391.646 ms) ====== [2025-07-01T18:19:56.596Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-07-01T18:19:56.596Z] GC before operation: completed in 74.261 ms, heap usage 285.985 MB -> 122.478 MB. [2025-07-01T18:19:57.721Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1259.335 ms) ====== [2025-07-01T18:19:57.721Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-07-01T18:19:57.721Z] GC before operation: completed in 81.279 ms, heap usage 492.810 MB -> 123.303 MB. [2025-07-01T18:19:59.467Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1374.937 ms) ====== [2025-07-01T18:19:59.467Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-07-01T18:19:59.467Z] GC before operation: completed in 78.272 ms, heap usage 473.588 MB -> 123.424 MB. [2025-07-01T18:20:00.670Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1375.658 ms) ====== [2025-07-01T18:20:00.670Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-07-01T18:20:00.670Z] GC before operation: completed in 78.108 ms, heap usage 489.537 MB -> 123.675 MB. [2025-07-01T18:20:01.788Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1328.741 ms) ====== [2025-07-01T18:20:01.788Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-07-01T18:20:01.788Z] GC before operation: completed in 80.302 ms, heap usage 638.166 MB -> 124.294 MB. [2025-07-01T18:20:03.475Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1363.411 ms) ====== [2025-07-01T18:20:03.475Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-07-01T18:20:03.475Z] GC before operation: completed in 80.754 ms, heap usage 416.992 MB -> 123.839 MB. [2025-07-01T18:20:04.596Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1260.619 ms) ====== [2025-07-01T18:20:04.596Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-07-01T18:20:04.596Z] GC before operation: completed in 81.938 ms, heap usage 655.478 MB -> 124.797 MB. [2025-07-01T18:20:06.306Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1392.379 ms) ====== [2025-07-01T18:20:06.306Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-07-01T18:20:06.306Z] GC before operation: completed in 81.531 ms, heap usage 713.476 MB -> 125.171 MB. [2025-07-01T18:20:07.504Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1397.187 ms) ====== [2025-07-01T18:20:07.504Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-07-01T18:20:07.876Z] GC before operation: completed in 82.010 ms, heap usage 707.667 MB -> 125.392 MB. [2025-07-01T18:20:09.057Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1440.482 ms) ====== [2025-07-01T18:20:09.057Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-07-01T18:20:09.057Z] GC before operation: completed in 82.115 ms, heap usage 489.532 MB -> 124.924 MB. [2025-07-01T18:20:10.903Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1321.498 ms) ====== [2025-07-01T18:20:10.903Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-07-01T18:20:10.903Z] GC before operation: completed in 95.620 ms, heap usage 624.563 MB -> 125.576 MB. [2025-07-01T18:20:12.042Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1287.860 ms) ====== [2025-07-01T18:20:12.366Z] ----------------------------------- [2025-07-01T18:20:12.366Z] renaissance-log-regression_0_PASSED [2025-07-01T18:20:12.366Z] ----------------------------------- [2025-07-01T18:20:12.366Z] [2025-07-01T18:20:12.366Z] TEST TEARDOWN: [2025-07-01T18:20:12.366Z] Nothing to be done for teardown. [2025-07-01T18:20:12.676Z] renaissance-log-regression_0 Finish Time: Tue Jul 1 18:20:12 2025 Epoch Time (ms): 1751394012521