[2025-09-03T22:20:20.113Z] Running test renaissance-log-regression_0 ... [2025-09-03T22:20:20.113Z] =============================================== [2025-09-03T22:20:20.113Z] renaissance-log-regression_0 Start Time: Wed Sep 3 22:20:19 2025 Epoch Time (ms): 1756938019454 [2025-09-03T22:20:20.113Z] variation: NoOptions [2025-09-03T22:20:20.113Z] JVM_OPTIONS: [2025-09-03T22:20:20.113Z] { \ [2025-09-03T22:20:20.113Z] echo ""; echo "TEST SETUP:"; \ [2025-09-03T22:20:20.113Z] echo "Nothing to be done for setup."; \ [2025-09-03T22:20:20.113Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17569367597953/renaissance-log-regression_0"; \ [2025-09-03T22:20:20.113Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17569367597953/renaissance-log-regression_0"; \ [2025-09-03T22:20:20.113Z] echo ""; echo "TESTING:"; \ [2025-09-03T22:20:20.113Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_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_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17569367597953/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-09-03T22:20:20.113Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17569367597953/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-03T22:20:20.113Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-03T22:20:20.113Z] echo "Nothing to be done for teardown."; \ [2025-09-03T22:20:20.113Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17569367597953/TestTargetResult"; [2025-09-03T22:20:20.113Z] [2025-09-03T22:20:20.113Z] TEST SETUP: [2025-09-03T22:20:20.113Z] Nothing to be done for setup. [2025-09-03T22:20:20.113Z] [2025-09-03T22:20:20.113Z] TESTING: [2025-09-03T22:20:25.442Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-09-03T22:20:27.380Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-09-03T22:20:27.380Z] GC before operation: completed in 57.788 ms, heap usage 53.485 MB -> 35.211 MB. [2025-09-03T22:20:38.832Z] ====== log-regression (apache-spark) [default], iteration 0 completed (10276.525 ms) ====== [2025-09-03T22:20:38.832Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-09-03T22:20:38.832Z] GC before operation: completed in 72.810 ms, heap usage 286.050 MB -> 119.666 MB. [2025-09-03T22:20:40.770Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2398.353 ms) ====== [2025-09-03T22:20:40.770Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-09-03T22:20:40.770Z] GC before operation: completed in 72.819 ms, heap usage 340.740 MB -> 120.420 MB. [2025-09-03T22:20:42.706Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1884.089 ms) ====== [2025-09-03T22:20:42.706Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-09-03T22:20:42.706Z] GC before operation: completed in 77.421 ms, heap usage 239.127 MB -> 120.543 MB. [2025-09-03T22:20:43.649Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1596.761 ms) ====== [2025-09-03T22:20:43.649Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-09-03T22:20:43.649Z] GC before operation: completed in 94.459 ms, heap usage 746.541 MB -> 122.395 MB. [2025-09-03T22:20:45.597Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1605.675 ms) ====== [2025-09-03T22:20:45.597Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-09-03T22:20:45.597Z] GC before operation: completed in 86.001 ms, heap usage 476.166 MB -> 121.798 MB. [2025-09-03T22:20:47.534Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1538.324 ms) ====== [2025-09-03T22:20:47.534Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-09-03T22:20:47.534Z] GC before operation: completed in 81.054 ms, heap usage 244.198 MB -> 121.350 MB. [2025-09-03T22:20:49.479Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1581.848 ms) ====== [2025-09-03T22:20:49.480Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-09-03T22:20:49.480Z] GC before operation: completed in 85.560 ms, heap usage 433.603 MB -> 122.672 MB. [2025-09-03T22:20:50.424Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1486.488 ms) ====== [2025-09-03T22:20:50.424Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-09-03T22:20:50.424Z] GC before operation: completed in 82.254 ms, heap usage 475.978 MB -> 122.468 MB. [2025-09-03T22:20:52.363Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1504.477 ms) ====== [2025-09-03T22:20:52.363Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-09-03T22:20:52.363Z] GC before operation: completed in 89.731 ms, heap usage 313.713 MB -> 122.086 MB. [2025-09-03T22:20:54.304Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1566.563 ms) ====== [2025-09-03T22:20:54.304Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-09-03T22:20:54.304Z] GC before operation: completed in 90.020 ms, heap usage 520.079 MB -> 122.973 MB. [2025-09-03T22:20:55.250Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1461.677 ms) ====== [2025-09-03T22:20:55.250Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-09-03T22:20:55.250Z] GC before operation: completed in 93.814 ms, heap usage 463.086 MB -> 123.005 MB. [2025-09-03T22:20:57.193Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1553.200 ms) ====== [2025-09-03T22:20:57.194Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-09-03T22:20:57.194Z] GC before operation: completed in 101.082 ms, heap usage 421.696 MB -> 123.083 MB. [2025-09-03T22:20:59.131Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1506.081 ms) ====== [2025-09-03T22:20:59.131Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-09-03T22:20:59.131Z] GC before operation: completed in 98.988 ms, heap usage 622.600 MB -> 123.919 MB. [2025-09-03T22:21:00.075Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1493.992 ms) ====== [2025-09-03T22:21:00.075Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-09-03T22:21:00.075Z] GC before operation: completed in 93.676 ms, heap usage 937.880 MB -> 124.648 MB. [2025-09-03T22:21:02.030Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1427.928 ms) ====== [2025-09-03T22:21:02.030Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-09-03T22:21:02.030Z] GC before operation: completed in 99.437 ms, heap usage 534.715 MB -> 123.997 MB. [2025-09-03T22:21:02.978Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1497.153 ms) ====== [2025-09-03T22:21:02.978Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-09-03T22:21:02.978Z] GC before operation: completed in 97.940 ms, heap usage 740.466 MB -> 124.788 MB. [2025-09-03T22:21:05.162Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1502.893 ms) ====== [2025-09-03T22:21:05.162Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-09-03T22:21:05.162Z] GC before operation: completed in 99.477 ms, heap usage 251.757 MB -> 123.553 MB. [2025-09-03T22:21:06.115Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1427.621 ms) ====== [2025-09-03T22:21:06.115Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-09-03T22:21:06.115Z] GC before operation: completed in 97.059 ms, heap usage 549.429 MB -> 124.685 MB. [2025-09-03T22:21:08.050Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1528.191 ms) ====== [2025-09-03T22:21:08.051Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-09-03T22:21:08.051Z] GC before operation: completed in 96.121 ms, heap usage 143.012 MB -> 123.926 MB. [2025-09-03T22:21:09.990Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1485.311 ms) ====== [2025-09-03T22:21:09.990Z] ----------------------------------- [2025-09-03T22:21:09.990Z] renaissance-log-regression_0_PASSED [2025-09-03T22:21:09.990Z] ----------------------------------- [2025-09-03T22:21:09.990Z] [2025-09-03T22:21:09.990Z] TEST TEARDOWN: [2025-09-03T22:21:09.990Z] Nothing to be done for teardown. [2025-09-03T22:21:09.990Z] renaissance-log-regression_0 Finish Time: Wed Sep 3 22:21:09 2025 Epoch Time (ms): 1756938069369