[2025-08-28T12:26:52.145Z] Running test renaissance-log-regression_0 ... [2025-08-28T12:26:52.145Z] =============================================== [2025-08-28T12:26:52.145Z] renaissance-log-regression_0 Start Time: Thu Aug 28 05:26:49 2025 Epoch Time (ms): 1756384009213 [2025-08-28T12:26:52.145Z] variation: NoOptions [2025-08-28T12:26:52.145Z] JVM_OPTIONS: [2025-08-28T12:26:52.145Z] { \ [2025-08-28T12:26:52.145Z] echo ""; echo "TEST SETUP:"; \ [2025-08-28T12:26:52.145Z] echo "Nothing to be done for setup."; \ [2025-08-28T12:26:52.145Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17563798899561/renaissance-log-regression_0"; \ [2025-08-28T12:26:52.145Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17563798899561/renaissance-log-regression_0"; \ [2025-08-28T12:26:52.145Z] echo ""; echo "TESTING:"; \ [2025-08-28T12:26:52.145Z] "/Users/admin/workspace/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17563798899561/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-28T12:26:52.145Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17563798899561/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-28T12:26:52.145Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-28T12:26:52.145Z] echo "Nothing to be done for teardown."; \ [2025-08-28T12:26:52.145Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17563798899561/TestTargetResult"; [2025-08-28T12:26:52.145Z] [2025-08-28T12:26:52.145Z] TEST SETUP: [2025-08-28T12:26:52.145Z] Nothing to be done for setup. [2025-08-28T12:26:52.145Z] [2025-08-28T12:26:52.145Z] TESTING: [2025-08-28T12:27:09.824Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-08-28T12:27:17.993Z] WARNING: An illegal reflective access operation has occurred [2025-08-28T12:27:17.993Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/output_17563798899561/renaissance-log-regression_0/harness-052651-2114382408454650739/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-08-28T12:27:17.993Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-08-28T12:27:17.993Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-08-28T12:27:17.993Z] WARNING: All illegal access operations will be denied in a future release [2025-08-28T12:27:21.682Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-28T12:27:21.682Z] GC before operation: completed in 213.264 ms, heap usage 157.731 MB -> 34.605 MB. [2025-08-28T12:28:04.740Z] ====== log-regression (apache-spark) [default], iteration 0 completed (37563.618 ms) ====== [2025-08-28T12:28:04.740Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-28T12:28:04.740Z] GC before operation: completed in 425.289 ms, heap usage 253.481 MB -> 115.915 MB. [2025-08-28T12:28:09.367Z] ====== log-regression (apache-spark) [default], iteration 1 completed (9437.758 ms) ====== [2025-08-28T12:28:09.367Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-28T12:28:09.367Z] GC before operation: completed in 515.856 ms, heap usage 313.205 MB -> 116.575 MB. [2025-08-28T12:28:17.013Z] ====== log-regression (apache-spark) [default], iteration 2 completed (7916.881 ms) ====== [2025-08-28T12:28:17.013Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-28T12:28:17.466Z] GC before operation: completed in 196.942 ms, heap usage 337.734 MB -> 117.004 MB. [2025-08-28T12:28:25.227Z] ====== log-regression (apache-spark) [default], iteration 3 completed (7056.830 ms) ====== [2025-08-28T12:28:25.227Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-28T12:28:25.227Z] GC before operation: completed in 291.666 ms, heap usage 493.689 MB -> 117.730 MB. [2025-08-28T12:28:31.365Z] ====== log-regression (apache-spark) [default], iteration 4 completed (5760.439 ms) ====== [2025-08-28T12:28:31.365Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-28T12:28:31.365Z] GC before operation: completed in 378.579 ms, heap usage 288.431 MB -> 117.273 MB. [2025-08-28T12:28:37.466Z] ====== log-regression (apache-spark) [default], iteration 5 completed (5844.418 ms) ====== [2025-08-28T12:28:37.466Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-28T12:28:37.466Z] GC before operation: completed in 300.308 ms, heap usage 383.435 MB -> 117.795 MB. [2025-08-28T12:28:43.496Z] ====== log-regression (apache-spark) [default], iteration 6 completed (5899.370 ms) ====== [2025-08-28T12:28:43.496Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-28T12:28:43.496Z] GC before operation: completed in 321.278 ms, heap usage 510.464 MB -> 118.376 MB. [2025-08-28T12:28:49.418Z] ====== log-regression (apache-spark) [default], iteration 7 completed (5858.407 ms) ====== [2025-08-28T12:28:49.418Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-28T12:28:49.418Z] GC before operation: completed in 283.586 ms, heap usage 287.463 MB -> 117.823 MB. [2025-08-28T12:28:54.189Z] ====== log-regression (apache-spark) [default], iteration 8 completed (4796.203 ms) ====== [2025-08-28T12:28:54.189Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-28T12:28:54.569Z] GC before operation: completed in 413.692 ms, heap usage 374.203 MB -> 118.345 MB. [2025-08-28T12:28:59.432Z] ====== log-regression (apache-spark) [default], iteration 9 completed (4797.931 ms) ====== [2025-08-28T12:28:59.433Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-28T12:28:59.894Z] GC before operation: completed in 295.644 ms, heap usage 553.832 MB -> 119.103 MB. [2025-08-28T12:29:04.843Z] ====== log-regression (apache-spark) [default], iteration 10 completed (4936.356 ms) ====== [2025-08-28T12:29:04.843Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-28T12:29:05.373Z] GC before operation: completed in 432.388 ms, heap usage 523.646 MB -> 119.281 MB. [2025-08-28T12:29:11.128Z] ====== log-regression (apache-spark) [default], iteration 11 completed (5763.617 ms) ====== [2025-08-28T12:29:11.128Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-28T12:29:11.128Z] GC before operation: completed in 408.793 ms, heap usage 642.948 MB -> 119.875 MB. [2025-08-28T12:29:17.140Z] ====== log-regression (apache-spark) [default], iteration 12 completed (5241.508 ms) ====== [2025-08-28T12:29:17.140Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-28T12:29:17.140Z] GC before operation: completed in 527.294 ms, heap usage 714.645 MB -> 120.232 MB. [2025-08-28T12:29:21.930Z] ====== log-regression (apache-spark) [default], iteration 13 completed (4836.985 ms) ====== [2025-08-28T12:29:21.930Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-28T12:29:22.354Z] GC before operation: completed in 390.388 ms, heap usage 231.633 MB -> 118.763 MB. [2025-08-28T12:29:27.503Z] ====== log-regression (apache-spark) [default], iteration 14 completed (5326.902 ms) ====== [2025-08-28T12:29:27.503Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-28T12:29:27.969Z] GC before operation: completed in 382.840 ms, heap usage 273.992 MB -> 119.124 MB. [2025-08-28T12:29:32.722Z] ====== log-regression (apache-spark) [default], iteration 15 completed (4682.488 ms) ====== [2025-08-28T12:29:32.722Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-28T12:29:33.153Z] GC before operation: completed in 364.221 ms, heap usage 323.258 MB -> 119.435 MB. [2025-08-28T12:29:38.968Z] ====== log-regression (apache-spark) [default], iteration 16 completed (5286.217 ms) ====== [2025-08-28T12:29:38.968Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-28T12:29:38.968Z] GC before operation: completed in 539.042 ms, heap usage 766.097 MB -> 121.119 MB. [2025-08-28T12:29:43.971Z] ====== log-regression (apache-spark) [default], iteration 17 completed (4877.526 ms) ====== [2025-08-28T12:29:43.971Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-28T12:29:44.480Z] GC before operation: completed in 449.491 ms, heap usage 351.191 MB -> 119.989 MB. [2025-08-28T12:29:49.185Z] ====== log-regression (apache-spark) [default], iteration 18 completed (4782.585 ms) ====== [2025-08-28T12:29:49.185Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-28T12:29:49.796Z] GC before operation: completed in 369.746 ms, heap usage 338.608 MB -> 120.093 MB. [2025-08-28T12:29:54.777Z] ====== log-regression (apache-spark) [default], iteration 19 completed (4918.833 ms) ====== [2025-08-28T12:29:55.199Z] ----------------------------------- [2025-08-28T12:29:55.199Z] renaissance-log-regression_0_PASSED [2025-08-28T12:29:55.199Z] ----------------------------------- [2025-08-28T12:29:55.199Z] [2025-08-28T12:29:55.199Z] TEST TEARDOWN: [2025-08-28T12:29:55.199Z] Nothing to be done for teardown. [2025-08-28T12:29:55.199Z] renaissance-log-regression_0 Finish Time: Thu Aug 28 05:29:52 2025 Epoch Time (ms): 1756384192608