[2025-01-22T01:55:42.414Z] Running test renaissance-log-regression_0 ... [2025-01-22T01:55:42.414Z] =============================================== [2025-01-22T01:55:42.414Z] renaissance-log-regression_0 Start Time: Tue Jan 21 17:55:41 2025 Epoch Time (ms): 1737510942017 [2025-01-22T01:55:42.414Z] variation: NoOptions [2025-01-22T01:55:42.414Z] JVM_OPTIONS: [2025-01-22T01:55:42.414Z] { \ [2025-01-22T01:55:42.414Z] echo ""; echo "TEST SETUP:"; \ [2025-01-22T01:55:42.414Z] echo "Nothing to be done for setup."; \ [2025-01-22T01:55:42.415Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17375075468750/renaissance-log-regression_0"; \ [2025-01-22T01:55:42.415Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17375075468750/renaissance-log-regression_0"; \ [2025-01-22T01:55:42.415Z] echo ""; echo "TESTING:"; \ [2025-01-22T01:55:42.415Z] "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_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_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17375075468750/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-01-22T01:55:42.415Z] 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/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17375075468750/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-01-22T01:55:42.415Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-01-22T01:55:42.415Z] echo "Nothing to be done for teardown."; \ [2025-01-22T01:55:42.415Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17375075468750/TestTargetResult"; [2025-01-22T01:55:42.415Z] [2025-01-22T01:55:42.415Z] TEST SETUP: [2025-01-22T01:55:42.415Z] Nothing to be done for setup. [2025-01-22T01:55:42.415Z] [2025-01-22T01:55:42.415Z] TESTING: [2025-01-22T01:55:55.893Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-01-22T01:56:03.274Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-01-22T01:56:18.810Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-01-22T01:56:18.810Z] GC before operation: completed in 121.822 ms, heap usage 78.917 MB -> 35.459 MB. [2025-01-22T01:56:57.745Z] ====== log-regression (apache-spark) [default], iteration 0 completed (39460.235 ms) ====== [2025-01-22T01:56:57.745Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-01-22T01:56:58.343Z] GC before operation: completed in 377.446 ms, heap usage 456.409 MB -> 111.257 MB. [2025-01-22T01:57:19.085Z] ====== log-regression (apache-spark) [default], iteration 1 completed (20506.182 ms) ====== [2025-01-22T01:57:19.085Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-01-22T01:57:19.085Z] GC before operation: completed in 211.001 ms, heap usage 398.027 MB -> 111.725 MB. [2025-01-22T01:57:25.773Z] ====== log-regression (apache-spark) [default], iteration 2 completed (6517.286 ms) ====== [2025-01-22T01:57:25.773Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-01-22T01:57:25.773Z] GC before operation: completed in 221.000 ms, heap usage 501.051 MB -> 112.401 MB. [2025-01-22T01:57:35.519Z] ====== log-regression (apache-spark) [default], iteration 3 completed (8426.273 ms) ====== [2025-01-22T01:57:35.519Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-01-22T01:57:35.519Z] GC before operation: completed in 210.821 ms, heap usage 387.595 MB -> 112.409 MB. [2025-01-22T01:57:43.669Z] ====== log-regression (apache-spark) [default], iteration 4 completed (9066.755 ms) ====== [2025-01-22T01:57:43.669Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-01-22T01:57:43.669Z] GC before operation: completed in 149.297 ms, heap usage 454.971 MB -> 112.727 MB. [2025-01-22T01:57:50.548Z] ====== log-regression (apache-spark) [default], iteration 5 completed (6882.751 ms) ====== [2025-01-22T01:57:50.548Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-01-22T01:57:51.961Z] GC before operation: completed in 1260.584 ms, heap usage 299.503 MB -> 112.692 MB. [2025-01-22T01:57:58.787Z] ====== log-regression (apache-spark) [default], iteration 6 completed (6861.431 ms) ====== [2025-01-22T01:57:58.787Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-01-22T01:57:58.787Z] GC before operation: completed in 247.073 ms, heap usage 556.136 MB -> 113.393 MB. [2025-01-22T01:58:07.282Z] ====== log-regression (apache-spark) [default], iteration 7 completed (7814.467 ms) ====== [2025-01-22T01:58:07.282Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-01-22T01:58:07.282Z] GC before operation: completed in 397.104 ms, heap usage 372.260 MB -> 113.236 MB. [2025-01-22T01:58:19.667Z] ====== log-regression (apache-spark) [default], iteration 8 completed (12537.098 ms) ====== [2025-01-22T01:58:19.667Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-01-22T01:58:19.667Z] GC before operation: completed in 232.365 ms, heap usage 207.822 MB -> 113.056 MB. [2025-01-22T01:58:26.020Z] ====== log-regression (apache-spark) [default], iteration 9 completed (5360.816 ms) ====== [2025-01-22T01:58:26.020Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-01-22T01:58:26.020Z] GC before operation: completed in 261.057 ms, heap usage 284.066 MB -> 113.447 MB. [2025-01-22T01:58:32.767Z] ====== log-regression (apache-spark) [default], iteration 10 completed (6447.293 ms) ====== [2025-01-22T01:58:32.767Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-01-22T01:58:32.767Z] GC before operation: completed in 266.934 ms, heap usage 520.436 MB -> 114.126 MB. [2025-01-22T01:58:38.218Z] ====== log-regression (apache-spark) [default], iteration 11 completed (5951.978 ms) ====== [2025-01-22T01:58:38.218Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-01-22T01:58:38.218Z] GC before operation: completed in 233.688 ms, heap usage 324.150 MB -> 113.804 MB. [2025-01-22T01:58:45.243Z] ====== log-regression (apache-spark) [default], iteration 12 completed (6639.805 ms) ====== [2025-01-22T01:58:45.243Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-01-22T01:58:45.243Z] GC before operation: completed in 258.212 ms, heap usage 802.543 MB -> 114.705 MB. [2025-01-22T01:58:59.763Z] ====== log-regression (apache-spark) [default], iteration 13 completed (14140.901 ms) ====== [2025-01-22T01:58:59.763Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-01-22T01:58:59.763Z] GC before operation: completed in 350.557 ms, heap usage 273.079 MB -> 114.144 MB. [2025-01-22T01:59:03.835Z] ====== log-regression (apache-spark) [default], iteration 14 completed (4094.948 ms) ====== [2025-01-22T01:59:03.835Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-01-22T01:59:04.619Z] GC before operation: completed in 235.148 ms, heap usage 205.383 MB -> 114.220 MB. [2025-01-22T01:59:12.726Z] ====== log-regression (apache-spark) [default], iteration 15 completed (8369.843 ms) ====== [2025-01-22T01:59:12.726Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-01-22T01:59:12.726Z] GC before operation: completed in 296.564 ms, heap usage 330.956 MB -> 114.735 MB. [2025-01-22T01:59:19.134Z] ====== log-regression (apache-spark) [default], iteration 16 completed (6048.985 ms) ====== [2025-01-22T01:59:19.134Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-01-22T01:59:19.134Z] GC before operation: completed in 180.598 ms, heap usage 294.385 MB -> 114.756 MB. [2025-01-22T01:59:26.055Z] ====== log-regression (apache-spark) [default], iteration 17 completed (6120.407 ms) ====== [2025-01-22T01:59:26.055Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-01-22T01:59:26.055Z] GC before operation: completed in 163.208 ms, heap usage 343.751 MB -> 114.999 MB. [2025-01-22T01:59:30.656Z] ====== log-regression (apache-spark) [default], iteration 18 completed (4615.180 ms) ====== [2025-01-22T01:59:30.656Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-01-22T01:59:30.656Z] GC before operation: completed in 339.469 ms, heap usage 350.053 MB -> 115.179 MB. [2025-01-22T01:59:35.860Z] ====== log-regression (apache-spark) [default], iteration 19 completed (5364.156 ms) ====== [2025-01-22T01:59:37.818Z] ----------------------------------- [2025-01-22T01:59:37.818Z] renaissance-log-regression_0_PASSED [2025-01-22T01:59:37.818Z] ----------------------------------- [2025-01-22T01:59:37.818Z] [2025-01-22T01:59:37.818Z] TEST TEARDOWN: [2025-01-22T01:59:37.818Z] Nothing to be done for teardown. [2025-01-22T01:59:38.276Z] renaissance-log-regression_0 Finish Time: Tue Jan 21 17:59:37 2025 Epoch Time (ms): 1737511177697