[2025-06-11T21:08:38.870Z] Running test renaissance-log-regression_0 ... [2025-06-11T21:08:39.190Z] =============================================== [2025-06-11T21:08:39.190Z] renaissance-log-regression_0 Start Time: Wed Jun 11 21:08:38 2025 Epoch Time (ms): 1749676118871 [2025-06-11T21:08:39.190Z] variation: NoOptions [2025-06-11T21:08:39.190Z] JVM_OPTIONS: [2025-06-11T21:08:39.190Z] { \ [2025-06-11T21:08:39.190Z] echo ""; echo "TEST SETUP:"; \ [2025-06-11T21:08:39.190Z] echo "Nothing to be done for setup."; \ [2025-06-11T21:08:39.190Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17496746368067/renaissance-log-regression_0"; \ [2025-06-11T21:08:39.190Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17496746368067/renaissance-log-regression_0"; \ [2025-06-11T21:08:39.190Z] echo ""; echo "TESTING:"; \ [2025-06-11T21:08:39.190Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_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_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17496746368067/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-06-11T21:08:39.190Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17496746368067/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-11T21:08:39.190Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-11T21:08:39.190Z] echo "Nothing to be done for teardown."; \ [2025-06-11T21:08:39.190Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17496746368067/TestTargetResult"; [2025-06-11T21:08:39.190Z] [2025-06-11T21:08:39.190Z] TEST SETUP: [2025-06-11T21:08:39.190Z] Nothing to be done for setup. [2025-06-11T21:08:39.190Z] [2025-06-11T21:08:39.190Z] TESTING: [2025-06-11T21:08:49.949Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-06-11T21:08:52.177Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-06-11T21:08:52.499Z] GC before operation: completed in 80.623 ms, heap usage 74.781 MB -> 34.796 MB. [2025-06-11T21:09:08.307Z] ====== log-regression (apache-spark) [default], iteration 0 completed (13758.285 ms) ====== [2025-06-11T21:09:08.307Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-06-11T21:09:08.307Z] GC before operation: completed in 111.813 ms, heap usage 274.681 MB -> 116.303 MB. [2025-06-11T21:09:11.240Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4527.502 ms) ====== [2025-06-11T21:09:11.240Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-06-11T21:09:11.240Z] GC before operation: completed in 96.688 ms, heap usage 279.720 MB -> 116.880 MB. [2025-06-11T21:09:14.985Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3550.583 ms) ====== [2025-06-11T21:09:14.985Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-06-11T21:09:14.985Z] GC before operation: completed in 107.982 ms, heap usage 230.303 MB -> 117.126 MB. [2025-06-11T21:09:17.952Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2976.086 ms) ====== [2025-06-11T21:09:17.952Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-06-11T21:09:17.952Z] GC before operation: completed in 98.293 ms, heap usage 282.920 MB -> 117.683 MB. [2025-06-11T21:09:20.923Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3442.048 ms) ====== [2025-06-11T21:09:20.923Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-06-11T21:09:21.248Z] GC before operation: completed in 112.644 ms, heap usage 258.970 MB -> 117.728 MB. [2025-06-11T21:09:24.180Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2713.260 ms) ====== [2025-06-11T21:09:24.180Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-06-11T21:09:24.180Z] GC before operation: completed in 108.551 ms, heap usage 277.301 MB -> 117.975 MB. [2025-06-11T21:09:26.405Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2502.167 ms) ====== [2025-06-11T21:09:26.405Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-06-11T21:09:26.724Z] GC before operation: completed in 104.928 ms, heap usage 398.735 MB -> 118.580 MB. [2025-06-11T21:09:28.946Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2293.622 ms) ====== [2025-06-11T21:09:28.946Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-06-11T21:09:28.946Z] GC before operation: completed in 108.632 ms, heap usage 672.356 MB -> 119.911 MB. [2025-06-11T21:09:31.845Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2390.399 ms) ====== [2025-06-11T21:09:31.845Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-06-11T21:09:31.845Z] GC before operation: completed in 107.476 ms, heap usage 567.999 MB -> 119.706 MB. [2025-06-11T21:09:34.086Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2638.571 ms) ====== [2025-06-11T21:09:34.086Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-06-11T21:09:34.405Z] GC before operation: completed in 114.089 ms, heap usage 275.367 MB -> 118.808 MB. [2025-06-11T21:09:37.343Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3225.464 ms) ====== [2025-06-11T21:09:37.343Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-06-11T21:09:37.666Z] GC before operation: completed in 118.121 ms, heap usage 233.547 MB -> 118.872 MB. [2025-06-11T21:09:39.897Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2313.483 ms) ====== [2025-06-11T21:09:39.897Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-06-11T21:09:39.897Z] GC before operation: completed in 117.334 ms, heap usage 262.185 MB -> 119.061 MB. [2025-06-11T21:09:43.625Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3040.536 ms) ====== [2025-06-11T21:09:43.625Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-06-11T21:09:43.625Z] GC before operation: completed in 112.846 ms, heap usage 251.672 MB -> 119.278 MB. [2025-06-11T21:09:45.838Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2410.798 ms) ====== [2025-06-11T21:09:45.838Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-06-11T21:09:45.838Z] GC before operation: completed in 112.023 ms, heap usage 301.270 MB -> 119.551 MB. [2025-06-11T21:09:48.068Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2284.584 ms) ====== [2025-06-11T21:09:48.068Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-06-11T21:09:48.068Z] GC before operation: completed in 121.996 ms, heap usage 752.631 MB -> 121.327 MB. [2025-06-11T21:09:50.978Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2355.096 ms) ====== [2025-06-11T21:09:50.978Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-06-11T21:09:50.978Z] GC before operation: completed in 115.656 ms, heap usage 277.927 MB -> 119.973 MB. [2025-06-11T21:09:53.201Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2396.753 ms) ====== [2025-06-11T21:09:53.201Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-06-11T21:09:53.201Z] GC before operation: completed in 114.392 ms, heap usage 451.454 MB -> 120.686 MB. [2025-06-11T21:09:55.430Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2118.926 ms) ====== [2025-06-11T21:09:55.430Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-06-11T21:09:55.430Z] GC before operation: completed in 117.595 ms, heap usage 559.070 MB -> 121.270 MB. [2025-06-11T21:09:57.640Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2234.516 ms) ====== [2025-06-11T21:09:57.640Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-06-11T21:09:57.641Z] GC before operation: completed in 121.385 ms, heap usage 411.613 MB -> 120.895 MB. [2025-06-11T21:10:00.568Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2328.560 ms) ====== [2025-06-11T21:10:00.568Z] ----------------------------------- [2025-06-11T21:10:00.568Z] renaissance-log-regression_0_PASSED [2025-06-11T21:10:00.568Z] ----------------------------------- [2025-06-11T21:10:00.568Z] [2025-06-11T21:10:00.568Z] TEST TEARDOWN: [2025-06-11T21:10:00.568Z] Nothing to be done for teardown. [2025-06-11T21:10:00.568Z] renaissance-log-regression_0 Finish Time: Wed Jun 11 21:10:00 2025 Epoch Time (ms): 1749676200123