[2024-08-22T02:13:01.445Z] Running test renaissance-log-regression_0 ... [2024-08-22T02:13:01.445Z] =============================================== [2024-08-22T02:13:01.445Z] renaissance-log-regression_0 Start Time: Wed Aug 21 19:13:00 2024 Epoch Time (ms): 1724292780698 [2024-08-22T02:13:01.445Z] variation: NoOptions [2024-08-22T02:13:01.445Z] JVM_OPTIONS: [2024-08-22T02:13:01.445Z] { \ [2024-08-22T02:13:01.445Z] echo ""; echo "TEST SETUP:"; \ [2024-08-22T02:13:01.445Z] echo "Nothing to be done for setup."; \ [2024-08-22T02:13:01.445Z] mkdir -p "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17242911701126/renaissance-log-regression_0"; \ [2024-08-22T02:13:01.445Z] cd "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17242911701126/renaissance-log-regression_0"; \ [2024-08-22T02:13:01.445Z] echo ""; echo "TESTING:"; \ [2024-08-22T02:13:01.445Z] "/Users/admin/workspace/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17242911701126/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-22T02:13:01.445Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/..; rm -f -r "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17242911701126/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-22T02:13:01.445Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-22T02:13:01.445Z] echo "Nothing to be done for teardown."; \ [2024-08-22T02:13:01.445Z] } 2>&1 | tee -a "/Users/admin/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17242911701126/TestTargetResult"; [2024-08-22T02:13:01.445Z] [2024-08-22T02:13:01.445Z] TEST SETUP: [2024-08-22T02:13:01.445Z] Nothing to be done for setup. [2024-08-22T02:13:01.445Z] [2024-08-22T02:13:01.445Z] TESTING: [2024-08-22T02:13:17.567Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-22T02:13:22.292Z] NOTE: 'log-regression' benchmark uses Spark local executor with 3 (out of 3) threads. [2024-08-22T02:13:43.289Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-22T02:13:43.289Z] GC before operation: completed in 139.691 ms, heap usage 114.557 MB -> 35.407 MB. [2024-08-22T02:14:15.211Z] ====== log-regression (apache-spark) [default], iteration 0 completed (31989.636 ms) ====== [2024-08-22T02:14:15.211Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-22T02:14:15.211Z] GC before operation: completed in 290.112 ms, heap usage 795.059 MB -> 111.738 MB. [2024-08-22T02:14:23.230Z] ====== log-regression (apache-spark) [default], iteration 1 completed (9993.054 ms) ====== [2024-08-22T02:14:23.230Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-22T02:14:23.752Z] GC before operation: completed in 285.994 ms, heap usage 255.774 MB -> 111.477 MB. [2024-08-22T02:14:33.265Z] ====== log-regression (apache-spark) [default], iteration 2 completed (8629.410 ms) ====== [2024-08-22T02:14:33.265Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-22T02:14:33.265Z] GC before operation: completed in 251.896 ms, heap usage 429.473 MB -> 112.307 MB. [2024-08-22T02:14:41.455Z] ====== log-regression (apache-spark) [default], iteration 3 completed (8536.751 ms) ====== [2024-08-22T02:14:41.455Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-22T02:14:41.455Z] GC before operation: completed in 274.147 ms, heap usage 373.806 MB -> 112.334 MB. [2024-08-22T02:14:48.011Z] ====== log-regression (apache-spark) [default], iteration 4 completed (6035.097 ms) ====== [2024-08-22T02:14:48.011Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-22T02:14:48.011Z] GC before operation: completed in 169.321 ms, heap usage 410.500 MB -> 112.635 MB. [2024-08-22T02:14:59.715Z] ====== log-regression (apache-spark) [default], iteration 5 completed (11492.924 ms) ====== [2024-08-22T02:14:59.715Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-22T02:15:00.452Z] GC before operation: completed in 1025.943 ms, heap usage 419.305 MB -> 112.873 MB. [2024-08-22T02:15:08.612Z] ====== log-regression (apache-spark) [default], iteration 6 completed (7577.715 ms) ====== [2024-08-22T02:15:08.612Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-22T02:15:08.612Z] GC before operation: completed in 217.422 ms, heap usage 515.774 MB -> 113.298 MB. [2024-08-22T02:15:13.648Z] ====== log-regression (apache-spark) [default], iteration 7 completed (5898.943 ms) ====== [2024-08-22T02:15:13.648Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-22T02:15:14.194Z] GC before operation: completed in 257.215 ms, heap usage 482.967 MB -> 113.421 MB. [2024-08-22T02:15:20.778Z] ====== log-regression (apache-spark) [default], iteration 8 completed (6558.568 ms) ====== [2024-08-22T02:15:20.778Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-22T02:15:20.778Z] GC before operation: completed in 183.756 ms, heap usage 241.034 MB -> 113.059 MB. [2024-08-22T02:15:29.266Z] ====== log-regression (apache-spark) [default], iteration 9 completed (8530.745 ms) ====== [2024-08-22T02:15:29.266Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-22T02:15:29.767Z] GC before operation: completed in 225.209 ms, heap usage 805.052 MB -> 114.191 MB. [2024-08-22T02:15:36.026Z] ====== log-regression (apache-spark) [default], iteration 10 completed (6310.616 ms) ====== [2024-08-22T02:15:36.026Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-22T02:15:36.026Z] GC before operation: completed in 186.339 ms, heap usage 321.189 MB -> 113.596 MB. [2024-08-22T02:15:43.711Z] ====== log-regression (apache-spark) [default], iteration 11 completed (6632.033 ms) ====== [2024-08-22T02:15:43.711Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-22T02:15:43.711Z] GC before operation: completed in 142.377 ms, heap usage 230.938 MB -> 113.658 MB. [2024-08-22T02:15:48.888Z] ====== log-regression (apache-spark) [default], iteration 12 completed (5496.954 ms) ====== [2024-08-22T02:15:48.888Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-22T02:15:48.888Z] GC before operation: completed in 240.032 ms, heap usage 366.985 MB -> 114.088 MB. [2024-08-22T02:15:54.013Z] ====== log-regression (apache-spark) [default], iteration 13 completed (4755.272 ms) ====== [2024-08-22T02:15:54.013Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-22T02:15:54.013Z] GC before operation: completed in 642.792 ms, heap usage 302.345 MB -> 114.131 MB. [2024-08-22T02:16:01.894Z] ====== log-regression (apache-spark) [default], iteration 14 completed (7401.260 ms) ====== [2024-08-22T02:16:01.894Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-22T02:16:02.454Z] GC before operation: completed in 744.322 ms, heap usage 393.462 MB -> 114.560 MB. [2024-08-22T02:16:08.815Z] ====== log-regression (apache-spark) [default], iteration 15 completed (6275.686 ms) ====== [2024-08-22T02:16:08.815Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-22T02:16:08.815Z] GC before operation: completed in 213.749 ms, heap usage 271.529 MB -> 114.430 MB. [2024-08-22T02:16:14.081Z] ====== log-regression (apache-spark) [default], iteration 16 completed (5652.312 ms) ====== [2024-08-22T02:16:14.081Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-22T02:16:14.746Z] GC before operation: completed in 242.647 ms, heap usage 334.911 MB -> 114.798 MB. [2024-08-22T02:16:21.411Z] ====== log-regression (apache-spark) [default], iteration 17 completed (6466.973 ms) ====== [2024-08-22T02:16:21.411Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-22T02:16:21.411Z] GC before operation: completed in 238.840 ms, heap usage 344.684 MB -> 114.990 MB. [2024-08-22T02:16:28.035Z] ====== log-regression (apache-spark) [default], iteration 18 completed (6919.140 ms) ====== [2024-08-22T02:16:28.035Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-22T02:16:28.663Z] GC before operation: completed in 254.236 ms, heap usage 735.676 MB -> 115.896 MB. [2024-08-22T02:16:35.082Z] ====== log-regression (apache-spark) [default], iteration 19 completed (5926.311 ms) ====== [2024-08-22T02:16:35.082Z] ----------------------------------- [2024-08-22T02:16:35.082Z] renaissance-log-regression_0_PASSED [2024-08-22T02:16:35.082Z] ----------------------------------- [2024-08-22T02:16:35.570Z] [2024-08-22T02:16:35.570Z] TEST TEARDOWN: [2024-08-22T02:16:35.570Z] Nothing to be done for teardown. [2024-08-22T02:16:35.570Z] renaissance-log-regression_0 Finish Time: Wed Aug 21 19:16:34 2024 Epoch Time (ms): 1724292994587