[2024-08-14T21:03:55.941Z] Running test renaissance-log-regression_0 ... [2024-08-14T21:03:55.941Z] =============================================== [2024-08-14T21:03:55.941Z] renaissance-log-regression_0 Start Time: Wed Aug 14 17:03:55 2024 Epoch Time (ms): 1723669435636 [2024-08-14T21:03:55.941Z] variation: NoOptions [2024-08-14T21:03:55.941Z] JVM_OPTIONS: [2024-08-14T21:03:55.941Z] { \ [2024-08-14T21:03:55.941Z] echo ""; echo "TEST SETUP:"; \ [2024-08-14T21:03:55.941Z] echo "Nothing to be done for setup."; \ [2024-08-14T21:03:55.941Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17236692022236/renaissance-log-regression_0"; \ [2024-08-14T21:03:55.941Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17236692022236/renaissance-log-regression_0"; \ [2024-08-14T21:03:55.941Z] echo ""; echo "TESTING:"; \ [2024-08-14T21:03:55.941Z] "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac_testList_0/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_aarch64_mac_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17236692022236/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-14T21:03:55.941Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac_testList_0/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17236692022236/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-14T21:03:55.941Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-14T21:03:55.941Z] echo "Nothing to be done for teardown."; \ [2024-08-14T21:03:55.941Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17236692022236/TestTargetResult"; [2024-08-14T21:03:55.941Z] [2024-08-14T21:03:55.941Z] TEST SETUP: [2024-08-14T21:03:55.941Z] Nothing to be done for setup. [2024-08-14T21:03:55.941Z] [2024-08-14T21:03:55.941Z] TESTING: [2024-08-14T21:03:57.699Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-14T21:03:58.055Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2024-08-14T21:03:59.816Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-14T21:03:59.816Z] GC before operation: completed in 31.000 ms, heap usage 51.302 MB -> 34.892 MB. [2024-08-14T21:04:03.841Z] ====== log-regression (apache-spark) [default], iteration 0 completed (3598.012 ms) ====== [2024-08-14T21:04:03.841Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-14T21:04:03.841Z] GC before operation: completed in 36.077 ms, heap usage 349.360 MB -> 110.609 MB. [2024-08-14T21:04:04.207Z] ====== log-regression (apache-spark) [default], iteration 1 completed (825.237 ms) ====== [2024-08-14T21:04:04.207Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-14T21:04:04.207Z] GC before operation: completed in 26.348 ms, heap usage 351.445 MB -> 111.132 MB. [2024-08-14T21:04:05.002Z] ====== log-regression (apache-spark) [default], iteration 2 completed (740.800 ms) ====== [2024-08-14T21:04:05.002Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-14T21:04:05.002Z] GC before operation: completed in 34.112 ms, heap usage 320.863 MB -> 111.521 MB. [2024-08-14T21:04:06.245Z] ====== log-regression (apache-spark) [default], iteration 3 completed (936.926 ms) ====== [2024-08-14T21:04:06.245Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-14T21:04:06.245Z] GC before operation: completed in 45.738 ms, heap usage 318.070 MB -> 111.706 MB. [2024-08-14T21:04:07.008Z] ====== log-regression (apache-spark) [default], iteration 4 completed (820.350 ms) ====== [2024-08-14T21:04:07.008Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-14T21:04:07.008Z] GC before operation: completed in 40.124 ms, heap usage 232.235 MB -> 111.815 MB. [2024-08-14T21:04:07.757Z] ====== log-regression (apache-spark) [default], iteration 5 completed (666.093 ms) ====== [2024-08-14T21:04:07.757Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-14T21:04:07.757Z] GC before operation: completed in 24.743 ms, heap usage 260.863 MB -> 112.003 MB. [2024-08-14T21:04:08.108Z] ====== log-regression (apache-spark) [default], iteration 6 completed (520.703 ms) ====== [2024-08-14T21:04:08.108Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-14T21:04:08.108Z] GC before operation: completed in 26.123 ms, heap usage 314.905 MB -> 112.252 MB. [2024-08-14T21:04:08.867Z] ====== log-regression (apache-spark) [default], iteration 7 completed (524.249 ms) ====== [2024-08-14T21:04:08.867Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-14T21:04:08.867Z] GC before operation: completed in 26.474 ms, heap usage 316.346 MB -> 112.496 MB. [2024-08-14T21:04:09.222Z] ====== log-regression (apache-spark) [default], iteration 8 completed (502.606 ms) ====== [2024-08-14T21:04:09.222Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-14T21:04:09.222Z] GC before operation: completed in 27.330 ms, heap usage 313.473 MB -> 112.659 MB. [2024-08-14T21:04:09.572Z] ====== log-regression (apache-spark) [default], iteration 9 completed (497.967 ms) ====== [2024-08-14T21:04:09.572Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-14T21:04:09.925Z] GC before operation: completed in 27.172 ms, heap usage 332.684 MB -> 113.018 MB. [2024-08-14T21:04:10.299Z] ====== log-regression (apache-spark) [default], iteration 10 completed (488.179 ms) ====== [2024-08-14T21:04:10.299Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-14T21:04:10.299Z] GC before operation: completed in 28.060 ms, heap usage 436.477 MB -> 113.431 MB. [2024-08-14T21:04:10.681Z] ====== log-regression (apache-spark) [default], iteration 11 completed (545.216 ms) ====== [2024-08-14T21:04:10.681Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-14T21:04:11.098Z] GC before operation: completed in 30.198 ms, heap usage 227.617 MB -> 113.165 MB. [2024-08-14T21:04:11.453Z] ====== log-regression (apache-spark) [default], iteration 12 completed (656.170 ms) ====== [2024-08-14T21:04:11.453Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-14T21:04:11.453Z] GC before operation: completed in 45.980 ms, heap usage 321.898 MB -> 113.522 MB. [2024-08-14T21:04:12.229Z] ====== log-regression (apache-spark) [default], iteration 13 completed (753.452 ms) ====== [2024-08-14T21:04:12.229Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-14T21:04:12.229Z] GC before operation: completed in 35.431 ms, heap usage 257.373 MB -> 113.570 MB. [2024-08-14T21:04:12.993Z] ====== log-regression (apache-spark) [default], iteration 14 completed (586.041 ms) ====== [2024-08-14T21:04:12.993Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-14T21:04:12.993Z] GC before operation: completed in 41.966 ms, heap usage 293.901 MB -> 113.874 MB. [2024-08-14T21:04:13.758Z] ====== log-regression (apache-spark) [default], iteration 15 completed (629.097 ms) ====== [2024-08-14T21:04:13.758Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-14T21:04:13.758Z] GC before operation: completed in 34.041 ms, heap usage 230.237 MB -> 113.949 MB. [2024-08-14T21:04:14.518Z] ====== log-regression (apache-spark) [default], iteration 16 completed (650.401 ms) ====== [2024-08-14T21:04:14.518Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-14T21:04:14.518Z] GC before operation: completed in 40.029 ms, heap usage 426.543 MB -> 115.025 MB. [2024-08-14T21:04:14.871Z] ====== log-regression (apache-spark) [default], iteration 17 completed (668.531 ms) ====== [2024-08-14T21:04:14.871Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-14T21:04:15.227Z] GC before operation: completed in 49.997 ms, heap usage 294.477 MB -> 114.399 MB. [2024-08-14T21:04:15.585Z] ====== log-regression (apache-spark) [default], iteration 18 completed (664.269 ms) ====== [2024-08-14T21:04:15.585Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-14T21:04:15.585Z] GC before operation: completed in 35.929 ms, heap usage 454.230 MB -> 114.960 MB. [2024-08-14T21:04:16.350Z] ====== log-regression (apache-spark) [default], iteration 19 completed (534.472 ms) ====== [2024-08-14T21:04:16.350Z] ----------------------------------- [2024-08-14T21:04:16.350Z] renaissance-log-regression_0_PASSED [2024-08-14T21:04:16.350Z] ----------------------------------- [2024-08-14T21:04:16.350Z] [2024-08-14T21:04:16.350Z] TEST TEARDOWN: [2024-08-14T21:04:16.350Z] Nothing to be done for teardown. [2024-08-14T21:04:16.350Z] renaissance-log-regression_0 Finish Time: Wed Aug 14 17:04:16 2024 Epoch Time (ms): 1723669456224