[2023-04-19T11:04:22.319Z] Running test renaissance-log-regression_0 ... [2023-04-19T11:04:22.319Z] =============================================== [2023-04-19T11:04:22.319Z] renaissance-log-regression_0 Start Time: Wed Apr 19 06:04:17 2023 Epoch Time (ms): 1681902257676 [2023-04-19T11:04:22.863Z] variation: NoOptions [2023-04-19T11:04:22.863Z] JVM_OPTIONS: [2023-04-19T11:04:22.863Z] { \ [2023-04-19T11:04:22.863Z] echo ""; echo "TEST SETUP:"; \ [2023-04-19T11:04:22.863Z] echo "Nothing to be done for setup."; \ [2023-04-19T11:04:22.863Z] mkdir -p "E:/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_16819009566849\\renaissance-log-regression_0"; \ [2023-04-19T11:04:22.863Z] cd "E:/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_16819009566849\\renaissance-log-regression_0"; \ [2023-04-19T11:04:22.863Z] echo ""; echo "TESTING:"; \ [2023-04-19T11:04:22.863Z] "E:/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/openjdkbinary/j2sdk-image\\bin\\java" -jar "E:/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""E:/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_16819009566849\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2023-04-19T11:04:22.863Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd E:/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "E:/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_16819009566849\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2023-04-19T11:04:22.863Z] echo ""; echo "TEST TEARDOWN:"; \ [2023-04-19T11:04:22.863Z] echo "Nothing to be done for teardown."; \ [2023-04-19T11:04:22.863Z] } 2>&1 | tee -a "E:/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_16819009566849\\TestTargetResult"; [2023-04-19T11:04:22.863Z] [2023-04-19T11:04:22.863Z] TEST SETUP: [2023-04-19T11:04:22.863Z] Nothing to be done for setup. [2023-04-19T11:04:22.863Z] [2023-04-19T11:04:22.863Z] TESTING: [2023-04-19T11:04:26.193Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2023-04-19T11:04:28.708Z] NOTE: 'log-regression' benchmark uses Spark local executor with 8 (out of 8) threads. [2023-04-19T11:04:33.985Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2023-04-19T11:04:33.985Z] GC before operation: completed in 219.729 ms, heap usage 110.984 MB -> 28.442 MB. [2023-04-19T11:04:43.404Z] ====== log-regression (apache-spark) [default], iteration 0 completed (8982.632 ms) ====== [2023-04-19T11:04:43.404Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2023-04-19T11:04:43.404Z] GC before operation: completed in 429.136 ms, heap usage 290.161 MB -> 103.270 MB. [2023-04-19T11:04:45.196Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2005.603 ms) ====== [2023-04-19T11:04:45.196Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2023-04-19T11:04:45.740Z] GC before operation: completed in 301.826 ms, heap usage 661.596 MB -> 107.525 MB. [2023-04-19T11:04:47.525Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1760.367 ms) ====== [2023-04-19T11:04:47.525Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2023-04-19T11:04:47.525Z] GC before operation: completed in 213.158 ms, heap usage 644.355 MB -> 107.932 MB. [2023-04-19T11:04:49.313Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1776.434 ms) ====== [2023-04-19T11:04:49.313Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2023-04-19T11:04:49.313Z] GC before operation: completed in 248.580 ms, heap usage 605.226 MB -> 107.987 MB. [2023-04-19T11:04:51.097Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1779.354 ms) ====== [2023-04-19T11:04:51.097Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2023-04-19T11:04:51.638Z] GC before operation: completed in 234.283 ms, heap usage 526.699 MB -> 107.628 MB. [2023-04-19T11:04:53.421Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1654.928 ms) ====== [2023-04-19T11:04:53.421Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2023-04-19T11:04:53.421Z] GC before operation: completed in 241.118 ms, heap usage 537.764 MB -> 108.184 MB. [2023-04-19T11:04:55.205Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1740.397 ms) ====== [2023-04-19T11:04:55.205Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2023-04-19T11:04:55.205Z] GC before operation: completed in 202.639 ms, heap usage 543.319 MB -> 108.637 MB. [2023-04-19T11:04:56.991Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1691.924 ms) ====== [2023-04-19T11:04:56.991Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2023-04-19T11:04:57.530Z] GC before operation: completed in 221.806 ms, heap usage 550.617 MB -> 109.278 MB. [2023-04-19T11:04:59.314Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1616.069 ms) ====== [2023-04-19T11:04:59.314Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2023-04-19T11:04:59.314Z] GC before operation: completed in 240.487 ms, heap usage 557.852 MB -> 109.850 MB. [2023-04-19T11:05:01.096Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1602.350 ms) ====== [2023-04-19T11:05:01.096Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2023-04-19T11:05:01.096Z] GC before operation: completed in 244.385 ms, heap usage 562.277 MB -> 110.269 MB. [2023-04-19T11:05:02.888Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1607.127 ms) ====== [2023-04-19T11:05:02.888Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2023-04-19T11:05:02.888Z] GC before operation: completed in 236.342 ms, heap usage 559.615 MB -> 110.603 MB. [2023-04-19T11:05:04.673Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1682.843 ms) ====== [2023-04-19T11:05:04.673Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2023-04-19T11:05:04.673Z] GC before operation: completed in 184.729 ms, heap usage 563.458 MB -> 111.190 MB. [2023-04-19T11:05:06.456Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1591.776 ms) ====== [2023-04-19T11:05:06.456Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2023-04-19T11:05:06.456Z] GC before operation: completed in 213.294 ms, heap usage 567.297 MB -> 111.625 MB. [2023-04-19T11:05:08.240Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1671.440 ms) ====== [2023-04-19T11:05:08.240Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2023-04-19T11:05:08.240Z] GC before operation: completed in 210.870 ms, heap usage 555.258 MB -> 111.902 MB. [2023-04-19T11:05:10.024Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1601.398 ms) ====== [2023-04-19T11:05:10.024Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2023-04-19T11:05:10.024Z] GC before operation: completed in 232.175 ms, heap usage 552.380 MB -> 112.267 MB. [2023-04-19T11:05:11.814Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1542.097 ms) ====== [2023-04-19T11:05:11.814Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2023-04-19T11:05:11.814Z] GC before operation: completed in 229.152 ms, heap usage 552.275 MB -> 112.693 MB. [2023-04-19T11:05:13.599Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1632.302 ms) ====== [2023-04-19T11:05:13.599Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2023-04-19T11:05:14.143Z] GC before operation: completed in 239.573 ms, heap usage 545.699 MB -> 113.153 MB. [2023-04-19T11:05:15.288Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1391.177 ms) ====== [2023-04-19T11:05:15.288Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2023-04-19T11:05:15.288Z] GC before operation: completed in 209.861 ms, heap usage 546.682 MB -> 113.582 MB. [2023-04-19T11:05:17.075Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1465.753 ms) ====== [2023-04-19T11:05:17.075Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2023-04-19T11:05:17.075Z] GC before operation: completed in 244.890 ms, heap usage 548.894 MB -> 113.990 MB. [2023-04-19T11:05:18.868Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1542.299 ms) ====== [2023-04-19T11:05:19.411Z] ----------------------------------- [2023-04-19T11:05:19.411Z] renaissance-log-regression_0_PASSED [2023-04-19T11:05:19.411Z] ----------------------------------- [2023-04-19T11:05:19.411Z] [2023-04-19T11:05:19.411Z] TEST TEARDOWN: [2023-04-19T11:05:19.411Z] Nothing to be done for teardown. [2023-04-19T11:05:19.411Z] renaissance-log-regression_0 Finish Time: Wed Apr 19 06:05:14 2023 Epoch Time (ms): 1681902314552