[2024-08-02T05:12:14.799Z] Running test renaissance-log-regression_0 ... [2024-08-02T05:12:14.799Z] =============================================== [2024-08-02T05:12:14.799Z] renaissance-log-regression_0 Start Time: Fri Aug 2 00:12:14 2024 Epoch Time (ms): 1722575534374 [2024-08-02T05:12:14.799Z] variation: NoOptions [2024-08-02T05:12:14.799Z] JVM_OPTIONS: [2024-08-02T05:12:14.799Z] { \ [2024-08-02T05:12:14.799Z] echo ""; echo "TEST SETUP:"; \ [2024-08-02T05:12:14.799Z] echo "Nothing to be done for setup."; \ [2024-08-02T05:12:14.799Z] mkdir -p "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17225748014076/renaissance-log-regression_0"; \ [2024-08-02T05:12:14.799Z] cd "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17225748014076/renaissance-log-regression_0"; \ [2024-08-02T05:12:14.799Z] echo ""; echo "TESTING:"; \ [2024-08-02T05:12:14.800Z] "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk8u432-b01/bin/..//bin/java" -jar "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17225748014076/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-08-02T05:12:14.800Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17225748014076/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-02T05:12:14.800Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-02T05:12:14.800Z] echo "Nothing to be done for teardown."; \ [2024-08-02T05:12:14.800Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_17225748014076/TestTargetResult"; [2024-08-02T05:12:14.800Z] [2024-08-02T05:12:14.800Z] TEST SETUP: [2024-08-02T05:12:14.800Z] Nothing to be done for setup. [2024-08-02T05:12:14.800Z] [2024-08-02T05:12:14.800Z] TESTING: [2024-08-02T05:12:17.854Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-02T05:12:19.286Z] NOTE: 'log-regression' benchmark uses Spark local executor with 8 (out of 8) threads. [2024-08-02T05:12:23.283Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-02T05:12:23.283Z] GC before operation: completed in 212.320 ms, heap usage 66.545 MB -> 26.496 MB. [2024-08-02T05:12:32.471Z] ====== log-regression (apache-spark) [default], iteration 0 completed (8903.015 ms) ====== [2024-08-02T05:12:32.471Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-02T05:12:32.471Z] GC before operation: completed in 409.823 ms, heap usage 403.622 MB -> 105.901 MB. [2024-08-02T05:12:34.701Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2053.713 ms) ====== [2024-08-02T05:12:34.701Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-02T05:12:34.701Z] GC before operation: completed in 297.251 ms, heap usage 296.380 MB -> 105.746 MB. [2024-08-02T05:12:36.908Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1961.478 ms) ====== [2024-08-02T05:12:36.908Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-02T05:12:36.908Z] GC before operation: completed in 252.950 ms, heap usage 265.705 MB -> 105.883 MB. [2024-08-02T05:12:39.129Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1732.266 ms) ====== [2024-08-02T05:12:39.129Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-02T05:12:39.129Z] GC before operation: completed in 265.772 ms, heap usage 174.096 MB -> 107.018 MB. [2024-08-02T05:12:41.335Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1822.528 ms) ====== [2024-08-02T05:12:41.335Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-02T05:12:41.335Z] GC before operation: completed in 191.984 ms, heap usage 625.152 MB -> 110.622 MB. [2024-08-02T05:12:42.739Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1725.553 ms) ====== [2024-08-02T05:12:42.739Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-02T05:12:43.416Z] GC before operation: completed in 246.835 ms, heap usage 611.446 MB -> 110.849 MB. [2024-08-02T05:12:44.825Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1652.920 ms) ====== [2024-08-02T05:12:44.825Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-02T05:12:44.825Z] GC before operation: completed in 224.676 ms, heap usage 606.175 MB -> 111.346 MB. [2024-08-02T05:12:47.037Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1763.638 ms) ====== [2024-08-02T05:12:47.037Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-02T05:12:47.037Z] GC before operation: completed in 212.831 ms, heap usage 605.713 MB -> 111.779 MB. [2024-08-02T05:12:48.441Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1652.563 ms) ====== [2024-08-02T05:12:48.441Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-02T05:12:49.122Z] GC before operation: completed in 258.074 ms, heap usage 608.613 MB -> 112.153 MB. [2024-08-02T05:12:50.531Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1618.835 ms) ====== [2024-08-02T05:12:50.531Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-02T05:12:50.531Z] GC before operation: completed in 216.496 ms, heap usage 614.828 MB -> 112.666 MB. [2024-08-02T05:12:52.730Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1605.798 ms) ====== [2024-08-02T05:12:52.730Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-02T05:12:52.730Z] GC before operation: completed in 230.495 ms, heap usage 614.695 MB -> 113.107 MB. [2024-08-02T05:12:54.140Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1673.719 ms) ====== [2024-08-02T05:12:54.140Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-02T05:12:54.833Z] GC before operation: completed in 258.776 ms, heap usage 608.430 MB -> 113.560 MB. [2024-08-02T05:12:56.247Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1638.952 ms) ====== [2024-08-02T05:12:56.247Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-02T05:12:56.247Z] GC before operation: completed in 233.925 ms, heap usage 614.319 MB -> 114.048 MB. [2024-08-02T05:12:57.666Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1607.845 ms) ====== [2024-08-02T05:12:57.667Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-02T05:12:58.355Z] GC before operation: completed in 193.902 ms, heap usage 616.065 MB -> 114.505 MB. [2024-08-02T05:12:59.761Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1600.248 ms) ====== [2024-08-02T05:12:59.761Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-02T05:12:59.761Z] GC before operation: completed in 221.732 ms, heap usage 615.857 MB -> 114.879 MB. [2024-08-02T05:13:01.991Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1574.954 ms) ====== [2024-08-02T05:13:01.991Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-02T05:13:01.991Z] GC before operation: completed in 249.429 ms, heap usage 616.064 MB -> 115.351 MB. [2024-08-02T05:13:03.405Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1682.613 ms) ====== [2024-08-02T05:13:03.405Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-02T05:13:04.082Z] GC before operation: completed in 228.989 ms, heap usage 611.808 MB -> 115.855 MB. [2024-08-02T05:13:05.496Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1595.983 ms) ====== [2024-08-02T05:13:05.496Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-02T05:13:05.496Z] GC before operation: completed in 198.432 ms, heap usage 619.189 MB -> 116.249 MB. [2024-08-02T05:13:06.911Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1611.316 ms) ====== [2024-08-02T05:13:06.911Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-02T05:13:07.590Z] GC before operation: completed in 232.903 ms, heap usage 620.478 MB -> 116.705 MB. [2024-08-02T05:13:09.037Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1557.216 ms) ====== [2024-08-02T05:13:09.037Z] ----------------------------------- [2024-08-02T05:13:09.037Z] renaissance-log-regression_0_PASSED [2024-08-02T05:13:09.037Z] ----------------------------------- [2024-08-02T05:13:09.037Z] [2024-08-02T05:13:09.037Z] TEST TEARDOWN: [2024-08-02T05:13:09.037Z] Nothing to be done for teardown. [2024-08-02T05:13:09.715Z] renaissance-log-regression_0 Finish Time: Fri Aug 2 00:13:09 2024 Epoch Time (ms): 1722575589011