[2025-06-18T21:25:56.751Z] Running test renaissance-log-regression_0 ... [2025-06-18T21:25:56.751Z] =============================================== [2025-06-18T21:25:56.751Z] renaissance-log-regression_0 Start Time: Wed Jun 18 21:25:55 2025 Epoch Time (ms): 1750281955565 [2025-06-18T21:25:56.751Z] variation: NoOptions [2025-06-18T21:25:56.751Z] JVM_OPTIONS: [2025-06-18T21:25:56.751Z] { \ [2025-06-18T21:25:56.751Z] echo ""; echo "TEST SETUP:"; \ [2025-06-18T21:25:56.751Z] echo "Nothing to be done for setup."; \ [2025-06-18T21:25:56.751Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17502813221287/renaissance-log-regression_0"; \ [2025-06-18T21:25:56.751Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17502813221287/renaissance-log-regression_0"; \ [2025-06-18T21:25:56.751Z] echo ""; echo "TESTING:"; \ [2025-06-18T21:25:56.751Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17502813221287/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-06-18T21:25:56.751Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17502813221287/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-18T21:25:56.751Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-18T21:25:56.751Z] echo "Nothing to be done for teardown."; \ [2025-06-18T21:25:56.751Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux_testList_1/aqa-tests/TKG/../TKG/output_17502813221287/TestTargetResult"; [2025-06-18T21:25:56.751Z] [2025-06-18T21:25:56.751Z] TEST SETUP: [2025-06-18T21:25:56.751Z] Nothing to be done for setup. [2025-06-18T21:25:56.751Z] [2025-06-18T21:25:56.751Z] TESTING: [2025-06-18T21:26:07.091Z] NOTE: 'log-regression' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-06-18T21:26:13.717Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-06-18T21:26:13.717Z] GC before operation: completed in 43.621 ms, heap usage 79.539 MB -> 36.593 MB. [2025-06-18T21:26:31.887Z] ====== log-regression (apache-spark) [default], iteration 0 completed (17134.019 ms) ====== [2025-06-18T21:26:31.887Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-06-18T21:26:31.887Z] GC before operation: completed in 127.160 ms, heap usage 312.290 MB -> 127.659 MB. [2025-06-18T21:26:34.897Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3982.796 ms) ====== [2025-06-18T21:26:34.897Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-06-18T21:26:34.897Z] GC before operation: completed in 112.324 ms, heap usage 236.451 MB -> 128.125 MB. [2025-06-18T21:26:37.880Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3347.007 ms) ====== [2025-06-18T21:26:37.880Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-06-18T21:26:37.880Z] GC before operation: completed in 134.155 ms, heap usage 386.675 MB -> 128.834 MB. [2025-06-18T21:26:40.863Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3077.425 ms) ====== [2025-06-18T21:26:40.863Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-06-18T21:26:41.803Z] GC before operation: completed in 128.996 ms, heap usage 441.391 MB -> 129.415 MB. [2025-06-18T21:26:43.736Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2570.165 ms) ====== [2025-06-18T21:26:43.736Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-06-18T21:26:43.736Z] GC before operation: completed in 142.011 ms, heap usage 287.266 MB -> 129.320 MB. [2025-06-18T21:26:46.717Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2628.553 ms) ====== [2025-06-18T21:26:46.717Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-06-18T21:26:46.717Z] GC before operation: completed in 104.758 ms, heap usage 493.371 MB -> 130.443 MB. [2025-06-18T21:26:48.649Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2182.565 ms) ====== [2025-06-18T21:26:48.649Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-06-18T21:26:49.592Z] GC before operation: completed in 108.412 ms, heap usage 370.766 MB -> 130.412 MB. [2025-06-18T21:26:51.524Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2221.522 ms) ====== [2025-06-18T21:26:51.524Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-06-18T21:26:51.524Z] GC before operation: completed in 125.390 ms, heap usage 389.264 MB -> 130.835 MB. [2025-06-18T21:26:53.568Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2030.969 ms) ====== [2025-06-18T21:26:53.568Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-06-18T21:26:53.568Z] GC before operation: completed in 108.403 ms, heap usage 520.559 MB -> 131.516 MB. [2025-06-18T21:26:56.548Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2318.734 ms) ====== [2025-06-18T21:26:56.549Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-06-18T21:26:56.549Z] GC before operation: completed in 109.538 ms, heap usage 346.258 MB -> 131.563 MB. [2025-06-18T21:26:58.483Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1968.917 ms) ====== [2025-06-18T21:26:58.483Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-06-18T21:26:58.483Z] GC before operation: completed in 111.198 ms, heap usage 362.114 MB -> 131.920 MB. [2025-06-18T21:27:00.412Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1974.267 ms) ====== [2025-06-18T21:27:00.412Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-06-18T21:27:00.412Z] GC before operation: completed in 116.093 ms, heap usage 342.111 MB -> 132.204 MB. [2025-06-18T21:27:02.344Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1981.729 ms) ====== [2025-06-18T21:27:02.344Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-06-18T21:27:02.344Z] GC before operation: completed in 115.681 ms, heap usage 475.471 MB -> 132.920 MB. [2025-06-18T21:27:04.275Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2115.872 ms) ====== [2025-06-18T21:27:04.275Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-06-18T21:27:04.275Z] GC before operation: completed in 121.695 ms, heap usage 355.244 MB -> 132.985 MB. [2025-06-18T21:27:06.218Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1935.425 ms) ====== [2025-06-18T21:27:06.218Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-06-18T21:27:06.218Z] GC before operation: completed in 119.571 ms, heap usage 403.274 MB -> 133.519 MB. [2025-06-18T21:27:08.151Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1932.374 ms) ====== [2025-06-18T21:27:08.151Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-06-18T21:27:09.092Z] GC before operation: completed in 120.247 ms, heap usage 491.082 MB -> 134.103 MB. [2025-06-18T21:27:11.776Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2075.287 ms) ====== [2025-06-18T21:27:11.776Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-06-18T21:27:11.776Z] GC before operation: completed in 124.907 ms, heap usage 602.836 MB -> 134.975 MB. [2025-06-18T21:27:12.717Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2081.900 ms) ====== [2025-06-18T21:27:12.717Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-06-18T21:27:12.717Z] GC before operation: completed in 126.183 ms, heap usage 610.436 MB -> 135.290 MB. [2025-06-18T21:27:14.649Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1828.774 ms) ====== [2025-06-18T21:27:14.649Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-06-18T21:27:14.649Z] GC before operation: completed in 123.270 ms, heap usage 358.364 MB -> 134.957 MB. [2025-06-18T21:27:17.740Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2076.470 ms) ====== [2025-06-18T21:27:17.740Z] ----------------------------------- [2025-06-18T21:27:17.740Z] renaissance-log-regression_0_PASSED [2025-06-18T21:27:17.740Z] ----------------------------------- [2025-06-18T21:27:17.740Z] [2025-06-18T21:27:17.740Z] TEST TEARDOWN: [2025-06-18T21:27:17.740Z] Nothing to be done for teardown. [2025-06-18T21:27:17.740Z] renaissance-log-regression_0 Finish Time: Wed Jun 18 21:27:17 2025 Epoch Time (ms): 1750282037133