[2024-09-05T22:24:25.564Z] Running test renaissance-log-regression_0 ... [2024-09-05T22:24:25.564Z] =============================================== [2024-09-05T22:24:25.564Z] renaissance-log-regression_0 Start Time: Thu Sep 5 22:24:24 2024 Epoch Time (ms): 1725575064736 [2024-09-05T22:24:25.564Z] variation: NoOptions [2024-09-05T22:24:25.564Z] JVM_OPTIONS: [2024-09-05T22:24:25.564Z] { \ [2024-09-05T22:24:25.564Z] echo ""; echo "TEST SETUP:"; \ [2024-09-05T22:24:25.564Z] echo "Nothing to be done for setup."; \ [2024-09-05T22:24:25.564Z] mkdir -p "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17255742407341/renaissance-log-regression_0"; \ [2024-09-05T22:24:25.564Z] cd "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17255742407341/renaissance-log-regression_0"; \ [2024-09-05T22:24:25.564Z] echo ""; echo "TESTING:"; \ [2024-09-05T22:24:25.564Z] "/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_17255742407341/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-09-05T22:24:25.564Z] 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_17255742407341/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-05T22:24:25.564Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-05T22:24:25.564Z] echo "Nothing to be done for teardown."; \ [2024-09-05T22:24:25.564Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17255742407341/TestTargetResult"; [2024-09-05T22:24:25.564Z] [2024-09-05T22:24:25.564Z] TEST SETUP: [2024-09-05T22:24:25.564Z] Nothing to be done for setup. [2024-09-05T22:24:25.564Z] [2024-09-05T22:24:25.564Z] TESTING: [2024-09-05T22:24:28.524Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-05T22:24:31.451Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-09-05T22:24:36.378Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-09-05T22:24:36.378Z] GC before operation: completed in 145.844 ms, heap usage 106.508 MB -> 26.419 MB. [2024-09-05T22:24:45.886Z] ====== log-regression (apache-spark) [default], iteration 0 completed (10068.673 ms) ====== [2024-09-05T22:24:45.886Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-09-05T22:24:45.886Z] GC before operation: completed in 298.420 ms, heap usage 397.282 MB -> 101.527 MB. [2024-09-05T22:24:48.826Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2335.683 ms) ====== [2024-09-05T22:24:48.826Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-09-05T22:24:48.826Z] GC before operation: completed in 207.759 ms, heap usage 361.913 MB -> 101.637 MB. [2024-09-05T22:24:50.725Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2159.155 ms) ====== [2024-09-05T22:24:50.725Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-09-05T22:24:50.725Z] GC before operation: completed in 195.664 ms, heap usage 344.190 MB -> 102.060 MB. [2024-09-05T22:24:52.623Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1882.732 ms) ====== [2024-09-05T22:24:52.623Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-09-05T22:24:53.544Z] GC before operation: completed in 213.619 ms, heap usage 249.596 MB -> 101.486 MB. [2024-09-05T22:24:55.440Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1813.589 ms) ====== [2024-09-05T22:24:55.440Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-09-05T22:24:55.440Z] GC before operation: completed in 205.253 ms, heap usage 279.514 MB -> 101.999 MB. [2024-09-05T22:24:57.335Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1761.742 ms) ====== [2024-09-05T22:24:57.336Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-09-05T22:24:57.336Z] GC before operation: completed in 190.913 ms, heap usage 193.992 MB -> 101.644 MB. [2024-09-05T22:24:59.233Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1676.284 ms) ====== [2024-09-05T22:24:59.233Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-09-05T22:24:59.233Z] GC before operation: completed in 204.346 ms, heap usage 234.679 MB -> 102.252 MB. [2024-09-05T22:25:01.131Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1759.167 ms) ====== [2024-09-05T22:25:01.131Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-09-05T22:25:01.131Z] GC before operation: completed in 192.985 ms, heap usage 331.805 MB -> 103.299 MB. [2024-09-05T22:25:03.025Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1619.599 ms) ====== [2024-09-05T22:25:03.025Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-09-05T22:25:03.025Z] GC before operation: completed in 182.558 ms, heap usage 292.692 MB -> 103.255 MB. [2024-09-05T22:25:04.926Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1569.021 ms) ====== [2024-09-05T22:25:04.926Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-09-05T22:25:04.926Z] GC before operation: completed in 195.171 ms, heap usage 366.578 MB -> 104.067 MB. [2024-09-05T22:25:05.858Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1501.506 ms) ====== [2024-09-05T22:25:05.858Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-09-05T22:25:06.785Z] GC before operation: completed in 233.022 ms, heap usage 275.105 MB -> 103.593 MB. [2024-09-05T22:25:07.709Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1550.138 ms) ====== [2024-09-05T22:25:07.709Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-09-05T22:25:07.709Z] GC before operation: completed in 142.777 ms, heap usage 370.464 MB -> 104.585 MB. [2024-09-05T22:25:09.605Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1530.301 ms) ====== [2024-09-05T22:25:09.605Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-09-05T22:25:09.605Z] GC before operation: completed in 160.544 ms, heap usage 289.077 MB -> 104.195 MB. [2024-09-05T22:25:11.500Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1496.262 ms) ====== [2024-09-05T22:25:11.500Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-09-05T22:25:11.500Z] GC before operation: completed in 165.081 ms, heap usage 360.827 MB -> 105.079 MB. [2024-09-05T22:25:12.424Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1470.964 ms) ====== [2024-09-05T22:25:12.424Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-09-05T22:25:13.347Z] GC before operation: completed in 184.816 ms, heap usage 308.569 MB -> 104.878 MB. [2024-09-05T22:25:14.269Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1483.376 ms) ====== [2024-09-05T22:25:14.269Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-09-05T22:25:15.191Z] GC before operation: completed in 195.734 ms, heap usage 308.375 MB -> 105.068 MB. [2024-09-05T22:25:16.116Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1456.863 ms) ====== [2024-09-05T22:25:16.116Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-09-05T22:25:16.116Z] GC before operation: completed in 190.112 ms, heap usage 298.831 MB -> 105.276 MB. [2024-09-05T22:25:18.009Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1386.432 ms) ====== [2024-09-05T22:25:18.009Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-09-05T22:25:18.009Z] GC before operation: completed in 206.470 ms, heap usage 196.786 MB -> 104.569 MB. [2024-09-05T22:25:19.901Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1441.812 ms) ====== [2024-09-05T22:25:19.901Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-09-05T22:25:19.901Z] GC before operation: completed in 201.037 ms, heap usage 228.611 MB -> 105.071 MB. [2024-09-05T22:25:20.823Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1421.459 ms) ====== [2024-09-05T22:25:20.823Z] ----------------------------------- [2024-09-05T22:25:20.823Z] renaissance-log-regression_0_PASSED [2024-09-05T22:25:20.823Z] ----------------------------------- [2024-09-05T22:25:20.823Z] [2024-09-05T22:25:20.823Z] TEST TEARDOWN: [2024-09-05T22:25:20.823Z] Nothing to be done for teardown. [2024-09-05T22:25:20.823Z] renaissance-log-regression_0 Finish Time: Thu Sep 5 22:25:20 2024 Epoch Time (ms): 1725575120732