[2025-12-03T22:42:51.098Z] Running test renaissance-log-regression_0 ... [2025-12-03T22:42:51.099Z] =============================================== [2025-12-03T22:42:51.099Z] renaissance-log-regression_0 Start Time: Wed Dec 3 22:42:50 2025 Epoch Time (ms): 1764801770793 [2025-12-03T22:42:51.099Z] variation: NoOptions [2025-12-03T22:42:51.099Z] JVM_OPTIONS: [2025-12-03T22:42:51.099Z] { \ [2025-12-03T22:42:51.099Z] echo ""; echo "TEST SETUP:"; \ [2025-12-03T22:42:51.099Z] echo "Nothing to be done for setup."; \ [2025-12-03T22:42:51.099Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17648003548474/renaissance-log-regression_0"; \ [2025-12-03T22:42:51.099Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17648003548474/renaissance-log-regression_0"; \ [2025-12-03T22:42:51.099Z] echo ""; echo "TESTING:"; \ [2025-12-03T22:42:51.099Z] "/home/jenkins/workspace/Test_openjdk21_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_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17648003548474/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-12-03T22:42:51.099Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17648003548474/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-03T22:42:51.099Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-03T22:42:51.099Z] echo "Nothing to be done for teardown."; \ [2025-12-03T22:42:51.099Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17648003548474/TestTargetResult"; [2025-12-03T22:42:51.099Z] [2025-12-03T22:42:51.099Z] TEST SETUP: [2025-12-03T22:42:51.099Z] Nothing to be done for setup. [2025-12-03T22:42:51.099Z] [2025-12-03T22:42:51.099Z] TESTING: [2025-12-03T22:42:57.858Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-12-03T22:43:01.029Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-12-03T22:43:01.029Z] GC before operation: completed in 57.422 ms, heap usage 69.677 MB -> 35.195 MB. [2025-12-03T22:43:14.760Z] ====== log-regression (apache-spark) [default], iteration 0 completed (12865.516 ms) ====== [2025-12-03T22:43:14.760Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-12-03T22:43:14.760Z] GC before operation: completed in 104.812 ms, heap usage 596.132 MB -> 120.545 MB. [2025-12-03T22:43:16.905Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2778.168 ms) ====== [2025-12-03T22:43:16.905Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-12-03T22:43:16.905Z] GC before operation: completed in 100.977 ms, heap usage 613.038 MB -> 121.263 MB. [2025-12-03T22:43:18.888Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2148.959 ms) ====== [2025-12-03T22:43:18.888Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-12-03T22:43:18.888Z] GC before operation: completed in 106.054 ms, heap usage 696.508 MB -> 121.938 MB. [2025-12-03T22:43:20.860Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1948.636 ms) ====== [2025-12-03T22:43:20.860Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-12-03T22:43:20.860Z] GC before operation: completed in 108.448 ms, heap usage 484.037 MB -> 121.532 MB. [2025-12-03T22:43:22.955Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1842.125 ms) ====== [2025-12-03T22:43:22.955Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-12-03T22:43:22.955Z] GC before operation: completed in 117.710 ms, heap usage 673.040 MB -> 122.369 MB. [2025-12-03T22:43:23.911Z] 22:43:23.487 WARN [block-manager-storage-async-thread-pool-3] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_254, which does not exist [2025-12-03T22:43:24.866Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1843.201 ms) ====== [2025-12-03T22:43:24.866Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-12-03T22:43:24.866Z] GC before operation: completed in 108.830 ms, heap usage 615.499 MB -> 122.429 MB. [2025-12-03T22:43:27.375Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1911.626 ms) ====== [2025-12-03T22:43:27.375Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-12-03T22:43:27.375Z] GC before operation: completed in 121.837 ms, heap usage 549.567 MB -> 122.504 MB. [2025-12-03T22:43:28.350Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1616.766 ms) ====== [2025-12-03T22:43:28.350Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-12-03T22:43:28.350Z] GC before operation: completed in 113.771 ms, heap usage 582.658 MB -> 122.899 MB. [2025-12-03T22:43:30.438Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1801.449 ms) ====== [2025-12-03T22:43:30.438Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-12-03T22:43:30.438Z] GC before operation: completed in 141.484 ms, heap usage 324.015 MB -> 122.126 MB. [2025-12-03T22:43:32.413Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2129.654 ms) ====== [2025-12-03T22:43:32.413Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-12-03T22:43:33.394Z] GC before operation: completed in 158.728 ms, heap usage 330.513 MB -> 122.472 MB. [2025-12-03T22:43:34.364Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1845.701 ms) ====== [2025-12-03T22:43:34.364Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-12-03T22:43:35.860Z] GC before operation: completed in 133.142 ms, heap usage 496.053 MB -> 123.090 MB. [2025-12-03T22:43:36.827Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1551.444 ms) ====== [2025-12-03T22:43:36.827Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-12-03T22:43:36.827Z] GC before operation: completed in 142.630 ms, heap usage 262.285 MB -> 122.529 MB. [2025-12-03T22:43:37.931Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1616.061 ms) ====== [2025-12-03T22:43:37.931Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-12-03T22:43:37.931Z] GC before operation: completed in 135.292 ms, heap usage 439.143 MB -> 123.457 MB. [2025-12-03T22:43:40.069Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1584.321 ms) ====== [2025-12-03T22:43:40.069Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-12-03T22:43:40.069Z] GC before operation: completed in 132.534 ms, heap usage 285.189 MB -> 123.123 MB. [2025-12-03T22:43:42.096Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1674.786 ms) ====== [2025-12-03T22:43:42.096Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-12-03T22:43:42.096Z] GC before operation: completed in 146.253 ms, heap usage 570.881 MB -> 124.264 MB. [2025-12-03T22:43:43.055Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1602.097 ms) ====== [2025-12-03T22:43:43.055Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-12-03T22:43:44.012Z] GC before operation: completed in 125.015 ms, heap usage 501.041 MB -> 124.125 MB. [2025-12-03T22:43:44.970Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1402.614 ms) ====== [2025-12-03T22:43:44.970Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-12-03T22:43:44.970Z] GC before operation: completed in 120.031 ms, heap usage 389.477 MB -> 124.061 MB. [2025-12-03T22:43:46.943Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1264.762 ms) ====== [2025-12-03T22:43:46.943Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-12-03T22:43:46.943Z] GC before operation: completed in 120.881 ms, heap usage 430.696 MB -> 124.379 MB. [2025-12-03T22:43:47.898Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1268.927 ms) ====== [2025-12-03T22:43:47.898Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-12-03T22:43:47.898Z] GC before operation: completed in 129.839 ms, heap usage 721.968 MB -> 125.504 MB. [2025-12-03T22:43:48.855Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1244.664 ms) ====== [2025-12-03T22:43:49.811Z] ----------------------------------- [2025-12-03T22:43:49.811Z] renaissance-log-regression_0_PASSED [2025-12-03T22:43:49.811Z] ----------------------------------- [2025-12-03T22:43:49.811Z] [2025-12-03T22:43:49.811Z] TEST TEARDOWN: [2025-12-03T22:43:49.811Z] Nothing to be done for teardown. [2025-12-03T22:43:49.811Z] renaissance-log-regression_0 Finish Time: Wed Dec 3 22:43:49 2025 Epoch Time (ms): 1764801829057