[2024-06-26T20:20:18.423Z] Running test renaissance-log-regression_0 ... [2024-06-26T20:20:18.423Z] =============================================== [2024-06-26T20:20:18.423Z] renaissance-log-regression_0 Start Time: Wed Jun 26 16:20:18 2024 Epoch Time (ms): 1719433218206 [2024-06-26T20:20:18.423Z] variation: NoOptions [2024-06-26T20:20:18.423Z] JVM_OPTIONS: [2024-06-26T20:20:18.423Z] { \ [2024-06-26T20:20:18.423Z] echo ""; echo "TEST SETUP:"; \ [2024-06-26T20:20:18.423Z] echo "Nothing to be done for setup."; \ [2024-06-26T20:20:18.423Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17194329494866/renaissance-log-regression_0"; \ [2024-06-26T20:20:18.423Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17194329494866/renaissance-log-regression_0"; \ [2024-06-26T20:20:18.423Z] echo ""; echo "TESTING:"; \ [2024-06-26T20:20:18.423Z] "/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_17194329494866/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-06-26T20:20:18.423Z] 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_17194329494866/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-06-26T20:20:18.423Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-06-26T20:20:18.423Z] echo "Nothing to be done for teardown."; \ [2024-06-26T20:20:18.423Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17194329494866/TestTargetResult"; [2024-06-26T20:20:18.423Z] [2024-06-26T20:20:18.423Z] TEST SETUP: [2024-06-26T20:20:18.423Z] Nothing to be done for setup. [2024-06-26T20:20:18.423Z] [2024-06-26T20:20:18.423Z] TESTING: [2024-06-26T20:20:20.790Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-06-26T20:20:22.010Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2024-06-26T20:20:23.758Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-06-26T20:20:23.758Z] GC before operation: completed in 35.815 ms, heap usage 67.641 MB -> 34.821 MB. [2024-06-26T20:20:27.813Z] ====== log-regression (apache-spark) [default], iteration 0 completed (3947.393 ms) ====== [2024-06-26T20:20:27.813Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-06-26T20:20:27.813Z] GC before operation: completed in 45.814 ms, heap usage 278.391 MB -> 110.307 MB. [2024-06-26T20:20:29.075Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1136.439 ms) ====== [2024-06-26T20:20:29.075Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-06-26T20:20:29.075Z] GC before operation: completed in 34.354 ms, heap usage 299.512 MB -> 110.859 MB. [2024-06-26T20:20:30.292Z] ====== log-regression (apache-spark) [default], iteration 2 completed (946.333 ms) ====== [2024-06-26T20:20:30.292Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-06-26T20:20:30.292Z] GC before operation: completed in 33.406 ms, heap usage 318.325 MB -> 111.335 MB. [2024-06-26T20:20:31.053Z] ====== log-regression (apache-spark) [default], iteration 3 completed (878.173 ms) ====== [2024-06-26T20:20:31.053Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-06-26T20:20:31.053Z] GC before operation: completed in 31.950 ms, heap usage 318.106 MB -> 111.574 MB. [2024-06-26T20:20:31.834Z] ====== log-regression (apache-spark) [default], iteration 4 completed (902.753 ms) ====== [2024-06-26T20:20:31.834Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-06-26T20:20:31.834Z] GC before operation: completed in 39.171 ms, heap usage 326.574 MB -> 111.858 MB. [2024-06-26T20:20:32.604Z] ====== log-regression (apache-spark) [default], iteration 5 completed (839.721 ms) ====== [2024-06-26T20:20:32.604Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-06-26T20:20:32.973Z] GC before operation: completed in 39.013 ms, heap usage 276.442 MB -> 111.883 MB. [2024-06-26T20:20:33.750Z] ====== log-regression (apache-spark) [default], iteration 6 completed (759.838 ms) ====== [2024-06-26T20:20:33.751Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-06-26T20:20:33.751Z] GC before operation: completed in 36.747 ms, heap usage 395.641 MB -> 112.292 MB. [2024-06-26T20:20:34.517Z] ====== log-regression (apache-spark) [default], iteration 7 completed (827.203 ms) ====== [2024-06-26T20:20:34.517Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-06-26T20:20:34.517Z] GC before operation: completed in 35.744 ms, heap usage 303.942 MB -> 112.446 MB. [2024-06-26T20:20:35.265Z] ====== log-regression (apache-spark) [default], iteration 8 completed (766.573 ms) ====== [2024-06-26T20:20:35.265Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-06-26T20:20:35.265Z] GC before operation: completed in 44.253 ms, heap usage 275.354 MB -> 112.510 MB. [2024-06-26T20:20:36.040Z] ====== log-regression (apache-spark) [default], iteration 9 completed (783.037 ms) ====== [2024-06-26T20:20:36.040Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-06-26T20:20:36.040Z] GC before operation: completed in 48.582 ms, heap usage 402.844 MB -> 113.021 MB. [2024-06-26T20:20:36.852Z] ====== log-regression (apache-spark) [default], iteration 10 completed (764.424 ms) ====== [2024-06-26T20:20:36.852Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-06-26T20:20:36.852Z] GC before operation: completed in 38.088 ms, heap usage 302.022 MB -> 113.009 MB. [2024-06-26T20:20:37.650Z] ====== log-regression (apache-spark) [default], iteration 11 completed (752.585 ms) ====== [2024-06-26T20:20:37.650Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-06-26T20:20:37.650Z] GC before operation: completed in 41.130 ms, heap usage 246.006 MB -> 113.068 MB. [2024-06-26T20:20:38.426Z] ====== log-regression (apache-spark) [default], iteration 12 completed (729.325 ms) ====== [2024-06-26T20:20:38.426Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-06-26T20:20:38.426Z] GC before operation: completed in 45.847 ms, heap usage 236.325 MB -> 113.219 MB. [2024-06-26T20:20:39.192Z] ====== log-regression (apache-spark) [default], iteration 13 completed (673.704 ms) ====== [2024-06-26T20:20:39.192Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-06-26T20:20:39.192Z] GC before operation: completed in 41.318 ms, heap usage 284.255 MB -> 113.564 MB. [2024-06-26T20:20:39.960Z] ====== log-regression (apache-spark) [default], iteration 14 completed (738.636 ms) ====== [2024-06-26T20:20:39.960Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-06-26T20:20:39.960Z] GC before operation: completed in 37.893 ms, heap usage 391.227 MB -> 113.875 MB. [2024-06-26T20:20:40.724Z] ====== log-regression (apache-spark) [default], iteration 15 completed (704.258 ms) ====== [2024-06-26T20:20:40.724Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-06-26T20:20:40.724Z] GC before operation: completed in 45.823 ms, heap usage 293.543 MB -> 114.530 MB. [2024-06-26T20:20:41.496Z] ====== log-regression (apache-spark) [default], iteration 16 completed (616.038 ms) ====== [2024-06-26T20:20:41.496Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-06-26T20:20:41.496Z] GC before operation: completed in 44.361 ms, heap usage 349.426 MB -> 114.197 MB. [2024-06-26T20:20:42.265Z] ====== log-regression (apache-spark) [default], iteration 17 completed (657.282 ms) ====== [2024-06-26T20:20:42.265Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-06-26T20:20:42.265Z] GC before operation: completed in 45.074 ms, heap usage 301.537 MB -> 114.281 MB. [2024-06-26T20:20:42.620Z] ====== log-regression (apache-spark) [default], iteration 18 completed (617.291 ms) ====== [2024-06-26T20:20:42.620Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-06-26T20:20:42.620Z] GC before operation: completed in 40.001 ms, heap usage 237.030 MB -> 114.333 MB. [2024-06-26T20:20:43.383Z] ====== log-regression (apache-spark) [default], iteration 19 completed (671.206 ms) ====== [2024-06-26T20:20:43.734Z] ----------------------------------- [2024-06-26T20:20:43.734Z] renaissance-log-regression_0_PASSED [2024-06-26T20:20:43.734Z] ----------------------------------- [2024-06-26T20:20:43.734Z] [2024-06-26T20:20:43.734Z] TEST TEARDOWN: [2024-06-26T20:20:43.734Z] Nothing to be done for teardown. [2024-06-26T20:20:43.734Z] renaissance-log-regression_0 Finish Time: Wed Jun 26 16:20:43 2024 Epoch Time (ms): 1719433243355