[2025-11-05T23:04:01.329Z] Running test renaissance-log-regression_0 ... [2025-11-05T23:04:01.329Z] =============================================== [2025-11-05T23:04:01.329Z] renaissance-log-regression_0 Start Time: Wed Nov 5 23:04:01 2025 Epoch Time (ms): 1762383841110 [2025-11-05T23:04:01.329Z] variation: NoOptions [2025-11-05T23:04:01.329Z] JVM_OPTIONS: [2025-11-05T23:04:01.329Z] { \ [2025-11-05T23:04:01.329Z] echo ""; echo "TEST SETUP:"; \ [2025-11-05T23:04:01.329Z] echo "Nothing to be done for setup."; \ [2025-11-05T23:04:01.329Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17623826958448/renaissance-log-regression_0"; \ [2025-11-05T23:04:01.329Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17623826958448/renaissance-log-regression_0"; \ [2025-11-05T23:04:01.329Z] echo ""; echo "TESTING:"; \ [2025-11-05T23:04:01.329Z] "/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_17623826958448/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-11-05T23:04:01.329Z] 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_17623826958448/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-05T23:04:01.329Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-05T23:04:01.329Z] echo "Nothing to be done for teardown."; \ [2025-11-05T23:04:01.329Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17623826958448/TestTargetResult"; [2025-11-05T23:04:01.329Z] [2025-11-05T23:04:01.329Z] TEST SETUP: [2025-11-05T23:04:01.329Z] Nothing to be done for setup. [2025-11-05T23:04:01.329Z] [2025-11-05T23:04:01.329Z] TESTING: [2025-11-05T23:04:08.284Z] NOTE: 'log-regression' benchmark uses Spark local executor with 12 (out of 24) threads. [2025-11-05T23:04:11.756Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-11-05T23:04:11.756Z] GC before operation: completed in 68.998 ms, heap usage 49.782 MB -> 35.056 MB. [2025-11-05T23:04:23.543Z] ====== log-regression (apache-spark) [default], iteration 0 completed (11700.468 ms) ====== [2025-11-05T23:04:23.543Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-11-05T23:04:24.415Z] GC before operation: completed in 104.523 ms, heap usage 331.075 MB -> 131.916 MB. [2025-11-05T23:04:26.015Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2115.996 ms) ====== [2025-11-05T23:04:26.015Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-11-05T23:04:26.015Z] GC before operation: completed in 100.572 ms, heap usage 386.425 MB -> 132.842 MB. [2025-11-05T23:04:28.515Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1988.254 ms) ====== [2025-11-05T23:04:28.515Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-11-05T23:04:28.515Z] GC before operation: completed in 115.114 ms, heap usage 348.815 MB -> 133.123 MB. [2025-11-05T23:04:30.151Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1859.976 ms) ====== [2025-11-05T23:04:30.151Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-11-05T23:04:30.151Z] GC before operation: completed in 115.737 ms, heap usage 306.096 MB -> 133.403 MB. [2025-11-05T23:04:32.631Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1920.228 ms) ====== [2025-11-05T23:04:32.632Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-11-05T23:04:32.632Z] GC before operation: completed in 122.499 ms, heap usage 488.071 MB -> 134.252 MB. [2025-11-05T23:04:34.239Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1778.096 ms) ====== [2025-11-05T23:04:34.239Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-11-05T23:04:34.239Z] GC before operation: completed in 135.442 ms, heap usage 466.908 MB -> 134.477 MB. [2025-11-05T23:04:35.848Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1758.974 ms) ====== [2025-11-05T23:04:35.848Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-11-05T23:04:35.848Z] GC before operation: completed in 122.663 ms, heap usage 864.125 MB -> 136.088 MB. [2025-11-05T23:04:37.450Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1744.378 ms) ====== [2025-11-05T23:04:37.450Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-11-05T23:04:38.226Z] GC before operation: completed in 121.644 ms, heap usage 520.774 MB -> 135.443 MB. [2025-11-05T23:04:39.846Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1777.697 ms) ====== [2025-11-05T23:04:39.846Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-11-05T23:04:39.846Z] GC before operation: completed in 133.991 ms, heap usage 725.524 MB -> 136.601 MB. [2025-11-05T23:04:41.457Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1720.407 ms) ====== [2025-11-05T23:04:41.457Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-11-05T23:04:41.457Z] GC before operation: completed in 132.762 ms, heap usage 464.844 MB -> 136.212 MB. [2025-11-05T23:04:43.071Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1673.643 ms) ====== [2025-11-05T23:04:43.071Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-11-05T23:04:43.870Z] GC before operation: completed in 137.141 ms, heap usage 269.064 MB -> 135.997 MB. [2025-11-05T23:04:45.482Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1727.620 ms) ====== [2025-11-05T23:04:45.482Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-11-05T23:04:45.482Z] GC before operation: completed in 130.176 ms, heap usage 385.921 MB -> 136.756 MB. [2025-11-05T23:04:47.129Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1688.342 ms) ====== [2025-11-05T23:04:47.130Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-11-05T23:04:47.130Z] GC before operation: completed in 130.517 ms, heap usage 294.993 MB -> 137.056 MB. [2025-11-05T23:04:48.745Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1681.702 ms) ====== [2025-11-05T23:04:48.745Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-11-05T23:04:48.745Z] GC before operation: completed in 138.463 ms, heap usage 464.016 MB -> 137.708 MB. [2025-11-05T23:04:50.373Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1634.189 ms) ====== [2025-11-05T23:04:50.373Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-11-05T23:04:51.158Z] GC before operation: completed in 134.878 ms, heap usage 463.187 MB -> 138.030 MB. [2025-11-05T23:04:52.791Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1651.297 ms) ====== [2025-11-05T23:04:52.791Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-11-05T23:04:52.791Z] GC before operation: completed in 144.925 ms, heap usage 743.710 MB -> 139.376 MB. [2025-11-05T23:04:54.409Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1637.214 ms) ====== [2025-11-05T23:04:54.409Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-11-05T23:04:54.409Z] GC before operation: completed in 137.279 ms, heap usage 407.492 MB -> 138.737 MB. [2025-11-05T23:04:56.015Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1663.877 ms) ====== [2025-11-05T23:04:56.015Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-11-05T23:04:56.015Z] GC before operation: completed in 133.367 ms, heap usage 936.317 MB -> 140.701 MB. [2025-11-05T23:04:57.640Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1629.722 ms) ====== [2025-11-05T23:04:57.640Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-11-05T23:04:57.640Z] GC before operation: completed in 151.499 ms, heap usage 284.299 MB -> 139.156 MB. [2025-11-05T23:04:59.436Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1668.658 ms) ====== [2025-11-05T23:05:00.225Z] ----------------------------------- [2025-11-05T23:05:00.225Z] renaissance-log-regression_0_PASSED [2025-11-05T23:05:00.225Z] ----------------------------------- [2025-11-05T23:05:00.225Z] [2025-11-05T23:05:00.225Z] TEST TEARDOWN: [2025-11-05T23:05:00.225Z] Nothing to be done for teardown. [2025-11-05T23:05:00.225Z] renaissance-log-regression_0 Finish Time: Wed Nov 5 23:04:59 2025 Epoch Time (ms): 1762383899608