[2025-09-24T21:03:43.731Z] Running test renaissance-log-regression_0 ... [2025-09-24T21:03:43.731Z] =============================================== [2025-09-24T21:03:43.731Z] renaissance-log-regression_0 Start Time: Wed Sep 24 21:02:32 2025 Epoch Time (ms): 1758747752183 [2025-09-24T21:03:43.731Z] variation: NoOptions [2025-09-24T21:03:43.731Z] JVM_OPTIONS: [2025-09-24T21:03:43.731Z] { \ [2025-09-24T21:03:43.731Z] echo ""; echo "TEST SETUP:"; \ [2025-09-24T21:03:43.731Z] echo "Nothing to be done for setup."; \ [2025-09-24T21:03:43.731Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17587465987135/renaissance-log-regression_0"; \ [2025-09-24T21:03:43.731Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17587465987135/renaissance-log-regression_0"; \ [2025-09-24T21:03:43.731Z] echo ""; echo "TESTING:"; \ [2025-09-24T21:03:43.731Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/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_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17587465987135/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-09-24T21:03:43.731Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17587465987135/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-09-24T21:03:43.731Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-09-24T21:03:43.731Z] echo "Nothing to be done for teardown."; \ [2025-09-24T21:03:43.731Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17587465987135/TestTargetResult"; [2025-09-24T21:03:43.731Z] [2025-09-24T21:03:43.731Z] TEST SETUP: [2025-09-24T21:03:43.731Z] Nothing to be done for setup. [2025-09-24T21:03:43.731Z] [2025-09-24T21:03:43.731Z] TESTING: [2025-09-24T21:03:43.731Z] NOTE: 'log-regression' benchmark uses Spark local executor with 12 (out of 24) threads. [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-09-24T21:03:43.731Z] GC before operation: completed in 74.493 ms, heap usage 118.510 MB -> 34.853 MB. [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 0 completed (11447.385 ms) ====== [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-09-24T21:03:43.731Z] GC before operation: completed in 123.231 ms, heap usage 370.479 MB -> 132.090 MB. [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1983.568 ms) ====== [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-09-24T21:03:43.731Z] GC before operation: completed in 120.138 ms, heap usage 280.900 MB -> 132.652 MB. [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1756.936 ms) ====== [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-09-24T21:03:43.731Z] GC before operation: completed in 120.814 ms, heap usage 1.137 GB -> 135.581 MB. [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1725.078 ms) ====== [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-09-24T21:03:43.731Z] GC before operation: completed in 113.981 ms, heap usage 420.350 MB -> 133.996 MB. [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1656.903 ms) ====== [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-09-24T21:03:43.731Z] GC before operation: completed in 126.079 ms, heap usage 344.184 MB -> 134.068 MB. [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1632.648 ms) ====== [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-09-24T21:03:43.731Z] GC before operation: completed in 130.685 ms, heap usage 626.568 MB -> 135.331 MB. [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1601.023 ms) ====== [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-09-24T21:03:43.731Z] GC before operation: completed in 126.607 ms, heap usage 283.985 MB -> 134.541 MB. [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1563.370 ms) ====== [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-09-24T21:03:43.731Z] GC before operation: completed in 124.573 ms, heap usage 1.044 GB -> 137.461 MB. [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1561.876 ms) ====== [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-09-24T21:03:43.731Z] GC before operation: completed in 131.231 ms, heap usage 414.253 MB -> 135.943 MB. [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1572.079 ms) ====== [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-09-24T21:03:43.731Z] GC before operation: completed in 126.979 ms, heap usage 639.272 MB -> 136.940 MB. [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1512.347 ms) ====== [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-09-24T21:03:43.731Z] GC before operation: completed in 122.079 ms, heap usage 783.892 MB -> 137.754 MB. [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1529.645 ms) ====== [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-09-24T21:03:43.731Z] GC before operation: completed in 123.919 ms, heap usage 1.071 GB -> 138.945 MB. [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1496.140 ms) ====== [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-09-24T21:03:43.731Z] GC before operation: completed in 129.849 ms, heap usage 344.784 MB -> 137.074 MB. [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1499.556 ms) ====== [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-09-24T21:03:43.731Z] GC before operation: completed in 131.117 ms, heap usage 508.371 MB -> 137.974 MB. [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1481.056 ms) ====== [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-09-24T21:03:43.731Z] GC before operation: completed in 137.977 ms, heap usage 655.104 MB -> 138.885 MB. [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1505.238 ms) ====== [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-09-24T21:03:43.731Z] GC before operation: completed in 134.344 ms, heap usage 286.539 MB -> 138.041 MB. [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1499.171 ms) ====== [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-09-24T21:03:43.731Z] GC before operation: completed in 132.175 ms, heap usage 501.027 MB -> 139.135 MB. [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1482.573 ms) ====== [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-09-24T21:03:43.731Z] GC before operation: completed in 129.062 ms, heap usage 285.777 MB -> 138.819 MB. [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1445.302 ms) ====== [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-09-24T21:03:43.731Z] GC before operation: completed in 137.986 ms, heap usage 986.594 MB -> 141.207 MB. [2025-09-24T21:03:43.731Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1525.650 ms) ====== [2025-09-24T21:03:43.731Z] ----------------------------------- [2025-09-24T21:03:43.731Z] renaissance-log-regression_0_PASSED [2025-09-24T21:03:43.731Z] ----------------------------------- [2025-09-24T21:03:43.731Z] [2025-09-24T21:03:43.731Z] TEST TEARDOWN: [2025-09-24T21:03:43.731Z] Nothing to be done for teardown. [2025-09-24T21:03:43.731Z] renaissance-log-regression_0 Finish Time: Wed Sep 24 21:03:26 2025 Epoch Time (ms): 1758747806712