[2024-11-25T03:11:30.109Z] Running test renaissance-log-regression_0 ... [2024-11-25T03:11:30.109Z] =============================================== [2024-11-25T03:11:30.439Z] renaissance-log-regression_0 Start Time: Mon Nov 25 03:11:30 2024 Epoch Time (ms): 1732504290143 [2024-11-25T03:11:30.439Z] variation: NoOptions [2024-11-25T03:11:30.439Z] JVM_OPTIONS: [2024-11-25T03:11:30.439Z] { \ [2024-11-25T03:11:30.439Z] echo ""; echo "TEST SETUP:"; \ [2024-11-25T03:11:30.439Z] echo "Nothing to be done for setup."; \ [2024-11-25T03:11:30.439Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1732503462773\\renaissance-log-regression_0"; \ [2024-11-25T03:11:30.439Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1732503462773\\renaissance-log-regression_0"; \ [2024-11-25T03:11:30.439Z] echo ""; echo "TESTING:"; \ [2024-11-25T03:11:30.439Z] "c:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/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/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1732503462773\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2024-11-25T03:11:30.439Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1732503462773\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-25T03:11:30.439Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-25T03:11:30.439Z] echo "Nothing to be done for teardown."; \ [2024-11-25T03:11:30.439Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_1732503462773\\TestTargetResult"; [2024-11-25T03:11:30.439Z] [2024-11-25T03:11:30.439Z] TEST SETUP: [2024-11-25T03:11:30.439Z] Nothing to be done for setup. [2024-11-25T03:11:30.439Z] [2024-11-25T03:11:30.439Z] TESTING: [2024-11-25T03:11:41.132Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-25T03:11:41.839Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-11-25T03:11:45.558Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-11-25T03:11:45.558Z] GC before operation: completed in 55.328 ms, heap usage 93.167 MB -> 35.450 MB. [2024-11-25T03:11:51.694Z] ====== log-regression (apache-spark) [default], iteration 0 completed (6403.011 ms) ====== [2024-11-25T03:11:51.694Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-11-25T03:11:51.694Z] GC before operation: completed in 75.814 ms, heap usage 466.202 MB -> 114.005 MB. [2024-11-25T03:11:54.040Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2105.159 ms) ====== [2024-11-25T03:11:54.040Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-11-25T03:11:54.040Z] GC before operation: completed in 51.728 ms, heap usage 536.668 MB -> 114.724 MB. [2024-11-25T03:11:55.810Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1787.154 ms) ====== [2024-11-25T03:11:55.810Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-11-25T03:11:55.810Z] GC before operation: completed in 68.654 ms, heap usage 304.746 MB -> 114.711 MB. [2024-11-25T03:11:57.104Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1393.428 ms) ====== [2024-11-25T03:11:57.104Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-11-25T03:11:57.104Z] GC before operation: completed in 58.840 ms, heap usage 597.043 MB -> 115.590 MB. [2024-11-25T03:11:58.285Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1318.124 ms) ====== [2024-11-25T03:11:58.285Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-11-25T03:11:58.628Z] GC before operation: completed in 58.368 ms, heap usage 388.448 MB -> 115.408 MB. [2024-11-25T03:11:59.859Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1328.197 ms) ====== [2024-11-25T03:11:59.859Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-11-25T03:11:59.859Z] GC before operation: completed in 63.520 ms, heap usage 386.062 MB -> 115.641 MB. [2024-11-25T03:12:01.183Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1218.938 ms) ====== [2024-11-25T03:12:01.183Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-11-25T03:12:01.183Z] GC before operation: completed in 61.253 ms, heap usage 392.411 MB -> 115.764 MB. [2024-11-25T03:12:02.335Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1279.101 ms) ====== [2024-11-25T03:12:02.335Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-11-25T03:12:02.718Z] GC before operation: completed in 66.080 ms, heap usage 377.095 MB -> 115.919 MB. [2024-11-25T03:12:03.944Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1173.911 ms) ====== [2024-11-25T03:12:03.944Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-11-25T03:12:03.944Z] GC before operation: completed in 61.376 ms, heap usage 353.690 MB -> 116.079 MB. [2024-11-25T03:12:05.153Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1114.351 ms) ====== [2024-11-25T03:12:05.153Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-11-25T03:12:05.153Z] GC before operation: completed in 76.665 ms, heap usage 362.837 MB -> 116.340 MB. [2024-11-25T03:12:06.313Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1194.475 ms) ====== [2024-11-25T03:12:06.313Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-11-25T03:12:06.313Z] GC before operation: completed in 68.359 ms, heap usage 328.626 MB -> 116.484 MB. [2024-11-25T03:12:07.493Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1138.080 ms) ====== [2024-11-25T03:12:07.493Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-11-25T03:12:07.493Z] GC before operation: completed in 81.681 ms, heap usage 286.921 MB -> 116.596 MB. [2024-11-25T03:12:08.641Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1170.734 ms) ====== [2024-11-25T03:12:08.641Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-11-25T03:12:08.641Z] GC before operation: completed in 77.425 ms, heap usage 229.927 MB -> 116.717 MB. [2024-11-25T03:12:09.872Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1126.245 ms) ====== [2024-11-25T03:12:09.872Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-11-25T03:12:09.872Z] GC before operation: completed in 78.532 ms, heap usage 510.333 MB -> 117.447 MB. [2024-11-25T03:12:11.050Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1152.856 ms) ====== [2024-11-25T03:12:11.050Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-11-25T03:12:11.050Z] GC before operation: completed in 63.961 ms, heap usage 377.697 MB -> 117.234 MB. [2024-11-25T03:12:12.253Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1145.763 ms) ====== [2024-11-25T03:12:12.253Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-11-25T03:12:12.253Z] GC before operation: completed in 67.967 ms, heap usage 343.995 MB -> 117.524 MB. [2024-11-25T03:12:13.391Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1202.487 ms) ====== [2024-11-25T03:12:13.391Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-11-25T03:12:13.859Z] GC before operation: completed in 65.396 ms, heap usage 321.824 MB -> 117.635 MB. [2024-11-25T03:12:14.571Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1117.801 ms) ====== [2024-11-25T03:12:14.571Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-11-25T03:12:14.911Z] GC before operation: completed in 65.211 ms, heap usage 274.906 MB -> 117.730 MB. [2024-11-25T03:12:16.082Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1141.427 ms) ====== [2024-11-25T03:12:16.082Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-11-25T03:12:16.082Z] GC before operation: completed in 67.403 ms, heap usage 547.284 MB -> 118.583 MB. [2024-11-25T03:12:16.865Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1078.894 ms) ====== [2024-11-25T03:12:17.191Z] ----------------------------------- [2024-11-25T03:12:17.191Z] renaissance-log-regression_0_PASSED [2024-11-25T03:12:17.191Z] ----------------------------------- [2024-11-25T03:12:17.506Z] [2024-11-25T03:12:17.506Z] TEST TEARDOWN: [2024-11-25T03:12:17.506Z] Nothing to be done for teardown. [2024-11-25T03:12:17.506Z] renaissance-log-regression_0 Finish Time: Mon Nov 25 03:12:17 2024 Epoch Time (ms): 1732504337449