[2024-08-08T04:42:03.088Z] Running test renaissance-log-regression_0 ... [2024-08-08T04:42:03.088Z] =============================================== [2024-08-08T04:42:03.088Z] renaissance-log-regression_0 Start Time: Wed Aug 7 21:42:01 2024 Epoch Time (ms): 1723092121703 [2024-08-08T04:42:03.088Z] variation: NoOptions [2024-08-08T04:42:03.088Z] JVM_OPTIONS: [2024-08-08T04:42:03.088Z] { \ [2024-08-08T04:42:03.088Z] echo ""; echo "TEST SETUP:"; \ [2024-08-08T04:42:03.088Z] echo "Nothing to be done for setup."; \ [2024-08-08T04:42:03.088Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17230910596973/renaissance-log-regression_0"; \ [2024-08-08T04:42:03.088Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17230910596973/renaissance-log-regression_0"; \ [2024-08-08T04:42:03.088Z] echo ""; echo "TESTING:"; \ [2024-08-08T04:42:03.088Z] "/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_17230910596973/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-08T04:42:03.088Z] 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_17230910596973/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-08T04:42:03.088Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-08T04:42:03.088Z] echo "Nothing to be done for teardown."; \ [2024-08-08T04:42:03.088Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17230910596973/TestTargetResult"; [2024-08-08T04:42:03.088Z] [2024-08-08T04:42:03.088Z] TEST SETUP: [2024-08-08T04:42:03.088Z] Nothing to be done for setup. [2024-08-08T04:42:03.088Z] [2024-08-08T04:42:03.088Z] TESTING: [2024-08-08T04:42:15.645Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-08T04:42:19.184Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2024-08-08T04:42:39.671Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-08T04:42:39.671Z] GC before operation: completed in 140.998 ms, heap usage 44.400 MB -> 35.624 MB. [2024-08-08T04:43:01.786Z] ====== log-regression (apache-spark) [default], iteration 0 completed (23027.473 ms) ====== [2024-08-08T04:43:01.786Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-08T04:43:01.786Z] GC before operation: completed in 326.251 ms, heap usage 623.619 MB -> 111.697 MB. [2024-08-08T04:43:09.558Z] ====== log-regression (apache-spark) [default], iteration 1 completed (7613.697 ms) ====== [2024-08-08T04:43:09.558Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-08T04:43:09.558Z] GC before operation: completed in 146.060 ms, heap usage 432.751 MB -> 111.953 MB. [2024-08-08T04:43:14.834Z] ====== log-regression (apache-spark) [default], iteration 2 completed (5674.986 ms) ====== [2024-08-08T04:43:14.834Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-08T04:43:14.834Z] GC before operation: completed in 230.149 ms, heap usage 304.726 MB -> 112.152 MB. [2024-08-08T04:43:19.852Z] ====== log-regression (apache-spark) [default], iteration 3 completed (4878.239 ms) ====== [2024-08-08T04:43:19.852Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-08T04:43:19.852Z] GC before operation: completed in 221.995 ms, heap usage 839.138 MB -> 113.156 MB. [2024-08-08T04:43:26.198Z] ====== log-regression (apache-spark) [default], iteration 4 completed (5321.800 ms) ====== [2024-08-08T04:43:26.199Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-08T04:43:26.199Z] GC before operation: completed in 225.960 ms, heap usage 305.834 MB -> 112.522 MB. [2024-08-08T04:43:31.638Z] ====== log-regression (apache-spark) [default], iteration 5 completed (5572.915 ms) ====== [2024-08-08T04:43:31.638Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-08T04:43:31.638Z] GC before operation: completed in 173.199 ms, heap usage 265.495 MB -> 112.706 MB. [2024-08-08T04:43:36.725Z] ====== log-regression (apache-spark) [default], iteration 6 completed (4703.660 ms) ====== [2024-08-08T04:43:36.725Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-08T04:43:36.725Z] GC before operation: completed in 240.293 ms, heap usage 245.162 MB -> 112.833 MB. [2024-08-08T04:43:41.231Z] ====== log-regression (apache-spark) [default], iteration 7 completed (5073.441 ms) ====== [2024-08-08T04:43:41.231Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-08T04:43:41.687Z] GC before operation: completed in 189.830 ms, heap usage 806.495 MB -> 113.834 MB. [2024-08-08T04:43:45.677Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3843.951 ms) ====== [2024-08-08T04:43:45.677Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-08T04:43:45.677Z] GC before operation: completed in 208.659 ms, heap usage 271.007 MB -> 113.329 MB. [2024-08-08T04:43:50.650Z] ====== log-regression (apache-spark) [default], iteration 9 completed (4648.226 ms) ====== [2024-08-08T04:43:50.650Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-08T04:43:50.650Z] GC before operation: completed in 188.534 ms, heap usage 383.399 MB -> 113.806 MB. [2024-08-08T04:43:54.660Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3635.678 ms) ====== [2024-08-08T04:43:54.660Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-08T04:43:54.660Z] GC before operation: completed in 205.122 ms, heap usage 403.731 MB -> 113.939 MB. [2024-08-08T04:43:58.376Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3790.743 ms) ====== [2024-08-08T04:43:58.376Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-08T04:43:58.376Z] GC before operation: completed in 139.293 ms, heap usage 281.299 MB -> 113.905 MB. [2024-08-08T04:44:03.257Z] ====== log-regression (apache-spark) [default], iteration 12 completed (4487.161 ms) ====== [2024-08-08T04:44:03.257Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-08T04:44:03.257Z] GC before operation: completed in 201.850 ms, heap usage 238.302 MB -> 113.917 MB. [2024-08-08T04:44:07.319Z] ====== log-regression (apache-spark) [default], iteration 13 completed (4158.024 ms) ====== [2024-08-08T04:44:07.319Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-08T04:44:07.319Z] GC before operation: completed in 198.299 ms, heap usage 799.115 MB -> 114.992 MB. [2024-08-08T04:44:11.268Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3439.241 ms) ====== [2024-08-08T04:44:11.268Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-08T04:44:11.268Z] GC before operation: completed in 247.882 ms, heap usage 353.762 MB -> 114.607 MB. [2024-08-08T04:44:15.225Z] ====== log-regression (apache-spark) [default], iteration 15 completed (4194.575 ms) ====== [2024-08-08T04:44:15.225Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-08T04:44:15.225Z] GC before operation: completed in 190.434 ms, heap usage 294.681 MB -> 114.630 MB. [2024-08-08T04:44:19.375Z] ====== log-regression (apache-spark) [default], iteration 16 completed (4116.810 ms) ====== [2024-08-08T04:44:19.375Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-08T04:44:19.865Z] GC before operation: completed in 207.425 ms, heap usage 547.174 MB -> 115.598 MB. [2024-08-08T04:44:23.834Z] ====== log-regression (apache-spark) [default], iteration 17 completed (4137.881 ms) ====== [2024-08-08T04:44:23.834Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-08T04:44:24.307Z] GC before operation: completed in 255.772 ms, heap usage 363.671 MB -> 115.233 MB. [2024-08-08T04:44:28.642Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3952.874 ms) ====== [2024-08-08T04:44:28.642Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-08T04:44:28.642Z] GC before operation: completed in 186.432 ms, heap usage 210.886 MB -> 115.148 MB. [2024-08-08T04:44:32.418Z] ====== log-regression (apache-spark) [default], iteration 19 completed (4260.258 ms) ====== [2024-08-08T04:44:33.267Z] ----------------------------------- [2024-08-08T04:44:33.267Z] renaissance-log-regression_0_PASSED [2024-08-08T04:44:33.267Z] ----------------------------------- [2024-08-08T04:44:33.267Z] [2024-08-08T04:44:33.267Z] TEST TEARDOWN: [2024-08-08T04:44:33.267Z] Nothing to be done for teardown. [2024-08-08T04:44:33.267Z] renaissance-log-regression_0 Finish Time: Wed Aug 7 21:44:31 2024 Epoch Time (ms): 1723092272010