[2024-08-10T10:13:13.638Z] Running test renaissance-log-regression_0 ... [2024-08-10T10:13:13.638Z] =============================================== [2024-08-10T10:13:13.638Z] renaissance-log-regression_0 Start Time: Sat Aug 10 10:13:13 2024 Epoch Time (ms): 1723284793026 [2024-08-10T10:13:13.638Z] variation: NoOptions [2024-08-10T10:13:13.638Z] JVM_OPTIONS: [2024-08-10T10:13:13.638Z] { \ [2024-08-10T10:13:13.638Z] echo ""; echo "TEST SETUP:"; \ [2024-08-10T10:13:13.638Z] echo "Nothing to be done for setup."; \ [2024-08-10T10:13:13.638Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17232836225986/renaissance-log-regression_0"; \ [2024-08-10T10:13:13.638Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17232836225986/renaissance-log-regression_0"; \ [2024-08-10T10:13:13.638Z] echo ""; echo "TESTING:"; \ [2024-08-10T10:13:13.638Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-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_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17232836225986/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-10T10:13:13.638Z] 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_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17232836225986/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-10T10:13:13.638Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-10T10:13:13.638Z] echo "Nothing to be done for teardown."; \ [2024-08-10T10:13:13.638Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17232836225986/TestTargetResult"; [2024-08-10T10:13:13.638Z] [2024-08-10T10:13:13.638Z] TEST SETUP: [2024-08-10T10:13:13.638Z] Nothing to be done for setup. [2024-08-10T10:13:13.638Z] [2024-08-10T10:13:13.638Z] TESTING: [2024-08-10T10:13:16.943Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-10T10:13:19.313Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-08-10T10:13:24.751Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-10T10:13:24.751Z] GC before operation: completed in 96.290 ms, heap usage 60.009 MB -> 35.109 MB. [2024-08-10T10:13:34.400Z] 24/08/10 10:13:34 WARN BlockManager: Asked to remove block broadcast_6, which does not exist [2024-08-10T10:13:34.400Z] 24/08/10 10:13:34 WARN BlockManager: Asked to remove block broadcast_6_piece0, which does not exist [2024-08-10T10:13:37.717Z] ====== log-regression (apache-spark) [default], iteration 0 completed (12941.152 ms) ====== [2024-08-10T10:13:37.717Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-10T10:13:37.717Z] GC before operation: completed in 178.689 ms, heap usage 310.612 MB -> 113.319 MB. [2024-08-10T10:13:41.030Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3442.675 ms) ====== [2024-08-10T10:13:41.030Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-10T10:13:41.030Z] GC before operation: completed in 94.763 ms, heap usage 276.191 MB -> 113.863 MB. [2024-08-10T10:13:44.459Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3282.393 ms) ====== [2024-08-10T10:13:44.459Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-10T10:13:44.459Z] GC before operation: completed in 162.930 ms, heap usage 321.330 MB -> 114.326 MB. [2024-08-10T10:13:47.776Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3308.875 ms) ====== [2024-08-10T10:13:47.776Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-10T10:13:48.537Z] GC before operation: completed in 130.486 ms, heap usage 288.648 MB -> 114.463 MB. [2024-08-10T10:13:50.934Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2735.234 ms) ====== [2024-08-10T10:13:50.934Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-10T10:13:50.934Z] GC before operation: completed in 115.360 ms, heap usage 376.193 MB -> 114.913 MB. [2024-08-10T10:13:53.312Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2483.782 ms) ====== [2024-08-10T10:13:53.312Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-10T10:13:53.312Z] GC before operation: completed in 123.518 ms, heap usage 299.276 MB -> 114.937 MB. [2024-08-10T10:13:57.123Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2892.409 ms) ====== [2024-08-10T10:13:57.123Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-10T10:13:57.123Z] GC before operation: completed in 168.016 ms, heap usage 372.329 MB -> 115.298 MB. [2024-08-10T10:13:59.535Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2605.538 ms) ====== [2024-08-10T10:13:59.535Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-10T10:13:59.535Z] GC before operation: completed in 172.559 ms, heap usage 380.368 MB -> 115.615 MB. [2024-08-10T10:14:01.938Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2461.637 ms) ====== [2024-08-10T10:14:01.938Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-10T10:14:01.938Z] GC before operation: completed in 180.378 ms, heap usage 269.183 MB -> 115.488 MB. [2024-08-10T10:14:04.334Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2395.728 ms) ====== [2024-08-10T10:14:04.334Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-10T10:14:05.087Z] GC before operation: completed in 188.656 ms, heap usage 232.050 MB -> 115.535 MB. [2024-08-10T10:14:07.566Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2516.098 ms) ====== [2024-08-10T10:14:07.566Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-10T10:14:07.566Z] GC before operation: completed in 174.899 ms, heap usage 464.367 MB -> 116.350 MB. [2024-08-10T10:14:09.959Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2562.071 ms) ====== [2024-08-10T10:14:09.959Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-10T10:14:09.959Z] GC before operation: completed in 197.439 ms, heap usage 424.398 MB -> 116.437 MB. [2024-08-10T10:14:12.352Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2533.291 ms) ====== [2024-08-10T10:14:12.353Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-10T10:14:13.094Z] GC before operation: completed in 206.066 ms, heap usage 288.288 MB -> 116.374 MB. [2024-08-10T10:14:15.480Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2580.919 ms) ====== [2024-08-10T10:14:15.480Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-10T10:14:15.480Z] GC before operation: completed in 175.720 ms, heap usage 387.762 MB -> 116.743 MB. [2024-08-10T10:14:17.871Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2445.490 ms) ====== [2024-08-10T10:14:17.871Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-10T10:14:18.623Z] GC before operation: completed in 223.584 ms, heap usage 359.046 MB -> 116.893 MB. [2024-08-10T10:14:21.039Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2460.843 ms) ====== [2024-08-10T10:14:21.039Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-10T10:14:21.039Z] GC before operation: completed in 145.283 ms, heap usage 274.900 MB -> 117.006 MB. [2024-08-10T10:14:23.426Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2247.247 ms) ====== [2024-08-10T10:14:23.426Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-10T10:14:23.426Z] GC before operation: completed in 181.066 ms, heap usage 247.338 MB -> 117.059 MB. [2024-08-10T10:14:25.823Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2254.012 ms) ====== [2024-08-10T10:14:25.823Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-10T10:14:25.823Z] GC before operation: completed in 229.152 ms, heap usage 246.102 MB -> 117.236 MB. [2024-08-10T10:14:28.214Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2190.264 ms) ====== [2024-08-10T10:14:28.215Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-10T10:14:28.215Z] GC before operation: completed in 145.577 ms, heap usage 255.836 MB -> 117.416 MB. [2024-08-10T10:14:30.619Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2442.948 ms) ====== [2024-08-10T10:14:31.358Z] ----------------------------------- [2024-08-10T10:14:31.358Z] renaissance-log-regression_0_PASSED [2024-08-10T10:14:31.358Z] ----------------------------------- [2024-08-10T10:14:31.358Z] [2024-08-10T10:14:31.358Z] TEST TEARDOWN: [2024-08-10T10:14:31.358Z] Nothing to be done for teardown. [2024-08-10T10:14:31.358Z] renaissance-log-regression_0 Finish Time: Sat Aug 10 10:14:30 2024 Epoch Time (ms): 1723284870750