[2025-02-19T21:56:17.927Z] Running test renaissance-log-regression_0 ... [2025-02-19T21:56:17.927Z] =============================================== [2025-02-19T21:56:17.927Z] renaissance-log-regression_0 Start Time: Wed Feb 19 21:56:17 2025 Epoch Time (ms): 1740002177161 [2025-02-19T21:56:17.927Z] variation: NoOptions [2025-02-19T21:56:17.927Z] JVM_OPTIONS: [2025-02-19T21:56:17.927Z] { \ [2025-02-19T21:56:17.927Z] echo ""; echo "TEST SETUP:"; \ [2025-02-19T21:56:17.927Z] echo "Nothing to be done for setup."; \ [2025-02-19T21:56:17.927Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17400015116116/renaissance-log-regression_0"; \ [2025-02-19T21:56:17.927Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17400015116116/renaissance-log-regression_0"; \ [2025-02-19T21:56:17.927Z] echo ""; echo "TESTING:"; \ [2025-02-19T21:56:17.927Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/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 "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17400015116116/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-02-19T21:56:17.927Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17400015116116/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-19T21:56:17.927Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-19T21:56:17.927Z] echo "Nothing to be done for teardown."; \ [2025-02-19T21:56:17.927Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_aarch64_linux/aqa-tests/TKG/../TKG/output_17400015116116/TestTargetResult"; [2025-02-19T21:56:17.927Z] [2025-02-19T21:56:17.927Z] TEST SETUP: [2025-02-19T21:56:17.927Z] Nothing to be done for setup. [2025-02-19T21:56:17.927Z] [2025-02-19T21:56:17.927Z] TESTING: [2025-02-19T21:56:20.953Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-19T21:56:23.981Z] NOTE: 'log-regression' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-02-19T21:56:28.121Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-02-19T21:56:28.121Z] GC before operation: completed in 57.838 ms, heap usage 71.799 MB -> 37.247 MB. [2025-02-19T21:56:37.855Z] ====== log-regression (apache-spark) [default], iteration 0 completed (9461.446 ms) ====== [2025-02-19T21:56:37.855Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-02-19T21:56:37.855Z] GC before operation: completed in 139.959 ms, heap usage 317.354 MB -> 121.517 MB. [2025-02-19T21:56:40.978Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2700.910 ms) ====== [2025-02-19T21:56:40.979Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-02-19T21:56:40.979Z] GC before operation: completed in 91.934 ms, heap usage 380.624 MB -> 122.418 MB. [2025-02-19T21:56:42.942Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2316.072 ms) ====== [2025-02-19T21:56:42.942Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-02-19T21:56:42.942Z] GC before operation: completed in 111.066 ms, heap usage 266.824 MB -> 122.656 MB. [2025-02-19T21:56:44.922Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2133.113 ms) ====== [2025-02-19T21:56:44.922Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-02-19T21:56:45.871Z] GC before operation: completed in 96.223 ms, heap usage 373.240 MB -> 123.237 MB. [2025-02-19T21:56:47.825Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2002.683 ms) ====== [2025-02-19T21:56:47.825Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-02-19T21:56:47.825Z] GC before operation: completed in 117.177 ms, heap usage 417.374 MB -> 123.703 MB. [2025-02-19T21:56:49.774Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2272.606 ms) ====== [2025-02-19T21:56:49.774Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-02-19T21:56:49.774Z] GC before operation: completed in 105.414 ms, heap usage 298.386 MB -> 123.756 MB. [2025-02-19T21:56:51.725Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2087.992 ms) ====== [2025-02-19T21:56:51.725Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-02-19T21:56:51.725Z] GC before operation: completed in 106.012 ms, heap usage 437.407 MB -> 124.414 MB. [2025-02-19T21:56:53.673Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1859.088 ms) ====== [2025-02-19T21:56:53.673Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-02-19T21:56:54.621Z] GC before operation: completed in 109.410 ms, heap usage 312.071 MB -> 124.563 MB. [2025-02-19T21:56:55.608Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1824.511 ms) ====== [2025-02-19T21:56:55.608Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-02-19T21:56:56.555Z] GC before operation: completed in 118.063 ms, heap usage 288.917 MB -> 124.792 MB. [2025-02-19T21:56:57.504Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1785.933 ms) ====== [2025-02-19T21:56:57.504Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-02-19T21:56:58.452Z] GC before operation: completed in 109.786 ms, heap usage 223.325 MB -> 125.055 MB. [2025-02-19T21:56:59.403Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1839.919 ms) ====== [2025-02-19T21:56:59.403Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-02-19T21:57:00.358Z] GC before operation: completed in 116.973 ms, heap usage 691.385 MB -> 126.692 MB. [2025-02-19T21:57:01.311Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1642.812 ms) ====== [2025-02-19T21:57:01.312Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-02-19T21:57:01.312Z] GC before operation: completed in 113.132 ms, heap usage 449.659 MB -> 126.535 MB. [2025-02-19T21:57:04.357Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2311.411 ms) ====== [2025-02-19T21:57:04.357Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-02-19T21:57:04.357Z] GC before operation: completed in 116.887 ms, heap usage 464.501 MB -> 126.842 MB. [2025-02-19T21:57:06.329Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1769.868 ms) ====== [2025-02-19T21:57:06.329Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-02-19T21:57:06.329Z] GC before operation: completed in 137.135 ms, heap usage 238.497 MB -> 126.668 MB. [2025-02-19T21:57:08.310Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1849.810 ms) ====== [2025-02-19T21:57:08.310Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-02-19T21:57:08.310Z] GC before operation: completed in 120.417 ms, heap usage 689.984 MB -> 128.081 MB. [2025-02-19T21:57:10.276Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1838.574 ms) ====== [2025-02-19T21:57:10.276Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-02-19T21:57:10.276Z] GC before operation: completed in 120.554 ms, heap usage 439.364 MB -> 127.604 MB. [2025-02-19T21:57:11.227Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1685.565 ms) ====== [2025-02-19T21:57:11.227Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-02-19T21:57:12.183Z] GC before operation: completed in 126.173 ms, heap usage 463.968 MB -> 128.043 MB. [2025-02-19T21:57:13.151Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1625.599 ms) ====== [2025-02-19T21:57:13.151Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-02-19T21:57:13.151Z] GC before operation: completed in 125.107 ms, heap usage 490.435 MB -> 128.524 MB. [2025-02-19T21:57:15.101Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1680.961 ms) ====== [2025-02-19T21:57:15.101Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-02-19T21:57:15.101Z] GC before operation: completed in 125.600 ms, heap usage 667.516 MB -> 129.416 MB. [2025-02-19T21:57:17.057Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1804.937 ms) ====== [2025-02-19T21:57:18.007Z] ----------------------------------- [2025-02-19T21:57:18.007Z] renaissance-log-regression_0_PASSED [2025-02-19T21:57:18.007Z] ----------------------------------- [2025-02-19T21:57:18.007Z] [2025-02-19T21:57:18.007Z] TEST TEARDOWN: [2025-02-19T21:57:18.007Z] Nothing to be done for teardown. [2025-02-19T21:57:18.007Z] renaissance-log-regression_0 Finish Time: Wed Feb 19 21:57:16 2025 Epoch Time (ms): 1740002236993