[2025-02-27T04:06:32.938Z] Running test renaissance-log-regression_0 ... [2025-02-27T04:06:32.938Z] =============================================== [2025-02-27T04:06:32.938Z] renaissance-log-regression_0 Start Time: Wed Feb 26 20:06:31 2025 Epoch Time (ms): 1740629191641 [2025-02-27T04:06:32.938Z] variation: NoOptions [2025-02-27T04:06:32.938Z] JVM_OPTIONS: [2025-02-27T04:06:32.938Z] { \ [2025-02-27T04:06:32.938Z] echo ""; echo "TEST SETUP:"; \ [2025-02-27T04:06:32.938Z] echo "Nothing to be done for setup."; \ [2025-02-27T04:06:32.938Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17406291863188/renaissance-log-regression_0"; \ [2025-02-27T04:06:32.938Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17406291863188/renaissance-log-regression_0"; \ [2025-02-27T04:06:32.938Z] echo ""; echo "TESTING:"; \ [2025-02-27T04:06:32.938Z] "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/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_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17406291863188/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-02-27T04:06:32.938Z] 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_testList_1/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17406291863188/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-27T04:06:32.938Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-27T04:06:32.938Z] echo "Nothing to be done for teardown."; \ [2025-02-27T04:06:32.938Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17406291863188/TestTargetResult"; [2025-02-27T04:06:32.938Z] [2025-02-27T04:06:32.938Z] TEST SETUP: [2025-02-27T04:06:32.938Z] Nothing to be done for setup. [2025-02-27T04:06:32.938Z] [2025-02-27T04:06:32.938Z] TESTING: [2025-02-27T04:06:56.056Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-27T04:07:03.967Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-02-27T04:07:22.583Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-02-27T04:07:22.584Z] GC before operation: completed in 102.188 ms, heap usage 49.099 MB -> 35.454 MB. [2025-02-27T04:08:08.581Z] ====== log-regression (apache-spark) [default], iteration 0 completed (43411.567 ms) ====== [2025-02-27T04:08:08.581Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-02-27T04:08:08.581Z] GC before operation: completed in 365.567 ms, heap usage 274.253 MB -> 110.903 MB. [2025-02-27T04:08:18.734Z] ====== log-regression (apache-spark) [default], iteration 1 completed (11581.851 ms) ====== [2025-02-27T04:08:18.734Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-02-27T04:08:18.734Z] GC before operation: completed in 297.707 ms, heap usage 241.119 MB -> 111.334 MB. [2025-02-27T04:08:29.046Z] ====== log-regression (apache-spark) [default], iteration 2 completed (10237.951 ms) ====== [2025-02-27T04:08:29.046Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-02-27T04:08:30.338Z] GC before operation: completed in 505.891 ms, heap usage 329.059 MB -> 111.933 MB. [2025-02-27T04:08:40.583Z] ====== log-regression (apache-spark) [default], iteration 3 completed (10808.501 ms) ====== [2025-02-27T04:08:40.583Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-02-27T04:08:40.583Z] GC before operation: completed in 249.318 ms, heap usage 248.328 MB -> 111.964 MB. [2025-02-27T04:08:50.700Z] ====== log-regression (apache-spark) [default], iteration 4 completed (9366.504 ms) ====== [2025-02-27T04:08:50.700Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-02-27T04:08:50.700Z] GC before operation: completed in 242.949 ms, heap usage 220.914 MB -> 112.188 MB. [2025-02-27T04:09:05.831Z] ====== log-regression (apache-spark) [default], iteration 5 completed (15911.338 ms) ====== [2025-02-27T04:09:05.831Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-02-27T04:09:05.831Z] GC before operation: completed in 304.588 ms, heap usage 482.842 MB -> 112.926 MB. [2025-02-27T04:09:11.661Z] ====== log-regression (apache-spark) [default], iteration 6 completed (5799.811 ms) ====== [2025-02-27T04:09:11.661Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-02-27T04:09:12.914Z] GC before operation: completed in 889.979 ms, heap usage 361.998 MB -> 112.828 MB. [2025-02-27T04:09:23.025Z] ====== log-regression (apache-spark) [default], iteration 7 completed (10226.212 ms) ====== [2025-02-27T04:09:23.025Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-02-27T04:09:23.025Z] GC before operation: completed in 322.202 ms, heap usage 651.155 MB -> 113.627 MB. [2025-02-27T04:09:31.182Z] ====== log-regression (apache-spark) [default], iteration 8 completed (7488.278 ms) ====== [2025-02-27T04:09:31.183Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-02-27T04:09:31.183Z] GC before operation: completed in 104.074 ms, heap usage 466.321 MB -> 113.523 MB. [2025-02-27T04:09:38.128Z] ====== log-regression (apache-spark) [default], iteration 9 completed (6534.340 ms) ====== [2025-02-27T04:09:38.128Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-02-27T04:09:38.128Z] GC before operation: completed in 179.559 ms, heap usage 313.105 MB -> 113.359 MB. [2025-02-27T04:09:45.144Z] ====== log-regression (apache-spark) [default], iteration 10 completed (7911.863 ms) ====== [2025-02-27T04:09:45.144Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-02-27T04:09:45.662Z] GC before operation: completed in 168.463 ms, heap usage 395.353 MB -> 113.791 MB. [2025-02-27T04:09:52.587Z] ====== log-regression (apache-spark) [default], iteration 11 completed (7292.072 ms) ====== [2025-02-27T04:09:52.587Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-02-27T04:09:53.831Z] GC before operation: completed in 925.082 ms, heap usage 651.021 MB -> 114.412 MB. [2025-02-27T04:10:08.865Z] ====== log-regression (apache-spark) [default], iteration 12 completed (13312.540 ms) ====== [2025-02-27T04:10:08.865Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-02-27T04:10:08.865Z] GC before operation: completed in 215.130 ms, heap usage 373.070 MB -> 114.043 MB. [2025-02-27T04:10:11.222Z] ====== log-regression (apache-spark) [default], iteration 13 completed (4080.747 ms) ====== [2025-02-27T04:10:11.222Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-02-27T04:10:11.781Z] GC before operation: completed in 273.361 ms, heap usage 431.959 MB -> 114.393 MB. [2025-02-27T04:10:19.449Z] ====== log-regression (apache-spark) [default], iteration 14 completed (7992.238 ms) ====== [2025-02-27T04:10:19.449Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-02-27T04:10:20.084Z] GC before operation: completed in 245.286 ms, heap usage 221.761 MB -> 114.017 MB. [2025-02-27T04:10:26.914Z] ====== log-regression (apache-spark) [default], iteration 15 completed (7209.639 ms) ====== [2025-02-27T04:10:26.914Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-02-27T04:10:27.439Z] GC before operation: completed in 374.768 ms, heap usage 776.997 MB -> 115.111 MB. [2025-02-27T04:10:32.906Z] ====== log-regression (apache-spark) [default], iteration 16 completed (4979.804 ms) ====== [2025-02-27T04:10:32.906Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-02-27T04:10:33.543Z] GC before operation: completed in 828.200 ms, heap usage 284.049 MB -> 114.562 MB. [2025-02-27T04:10:41.620Z] ====== log-regression (apache-spark) [default], iteration 17 completed (8154.598 ms) ====== [2025-02-27T04:10:41.620Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-02-27T04:10:41.620Z] GC before operation: completed in 207.667 ms, heap usage 282.552 MB -> 114.756 MB. [2025-02-27T04:10:50.120Z] ====== log-regression (apache-spark) [default], iteration 18 completed (7821.674 ms) ====== [2025-02-27T04:10:50.120Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-02-27T04:10:50.120Z] GC before operation: completed in 204.174 ms, heap usage 255.078 MB -> 114.915 MB. [2025-02-27T04:10:58.176Z] ====== log-regression (apache-spark) [default], iteration 19 completed (8040.611 ms) ====== [2025-02-27T04:10:59.152Z] ----------------------------------- [2025-02-27T04:10:59.152Z] renaissance-log-regression_0_PASSED [2025-02-27T04:10:59.152Z] ----------------------------------- [2025-02-27T04:10:59.152Z] [2025-02-27T04:10:59.152Z] TEST TEARDOWN: [2025-02-27T04:10:59.152Z] Nothing to be done for teardown. [2025-02-27T04:10:59.598Z] renaissance-log-regression_0 Finish Time: Wed Feb 26 20:10:58 2025 Epoch Time (ms): 1740629458255