[2025-11-20T02:06:29.374Z] Running test renaissance-log-regression_0 ... [2025-11-20T02:06:29.374Z] =============================================== [2025-11-20T02:06:29.374Z] renaissance-log-regression_0 Start Time: Thu Nov 20 02:06:29 2025 Epoch Time (ms): 1763604389313 [2025-11-20T02:06:29.374Z] variation: NoOptions [2025-11-20T02:06:29.374Z] JVM_OPTIONS: [2025-11-20T02:06:29.374Z] { \ [2025-11-20T02:06:29.374Z] echo ""; echo "TEST SETUP:"; \ [2025-11-20T02:06:29.374Z] echo "Nothing to be done for setup."; \ [2025-11-20T02:06:29.374Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17636025014975/renaissance-log-regression_0"; \ [2025-11-20T02:06:29.374Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17636025014975/renaissance-log-regression_0"; \ [2025-11-20T02:06:29.374Z] echo ""; echo "TESTING:"; \ [2025-11-20T02:06:29.374Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/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_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17636025014975/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-11-20T02:06:29.374Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17636025014975/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-20T02:06:29.374Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-20T02:06:29.374Z] echo "Nothing to be done for teardown."; \ [2025-11-20T02:06:29.374Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17636025014975/TestTargetResult"; [2025-11-20T02:06:29.374Z] [2025-11-20T02:06:29.374Z] TEST SETUP: [2025-11-20T02:06:29.374Z] Nothing to be done for setup. [2025-11-20T02:06:29.374Z] [2025-11-20T02:06:29.374Z] TESTING: [2025-11-20T02:06:37.012Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-11-20T02:06:41.057Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-11-20T02:06:41.057Z] GC before operation: completed in 55.714 ms, heap usage 41.845 MB -> 34.879 MB. [2025-11-20T02:06:57.697Z] ====== log-regression (apache-spark) [default], iteration 0 completed (15979.487 ms) ====== [2025-11-20T02:06:57.697Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-11-20T02:06:57.697Z] GC before operation: completed in 96.188 ms, heap usage 441.642 MB -> 119.812 MB. [2025-11-20T02:07:00.209Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3545.764 ms) ====== [2025-11-20T02:07:00.209Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-11-20T02:07:00.209Z] GC before operation: completed in 73.192 ms, heap usage 323.460 MB -> 120.037 MB. [2025-11-20T02:07:02.734Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2714.250 ms) ====== [2025-11-20T02:07:02.734Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-11-20T02:07:03.119Z] GC before operation: completed in 86.989 ms, heap usage 391.325 MB -> 120.631 MB. [2025-11-20T02:07:05.624Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2409.228 ms) ====== [2025-11-20T02:07:05.624Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-11-20T02:07:05.624Z] GC before operation: completed in 80.017 ms, heap usage 309.001 MB -> 120.792 MB. [2025-11-20T02:07:08.139Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2325.702 ms) ====== [2025-11-20T02:07:08.139Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-11-20T02:07:08.139Z] GC before operation: completed in 81.921 ms, heap usage 452.598 MB -> 121.319 MB. [2025-11-20T02:07:09.993Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2082.581 ms) ====== [2025-11-20T02:07:09.993Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-11-20T02:07:09.993Z] GC before operation: completed in 82.779 ms, heap usage 513.812 MB -> 121.766 MB. [2025-11-20T02:07:12.513Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2081.181 ms) ====== [2025-11-20T02:07:12.513Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-11-20T02:07:12.513Z] GC before operation: completed in 86.259 ms, heap usage 288.588 MB -> 121.280 MB. [2025-11-20T02:07:14.399Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2026.740 ms) ====== [2025-11-20T02:07:14.399Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-11-20T02:07:14.399Z] GC before operation: completed in 83.906 ms, heap usage 275.947 MB -> 121.470 MB. [2025-11-20T02:07:16.409Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1981.482 ms) ====== [2025-11-20T02:07:16.409Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-11-20T02:07:16.409Z] GC before operation: completed in 83.522 ms, heap usage 258.496 MB -> 121.693 MB. [2025-11-20T02:07:18.326Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1952.843 ms) ====== [2025-11-20T02:07:18.326Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-11-20T02:07:18.326Z] GC before operation: completed in 90.351 ms, heap usage 276.362 MB -> 121.880 MB. [2025-11-20T02:07:20.818Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2092.698 ms) ====== [2025-11-20T02:07:20.818Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-11-20T02:07:20.818Z] GC before operation: completed in 87.175 ms, heap usage 256.537 MB -> 122.046 MB. [2025-11-20T02:07:22.699Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2001.358 ms) ====== [2025-11-20T02:07:22.699Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-11-20T02:07:22.699Z] GC before operation: completed in 89.679 ms, heap usage 371.863 MB -> 122.591 MB. [2025-11-20T02:07:24.765Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1928.621 ms) ====== [2025-11-20T02:07:24.765Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-11-20T02:07:24.765Z] GC before operation: completed in 90.895 ms, heap usage 286.261 MB -> 122.445 MB. [2025-11-20T02:07:26.669Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1915.652 ms) ====== [2025-11-20T02:07:26.669Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-11-20T02:07:26.669Z] GC before operation: completed in 84.960 ms, heap usage 248.900 MB -> 122.673 MB. [2025-11-20T02:07:28.607Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1963.292 ms) ====== [2025-11-20T02:07:28.607Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-11-20T02:07:28.607Z] GC before operation: completed in 92.146 ms, heap usage 430.504 MB -> 123.363 MB. [2025-11-20T02:07:31.198Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1983.814 ms) ====== [2025-11-20T02:07:31.198Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-11-20T02:07:31.198Z] GC before operation: completed in 88.550 ms, heap usage 264.619 MB -> 123.181 MB. [2025-11-20T02:07:33.059Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1996.371 ms) ====== [2025-11-20T02:07:33.059Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-11-20T02:07:33.059Z] GC before operation: completed in 88.036 ms, heap usage 290.607 MB -> 123.450 MB. [2025-11-20T02:07:34.928Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1843.649 ms) ====== [2025-11-20T02:07:34.928Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-11-20T02:07:34.928Z] GC before operation: completed in 92.874 ms, heap usage 310.910 MB -> 123.610 MB. [2025-11-20T02:07:36.773Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1922.824 ms) ====== [2025-11-20T02:07:36.773Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-11-20T02:07:36.773Z] GC before operation: completed in 92.563 ms, heap usage 483.815 MB -> 124.330 MB. [2025-11-20T02:07:39.245Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2181.682 ms) ====== [2025-11-20T02:07:39.245Z] ----------------------------------- [2025-11-20T02:07:39.246Z] renaissance-log-regression_0_PASSED [2025-11-20T02:07:39.246Z] ----------------------------------- [2025-11-20T02:07:39.246Z] [2025-11-20T02:07:39.246Z] TEST TEARDOWN: [2025-11-20T02:07:39.246Z] Nothing to be done for teardown. [2025-11-20T02:07:39.246Z] renaissance-log-regression_0 Finish Time: Thu Nov 20 02:07:39 2025 Epoch Time (ms): 1763604459175