[2025-12-04T00:15:32.970Z] Running test renaissance-log-regression_0 ... [2025-12-04T00:15:32.970Z] =============================================== [2025-12-04T00:15:32.970Z] renaissance-log-regression_0 Start Time: Thu Dec 4 00:15:32 2025 Epoch Time (ms): 1764807332703 [2025-12-04T00:15:32.970Z] variation: NoOptions [2025-12-04T00:15:32.970Z] JVM_OPTIONS: [2025-12-04T00:15:32.970Z] { \ [2025-12-04T00:15:32.970Z] echo ""; echo "TEST SETUP:"; \ [2025-12-04T00:15:32.970Z] echo "Nothing to be done for setup."; \ [2025-12-04T00:15:32.970Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17648055159932/renaissance-log-regression_0"; \ [2025-12-04T00:15:32.970Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17648055159932/renaissance-log-regression_0"; \ [2025-12-04T00:15:32.970Z] echo ""; echo "TESTING:"; \ [2025-12-04T00:15:32.970Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_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_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17648055159932/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-12-04T00:15:32.970Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17648055159932/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-12-04T00:15:32.970Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-12-04T00:15:32.970Z] echo "Nothing to be done for teardown."; \ [2025-12-04T00:15:32.970Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17648055159932/TestTargetResult"; [2025-12-04T00:15:32.970Z] [2025-12-04T00:15:32.970Z] TEST SETUP: [2025-12-04T00:15:32.970Z] Nothing to be done for setup. [2025-12-04T00:15:32.970Z] [2025-12-04T00:15:32.970Z] TESTING: [2025-12-04T00:15:37.580Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-12-04T00:15:39.751Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-12-04T00:15:39.751Z] GC before operation: completed in 41.553 ms, heap usage 75.401 MB -> 34.885 MB. [2025-12-04T00:15:52.710Z] ====== log-regression (apache-spark) [default], iteration 0 completed (11783.248 ms) ====== [2025-12-04T00:15:52.710Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-12-04T00:15:52.710Z] GC before operation: completed in 107.934 ms, heap usage 388.478 MB -> 116.906 MB. [2025-12-04T00:15:54.886Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3116.645 ms) ====== [2025-12-04T00:15:54.886Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-12-04T00:15:54.886Z] GC before operation: completed in 90.688 ms, heap usage 271.431 MB -> 116.930 MB. [2025-12-04T00:15:57.748Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2672.283 ms) ====== [2025-12-04T00:15:57.749Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-12-04T00:15:57.749Z] GC before operation: completed in 80.020 ms, heap usage 755.771 MB -> 118.981 MB. [2025-12-04T00:16:00.598Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2513.121 ms) ====== [2025-12-04T00:16:00.598Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-12-04T00:16:00.598Z] GC before operation: completed in 73.663 ms, heap usage 427.427 MB -> 118.270 MB. [2025-12-04T00:16:02.776Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2292.010 ms) ====== [2025-12-04T00:16:02.776Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-12-04T00:16:02.776Z] GC before operation: completed in 85.903 ms, heap usage 272.138 MB -> 117.988 MB. [2025-12-04T00:16:04.961Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2143.254 ms) ====== [2025-12-04T00:16:04.961Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-12-04T00:16:04.961Z] GC before operation: completed in 89.409 ms, heap usage 289.150 MB -> 118.216 MB. [2025-12-04T00:16:07.130Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1948.676 ms) ====== [2025-12-04T00:16:07.130Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-12-04T00:16:07.130Z] GC before operation: completed in 99.998 ms, heap usage 602.482 MB -> 119.685 MB. [2025-12-04T00:16:09.318Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2261.539 ms) ====== [2025-12-04T00:16:09.318Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-12-04T00:16:09.318Z] GC before operation: completed in 109.336 ms, heap usage 334.355 MB -> 118.816 MB. [2025-12-04T00:16:11.563Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2239.510 ms) ====== [2025-12-04T00:16:11.563Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-12-04T00:16:11.563Z] GC before operation: completed in 88.858 ms, heap usage 147.887 MB -> 118.529 MB. [2025-12-04T00:16:13.742Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2177.889 ms) ====== [2025-12-04T00:16:13.742Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-12-04T00:16:13.742Z] GC before operation: completed in 91.090 ms, heap usage 321.057 MB -> 119.067 MB. [2025-12-04T00:16:15.921Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1906.973 ms) ====== [2025-12-04T00:16:15.921Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-12-04T00:16:15.921Z] GC before operation: completed in 87.355 ms, heap usage 310.626 MB -> 119.254 MB. [2025-12-04T00:16:18.092Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1924.892 ms) ====== [2025-12-04T00:16:18.092Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-12-04T00:16:18.092Z] GC before operation: completed in 97.632 ms, heap usage 255.257 MB -> 119.213 MB. [2025-12-04T00:16:20.279Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2163.694 ms) ====== [2025-12-04T00:16:20.279Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-12-04T00:16:20.279Z] GC before operation: completed in 98.305 ms, heap usage 534.810 MB -> 120.525 MB. [2025-12-04T00:16:21.875Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1922.994 ms) ====== [2025-12-04T00:16:21.875Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-12-04T00:16:22.189Z] GC before operation: completed in 97.916 ms, heap usage 274.954 MB -> 119.659 MB. [2025-12-04T00:16:23.785Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1844.743 ms) ====== [2025-12-04T00:16:23.785Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-12-04T00:16:24.098Z] GC before operation: completed in 110.115 ms, heap usage 342.960 MB -> 120.084 MB. [2025-12-04T00:16:26.281Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1915.773 ms) ====== [2025-12-04T00:16:26.281Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-12-04T00:16:26.281Z] GC before operation: completed in 99.104 ms, heap usage 416.252 MB -> 120.622 MB. [2025-12-04T00:16:27.903Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1896.722 ms) ====== [2025-12-04T00:16:27.903Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-12-04T00:16:28.216Z] GC before operation: completed in 100.076 ms, heap usage 300.916 MB -> 120.312 MB. [2025-12-04T00:16:29.826Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1770.526 ms) ====== [2025-12-04T00:16:29.826Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-12-04T00:16:29.826Z] GC before operation: completed in 100.823 ms, heap usage 541.247 MB -> 121.481 MB. [2025-12-04T00:16:32.046Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1922.413 ms) ====== [2025-12-04T00:16:32.046Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-12-04T00:16:32.046Z] GC before operation: completed in 95.452 ms, heap usage 342.483 MB -> 120.834 MB. [2025-12-04T00:16:34.226Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1907.130 ms) ====== [2025-12-04T00:16:34.226Z] ----------------------------------- [2025-12-04T00:16:34.226Z] renaissance-log-regression_0_PASSED [2025-12-04T00:16:34.226Z] ----------------------------------- [2025-12-04T00:16:34.226Z] [2025-12-04T00:16:34.226Z] TEST TEARDOWN: [2025-12-04T00:16:34.226Z] Nothing to be done for teardown. [2025-12-04T00:16:34.226Z] renaissance-log-regression_0 Finish Time: Thu Dec 4 00:16:34 2025 Epoch Time (ms): 1764807394003