[2024-12-05T02:11:07.617Z] Running test renaissance-log-regression_0 ... [2024-12-05T02:11:07.617Z] =============================================== [2024-12-05T02:11:07.617Z] renaissance-log-regression_0 Start Time: Thu Dec 5 02:11:07 2024 Epoch Time (ms): 1733364667438 [2024-12-05T02:11:07.617Z] variation: NoOptions [2024-12-05T02:11:07.617Z] JVM_OPTIONS: [2024-12-05T02:11:07.617Z] { \ [2024-12-05T02:11:07.617Z] echo ""; echo "TEST SETUP:"; \ [2024-12-05T02:11:07.617Z] echo "Nothing to be done for setup."; \ [2024-12-05T02:11:07.617Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17333639874731/renaissance-log-regression_0"; \ [2024-12-05T02:11:07.617Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17333639874731/renaissance-log-regression_0"; \ [2024-12-05T02:11:07.617Z] echo ""; echo "TESTING:"; \ [2024-12-05T02:11:07.617Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/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_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17333639874731/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-12-05T02:11:07.617Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17333639874731/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-12-05T02:11:07.617Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-12-05T02:11:07.617Z] echo "Nothing to be done for teardown."; \ [2024-12-05T02:11:07.617Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17333639874731/TestTargetResult"; [2024-12-05T02:11:07.617Z] [2024-12-05T02:11:07.617Z] TEST SETUP: [2024-12-05T02:11:07.617Z] Nothing to be done for setup. [2024-12-05T02:11:07.617Z] [2024-12-05T02:11:07.617Z] TESTING: [2024-12-05T02:11:12.368Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-12-05T02:11:14.857Z] NOTE: 'log-regression' benchmark uses Spark local executor with 12 (out of 24) threads. [2024-12-05T02:11:19.327Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-12-05T02:11:19.327Z] GC before operation: completed in 68.504 ms, heap usage 38.340 MB -> 35.387 MB. [2024-12-05T02:11:26.220Z] ====== log-regression (apache-spark) [default], iteration 0 completed (7428.996 ms) ====== [2024-12-05T02:11:26.220Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-12-05T02:11:26.220Z] GC before operation: completed in 95.564 ms, heap usage 642.610 MB -> 126.372 MB. [2024-12-05T02:11:27.813Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1807.179 ms) ====== [2024-12-05T02:11:27.813Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-12-05T02:11:28.587Z] GC before operation: completed in 91.721 ms, heap usage 285.975 MB -> 126.365 MB. [2024-12-05T02:11:30.180Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1567.082 ms) ====== [2024-12-05T02:11:30.180Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-12-05T02:11:30.180Z] GC before operation: completed in 90.263 ms, heap usage 579.513 MB -> 127.397 MB. [2024-12-05T02:11:31.773Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1505.826 ms) ====== [2024-12-05T02:11:31.773Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-12-05T02:11:31.773Z] GC before operation: completed in 87.232 ms, heap usage 253.522 MB -> 127.007 MB. [2024-12-05T02:11:32.548Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1436.785 ms) ====== [2024-12-05T02:11:32.548Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-12-05T02:11:33.320Z] GC before operation: completed in 92.347 ms, heap usage 674.607 MB -> 128.339 MB. [2024-12-05T02:11:34.098Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1468.167 ms) ====== [2024-12-05T02:11:34.098Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-12-05T02:11:34.869Z] GC before operation: completed in 102.310 ms, heap usage 221.696 MB -> 127.741 MB. [2024-12-05T02:11:35.642Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1410.964 ms) ====== [2024-12-05T02:11:35.642Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-12-05T02:11:36.415Z] GC before operation: completed in 93.319 ms, heap usage 790.974 MB -> 129.266 MB. [2024-12-05T02:11:37.187Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1426.125 ms) ====== [2024-12-05T02:11:37.187Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-12-05T02:11:37.958Z] GC before operation: completed in 90.548 ms, heap usage 350.130 MB -> 128.673 MB. [2024-12-05T02:11:38.729Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1379.033 ms) ====== [2024-12-05T02:11:38.729Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-12-05T02:11:38.729Z] GC before operation: completed in 99.833 ms, heap usage 487.317 MB -> 129.334 MB. [2024-12-05T02:11:40.329Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1358.551 ms) ====== [2024-12-05T02:11:40.330Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-12-05T02:11:40.330Z] GC before operation: completed in 99.630 ms, heap usage 379.648 MB -> 129.483 MB. [2024-12-05T02:11:41.926Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1362.886 ms) ====== [2024-12-05T02:11:41.926Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-12-05T02:11:41.926Z] GC before operation: completed in 99.465 ms, heap usage 668.461 MB -> 130.508 MB. [2024-12-05T02:11:43.528Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1353.470 ms) ====== [2024-12-05T02:11:43.528Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-12-05T02:11:43.528Z] GC before operation: completed in 101.875 ms, heap usage 496.630 MB -> 130.526 MB. [2024-12-05T02:11:45.122Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1298.991 ms) ====== [2024-12-05T02:11:45.122Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-12-05T02:11:45.122Z] GC before operation: completed in 99.597 ms, heap usage 489.186 MB -> 130.728 MB. [2024-12-05T02:11:45.893Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1302.026 ms) ====== [2024-12-05T02:11:45.893Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-12-05T02:11:45.893Z] GC before operation: completed in 101.719 ms, heap usage 272.782 MB -> 130.666 MB. [2024-12-05T02:11:47.511Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1285.388 ms) ====== [2024-12-05T02:11:47.511Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-12-05T02:11:47.511Z] GC before operation: completed in 103.535 ms, heap usage 274.714 MB -> 131.018 MB. [2024-12-05T02:11:49.109Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1303.991 ms) ====== [2024-12-05T02:11:49.109Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-12-05T02:11:49.109Z] GC before operation: completed in 108.672 ms, heap usage 564.381 MB -> 132.032 MB. [2024-12-05T02:11:50.706Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1358.355 ms) ====== [2024-12-05T02:11:50.706Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-12-05T02:11:50.706Z] GC before operation: completed in 107.122 ms, heap usage 638.443 MB -> 132.604 MB. [2024-12-05T02:11:51.481Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1273.981 ms) ====== [2024-12-05T02:11:51.481Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-12-05T02:11:52.251Z] GC before operation: completed in 102.899 ms, heap usage 318.260 MB -> 132.248 MB. [2024-12-05T02:11:53.025Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1301.195 ms) ====== [2024-12-05T02:11:53.025Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-12-05T02:11:53.025Z] GC before operation: completed in 108.615 ms, heap usage 590.735 MB -> 133.210 MB. [2024-12-05T02:11:54.618Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1344.935 ms) ====== [2024-12-05T02:11:55.391Z] ----------------------------------- [2024-12-05T02:11:55.391Z] renaissance-log-regression_0_PASSED [2024-12-05T02:11:55.391Z] ----------------------------------- [2024-12-05T02:11:55.391Z] [2024-12-05T02:11:55.391Z] TEST TEARDOWN: [2024-12-05T02:11:55.391Z] Nothing to be done for teardown. [2024-12-05T02:11:55.391Z] renaissance-log-regression_0 Finish Time: Thu Dec 5 02:11:54 2024 Epoch Time (ms): 1733364714658