[2024-11-27T22:12:23.951Z] Running test renaissance-log-regression_0 ... [2024-11-27T22:12:23.951Z] =============================================== [2024-11-27T22:12:23.951Z] renaissance-log-regression_0 Start Time: Wed Nov 27 17:12:23 2024 Epoch Time (ms): 1732745543660 [2024-11-27T22:12:23.951Z] variation: NoOptions [2024-11-27T22:12:23.951Z] JVM_OPTIONS: [2024-11-27T22:12:23.951Z] { \ [2024-11-27T22:12:23.951Z] echo ""; echo "TEST SETUP:"; \ [2024-11-27T22:12:23.951Z] echo "Nothing to be done for setup."; \ [2024-11-27T22:12:23.951Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17327452733710/renaissance-log-regression_0"; \ [2024-11-27T22:12:23.951Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17327452733710/renaissance-log-regression_0"; \ [2024-11-27T22:12:23.951Z] echo ""; echo "TESTING:"; \ [2024-11-27T22:12:23.951Z] "/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_17327452733710/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-11-27T22:12:23.951Z] 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_17327452733710/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-27T22:12:23.951Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-27T22:12:23.951Z] echo "Nothing to be done for teardown."; \ [2024-11-27T22:12:23.951Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac/aqa-tests/TKG/../TKG/output_17327452733710/TestTargetResult"; [2024-11-27T22:12:23.951Z] [2024-11-27T22:12:23.951Z] TEST SETUP: [2024-11-27T22:12:23.951Z] Nothing to be done for setup. [2024-11-27T22:12:23.951Z] [2024-11-27T22:12:23.951Z] TESTING: [2024-11-27T22:12:26.421Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-27T22:12:27.215Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2024-11-27T22:12:29.692Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-11-27T22:12:29.692Z] GC before operation: completed in 29.662 ms, heap usage 41.759 MB -> 35.200 MB. [2024-11-27T22:12:33.803Z] ====== log-regression (apache-spark) [default], iteration 0 completed (4260.838 ms) ====== [2024-11-27T22:12:33.803Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-11-27T22:12:33.803Z] GC before operation: completed in 52.380 ms, heap usage 279.937 MB -> 110.790 MB. [2024-11-27T22:12:35.144Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1233.054 ms) ====== [2024-11-27T22:12:35.144Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-11-27T22:12:35.145Z] GC before operation: completed in 38.658 ms, heap usage 304.060 MB -> 111.364 MB. [2024-11-27T22:12:35.967Z] ====== log-regression (apache-spark) [default], iteration 2 completed (992.175 ms) ====== [2024-11-27T22:12:35.967Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-11-27T22:12:35.967Z] GC before operation: completed in 38.093 ms, heap usage 320.708 MB -> 111.832 MB. [2024-11-27T22:12:37.294Z] ====== log-regression (apache-spark) [default], iteration 3 completed (924.681 ms) ====== [2024-11-27T22:12:37.294Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-11-27T22:12:37.294Z] GC before operation: completed in 40.286 ms, heap usage 338.159 MB -> 112.146 MB. [2024-11-27T22:12:38.099Z] ====== log-regression (apache-spark) [default], iteration 4 completed (924.771 ms) ====== [2024-11-27T22:12:38.099Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-11-27T22:12:38.099Z] GC before operation: completed in 44.362 ms, heap usage 288.645 MB -> 112.168 MB. [2024-11-27T22:12:38.898Z] ====== log-regression (apache-spark) [default], iteration 5 completed (894.867 ms) ====== [2024-11-27T22:12:38.898Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-11-27T22:12:38.898Z] GC before operation: completed in 44.727 ms, heap usage 419.873 MB -> 112.764 MB. [2024-11-27T22:12:40.190Z] ====== log-regression (apache-spark) [default], iteration 6 completed (893.742 ms) ====== [2024-11-27T22:12:40.190Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-11-27T22:12:40.190Z] GC before operation: completed in 45.646 ms, heap usage 241.470 MB -> 112.509 MB. [2024-11-27T22:12:41.000Z] ====== log-regression (apache-spark) [default], iteration 7 completed (844.029 ms) ====== [2024-11-27T22:12:41.000Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-11-27T22:12:41.000Z] GC before operation: completed in 40.442 ms, heap usage 248.616 MB -> 112.779 MB. [2024-11-27T22:12:41.805Z] ====== log-regression (apache-spark) [default], iteration 8 completed (813.463 ms) ====== [2024-11-27T22:12:41.805Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-11-27T22:12:41.805Z] GC before operation: completed in 43.696 ms, heap usage 354.320 MB -> 113.273 MB. [2024-11-27T22:12:42.627Z] ====== log-regression (apache-spark) [default], iteration 9 completed (796.055 ms) ====== [2024-11-27T22:12:42.627Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-11-27T22:12:42.627Z] GC before operation: completed in 63.331 ms, heap usage 305.468 MB -> 113.216 MB. [2024-11-27T22:12:43.429Z] ====== log-regression (apache-spark) [default], iteration 10 completed (793.493 ms) ====== [2024-11-27T22:12:43.429Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-11-27T22:12:43.429Z] GC before operation: completed in 44.928 ms, heap usage 237.451 MB -> 114.011 MB. [2024-11-27T22:12:44.235Z] ====== log-regression (apache-spark) [default], iteration 11 completed (792.748 ms) ====== [2024-11-27T22:12:44.235Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-11-27T22:12:44.235Z] GC before operation: completed in 47.576 ms, heap usage 329.010 MB -> 113.673 MB. [2024-11-27T22:12:45.054Z] ====== log-regression (apache-spark) [default], iteration 12 completed (776.380 ms) ====== [2024-11-27T22:12:45.054Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-11-27T22:12:45.054Z] GC before operation: completed in 48.810 ms, heap usage 247.013 MB -> 113.749 MB. [2024-11-27T22:12:45.867Z] ====== log-regression (apache-spark) [default], iteration 13 completed (754.356 ms) ====== [2024-11-27T22:12:45.867Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-11-27T22:12:45.867Z] GC before operation: completed in 49.768 ms, heap usage 304.441 MB -> 114.057 MB. [2024-11-27T22:12:46.673Z] ====== log-regression (apache-spark) [default], iteration 14 completed (780.068 ms) ====== [2024-11-27T22:12:46.673Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-11-27T22:12:46.673Z] GC before operation: completed in 53.574 ms, heap usage 340.328 MB -> 114.270 MB. [2024-11-27T22:12:47.500Z] ====== log-regression (apache-spark) [default], iteration 15 completed (719.228 ms) ====== [2024-11-27T22:12:47.500Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-11-27T22:12:47.500Z] GC before operation: completed in 50.180 ms, heap usage 257.667 MB -> 114.358 MB. [2024-11-27T22:12:48.312Z] ====== log-regression (apache-spark) [default], iteration 16 completed (715.014 ms) ====== [2024-11-27T22:12:48.312Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-11-27T22:12:48.312Z] GC before operation: completed in 48.430 ms, heap usage 245.991 MB -> 114.596 MB. [2024-11-27T22:12:49.111Z] ====== log-regression (apache-spark) [default], iteration 17 completed (714.702 ms) ====== [2024-11-27T22:12:49.112Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-11-27T22:12:49.112Z] GC before operation: completed in 52.832 ms, heap usage 357.459 MB -> 114.977 MB. [2024-11-27T22:12:49.921Z] ====== log-regression (apache-spark) [default], iteration 18 completed (709.280 ms) ====== [2024-11-27T22:12:49.921Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-11-27T22:12:49.921Z] GC before operation: completed in 46.614 ms, heap usage 332.253 MB -> 115.037 MB. [2024-11-27T22:12:50.299Z] ====== log-regression (apache-spark) [default], iteration 19 completed (714.508 ms) ====== [2024-11-27T22:12:50.672Z] ----------------------------------- [2024-11-27T22:12:50.672Z] renaissance-log-regression_0_PASSED [2024-11-27T22:12:50.672Z] ----------------------------------- [2024-11-27T22:12:50.672Z] [2024-11-27T22:12:50.672Z] TEST TEARDOWN: [2024-11-27T22:12:50.672Z] Nothing to be done for teardown. [2024-11-27T22:12:50.672Z] renaissance-log-regression_0 Finish Time: Wed Nov 27 17:12:50 2024 Epoch Time (ms): 1732745570454