[2024-08-14T20:32:41.330Z] Running test renaissance-log-regression_0 ... [2024-08-14T20:32:41.330Z] =============================================== [2024-08-14T20:32:41.330Z] renaissance-log-regression_0 Start Time: Wed Aug 14 20:32:40 2024 Epoch Time (ms): 1723667560704 [2024-08-14T20:32:41.330Z] variation: NoOptions [2024-08-14T20:32:41.330Z] JVM_OPTIONS: [2024-08-14T20:32:41.330Z] { \ [2024-08-14T20:32:41.330Z] echo ""; echo "TEST SETUP:"; \ [2024-08-14T20:32:41.330Z] echo "Nothing to be done for setup."; \ [2024-08-14T20:32:41.330Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17236668707009/renaissance-log-regression_0"; \ [2024-08-14T20:32:41.331Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17236668707009/renaissance-log-regression_0"; \ [2024-08-14T20:32:41.331Z] echo ""; echo "TESTING:"; \ [2024-08-14T20:32:41.331Z] "/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_17236668707009/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-14T20:32:41.331Z] 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_17236668707009/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-14T20:32:41.331Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-14T20:32:41.331Z] echo "Nothing to be done for teardown."; \ [2024-08-14T20:32:41.331Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux_testList_0/aqa-tests/TKG/../TKG/output_17236668707009/TestTargetResult"; [2024-08-14T20:32:41.331Z] [2024-08-14T20:32:41.331Z] TEST SETUP: [2024-08-14T20:32:41.331Z] Nothing to be done for setup. [2024-08-14T20:32:41.331Z] [2024-08-14T20:32:41.331Z] TESTING: [2024-08-14T20:32:44.388Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-14T20:32:45.318Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-08-14T20:32:48.270Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-14T20:32:48.270Z] GC before operation: completed in 52.338 ms, heap usage 96.193 MB -> 35.216 MB. [2024-08-14T20:32:54.841Z] ====== log-regression (apache-spark) [default], iteration 0 completed (6352.613 ms) ====== [2024-08-14T20:32:54.842Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-14T20:32:54.842Z] GC before operation: completed in 81.511 ms, heap usage 295.012 MB -> 113.341 MB. [2024-08-14T20:32:56.753Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1741.109 ms) ====== [2024-08-14T20:32:56.753Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-14T20:32:56.753Z] GC before operation: completed in 60.759 ms, heap usage 447.503 MB -> 114.262 MB. [2024-08-14T20:32:58.665Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1559.544 ms) ====== [2024-08-14T20:32:58.665Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-14T20:32:58.665Z] GC before operation: completed in 74.245 ms, heap usage 427.003 MB -> 114.714 MB. [2024-08-14T20:32:59.596Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1390.435 ms) ====== [2024-08-14T20:32:59.596Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-14T20:32:59.596Z] GC before operation: completed in 63.583 ms, heap usage 430.457 MB -> 114.977 MB. [2024-08-14T20:33:01.505Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1295.906 ms) ====== [2024-08-14T20:33:01.505Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-14T20:33:01.505Z] GC before operation: completed in 68.291 ms, heap usage 417.380 MB -> 115.103 MB. [2024-08-14T20:33:02.437Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1398.347 ms) ====== [2024-08-14T20:33:02.437Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-14T20:33:02.437Z] GC before operation: completed in 77.434 ms, heap usage 459.852 MB -> 115.531 MB. [2024-08-14T20:33:04.347Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1403.078 ms) ====== [2024-08-14T20:33:04.347Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-14T20:33:04.347Z] GC before operation: completed in 75.154 ms, heap usage 514.026 MB -> 115.828 MB. [2024-08-14T20:33:05.278Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1308.800 ms) ====== [2024-08-14T20:33:05.278Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-14T20:33:05.278Z] GC before operation: completed in 83.884 ms, heap usage 400.322 MB -> 115.706 MB. [2024-08-14T20:33:07.252Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1289.353 ms) ====== [2024-08-14T20:33:07.252Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-14T20:33:07.252Z] GC before operation: completed in 72.573 ms, heap usage 361.198 MB -> 115.878 MB. [2024-08-14T20:33:08.181Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1299.532 ms) ====== [2024-08-14T20:33:08.181Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-14T20:33:08.181Z] GC before operation: completed in 69.273 ms, heap usage 371.041 MB -> 116.019 MB. [2024-08-14T20:33:10.093Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1242.206 ms) ====== [2024-08-14T20:33:10.093Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-14T20:33:10.093Z] GC before operation: completed in 82.443 ms, heap usage 406.285 MB -> 116.354 MB. [2024-08-14T20:33:11.026Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1251.611 ms) ====== [2024-08-14T20:33:11.026Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-14T20:33:11.026Z] GC before operation: completed in 93.235 ms, heap usage 415.593 MB -> 116.577 MB. [2024-08-14T20:33:11.956Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1210.365 ms) ====== [2024-08-14T20:33:11.956Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-14T20:33:12.886Z] GC before operation: completed in 94.580 ms, heap usage 396.141 MB -> 116.730 MB. [2024-08-14T20:33:13.816Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1183.308 ms) ====== [2024-08-14T20:33:13.816Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-14T20:33:13.816Z] GC before operation: completed in 83.521 ms, heap usage 376.721 MB -> 116.848 MB. [2024-08-14T20:33:14.745Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1165.757 ms) ====== [2024-08-14T20:33:14.745Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-14T20:33:14.745Z] GC before operation: completed in 74.066 ms, heap usage 349.577 MB -> 116.966 MB. [2024-08-14T20:33:15.674Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1197.084 ms) ====== [2024-08-14T20:33:15.674Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-14T20:33:16.605Z] GC before operation: completed in 75.748 ms, heap usage 512.798 MB -> 117.592 MB. [2024-08-14T20:33:17.535Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1207.468 ms) ====== [2024-08-14T20:33:17.535Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-14T20:33:18.465Z] GC before operation: completed in 868.957 ms, heap usage 408.114 MB -> 117.610 MB. [2024-08-14T20:33:19.394Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1135.676 ms) ====== [2024-08-14T20:33:19.394Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-14T20:33:19.394Z] GC before operation: completed in 75.435 ms, heap usage 397.045 MB -> 117.750 MB. [2024-08-14T20:33:21.300Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2135.954 ms) ====== [2024-08-14T20:33:21.300Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-14T20:33:21.300Z] GC before operation: completed in 88.665 ms, heap usage 489.639 MB -> 118.189 MB. [2024-08-14T20:33:23.212Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1237.086 ms) ====== [2024-08-14T20:33:23.212Z] ----------------------------------- [2024-08-14T20:33:23.212Z] renaissance-log-regression_0_PASSED [2024-08-14T20:33:23.212Z] ----------------------------------- [2024-08-14T20:33:23.212Z] [2024-08-14T20:33:23.212Z] TEST TEARDOWN: [2024-08-14T20:33:23.212Z] Nothing to be done for teardown. [2024-08-14T20:33:23.212Z] renaissance-log-regression_0 Finish Time: Wed Aug 14 20:33:22 2024 Epoch Time (ms): 1723667602647