[2024-06-27T00:03:41.596Z] Running test renaissance-log-regression_0 ... [2024-06-27T00:03:41.596Z] =============================================== [2024-06-27T00:03:41.596Z] renaissance-log-regression_0 Start Time: Thu Jun 27 00:03:41 2024 Epoch Time (ms): 1719446621114 [2024-06-27T00:03:41.596Z] variation: NoOptions [2024-06-27T00:03:41.596Z] JVM_OPTIONS: [2024-06-27T00:03:41.596Z] { \ [2024-06-27T00:03:41.596Z] echo ""; echo "TEST SETUP:"; \ [2024-06-27T00:03:41.596Z] echo "Nothing to be done for setup."; \ [2024-06-27T00:03:41.596Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17194459234443/renaissance-log-regression_0"; \ [2024-06-27T00:03:41.596Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17194459234443/renaissance-log-regression_0"; \ [2024-06-27T00:03:41.596Z] echo ""; echo "TESTING:"; \ [2024-06-27T00:03:41.596Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_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_ppc64le_linux_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17194459234443/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-06-27T00:03:41.596Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17194459234443/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-06-27T00:03:41.596Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-06-27T00:03:41.596Z] echo "Nothing to be done for teardown."; \ [2024-06-27T00:03:41.596Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17194459234443/TestTargetResult"; [2024-06-27T00:03:41.596Z] [2024-06-27T00:03:41.596Z] TEST SETUP: [2024-06-27T00:03:41.596Z] Nothing to be done for setup. [2024-06-27T00:03:41.596Z] [2024-06-27T00:03:41.596Z] TESTING: [2024-06-27T00:03:44.547Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-06-27T00:03:46.460Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-06-27T00:03:49.437Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-06-27T00:03:49.437Z] GC before operation: completed in 59.776 ms, heap usage 41.408 MB -> 35.177 MB. [2024-06-27T00:03:56.118Z] ====== log-regression (apache-spark) [default], iteration 0 completed (6411.317 ms) ====== [2024-06-27T00:03:56.118Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-06-27T00:03:56.118Z] GC before operation: completed in 71.340 ms, heap usage 294.374 MB -> 113.331 MB. [2024-06-27T00:03:58.036Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1771.808 ms) ====== [2024-06-27T00:03:58.036Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-06-27T00:03:58.036Z] GC before operation: completed in 63.962 ms, heap usage 279.001 MB -> 113.921 MB. [2024-06-27T00:03:58.969Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1665.253 ms) ====== [2024-06-27T00:03:58.969Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-06-27T00:03:58.969Z] GC before operation: completed in 61.483 ms, heap usage 438.320 MB -> 114.644 MB. [2024-06-27T00:04:00.886Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1423.820 ms) ====== [2024-06-27T00:04:00.886Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-06-27T00:04:00.886Z] GC before operation: completed in 72.297 ms, heap usage 389.878 MB -> 114.815 MB. [2024-06-27T00:04:01.819Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1445.447 ms) ====== [2024-06-27T00:04:01.819Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-06-27T00:04:02.757Z] GC before operation: completed in 70.054 ms, heap usage 419.146 MB -> 115.095 MB. [2024-06-27T00:04:03.690Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1399.171 ms) ====== [2024-06-27T00:04:03.690Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-06-27T00:04:03.690Z] GC before operation: completed in 68.409 ms, heap usage 356.268 MB -> 115.173 MB. [2024-06-27T00:04:06.334Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1346.957 ms) ====== [2024-06-27T00:04:06.334Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-06-27T00:04:06.334Z] GC before operation: completed in 73.508 ms, heap usage 532.835 MB -> 115.827 MB. [2024-06-27T00:04:06.334Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1286.712 ms) ====== [2024-06-27T00:04:06.334Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-06-27T00:04:06.334Z] GC before operation: completed in 72.449 ms, heap usage 401.657 MB -> 115.708 MB. [2024-06-27T00:04:08.251Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1244.945 ms) ====== [2024-06-27T00:04:08.251Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-06-27T00:04:08.251Z] GC before operation: completed in 65.825 ms, heap usage 400.281 MB -> 115.860 MB. [2024-06-27T00:04:09.184Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1248.080 ms) ====== [2024-06-27T00:04:09.184Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-06-27T00:04:09.184Z] GC before operation: completed in 70.515 ms, heap usage 396.191 MB -> 116.110 MB. [2024-06-27T00:04:10.115Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1193.492 ms) ====== [2024-06-27T00:04:10.115Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-06-27T00:04:10.115Z] GC before operation: completed in 75.143 ms, heap usage 284.601 MB -> 115.905 MB. [2024-06-27T00:04:12.030Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1204.132 ms) ====== [2024-06-27T00:04:12.030Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-06-27T00:04:12.030Z] GC before operation: completed in 78.536 ms, heap usage 339.730 MB -> 116.349 MB. [2024-06-27T00:04:12.961Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1175.107 ms) ====== [2024-06-27T00:04:12.961Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-06-27T00:04:12.961Z] GC before operation: completed in 71.236 ms, heap usage 313.741 MB -> 116.429 MB. [2024-06-27T00:04:13.893Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1217.713 ms) ====== [2024-06-27T00:04:13.893Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-06-27T00:04:13.893Z] GC before operation: completed in 77.601 ms, heap usage 331.705 MB -> 116.737 MB. [2024-06-27T00:04:15.810Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1192.661 ms) ====== [2024-06-27T00:04:15.810Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-06-27T00:04:15.810Z] GC before operation: completed in 76.368 ms, heap usage 320.579 MB -> 116.889 MB. [2024-06-27T00:04:16.742Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1236.078 ms) ====== [2024-06-27T00:04:16.742Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-06-27T00:04:16.742Z] GC before operation: completed in 109.529 ms, heap usage 312.936 MB -> 117.056 MB. [2024-06-27T00:04:18.661Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1250.796 ms) ====== [2024-06-27T00:04:18.661Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-06-27T00:04:18.661Z] GC before operation: completed in 87.868 ms, heap usage 284.381 MB -> 117.229 MB. [2024-06-27T00:04:19.596Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1192.612 ms) ====== [2024-06-27T00:04:19.596Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-06-27T00:04:19.596Z] GC before operation: completed in 91.156 ms, heap usage 521.892 MB -> 117.985 MB. [2024-06-27T00:04:20.529Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1200.310 ms) ====== [2024-06-27T00:04:20.529Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-06-27T00:04:20.529Z] GC before operation: completed in 93.554 ms, heap usage 370.046 MB -> 117.809 MB. [2024-06-27T00:04:22.445Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1222.181 ms) ====== [2024-06-27T00:04:22.445Z] ----------------------------------- [2024-06-27T00:04:22.445Z] renaissance-log-regression_0_PASSED [2024-06-27T00:04:22.445Z] ----------------------------------- [2024-06-27T00:04:22.445Z] [2024-06-27T00:04:22.445Z] TEST TEARDOWN: [2024-06-27T00:04:22.445Z] Nothing to be done for teardown. [2024-06-27T00:04:22.445Z] renaissance-log-regression_0 Finish Time: Thu Jun 27 00:04:21 2024 Epoch Time (ms): 1719446661780