[2024-09-05T21:08:14.319Z] Running test renaissance-log-regression_0 ... [2024-09-05T21:08:14.319Z] =============================================== [2024-09-05T21:08:14.319Z] renaissance-log-regression_0 Start Time: Thu Sep 5 21:08:13 2024 Epoch Time (ms): 1725570493957 [2024-09-05T21:08:14.319Z] variation: NoOptions [2024-09-05T21:08:14.319Z] JVM_OPTIONS: [2024-09-05T21:08:14.319Z] { \ [2024-09-05T21:08:14.319Z] echo ""; echo "TEST SETUP:"; \ [2024-09-05T21:08:14.319Z] echo "Nothing to be done for setup."; \ [2024-09-05T21:08:14.319Z] mkdir -p "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17255696153005/renaissance-log-regression_0"; \ [2024-09-05T21:08:14.319Z] cd "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17255696153005/renaissance-log-regression_0"; \ [2024-09-05T21:08:14.319Z] echo ""; echo "TESTING:"; \ [2024-09-05T21:08:14.319Z] "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/jdkbinary/j2sdk-image/bin/java" -jar "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17255696153005/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-09-05T21:08:14.319Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17255696153005/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-05T21:08:14.319Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-05T21:08:14.319Z] echo "Nothing to be done for teardown."; \ [2024-09-05T21:08:14.319Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17255696153005/TestTargetResult"; [2024-09-05T21:08:14.319Z] [2024-09-05T21:08:14.319Z] TEST SETUP: [2024-09-05T21:08:14.319Z] Nothing to be done for setup. [2024-09-05T21:08:14.319Z] [2024-09-05T21:08:14.319Z] TESTING: [2024-09-05T21:08:19.815Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-05T21:08:23.182Z] NOTE: 'log-regression' benchmark uses Spark local executor with 12 (out of 24) threads. [2024-09-05T21:08:29.927Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-09-05T21:08:29.927Z] GC before operation: completed in 270.110 ms, heap usage 91.879 MB -> 26.552 MB. [2024-09-05T21:08:43.698Z] ====== log-regression (apache-spark) [default], iteration 0 completed (13276.133 ms) ====== [2024-09-05T21:08:43.698Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-09-05T21:08:43.698Z] GC before operation: completed in 408.862 ms, heap usage 244.099 MB -> 102.236 MB. [2024-09-05T21:08:47.054Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2790.907 ms) ====== [2024-09-05T21:08:47.055Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-09-05T21:08:47.055Z] GC before operation: completed in 398.942 ms, heap usage 327.809 MB -> 103.505 MB. [2024-09-05T21:08:49.472Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2830.026 ms) ====== [2024-09-05T21:08:49.472Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-09-05T21:08:50.229Z] GC before operation: completed in 277.304 ms, heap usage 229.540 MB -> 103.134 MB. [2024-09-05T21:08:52.660Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2516.026 ms) ====== [2024-09-05T21:08:52.660Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-09-05T21:08:52.660Z] GC before operation: completed in 252.494 ms, heap usage 273.755 MB -> 104.048 MB. [2024-09-05T21:08:55.075Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2394.485 ms) ====== [2024-09-05T21:08:55.075Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-09-05T21:08:55.075Z] GC before operation: completed in 206.166 ms, heap usage 350.982 MB -> 105.298 MB. [2024-09-05T21:08:57.499Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2304.906 ms) ====== [2024-09-05T21:08:57.499Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-09-05T21:08:58.251Z] GC before operation: completed in 284.541 ms, heap usage 232.461 MB -> 104.622 MB. [2024-09-05T21:09:00.669Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2266.104 ms) ====== [2024-09-05T21:09:00.669Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-09-05T21:09:00.669Z] GC before operation: completed in 214.984 ms, heap usage 175.780 MB -> 105.196 MB. [2024-09-05T21:09:03.094Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2221.325 ms) ====== [2024-09-05T21:09:03.095Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-09-05T21:09:03.095Z] GC before operation: completed in 254.883 ms, heap usage 385.806 MB -> 106.957 MB. [2024-09-05T21:09:05.508Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2137.941 ms) ====== [2024-09-05T21:09:05.508Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-09-05T21:09:05.508Z] GC before operation: completed in 260.116 ms, heap usage 388.023 MB -> 107.436 MB. [2024-09-05T21:09:07.921Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2118.982 ms) ====== [2024-09-05T21:09:07.921Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-09-05T21:09:07.921Z] GC before operation: completed in 293.185 ms, heap usage 399.165 MB -> 107.974 MB. [2024-09-05T21:09:09.473Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2060.335 ms) ====== [2024-09-05T21:09:09.473Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-09-05T21:09:10.229Z] GC before operation: completed in 195.676 ms, heap usage 399.406 MB -> 108.478 MB. [2024-09-05T21:09:11.786Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2059.575 ms) ====== [2024-09-05T21:09:11.786Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-09-05T21:09:12.537Z] GC before operation: completed in 296.090 ms, heap usage 401.009 MB -> 108.991 MB. [2024-09-05T21:09:14.090Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2040.364 ms) ====== [2024-09-05T21:09:14.090Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-09-05T21:09:14.841Z] GC before operation: completed in 170.034 ms, heap usage 187.518 MB -> 107.372 MB. [2024-09-05T21:09:16.435Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1961.024 ms) ====== [2024-09-05T21:09:16.435Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-09-05T21:09:16.435Z] GC before operation: completed in 218.617 ms, heap usage 412.624 MB -> 110.015 MB. [2024-09-05T21:09:18.848Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1956.665 ms) ====== [2024-09-05T21:09:18.848Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-09-05T21:09:18.848Z] GC before operation: completed in 281.833 ms, heap usage 287.872 MB -> 109.028 MB. [2024-09-05T21:09:21.268Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1974.623 ms) ====== [2024-09-05T21:09:21.268Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-09-05T21:09:21.268Z] GC before operation: completed in 233.868 ms, heap usage 345.283 MB -> 110.157 MB. [2024-09-05T21:09:22.827Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1872.573 ms) ====== [2024-09-05T21:09:22.827Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-09-05T21:09:23.578Z] GC before operation: completed in 241.760 ms, heap usage 223.506 MB -> 109.554 MB. [2024-09-05T21:09:25.145Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1851.071 ms) ====== [2024-09-05T21:09:25.145Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-09-05T21:09:25.145Z] GC before operation: completed in 229.335 ms, heap usage 338.342 MB -> 111.034 MB. [2024-09-05T21:09:27.145Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1883.537 ms) ====== [2024-09-05T21:09:27.145Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-09-05T21:09:27.145Z] GC before operation: completed in 209.501 ms, heap usage 260.836 MB -> 110.572 MB. [2024-09-05T21:09:29.560Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1973.481 ms) ====== [2024-09-05T21:09:30.309Z] ----------------------------------- [2024-09-05T21:09:30.309Z] renaissance-log-regression_0_PASSED [2024-09-05T21:09:30.309Z] ----------------------------------- [2024-09-05T21:09:30.309Z] [2024-09-05T21:09:30.309Z] TEST TEARDOWN: [2024-09-05T21:09:30.309Z] Nothing to be done for teardown. [2024-09-05T21:09:30.309Z] renaissance-log-regression_0 Finish Time: Thu Sep 5 21:09:29 2024 Epoch Time (ms): 1725570569721