[2025-10-22T17:23:19.693Z] Running test renaissance-log-regression_0 ... [2025-10-22T17:23:19.693Z] =============================================== [2025-10-22T17:23:19.693Z] renaissance-log-regression_0 Start Time: Wed Oct 22 17:23:19 2025 Epoch Time (ms): 1761153799042 [2025-10-22T17:23:19.693Z] variation: NoOptions [2025-10-22T17:23:19.693Z] JVM_OPTIONS: [2025-10-22T17:23:19.693Z] { \ [2025-10-22T17:23:19.693Z] echo ""; echo "TEST SETUP:"; \ [2025-10-22T17:23:19.693Z] echo "Nothing to be done for setup."; \ [2025-10-22T17:23:19.693Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17611525451168/renaissance-log-regression_0"; \ [2025-10-22T17:23:19.693Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17611525451168/renaissance-log-regression_0"; \ [2025-10-22T17:23:19.693Z] echo ""; echo "TESTING:"; \ [2025-10-22T17:23:19.693Z] "/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_17611525451168/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-10-22T17:23:19.694Z] 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_17611525451168/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-10-22T17:23:19.694Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-10-22T17:23:19.694Z] echo "Nothing to be done for teardown."; \ [2025-10-22T17:23:19.694Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17611525451168/TestTargetResult"; [2025-10-22T17:23:19.694Z] [2025-10-22T17:23:19.694Z] TEST SETUP: [2025-10-22T17:23:19.694Z] Nothing to be done for setup. [2025-10-22T17:23:19.694Z] [2025-10-22T17:23:19.694Z] TESTING: [2025-10-22T17:23:25.087Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-10-22T17:23:27.061Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-10-22T17:23:27.061Z] GC before operation: completed in 63.380 ms, heap usage 51.570 MB -> 35.061 MB. [2025-10-22T17:23:38.048Z] ====== log-regression (apache-spark) [default], iteration 0 completed (9770.897 ms) ====== [2025-10-22T17:23:38.048Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-10-22T17:23:38.048Z] GC before operation: completed in 103.585 ms, heap usage 973.735 MB -> 121.250 MB. [2025-10-22T17:23:39.005Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1931.549 ms) ====== [2025-10-22T17:23:39.005Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-10-22T17:23:39.005Z] GC before operation: completed in 77.699 ms, heap usage 450.996 MB -> 120.645 MB. [2025-10-22T17:23:40.969Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1582.783 ms) ====== [2025-10-22T17:23:40.969Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-10-22T17:23:40.969Z] GC before operation: completed in 72.412 ms, heap usage 420.897 MB -> 120.978 MB. [2025-10-22T17:23:42.931Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1533.383 ms) ====== [2025-10-22T17:23:42.932Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-10-22T17:23:42.932Z] GC before operation: completed in 77.349 ms, heap usage 683.984 MB -> 122.076 MB. [2025-10-22T17:23:43.886Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1469.753 ms) ====== [2025-10-22T17:23:43.886Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-10-22T17:23:43.886Z] GC before operation: completed in 75.276 ms, heap usage 996.560 MB -> 122.763 MB. [2025-10-22T17:23:45.842Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1475.948 ms) ====== [2025-10-22T17:23:45.842Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-10-22T17:23:45.842Z] GC before operation: completed in 76.910 ms, heap usage 817.684 MB -> 123.007 MB. [2025-10-22T17:23:46.795Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1438.755 ms) ====== [2025-10-22T17:23:46.795Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-10-22T17:23:46.795Z] GC before operation: completed in 80.805 ms, heap usage 653.317 MB -> 122.643 MB. [2025-10-22T17:23:48.756Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1471.874 ms) ====== [2025-10-22T17:23:48.756Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-10-22T17:23:48.756Z] GC before operation: completed in 80.265 ms, heap usage 276.052 MB -> 121.749 MB. [2025-10-22T17:23:49.709Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1386.077 ms) ====== [2025-10-22T17:23:49.709Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-10-22T17:23:49.709Z] GC before operation: completed in 80.152 ms, heap usage 586.676 MB -> 122.926 MB. [2025-10-22T17:23:52.021Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1435.604 ms) ====== [2025-10-22T17:23:52.021Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-10-22T17:23:52.021Z] GC before operation: completed in 93.702 ms, heap usage 301.388 MB -> 122.248 MB. [2025-10-22T17:23:52.983Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1366.328 ms) ====== [2025-10-22T17:23:52.983Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-10-22T17:23:52.983Z] GC before operation: completed in 85.061 ms, heap usage 672.687 MB -> 123.602 MB. [2025-10-22T17:23:54.940Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1376.824 ms) ====== [2025-10-22T17:23:54.940Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-10-22T17:23:54.940Z] GC before operation: completed in 81.025 ms, heap usage 730.141 MB -> 124.021 MB. [2025-10-22T17:23:55.893Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1341.762 ms) ====== [2025-10-22T17:23:55.893Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-10-22T17:23:55.893Z] GC before operation: completed in 81.764 ms, heap usage 353.704 MB -> 123.031 MB. [2025-10-22T17:23:57.872Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1340.362 ms) ====== [2025-10-22T17:23:57.872Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-10-22T17:23:57.872Z] GC before operation: completed in 93.305 ms, heap usage 457.282 MB -> 123.529 MB. [2025-10-22T17:23:58.828Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1347.468 ms) ====== [2025-10-22T17:23:58.828Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-10-22T17:23:58.828Z] GC before operation: completed in 81.513 ms, heap usage 644.732 MB -> 124.830 MB. [2025-10-22T17:24:00.216Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1329.788 ms) ====== [2025-10-22T17:24:00.216Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-10-22T17:24:00.216Z] GC before operation: completed in 88.025 ms, heap usage 654.964 MB -> 124.487 MB. [2025-10-22T17:24:02.182Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1387.034 ms) ====== [2025-10-22T17:24:02.182Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-10-22T17:24:02.182Z] GC before operation: completed in 82.201 ms, heap usage 547.248 MB -> 124.403 MB. [2025-10-22T17:24:03.137Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1257.780 ms) ====== [2025-10-22T17:24:03.137Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-10-22T17:24:03.137Z] GC before operation: completed in 95.268 ms, heap usage 403.897 MB -> 124.209 MB. [2025-10-22T17:24:04.095Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1255.446 ms) ====== [2025-10-22T17:24:04.095Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-10-22T17:24:04.095Z] GC before operation: completed in 88.538 ms, heap usage 259.799 MB -> 124.010 MB. [2025-10-22T17:24:06.056Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1325.886 ms) ====== [2025-10-22T17:24:06.056Z] ----------------------------------- [2025-10-22T17:24:06.056Z] renaissance-log-regression_0_PASSED [2025-10-22T17:24:06.056Z] ----------------------------------- [2025-10-22T17:24:06.056Z] [2025-10-22T17:24:06.056Z] TEST TEARDOWN: [2025-10-22T17:24:06.056Z] Nothing to be done for teardown. [2025-10-22T17:24:06.056Z] renaissance-log-regression_0 Finish Time: Wed Oct 22 17:24:05 2025 Epoch Time (ms): 1761153845453