[2024-09-26T02:39:41.688Z] Running test renaissance-log-regression_0 ... [2024-09-26T02:39:41.688Z] =============================================== [2024-09-26T02:39:41.688Z] renaissance-log-regression_0 Start Time: Wed Sep 25 19:39:40 2024 Epoch Time (ms): 1727318380936 [2024-09-26T02:39:41.688Z] variation: NoOptions [2024-09-26T02:39:41.688Z] JVM_OPTIONS: [2024-09-26T02:39:41.688Z] { \ [2024-09-26T02:39:41.688Z] echo ""; echo "TEST SETUP:"; \ [2024-09-26T02:39:41.688Z] echo "Nothing to be done for setup."; \ [2024-09-26T02:39:41.688Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17273160556125/renaissance-log-regression_0"; \ [2024-09-26T02:39:41.688Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17273160556125/renaissance-log-regression_0"; \ [2024-09-26T02:39:41.688Z] echo ""; echo "TESTING:"; \ [2024-09-26T02:39:41.689Z] "/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_17273160556125/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-09-26T02:39:41.689Z] 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_17273160556125/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-26T02:39:41.689Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-26T02:39:41.689Z] echo "Nothing to be done for teardown."; \ [2024-09-26T02:39:41.689Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17273160556125/TestTargetResult"; [2024-09-26T02:39:41.689Z] [2024-09-26T02:39:41.689Z] TEST SETUP: [2024-09-26T02:39:41.689Z] Nothing to be done for setup. [2024-09-26T02:39:41.689Z] [2024-09-26T02:39:41.689Z] TESTING: [2024-09-26T02:39:54.487Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-26T02:39:59.073Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2024-09-26T02:40:07.482Z] WARNING: An illegal reflective access operation has occurred [2024-09-26T02:40:07.482Z] 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_17273160556125/renaissance-log-regression_0/harness-193944-2001069044823886523/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-09-26T02:40:07.482Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-09-26T02:40:07.482Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-09-26T02:40:07.482Z] WARNING: All illegal access operations will be denied in a future release [2024-09-26T02:40:13.498Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-09-26T02:40:13.498Z] GC before operation: completed in 163.166 ms, heap usage 75.705 MB -> 33.900 MB. [2024-09-26T02:40:45.056Z] ====== log-regression (apache-spark) [default], iteration 0 completed (31092.308 ms) ====== [2024-09-26T02:40:45.056Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-09-26T02:40:45.056Z] GC before operation: completed in 253.547 ms, heap usage 266.215 MB -> 109.562 MB. [2024-09-26T02:40:52.779Z] ====== log-regression (apache-spark) [default], iteration 1 completed (8203.325 ms) ====== [2024-09-26T02:40:52.779Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-09-26T02:40:52.779Z] GC before operation: completed in 210.943 ms, heap usage 284.639 MB -> 110.175 MB. [2024-09-26T02:41:00.083Z] ====== log-regression (apache-spark) [default], iteration 2 completed (6610.103 ms) ====== [2024-09-26T02:41:00.083Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-09-26T02:41:00.083Z] GC before operation: completed in 219.436 ms, heap usage 267.770 MB -> 110.427 MB. [2024-09-26T02:41:07.874Z] ====== log-regression (apache-spark) [default], iteration 3 completed (7797.459 ms) ====== [2024-09-26T02:41:07.875Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-09-26T02:41:07.875Z] GC before operation: completed in 399.837 ms, heap usage 255.674 MB -> 110.555 MB. [2024-09-26T02:41:15.490Z] ====== log-regression (apache-spark) [default], iteration 4 completed (6938.006 ms) ====== [2024-09-26T02:41:15.490Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-09-26T02:41:15.490Z] GC before operation: completed in 244.897 ms, heap usage 398.839 MB -> 111.111 MB. [2024-09-26T02:41:21.490Z] ====== log-regression (apache-spark) [default], iteration 5 completed (6301.794 ms) ====== [2024-09-26T02:41:21.490Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-09-26T02:41:21.490Z] GC before operation: completed in 231.504 ms, heap usage 255.940 MB -> 110.940 MB. [2024-09-26T02:41:28.890Z] ====== log-regression (apache-spark) [default], iteration 6 completed (6585.926 ms) ====== [2024-09-26T02:41:28.890Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-09-26T02:41:28.890Z] GC before operation: completed in 202.694 ms, heap usage 227.893 MB -> 111.070 MB. [2024-09-26T02:41:35.003Z] ====== log-regression (apache-spark) [default], iteration 7 completed (6631.001 ms) ====== [2024-09-26T02:41:35.003Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-09-26T02:41:35.003Z] GC before operation: completed in 231.942 ms, heap usage 235.093 MB -> 111.244 MB. [2024-09-26T02:41:41.280Z] ====== log-regression (apache-spark) [default], iteration 8 completed (6334.702 ms) ====== [2024-09-26T02:41:41.280Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-09-26T02:41:41.754Z] GC before operation: completed in 223.928 ms, heap usage 268.866 MB -> 111.527 MB. [2024-09-26T02:41:49.369Z] ====== log-regression (apache-spark) [default], iteration 9 completed (7145.797 ms) ====== [2024-09-26T02:41:49.369Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-09-26T02:41:49.369Z] GC before operation: completed in 759.303 ms, heap usage 489.858 MB -> 112.297 MB. [2024-09-26T02:41:56.666Z] ====== log-regression (apache-spark) [default], iteration 10 completed (6405.274 ms) ====== [2024-09-26T02:41:56.666Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-09-26T02:41:56.666Z] GC before operation: completed in 304.348 ms, heap usage 305.128 MB -> 111.968 MB. [2024-09-26T02:42:02.983Z] ====== log-regression (apache-spark) [default], iteration 11 completed (5932.860 ms) ====== [2024-09-26T02:42:02.983Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-09-26T02:42:02.983Z] GC before operation: completed in 228.890 ms, heap usage 347.360 MB -> 112.240 MB. [2024-09-26T02:42:08.180Z] 24/09/25 19:42:06 WARN BlockManager: Asked to remove block broadcast_592, which does not exist [2024-09-26T02:42:08.180Z] ====== log-regression (apache-spark) [default], iteration 12 completed (5958.260 ms) ====== [2024-09-26T02:42:08.180Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-09-26T02:42:08.830Z] GC before operation: completed in 215.164 ms, heap usage 380.121 MB -> 112.500 MB. [2024-09-26T02:42:14.739Z] ====== log-regression (apache-spark) [default], iteration 13 completed (5236.460 ms) ====== [2024-09-26T02:42:14.739Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-09-26T02:42:14.739Z] GC before operation: completed in 240.595 ms, heap usage 256.502 MB -> 112.355 MB. [2024-09-26T02:42:19.557Z] ====== log-regression (apache-spark) [default], iteration 14 completed (5441.877 ms) ====== [2024-09-26T02:42:19.557Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-09-26T02:42:19.557Z] GC before operation: completed in 231.815 ms, heap usage 501.345 MB -> 113.191 MB. [2024-09-26T02:42:27.369Z] ====== log-regression (apache-spark) [default], iteration 15 completed (6511.848 ms) ====== [2024-09-26T02:42:27.369Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-09-26T02:42:27.369Z] GC before operation: completed in 284.478 ms, heap usage 300.959 MB -> 112.896 MB. [2024-09-26T02:42:32.244Z] ====== log-regression (apache-spark) [default], iteration 16 completed (5862.486 ms) ====== [2024-09-26T02:42:32.244Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-09-26T02:42:32.750Z] GC before operation: completed in 154.980 ms, heap usage 323.124 MB -> 113.132 MB. [2024-09-26T02:42:37.611Z] ====== log-regression (apache-spark) [default], iteration 17 completed (4730.372 ms) ====== [2024-09-26T02:42:37.611Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-09-26T02:42:37.611Z] GC before operation: completed in 243.568 ms, heap usage 440.335 MB -> 113.620 MB. [2024-09-26T02:42:42.122Z] ====== log-regression (apache-spark) [default], iteration 18 completed (4565.882 ms) ====== [2024-09-26T02:42:42.122Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-09-26T02:42:43.275Z] GC before operation: completed in 872.482 ms, heap usage 511.053 MB -> 113.938 MB. [2024-09-26T02:42:49.223Z] ====== log-regression (apache-spark) [default], iteration 19 completed (5807.823 ms) ====== [2024-09-26T02:42:50.665Z] ----------------------------------- [2024-09-26T02:42:50.665Z] renaissance-log-regression_0_PASSED [2024-09-26T02:42:50.665Z] ----------------------------------- [2024-09-26T02:42:50.665Z] [2024-09-26T02:42:50.665Z] TEST TEARDOWN: [2024-09-26T02:42:50.665Z] Nothing to be done for teardown. [2024-09-26T02:42:50.665Z] renaissance-log-regression_0 Finish Time: Wed Sep 25 19:42:49 2024 Epoch Time (ms): 1727318569884