[2025-02-25T22:06:13.712Z] Running test renaissance-log-regression_0 ... [2025-02-25T22:06:13.712Z] =============================================== [2025-02-25T22:06:13.712Z] renaissance-log-regression_0 Start Time: Tue Feb 25 22:06:13 2025 Epoch Time (ms): 1740521173583 [2025-02-25T22:06:13.712Z] variation: NoOptions [2025-02-25T22:06:13.712Z] JVM_OPTIONS: [2025-02-25T22:06:13.712Z] { \ [2025-02-25T22:06:13.712Z] echo ""; echo "TEST SETUP:"; \ [2025-02-25T22:06:13.712Z] echo "Nothing to be done for setup."; \ [2025-02-25T22:06:13.712Z] mkdir -p "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17405198451349/renaissance-log-regression_0"; \ [2025-02-25T22:06:13.712Z] cd "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17405198451349/renaissance-log-regression_0"; \ [2025-02-25T22:06:13.712Z] echo ""; echo "TESTING:"; \ [2025-02-25T22:06:13.712Z] "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/jdkbinary/j2sdk-image/bin/java" -jar "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17405198451349/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-02-25T22:06:13.712Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17405198451349/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-25T22:06:13.712Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-25T22:06:13.712Z] echo "Nothing to be done for teardown."; \ [2025-02-25T22:06:13.712Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17405198451349/TestTargetResult"; [2025-02-25T22:06:13.712Z] [2025-02-25T22:06:13.712Z] TEST SETUP: [2025-02-25T22:06:13.712Z] Nothing to be done for setup. [2025-02-25T22:06:13.712Z] [2025-02-25T22:06:13.712Z] TESTING: [2025-02-25T22:06:43.930Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-25T22:06:50.816Z] NOTE: 'log-regression' benchmark uses Spark local executor with 12 (out of 16) threads. [2025-02-25T22:07:10.129Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-02-25T22:07:10.129Z] GC before operation: completed in 639.067 ms, heap usage 66.295 MB -> 25.725 MB. [2025-02-25T22:07:28.972Z] ====== log-regression (apache-spark) [default], iteration 0 completed (19492.851 ms) ====== [2025-02-25T22:07:28.972Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-02-25T22:07:29.754Z] GC before operation: completed in 482.629 ms, heap usage 252.360 MB -> 102.210 MB. [2025-02-25T22:07:33.188Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3248.944 ms) ====== [2025-02-25T22:07:33.188Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-02-25T22:07:33.188Z] GC before operation: completed in 332.600 ms, heap usage 246.730 MB -> 102.655 MB. [2025-02-25T22:07:36.658Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2802.864 ms) ====== [2025-02-25T22:07:36.658Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-02-25T22:07:36.658Z] GC before operation: completed in 318.668 ms, heap usage 310.685 MB -> 103.714 MB. [2025-02-25T22:07:39.155Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2762.130 ms) ====== [2025-02-25T22:07:39.155Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-02-25T22:07:39.931Z] GC before operation: completed in 418.542 ms, heap usage 236.260 MB -> 103.504 MB. [2025-02-25T22:07:42.442Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2612.162 ms) ====== [2025-02-25T22:07:42.442Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-02-25T22:07:42.442Z] GC before operation: completed in 293.371 ms, heap usage 217.262 MB -> 103.793 MB. [2025-02-25T22:07:44.932Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2462.723 ms) ====== [2025-02-25T22:07:44.932Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-02-25T22:07:45.708Z] GC before operation: completed in 334.217 ms, heap usage 140.478 MB -> 103.892 MB. [2025-02-25T22:07:47.303Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2251.974 ms) ====== [2025-02-25T22:07:47.303Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-02-25T22:07:48.077Z] GC before operation: completed in 238.687 ms, heap usage 206.921 MB -> 104.570 MB. [2025-02-25T22:07:50.573Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2326.233 ms) ====== [2025-02-25T22:07:50.573Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-02-25T22:07:50.573Z] GC before operation: completed in 233.093 ms, heap usage 222.648 MB -> 105.335 MB. [2025-02-25T22:07:53.064Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2223.965 ms) ====== [2025-02-25T22:07:53.064Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-02-25T22:07:53.064Z] GC before operation: completed in 255.215 ms, heap usage 291.290 MB -> 106.422 MB. [2025-02-25T22:07:55.557Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2271.896 ms) ====== [2025-02-25T22:07:55.557Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-02-25T22:07:55.557Z] GC before operation: completed in 175.053 ms, heap usage 295.270 MB -> 106.900 MB. [2025-02-25T22:07:58.041Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2242.253 ms) ====== [2025-02-25T22:07:58.041Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-02-25T22:07:58.041Z] GC before operation: completed in 197.216 ms, heap usage 233.064 MB -> 106.715 MB. [2025-02-25T22:07:59.646Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2138.863 ms) ====== [2025-02-25T22:07:59.646Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-02-25T22:08:00.433Z] GC before operation: completed in 303.221 ms, heap usage 295.059 MB -> 107.959 MB. [2025-02-25T22:08:02.035Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2117.413 ms) ====== [2025-02-25T22:08:02.035Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-02-25T22:08:02.809Z] GC before operation: completed in 303.339 ms, heap usage 202.289 MB -> 107.288 MB. [2025-02-25T22:08:04.407Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2153.733 ms) ====== [2025-02-25T22:08:04.407Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-02-25T22:08:05.184Z] GC before operation: completed in 298.494 ms, heap usage 285.282 MB -> 108.581 MB. [2025-02-25T22:08:07.671Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2136.769 ms) ====== [2025-02-25T22:08:07.671Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-02-25T22:08:07.671Z] GC before operation: completed in 185.918 ms, heap usage 285.916 MB -> 109.106 MB. [2025-02-25T22:08:09.277Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2063.677 ms) ====== [2025-02-25T22:08:09.277Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-02-25T22:08:10.052Z] GC before operation: completed in 246.716 ms, heap usage 278.445 MB -> 109.477 MB. [2025-02-25T22:08:11.649Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2044.568 ms) ====== [2025-02-25T22:08:11.649Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-02-25T22:08:11.649Z] GC before operation: completed in 245.498 ms, heap usage 274.797 MB -> 109.900 MB. [2025-02-25T22:08:14.135Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2041.905 ms) ====== [2025-02-25T22:08:14.135Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-02-25T22:08:14.135Z] GC before operation: completed in 261.546 ms, heap usage 227.952 MB -> 109.812 MB. [2025-02-25T22:08:16.630Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1938.392 ms) ====== [2025-02-25T22:08:16.630Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-02-25T22:08:16.630Z] GC before operation: completed in 319.120 ms, heap usage 332.830 MB -> 111.377 MB. [2025-02-25T22:08:18.231Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1945.575 ms) ====== [2025-02-25T22:08:19.008Z] ----------------------------------- [2025-02-25T22:08:19.008Z] renaissance-log-regression_0_PASSED [2025-02-25T22:08:19.008Z] ----------------------------------- [2025-02-25T22:08:19.008Z] [2025-02-25T22:08:19.008Z] TEST TEARDOWN: [2025-02-25T22:08:19.008Z] Nothing to be done for teardown. [2025-02-25T22:08:19.008Z] renaissance-log-regression_0 Finish Time: Tue Feb 25 22:08:18 2025 Epoch Time (ms): 1740521298705