[2024-09-26T21:35:01.877Z] Running test renaissance-log-regression_0 ... [2024-09-26T21:35:01.877Z] =============================================== [2024-09-26T21:35:01.877Z] renaissance-log-regression_0 Start Time: Thu Sep 26 21:35:00 2024 Epoch Time (ms): 1727386500974 [2024-09-26T21:35:01.877Z] variation: NoOptions [2024-09-26T21:35:01.877Z] JVM_OPTIONS: [2024-09-26T21:35:01.877Z] { \ [2024-09-26T21:35:01.877Z] echo ""; echo "TEST SETUP:"; \ [2024-09-26T21:35:01.877Z] echo "Nothing to be done for setup."; \ [2024-09-26T21:35:01.877Z] mkdir -p "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17273857068101/renaissance-log-regression_0"; \ [2024-09-26T21:35:01.877Z] cd "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17273857068101/renaissance-log-regression_0"; \ [2024-09-26T21:35:01.877Z] echo ""; echo "TESTING:"; \ [2024-09-26T21:35:01.877Z] "/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_17273857068101/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-09-26T21:35:01.877Z] 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_17273857068101/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-26T21:35:01.877Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-26T21:35:01.877Z] echo "Nothing to be done for teardown."; \ [2024-09-26T21:35:01.877Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17273857068101/TestTargetResult"; [2024-09-26T21:35:01.877Z] [2024-09-26T21:35:01.877Z] TEST SETUP: [2024-09-26T21:35:01.877Z] Nothing to be done for setup. [2024-09-26T21:35:01.877Z] [2024-09-26T21:35:01.877Z] TESTING: [2024-09-26T21:35:04.826Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-26T21:35:07.783Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-09-26T21:35:11.855Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-09-26T21:35:11.856Z] GC before operation: completed in 166.344 ms, heap usage 121.775 MB -> 27.731 MB. [2024-09-26T21:35:23.172Z] ====== log-regression (apache-spark) [default], iteration 0 completed (10163.411 ms) ====== [2024-09-26T21:35:23.172Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-09-26T21:35:23.172Z] GC before operation: completed in 355.180 ms, heap usage 363.461 MB -> 101.290 MB. [2024-09-26T21:35:25.170Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2414.975 ms) ====== [2024-09-26T21:35:25.170Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-09-26T21:35:25.171Z] GC before operation: completed in 227.821 ms, heap usage 301.435 MB -> 101.196 MB. [2024-09-26T21:35:27.077Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2141.876 ms) ====== [2024-09-26T21:35:27.077Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-09-26T21:35:28.005Z] GC before operation: completed in 231.891 ms, heap usage 342.948 MB -> 102.104 MB. [2024-09-26T21:35:29.950Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1964.589 ms) ====== [2024-09-26T21:35:29.950Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-09-26T21:35:29.950Z] GC before operation: completed in 196.410 ms, heap usage 259.078 MB -> 101.682 MB. [2024-09-26T21:35:31.860Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1842.945 ms) ====== [2024-09-26T21:35:31.860Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-09-26T21:35:31.860Z] GC before operation: completed in 193.266 ms, heap usage 419.336 MB -> 103.254 MB. [2024-09-26T21:35:33.776Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1852.184 ms) ====== [2024-09-26T21:35:33.776Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-09-26T21:35:33.776Z] GC before operation: completed in 205.115 ms, heap usage 393.758 MB -> 103.359 MB. [2024-09-26T21:35:36.726Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1761.877 ms) ====== [2024-09-26T21:35:36.726Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-09-26T21:35:36.726Z] GC before operation: completed in 1197.725 ms, heap usage 359.054 MB -> 103.306 MB. [2024-09-26T21:35:38.648Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1741.787 ms) ====== [2024-09-26T21:35:38.648Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-09-26T21:35:38.648Z] GC before operation: completed in 196.737 ms, heap usage 349.574 MB -> 103.446 MB. [2024-09-26T21:35:40.560Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1659.869 ms) ====== [2024-09-26T21:35:40.560Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-09-26T21:35:40.560Z] GC before operation: completed in 184.496 ms, heap usage 349.735 MB -> 103.712 MB. [2024-09-26T21:35:42.493Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1672.548 ms) ====== [2024-09-26T21:35:42.493Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-09-26T21:35:42.493Z] GC before operation: completed in 209.138 ms, heap usage 344.855 MB -> 103.963 MB. [2024-09-26T21:35:44.403Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1588.720 ms) ====== [2024-09-26T21:35:44.403Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-09-26T21:35:44.403Z] GC before operation: completed in 244.599 ms, heap usage 342.478 MB -> 104.136 MB. [2024-09-26T21:35:46.314Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1640.104 ms) ====== [2024-09-26T21:35:46.314Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-09-26T21:35:46.314Z] GC before operation: completed in 170.516 ms, heap usage 368.586 MB -> 104.521 MB. [2024-09-26T21:35:48.225Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1589.889 ms) ====== [2024-09-26T21:35:48.225Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-09-26T21:35:48.225Z] GC before operation: completed in 190.628 ms, heap usage 404.903 MB -> 105.096 MB. [2024-09-26T21:35:50.144Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1598.296 ms) ====== [2024-09-26T21:35:50.144Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-09-26T21:35:50.144Z] GC before operation: completed in 165.349 ms, heap usage 403.756 MB -> 105.300 MB. [2024-09-26T21:35:51.074Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1594.508 ms) ====== [2024-09-26T21:35:51.074Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-09-26T21:35:52.002Z] GC before operation: completed in 224.603 ms, heap usage 371.924 MB -> 105.354 MB. [2024-09-26T21:35:52.930Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1559.102 ms) ====== [2024-09-26T21:35:52.930Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-09-26T21:35:53.864Z] GC before operation: completed in 198.079 ms, heap usage 361.933 MB -> 105.569 MB. [2024-09-26T21:35:54.792Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1504.470 ms) ====== [2024-09-26T21:35:54.792Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-09-26T21:35:54.792Z] GC before operation: completed in 218.649 ms, heap usage 395.351 MB -> 106.008 MB. [2024-09-26T21:35:56.708Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1520.020 ms) ====== [2024-09-26T21:35:56.708Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-09-26T21:35:56.708Z] GC before operation: completed in 216.909 ms, heap usage 261.086 MB -> 105.234 MB. [2024-09-26T21:35:58.622Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1453.559 ms) ====== [2024-09-26T21:35:58.622Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-09-26T21:35:58.622Z] GC before operation: completed in 193.177 ms, heap usage 189.529 MB -> 104.831 MB. [2024-09-26T21:36:00.534Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1495.271 ms) ====== [2024-09-26T21:36:00.534Z] ----------------------------------- [2024-09-26T21:36:00.534Z] renaissance-log-regression_0_PASSED [2024-09-26T21:36:00.534Z] ----------------------------------- [2024-09-26T21:36:00.534Z] [2024-09-26T21:36:00.534Z] TEST TEARDOWN: [2024-09-26T21:36:00.534Z] Nothing to be done for teardown. [2024-09-26T21:36:00.534Z] renaissance-log-regression_0 Finish Time: Thu Sep 26 21:35:59 2024 Epoch Time (ms): 1727386559751