[2024-08-15T00:17:24.019Z] Running test renaissance-log-regression_0 ... [2024-08-15T00:17:24.019Z] =============================================== [2024-08-15T00:17:24.019Z] renaissance-log-regression_0 Start Time: Thu Aug 15 00:17:23 2024 Epoch Time (ms): 1723681043673 [2024-08-15T00:17:24.019Z] variation: NoOptions [2024-08-15T00:17:24.019Z] JVM_OPTIONS: [2024-08-15T00:17:24.019Z] { \ [2024-08-15T00:17:24.019Z] echo ""; echo "TEST SETUP:"; \ [2024-08-15T00:17:24.019Z] echo "Nothing to be done for setup."; \ [2024-08-15T00:17:24.019Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_1723680260174/renaissance-log-regression_0"; \ [2024-08-15T00:17:24.019Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_1723680260174/renaissance-log-regression_0"; \ [2024-08-15T00:17:24.019Z] echo ""; echo "TESTING:"; \ [2024-08-15T00:17:24.019Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/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_s390x_linux_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_1723680260174/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-15T00:17:24.019Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_1723680260174/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-15T00:17:24.019Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-15T00:17:24.019Z] echo "Nothing to be done for teardown."; \ [2024-08-15T00:17:24.019Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_1723680260174/TestTargetResult"; [2024-08-15T00:17:24.019Z] [2024-08-15T00:17:24.019Z] TEST SETUP: [2024-08-15T00:17:24.019Z] Nothing to be done for setup. [2024-08-15T00:17:24.019Z] [2024-08-15T00:17:24.019Z] TESTING: [2024-08-15T00:17:26.816Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-15T00:17:28.694Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-08-15T00:17:31.690Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-15T00:17:31.690Z] GC before operation: completed in 46.303 ms, heap usage 58.626 MB -> 35.088 MB. [2024-08-15T00:17:40.923Z] ====== log-regression (apache-spark) [default], iteration 0 completed (9012.724 ms) ====== [2024-08-15T00:17:40.923Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-15T00:17:40.923Z] GC before operation: completed in 131.338 ms, heap usage 281.339 MB -> 110.413 MB. [2024-08-15T00:17:44.275Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3435.868 ms) ====== [2024-08-15T00:17:44.275Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-15T00:17:44.275Z] GC before operation: completed in 112.170 ms, heap usage 277.092 MB -> 110.950 MB. [2024-08-15T00:17:46.705Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2846.923 ms) ====== [2024-08-15T00:17:46.706Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-15T00:17:46.706Z] GC before operation: completed in 95.478 ms, heap usage 287.340 MB -> 111.379 MB. [2024-08-15T00:17:49.718Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2767.787 ms) ====== [2024-08-15T00:17:49.718Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-15T00:17:49.718Z] GC before operation: completed in 119.892 ms, heap usage 262.384 MB -> 111.598 MB. [2024-08-15T00:17:53.497Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3041.714 ms) ====== [2024-08-15T00:17:53.497Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-15T00:17:53.497Z] GC before operation: completed in 105.052 ms, heap usage 314.397 MB -> 111.953 MB. [2024-08-15T00:17:56.576Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2606.392 ms) ====== [2024-08-15T00:17:56.576Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-15T00:17:56.577Z] GC before operation: completed in 85.139 ms, heap usage 237.319 MB -> 111.979 MB. [2024-08-15T00:17:58.301Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2230.741 ms) ====== [2024-08-15T00:17:58.301Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-15T00:17:58.301Z] GC before operation: completed in 108.608 ms, heap usage 299.932 MB -> 112.404 MB. [2024-08-15T00:18:00.720Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2492.715 ms) ====== [2024-08-15T00:18:00.720Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-15T00:18:00.720Z] GC before operation: completed in 98.980 ms, heap usage 240.685 MB -> 112.369 MB. [2024-08-15T00:18:03.287Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2623.102 ms) ====== [2024-08-15T00:18:03.287Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-15T00:18:03.287Z] GC before operation: completed in 138.563 ms, heap usage 319.494 MB -> 112.745 MB. [2024-08-15T00:18:05.949Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2260.543 ms) ====== [2024-08-15T00:18:05.949Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-15T00:18:05.949Z] GC before operation: completed in 106.284 ms, heap usage 302.618 MB -> 112.889 MB. [2024-08-15T00:18:07.938Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2449.266 ms) ====== [2024-08-15T00:18:07.938Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-15T00:18:08.542Z] GC before operation: completed in 126.220 ms, heap usage 244.214 MB -> 112.841 MB. [2024-08-15T00:18:11.030Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2282.818 ms) ====== [2024-08-15T00:18:11.030Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-15T00:18:11.030Z] GC before operation: completed in 95.526 ms, heap usage 281.002 MB -> 113.168 MB. [2024-08-15T00:18:12.858Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2251.772 ms) ====== [2024-08-15T00:18:12.858Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-15T00:18:12.858Z] GC before operation: completed in 121.270 ms, heap usage 226.893 MB -> 113.172 MB. [2024-08-15T00:18:15.780Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2279.629 ms) ====== [2024-08-15T00:18:15.780Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-15T00:18:15.780Z] GC before operation: completed in 100.800 ms, heap usage 263.888 MB -> 113.414 MB. [2024-08-15T00:18:18.029Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2170.793 ms) ====== [2024-08-15T00:18:18.029Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-15T00:18:18.029Z] GC before operation: completed in 99.161 ms, heap usage 244.574 MB -> 113.566 MB. [2024-08-15T00:18:20.340Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2211.055 ms) ====== [2024-08-15T00:18:20.340Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-15T00:18:20.340Z] GC before operation: completed in 102.446 ms, heap usage 280.914 MB -> 113.914 MB. [2024-08-15T00:18:22.323Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2257.670 ms) ====== [2024-08-15T00:18:22.323Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-15T00:18:22.323Z] GC before operation: completed in 113.441 ms, heap usage 263.509 MB -> 114.023 MB. [2024-08-15T00:18:24.787Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2459.182 ms) ====== [2024-08-15T00:18:24.787Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-15T00:18:25.405Z] GC before operation: completed in 159.901 ms, heap usage 237.746 MB -> 114.168 MB. [2024-08-15T00:18:27.075Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2152.224 ms) ====== [2024-08-15T00:18:27.075Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-15T00:18:27.771Z] GC before operation: completed in 128.719 ms, heap usage 331.539 MB -> 114.608 MB. [2024-08-15T00:18:29.782Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2338.199 ms) ====== [2024-08-15T00:18:29.782Z] ----------------------------------- [2024-08-15T00:18:29.782Z] renaissance-log-regression_0_PASSED [2024-08-15T00:18:29.782Z] ----------------------------------- [2024-08-15T00:18:30.523Z] [2024-08-15T00:18:30.523Z] TEST TEARDOWN: [2024-08-15T00:18:30.523Z] Nothing to be done for teardown. [2024-08-15T00:18:30.523Z] renaissance-log-regression_0 Finish Time: Thu Aug 15 00:18:29 2024 Epoch Time (ms): 1723681109792