[2025-08-26T01:25:10.931Z] Running test renaissance-log-regression_0 ... [2025-08-26T01:25:10.931Z] =============================================== [2025-08-26T01:25:11.305Z] renaissance-log-regression_0 Start Time: Mon Aug 25 18:25:10 2025 Epoch Time (ms): 1756171510586 [2025-08-26T01:25:11.305Z] variation: NoOptions [2025-08-26T01:25:11.305Z] JVM_OPTIONS: [2025-08-26T01:25:11.305Z] { \ [2025-08-26T01:25:11.305Z] echo ""; echo "TEST SETUP:"; \ [2025-08-26T01:25:11.305Z] echo "Nothing to be done for setup."; \ [2025-08-26T01:25:11.305Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17561663982405/renaissance-log-regression_0"; \ [2025-08-26T01:25:11.305Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17561663982405/renaissance-log-regression_0"; \ [2025-08-26T01:25:11.305Z] echo ""; echo "TESTING:"; \ [2025-08-26T01:25:11.305Z] "/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_17561663982405/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-08-26T01:25:11.305Z] 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_17561663982405/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-26T01:25:11.305Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-26T01:25:11.305Z] echo "Nothing to be done for teardown."; \ [2025-08-26T01:25:11.305Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17561663982405/TestTargetResult"; [2025-08-26T01:25:11.305Z] [2025-08-26T01:25:11.305Z] TEST SETUP: [2025-08-26T01:25:11.305Z] Nothing to be done for setup. [2025-08-26T01:25:11.305Z] [2025-08-26T01:25:11.305Z] TESTING: [2025-08-26T01:25:32.403Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2025-08-26T01:25:37.039Z] WARNING: An illegal reflective access operation has occurred [2025-08-26T01:25:37.039Z] 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_17561663982405/renaissance-log-regression_0/harness-182512-10790286059712912197/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-08-26T01:25:37.039Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-08-26T01:25:37.039Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-08-26T01:25:37.039Z] WARNING: All illegal access operations will be denied in a future release [2025-08-26T01:25:40.885Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-08-26T01:25:40.885Z] GC before operation: completed in 212.153 ms, heap usage 74.310 MB -> 34.576 MB. [2025-08-26T01:26:42.436Z] ====== log-regression (apache-spark) [default], iteration 0 completed (53251.284 ms) ====== [2025-08-26T01:26:42.436Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-08-26T01:26:42.436Z] GC before operation: completed in 262.840 ms, heap usage 425.698 MB -> 116.391 MB. [2025-08-26T01:26:42.436Z] ====== log-regression (apache-spark) [default], iteration 1 completed (7319.823 ms) ====== [2025-08-26T01:26:42.436Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-08-26T01:26:42.436Z] GC before operation: completed in 238.766 ms, heap usage 306.730 MB -> 116.479 MB. [2025-08-26T01:26:49.694Z] ====== log-regression (apache-spark) [default], iteration 2 completed (7897.042 ms) ====== [2025-08-26T01:26:49.694Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-08-26T01:26:50.101Z] GC before operation: completed in 313.762 ms, heap usage 515.410 MB -> 117.570 MB. [2025-08-26T01:26:59.162Z] ====== log-regression (apache-spark) [default], iteration 3 completed (7732.057 ms) ====== [2025-08-26T01:26:59.162Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-08-26T01:26:59.162Z] GC before operation: completed in 292.718 ms, heap usage 556.013 MB -> 117.956 MB. [2025-08-26T01:27:04.158Z] ====== log-regression (apache-spark) [default], iteration 4 completed (6022.707 ms) ====== [2025-08-26T01:27:04.158Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-08-26T01:27:04.158Z] GC before operation: completed in 282.445 ms, heap usage 283.729 MB -> 117.254 MB. [2025-08-26T01:27:13.434Z] ====== log-regression (apache-spark) [default], iteration 5 completed (8487.386 ms) ====== [2025-08-26T01:27:13.434Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-08-26T01:27:13.434Z] GC before operation: completed in 238.823 ms, heap usage 551.409 MB -> 118.329 MB. [2025-08-26T01:27:29.464Z] ====== log-regression (apache-spark) [default], iteration 6 completed (15303.918 ms) ====== [2025-08-26T01:27:29.464Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-08-26T01:27:29.464Z] GC before operation: completed in 283.540 ms, heap usage 353.070 MB -> 117.865 MB. [2025-08-26T01:27:33.092Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3864.520 ms) ====== [2025-08-26T01:27:33.092Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-08-26T01:27:33.092Z] GC before operation: completed in 251.690 ms, heap usage 348.640 MB -> 117.971 MB. [2025-08-26T01:27:38.809Z] ====== log-regression (apache-spark) [default], iteration 8 completed (5921.759 ms) ====== [2025-08-26T01:27:38.809Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-08-26T01:27:38.809Z] GC before operation: completed in 329.196 ms, heap usage 297.804 MB -> 117.994 MB. [2025-08-26T01:27:46.294Z] ====== log-regression (apache-spark) [default], iteration 9 completed (6726.387 ms) ====== [2025-08-26T01:27:46.294Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-08-26T01:27:46.294Z] GC before operation: completed in 300.949 ms, heap usage 490.615 MB -> 118.827 MB. [2025-08-26T01:27:57.242Z] ====== log-regression (apache-spark) [default], iteration 10 completed (10746.399 ms) ====== [2025-08-26T01:27:57.242Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-08-26T01:27:57.765Z] GC before operation: completed in 1044.828 ms, heap usage 288.723 MB -> 118.345 MB. [2025-08-26T01:28:07.225Z] ====== log-regression (apache-spark) [default], iteration 11 completed (9120.922 ms) ====== [2025-08-26T01:28:07.225Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-08-26T01:28:07.225Z] GC before operation: completed in 302.048 ms, heap usage 294.877 MB -> 118.626 MB. [2025-08-26T01:28:13.101Z] ====== log-regression (apache-spark) [default], iteration 12 completed (5610.686 ms) ====== [2025-08-26T01:28:13.101Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-08-26T01:28:13.101Z] GC before operation: completed in 294.873 ms, heap usage 389.241 MB -> 119.139 MB. [2025-08-26T01:28:20.352Z] ====== log-regression (apache-spark) [default], iteration 13 completed (6222.677 ms) ====== [2025-08-26T01:28:20.352Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-08-26T01:28:20.352Z] GC before operation: completed in 288.577 ms, heap usage 286.799 MB -> 119.001 MB. [2025-08-26T01:28:26.753Z] ====== log-regression (apache-spark) [default], iteration 14 completed (6801.906 ms) ====== [2025-08-26T01:28:26.753Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-08-26T01:28:27.814Z] GC before operation: completed in 1222.553 ms, heap usage 363.196 MB -> 119.361 MB. [2025-08-26T01:28:38.755Z] ====== log-regression (apache-spark) [default], iteration 15 completed (10801.440 ms) ====== [2025-08-26T01:28:38.755Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-08-26T01:28:39.832Z] GC before operation: completed in 1112.144 ms, heap usage 466.209 MB -> 119.920 MB. [2025-08-26T01:28:47.050Z] ====== log-regression (apache-spark) [default], iteration 16 completed (7246.759 ms) ====== [2025-08-26T01:28:47.050Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-08-26T01:28:47.050Z] GC before operation: completed in 278.738 ms, heap usage 348.119 MB -> 119.698 MB. [2025-08-26T01:28:52.690Z] ====== log-regression (apache-spark) [default], iteration 17 completed (4795.726 ms) ====== [2025-08-26T01:28:52.690Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-08-26T01:28:52.690Z] GC before operation: completed in 282.519 ms, heap usage 849.319 MB -> 121.281 MB. [2025-08-26T01:28:58.660Z] ====== log-regression (apache-spark) [default], iteration 18 completed (5741.043 ms) ====== [2025-08-26T01:28:58.661Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-08-26T01:28:58.661Z] GC before operation: completed in 364.056 ms, heap usage 287.944 MB -> 119.907 MB. [2025-08-26T01:29:04.791Z] ====== log-regression (apache-spark) [default], iteration 19 completed (5804.739 ms) ====== [2025-08-26T01:29:04.791Z] ----------------------------------- [2025-08-26T01:29:04.791Z] renaissance-log-regression_0_PASSED [2025-08-26T01:29:04.791Z] ----------------------------------- [2025-08-26T01:29:05.259Z] [2025-08-26T01:29:05.259Z] TEST TEARDOWN: [2025-08-26T01:29:05.259Z] Nothing to be done for teardown. [2025-08-26T01:29:05.259Z] renaissance-log-regression_0 Finish Time: Mon Aug 25 18:29:04 2025 Epoch Time (ms): 1756171744490