[2024-11-15T20:18:04.433Z] Running test renaissance-log-regression_0 ... [2024-11-15T20:18:04.433Z] =============================================== [2024-11-15T20:18:04.433Z] renaissance-log-regression_0 Start Time: Fri Nov 15 20:18:04 2024 Epoch Time (ms): 1731701884379 [2024-11-15T20:18:04.745Z] variation: NoOptions [2024-11-15T20:18:04.745Z] JVM_OPTIONS: [2024-11-15T20:18:04.745Z] { \ [2024-11-15T20:18:04.745Z] echo ""; echo "TEST SETUP:"; \ [2024-11-15T20:18:04.745Z] echo "Nothing to be done for setup."; \ [2024-11-15T20:18:04.745Z] mkdir -p "C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17317002903352\\renaissance-log-regression_0"; \ [2024-11-15T20:18:04.745Z] cd "C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17317002903352\\renaissance-log-regression_0"; \ [2024-11-15T20:18:04.745Z] echo ""; echo "TESTING:"; \ [2024-11-15T20:18:04.745Z] "c:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/jdkbinary/j2sdk-image\\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 "C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17317002903352\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2024-11-15T20:18:04.745Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17317002903352\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-15T20:18:04.745Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-15T20:18:04.745Z] echo "Nothing to be done for teardown."; \ [2024-11-15T20:18:04.745Z] } 2>&1 | tee -a "C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17317002903352\\TestTargetResult"; [2024-11-15T20:18:05.070Z] [2024-11-15T20:18:05.070Z] TEST SETUP: [2024-11-15T20:18:05.070Z] Nothing to be done for setup. [2024-11-15T20:18:05.070Z] [2024-11-15T20:18:05.070Z] TESTING: [2024-11-15T20:18:20.710Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-15T20:18:22.323Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-11-15T20:18:24.512Z] WARNING: An illegal reflective access operation has occurred [2024-11-15T20:18:24.512Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/C:/workspace/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/TKG/output_17317002903352/renaissance-log-regression_0/harness-201805-8485888061153181951/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-11-15T20:18:24.512Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-11-15T20:18:24.512Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-11-15T20:18:24.512Z] WARNING: All illegal access operations will be denied in a future release [2024-11-15T20:18:27.396Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-11-15T20:18:27.396Z] GC before operation: completed in 70.275 ms, heap usage 115.469 MB -> 34.285 MB. [2024-11-15T20:18:38.246Z] ====== log-regression (apache-spark) [default], iteration 0 completed (10886.921 ms) ====== [2024-11-15T20:18:38.247Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-11-15T20:18:38.784Z] GC before operation: completed in 146.280 ms, heap usage 445.918 MB -> 112.819 MB. [2024-11-15T20:18:42.866Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3715.944 ms) ====== [2024-11-15T20:18:42.866Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-11-15T20:18:42.866Z] GC before operation: completed in 124.449 ms, heap usage 280.943 MB -> 113.013 MB. [2024-11-15T20:18:45.284Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2673.469 ms) ====== [2024-11-15T20:18:45.284Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-11-15T20:18:45.284Z] GC before operation: completed in 165.939 ms, heap usage 330.688 MB -> 113.542 MB. [2024-11-15T20:18:47.688Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2302.272 ms) ====== [2024-11-15T20:18:47.688Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-11-15T20:18:47.688Z] GC before operation: completed in 131.662 ms, heap usage 319.403 MB -> 113.715 MB. [2024-11-15T20:18:50.027Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2231.369 ms) ====== [2024-11-15T20:18:50.027Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-11-15T20:18:50.027Z] GC before operation: completed in 121.068 ms, heap usage 329.763 MB -> 113.983 MB. [2024-11-15T20:18:52.326Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2107.377 ms) ====== [2024-11-15T20:18:52.326Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-11-15T20:18:52.326Z] GC before operation: completed in 125.040 ms, heap usage 393.917 MB -> 114.291 MB. [2024-11-15T20:18:54.803Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2255.493 ms) ====== [2024-11-15T20:18:54.803Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-11-15T20:18:54.803Z] GC before operation: completed in 120.167 ms, heap usage 305.811 MB -> 114.298 MB. [2024-11-15T20:18:56.643Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1922.854 ms) ====== [2024-11-15T20:18:56.643Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-11-15T20:18:56.643Z] GC before operation: completed in 138.617 ms, heap usage 260.857 MB -> 114.360 MB. [2024-11-15T20:18:58.934Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2004.709 ms) ====== [2024-11-15T20:18:58.934Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-11-15T20:18:58.934Z] GC before operation: completed in 131.384 ms, heap usage 410.639 MB -> 114.923 MB. [2024-11-15T20:19:00.637Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1930.435 ms) ====== [2024-11-15T20:19:00.637Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-11-15T20:19:01.057Z] GC before operation: completed in 130.079 ms, heap usage 295.981 MB -> 114.843 MB. [2024-11-15T20:19:02.730Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1886.535 ms) ====== [2024-11-15T20:19:02.730Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-11-15T20:19:02.730Z] GC before operation: completed in 150.006 ms, heap usage 417.412 MB -> 115.322 MB. [2024-11-15T20:19:05.194Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1880.956 ms) ====== [2024-11-15T20:19:05.194Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-11-15T20:19:05.194Z] GC before operation: completed in 151.926 ms, heap usage 335.953 MB -> 115.284 MB. [2024-11-15T20:19:07.119Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2004.416 ms) ====== [2024-11-15T20:19:07.119Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-11-15T20:19:07.119Z] GC before operation: completed in 136.297 ms, heap usage 240.405 MB -> 115.243 MB. [2024-11-15T20:19:08.913Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1973.705 ms) ====== [2024-11-15T20:19:08.913Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-11-15T20:19:09.278Z] GC before operation: completed in 138.637 ms, heap usage 287.318 MB -> 115.594 MB. [2024-11-15T20:19:10.944Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1893.631 ms) ====== [2024-11-15T20:19:10.944Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-11-15T20:19:11.267Z] GC before operation: completed in 142.397 ms, heap usage 259.789 MB -> 115.661 MB. [2024-11-15T20:19:12.978Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1802.157 ms) ====== [2024-11-15T20:19:12.978Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-11-15T20:19:12.978Z] GC before operation: completed in 137.449 ms, heap usage 343.454 MB -> 116.121 MB. [2024-11-15T20:19:15.410Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1989.448 ms) ====== [2024-11-15T20:19:15.410Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-11-15T20:19:15.410Z] GC before operation: completed in 177.296 ms, heap usage 249.094 MB -> 116.096 MB. [2024-11-15T20:19:17.165Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1943.876 ms) ====== [2024-11-15T20:19:17.165Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-11-15T20:19:17.493Z] GC before operation: completed in 143.824 ms, heap usage 325.204 MB -> 116.472 MB. [2024-11-15T20:19:19.177Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1819.258 ms) ====== [2024-11-15T20:19:19.177Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-11-15T20:19:19.177Z] GC before operation: completed in 135.419 ms, heap usage 305.976 MB -> 116.619 MB. [2024-11-15T20:19:21.532Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1864.382 ms) ====== [2024-11-15T20:19:21.532Z] ----------------------------------- [2024-11-15T20:19:21.532Z] renaissance-log-regression_0_PASSED [2024-11-15T20:19:21.532Z] ----------------------------------- [2024-11-15T20:19:21.859Z] [2024-11-15T20:19:21.859Z] TEST TEARDOWN: [2024-11-15T20:19:21.859Z] Nothing to be done for teardown. [2024-11-15T20:19:21.859Z] renaissance-log-regression_0 Finish Time: Fri Nov 15 20:19:21 2024 Epoch Time (ms): 1731701961731