[2024-09-05T21:34:14.354Z] Running test renaissance-log-regression_0 ... [2024-09-05T21:34:14.354Z] =============================================== [2024-09-05T21:34:14.354Z] renaissance-log-regression_0 Start Time: Thu Sep 5 22:34:13 2024 Epoch Time (ms): 1725572053826 [2024-09-05T21:34:14.354Z] variation: NoOptions [2024-09-05T21:34:14.354Z] JVM_OPTIONS: [2024-09-05T21:34:14.354Z] { \ [2024-09-05T21:34:14.354Z] echo ""; echo "TEST SETUP:"; \ [2024-09-05T21:34:14.354Z] echo "Nothing to be done for setup."; \ [2024-09-05T21:34:14.354Z] mkdir -p "/export/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_solaris/aqa-tests/TKG/../TKG/output_17255707848343/renaissance-log-regression_0"; \ [2024-09-05T21:34:14.354Z] cd "/export/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_solaris/aqa-tests/TKG/../TKG/output_17255707848343/renaissance-log-regression_0"; \ [2024-09-05T21:34:14.354Z] echo ""; echo "TESTING:"; \ [2024-09-05T21:34:14.354Z] "/export/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_solaris/jdkbinary/j2sdk-image/bin/java" -jar "/export/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_solaris/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/export/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_solaris/aqa-tests/TKG/../TKG/output_17255707848343/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-09-05T21:34:14.354Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /export/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_solaris/aqa-tests/TKG/..; rm -f -r "/export/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_solaris/aqa-tests/TKG/../TKG/output_17255707848343/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-05T21:34:14.354Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-05T21:34:14.354Z] echo "Nothing to be done for teardown."; \ [2024-09-05T21:34:14.354Z] } 2>&1 | tee -a "/export/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_solaris/aqa-tests/TKG/../TKG/output_17255707848343/TestTargetResult"; [2024-09-05T21:34:14.354Z] [2024-09-05T21:34:14.354Z] TEST SETUP: [2024-09-05T21:34:14.354Z] Nothing to be done for setup. [2024-09-05T21:34:14.354Z] [2024-09-05T21:34:14.354Z] TESTING: [2024-09-05T21:34:21.552Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-05T21:34:24.419Z] NOTE: 'log-regression' benchmark uses Spark local executor with 1 (out of 1) threads. [2024-09-05T21:34:30.332Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-09-05T21:34:30.332Z] GC before operation: completed in 92.254 ms, heap usage 41.373 MB -> 22.705 MB. [2024-09-05T21:34:48.195Z] ====== log-regression (apache-spark) [default], iteration 0 completed (16701.292 ms) ====== [2024-09-05T21:34:48.195Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-09-05T21:34:48.195Z] GC before operation: completed in 186.197 ms, heap usage 230.854 MB -> 118.122 MB. [2024-09-05T21:34:50.297Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3407.731 ms) ====== [2024-09-05T21:34:50.297Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-09-05T21:34:50.297Z] GC before operation: completed in 152.298 ms, heap usage 245.744 MB -> 114.307 MB. [2024-09-05T21:34:53.746Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3371.329 ms) ====== [2024-09-05T21:34:53.746Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-09-05T21:34:53.746Z] GC before operation: completed in 206.737 ms, heap usage 183.667 MB -> 100.723 MB. [2024-09-05T21:34:56.623Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2748.607 ms) ====== [2024-09-05T21:34:56.623Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-09-05T21:34:56.623Z] GC before operation: completed in 149.289 ms, heap usage 169.203 MB -> 106.019 MB. [2024-09-05T21:34:58.691Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2140.011 ms) ====== [2024-09-05T21:34:58.691Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-09-05T21:34:59.316Z] GC before operation: completed in 142.476 ms, heap usage 174.079 MB -> 111.044 MB. [2024-09-05T21:35:02.257Z] ====== log-regression (apache-spark) [default], iteration 5 completed (3014.724 ms) ====== [2024-09-05T21:35:02.257Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-09-05T21:35:02.257Z] GC before operation: completed in 150.034 ms, heap usage 179.661 MB -> 111.450 MB. [2024-09-05T21:35:05.122Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2418.966 ms) ====== [2024-09-05T21:35:05.122Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-09-05T21:35:05.122Z] GC before operation: completed in 166.485 ms, heap usage 179.287 MB -> 101.766 MB. [2024-09-05T21:35:07.196Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2422.943 ms) ====== [2024-09-05T21:35:07.196Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-09-05T21:35:07.196Z] GC before operation: completed in 160.205 ms, heap usage 169.718 MB -> 106.868 MB. [2024-09-05T21:35:10.101Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2532.373 ms) ====== [2024-09-05T21:35:10.101Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-09-05T21:35:10.101Z] GC before operation: completed in 146.805 ms, heap usage 174.486 MB -> 112.094 MB. [2024-09-05T21:35:12.192Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2385.428 ms) ====== [2024-09-05T21:35:12.192Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-09-05T21:35:12.820Z] GC before operation: completed in 151.543 ms, heap usage 179.764 MB -> 112.390 MB. [2024-09-05T21:35:14.887Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2250.465 ms) ====== [2024-09-05T21:35:14.887Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-09-05T21:35:14.887Z] GC before operation: completed in 190.082 ms, heap usage 180.143 MB -> 102.637 MB. [2024-09-05T21:35:17.836Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2407.829 ms) ====== [2024-09-05T21:35:17.836Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-09-05T21:35:17.836Z] GC before operation: completed in 151.087 ms, heap usage 170.527 MB -> 108.050 MB. [2024-09-05T21:35:19.940Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2300.436 ms) ====== [2024-09-05T21:35:19.940Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-09-05T21:35:19.941Z] GC before operation: completed in 147.307 ms, heap usage 175.521 MB -> 112.981 MB. [2024-09-05T21:35:22.161Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2139.649 ms) ====== [2024-09-05T21:35:22.161Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-09-05T21:35:22.161Z] GC before operation: completed in 190.454 ms, heap usage 179.903 MB -> 113.339 MB. [2024-09-05T21:35:24.230Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2124.663 ms) ====== [2024-09-05T21:35:24.230Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-09-05T21:35:24.856Z] GC before operation: completed in 185.568 ms, heap usage 181.830 MB -> 103.522 MB. [2024-09-05T21:35:26.555Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2022.776 ms) ====== [2024-09-05T21:35:26.555Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-09-05T21:35:27.183Z] GC before operation: completed in 153.641 ms, heap usage 171.202 MB -> 108.629 MB. [2024-09-05T21:35:29.312Z] ====== log-regression (apache-spark) [default], iteration 16 completed (2353.551 ms) ====== [2024-09-05T21:35:29.312Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-09-05T21:35:29.312Z] GC before operation: completed in 150.484 ms, heap usage 175.668 MB -> 113.763 MB. [2024-09-05T21:35:31.403Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2093.066 ms) ====== [2024-09-05T21:35:31.403Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-09-05T21:35:31.403Z] GC before operation: completed in 151.948 ms, heap usage 180.404 MB -> 114.413 MB. [2024-09-05T21:35:33.599Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2095.260 ms) ====== [2024-09-05T21:35:33.599Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-09-05T21:35:34.229Z] GC before operation: completed in 235.349 ms, heap usage 182.168 MB -> 168.640 MB. [2024-09-05T21:35:36.539Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2171.046 ms) ====== [2024-09-05T21:35:36.539Z] ----------------------------------- [2024-09-05T21:35:36.539Z] renaissance-log-regression_0_PASSED [2024-09-05T21:35:36.539Z] ----------------------------------- [2024-09-05T21:35:36.539Z] [2024-09-05T21:35:36.539Z] TEST TEARDOWN: [2024-09-05T21:35:36.539Z] Nothing to be done for teardown. [2024-09-05T21:35:36.539Z] renaissance-log-regression_0 Finish Time: Thu Sep 5 22:35:36 2024 Epoch Time (ms): 1725572136371