[2024-05-29T20:37:04.587Z] Running test renaissance-log-regression_0 ... [2024-05-29T20:37:04.587Z] =============================================== [2024-05-29T20:37:04.587Z] renaissance-log-regression_0 Start Time: Wed May 29 16:37:04 2024 Epoch Time (ms): 1717015024531 [2024-05-29T20:37:04.587Z] variation: NoOptions [2024-05-29T20:37:04.587Z] JVM_OPTIONS: [2024-05-29T20:37:04.587Z] { \ [2024-05-29T20:37:04.587Z] echo ""; echo "TEST SETUP:"; \ [2024-05-29T20:37:04.587Z] echo "Nothing to be done for setup."; \ [2024-05-29T20:37:04.587Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17170147534968/renaissance-log-regression_0"; \ [2024-05-29T20:37:04.587Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17170147534968/renaissance-log-regression_0"; \ [2024-05-29T20:37:04.587Z] echo ""; echo "TESTING:"; \ [2024-05-29T20:37:04.588Z] "/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_17170147534968/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-05-29T20:37:04.588Z] 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_17170147534968/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-05-29T20:37:04.588Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-05-29T20:37:04.588Z] echo "Nothing to be done for teardown."; \ [2024-05-29T20:37:04.588Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_aarch64_mac_testList_0/aqa-tests/TKG/../TKG/output_17170147534968/TestTargetResult"; [2024-05-29T20:37:04.961Z] [2024-05-29T20:37:04.961Z] TEST SETUP: [2024-05-29T20:37:04.961Z] Nothing to be done for setup. [2024-05-29T20:37:04.961Z] [2024-05-29T20:37:04.961Z] TESTING: [2024-05-29T20:37:06.786Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-05-29T20:37:07.571Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2024-05-29T20:37:09.481Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-05-29T20:37:09.481Z] GC before operation: completed in 27.779 ms, heap usage 51.940 MB -> 34.816 MB. [2024-05-29T20:37:13.562Z] ====== log-regression (apache-spark) [default], iteration 0 completed (3632.206 ms) ====== [2024-05-29T20:37:13.562Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-05-29T20:37:13.562Z] GC before operation: completed in 45.453 ms, heap usage 272.397 MB -> 110.393 MB. [2024-05-29T20:37:14.353Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1169.615 ms) ====== [2024-05-29T20:37:14.353Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-05-29T20:37:14.353Z] GC before operation: completed in 29.013 ms, heap usage 266.013 MB -> 110.977 MB. [2024-05-29T20:37:15.155Z] ====== log-regression (apache-spark) [default], iteration 2 completed (867.682 ms) ====== [2024-05-29T20:37:15.155Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-05-29T20:37:15.155Z] GC before operation: completed in 23.584 ms, heap usage 328.945 MB -> 111.520 MB. [2024-05-29T20:37:15.950Z] ====== log-regression (apache-spark) [default], iteration 3 completed (730.941 ms) ====== [2024-05-29T20:37:15.950Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-05-29T20:37:15.950Z] GC before operation: completed in 38.392 ms, heap usage 338.354 MB -> 111.731 MB. [2024-05-29T20:37:16.789Z] ====== log-regression (apache-spark) [default], iteration 4 completed (888.334 ms) ====== [2024-05-29T20:37:16.789Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-05-29T20:37:16.789Z] GC before operation: completed in 35.772 ms, heap usage 248.331 MB -> 111.805 MB. [2024-05-29T20:37:17.579Z] ====== log-regression (apache-spark) [default], iteration 5 completed (704.150 ms) ====== [2024-05-29T20:37:17.579Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-05-29T20:37:17.579Z] GC before operation: completed in 40.923 ms, heap usage 306.493 MB -> 112.174 MB. [2024-05-29T20:37:18.362Z] ====== log-regression (apache-spark) [default], iteration 6 completed (667.360 ms) ====== [2024-05-29T20:37:18.362Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-05-29T20:37:18.362Z] GC before operation: completed in 31.861 ms, heap usage 259.415 MB -> 112.095 MB. [2024-05-29T20:37:19.152Z] ====== log-regression (apache-spark) [default], iteration 7 completed (747.211 ms) ====== [2024-05-29T20:37:19.152Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-05-29T20:37:19.152Z] GC before operation: completed in 40.384 ms, heap usage 239.269 MB -> 112.283 MB. [2024-05-29T20:37:19.522Z] ====== log-regression (apache-spark) [default], iteration 8 completed (516.858 ms) ====== [2024-05-29T20:37:19.522Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-05-29T20:37:19.522Z] GC before operation: completed in 32.405 ms, heap usage 337.824 MB -> 112.744 MB. [2024-05-29T20:37:20.323Z] ====== log-regression (apache-spark) [default], iteration 9 completed (708.328 ms) ====== [2024-05-29T20:37:20.323Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-05-29T20:37:20.323Z] GC before operation: completed in 48.887 ms, heap usage 238.588 MB -> 112.704 MB. [2024-05-29T20:37:21.131Z] ====== log-regression (apache-spark) [default], iteration 10 completed (732.484 ms) ====== [2024-05-29T20:37:21.131Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-05-29T20:37:21.131Z] GC before operation: completed in 34.540 ms, heap usage 246.663 MB -> 112.849 MB. [2024-05-29T20:37:21.922Z] ====== log-regression (apache-spark) [default], iteration 11 completed (700.866 ms) ====== [2024-05-29T20:37:21.922Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-05-29T20:37:21.922Z] GC before operation: completed in 47.186 ms, heap usage 336.908 MB -> 113.343 MB. [2024-05-29T20:37:22.707Z] ====== log-regression (apache-spark) [default], iteration 12 completed (757.207 ms) ====== [2024-05-29T20:37:22.707Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-05-29T20:37:22.707Z] GC before operation: completed in 38.268 ms, heap usage 257.282 MB -> 113.308 MB. [2024-05-29T20:37:23.082Z] ====== log-regression (apache-spark) [default], iteration 13 completed (594.354 ms) ====== [2024-05-29T20:37:23.082Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-05-29T20:37:23.082Z] GC before operation: completed in 40.808 ms, heap usage 313.175 MB -> 113.576 MB. [2024-05-29T20:37:23.896Z] ====== log-regression (apache-spark) [default], iteration 14 completed (708.443 ms) ====== [2024-05-29T20:37:23.896Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-05-29T20:37:23.896Z] GC before operation: completed in 41.142 ms, heap usage 283.399 MB -> 113.721 MB. [2024-05-29T20:37:24.684Z] ====== log-regression (apache-spark) [default], iteration 15 completed (656.278 ms) ====== [2024-05-29T20:37:24.684Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-05-29T20:37:24.684Z] GC before operation: completed in 44.274 ms, heap usage 265.219 MB -> 113.971 MB. [2024-05-29T20:37:25.539Z] ====== log-regression (apache-spark) [default], iteration 16 completed (645.685 ms) ====== [2024-05-29T20:37:25.539Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-05-29T20:37:25.539Z] GC before operation: completed in 33.538 ms, heap usage 304.138 MB -> 114.198 MB. [2024-05-29T20:37:25.911Z] ====== log-regression (apache-spark) [default], iteration 17 completed (549.757 ms) ====== [2024-05-29T20:37:25.911Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-05-29T20:37:25.911Z] GC before operation: completed in 46.067 ms, heap usage 257.935 MB -> 114.222 MB. [2024-05-29T20:37:26.700Z] ====== log-regression (apache-spark) [default], iteration 18 completed (618.572 ms) ====== [2024-05-29T20:37:26.700Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-05-29T20:37:26.700Z] GC before operation: completed in 33.511 ms, heap usage 328.216 MB -> 114.610 MB. [2024-05-29T20:37:27.073Z] ====== log-regression (apache-spark) [default], iteration 19 completed (504.910 ms) ====== [2024-05-29T20:37:27.444Z] ----------------------------------- [2024-05-29T20:37:27.444Z] renaissance-log-regression_0_PASSED [2024-05-29T20:37:27.444Z] ----------------------------------- [2024-05-29T20:37:27.444Z] [2024-05-29T20:37:27.444Z] TEST TEARDOWN: [2024-05-29T20:37:27.444Z] Nothing to be done for teardown. [2024-05-29T20:37:27.444Z] renaissance-log-regression_0 Finish Time: Wed May 29 16:37:27 2024 Epoch Time (ms): 1717015047064