[2024-08-21T23:55:20.588Z] Running test renaissance-log-regression_0 ... [2024-08-21T23:55:20.588Z] =============================================== [2024-08-21T23:55:20.588Z] renaissance-log-regression_0 Start Time: Wed Aug 21 16:55:19 2024 Epoch Time (ms): 1724284519901 [2024-08-21T23:55:20.588Z] variation: NoOptions [2024-08-21T23:55:20.588Z] JVM_OPTIONS: [2024-08-21T23:55:20.588Z] { \ [2024-08-21T23:55:20.588Z] echo ""; echo "TEST SETUP:"; \ [2024-08-21T23:55:20.588Z] echo "Nothing to be done for setup."; \ [2024-08-21T23:55:20.588Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17242818951253/renaissance-log-regression_0"; \ [2024-08-21T23:55:20.588Z] cd "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17242818951253/renaissance-log-regression_0"; \ [2024-08-21T23:55:20.588Z] echo ""; echo "TESTING:"; \ [2024-08-21T23:55:20.588Z] "/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_17242818951253/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-21T23:55:20.588Z] 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_17242818951253/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-21T23:55:20.588Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-21T23:55:20.588Z] echo "Nothing to be done for teardown."; \ [2024-08-21T23:55:20.588Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17242818951253/TestTargetResult"; [2024-08-21T23:55:20.588Z] [2024-08-21T23:55:20.588Z] TEST SETUP: [2024-08-21T23:55:20.588Z] Nothing to be done for setup. [2024-08-21T23:55:20.588Z] [2024-08-21T23:55:20.588Z] TESTING: [2024-08-21T23:55:39.471Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-21T23:55:46.768Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2024-08-21T23:55:55.556Z] WARNING: An illegal reflective access operation has occurred [2024-08-21T23:55:55.556Z] 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_17242818951253/renaissance-log-regression_0/harness-165522-15887397237145813835/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-21T23:55:55.556Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-21T23:55:55.556Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-21T23:55:55.556Z] WARNING: All illegal access operations will be denied in a future release [2024-08-21T23:56:03.148Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-21T23:56:03.659Z] GC before operation: completed in 235.502 ms, heap usage 87.432 MB -> 33.900 MB. [2024-08-21T23:56:47.444Z] ====== log-regression (apache-spark) [default], iteration 0 completed (37625.628 ms) ====== [2024-08-21T23:56:47.444Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-21T23:56:47.444Z] GC before operation: completed in 638.391 ms, heap usage 430.425 MB -> 109.901 MB. [2024-08-21T23:56:54.060Z] ====== log-regression (apache-spark) [default], iteration 1 completed (12261.482 ms) ====== [2024-08-21T23:56:54.060Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-21T23:56:54.594Z] GC before operation: completed in 323.091 ms, heap usage 492.135 MB -> 110.551 MB. [2024-08-21T23:57:05.986Z] ====== log-regression (apache-spark) [default], iteration 2 completed (11204.453 ms) ====== [2024-08-21T23:57:05.986Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-21T23:57:06.655Z] GC before operation: completed in 632.087 ms, heap usage 283.861 MB -> 110.413 MB. [2024-08-21T23:57:14.871Z] ====== log-regression (apache-spark) [default], iteration 3 completed (7652.466 ms) ====== [2024-08-21T23:57:14.871Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-21T23:57:14.871Z] GC before operation: completed in 281.450 ms, heap usage 244.397 MB -> 110.527 MB. [2024-08-21T23:57:24.718Z] ====== log-regression (apache-spark) [default], iteration 4 completed (10395.596 ms) ====== [2024-08-21T23:57:24.718Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-21T23:57:25.154Z] GC before operation: completed in 494.624 ms, heap usage 548.796 MB -> 111.468 MB. [2024-08-21T23:57:36.360Z] ====== log-regression (apache-spark) [default], iteration 5 completed (10501.397 ms) ====== [2024-08-21T23:57:36.360Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-21T23:57:36.360Z] GC before operation: completed in 370.898 ms, heap usage 181.787 MB -> 111.067 MB. [2024-08-21T23:57:45.722Z] ====== log-regression (apache-spark) [default], iteration 6 completed (9025.865 ms) ====== [2024-08-21T23:57:45.722Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-21T23:57:45.722Z] GC before operation: completed in 262.972 ms, heap usage 467.590 MB -> 111.703 MB. [2024-08-21T23:57:55.149Z] ====== log-regression (apache-spark) [default], iteration 7 completed (9818.829 ms) ====== [2024-08-21T23:57:55.150Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-21T23:57:55.896Z] GC before operation: completed in 359.768 ms, heap usage 329.586 MB -> 111.559 MB. [2024-08-21T23:58:03.582Z] ====== log-regression (apache-spark) [default], iteration 8 completed (7483.064 ms) ====== [2024-08-21T23:58:03.582Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-21T23:58:03.582Z] GC before operation: completed in 345.906 ms, heap usage 226.944 MB -> 111.418 MB. [2024-08-21T23:58:11.138Z] ====== log-regression (apache-spark) [default], iteration 9 completed (7399.104 ms) ====== [2024-08-21T23:58:11.138Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-21T23:58:11.138Z] GC before operation: completed in 270.634 ms, heap usage 416.842 MB -> 112.059 MB. [2024-08-21T23:58:18.737Z] ====== log-regression (apache-spark) [default], iteration 10 completed (7761.132 ms) ====== [2024-08-21T23:58:18.737Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-21T23:58:19.300Z] GC before operation: completed in 470.640 ms, heap usage 334.119 MB -> 112.048 MB. [2024-08-21T23:58:27.107Z] ====== log-regression (apache-spark) [default], iteration 11 completed (7806.040 ms) ====== [2024-08-21T23:58:27.107Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-21T23:58:27.597Z] GC before operation: completed in 392.038 ms, heap usage 396.577 MB -> 112.381 MB. [2024-08-21T23:58:37.835Z] ====== log-regression (apache-spark) [default], iteration 12 completed (9543.635 ms) ====== [2024-08-21T23:58:37.835Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-21T23:58:37.835Z] GC before operation: completed in 291.233 ms, heap usage 364.652 MB -> 112.465 MB. [2024-08-21T23:58:45.269Z] ====== log-regression (apache-spark) [default], iteration 13 completed (7045.803 ms) ====== [2024-08-21T23:58:45.269Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-21T23:58:45.269Z] GC before operation: completed in 300.018 ms, heap usage 456.870 MB -> 112.866 MB. [2024-08-21T23:58:52.678Z] ====== log-regression (apache-spark) [default], iteration 14 completed (7580.561 ms) ====== [2024-08-21T23:58:52.678Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-21T23:58:52.678Z] GC before operation: completed in 533.057 ms, heap usage 367.368 MB -> 112.861 MB. [2024-08-21T23:58:58.932Z] ====== log-regression (apache-spark) [default], iteration 15 completed (6358.080 ms) ====== [2024-08-21T23:58:58.932Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-21T23:58:59.510Z] GC before operation: completed in 358.882 ms, heap usage 300.297 MB -> 112.887 MB. [2024-08-21T23:59:08.786Z] ====== log-regression (apache-spark) [default], iteration 16 completed (8214.448 ms) ====== [2024-08-21T23:59:08.786Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-21T23:59:08.786Z] GC before operation: completed in 205.256 ms, heap usage 225.150 MB -> 112.897 MB. [2024-08-21T23:59:16.808Z] ====== log-regression (apache-spark) [default], iteration 17 completed (8813.328 ms) ====== [2024-08-21T23:59:16.808Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-21T23:59:16.808Z] GC before operation: completed in 275.492 ms, heap usage 403.399 MB -> 113.559 MB. [2024-08-21T23:59:24.215Z] ====== log-regression (apache-spark) [default], iteration 18 completed (6430.735 ms) ====== [2024-08-21T23:59:24.215Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-21T23:59:24.215Z] GC before operation: completed in 358.298 ms, heap usage 474.637 MB -> 113.870 MB. [2024-08-21T23:59:30.679Z] ====== log-regression (apache-spark) [default], iteration 19 completed (6362.372 ms) ====== [2024-08-21T23:59:32.321Z] ----------------------------------- [2024-08-21T23:59:32.321Z] renaissance-log-regression_0_PASSED [2024-08-21T23:59:32.321Z] ----------------------------------- [2024-08-21T23:59:32.321Z] [2024-08-21T23:59:32.321Z] TEST TEARDOWN: [2024-08-21T23:59:32.321Z] Nothing to be done for teardown. [2024-08-21T23:59:32.321Z] renaissance-log-regression_0 Finish Time: Wed Aug 21 16:59:31 2024 Epoch Time (ms): 1724284771828