[2024-08-15T06:31:21.640Z] Running test renaissance-log-regression_0 ... [2024-08-15T06:31:21.640Z] =============================================== [2024-08-15T06:31:21.640Z] renaissance-log-regression_0 Start Time: Thu Aug 15 06:31:20 2024 Epoch Time (ms): 1723703480339 [2024-08-15T06:31:21.640Z] variation: NoOptions [2024-08-15T06:31:21.640Z] JVM_OPTIONS: [2024-08-15T06:31:21.640Z] { \ [2024-08-15T06:31:21.640Z] echo ""; echo "TEST SETUP:"; \ [2024-08-15T06:31:21.640Z] echo "Nothing to be done for setup."; \ [2024-08-15T06:31:21.640Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17237025032524/renaissance-log-regression_0"; \ [2024-08-15T06:31:21.640Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17237025032524/renaissance-log-regression_0"; \ [2024-08-15T06:31:21.640Z] echo ""; echo "TESTING:"; \ [2024-08-15T06:31:21.640Z] "/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_17237025032524/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-15T06:31:21.640Z] 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_17237025032524/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-15T06:31:21.640Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-15T06:31:21.640Z] echo "Nothing to be done for teardown."; \ [2024-08-15T06:31:21.640Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17237025032524/TestTargetResult"; [2024-08-15T06:31:21.640Z] [2024-08-15T06:31:21.640Z] TEST SETUP: [2024-08-15T06:31:21.640Z] Nothing to be done for setup. [2024-08-15T06:31:21.640Z] [2024-08-15T06:31:21.640Z] TESTING: [2024-08-15T06:31:23.019Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-15T06:31:25.830Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-08-15T06:31:28.655Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-15T06:31:28.655Z] GC before operation: completed in 56.602 ms, heap usage 94.727 MB -> 35.231 MB. [2024-08-15T06:31:36.214Z] ====== log-regression (apache-spark) [default], iteration 0 completed (6926.461 ms) ====== [2024-08-15T06:31:36.214Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-15T06:31:36.214Z] GC before operation: completed in 96.911 ms, heap usage 333.218 MB -> 113.374 MB. [2024-08-15T06:31:39.040Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2029.107 ms) ====== [2024-08-15T06:31:39.040Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-15T06:31:39.040Z] GC before operation: completed in 54.341 ms, heap usage 248.966 MB -> 113.821 MB. [2024-08-15T06:31:40.427Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1723.990 ms) ====== [2024-08-15T06:31:40.427Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-15T06:31:40.427Z] GC before operation: completed in 75.011 ms, heap usage 338.450 MB -> 114.504 MB. [2024-08-15T06:31:41.828Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1773.629 ms) ====== [2024-08-15T06:31:41.828Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-15T06:31:41.828Z] GC before operation: completed in 138.350 ms, heap usage 345.124 MB -> 114.648 MB. [2024-08-15T06:31:44.644Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2224.671 ms) ====== [2024-08-15T06:31:44.644Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-15T06:31:44.644Z] GC before operation: completed in 71.724 ms, heap usage 308.822 MB -> 114.835 MB. [2024-08-15T06:31:46.026Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1450.790 ms) ====== [2024-08-15T06:31:46.026Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-15T06:31:46.026Z] GC before operation: completed in 69.845 ms, heap usage 261.510 MB -> 114.970 MB. [2024-08-15T06:31:47.410Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1270.436 ms) ====== [2024-08-15T06:31:47.410Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-15T06:31:47.410Z] GC before operation: completed in 64.713 ms, heap usage 419.336 MB -> 115.568 MB. [2024-08-15T06:31:48.796Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1313.303 ms) ====== [2024-08-15T06:31:48.796Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-15T06:31:48.796Z] GC before operation: completed in 71.008 ms, heap usage 322.394 MB -> 115.472 MB. [2024-08-15T06:31:50.179Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1246.203 ms) ====== [2024-08-15T06:31:50.179Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-15T06:31:50.179Z] GC before operation: completed in 62.583 ms, heap usage 306.512 MB -> 115.657 MB. [2024-08-15T06:31:51.761Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1175.239 ms) ====== [2024-08-15T06:31:51.761Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-15T06:31:51.761Z] GC before operation: completed in 67.713 ms, heap usage 315.781 MB -> 115.926 MB. [2024-08-15T06:31:53.146Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1296.940 ms) ====== [2024-08-15T06:31:53.146Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-15T06:31:53.146Z] GC before operation: completed in 80.496 ms, heap usage 329.172 MB -> 116.109 MB. [2024-08-15T06:31:53.146Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1163.263 ms) ====== [2024-08-15T06:31:53.146Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-15T06:31:53.146Z] GC before operation: completed in 89.849 ms, heap usage 266.771 MB -> 116.115 MB. [2024-08-15T06:31:54.541Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1174.240 ms) ====== [2024-08-15T06:31:54.541Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-15T06:31:54.541Z] GC before operation: completed in 66.964 ms, heap usage 417.340 MB -> 116.664 MB. [2024-08-15T06:31:55.928Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1422.331 ms) ====== [2024-08-15T06:31:55.928Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-15T06:31:55.928Z] GC before operation: completed in 111.878 ms, heap usage 314.082 MB -> 116.642 MB. [2024-08-15T06:31:57.318Z] 24/08/15 06:31:56 WARN BlockManager: Asked to remove block broadcast_672, which does not exist [2024-08-15T06:31:57.318Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1305.097 ms) ====== [2024-08-15T06:31:57.318Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-15T06:31:57.319Z] GC before operation: completed in 66.674 ms, heap usage 324.640 MB -> 116.847 MB. [2024-08-15T06:31:58.701Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1043.307 ms) ====== [2024-08-15T06:31:58.701Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-15T06:31:58.701Z] GC before operation: completed in 78.491 ms, heap usage 336.090 MB -> 117.177 MB. [2024-08-15T06:32:00.087Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1123.892 ms) ====== [2024-08-15T06:32:00.087Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-15T06:32:00.087Z] GC before operation: completed in 93.568 ms, heap usage 265.083 MB -> 117.203 MB. [2024-08-15T06:32:01.471Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1326.209 ms) ====== [2024-08-15T06:32:01.471Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-15T06:32:01.471Z] GC before operation: completed in 82.066 ms, heap usage 434.774 MB -> 117.940 MB. [2024-08-15T06:32:02.849Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1203.162 ms) ====== [2024-08-15T06:32:02.849Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-15T06:32:02.849Z] GC before operation: completed in 79.733 ms, heap usage 255.550 MB -> 117.550 MB. [2024-08-15T06:32:04.229Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1219.156 ms) ====== [2024-08-15T06:32:04.229Z] ----------------------------------- [2024-08-15T06:32:04.229Z] renaissance-log-regression_0_PASSED [2024-08-15T06:32:04.229Z] ----------------------------------- [2024-08-15T06:32:04.229Z] [2024-08-15T06:32:04.229Z] TEST TEARDOWN: [2024-08-15T06:32:04.229Z] Nothing to be done for teardown. [2024-08-15T06:32:04.229Z] renaissance-log-regression_0 Finish Time: Thu Aug 15 06:32:03 2024 Epoch Time (ms): 1723703523654