[2025-07-01T04:23:06.789Z] Running test renaissance-log-regression_0 ... [2025-07-01T04:23:06.789Z] =============================================== [2025-07-01T04:23:06.789Z] renaissance-log-regression_0 Start Time: Tue Jul 1 04:23:06 2025 Epoch Time (ms): 1751343786304 [2025-07-01T04:23:06.789Z] variation: NoOptions [2025-07-01T04:23:06.789Z] JVM_OPTIONS: [2025-07-01T04:23:06.789Z] { \ [2025-07-01T04:23:06.789Z] echo ""; echo "TEST SETUP:"; \ [2025-07-01T04:23:06.789Z] echo "Nothing to be done for setup."; \ [2025-07-01T04:23:06.789Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17513428052603/renaissance-log-regression_0"; \ [2025-07-01T04:23:06.789Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17513428052603/renaissance-log-regression_0"; \ [2025-07-01T04:23:06.789Z] echo ""; echo "TESTING:"; \ [2025-07-01T04:23:06.789Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/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 "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17513428052603/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-07-01T04:23:06.789Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17513428052603/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-01T04:23:06.789Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-01T04:23:06.789Z] echo "Nothing to be done for teardown."; \ [2025-07-01T04:23:06.789Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17513428052603/TestTargetResult"; [2025-07-01T04:23:06.789Z] [2025-07-01T04:23:06.789Z] TEST SETUP: [2025-07-01T04:23:06.789Z] Nothing to be done for setup. [2025-07-01T04:23:06.789Z] [2025-07-01T04:23:06.789Z] TESTING: [2025-07-01T04:23:14.495Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-07-01T04:23:16.963Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-07-01T04:23:17.333Z] GC before operation: completed in 60.712 ms, heap usage 52.976 MB -> 35.060 MB. [2025-07-01T04:23:34.216Z] ====== log-regression (apache-spark) [default], iteration 0 completed (15808.483 ms) ====== [2025-07-01T04:23:34.216Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-07-01T04:23:34.216Z] GC before operation: completed in 102.680 ms, heap usage 532.884 MB -> 120.364 MB. [2025-07-01T04:23:36.808Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3416.815 ms) ====== [2025-07-01T04:23:36.808Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-07-01T04:23:36.808Z] GC before operation: completed in 77.156 ms, heap usage 719.362 MB -> 121.468 MB. [2025-07-01T04:23:39.373Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2833.882 ms) ====== [2025-07-01T04:23:39.373Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-07-01T04:23:39.373Z] GC before operation: completed in 86.910 ms, heap usage 380.888 MB -> 120.754 MB. [2025-07-01T04:23:41.891Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2474.332 ms) ====== [2025-07-01T04:23:41.891Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-07-01T04:23:41.891Z] GC before operation: completed in 84.643 ms, heap usage 301.353 MB -> 120.788 MB. [2025-07-01T04:23:44.409Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2328.257 ms) ====== [2025-07-01T04:23:44.409Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-07-01T04:23:44.409Z] GC before operation: completed in 89.790 ms, heap usage 164.992 MB -> 120.831 MB. [2025-07-01T04:23:46.931Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2111.902 ms) ====== [2025-07-01T04:23:46.931Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-07-01T04:23:46.931Z] GC before operation: completed in 85.536 ms, heap usage 146.151 MB -> 122.347 MB. [2025-07-01T04:23:48.812Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2130.261 ms) ====== [2025-07-01T04:23:48.812Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-07-01T04:23:48.812Z] GC before operation: completed in 92.642 ms, heap usage 835.806 MB -> 123.056 MB. [2025-07-01T04:23:51.330Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2110.588 ms) ====== [2025-07-01T04:23:51.330Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-07-01T04:23:51.330Z] GC before operation: completed in 90.950 ms, heap usage 344.245 MB -> 121.810 MB. [2025-07-01T04:23:53.217Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2066.696 ms) ====== [2025-07-01T04:23:53.217Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-07-01T04:23:53.217Z] GC before operation: completed in 91.574 ms, heap usage 254.815 MB -> 121.801 MB. [2025-07-01T04:23:55.770Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2141.316 ms) ====== [2025-07-01T04:23:55.770Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-07-01T04:23:55.770Z] GC before operation: completed in 89.980 ms, heap usage 365.723 MB -> 122.372 MB. [2025-07-01T04:23:57.690Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2007.698 ms) ====== [2025-07-01T04:23:57.690Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-07-01T04:23:57.690Z] GC before operation: completed in 93.346 ms, heap usage 294.108 MB -> 122.402 MB. [2025-07-01T04:23:59.591Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2030.056 ms) ====== [2025-07-01T04:23:59.591Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-07-01T04:23:59.591Z] GC before operation: completed in 111.479 ms, heap usage 868.652 MB -> 124.152 MB. [2025-07-01T04:24:02.110Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2062.576 ms) ====== [2025-07-01T04:24:02.111Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-07-01T04:24:02.111Z] GC before operation: completed in 95.341 ms, heap usage 354.764 MB -> 122.990 MB. [2025-07-01T04:24:04.060Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1986.035 ms) ====== [2025-07-01T04:24:04.060Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-07-01T04:24:04.060Z] GC before operation: completed in 96.416 ms, heap usage 590.076 MB -> 123.953 MB. [2025-07-01T04:24:05.933Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1964.124 ms) ====== [2025-07-01T04:24:05.933Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-07-01T04:24:05.933Z] GC before operation: completed in 99.894 ms, heap usage 266.048 MB -> 123.022 MB. [2025-07-01T04:24:07.864Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1952.211 ms) ====== [2025-07-01T04:24:07.864Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-07-01T04:24:08.261Z] GC before operation: completed in 105.547 ms, heap usage 310.365 MB -> 123.406 MB. [2025-07-01T04:24:10.170Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1914.941 ms) ====== [2025-07-01T04:24:10.170Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-07-01T04:24:10.170Z] GC before operation: completed in 97.950 ms, heap usage 707.219 MB -> 124.828 MB. [2025-07-01T04:24:12.130Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1847.080 ms) ====== [2025-07-01T04:24:12.130Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-07-01T04:24:12.130Z] GC before operation: completed in 106.413 ms, heap usage 248.240 MB -> 123.658 MB. [2025-07-01T04:24:14.062Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1888.694 ms) ====== [2025-07-01T04:24:14.062Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-07-01T04:24:14.062Z] GC before operation: completed in 104.242 ms, heap usage 146.095 MB -> 123.837 MB. [2025-07-01T04:24:16.581Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2071.830 ms) ====== [2025-07-01T04:24:16.581Z] ----------------------------------- [2025-07-01T04:24:16.581Z] renaissance-log-regression_0_PASSED [2025-07-01T04:24:16.581Z] ----------------------------------- [2025-07-01T04:24:16.581Z] [2025-07-01T04:24:16.581Z] TEST TEARDOWN: [2025-07-01T04:24:16.581Z] Nothing to be done for teardown. [2025-07-01T04:24:16.581Z] renaissance-log-regression_0 Finish Time: Tue Jul 1 04:24:16 2025 Epoch Time (ms): 1751343856298