[2024-11-17T07:25:22.817Z] Running test renaissance-dec-tree_0 ... [2024-11-17T07:25:22.817Z] =============================================== [2024-11-17T07:25:22.817Z] renaissance-dec-tree_0 Start Time: Sun Nov 17 07:25:22 2024 Epoch Time (ms): 1731828322162 [2024-11-17T07:25:22.817Z] variation: NoOptions [2024-11-17T07:25:22.817Z] JVM_OPTIONS: [2024-11-17T07:25:22.817Z] { \ [2024-11-17T07:25:22.817Z] echo ""; echo "TEST SETUP:"; \ [2024-11-17T07:25:22.817Z] echo "Nothing to be done for setup."; \ [2024-11-17T07:25:22.817Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17318272656887/renaissance-dec-tree_0"; \ [2024-11-17T07:25:22.817Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17318272656887/renaissance-dec-tree_0"; \ [2024-11-17T07:25:22.817Z] echo ""; echo "TESTING:"; \ [2024-11-17T07:25:22.817Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/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 "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17318272656887/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-11-17T07:25:22.817Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17318272656887/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-17T07:25:22.817Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-17T07:25:22.817Z] echo "Nothing to be done for teardown."; \ [2024-11-17T07:25:22.817Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17318272656887/TestTargetResult"; [2024-11-17T07:25:22.817Z] [2024-11-17T07:25:22.817Z] TEST SETUP: [2024-11-17T07:25:22.817Z] Nothing to be done for setup. [2024-11-17T07:25:22.817Z] [2024-11-17T07:25:22.817Z] TESTING: [2024-11-17T07:25:28.772Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-17T07:25:34.719Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-11-17T07:25:37.363Z] WARNING: An illegal reflective access operation has occurred [2024-11-17T07:25:37.363Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/output_17318272656887/renaissance-dec-tree_0/harness-072523-15782884004249886386/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-11-17T07:25:37.363Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-11-17T07:25:37.363Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-11-17T07:25:37.363Z] WARNING: All illegal access operations will be denied in a future release [2024-11-17T07:25:43.343Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-11-17T07:25:43.343Z] GC before operation: completed in 161.419 ms, heap usage 92.752 MB -> 33.766 MB. [2024-11-17T07:26:10.082Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (25769.338 ms) ====== [2024-11-17T07:26:10.082Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-11-17T07:26:10.082Z] GC before operation: completed in 321.461 ms, heap usage 99.565 MB -> 64.089 MB. [2024-11-17T07:26:20.526Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (10288.413 ms) ====== [2024-11-17T07:26:20.527Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-11-17T07:26:20.527Z] GC before operation: completed in 407.414 ms, heap usage 223.419 MB -> 64.156 MB. [2024-11-17T07:26:27.839Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (7915.694 ms) ====== [2024-11-17T07:26:27.839Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-11-17T07:26:28.685Z] GC before operation: completed in 318.933 ms, heap usage 129.344 MB -> 64.375 MB. [2024-11-17T07:26:36.027Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (7071.107 ms) ====== [2024-11-17T07:26:36.027Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-11-17T07:26:36.027Z] GC before operation: completed in 398.732 ms, heap usage 250.333 MB -> 64.830 MB. [2024-11-17T07:26:43.405Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (6581.618 ms) ====== [2024-11-17T07:26:43.405Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-11-17T07:26:43.405Z] GC before operation: completed in 424.308 ms, heap usage 274.882 MB -> 75.783 MB. [2024-11-17T07:26:49.414Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (6099.216 ms) ====== [2024-11-17T07:26:49.414Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-11-17T07:26:49.414Z] GC before operation: completed in 307.987 ms, heap usage 228.309 MB -> 65.014 MB. [2024-11-17T07:26:55.445Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (5485.266 ms) ====== [2024-11-17T07:26:55.445Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-11-17T07:26:55.445Z] GC before operation: completed in 431.289 ms, heap usage 265.120 MB -> 65.326 MB. [2024-11-17T07:27:00.836Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (5043.085 ms) ====== [2024-11-17T07:27:00.836Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-11-17T07:27:00.836Z] GC before operation: completed in 307.428 ms, heap usage 107.777 MB -> 64.896 MB. [2024-11-17T07:27:06.829Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (5667.183 ms) ====== [2024-11-17T07:27:06.829Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-11-17T07:27:06.829Z] GC before operation: completed in 353.169 ms, heap usage 222.176 MB -> 65.296 MB. [2024-11-17T07:27:12.823Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (5607.170 ms) ====== [2024-11-17T07:27:12.823Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-11-17T07:27:12.823Z] GC before operation: completed in 387.759 ms, heap usage 239.523 MB -> 70.823 MB. [2024-11-17T07:27:17.607Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (5220.504 ms) ====== [2024-11-17T07:27:17.607Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-11-17T07:27:18.460Z] GC before operation: completed in 377.859 ms, heap usage 176.959 MB -> 65.384 MB. [2024-11-17T07:27:23.247Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (4484.750 ms) ====== [2024-11-17T07:27:23.247Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-11-17T07:27:23.247Z] GC before operation: completed in 413.546 ms, heap usage 240.351 MB -> 65.821 MB. [2024-11-17T07:27:28.053Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (5166.675 ms) ====== [2024-11-17T07:27:28.053Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-11-17T07:27:28.877Z] GC before operation: completed in 286.670 ms, heap usage 132.806 MB -> 65.712 MB. [2024-11-17T07:27:32.530Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (4002.722 ms) ====== [2024-11-17T07:27:32.530Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-11-17T07:27:33.355Z] GC before operation: completed in 354.182 ms, heap usage 251.813 MB -> 65.988 MB. [2024-11-17T07:27:38.198Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (5026.032 ms) ====== [2024-11-17T07:27:38.198Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-11-17T07:27:38.198Z] GC before operation: completed in 348.729 ms, heap usage 218.645 MB -> 66.143 MB. [2024-11-17T07:27:42.968Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (4410.016 ms) ====== [2024-11-17T07:27:42.968Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-11-17T07:27:42.968Z] GC before operation: completed in 462.288 ms, heap usage 121.067 MB -> 66.238 MB. [2024-11-17T07:27:47.736Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (4328.107 ms) ====== [2024-11-17T07:27:47.736Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-11-17T07:27:47.736Z] GC before operation: completed in 380.166 ms, heap usage 219.616 MB -> 66.348 MB. [2024-11-17T07:27:52.642Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (4332.498 ms) ====== [2024-11-17T07:27:52.642Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-11-17T07:27:52.642Z] GC before operation: completed in 303.312 ms, heap usage 223.697 MB -> 66.462 MB. [2024-11-17T07:27:57.412Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (4310.961 ms) ====== [2024-11-17T07:27:57.412Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-11-17T07:27:57.412Z] GC before operation: completed in 376.658 ms, heap usage 303.820 MB -> 66.812 MB. [2024-11-17T07:28:01.077Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (3763.112 ms) ====== [2024-11-17T07:28:01.077Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-11-17T07:28:01.077Z] 24/11/17 07:28:00 WARN BlockManager: Asked to remove block broadcast_274, which does not exist [2024-11-17T07:28:01.078Z] GC before operation: completed in 341.361 ms, heap usage 170.115 MB -> 66.757 MB. [2024-11-17T07:28:05.850Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (3789.758 ms) ====== [2024-11-17T07:28:05.850Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-11-17T07:28:05.850Z] GC before operation: completed in 422.565 ms, heap usage 196.351 MB -> 66.769 MB. [2024-11-17T07:28:09.526Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (3812.790 ms) ====== [2024-11-17T07:28:09.526Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-11-17T07:28:09.526Z] GC before operation: completed in 398.202 ms, heap usage 244.323 MB -> 66.895 MB. [2024-11-17T07:28:13.179Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (3362.303 ms) ====== [2024-11-17T07:28:13.179Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-11-17T07:28:14.010Z] GC before operation: completed in 402.602 ms, heap usage 229.452 MB -> 66.994 MB. [2024-11-17T07:28:17.683Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (3929.908 ms) ====== [2024-11-17T07:28:17.683Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-11-17T07:28:17.683Z] GC before operation: completed in 392.047 ms, heap usage 188.267 MB -> 72.525 MB. [2024-11-17T07:28:21.346Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (3401.322 ms) ====== [2024-11-17T07:28:21.346Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-11-17T07:28:22.176Z] GC before operation: completed in 435.229 ms, heap usage 285.087 MB -> 83.750 MB. [2024-11-17T07:28:26.446Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (4019.403 ms) ====== [2024-11-17T07:28:26.446Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-11-17T07:28:26.446Z] GC before operation: completed in 377.500 ms, heap usage 159.155 MB -> 67.006 MB. [2024-11-17T07:28:30.110Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (3601.711 ms) ====== [2024-11-17T07:28:30.110Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-11-17T07:28:30.110Z] GC before operation: completed in 351.359 ms, heap usage 109.606 MB -> 83.184 MB. [2024-11-17T07:28:33.773Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (3518.630 ms) ====== [2024-11-17T07:28:33.773Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-11-17T07:28:33.773Z] GC before operation: completed in 355.125 ms, heap usage 241.192 MB -> 67.515 MB. [2024-11-17T07:28:37.429Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (3646.745 ms) ====== [2024-11-17T07:28:37.429Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-11-17T07:28:38.267Z] GC before operation: completed in 410.649 ms, heap usage 111.767 MB -> 67.192 MB. [2024-11-17T07:28:42.014Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (3901.251 ms) ====== [2024-11-17T07:28:42.014Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-11-17T07:28:42.014Z] GC before operation: completed in 307.790 ms, heap usage 229.964 MB -> 67.711 MB. [2024-11-17T07:28:46.785Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (3883.825 ms) ====== [2024-11-17T07:28:46.785Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-11-17T07:28:46.785Z] GC before operation: completed in 447.449 ms, heap usage 258.872 MB -> 67.806 MB. [2024-11-17T07:28:50.437Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (3526.227 ms) ====== [2024-11-17T07:28:50.437Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-11-17T07:28:50.437Z] GC before operation: completed in 364.399 ms, heap usage 268.369 MB -> 67.775 MB. [2024-11-17T07:28:54.113Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (3547.870 ms) ====== [2024-11-17T07:28:54.114Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-11-17T07:28:54.950Z] GC before operation: completed in 404.190 ms, heap usage 247.271 MB -> 68.001 MB. [2024-11-17T07:28:58.621Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (3549.030 ms) ====== [2024-11-17T07:28:58.621Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-11-17T07:28:58.621Z] GC before operation: completed in 325.899 ms, heap usage 130.071 MB -> 67.799 MB. [2024-11-17T07:29:02.275Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (3615.655 ms) ====== [2024-11-17T07:29:02.275Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-11-17T07:29:02.275Z] GC before operation: completed in 405.790 ms, heap usage 194.315 MB -> 68.189 MB. [2024-11-17T07:29:05.947Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (3608.522 ms) ====== [2024-11-17T07:29:05.947Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-11-17T07:29:06.773Z] GC before operation: completed in 382.492 ms, heap usage 298.851 MB -> 68.215 MB. [2024-11-17T07:29:10.423Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (3720.056 ms) ====== [2024-11-17T07:29:10.423Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-11-17T07:29:10.423Z] GC before operation: completed in 392.116 ms, heap usage 149.421 MB -> 68.393 MB. [2024-11-17T07:29:14.097Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (3711.870 ms) ====== [2024-11-17T07:29:14.097Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-11-17T07:29:14.930Z] GC before operation: completed in 431.060 ms, heap usage 201.324 MB -> 68.476 MB. [2024-11-17T07:29:18.636Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (3903.246 ms) ====== [2024-11-17T07:29:18.636Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-11-17T07:29:19.458Z] GC before operation: completed in 450.076 ms, heap usage 230.062 MB -> 68.567 MB. [2024-11-17T07:29:23.086Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (3801.695 ms) ====== [2024-11-17T07:29:23.086Z] ----------------------------------- [2024-11-17T07:29:23.086Z] renaissance-dec-tree_0_PASSED [2024-11-17T07:29:23.086Z] ----------------------------------- [2024-11-17T07:29:23.086Z] [2024-11-17T07:29:23.086Z] TEST TEARDOWN: [2024-11-17T07:29:23.086Z] Nothing to be done for teardown. [2024-11-17T07:29:23.086Z] renaissance-dec-tree_0 Finish Time: Sun Nov 17 07:29:22 2024 Epoch Time (ms): 1731828562984