[2024-08-28T21:26:07.188Z] Running test renaissance-log-regression_0 ... [2024-08-28T21:26:07.188Z] =============================================== [2024-08-28T21:26:07.188Z] renaissance-log-regression_0 Start Time: Wed Aug 28 21:26:06 2024 Epoch Time (ms): 1724880366549 [2024-08-28T21:26:07.188Z] variation: NoOptions [2024-08-28T21:26:07.188Z] JVM_OPTIONS: [2024-08-28T21:26:07.188Z] { \ [2024-08-28T21:26:07.188Z] echo ""; echo "TEST SETUP:"; \ [2024-08-28T21:26:07.188Z] echo "Nothing to be done for setup."; \ [2024-08-28T21:26:07.188Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17248796737808/renaissance-log-regression_0"; \ [2024-08-28T21:26:07.188Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17248796737808/renaissance-log-regression_0"; \ [2024-08-28T21:26:07.188Z] echo ""; echo "TESTING:"; \ [2024-08-28T21:26:07.188Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/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/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17248796737808/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-28T21:26:07.188Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17248796737808/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-28T21:26:07.188Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-28T21:26:07.188Z] echo "Nothing to be done for teardown."; \ [2024-08-28T21:26:07.188Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17248796737808/TestTargetResult"; [2024-08-28T21:26:07.188Z] [2024-08-28T21:26:07.188Z] TEST SETUP: [2024-08-28T21:26:07.188Z] Nothing to be done for setup. [2024-08-28T21:26:07.188Z] [2024-08-28T21:26:07.189Z] TESTING: [2024-08-28T21:26:10.184Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-28T21:26:11.124Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-08-28T21:26:14.058Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-28T21:26:14.058Z] GC before operation: completed in 87.990 ms, heap usage 50.340 MB -> 35.188 MB. [2024-08-28T21:26:20.421Z] ====== log-regression (apache-spark) [default], iteration 0 completed (6020.330 ms) ====== [2024-08-28T21:26:20.421Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-28T21:26:20.421Z] GC before operation: completed in 60.205 ms, heap usage 274.900 MB -> 113.261 MB. [2024-08-28T21:26:22.315Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1873.886 ms) ====== [2024-08-28T21:26:22.315Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-28T21:26:22.315Z] GC before operation: completed in 66.954 ms, heap usage 301.194 MB -> 113.905 MB. [2024-08-28T21:26:24.210Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1577.198 ms) ====== [2024-08-28T21:26:24.210Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-28T21:26:24.210Z] GC before operation: completed in 55.791 ms, heap usage 437.068 MB -> 114.635 MB. [2024-08-28T21:26:26.101Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1442.043 ms) ====== [2024-08-28T21:26:26.101Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-28T21:26:26.101Z] GC before operation: completed in 71.622 ms, heap usage 438.797 MB -> 114.875 MB. [2024-08-28T21:26:27.028Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1365.729 ms) ====== [2024-08-28T21:26:27.028Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-28T21:26:27.028Z] GC before operation: completed in 60.258 ms, heap usage 416.538 MB -> 115.068 MB. [2024-08-28T21:26:28.926Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1365.986 ms) ====== [2024-08-28T21:26:28.926Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-28T21:26:28.926Z] GC before operation: completed in 64.434 ms, heap usage 458.515 MB -> 115.436 MB. [2024-08-28T21:26:29.856Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1343.526 ms) ====== [2024-08-28T21:26:29.856Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-28T21:26:29.856Z] GC before operation: completed in 63.961 ms, heap usage 446.598 MB -> 115.585 MB. [2024-08-28T21:26:31.760Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1308.870 ms) ====== [2024-08-28T21:26:31.760Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-28T21:26:31.760Z] GC before operation: completed in 70.320 ms, heap usage 399.096 MB -> 115.780 MB. [2024-08-28T21:26:32.685Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1275.438 ms) ====== [2024-08-28T21:26:32.685Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-28T21:26:32.685Z] GC before operation: completed in 69.548 ms, heap usage 400.774 MB -> 115.836 MB. [2024-08-28T21:26:33.607Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1235.649 ms) ====== [2024-08-28T21:26:33.607Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-28T21:26:33.607Z] GC before operation: completed in 66.306 ms, heap usage 397.667 MB -> 116.141 MB. [2024-08-28T21:26:35.500Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1272.719 ms) ====== [2024-08-28T21:26:35.500Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-28T21:26:35.500Z] GC before operation: completed in 67.689 ms, heap usage 397.715 MB -> 116.270 MB. [2024-08-28T21:26:36.443Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1283.650 ms) ====== [2024-08-28T21:26:36.443Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-28T21:26:36.443Z] GC before operation: completed in 66.749 ms, heap usage 395.663 MB -> 116.454 MB. [2024-08-28T21:26:38.352Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1217.942 ms) ====== [2024-08-28T21:26:38.352Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-28T21:26:38.352Z] GC before operation: completed in 71.758 ms, heap usage 396.448 MB -> 116.678 MB. [2024-08-28T21:26:39.272Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1233.859 ms) ====== [2024-08-28T21:26:39.272Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-28T21:26:39.272Z] GC before operation: completed in 70.456 ms, heap usage 386.841 MB -> 116.811 MB. [2024-08-28T21:26:41.410Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1250.362 ms) ====== [2024-08-28T21:26:41.410Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-28T21:26:41.410Z] GC before operation: completed in 77.678 ms, heap usage 377.188 MB -> 116.975 MB. [2024-08-28T21:26:42.330Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1501.662 ms) ====== [2024-08-28T21:26:42.330Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-28T21:26:42.330Z] GC before operation: completed in 75.209 ms, heap usage 313.499 MB -> 117.097 MB. [2024-08-28T21:26:43.250Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1203.298 ms) ====== [2024-08-28T21:26:43.250Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-28T21:26:43.250Z] GC before operation: completed in 68.094 ms, heap usage 351.261 MB -> 117.354 MB. [2024-08-28T21:26:44.171Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1177.594 ms) ====== [2024-08-28T21:26:44.171Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-28T21:26:45.091Z] GC before operation: completed in 71.940 ms, heap usage 527.575 MB -> 117.988 MB. [2024-08-28T21:26:46.014Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1227.465 ms) ====== [2024-08-28T21:26:46.014Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-28T21:26:46.014Z] GC before operation: completed in 80.822 ms, heap usage 408.221 MB -> 117.857 MB. [2024-08-28T21:26:46.935Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1260.796 ms) ====== [2024-08-28T21:26:47.854Z] ----------------------------------- [2024-08-28T21:26:47.854Z] renaissance-log-regression_0_PASSED [2024-08-28T21:26:47.854Z] ----------------------------------- [2024-08-28T21:26:47.854Z] [2024-08-28T21:26:47.854Z] TEST TEARDOWN: [2024-08-28T21:26:47.854Z] Nothing to be done for teardown. [2024-08-28T21:26:47.854Z] renaissance-log-regression_0 Finish Time: Wed Aug 28 21:26:46 2024 Epoch Time (ms): 1724880406959