[2023-04-19T09:48:10.287Z] Running test renaissance-log-regression_0 ... [2023-04-19T09:48:10.287Z] =============================================== [2023-04-19T09:48:10.287Z] renaissance-log-regression_0 Start Time: Wed Apr 19 10:48:10 2023 Epoch Time (ms): 1681897690179 [2023-04-19T09:48:10.287Z] variation: NoOptions [2023-04-19T09:48:10.287Z] JVM_OPTIONS: [2023-04-19T09:48:10.287Z] { \ [2023-04-19T09:48:10.287Z] echo ""; echo "TEST SETUP:"; \ [2023-04-19T09:48:10.287Z] echo "Nothing to be done for setup."; \ [2023-04-19T09:48:10.287Z] mkdir -p "/Users/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_16818969736089/renaissance-log-regression_0"; \ [2023-04-19T09:48:10.287Z] cd "/Users/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_16818969736089/renaissance-log-regression_0"; \ [2023-04-19T09:48:10.287Z] echo ""; echo "TESTING:"; \ [2023-04-19T09:48:10.288Z] "/Users/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_mac/openjdkbinary/j2sdk-image/Contents/Home/bin/..//bin/java" -jar "/Users/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_16818969736089/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2023-04-19T09:48:10.288Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /Users/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_16818969736089/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2023-04-19T09:48:10.288Z] echo ""; echo "TEST TEARDOWN:"; \ [2023-04-19T09:48:10.288Z] echo "Nothing to be done for teardown."; \ [2023-04-19T09:48:10.288Z] } 2>&1 | tee -a "/Users/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_16818969736089/TestTargetResult"; [2023-04-19T09:48:10.288Z] [2023-04-19T09:48:10.288Z] TEST SETUP: [2023-04-19T09:48:10.288Z] Nothing to be done for setup. [2023-04-19T09:48:10.288Z] [2023-04-19T09:48:10.288Z] TESTING: [2023-04-19T09:48:14.485Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2023-04-19T09:48:16.506Z] NOTE: 'log-regression' benchmark uses Spark local executor with 8 (out of 8) threads. [2023-04-19T09:48:20.563Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2023-04-19T09:48:20.563Z] GC before operation: completed in 168.623 ms, heap usage 86.365 MB -> 26.582 MB. [2023-04-19T09:48:31.823Z] ====== log-regression (apache-spark) [default], iteration 0 completed (9782.120 ms) ====== [2023-04-19T09:48:31.823Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2023-04-19T09:48:31.823Z] GC before operation: completed in 351.318 ms, heap usage 528.102 MB -> 103.898 MB. [2023-04-19T09:48:33.106Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2408.672 ms) ====== [2023-04-19T09:48:33.106Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2023-04-19T09:48:33.486Z] GC before operation: completed in 268.685 ms, heap usage 629.701 MB -> 106.904 MB. [2023-04-19T09:48:35.302Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2146.283 ms) ====== [2023-04-19T09:48:35.302Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2023-04-19T09:48:35.674Z] GC before operation: completed in 213.630 ms, heap usage 511.453 MB -> 106.115 MB. [2023-04-19T09:48:37.482Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1767.641 ms) ====== [2023-04-19T09:48:37.482Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2023-04-19T09:48:37.853Z] GC before operation: completed in 205.089 ms, heap usage 397.931 MB -> 105.233 MB. [2023-04-19T09:48:39.664Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1773.566 ms) ====== [2023-04-19T09:48:39.664Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2023-04-19T09:48:39.664Z] GC before operation: completed in 163.533 ms, heap usage 368.755 MB -> 105.350 MB. [2023-04-19T09:48:41.521Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1776.676 ms) ====== [2023-04-19T09:48:41.521Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2023-04-19T09:48:41.913Z] GC before operation: completed in 163.784 ms, heap usage 374.110 MB -> 105.849 MB. [2023-04-19T09:48:43.283Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1828.670 ms) ====== [2023-04-19T09:48:43.283Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2023-04-19T09:48:43.666Z] GC before operation: completed in 187.987 ms, heap usage 380.733 MB -> 106.401 MB. [2023-04-19T09:48:45.489Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1682.368 ms) ====== [2023-04-19T09:48:45.489Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2023-04-19T09:48:45.489Z] GC before operation: completed in 191.759 ms, heap usage 380.828 MB -> 106.807 MB. [2023-04-19T09:48:47.303Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1953.036 ms) ====== [2023-04-19T09:48:47.303Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2023-04-19T09:48:47.675Z] GC before operation: completed in 214.174 ms, heap usage 386.215 MB -> 107.257 MB. [2023-04-19T09:48:51.276Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1757.495 ms) ====== [2023-04-19T09:48:51.276Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2023-04-19T09:48:51.276Z] GC before operation: completed in 196.516 ms, heap usage 377.152 MB -> 107.699 MB. [2023-04-19T09:48:51.276Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1595.853 ms) ====== [2023-04-19T09:48:51.276Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2023-04-19T09:48:51.276Z] GC before operation: completed in 171.715 ms, heap usage 385.268 MB -> 108.148 MB. [2023-04-19T09:48:53.123Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1652.877 ms) ====== [2023-04-19T09:48:53.123Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2023-04-19T09:48:53.123Z] GC before operation: completed in 215.914 ms, heap usage 373.487 MB -> 108.567 MB. [2023-04-19T09:48:54.933Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1761.535 ms) ====== [2023-04-19T09:48:54.933Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2023-04-19T09:48:55.535Z] GC before operation: completed in 160.762 ms, heap usage 391.258 MB -> 108.965 MB. [2023-04-19T09:48:57.342Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1899.180 ms) ====== [2023-04-19T09:48:57.342Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2023-04-19T09:48:57.342Z] GC before operation: completed in 169.058 ms, heap usage 378.586 MB -> 109.406 MB. [2023-04-19T09:48:58.610Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1499.003 ms) ====== [2023-04-19T09:48:58.610Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2023-04-19T09:48:59.396Z] GC before operation: completed in 156.904 ms, heap usage 381.546 MB -> 109.855 MB. [2023-04-19T09:49:00.684Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1643.463 ms) ====== [2023-04-19T09:49:00.684Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2023-04-19T09:49:00.684Z] GC before operation: completed in 208.862 ms, heap usage 388.160 MB -> 110.322 MB. [2023-04-19T09:49:02.520Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1537.698 ms) ====== [2023-04-19T09:49:02.520Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2023-04-19T09:49:02.520Z] GC before operation: completed in 223.790 ms, heap usage 388.806 MB -> 110.826 MB. [2023-04-19T09:49:04.348Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1538.762 ms) ====== [2023-04-19T09:49:04.348Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2023-04-19T09:49:04.348Z] GC before operation: completed in 274.031 ms, heap usage 379.146 MB -> 111.209 MB. [2023-04-19T09:49:06.157Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1613.456 ms) ====== [2023-04-19T09:49:06.157Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2023-04-19T09:49:06.157Z] GC before operation: completed in 212.050 ms, heap usage 392.339 MB -> 111.613 MB. [2023-04-19T09:49:07.968Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1774.651 ms) ====== [2023-04-19T09:49:08.336Z] ----------------------------------- [2023-04-19T09:49:08.336Z] renaissance-log-regression_0_PASSED [2023-04-19T09:49:08.336Z] ----------------------------------- [2023-04-19T09:49:08.336Z] [2023-04-19T09:49:08.336Z] TEST TEARDOWN: [2023-04-19T09:49:08.336Z] Nothing to be done for teardown. [2023-04-19T09:49:08.336Z] renaissance-log-regression_0 Finish Time: Wed Apr 19 10:49:08 2023 Epoch Time (ms): 1681897748290