[2024-11-21T22:28:41.455Z] Running test renaissance-log-regression_0 ... [2024-11-21T22:28:41.772Z] =============================================== [2024-11-21T22:28:41.772Z] renaissance-log-regression_0 Start Time: Thu Nov 21 22:28:41 2024 Epoch Time (ms): 1732228121717 [2024-11-21T22:28:42.098Z] variation: NoOptions [2024-11-21T22:28:42.098Z] JVM_OPTIONS: [2024-11-21T22:28:42.098Z] { \ [2024-11-21T22:28:42.098Z] echo ""; echo "TEST SETUP:"; \ [2024-11-21T22:28:42.098Z] echo "Nothing to be done for setup."; \ [2024-11-21T22:28:42.098Z] mkdir -p "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17322272919127\\renaissance-log-regression_0"; \ [2024-11-21T22:28:42.098Z] cd "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17322272919127\\renaissance-log-regression_0"; \ [2024-11-21T22:28:42.098Z] echo ""; echo "TESTING:"; \ [2024-11-21T22:28:42.098Z] "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_17322272919127\\renaissance-log-regression_0"\\log-regression.json" log-regression; \ [2024-11-21T22:28:42.098Z] 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_17322272919127\\renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-21T22:28:42.099Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-21T22:28:42.099Z] echo "Nothing to be done for teardown."; \ [2024-11-21T22:28:42.099Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17322272919127\\TestTargetResult"; [2024-11-21T22:28:42.099Z] [2024-11-21T22:28:42.099Z] TEST SETUP: [2024-11-21T22:28:42.099Z] Nothing to be done for setup. [2024-11-21T22:28:42.425Z] [2024-11-21T22:28:42.425Z] TESTING: [2024-11-21T22:28:53.103Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-21T22:28:53.796Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-11-21T22:28:57.504Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-11-21T22:28:57.504Z] GC before operation: completed in 52.542 ms, heap usage 61.018 MB -> 35.349 MB. [2024-11-21T22:29:03.550Z] ====== log-regression (apache-spark) [default], iteration 0 completed (6539.178 ms) ====== [2024-11-21T22:29:03.550Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-11-21T22:29:03.550Z] GC before operation: completed in 64.233 ms, heap usage 539.771 MB -> 113.933 MB. [2024-11-21T22:29:06.197Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2237.939 ms) ====== [2024-11-21T22:29:06.197Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-11-21T22:29:06.197Z] GC before operation: completed in 62.250 ms, heap usage 399.885 MB -> 114.265 MB. [2024-11-21T22:29:07.907Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1788.290 ms) ====== [2024-11-21T22:29:07.907Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-11-21T22:29:07.907Z] GC before operation: completed in 72.777 ms, heap usage 434.099 MB -> 114.772 MB. [2024-11-21T22:29:09.575Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1542.147 ms) ====== [2024-11-21T22:29:09.575Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-11-21T22:29:09.575Z] GC before operation: completed in 61.151 ms, heap usage 424.928 MB -> 115.076 MB. [2024-11-21T22:29:10.732Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1227.978 ms) ====== [2024-11-21T22:29:10.732Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-11-21T22:29:10.732Z] GC before operation: completed in 55.454 ms, heap usage 405.737 MB -> 115.225 MB. [2024-11-21T22:29:11.944Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1193.406 ms) ====== [2024-11-21T22:29:11.944Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-11-21T22:29:11.944Z] GC before operation: completed in 56.944 ms, heap usage 382.265 MB -> 115.447 MB. [2024-11-21T22:29:13.145Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1130.248 ms) ====== [2024-11-21T22:29:13.145Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-11-21T22:29:13.145Z] GC before operation: completed in 58.852 ms, heap usage 363.797 MB -> 115.619 MB. [2024-11-21T22:29:14.338Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1139.697 ms) ====== [2024-11-21T22:29:14.338Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-11-21T22:29:14.338Z] GC before operation: completed in 71.815 ms, heap usage 361.610 MB -> 115.730 MB. [2024-11-21T22:29:15.510Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1134.081 ms) ====== [2024-11-21T22:29:15.510Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-11-21T22:29:15.510Z] GC before operation: completed in 58.805 ms, heap usage 343.061 MB -> 115.944 MB. [2024-11-21T22:29:16.668Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1086.349 ms) ====== [2024-11-21T22:29:16.668Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-11-21T22:29:16.668Z] GC before operation: completed in 71.047 ms, heap usage 325.273 MB -> 116.114 MB. [2024-11-21T22:29:17.812Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1069.203 ms) ====== [2024-11-21T22:29:17.812Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-11-21T22:29:17.812Z] GC before operation: completed in 61.652 ms, heap usage 329.755 MB -> 116.207 MB. [2024-11-21T22:29:18.988Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1088.410 ms) ====== [2024-11-21T22:29:18.988Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-11-21T22:29:18.988Z] GC before operation: completed in 69.504 ms, heap usage 305.190 MB -> 116.446 MB. [2024-11-21T22:29:20.147Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1114.846 ms) ====== [2024-11-21T22:29:20.148Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-11-21T22:29:20.148Z] GC before operation: completed in 63.177 ms, heap usage 319.992 MB -> 116.708 MB. [2024-11-21T22:29:21.332Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1111.369 ms) ====== [2024-11-21T22:29:21.332Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-11-21T22:29:21.332Z] GC before operation: completed in 65.791 ms, heap usage 278.140 MB -> 116.698 MB. [2024-11-21T22:29:22.537Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1119.266 ms) ====== [2024-11-21T22:29:22.537Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-11-21T22:29:22.537Z] GC before operation: completed in 75.431 ms, heap usage 550.771 MB -> 117.573 MB. [2024-11-21T22:29:23.687Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1104.347 ms) ====== [2024-11-21T22:29:23.687Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-11-21T22:29:23.687Z] GC before operation: completed in 65.868 ms, heap usage 351.237 MB -> 117.372 MB. [2024-11-21T22:29:24.837Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1073.409 ms) ====== [2024-11-21T22:29:24.837Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-11-21T22:29:24.837Z] GC before operation: completed in 72.733 ms, heap usage 327.065 MB -> 117.558 MB. [2024-11-21T22:29:26.100Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1140.344 ms) ====== [2024-11-21T22:29:26.100Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-11-21T22:29:26.100Z] GC before operation: completed in 65.063 ms, heap usage 537.495 MB -> 118.189 MB. [2024-11-21T22:29:27.255Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1108.032 ms) ====== [2024-11-21T22:29:27.255Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-11-21T22:29:27.255Z] GC before operation: completed in 63.354 ms, heap usage 358.349 MB -> 117.907 MB. [2024-11-21T22:29:28.380Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1084.561 ms) ====== [2024-11-21T22:29:28.695Z] ----------------------------------- [2024-11-21T22:29:28.695Z] renaissance-log-regression_0_PASSED [2024-11-21T22:29:28.695Z] ----------------------------------- [2024-11-21T22:29:28.695Z] [2024-11-21T22:29:28.695Z] TEST TEARDOWN: [2024-11-21T22:29:28.695Z] Nothing to be done for teardown. [2024-11-21T22:29:29.017Z] renaissance-log-regression_0 Finish Time: Thu Nov 21 22:29:28 2024 Epoch Time (ms): 1732228168716