[2023-04-18T22:05:05.474Z] Running test renaissance-log-regression_0 ... [2023-04-18T22:05:05.474Z] =============================================== [2023-04-18T22:05:05.474Z] renaissance-log-regression_0 Start Time: Tue Apr 18 22:05:05 2023 Epoch Time (ms): 1681855505263 [2023-04-18T22:05:05.474Z] variation: NoOptions [2023-04-18T22:05:05.474Z] JVM_OPTIONS: [2023-04-18T22:05:05.474Z] { \ [2023-04-18T22:05:05.474Z] echo ""; echo "TEST SETUP:"; \ [2023-04-18T22:05:05.474Z] echo "Nothing to be done for setup."; \ [2023-04-18T22:05:05.474Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_16818548411492/renaissance-log-regression_0"; \ [2023-04-18T22:05:05.474Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_16818548411492/renaissance-log-regression_0"; \ [2023-04-18T22:05:05.474Z] echo ""; echo "TESTING:"; \ [2023-04-18T22:05:05.474Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/openjdkbinary/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 "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_16818548411492/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2023-04-18T22:05:05.474Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_16818548411492/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2023-04-18T22:05:05.474Z] echo ""; echo "TEST TEARDOWN:"; \ [2023-04-18T22:05:05.474Z] echo "Nothing to be done for teardown."; \ [2023-04-18T22:05:05.474Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_16818548411492/TestTargetResult"; [2023-04-18T22:05:05.474Z] [2023-04-18T22:05:05.474Z] TEST SETUP: [2023-04-18T22:05:05.474Z] Nothing to be done for setup. [2023-04-18T22:05:05.474Z] [2023-04-18T22:05:05.474Z] TESTING: [2023-04-18T22:05:08.496Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2023-04-18T22:05:10.207Z] NOTE: 'log-regression' benchmark uses Spark local executor with 2 (out of 2) threads. [2023-04-18T22:05:14.106Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2023-04-18T22:05:14.106Z] GC before operation: completed in 54.484 ms, heap usage 60.724 MB -> 34.777 MB. [2023-04-18T22:05:27.481Z] ====== log-regression (apache-spark) [default], iteration 0 completed (12492.654 ms) ====== [2023-04-18T22:05:27.481Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2023-04-18T22:05:27.481Z] GC before operation: completed in 211.859 ms, heap usage 312.126 MB -> 110.272 MB. [2023-04-18T22:05:30.534Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3441.790 ms) ====== [2023-04-18T22:05:30.534Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2023-04-18T22:05:30.534Z] GC before operation: completed in 128.929 ms, heap usage 563.935 MB -> 111.403 MB. [2023-04-18T22:05:32.899Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2820.862 ms) ====== [2023-04-18T22:05:32.899Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2023-04-18T22:05:33.380Z] GC before operation: completed in 96.873 ms, heap usage 300.696 MB -> 111.189 MB. [2023-04-18T22:05:35.762Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2676.913 ms) ====== [2023-04-18T22:05:35.762Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2023-04-18T22:05:35.762Z] GC before operation: completed in 80.838 ms, heap usage 240.164 MB -> 111.328 MB. [2023-04-18T22:05:38.789Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2496.227 ms) ====== [2023-04-18T22:05:38.789Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2023-04-18T22:05:38.789Z] GC before operation: completed in 91.451 ms, heap usage 373.066 MB -> 111.873 MB. [2023-04-18T22:05:41.101Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2301.799 ms) ====== [2023-04-18T22:05:41.101Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2023-04-18T22:05:41.101Z] GC before operation: completed in 74.606 ms, heap usage 274.381 MB -> 111.798 MB. [2023-04-18T22:05:42.810Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2081.063 ms) ====== [2023-04-18T22:05:42.810Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2023-04-18T22:05:43.151Z] GC before operation: completed in 105.469 ms, heap usage 341.044 MB -> 112.138 MB. [2023-04-18T22:05:45.459Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2084.206 ms) ====== [2023-04-18T22:05:45.459Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2023-04-18T22:05:45.459Z] GC before operation: completed in 109.488 ms, heap usage 340.927 MB -> 112.325 MB. [2023-04-18T22:05:47.192Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1909.472 ms) ====== [2023-04-18T22:05:47.192Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2023-04-18T22:05:47.192Z] GC before operation: completed in 96.549 ms, heap usage 342.485 MB -> 112.589 MB. [2023-04-18T22:05:48.938Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1856.181 ms) ====== [2023-04-18T22:05:48.938Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2023-04-18T22:05:49.292Z] GC before operation: completed in 108.159 ms, heap usage 325.817 MB -> 112.691 MB. [2023-04-18T22:05:51.025Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1720.322 ms) ====== [2023-04-18T22:05:51.025Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2023-04-18T22:05:51.025Z] GC before operation: completed in 105.525 ms, heap usage 302.631 MB -> 112.863 MB. [2023-04-18T22:05:52.738Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1750.954 ms) ====== [2023-04-18T22:05:52.738Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2023-04-18T22:05:52.738Z] GC before operation: completed in 97.172 ms, heap usage 356.601 MB -> 113.079 MB. [2023-04-18T22:05:55.048Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1813.473 ms) ====== [2023-04-18T22:05:55.048Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2023-04-18T22:05:55.048Z] GC before operation: completed in 80.129 ms, heap usage 409.447 MB -> 113.387 MB. [2023-04-18T22:05:56.237Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1624.124 ms) ====== [2023-04-18T22:05:56.237Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2023-04-18T22:05:56.579Z] GC before operation: completed in 86.086 ms, heap usage 315.542 MB -> 113.302 MB. [2023-04-18T22:05:58.317Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1615.626 ms) ====== [2023-04-18T22:05:58.317Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2023-04-18T22:05:58.317Z] GC before operation: completed in 108.255 ms, heap usage 292.394 MB -> 113.483 MB. [2023-04-18T22:06:00.031Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1681.768 ms) ====== [2023-04-18T22:06:00.031Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2023-04-18T22:06:00.031Z] GC before operation: completed in 101.220 ms, heap usage 291.273 MB -> 113.762 MB. [2023-04-18T22:06:01.734Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1546.146 ms) ====== [2023-04-18T22:06:01.734Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2023-04-18T22:06:01.734Z] GC before operation: completed in 103.847 ms, heap usage 304.248 MB -> 113.898 MB. [2023-04-18T22:06:03.441Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1588.227 ms) ====== [2023-04-18T22:06:03.442Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2023-04-18T22:06:03.442Z] GC before operation: completed in 95.629 ms, heap usage 299.418 MB -> 113.980 MB. [2023-04-18T22:06:05.145Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1528.748 ms) ====== [2023-04-18T22:06:05.145Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2023-04-18T22:06:05.145Z] GC before operation: completed in 96.667 ms, heap usage 302.232 MB -> 114.199 MB. [2023-04-18T22:06:06.392Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1436.520 ms) ====== [2023-04-18T22:06:06.733Z] ----------------------------------- [2023-04-18T22:06:06.733Z] renaissance-log-regression_0_PASSED [2023-04-18T22:06:06.733Z] ----------------------------------- [2023-04-18T22:06:06.733Z] [2023-04-18T22:06:06.733Z] TEST TEARDOWN: [2023-04-18T22:06:06.733Z] Nothing to be done for teardown. [2023-04-18T22:06:06.733Z] renaissance-log-regression_0 Finish Time: Tue Apr 18 22:06:06 2023 Epoch Time (ms): 1681855566641