[2024-09-26T22:31:42.545Z] Running test renaissance-log-regression_0 ... [2024-09-26T22:31:42.863Z] =============================================== [2024-09-26T22:31:42.863Z] renaissance-log-regression_0 Start Time: Thu Sep 26 22:31:42 2024 Epoch Time (ms): 1727389902616 [2024-09-26T22:31:42.863Z] variation: NoOptions [2024-09-26T22:31:42.863Z] JVM_OPTIONS: [2024-09-26T22:31:42.863Z] { \ [2024-09-26T22:31:42.863Z] echo ""; echo "TEST SETUP:"; \ [2024-09-26T22:31:42.863Z] echo "Nothing to be done for setup."; \ [2024-09-26T22:31:42.863Z] mkdir -p "C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17273888269187\\renaissance-log-regression_0"; \ [2024-09-26T22:31:42.863Z] cd "C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17273888269187\\renaissance-log-regression_0"; \ [2024-09-26T22:31:42.863Z] echo ""; echo "TESTING:"; \ [2024-09-26T22:31:42.863Z] "c:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/jdkbinary/j2sdk-image\\bin\\java" -jar "C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17273888269187\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2024-09-26T22:31:42.863Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17273888269187\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-26T22:31:42.863Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-26T22:31:42.863Z] echo "Nothing to be done for teardown."; \ [2024-09-26T22:31:42.863Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17273888269187\\TestTargetResult"; [2024-09-26T22:31:42.863Z] [2024-09-26T22:31:42.863Z] TEST SETUP: [2024-09-26T22:31:42.863Z] Nothing to be done for setup. [2024-09-26T22:31:42.863Z] [2024-09-26T22:31:42.863Z] TESTING: [2024-09-26T22:31:53.401Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-26T22:31:54.980Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-09-26T22:31:58.614Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-09-26T22:31:58.943Z] GC before operation: completed in 165.025 ms, heap usage 121.268 MB -> 28.247 MB. [2024-09-26T22:32:06.493Z] ====== log-regression (apache-spark) [default], iteration 0 completed (7542.089 ms) ====== [2024-09-26T22:32:06.494Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-09-26T22:32:07.100Z] GC before operation: completed in 356.830 ms, heap usage 688.592 MB -> 103.299 MB. [2024-09-26T22:32:08.749Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1850.274 ms) ====== [2024-09-26T22:32:08.749Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-09-26T22:32:08.749Z] GC before operation: completed in 198.868 ms, heap usage 381.452 MB -> 101.824 MB. [2024-09-26T22:32:10.606Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1374.492 ms) ====== [2024-09-26T22:32:10.606Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-09-26T22:32:10.606Z] GC before operation: completed in 168.466 ms, heap usage 305.745 MB -> 101.733 MB. [2024-09-26T22:32:11.748Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1153.923 ms) ====== [2024-09-26T22:32:11.748Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-09-26T22:32:11.748Z] GC before operation: completed in 168.802 ms, heap usage 361.744 MB -> 102.592 MB. [2024-09-26T22:32:12.867Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1110.782 ms) ====== [2024-09-26T22:32:12.867Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-09-26T22:32:12.867Z] GC before operation: completed in 164.161 ms, heap usage 411.585 MB -> 103.306 MB. [2024-09-26T22:32:14.105Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1095.843 ms) ====== [2024-09-26T22:32:14.105Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-09-26T22:32:14.105Z] GC before operation: completed in 166.135 ms, heap usage 383.909 MB -> 103.397 MB. [2024-09-26T22:32:15.201Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1048.446 ms) ====== [2024-09-26T22:32:15.201Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-09-26T22:32:15.554Z] GC before operation: completed in 178.747 ms, heap usage 336.917 MB -> 103.194 MB. [2024-09-26T22:32:16.699Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1052.056 ms) ====== [2024-09-26T22:32:16.699Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-09-26T22:32:16.699Z] GC before operation: completed in 178.441 ms, heap usage 304.735 MB -> 103.111 MB. [2024-09-26T22:32:17.805Z] ====== log-regression (apache-spark) [default], iteration 8 completed (985.429 ms) ====== [2024-09-26T22:32:17.805Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-09-26T22:32:17.805Z] GC before operation: completed in 127.423 ms, heap usage 276.030 MB -> 103.221 MB. [2024-09-26T22:32:18.977Z] ====== log-regression (apache-spark) [default], iteration 9 completed (988.479 ms) ====== [2024-09-26T22:32:18.977Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-09-26T22:32:18.977Z] GC before operation: completed in 178.914 ms, heap usage 260.703 MB -> 103.316 MB. [2024-09-26T22:32:20.166Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1007.799 ms) ====== [2024-09-26T22:32:20.166Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-09-26T22:32:20.166Z] GC before operation: completed in 177.668 ms, heap usage 250.849 MB -> 103.395 MB. [2024-09-26T22:32:21.378Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1020.588 ms) ====== [2024-09-26T22:32:21.378Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-09-26T22:32:21.378Z] GC before operation: completed in 158.121 ms, heap usage 250.807 MB -> 103.699 MB. [2024-09-26T22:32:22.484Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1121.618 ms) ====== [2024-09-26T22:32:22.484Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-09-26T22:32:22.484Z] GC before operation: completed in 131.642 ms, heap usage 244.739 MB -> 103.939 MB. [2024-09-26T22:32:23.727Z] ====== log-regression (apache-spark) [default], iteration 13 completed (951.880 ms) ====== [2024-09-26T22:32:23.727Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-09-26T22:32:23.727Z] GC before operation: completed in 181.001 ms, heap usage 236.556 MB -> 104.104 MB. [2024-09-26T22:32:24.856Z] ====== log-regression (apache-spark) [default], iteration 14 completed (964.478 ms) ====== [2024-09-26T22:32:24.856Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-09-26T22:32:24.856Z] GC before operation: completed in 159.216 ms, heap usage 234.615 MB -> 104.308 MB. [2024-09-26T22:32:26.004Z] ====== log-regression (apache-spark) [default], iteration 15 completed (968.293 ms) ====== [2024-09-26T22:32:26.004Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-09-26T22:32:26.004Z] GC before operation: completed in 168.196 ms, heap usage 233.762 MB -> 104.581 MB. [2024-09-26T22:32:27.103Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1125.198 ms) ====== [2024-09-26T22:32:27.103Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-09-26T22:32:27.456Z] GC before operation: completed in 231.181 ms, heap usage 231.842 MB -> 104.916 MB. [2024-09-26T22:32:28.669Z] ====== log-regression (apache-spark) [default], iteration 17 completed (983.227 ms) ====== [2024-09-26T22:32:28.669Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-09-26T22:32:28.669Z] GC before operation: completed in 225.897 ms, heap usage 223.073 MB -> 105.035 MB. [2024-09-26T22:32:29.790Z] ====== log-regression (apache-spark) [default], iteration 18 completed (946.575 ms) ====== [2024-09-26T22:32:29.790Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-09-26T22:32:29.790Z] GC before operation: completed in 140.744 ms, heap usage 238.532 MB -> 105.358 MB. [2024-09-26T22:32:30.876Z] ====== log-regression (apache-spark) [default], iteration 19 completed (966.169 ms) ====== [2024-09-26T22:32:30.876Z] ----------------------------------- [2024-09-26T22:32:30.876Z] renaissance-log-regression_0_PASSED [2024-09-26T22:32:30.876Z] ----------------------------------- [2024-09-26T22:32:31.203Z] [2024-09-26T22:32:31.203Z] TEST TEARDOWN: [2024-09-26T22:32:31.203Z] Nothing to be done for teardown. [2024-09-26T22:32:31.203Z] renaissance-log-regression_0 Finish Time: Thu Sep 26 22:32:30 2024 Epoch Time (ms): 1727389950994