[2024-05-30T06:05:59.930Z] Running test renaissance-log-regression_0 ... [2024-05-30T06:05:59.930Z] =============================================== [2024-05-30T06:05:59.930Z] renaissance-log-regression_0 Start Time: Thu May 30 06:05:59 2024 Epoch Time (ms): 1717049159415 [2024-05-30T06:05:59.930Z] variation: NoOptions [2024-05-30T06:05:59.930Z] JVM_OPTIONS: [2024-05-30T06:05:59.930Z] { \ [2024-05-30T06:05:59.930Z] echo ""; echo "TEST SETUP:"; \ [2024-05-30T06:05:59.930Z] echo "Nothing to be done for setup."; \ [2024-05-30T06:05:59.930Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix_testList_0/aqa-tests/TKG/../TKG/output_17170484932554/renaissance-log-regression_0"; \ [2024-05-30T06:05:59.930Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix_testList_0/aqa-tests/TKG/../TKG/output_17170484932554/renaissance-log-regression_0"; \ [2024-05-30T06:05:59.930Z] echo ""; echo "TESTING:"; \ [2024-05-30T06:05:59.930Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix_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_ppc64_aix_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix_testList_0/aqa-tests/TKG/../TKG/output_17170484932554/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-05-30T06:05:59.930Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix_testList_0/aqa-tests/TKG/../TKG/output_17170484932554/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-05-30T06:05:59.930Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-05-30T06:05:59.930Z] echo "Nothing to be done for teardown."; \ [2024-05-30T06:05:59.930Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64_aix_testList_0/aqa-tests/TKG/../TKG/output_17170484932554/TestTargetResult"; [2024-05-30T06:05:59.930Z] [2024-05-30T06:05:59.930Z] TEST SETUP: [2024-05-30T06:05:59.930Z] Nothing to be done for setup. [2024-05-30T06:05:59.930Z] [2024-05-30T06:05:59.930Z] TESTING: [2024-05-30T06:06:04.338Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-05-30T06:06:05.907Z] NOTE: 'log-regression' benchmark uses Spark local executor with 12 (out of 24) threads. [2024-05-30T06:06:10.356Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-05-30T06:06:10.356Z] GC before operation: completed in 72.185 ms, heap usage 86.649 MB -> 35.413 MB. [2024-05-30T06:06:17.154Z] ====== log-regression (apache-spark) [default], iteration 0 completed (7110.443 ms) ====== [2024-05-30T06:06:17.154Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-05-30T06:06:17.154Z] GC before operation: completed in 98.866 ms, heap usage 424.600 MB -> 126.163 MB. [2024-05-30T06:06:18.722Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1724.491 ms) ====== [2024-05-30T06:06:18.722Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-05-30T06:06:19.481Z] GC before operation: completed in 83.403 ms, heap usage 280.715 MB -> 126.450 MB. [2024-05-30T06:06:21.054Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1505.787 ms) ====== [2024-05-30T06:06:21.054Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-05-30T06:06:21.054Z] GC before operation: completed in 86.004 ms, heap usage 687.456 MB -> 127.742 MB. [2024-05-30T06:06:21.815Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1466.849 ms) ====== [2024-05-30T06:06:21.815Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-05-30T06:06:22.585Z] GC before operation: completed in 93.196 ms, heap usage 412.881 MB -> 127.657 MB. [2024-05-30T06:06:23.343Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1418.543 ms) ====== [2024-05-30T06:06:23.343Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-05-30T06:06:24.105Z] GC before operation: completed in 92.306 ms, heap usage 583.447 MB -> 128.326 MB. [2024-05-30T06:06:24.863Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1364.031 ms) ====== [2024-05-30T06:06:24.863Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-05-30T06:06:24.863Z] GC before operation: completed in 88.279 ms, heap usage 380.439 MB -> 128.244 MB. [2024-05-30T06:06:26.432Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1332.685 ms) ====== [2024-05-30T06:06:26.432Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-05-30T06:06:26.432Z] GC before operation: completed in 93.945 ms, heap usage 490.215 MB -> 128.806 MB. [2024-05-30T06:06:28.012Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1342.071 ms) ====== [2024-05-30T06:06:28.012Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-05-30T06:06:28.012Z] GC before operation: completed in 105.170 ms, heap usage 365.597 MB -> 128.998 MB. [2024-05-30T06:06:29.576Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1305.016 ms) ====== [2024-05-30T06:06:29.576Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-05-30T06:06:29.576Z] GC before operation: completed in 95.865 ms, heap usage 653.298 MB -> 129.925 MB. [2024-05-30T06:06:31.143Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1300.445 ms) ====== [2024-05-30T06:06:31.143Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-05-30T06:06:31.143Z] GC before operation: completed in 111.903 ms, heap usage 407.269 MB -> 129.778 MB. [2024-05-30T06:06:31.935Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1279.129 ms) ====== [2024-05-30T06:06:31.935Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-05-30T06:06:32.689Z] GC before operation: completed in 96.222 ms, heap usage 649.662 MB -> 130.640 MB. [2024-05-30T06:06:33.445Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1312.970 ms) ====== [2024-05-30T06:06:33.445Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-05-30T06:06:33.445Z] GC before operation: completed in 104.256 ms, heap usage 453.279 MB -> 130.617 MB. [2024-05-30T06:06:35.010Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1248.638 ms) ====== [2024-05-30T06:06:35.010Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-05-30T06:06:35.010Z] GC before operation: completed in 98.628 ms, heap usage 626.011 MB -> 131.334 MB. [2024-05-30T06:06:36.575Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1250.276 ms) ====== [2024-05-30T06:06:36.575Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-05-30T06:06:36.575Z] GC before operation: completed in 97.212 ms, heap usage 397.390 MB -> 131.122 MB. [2024-05-30T06:06:37.333Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1236.121 ms) ====== [2024-05-30T06:06:37.333Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-05-30T06:06:38.096Z] GC before operation: completed in 98.365 ms, heap usage 679.451 MB -> 132.191 MB. [2024-05-30T06:06:38.863Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1289.707 ms) ====== [2024-05-30T06:06:38.863Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-05-30T06:06:38.863Z] GC before operation: completed in 93.983 ms, heap usage 452.081 MB -> 132.014 MB. [2024-05-30T06:06:40.433Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1243.133 ms) ====== [2024-05-30T06:06:40.433Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-05-30T06:06:40.433Z] GC before operation: completed in 103.277 ms, heap usage 678.869 MB -> 132.892 MB. [2024-05-30T06:06:41.999Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1202.411 ms) ====== [2024-05-30T06:06:41.999Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-05-30T06:06:41.999Z] GC before operation: completed in 106.108 ms, heap usage 364.752 MB -> 132.626 MB. [2024-05-30T06:06:42.760Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1240.493 ms) ====== [2024-05-30T06:06:42.761Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-05-30T06:06:43.522Z] GC before operation: completed in 104.087 ms, heap usage 609.121 MB -> 133.502 MB. [2024-05-30T06:06:44.282Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1247.289 ms) ====== [2024-05-30T06:06:45.040Z] ----------------------------------- [2024-05-30T06:06:45.040Z] renaissance-log-regression_0_PASSED [2024-05-30T06:06:45.040Z] ----------------------------------- [2024-05-30T06:06:45.040Z] [2024-05-30T06:06:45.040Z] TEST TEARDOWN: [2024-05-30T06:06:45.040Z] Nothing to be done for teardown. [2024-05-30T06:06:45.040Z] renaissance-log-regression_0 Finish Time: Thu May 30 06:06:44 2024 Epoch Time (ms): 1717049204304