[2024-11-15T04:02:33.378Z] Running test renaissance-log-regression_0 ... [2024-11-15T04:02:33.378Z] =============================================== [2024-11-15T04:02:33.378Z] renaissance-log-regression_0 Start Time: Fri Nov 15 04:02:33 2024 Epoch Time (ms): 1731643353322 [2024-11-15T04:02:33.711Z] variation: NoOptions [2024-11-15T04:02:33.711Z] JVM_OPTIONS: [2024-11-15T04:02:33.711Z] { \ [2024-11-15T04:02:33.711Z] echo ""; echo "TEST SETUP:"; \ [2024-11-15T04:02:33.711Z] echo "Nothing to be done for setup."; \ [2024-11-15T04:02:33.711Z] mkdir -p "C:/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17316422079109\\renaissance-log-regression_0"; \ [2024-11-15T04:02:33.711Z] cd "C:/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17316422079109\\renaissance-log-regression_0"; \ [2024-11-15T04:02:33.711Z] echo ""; echo "TESTING:"; \ [2024-11-15T04:02:33.711Z] "c:/workspace/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17316422079109\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2024-11-15T04:02:33.711Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd C:/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17316422079109\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-15T04:02:33.711Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-15T04:02:33.711Z] echo "Nothing to be done for teardown."; \ [2024-11-15T04:02:33.711Z] } 2>&1 | tee -a "C:/workspace/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17316422079109\\TestTargetResult"; [2024-11-15T04:02:33.711Z] [2024-11-15T04:02:33.711Z] TEST SETUP: [2024-11-15T04:02:33.711Z] Nothing to be done for setup. [2024-11-15T04:02:33.711Z] [2024-11-15T04:02:33.711Z] TESTING: [2024-11-15T04:02:49.320Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-15T04:02:50.018Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-11-15T04:02:54.665Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-11-15T04:02:54.987Z] GC before operation: completed in 66.659 ms, heap usage 40.886 MB -> 36.011 MB. [2024-11-15T04:03:04.115Z] ====== log-regression (apache-spark) [default], iteration 0 completed (8889.188 ms) ====== [2024-11-15T04:03:04.115Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-11-15T04:03:04.115Z] GC before operation: completed in 96.786 ms, heap usage 789.997 MB -> 118.125 MB. [2024-11-15T04:03:06.463Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2669.746 ms) ====== [2024-11-15T04:03:06.463Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-11-15T04:03:06.800Z] GC before operation: completed in 79.868 ms, heap usage 239.623 MB -> 117.865 MB. [2024-11-15T04:03:09.214Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2253.810 ms) ====== [2024-11-15T04:03:09.214Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-11-15T04:03:09.214Z] GC before operation: completed in 89.859 ms, heap usage 303.547 MB -> 118.347 MB. [2024-11-15T04:03:10.892Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1854.825 ms) ====== [2024-11-15T04:03:10.892Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-11-15T04:03:10.892Z] GC before operation: completed in 76.809 ms, heap usage 279.567 MB -> 118.543 MB. [2024-11-15T04:03:12.552Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1573.173 ms) ====== [2024-11-15T04:03:12.552Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-11-15T04:03:12.552Z] GC before operation: completed in 75.339 ms, heap usage 256.725 MB -> 118.806 MB. [2024-11-15T04:03:14.205Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1528.878 ms) ====== [2024-11-15T04:03:14.205Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-11-15T04:03:14.205Z] GC before operation: completed in 95.149 ms, heap usage 262.378 MB -> 119.044 MB. [2024-11-15T04:03:15.894Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1527.158 ms) ====== [2024-11-15T04:03:15.894Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-11-15T04:03:15.894Z] GC before operation: completed in 86.718 ms, heap usage 652.465 MB -> 120.009 MB. [2024-11-15T04:03:17.615Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1753.024 ms) ====== [2024-11-15T04:03:17.615Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-11-15T04:03:17.615Z] GC before operation: completed in 89.638 ms, heap usage 449.000 MB -> 119.825 MB. [2024-11-15T04:03:19.238Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1465.778 ms) ====== [2024-11-15T04:03:19.238Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-11-15T04:03:19.238Z] GC before operation: completed in 78.593 ms, heap usage 256.291 MB -> 119.620 MB. [2024-11-15T04:03:20.867Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1513.316 ms) ====== [2024-11-15T04:03:20.867Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-11-15T04:03:20.867Z] GC before operation: completed in 83.352 ms, heap usage 221.991 MB -> 119.681 MB. [2024-11-15T04:03:22.094Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1459.427 ms) ====== [2024-11-15T04:03:22.094Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-11-15T04:03:22.514Z] GC before operation: completed in 99.612 ms, heap usage 562.987 MB -> 120.717 MB. [2024-11-15T04:03:23.704Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1495.987 ms) ====== [2024-11-15T04:03:23.704Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-11-15T04:03:24.327Z] GC before operation: completed in 94.558 ms, heap usage 262.528 MB -> 120.347 MB. [2024-11-15T04:03:25.490Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1648.698 ms) ====== [2024-11-15T04:03:25.490Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-11-15T04:03:25.490Z] GC before operation: completed in 83.149 ms, heap usage 230.844 MB -> 120.335 MB. [2024-11-15T04:03:27.370Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1501.234 ms) ====== [2024-11-15T04:03:27.370Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-11-15T04:03:27.370Z] GC before operation: completed in 89.356 ms, heap usage 559.733 MB -> 121.314 MB. [2024-11-15T04:03:28.544Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1443.116 ms) ====== [2024-11-15T04:03:28.544Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-11-15T04:03:28.957Z] GC before operation: completed in 85.628 ms, heap usage 251.977 MB -> 120.796 MB. [2024-11-15T04:03:30.212Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1393.978 ms) ====== [2024-11-15T04:03:30.212Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-11-15T04:03:30.212Z] GC before operation: completed in 88.608 ms, heap usage 553.501 MB -> 121.765 MB. [2024-11-15T04:03:31.869Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1424.940 ms) ====== [2024-11-15T04:03:31.869Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-11-15T04:03:31.869Z] GC before operation: completed in 95.499 ms, heap usage 238.944 MB -> 121.306 MB. [2024-11-15T04:03:33.103Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1436.953 ms) ====== [2024-11-15T04:03:33.103Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-11-15T04:03:33.444Z] GC before operation: completed in 90.476 ms, heap usage 487.925 MB -> 121.955 MB. [2024-11-15T04:03:34.642Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1459.157 ms) ====== [2024-11-15T04:03:34.642Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-11-15T04:03:35.047Z] GC before operation: completed in 96.355 ms, heap usage 251.623 MB -> 121.509 MB. [2024-11-15T04:03:36.221Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1442.317 ms) ====== [2024-11-15T04:03:36.892Z] ----------------------------------- [2024-11-15T04:03:36.892Z] renaissance-log-regression_0_PASSED [2024-11-15T04:03:36.892Z] ----------------------------------- [2024-11-15T04:03:36.892Z] [2024-11-15T04:03:36.892Z] TEST TEARDOWN: [2024-11-15T04:03:36.892Z] Nothing to be done for teardown. [2024-11-15T04:03:36.892Z] renaissance-log-regression_0 Finish Time: Fri Nov 15 04:03:36 2024 Epoch Time (ms): 1731643416822