[2024-09-05T01:16:07.575Z] Running test renaissance-log-regression_0 ... [2024-09-05T01:16:07.575Z] =============================================== [2024-09-05T01:16:07.575Z] renaissance-log-regression_0 Start Time: Wed Sep 4 18:16:07 2024 Epoch Time (ms): 1725498967382 [2024-09-05T01:16:07.575Z] variation: NoOptions [2024-09-05T01:16:07.575Z] JVM_OPTIONS: [2024-09-05T01:16:07.575Z] { \ [2024-09-05T01:16:07.575Z] echo ""; echo "TEST SETUP:"; \ [2024-09-05T01:16:07.575Z] echo "Nothing to be done for setup."; \ [2024-09-05T01:16:07.575Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17254983258969/renaissance-log-regression_0"; \ [2024-09-05T01:16:07.575Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17254983258969/renaissance-log-regression_0"; \ [2024-09-05T01:16:07.575Z] echo ""; echo "TESTING:"; \ [2024-09-05T01:16:07.575Z] "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/jdkbinary/j2sdk-image/Contents/Home/bin/..//bin/java" --add-opens java.base/java.lang=ALL-UNNAMED --add-opens java.base/java.util=ALL-UNNAMED --add-opens java.base/java.util.concurrent=ALL-UNNAMED --add-opens java.base/java.nio=ALL-UNNAMED --add-opens java.base/sun.nio.ch=ALL-UNNAMED --add-opens java.base/java.lang.invoke=ALL-UNNAMED -jar "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17254983258969/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-09-05T01:16:07.575Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17254983258969/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-05T01:16:07.575Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-05T01:16:07.575Z] echo "Nothing to be done for teardown."; \ [2024-09-05T01:16:07.575Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17254983258969/TestTargetResult"; [2024-09-05T01:16:07.996Z] [2024-09-05T01:16:07.996Z] TEST SETUP: [2024-09-05T01:16:07.996Z] Nothing to be done for setup. [2024-09-05T01:16:07.996Z] [2024-09-05T01:16:07.996Z] TESTING: [2024-09-05T01:16:15.122Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-05T01:16:18.734Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2024-09-05T01:16:30.752Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-09-05T01:16:30.752Z] GC before operation: completed in 110.007 ms, heap usage 58.120 MB -> 35.426 MB. [2024-09-05T01:16:53.242Z] ====== log-regression (apache-spark) [default], iteration 0 completed (20320.344 ms) ====== [2024-09-05T01:16:53.242Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-09-05T01:16:53.242Z] GC before operation: completed in 119.860 ms, heap usage 397.885 MB -> 111.204 MB. [2024-09-05T01:16:57.146Z] ====== log-regression (apache-spark) [default], iteration 1 completed (6715.934 ms) ====== [2024-09-05T01:16:57.146Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-09-05T01:16:57.684Z] GC before operation: completed in 183.885 ms, heap usage 406.919 MB -> 111.763 MB. [2024-09-05T01:17:03.028Z] ====== log-regression (apache-spark) [default], iteration 2 completed (5880.773 ms) ====== [2024-09-05T01:17:03.028Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-09-05T01:17:03.522Z] GC before operation: completed in 236.913 ms, heap usage 343.964 MB -> 112.038 MB. [2024-09-05T01:17:08.384Z] ====== log-regression (apache-spark) [default], iteration 3 completed (4372.128 ms) ====== [2024-09-05T01:17:08.384Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-09-05T01:17:08.384Z] GC before operation: completed in 168.256 ms, heap usage 232.651 MB -> 112.101 MB. [2024-09-05T01:17:12.237Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3854.169 ms) ====== [2024-09-05T01:17:12.237Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-09-05T01:17:12.237Z] GC before operation: completed in 131.460 ms, heap usage 287.933 MB -> 112.444 MB. [2024-09-05T01:17:16.113Z] ====== log-regression (apache-spark) [default], iteration 5 completed (3724.435 ms) ====== [2024-09-05T01:17:16.113Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-09-05T01:17:16.113Z] GC before operation: completed in 231.963 ms, heap usage 239.175 MB -> 112.570 MB. [2024-09-05T01:17:20.223Z] ====== log-regression (apache-spark) [default], iteration 6 completed (4196.578 ms) ====== [2024-09-05T01:17:20.223Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-09-05T01:17:20.223Z] GC before operation: completed in 139.492 ms, heap usage 253.556 MB -> 112.658 MB. [2024-09-05T01:17:24.019Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3912.806 ms) ====== [2024-09-05T01:17:24.019Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-09-05T01:17:24.444Z] GC before operation: completed in 187.574 ms, heap usage 564.198 MB -> 113.588 MB. [2024-09-05T01:17:27.537Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3307.775 ms) ====== [2024-09-05T01:17:27.537Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-09-05T01:17:28.049Z] GC before operation: completed in 171.918 ms, heap usage 380.020 MB -> 113.449 MB. [2024-09-05T01:17:31.927Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3621.709 ms) ====== [2024-09-05T01:17:31.928Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-09-05T01:17:31.928Z] GC before operation: completed in 276.962 ms, heap usage 353.604 MB -> 113.498 MB. [2024-09-05T01:17:34.800Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2808.378 ms) ====== [2024-09-05T01:17:34.800Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-09-05T01:17:34.800Z] GC before operation: completed in 79.215 ms, heap usage 800.160 MB -> 114.328 MB. [2024-09-05T01:17:37.659Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2608.970 ms) ====== [2024-09-05T01:17:37.659Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-09-05T01:17:37.659Z] GC before operation: completed in 118.972 ms, heap usage 262.444 MB -> 113.655 MB. [2024-09-05T01:17:41.672Z] ====== log-regression (apache-spark) [default], iteration 12 completed (4391.793 ms) ====== [2024-09-05T01:17:41.672Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-09-05T01:17:42.223Z] GC before operation: completed in 317.267 ms, heap usage 759.462 MB -> 114.682 MB. [2024-09-05T01:17:46.092Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3977.504 ms) ====== [2024-09-05T01:17:46.092Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-09-05T01:17:46.092Z] GC before operation: completed in 136.160 ms, heap usage 273.064 MB -> 114.125 MB. [2024-09-05T01:17:50.195Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3913.957 ms) ====== [2024-09-05T01:17:50.195Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-09-05T01:17:50.669Z] GC before operation: completed in 276.426 ms, heap usage 313.043 MB -> 114.304 MB. [2024-09-05T01:17:54.441Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3651.224 ms) ====== [2024-09-05T01:17:54.441Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-09-05T01:17:54.441Z] GC before operation: completed in 207.788 ms, heap usage 403.963 MB -> 114.745 MB. [2024-09-05T01:17:57.410Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2755.972 ms) ====== [2024-09-05T01:17:57.410Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-09-05T01:17:57.410Z] GC before operation: completed in 143.469 ms, heap usage 322.257 MB -> 114.786 MB. [2024-09-05T01:18:01.584Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3777.564 ms) ====== [2024-09-05T01:18:01.584Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-09-05T01:18:01.584Z] GC before operation: completed in 148.170 ms, heap usage 282.770 MB -> 114.882 MB. [2024-09-05T01:18:04.528Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3011.834 ms) ====== [2024-09-05T01:18:04.528Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-09-05T01:18:04.528Z] GC before operation: completed in 152.588 ms, heap usage 301.429 MB -> 115.101 MB. [2024-09-05T01:18:09.346Z] ====== log-regression (apache-spark) [default], iteration 19 completed (4795.860 ms) ====== [2024-09-05T01:18:09.757Z] ----------------------------------- [2024-09-05T01:18:09.757Z] renaissance-log-regression_0_PASSED [2024-09-05T01:18:09.757Z] ----------------------------------- [2024-09-05T01:18:09.757Z] [2024-09-05T01:18:09.757Z] TEST TEARDOWN: [2024-09-05T01:18:09.757Z] Nothing to be done for teardown. [2024-09-05T01:18:09.757Z] renaissance-log-regression_0 Finish Time: Wed Sep 4 18:18:09 2024 Epoch Time (ms): 1725499089383