[2024-08-08T03:37:23.672Z] Running test renaissance-log-regression_0 ... [2024-08-08T03:37:23.672Z] =============================================== [2024-08-08T03:37:23.672Z] renaissance-log-regression_0 Start Time: Thu Aug 8 03:37:23 2024 Epoch Time (ms): 1723088243394 [2024-08-08T03:37:23.672Z] variation: NoOptions [2024-08-08T03:37:23.672Z] JVM_OPTIONS: [2024-08-08T03:37:23.672Z] { \ [2024-08-08T03:37:23.672Z] echo ""; echo "TEST SETUP:"; \ [2024-08-08T03:37:23.672Z] echo "Nothing to be done for setup."; \ [2024-08-08T03:37:23.672Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17230789718667/renaissance-log-regression_0"; \ [2024-08-08T03:37:23.672Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17230789718667/renaissance-log-regression_0"; \ [2024-08-08T03:37:23.672Z] echo ""; echo "TESTING:"; \ [2024-08-08T03:37:23.673Z] "/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_17230789718667/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-08T03:37:23.673Z] 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_17230789718667/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-08T03:37:23.673Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-08T03:37:23.673Z] echo "Nothing to be done for teardown."; \ [2024-08-08T03:37:23.673Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17230789718667/TestTargetResult"; [2024-08-08T03:37:23.673Z] [2024-08-08T03:37:23.673Z] TEST SETUP: [2024-08-08T03:37:23.673Z] Nothing to be done for setup. [2024-08-08T03:37:23.673Z] [2024-08-08T03:37:23.673Z] TESTING: [2024-08-08T03:37:26.524Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-08T03:37:27.613Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-08-08T03:37:30.517Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-08T03:37:30.517Z] GC before operation: completed in 40.369 ms, heap usage 58.521 MB -> 35.204 MB. [2024-08-08T03:37:39.179Z] ====== log-regression (apache-spark) [default], iteration 0 completed (8120.906 ms) ====== [2024-08-08T03:37:39.179Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-08T03:37:39.179Z] GC before operation: completed in 56.415 ms, heap usage 521.924 MB -> 113.346 MB. [2024-08-08T03:37:42.021Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3513.466 ms) ====== [2024-08-08T03:37:42.021Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-08T03:37:42.021Z] GC before operation: completed in 44.293 ms, heap usage 391.743 MB -> 113.871 MB. [2024-08-08T03:37:44.857Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2786.917 ms) ====== [2024-08-08T03:37:44.858Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-08T03:37:44.858Z] GC before operation: completed in 48.742 ms, heap usage 364.110 MB -> 114.307 MB. [2024-08-08T03:37:47.695Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2733.909 ms) ====== [2024-08-08T03:37:47.695Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-08T03:37:47.695Z] GC before operation: completed in 47.775 ms, heap usage 371.295 MB -> 114.546 MB. [2024-08-08T03:37:50.528Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2530.449 ms) ====== [2024-08-08T03:37:50.528Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-08T03:37:50.528Z] GC before operation: completed in 47.336 ms, heap usage 269.219 MB -> 114.795 MB. [2024-08-08T03:37:52.832Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2495.353 ms) ====== [2024-08-08T03:37:52.832Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-08T03:37:52.832Z] GC before operation: completed in 53.005 ms, heap usage 351.636 MB -> 114.950 MB. [2024-08-08T03:37:55.670Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2534.720 ms) ====== [2024-08-08T03:37:55.670Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-08T03:37:55.670Z] GC before operation: completed in 63.294 ms, heap usage 341.346 MB -> 115.165 MB. [2024-08-08T03:37:57.887Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2600.627 ms) ====== [2024-08-08T03:37:57.887Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-08T03:37:57.887Z] GC before operation: completed in 54.051 ms, heap usage 242.649 MB -> 115.348 MB. [2024-08-08T03:38:00.741Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2540.197 ms) ====== [2024-08-08T03:38:00.741Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-08T03:38:00.741Z] GC before operation: completed in 53.153 ms, heap usage 403.450 MB -> 115.615 MB. [2024-08-08T03:38:02.896Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2250.526 ms) ====== [2024-08-08T03:38:02.896Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-08T03:38:02.896Z] GC before operation: completed in 68.711 ms, heap usage 425.165 MB -> 115.832 MB. [2024-08-08T03:38:05.044Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2380.832 ms) ====== [2024-08-08T03:38:05.044Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-08T03:38:05.354Z] GC before operation: completed in 55.394 ms, heap usage 372.000 MB -> 115.952 MB. [2024-08-08T03:38:07.533Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2417.901 ms) ====== [2024-08-08T03:38:07.534Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-08T03:38:07.849Z] GC before operation: completed in 70.287 ms, heap usage 226.798 MB -> 116.138 MB. [2024-08-08T03:38:10.011Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2428.878 ms) ====== [2024-08-08T03:38:10.011Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-08T03:38:10.321Z] GC before operation: completed in 59.219 ms, heap usage 130.334 MB -> 116.317 MB. [2024-08-08T03:38:13.154Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2547.191 ms) ====== [2024-08-08T03:38:13.154Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-08T03:38:13.154Z] GC before operation: completed in 56.960 ms, heap usage 238.532 MB -> 116.533 MB. [2024-08-08T03:38:15.325Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2379.972 ms) ====== [2024-08-08T03:38:15.325Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-08T03:38:15.325Z] GC before operation: completed in 57.128 ms, heap usage 550.002 MB -> 116.707 MB. [2024-08-08T03:38:17.473Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2334.404 ms) ====== [2024-08-08T03:38:17.473Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-08T03:38:17.782Z] GC before operation: completed in 56.929 ms, heap usage 245.428 MB -> 116.910 MB. [2024-08-08T03:38:20.679Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2510.757 ms) ====== [2024-08-08T03:38:20.679Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-08T03:38:20.679Z] GC before operation: completed in 56.499 ms, heap usage 258.848 MB -> 117.113 MB. [2024-08-08T03:38:22.833Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2327.563 ms) ====== [2024-08-08T03:38:22.833Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-08T03:38:22.833Z] GC before operation: completed in 64.828 ms, heap usage 212.817 MB -> 117.317 MB. [2024-08-08T03:38:25.670Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2545.610 ms) ====== [2024-08-08T03:38:25.670Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-08T03:38:25.670Z] GC before operation: completed in 60.029 ms, heap usage 546.658 MB -> 117.638 MB. [2024-08-08T03:38:27.579Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2360.139 ms) ====== [2024-08-08T03:38:27.898Z] ----------------------------------- [2024-08-08T03:38:27.898Z] renaissance-log-regression_0_PASSED [2024-08-08T03:38:27.898Z] ----------------------------------- [2024-08-08T03:38:27.898Z] [2024-08-08T03:38:27.898Z] TEST TEARDOWN: [2024-08-08T03:38:27.898Z] Nothing to be done for teardown. [2024-08-08T03:38:27.898Z] renaissance-log-regression_0 Finish Time: Thu Aug 8 03:38:27 2024 Epoch Time (ms): 1723088307659