[2024-09-05T07:37:36.778Z] Running test renaissance-log-regression_0 ... [2024-09-05T07:37:36.778Z] =============================================== [2024-09-05T07:37:36.778Z] renaissance-log-regression_0 Start Time: Thu Sep 5 07:37:36 2024 Epoch Time (ms): 1725521856173 [2024-09-05T07:37:36.778Z] variation: NoOptions [2024-09-05T07:37:36.778Z] JVM_OPTIONS: [2024-09-05T07:37:36.778Z] { \ [2024-09-05T07:37:36.778Z] echo ""; echo "TEST SETUP:"; \ [2024-09-05T07:37:36.778Z] echo "Nothing to be done for setup."; \ [2024-09-05T07:37:36.778Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17255212989108/renaissance-log-regression_0"; \ [2024-09-05T07:37:36.778Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17255212989108/renaissance-log-regression_0"; \ [2024-09-05T07:37:36.778Z] echo ""; echo "TESTING:"; \ [2024-09-05T07:37:36.778Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-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_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17255212989108/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-09-05T07:37:36.778Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17255212989108/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-05T07:37:36.778Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-05T07:37:36.778Z] echo "Nothing to be done for teardown."; \ [2024-09-05T07:37:36.778Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17255212989108/TestTargetResult"; [2024-09-05T07:37:36.778Z] [2024-09-05T07:37:36.778Z] TEST SETUP: [2024-09-05T07:37:36.778Z] Nothing to be done for setup. [2024-09-05T07:37:36.778Z] [2024-09-05T07:37:36.778Z] TESTING: [2024-09-05T07:37:39.348Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-05T07:37:41.011Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-09-05T07:37:44.593Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-09-05T07:37:44.593Z] GC before operation: completed in 50.778 ms, heap usage 59.922 MB -> 35.088 MB. [2024-09-05T07:37:51.733Z] ====== log-regression (apache-spark) [default], iteration 0 completed (7085.889 ms) ====== [2024-09-05T07:37:51.733Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-09-05T07:37:51.733Z] GC before operation: completed in 71.578 ms, heap usage 324.453 MB -> 113.185 MB. [2024-09-05T07:37:53.397Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2137.153 ms) ====== [2024-09-05T07:37:53.397Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-09-05T07:37:53.397Z] GC before operation: completed in 95.723 ms, heap usage 358.982 MB -> 113.888 MB. [2024-09-05T07:37:55.072Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1841.673 ms) ====== [2024-09-05T07:37:55.072Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-09-05T07:37:55.072Z] GC before operation: completed in 56.396 ms, heap usage 332.290 MB -> 114.254 MB. [2024-09-05T07:37:56.740Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1593.798 ms) ====== [2024-09-05T07:37:56.740Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-09-05T07:37:56.740Z] GC before operation: completed in 62.912 ms, heap usage 325.750 MB -> 114.535 MB. [2024-09-05T07:37:58.407Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1489.355 ms) ====== [2024-09-05T07:37:58.407Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-09-05T07:37:58.407Z] GC before operation: completed in 76.939 ms, heap usage 329.379 MB -> 114.791 MB. [2024-09-05T07:38:00.076Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1311.961 ms) ====== [2024-09-05T07:38:00.076Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-09-05T07:38:00.076Z] GC before operation: completed in 66.797 ms, heap usage 323.633 MB -> 114.865 MB. [2024-09-05T07:38:01.756Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1291.455 ms) ====== [2024-09-05T07:38:01.756Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-09-05T07:38:01.756Z] GC before operation: completed in 62.791 ms, heap usage 316.465 MB -> 115.096 MB. [2024-09-05T07:38:02.561Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1322.273 ms) ====== [2024-09-05T07:38:02.561Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-09-05T07:38:02.561Z] GC before operation: completed in 63.291 ms, heap usage 316.823 MB -> 115.277 MB. [2024-09-05T07:38:04.231Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1273.419 ms) ====== [2024-09-05T07:38:04.231Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-09-05T07:38:04.231Z] GC before operation: completed in 66.750 ms, heap usage 320.004 MB -> 115.569 MB. [2024-09-05T07:38:05.037Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1178.582 ms) ====== [2024-09-05T07:38:05.037Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-09-05T07:38:05.037Z] GC before operation: completed in 66.989 ms, heap usage 301.403 MB -> 115.630 MB. [2024-09-05T07:38:06.730Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1182.505 ms) ====== [2024-09-05T07:38:06.730Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-09-05T07:38:06.730Z] GC before operation: completed in 65.756 ms, heap usage 286.507 MB -> 115.889 MB. [2024-09-05T07:38:07.540Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1187.620 ms) ====== [2024-09-05T07:38:07.540Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-09-05T07:38:08.356Z] GC before operation: completed in 99.733 ms, heap usage 247.797 MB -> 116.032 MB. [2024-09-05T07:38:09.168Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1175.880 ms) ====== [2024-09-05T07:38:09.169Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-09-05T07:38:09.169Z] GC before operation: completed in 70.283 ms, heap usage 400.426 MB -> 116.580 MB. [2024-09-05T07:38:10.837Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1205.040 ms) ====== [2024-09-05T07:38:10.837Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-09-05T07:38:10.837Z] GC before operation: completed in 71.197 ms, heap usage 257.774 MB -> 116.399 MB. [2024-09-05T07:38:11.645Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1153.116 ms) ====== [2024-09-05T07:38:11.645Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-09-05T07:38:11.645Z] GC before operation: completed in 90.606 ms, heap usage 393.762 MB -> 116.993 MB. [2024-09-05T07:38:12.452Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1119.690 ms) ====== [2024-09-05T07:38:12.452Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-09-05T07:38:13.259Z] GC before operation: completed in 75.000 ms, heap usage 238.824 MB -> 116.746 MB. [2024-09-05T07:38:14.066Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1086.453 ms) ====== [2024-09-05T07:38:14.066Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-09-05T07:38:14.066Z] GC before operation: completed in 78.813 ms, heap usage 383.991 MB -> 117.292 MB. [2024-09-05T07:38:14.877Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1163.331 ms) ====== [2024-09-05T07:38:14.877Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-09-05T07:38:15.686Z] GC before operation: completed in 75.813 ms, heap usage 307.881 MB -> 117.289 MB. [2024-09-05T07:38:16.495Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1084.926 ms) ====== [2024-09-05T07:38:16.496Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-09-05T07:38:16.496Z] GC before operation: completed in 69.966 ms, heap usage 247.618 MB -> 117.386 MB. [2024-09-05T07:38:17.299Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1109.366 ms) ====== [2024-09-05T07:38:18.107Z] ----------------------------------- [2024-09-05T07:38:18.107Z] renaissance-log-regression_0_PASSED [2024-09-05T07:38:18.107Z] ----------------------------------- [2024-09-05T07:38:18.107Z] [2024-09-05T07:38:18.107Z] TEST TEARDOWN: [2024-09-05T07:38:18.107Z] Nothing to be done for teardown. [2024-09-05T07:38:18.107Z] renaissance-log-regression_0 Finish Time: Thu Sep 5 07:38:17 2024 Epoch Time (ms): 1725521897340