[2023-04-19T10:34:07.133Z] Running test renaissance-log-regression_0 ... [2023-04-19T10:34:07.133Z] =============================================== [2023-04-19T10:34:07.133Z] renaissance-log-regression_0 Start Time: Wed Apr 19 10:34:06 2023 Epoch Time (ms): 1681900446472 [2023-04-19T10:34:07.133Z] variation: NoOptions [2023-04-19T10:34:07.133Z] JVM_OPTIONS: [2023-04-19T10:34:07.133Z] { \ [2023-04-19T10:34:07.133Z] echo ""; echo "TEST SETUP:"; \ [2023-04-19T10:34:07.133Z] echo "Nothing to be done for setup."; \ [2023-04-19T10:34:07.133Z] mkdir -p "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_16818995653055/renaissance-log-regression_0"; \ [2023-04-19T10:34:07.133Z] cd "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_16818995653055/renaissance-log-regression_0"; \ [2023-04-19T10:34:07.133Z] echo ""; echo "TESTING:"; \ [2023-04-19T10:34:07.133Z] "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/openjdkbinary/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_16818995653055/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2023-04-19T10:34:07.133Z] 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_16818995653055/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2023-04-19T10:34:07.133Z] echo ""; echo "TEST TEARDOWN:"; \ [2023-04-19T10:34:07.133Z] echo "Nothing to be done for teardown."; \ [2023-04-19T10:34:07.133Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_16818995653055/TestTargetResult"; [2023-04-19T10:34:07.133Z] [2023-04-19T10:34:07.133Z] TEST SETUP: [2023-04-19T10:34:07.133Z] Nothing to be done for setup. [2023-04-19T10:34:07.133Z] [2023-04-19T10:34:07.133Z] TESTING: [2023-04-19T10:34:12.382Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2023-04-19T10:34:14.883Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2023-04-19T10:34:18.953Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2023-04-19T10:34:18.953Z] GC before operation: completed in 192.043 ms, heap usage 103.755 MB -> 26.236 MB. [2023-04-19T10:34:30.660Z] ====== log-regression (apache-spark) [default], iteration 0 completed (10707.470 ms) ====== [2023-04-19T10:34:30.660Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2023-04-19T10:34:30.660Z] GC before operation: completed in 276.784 ms, heap usage 217.754 MB -> 100.185 MB. [2023-04-19T10:34:32.586Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2498.835 ms) ====== [2023-04-19T10:34:32.586Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2023-04-19T10:34:32.586Z] GC before operation: completed in 163.977 ms, heap usage 256.223 MB -> 100.894 MB. [2023-04-19T10:34:35.662Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2216.888 ms) ====== [2023-04-19T10:34:35.662Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2023-04-19T10:34:35.662Z] GC before operation: completed in 189.315 ms, heap usage 207.533 MB -> 100.865 MB. [2023-04-19T10:35:07.382Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2044.949 ms) ====== [2023-04-19T10:35:07.382Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2023-04-19T10:35:07.382Z] GC before operation: completed in 207.741 ms, heap usage 250.612 MB -> 101.541 MB. [2023-04-19T10:35:07.382Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2068.239 ms) ====== [2023-04-19T10:35:07.382Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2023-04-19T10:35:07.383Z] GC before operation: completed in 236.410 ms, heap usage 276.295 MB -> 102.057 MB. [2023-04-19T10:35:07.383Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1889.573 ms) ====== [2023-04-19T10:35:07.383Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2023-04-19T10:35:07.383Z] GC before operation: completed in 219.221 ms, heap usage 361.462 MB -> 103.090 MB. [2023-04-19T10:35:07.383Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1719.574 ms) ====== [2023-04-19T10:35:07.383Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2023-04-19T10:35:07.383Z] GC before operation: completed in 177.114 ms, heap usage 377.067 MB -> 103.440 MB. [2023-04-19T10:35:07.383Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1901.334 ms) ====== [2023-04-19T10:35:07.383Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2023-04-19T10:35:07.383Z] GC before operation: completed in 201.478 ms, heap usage 244.828 MB -> 102.496 MB. [2023-04-19T10:35:07.383Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1765.180 ms) ====== [2023-04-19T10:35:07.383Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2023-04-19T10:35:07.383Z] GC before operation: completed in 186.252 ms, heap usage 307.783 MB -> 103.301 MB. [2023-04-19T10:35:07.383Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1785.609 ms) ====== [2023-04-19T10:35:07.383Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2023-04-19T10:35:07.383Z] GC before operation: completed in 174.827 ms, heap usage 260.902 MB -> 103.096 MB. [2023-04-19T10:35:07.383Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1701.132 ms) ====== [2023-04-19T10:35:07.383Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2023-04-19T10:35:07.383Z] GC before operation: completed in 249.905 ms, heap usage 172.923 MB -> 102.935 MB. [2023-04-19T10:35:07.383Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1768.602 ms) ====== [2023-04-19T10:35:07.383Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2023-04-19T10:35:07.383Z] GC before operation: completed in 197.350 ms, heap usage 261.216 MB -> 103.658 MB. [2023-04-19T10:35:07.383Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1639.953 ms) ====== [2023-04-19T10:35:07.383Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2023-04-19T10:35:07.383Z] GC before operation: completed in 192.409 ms, heap usage 278.738 MB -> 104.057 MB. [2023-04-19T10:35:08.057Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2044.949 ms) ====== [2023-04-19T10:35:08.057Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2023-04-19T10:35:08.057Z] GC before operation: completed in 207.741 ms, heap usage 250.612 MB -> 101.541 MB. [2023-04-19T10:35:08.057Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2068.239 ms) ====== [2023-04-19T10:35:08.057Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2023-04-19T10:35:08.057Z] GC before operation: completed in 236.410 ms, heap usage 276.295 MB -> 102.057 MB. [2023-04-19T10:35:08.057Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1889.573 ms) ====== [2023-04-19T10:35:08.057Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2023-04-19T10:35:08.057Z] GC before operation: completed in 219.221 ms, heap usage 361.462 MB -> 103.090 MB. [2023-04-19T10:35:08.057Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1719.574 ms) ====== [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2023-04-19T10:35:08.058Z] GC before operation: completed in 177.114 ms, heap usage 377.067 MB -> 103.440 MB. [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1901.334 ms) ====== [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2023-04-19T10:35:08.058Z] GC before operation: completed in 201.478 ms, heap usage 244.828 MB -> 102.496 MB. [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1765.180 ms) ====== [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2023-04-19T10:35:08.058Z] GC before operation: completed in 186.252 ms, heap usage 307.783 MB -> 103.301 MB. [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1785.609 ms) ====== [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2023-04-19T10:35:08.058Z] GC before operation: completed in 174.827 ms, heap usage 260.902 MB -> 103.096 MB. [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1701.132 ms) ====== [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2023-04-19T10:35:08.058Z] GC before operation: completed in 249.905 ms, heap usage 172.923 MB -> 102.935 MB. [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1768.602 ms) ====== [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2023-04-19T10:35:08.058Z] GC before operation: completed in 197.350 ms, heap usage 261.216 MB -> 103.658 MB. [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1639.953 ms) ====== [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2023-04-19T10:35:08.058Z] GC before operation: completed in 192.409 ms, heap usage 278.738 MB -> 104.057 MB. [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1654.572 ms) ====== [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2023-04-19T10:35:08.058Z] GC before operation: completed in 205.413 ms, heap usage 273.428 MB -> 104.295 MB. [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1600.105 ms) ====== [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2023-04-19T10:35:08.058Z] GC before operation: completed in 184.163 ms, heap usage 327.295 MB -> 104.999 MB. [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1687.717 ms) ====== [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2023-04-19T10:35:08.058Z] GC before operation: completed in 219.711 ms, heap usage 266.300 MB -> 104.674 MB. [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1635.017 ms) ====== [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2023-04-19T10:35:08.058Z] GC before operation: completed in 207.537 ms, heap usage 227.417 MB -> 104.673 MB. [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1601.636 ms) ====== [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2023-04-19T10:35:08.058Z] GC before operation: completed in 196.749 ms, heap usage 282.442 MB -> 105.330 MB. [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1669.996 ms) ====== [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2023-04-19T10:35:08.058Z] GC before operation: completed in 201.152 ms, heap usage 267.893 MB -> 105.516 MB. [2023-04-19T10:35:08.058Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1595.002 ms) ====== [2023-04-19T10:35:09.522Z] ----------------------------------- [2023-04-19T10:35:09.522Z] renaissance-log-regression_0_PASSED [2023-04-19T10:35:09.522Z] ----------------------------------- [2023-04-19T10:35:09.522Z] [2023-04-19T10:35:09.522Z] TEST TEARDOWN: [2023-04-19T10:35:09.522Z] Nothing to be done for teardown. [2023-04-19T10:35:09.522Z] renaissance-log-regression_0 Finish Time: Wed Apr 19 10:35:08 2023 Epoch Time (ms): 1681900508042