[2024-11-28T22:31:34.879Z] Running test renaissance-log-regression_0 ... [2024-11-28T22:31:34.879Z] =============================================== [2024-11-28T22:31:34.879Z] renaissance-log-regression_0 Start Time: Thu Nov 28 22:31:34 2024 Epoch Time (ms): 1732833094160 [2024-11-28T22:31:34.879Z] variation: NoOptions [2024-11-28T22:31:34.879Z] JVM_OPTIONS: [2024-11-28T22:31:34.879Z] { \ [2024-11-28T22:31:34.879Z] echo ""; echo "TEST SETUP:"; \ [2024-11-28T22:31:34.879Z] echo "Nothing to be done for setup."; \ [2024-11-28T22:31:34.879Z] mkdir -p "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1732832259821/renaissance-log-regression_0"; \ [2024-11-28T22:31:34.879Z] cd "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1732832259821/renaissance-log-regression_0"; \ [2024-11-28T22:31:34.879Z] echo ""; echo "TESTING:"; \ [2024-11-28T22:31:34.879Z] "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/jdkbinary/j2sdk-image/bin/java" -jar "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1732832259821/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-11-28T22:31:34.879Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1732832259821/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-28T22:31:34.879Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-28T22:31:34.879Z] echo "Nothing to be done for teardown."; \ [2024-11-28T22:31:34.879Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_1732832259821/TestTargetResult"; [2024-11-28T22:31:34.879Z] [2024-11-28T22:31:34.879Z] TEST SETUP: [2024-11-28T22:31:34.879Z] Nothing to be done for setup. [2024-11-28T22:31:34.879Z] [2024-11-28T22:31:34.879Z] TESTING: [2024-11-28T22:31:37.868Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-28T22:31:40.880Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-11-28T22:31:45.022Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-11-28T22:31:45.966Z] GC before operation: completed in 192.441 ms, heap usage 80.916 MB -> 26.276 MB. [2024-11-28T22:31:55.641Z] ====== log-regression (apache-spark) [default], iteration 0 completed (10335.402 ms) ====== [2024-11-28T22:31:55.641Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-11-28T22:31:56.583Z] GC before operation: completed in 362.822 ms, heap usage 265.045 MB -> 100.376 MB. [2024-11-28T22:31:58.535Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2422.109 ms) ====== [2024-11-28T22:31:58.535Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-11-28T22:31:58.535Z] GC before operation: completed in 258.440 ms, heap usage 335.342 MB -> 101.520 MB. [2024-11-28T22:32:01.532Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2131.476 ms) ====== [2024-11-28T22:32:01.532Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-11-28T22:32:01.532Z] GC before operation: completed in 202.002 ms, heap usage 270.681 MB -> 101.460 MB. [2024-11-28T22:32:03.479Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2183.210 ms) ====== [2024-11-28T22:32:03.479Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-11-28T22:32:03.479Z] GC before operation: completed in 198.915 ms, heap usage 208.713 MB -> 101.181 MB. [2024-11-28T22:32:05.425Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1858.188 ms) ====== [2024-11-28T22:32:05.425Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-11-28T22:32:05.425Z] GC before operation: completed in 213.829 ms, heap usage 393.222 MB -> 103.160 MB. [2024-11-28T22:32:07.374Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1787.822 ms) ====== [2024-11-28T22:32:07.374Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-11-28T22:32:07.374Z] GC before operation: completed in 197.386 ms, heap usage 220.811 MB -> 102.034 MB. [2024-11-28T22:32:09.318Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1808.696 ms) ====== [2024-11-28T22:32:09.318Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-11-28T22:32:10.291Z] GC before operation: completed in 245.559 ms, heap usage 387.958 MB -> 103.635 MB. [2024-11-28T22:32:12.235Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2149.835 ms) ====== [2024-11-28T22:32:12.235Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-11-28T22:32:12.235Z] GC before operation: completed in 210.888 ms, heap usage 206.058 MB -> 102.300 MB. [2024-11-28T22:32:14.182Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1684.677 ms) ====== [2024-11-28T22:32:14.182Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-11-28T22:32:14.182Z] GC before operation: completed in 197.214 ms, heap usage 413.025 MB -> 104.394 MB. [2024-11-28T22:32:16.118Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1578.157 ms) ====== [2024-11-28T22:32:16.119Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-11-28T22:32:16.119Z] GC before operation: completed in 189.181 ms, heap usage 394.581 MB -> 104.446 MB. [2024-11-28T22:32:17.067Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1600.827 ms) ====== [2024-11-28T22:32:17.067Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-11-28T22:32:18.019Z] GC before operation: completed in 252.775 ms, heap usage 197.288 MB -> 102.904 MB. [2024-11-28T22:32:18.962Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1670.076 ms) ====== [2024-11-28T22:32:18.962Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-11-28T22:32:19.906Z] GC before operation: completed in 188.017 ms, heap usage 220.113 MB -> 103.396 MB. [2024-11-28T22:32:20.851Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1552.746 ms) ====== [2024-11-28T22:32:20.851Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-11-28T22:32:20.851Z] GC before operation: completed in 199.766 ms, heap usage 296.414 MB -> 104.246 MB. [2024-11-28T22:32:22.797Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1515.519 ms) ====== [2024-11-28T22:32:22.797Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-11-28T22:32:22.797Z] GC before operation: completed in 210.964 ms, heap usage 300.456 MB -> 104.583 MB. [2024-11-28T22:32:24.733Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1588.267 ms) ====== [2024-11-28T22:32:24.733Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-11-28T22:32:24.733Z] GC before operation: completed in 199.420 ms, heap usage 195.705 MB -> 103.972 MB. [2024-11-28T22:32:26.671Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1550.197 ms) ====== [2024-11-28T22:32:26.671Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-11-28T22:32:26.671Z] GC before operation: completed in 183.930 ms, heap usage 199.701 MB -> 104.164 MB. [2024-11-28T22:32:28.615Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1554.191 ms) ====== [2024-11-28T22:32:28.615Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-11-28T22:32:28.615Z] GC before operation: completed in 163.417 ms, heap usage 333.668 MB -> 105.784 MB. [2024-11-28T22:32:29.559Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1471.009 ms) ====== [2024-11-28T22:32:29.559Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-11-28T22:32:29.559Z] GC before operation: completed in 201.766 ms, heap usage 211.792 MB -> 104.819 MB. [2024-11-28T22:32:31.510Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1536.766 ms) ====== [2024-11-28T22:32:31.510Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-11-28T22:32:31.510Z] GC before operation: completed in 193.603 ms, heap usage 406.819 MB -> 106.745 MB. [2024-11-28T22:32:33.453Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1499.741 ms) ====== [2024-11-28T22:32:33.453Z] ----------------------------------- [2024-11-28T22:32:33.454Z] renaissance-log-regression_0_PASSED [2024-11-28T22:32:33.454Z] ----------------------------------- [2024-11-28T22:32:33.454Z] [2024-11-28T22:32:33.454Z] TEST TEARDOWN: [2024-11-28T22:32:33.454Z] Nothing to be done for teardown. [2024-11-28T22:32:33.454Z] renaissance-log-regression_0 Finish Time: Thu Nov 28 22:32:32 2024 Epoch Time (ms): 1732833152884