[2024-08-28T20:31:31.415Z] Running test renaissance-log-regression_0 ... [2024-08-28T20:31:31.415Z] =============================================== [2024-08-28T20:31:31.415Z] renaissance-log-regression_0 Start Time: Wed Aug 28 16:31:31 2024 Epoch Time (ms): 1724877091145 [2024-08-28T20:31:31.415Z] variation: NoOptions [2024-08-28T20:31:31.415Z] JVM_OPTIONS: [2024-08-28T20:31:31.415Z] { \ [2024-08-28T20:31:31.415Z] echo ""; echo "TEST SETUP:"; \ [2024-08-28T20:31:31.415Z] echo "Nothing to be done for setup."; \ [2024-08-28T20:31:31.415Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17248767966974/renaissance-log-regression_0"; \ [2024-08-28T20:31:31.415Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17248767966974/renaissance-log-regression_0"; \ [2024-08-28T20:31:31.415Z] echo ""; echo "TESTING:"; \ [2024-08-28T20:31:31.415Z] "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/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/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17248767966974/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-28T20:31:31.415Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17248767966974/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-28T20:31:31.415Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-28T20:31:31.415Z] echo "Nothing to be done for teardown."; \ [2024-08-28T20:31:31.415Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17248767966974/TestTargetResult"; [2024-08-28T20:31:31.415Z] [2024-08-28T20:31:31.415Z] TEST SETUP: [2024-08-28T20:31:31.415Z] Nothing to be done for setup. [2024-08-28T20:31:31.415Z] [2024-08-28T20:31:31.415Z] TESTING: [2024-08-28T20:31:33.193Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-28T20:31:33.972Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2024-08-28T20:31:35.760Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-28T20:31:35.760Z] GC before operation: completed in 22.352 ms, heap usage 57.816 MB -> 35.051 MB. [2024-08-28T20:31:40.812Z] ====== log-regression (apache-spark) [default], iteration 0 completed (4195.579 ms) ====== [2024-08-28T20:31:40.812Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-28T20:31:40.812Z] GC before operation: completed in 26.808 ms, heap usage 289.172 MB -> 110.633 MB. [2024-08-28T20:31:40.812Z] ====== log-regression (apache-spark) [default], iteration 1 completed (880.157 ms) ====== [2024-08-28T20:31:40.812Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-28T20:31:40.812Z] GC before operation: completed in 33.998 ms, heap usage 280.638 MB -> 111.653 MB. [2024-08-28T20:31:41.607Z] ====== log-regression (apache-spark) [default], iteration 2 completed (717.207 ms) ====== [2024-08-28T20:31:41.607Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-28T20:31:41.607Z] GC before operation: completed in 24.962 ms, heap usage 311.703 MB -> 111.631 MB. [2024-08-28T20:31:42.376Z] ====== log-regression (apache-spark) [default], iteration 3 completed (686.466 ms) ====== [2024-08-28T20:31:42.376Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-28T20:31:42.376Z] GC before operation: completed in 28.786 ms, heap usage 320.637 MB -> 111.812 MB. [2024-08-28T20:31:43.170Z] ====== log-regression (apache-spark) [default], iteration 4 completed (815.325 ms) ====== [2024-08-28T20:31:43.170Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-28T20:31:43.170Z] GC before operation: completed in 29.016 ms, heap usage 244.083 MB -> 111.929 MB. [2024-08-28T20:31:43.941Z] ====== log-regression (apache-spark) [default], iteration 5 completed (737.124 ms) ====== [2024-08-28T20:31:43.941Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-28T20:31:43.941Z] GC before operation: completed in 45.828 ms, heap usage 357.370 MB -> 112.382 MB. [2024-08-28T20:31:44.732Z] ====== log-regression (apache-spark) [default], iteration 6 completed (829.855 ms) ====== [2024-08-28T20:31:44.732Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-28T20:31:44.732Z] GC before operation: completed in 32.365 ms, heap usage 304.516 MB -> 112.531 MB. [2024-08-28T20:31:45.519Z] ====== log-regression (apache-spark) [default], iteration 7 completed (777.176 ms) ====== [2024-08-28T20:31:45.519Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-28T20:31:45.889Z] GC before operation: completed in 33.247 ms, heap usage 297.294 MB -> 112.632 MB. [2024-08-28T20:31:46.665Z] ====== log-regression (apache-spark) [default], iteration 8 completed (865.205 ms) ====== [2024-08-28T20:31:46.665Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-28T20:31:46.665Z] GC before operation: completed in 41.515 ms, heap usage 400.734 MB -> 113.087 MB. [2024-08-28T20:31:47.453Z] ====== log-regression (apache-spark) [default], iteration 9 completed (811.958 ms) ====== [2024-08-28T20:31:47.453Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-28T20:31:47.453Z] GC before operation: completed in 42.115 ms, heap usage 267.436 MB -> 113.019 MB. [2024-08-28T20:31:48.227Z] ====== log-regression (apache-spark) [default], iteration 10 completed (790.714 ms) ====== [2024-08-28T20:31:48.227Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-28T20:31:48.227Z] GC before operation: completed in 40.140 ms, heap usage 264.688 MB -> 113.130 MB. [2024-08-28T20:31:49.003Z] ====== log-regression (apache-spark) [default], iteration 11 completed (755.715 ms) ====== [2024-08-28T20:31:49.003Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-28T20:31:49.003Z] GC before operation: completed in 39.707 ms, heap usage 240.157 MB -> 113.285 MB. [2024-08-28T20:31:49.813Z] ====== log-regression (apache-spark) [default], iteration 12 completed (711.926 ms) ====== [2024-08-28T20:31:49.813Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-28T20:31:49.813Z] GC before operation: completed in 42.014 ms, heap usage 300.554 MB -> 113.698 MB. [2024-08-28T20:31:50.609Z] ====== log-regression (apache-spark) [default], iteration 13 completed (735.326 ms) ====== [2024-08-28T20:31:50.609Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-28T20:31:50.609Z] GC before operation: completed in 43.041 ms, heap usage 354.200 MB -> 113.991 MB. [2024-08-28T20:31:51.404Z] ====== log-regression (apache-spark) [default], iteration 14 completed (735.760 ms) ====== [2024-08-28T20:31:51.404Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-28T20:31:51.404Z] GC before operation: completed in 34.492 ms, heap usage 302.993 MB -> 114.023 MB. [2024-08-28T20:31:52.187Z] ====== log-regression (apache-spark) [default], iteration 15 completed (730.844 ms) ====== [2024-08-28T20:31:52.187Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-28T20:31:52.187Z] GC before operation: completed in 42.725 ms, heap usage 302.601 MB -> 114.338 MB. [2024-08-28T20:31:52.964Z] ====== log-regression (apache-spark) [default], iteration 16 completed (720.498 ms) ====== [2024-08-28T20:31:52.964Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-28T20:31:52.964Z] GC before operation: completed in 41.978 ms, heap usage 332.500 MB -> 114.534 MB. [2024-08-28T20:31:53.746Z] ====== log-regression (apache-spark) [default], iteration 17 completed (707.558 ms) ====== [2024-08-28T20:31:53.747Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-28T20:31:53.747Z] GC before operation: completed in 53.115 ms, heap usage 246.607 MB -> 114.533 MB. [2024-08-28T20:31:54.528Z] ====== log-regression (apache-spark) [default], iteration 18 completed (741.947 ms) ====== [2024-08-28T20:31:54.528Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-28T20:31:54.528Z] GC before operation: completed in 48.600 ms, heap usage 371.269 MB -> 115.287 MB. [2024-08-28T20:31:55.301Z] ====== log-regression (apache-spark) [default], iteration 19 completed (748.706 ms) ====== [2024-08-28T20:31:55.301Z] ----------------------------------- [2024-08-28T20:31:55.301Z] renaissance-log-regression_0_PASSED [2024-08-28T20:31:55.301Z] ----------------------------------- [2024-08-28T20:31:55.301Z] [2024-08-28T20:31:55.301Z] TEST TEARDOWN: [2024-08-28T20:31:55.301Z] Nothing to be done for teardown. [2024-08-28T20:31:55.301Z] renaissance-log-regression_0 Finish Time: Wed Aug 28 16:31:55 2024 Epoch Time (ms): 1724877115245