[2025-08-27T20:46:09.346Z] Running test renaissance-log-regression_0 ... [2025-08-27T20:46:09.346Z] =============================================== [2025-08-27T20:46:09.346Z] renaissance-log-regression_0 Start Time: Wed Aug 27 20:46:08 2025 Epoch Time (ms): 1756327568724 [2025-08-27T20:46:09.346Z] variation: NoOptions [2025-08-27T20:46:09.346Z] JVM_OPTIONS: [2025-08-27T20:46:09.346Z] { \ [2025-08-27T20:46:09.346Z] echo ""; echo "TEST SETUP:"; \ [2025-08-27T20:46:09.346Z] echo "Nothing to be done for setup."; \ [2025-08-27T20:46:09.346Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17563264741749/renaissance-log-regression_0"; \ [2025-08-27T20:46:09.346Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17563264741749/renaissance-log-regression_0"; \ [2025-08-27T20:46:09.346Z] echo ""; echo "TESTING:"; \ [2025-08-27T20:46:09.346Z] "/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_17563264741749/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-27T20:46:09.346Z] 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_17563264741749/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-27T20:46:09.346Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-27T20:46:09.346Z] echo "Nothing to be done for teardown."; \ [2025-08-27T20:46:09.346Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17563264741749/TestTargetResult"; [2025-08-27T20:46:09.346Z] [2025-08-27T20:46:09.346Z] TEST SETUP: [2025-08-27T20:46:09.346Z] Nothing to be done for setup. [2025-08-27T20:46:09.346Z] [2025-08-27T20:46:09.346Z] TESTING: [2025-08-27T20:46:16.133Z] NOTE: 'log-regression' benchmark uses Spark local executor with 12 (out of 24) threads. [2025-08-27T20:46:19.528Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-27T20:46:19.528Z] GC before operation: completed in 79.825 ms, heap usage 85.928 MB -> 35.008 MB. [2025-08-27T20:46:31.107Z] ====== log-regression (apache-spark) [default], iteration 0 completed (11049.406 ms) ====== [2025-08-27T20:46:31.107Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-27T20:46:31.107Z] GC before operation: completed in 120.546 ms, heap usage 384.110 MB -> 132.367 MB. [2025-08-27T20:46:32.670Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1910.178 ms) ====== [2025-08-27T20:46:32.670Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-27T20:46:32.670Z] GC before operation: completed in 110.802 ms, heap usage 310.830 MB -> 132.844 MB. [2025-08-27T20:46:34.236Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1673.950 ms) ====== [2025-08-27T20:46:34.236Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-27T20:46:34.236Z] GC before operation: completed in 103.277 ms, heap usage 289.930 MB -> 133.080 MB. [2025-08-27T20:46:35.806Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1603.697 ms) ====== [2025-08-27T20:46:35.806Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-27T20:46:35.806Z] GC before operation: completed in 119.967 ms, heap usage 286.102 MB -> 133.482 MB. [2025-08-27T20:46:37.379Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1571.845 ms) ====== [2025-08-27T20:46:37.380Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-27T20:46:37.380Z] GC before operation: completed in 122.581 ms, heap usage 299.770 MB -> 134.108 MB. [2025-08-27T20:46:38.945Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1538.270 ms) ====== [2025-08-27T20:46:38.945Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-27T20:46:38.945Z] GC before operation: completed in 123.703 ms, heap usage 235.128 MB -> 134.331 MB. [2025-08-27T20:46:40.521Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1469.257 ms) ====== [2025-08-27T20:46:40.521Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-27T20:46:41.281Z] GC before operation: completed in 128.114 ms, heap usage 903.293 MB -> 136.548 MB. [2025-08-27T20:46:42.038Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1449.265 ms) ====== [2025-08-27T20:46:42.038Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-27T20:46:42.808Z] GC before operation: completed in 125.459 ms, heap usage 268.626 MB -> 135.115 MB. [2025-08-27T20:46:43.599Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1469.301 ms) ====== [2025-08-27T20:46:43.599Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-27T20:46:44.358Z] GC before operation: completed in 129.440 ms, heap usage 415.099 MB -> 136.029 MB. [2025-08-27T20:46:45.930Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1519.654 ms) ====== [2025-08-27T20:46:45.930Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-27T20:46:45.930Z] GC before operation: completed in 135.489 ms, heap usage 651.678 MB -> 137.113 MB. [2025-08-27T20:46:47.502Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1428.608 ms) ====== [2025-08-27T20:46:47.502Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-27T20:46:47.502Z] GC before operation: completed in 130.896 ms, heap usage 361.638 MB -> 136.576 MB. [2025-08-27T20:46:49.071Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1423.599 ms) ====== [2025-08-27T20:46:49.071Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-27T20:46:49.071Z] GC before operation: completed in 121.678 ms, heap usage 1.114 GB -> 139.159 MB. [2025-08-27T20:46:49.839Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1362.916 ms) ====== [2025-08-27T20:46:49.839Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-27T20:46:50.597Z] GC before operation: completed in 130.534 ms, heap usage 387.351 MB -> 137.365 MB. [2025-08-27T20:46:52.164Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1414.392 ms) ====== [2025-08-27T20:46:52.164Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-27T20:46:52.164Z] GC before operation: completed in 134.837 ms, heap usage 491.011 MB -> 138.086 MB. [2025-08-27T20:46:52.921Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1400.315 ms) ====== [2025-08-27T20:46:52.921Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-27T20:46:53.677Z] GC before operation: completed in 137.817 ms, heap usage 286.079 MB -> 137.768 MB. [2025-08-27T20:46:55.249Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1428.176 ms) ====== [2025-08-27T20:46:55.249Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-27T20:46:55.249Z] GC before operation: completed in 138.094 ms, heap usage 921.907 MB -> 139.982 MB. [2025-08-27T20:46:56.009Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1373.572 ms) ====== [2025-08-27T20:46:56.009Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-27T20:46:56.766Z] GC before operation: completed in 127.125 ms, heap usage 705.341 MB -> 139.600 MB. [2025-08-27T20:46:57.524Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1340.051 ms) ====== [2025-08-27T20:46:57.524Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-27T20:46:58.321Z] GC before operation: completed in 136.977 ms, heap usage 693.157 MB -> 140.071 MB. [2025-08-27T20:46:59.077Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1364.513 ms) ====== [2025-08-27T20:46:59.077Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-27T20:46:59.833Z] GC before operation: completed in 137.210 ms, heap usage 670.724 MB -> 140.446 MB. [2025-08-27T20:47:00.590Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1338.159 ms) ====== [2025-08-27T20:47:01.347Z] ----------------------------------- [2025-08-27T20:47:01.347Z] renaissance-log-regression_0_PASSED [2025-08-27T20:47:01.347Z] ----------------------------------- [2025-08-27T20:47:01.347Z] [2025-08-27T20:47:01.347Z] TEST TEARDOWN: [2025-08-27T20:47:01.347Z] Nothing to be done for teardown. [2025-08-27T20:47:01.347Z] renaissance-log-regression_0 Finish Time: Wed Aug 27 20:47:00 2025 Epoch Time (ms): 1756327620828