[2024-08-10T06:55:56.828Z] Running test renaissance-log-regression_0 ... [2024-08-10T06:55:57.140Z] =============================================== [2024-08-10T06:55:57.444Z] renaissance-log-regression_0 Start Time: Sat Aug 10 06:55:57 2024 Epoch Time (ms): 1723272957206 [2024-08-10T06:55:57.444Z] variation: NoOptions [2024-08-10T06:55:57.747Z] JVM_OPTIONS: [2024-08-10T06:55:57.747Z] { \ [2024-08-10T06:55:57.747Z] echo ""; echo "TEST SETUP:"; \ [2024-08-10T06:55:57.747Z] echo "Nothing to be done for setup."; \ [2024-08-10T06:55:57.747Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17232721172834\\renaissance-log-regression_0"; \ [2024-08-10T06:55:57.747Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17232721172834\\renaissance-log-regression_0"; \ [2024-08-10T06:55:57.747Z] echo ""; echo "TESTING:"; \ [2024-08-10T06:55:57.747Z] "c:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_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 "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17232721172834\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2024-08-10T06:55:57.747Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17232721172834\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-10T06:55:57.747Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-10T06:55:57.747Z] echo "Nothing to be done for teardown."; \ [2024-08-10T06:55:57.747Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17232721172834\\TestTargetResult"; [2024-08-10T06:55:58.050Z] [2024-08-10T06:55:58.050Z] TEST SETUP: [2024-08-10T06:55:58.051Z] Nothing to be done for setup. [2024-08-10T06:55:58.051Z] [2024-08-10T06:55:58.051Z] TESTING: [2024-08-10T06:56:08.656Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-10T06:56:09.773Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-08-10T06:56:13.384Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-08-10T06:56:13.384Z] GC before operation: completed in 41.857 ms, heap usage 61.004 MB -> 35.390 MB. [2024-08-10T06:56:19.409Z] ====== log-regression (apache-spark) [default], iteration 0 completed (6332.183 ms) ====== [2024-08-10T06:56:19.409Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-08-10T06:56:19.409Z] GC before operation: completed in 76.960 ms, heap usage 412.150 MB -> 113.795 MB. [2024-08-10T06:56:21.166Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1945.906 ms) ====== [2024-08-10T06:56:21.167Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-08-10T06:56:21.167Z] GC before operation: completed in 53.871 ms, heap usage 274.042 MB -> 114.089 MB. [2024-08-10T06:56:22.917Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1556.360 ms) ====== [2024-08-10T06:56:22.917Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-08-10T06:56:22.917Z] GC before operation: completed in 60.782 ms, heap usage 408.507 MB -> 114.867 MB. [2024-08-10T06:56:24.501Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1337.152 ms) ====== [2024-08-10T06:56:24.501Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-08-10T06:56:24.501Z] GC before operation: completed in 56.432 ms, heap usage 418.361 MB -> 115.041 MB. [2024-08-10T06:56:25.660Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1252.513 ms) ====== [2024-08-10T06:56:25.660Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-08-10T06:56:25.660Z] GC before operation: completed in 59.692 ms, heap usage 399.857 MB -> 115.369 MB. [2024-08-10T06:56:26.764Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1267.727 ms) ====== [2024-08-10T06:56:26.764Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-08-10T06:56:27.189Z] GC before operation: completed in 64.363 ms, heap usage 379.607 MB -> 115.440 MB. [2024-08-10T06:56:28.277Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1259.989 ms) ====== [2024-08-10T06:56:28.277Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-08-10T06:56:28.277Z] GC before operation: completed in 64.201 ms, heap usage 382.188 MB -> 115.748 MB. [2024-08-10T06:56:29.419Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1206.503 ms) ====== [2024-08-10T06:56:29.419Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-08-10T06:56:29.837Z] GC before operation: completed in 72.910 ms, heap usage 391.910 MB -> 115.986 MB. [2024-08-10T06:56:31.012Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1167.098 ms) ====== [2024-08-10T06:56:31.012Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-08-10T06:56:31.012Z] GC before operation: completed in 60.077 ms, heap usage 371.803 MB -> 116.061 MB. [2024-08-10T06:56:32.214Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1157.196 ms) ====== [2024-08-10T06:56:32.214Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-08-10T06:56:32.214Z] GC before operation: completed in 62.748 ms, heap usage 370.565 MB -> 116.315 MB. [2024-08-10T06:56:33.402Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1115.939 ms) ====== [2024-08-10T06:56:33.402Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-08-10T06:56:33.402Z] GC before operation: completed in 60.199 ms, heap usage 351.285 MB -> 116.517 MB. [2024-08-10T06:56:34.546Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1122.874 ms) ====== [2024-08-10T06:56:34.546Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-08-10T06:56:34.546Z] GC before operation: completed in 64.681 ms, heap usage 338.449 MB -> 116.620 MB. [2024-08-10T06:56:35.770Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1134.789 ms) ====== [2024-08-10T06:56:35.770Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-08-10T06:56:35.770Z] GC before operation: completed in 67.670 ms, heap usage 296.011 MB -> 116.664 MB. [2024-08-10T06:56:36.963Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1105.805 ms) ====== [2024-08-10T06:56:36.963Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-08-10T06:56:36.963Z] GC before operation: completed in 84.088 ms, heap usage 266.243 MB -> 116.909 MB. [2024-08-10T06:56:38.144Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1100.332 ms) ====== [2024-08-10T06:56:38.144Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-08-10T06:56:38.144Z] GC before operation: completed in 65.598 ms, heap usage 549.167 MB -> 117.673 MB. [2024-08-10T06:56:39.308Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1113.202 ms) ====== [2024-08-10T06:56:39.308Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-08-10T06:56:39.308Z] GC before operation: completed in 62.954 ms, heap usage 369.858 MB -> 117.573 MB. [2024-08-10T06:56:40.039Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1050.246 ms) ====== [2024-08-10T06:56:40.039Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-08-10T06:56:40.510Z] GC before operation: completed in 71.252 ms, heap usage 332.070 MB -> 117.578 MB. [2024-08-10T06:56:41.637Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1146.258 ms) ====== [2024-08-10T06:56:41.637Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-08-10T06:56:41.637Z] GC before operation: completed in 66.121 ms, heap usage 313.737 MB -> 117.712 MB. [2024-08-10T06:56:42.829Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1088.668 ms) ====== [2024-08-10T06:56:42.829Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-08-10T06:56:42.829Z] GC before operation: completed in 65.320 ms, heap usage 285.043 MB -> 117.901 MB. [2024-08-10T06:56:43.917Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1082.061 ms) ====== [2024-08-10T06:56:43.917Z] ----------------------------------- [2024-08-10T06:56:43.917Z] renaissance-log-regression_0_PASSED [2024-08-10T06:56:43.917Z] ----------------------------------- [2024-08-10T06:56:44.232Z] [2024-08-10T06:56:44.232Z] TEST TEARDOWN: [2024-08-10T06:56:44.232Z] Nothing to be done for teardown. [2024-08-10T06:56:44.232Z] renaissance-log-regression_0 Finish Time: Sat Aug 10 06:56:44 2024 Epoch Time (ms): 1723273004194