[2024-10-29T22:50:32.763Z] Running test renaissance-log-regression_0 ... [2024-10-29T22:50:32.763Z] =============================================== [2024-10-29T22:50:32.763Z] renaissance-log-regression_0 Start Time: Tue Oct 29 22:50:32 2024 Epoch Time (ms): 1730242232518 [2024-10-29T22:50:32.763Z] variation: NoOptions [2024-10-29T22:50:32.763Z] JVM_OPTIONS: [2024-10-29T22:50:32.763Z] { \ [2024-10-29T22:50:32.763Z] echo ""; echo "TEST SETUP:"; \ [2024-10-29T22:50:32.763Z] echo "Nothing to be done for setup."; \ [2024-10-29T22:50:32.763Z] mkdir -p "/export/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_solaris/aqa-tests/TKG/../TKG/output_17302403458406/renaissance-log-regression_0"; \ [2024-10-29T22:50:32.763Z] cd "/export/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_solaris/aqa-tests/TKG/../TKG/output_17302403458406/renaissance-log-regression_0"; \ [2024-10-29T22:50:32.763Z] echo ""; echo "TESTING:"; \ [2024-10-29T22:50:32.763Z] "/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_17302403458406/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-10-29T22:50:32.763Z] 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_17302403458406/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-10-29T22:50:32.763Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-10-29T22:50:32.763Z] echo "Nothing to be done for teardown."; \ [2024-10-29T22:50:32.763Z] } 2>&1 | tee -a "/export/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_solaris/aqa-tests/TKG/../TKG/output_17302403458406/TestTargetResult"; [2024-10-29T22:50:32.763Z] [2024-10-29T22:50:32.763Z] TEST SETUP: [2024-10-29T22:50:32.763Z] Nothing to be done for setup. [2024-10-29T22:50:32.763Z] [2024-10-29T22:50:32.763Z] TESTING: [2024-10-29T22:50:41.536Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-10-29T22:50:47.649Z] NOTE: 'log-regression' benchmark uses Spark local executor with 1 (out of 1) threads. [2024-10-29T22:50:56.391Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-10-29T22:50:56.391Z] GC before operation: completed in 185.725 ms, heap usage 44.914 MB -> 23.530 MB. [2024-10-29T22:51:21.464Z] ====== log-regression (apache-spark) [default], iteration 0 completed (23947.503 ms) ====== [2024-10-29T22:51:21.464Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-10-29T22:51:21.464Z] GC before operation: completed in 349.491 ms, heap usage 195.766 MB -> 108.818 MB. [2024-10-29T22:51:23.541Z] 24/10/29 22:51:23 WARN BlockManager: Asked to remove block broadcast_65, which does not exist [2024-10-29T22:51:25.883Z] ====== log-regression (apache-spark) [default], iteration 1 completed (5240.728 ms) ====== [2024-10-29T22:51:25.883Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-10-29T22:51:25.883Z] GC before operation: completed in 252.903 ms, heap usage 181.890 MB -> 108.096 MB. [2024-10-29T22:51:30.855Z] ====== log-regression (apache-spark) [default], iteration 2 completed (5077.751 ms) ====== [2024-10-29T22:51:30.855Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-10-29T22:51:30.855Z] GC before operation: completed in 306.180 ms, heap usage 241.059 MB -> 102.005 MB. [2024-10-29T22:51:34.829Z] ====== log-regression (apache-spark) [default], iteration 3 completed (4027.338 ms) ====== [2024-10-29T22:51:34.829Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-10-29T22:51:35.463Z] GC before operation: completed in 226.348 ms, heap usage 190.505 MB -> 105.886 MB. [2024-10-29T22:51:40.291Z] ====== log-regression (apache-spark) [default], iteration 4 completed (4480.265 ms) ====== [2024-10-29T22:51:40.291Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-10-29T22:51:40.291Z] GC before operation: completed in 311.957 ms, heap usage 189.682 MB -> 107.845 MB. [2024-10-29T22:51:43.966Z] ====== log-regression (apache-spark) [default], iteration 5 completed (4147.925 ms) ====== [2024-10-29T22:51:43.966Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-10-29T22:51:44.608Z] GC before operation: completed in 342.353 ms, heap usage 196.438 MB -> 109.683 MB. [2024-10-29T22:51:48.453Z] ====== log-regression (apache-spark) [default], iteration 6 completed (3870.701 ms) ====== [2024-10-29T22:51:48.453Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-10-29T22:51:48.453Z] GC before operation: completed in 297.431 ms, heap usage 197.346 MB -> 101.992 MB. [2024-10-29T22:51:52.262Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3511.228 ms) ====== [2024-10-29T22:51:52.262Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-10-29T22:51:52.262Z] GC before operation: completed in 229.245 ms, heap usage 186.064 MB -> 111.251 MB. [2024-10-29T22:51:56.069Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3229.292 ms) ====== [2024-10-29T22:51:56.069Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-10-29T22:51:56.069Z] GC before operation: completed in 247.921 ms, heap usage 198.328 MB -> 113.115 MB. [2024-10-29T22:51:59.443Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3179.787 ms) ====== [2024-10-29T22:51:59.443Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-10-29T22:51:59.443Z] GC before operation: completed in 262.153 ms, heap usage 196.010 MB -> 111.930 MB. [2024-10-29T22:52:03.237Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3566.562 ms) ====== [2024-10-29T22:52:03.237Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-10-29T22:52:03.237Z] GC before operation: completed in 350.286 ms, heap usage 195.724 MB -> 103.185 MB. [2024-10-29T22:52:06.121Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3124.804 ms) ====== [2024-10-29T22:52:06.121Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-10-29T22:52:06.810Z] GC before operation: completed in 256.665 ms, heap usage 186.628 MB -> 112.520 MB. [2024-10-29T22:52:09.731Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3241.891 ms) ====== [2024-10-29T22:52:09.731Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-10-29T22:52:10.356Z] GC before operation: completed in 334.509 ms, heap usage 196.027 MB -> 112.641 MB. [2024-10-29T22:52:13.232Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3292.211 ms) ====== [2024-10-29T22:52:13.232Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-10-29T22:52:13.880Z] GC before operation: completed in 235.866 ms, heap usage 196.391 MB -> 112.887 MB. [2024-10-29T22:52:16.750Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3206.748 ms) ====== [2024-10-29T22:52:16.750Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-10-29T22:52:17.394Z] GC before operation: completed in 302.041 ms, heap usage 196.553 MB -> 103.718 MB. [2024-10-29T22:52:20.279Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3281.637 ms) ====== [2024-10-29T22:52:20.279Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-10-29T22:52:20.905Z] GC before operation: completed in 252.737 ms, heap usage 187.704 MB -> 112.972 MB. [2024-10-29T22:52:23.815Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3010.027 ms) ====== [2024-10-29T22:52:23.815Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-10-29T22:52:23.815Z] GC before operation: completed in 244.699 ms, heap usage 196.835 MB -> 113.459 MB. [2024-10-29T22:52:27.603Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3109.082 ms) ====== [2024-10-29T22:52:27.603Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-10-29T22:52:27.603Z] GC before operation: completed in 252.124 ms, heap usage 184.343 MB -> 113.765 MB. [2024-10-29T22:52:29.694Z] 24/10/29 22:52:29 WARN BlockManager: Asked to remove block broadcast_868_piece0, which does not exist [2024-10-29T22:52:29.694Z] 24/10/29 22:52:29 WARN BlockManager: Asked to remove block broadcast_868, which does not exist [2024-10-29T22:52:30.386Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2994.292 ms) ====== [2024-10-29T22:52:30.386Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-10-29T22:52:30.386Z] GC before operation: completed in 297.110 ms, heap usage 184.200 MB -> 104.832 MB. [2024-10-29T22:52:34.344Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3234.976 ms) ====== [2024-10-29T22:52:34.970Z] ----------------------------------- [2024-10-29T22:52:34.971Z] renaissance-log-regression_0_PASSED [2024-10-29T22:52:34.971Z] ----------------------------------- [2024-10-29T22:52:34.971Z] [2024-10-29T22:52:34.971Z] TEST TEARDOWN: [2024-10-29T22:52:34.971Z] Nothing to be done for teardown. [2024-10-29T22:52:34.971Z] renaissance-log-regression_0 Finish Time: Tue Oct 29 22:52:34 2024 Epoch Time (ms): 1730242354894