[2025-06-12T02:45:27.666Z] Running test renaissance-log-regression_0 ... [2025-06-12T02:45:27.666Z] =============================================== [2025-06-12T02:45:27.666Z] renaissance-log-regression_0 Start Time: Thu Jun 12 02:45:27 2025 Epoch Time (ms): 1749696327381 [2025-06-12T02:45:27.666Z] variation: NoOptions [2025-06-12T02:45:27.666Z] JVM_OPTIONS: [2025-06-12T02:45:27.666Z] { \ [2025-06-12T02:45:27.666Z] echo ""; echo "TEST SETUP:"; \ [2025-06-12T02:45:27.666Z] echo "Nothing to be done for setup."; \ [2025-06-12T02:45:27.666Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17496941643392/renaissance-log-regression_0"; \ [2025-06-12T02:45:27.666Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17496941643392/renaissance-log-regression_0"; \ [2025-06-12T02:45:27.666Z] echo ""; echo "TESTING:"; \ [2025-06-12T02:45:27.667Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17496941643392/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-06-12T02:45:27.667Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17496941643392/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-12T02:45:27.667Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-12T02:45:27.667Z] echo "Nothing to be done for teardown."; \ [2025-06-12T02:45:27.667Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17496941643392/TestTargetResult"; [2025-06-12T02:45:27.667Z] [2025-06-12T02:45:27.667Z] TEST SETUP: [2025-06-12T02:45:27.667Z] Nothing to be done for setup. [2025-06-12T02:45:27.667Z] [2025-06-12T02:45:27.667Z] TESTING: [2025-06-12T02:45:33.503Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-06-12T02:45:36.417Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-06-12T02:45:36.417Z] GC before operation: completed in 49.256 ms, heap usage 43.880 MB -> 34.740 MB. [2025-06-12T02:45:52.176Z] ====== log-regression (apache-spark) [default], iteration 0 completed (14519.091 ms) ====== [2025-06-12T02:45:52.176Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-06-12T02:45:52.176Z] GC before operation: completed in 145.125 ms, heap usage 391.119 MB -> 116.886 MB. [2025-06-12T02:45:55.093Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3871.677 ms) ====== [2025-06-12T02:45:55.093Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-06-12T02:45:55.093Z] GC before operation: completed in 86.146 ms, heap usage 310.435 MB -> 116.926 MB. [2025-06-12T02:45:58.013Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3269.516 ms) ====== [2025-06-12T02:45:58.013Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-06-12T02:45:58.013Z] GC before operation: completed in 105.774 ms, heap usage 307.302 MB -> 117.419 MB. [2025-06-12T02:46:00.956Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2918.270 ms) ====== [2025-06-12T02:46:00.956Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-06-12T02:46:01.279Z] GC before operation: completed in 87.587 ms, heap usage 255.280 MB -> 117.525 MB. [2025-06-12T02:46:04.205Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2727.222 ms) ====== [2025-06-12T02:46:04.205Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-06-12T02:46:04.205Z] GC before operation: completed in 96.672 ms, heap usage 328.715 MB -> 117.947 MB. [2025-06-12T02:46:06.525Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2697.864 ms) ====== [2025-06-12T02:46:06.525Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-06-12T02:46:06.847Z] GC before operation: completed in 100.924 ms, heap usage 394.278 MB -> 118.398 MB. [2025-06-12T02:46:09.764Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2672.556 ms) ====== [2025-06-12T02:46:09.764Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-06-12T02:46:09.764Z] GC before operation: completed in 94.747 ms, heap usage 364.036 MB -> 118.487 MB. [2025-06-12T02:46:11.989Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2300.532 ms) ====== [2025-06-12T02:46:11.989Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-06-12T02:46:11.989Z] GC before operation: completed in 128.882 ms, heap usage 354.980 MB -> 118.649 MB. [2025-06-12T02:46:14.209Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2168.148 ms) ====== [2025-06-12T02:46:14.209Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-06-12T02:46:14.209Z] GC before operation: completed in 107.509 ms, heap usage 320.811 MB -> 118.841 MB. [2025-06-12T02:46:16.436Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2148.740 ms) ====== [2025-06-12T02:46:16.436Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-06-12T02:46:16.436Z] GC before operation: completed in 95.051 ms, heap usage 314.504 MB -> 118.969 MB. [2025-06-12T02:46:18.656Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2140.006 ms) ====== [2025-06-12T02:46:18.656Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-06-12T02:46:18.656Z] GC before operation: completed in 97.717 ms, heap usage 320.131 MB -> 119.129 MB. [2025-06-12T02:46:20.876Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2088.040 ms) ====== [2025-06-12T02:46:20.876Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-06-12T02:46:20.876Z] GC before operation: completed in 98.226 ms, heap usage 363.564 MB -> 119.476 MB. [2025-06-12T02:46:23.113Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2019.044 ms) ====== [2025-06-12T02:46:23.113Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-06-12T02:46:23.113Z] GC before operation: completed in 103.038 ms, heap usage 398.300 MB -> 119.841 MB. [2025-06-12T02:46:25.334Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2109.779 ms) ====== [2025-06-12T02:46:25.334Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-06-12T02:46:25.334Z] GC before operation: completed in 102.521 ms, heap usage 399.511 MB -> 119.946 MB. [2025-06-12T02:46:27.552Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2035.901 ms) ====== [2025-06-12T02:46:27.552Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-06-12T02:46:27.552Z] GC before operation: completed in 101.007 ms, heap usage 318.592 MB -> 119.919 MB. [2025-06-12T02:46:29.803Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2119.650 ms) ====== [2025-06-12T02:46:29.803Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-06-12T02:46:29.803Z] GC before operation: completed in 98.464 ms, heap usage 311.413 MB -> 120.137 MB. [2025-06-12T02:46:31.468Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1944.735 ms) ====== [2025-06-12T02:46:31.468Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-06-12T02:46:31.792Z] GC before operation: completed in 105.893 ms, heap usage 361.445 MB -> 120.486 MB. [2025-06-12T02:46:33.514Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1996.010 ms) ====== [2025-06-12T02:46:33.514Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-06-12T02:46:33.835Z] GC before operation: completed in 108.084 ms, heap usage 373.981 MB -> 120.780 MB. [2025-06-12T02:46:36.054Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1906.920 ms) ====== [2025-06-12T02:46:36.054Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-06-12T02:46:36.054Z] GC before operation: completed in 108.647 ms, heap usage 372.328 MB -> 120.749 MB. [2025-06-12T02:46:37.687Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1961.342 ms) ====== [2025-06-12T02:46:38.007Z] ----------------------------------- [2025-06-12T02:46:38.007Z] renaissance-log-regression_0_PASSED [2025-06-12T02:46:38.007Z] ----------------------------------- [2025-06-12T02:46:38.007Z] [2025-06-12T02:46:38.007Z] TEST TEARDOWN: [2025-06-12T02:46:38.007Z] Nothing to be done for teardown. [2025-06-12T02:46:38.007Z] renaissance-log-regression_0 Finish Time: Thu Jun 12 02:46:37 2025 Epoch Time (ms): 1749696397833