[2025-02-27T06:25:51.040Z] Running test renaissance-dec-tree_0 ... [2025-02-27T06:25:51.040Z] =============================================== [2025-02-27T06:25:51.040Z] renaissance-dec-tree_0 Start Time: Thu Feb 27 06:25:50 2025 Epoch Time (ms): 1740637550645 [2025-02-27T06:25:51.040Z] variation: NoOptions [2025-02-27T06:25:51.040Z] JVM_OPTIONS: [2025-02-27T06:25:51.040Z] { \ [2025-02-27T06:25:51.040Z] echo ""; echo "TEST SETUP:"; \ [2025-02-27T06:25:51.040Z] echo "Nothing to be done for setup."; \ [2025-02-27T06:25:51.040Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17406366637941/renaissance-dec-tree_0"; \ [2025-02-27T06:25:51.040Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17406366637941/renaissance-dec-tree_0"; \ [2025-02-27T06:25:51.040Z] echo ""; echo "TESTING:"; \ [2025-02-27T06:25:51.040Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_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_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17406366637941/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-02-27T06:25:51.040Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17406366637941/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-27T06:25:51.040Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-27T06:25:51.040Z] echo "Nothing to be done for teardown."; \ [2025-02-27T06:25:51.040Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17406366637941/TestTargetResult"; [2025-02-27T06:25:51.040Z] [2025-02-27T06:25:51.040Z] TEST SETUP: [2025-02-27T06:25:51.040Z] Nothing to be done for setup. [2025-02-27T06:25:51.040Z] [2025-02-27T06:25:51.040Z] TESTING: [2025-02-27T06:25:56.305Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-27T06:25:59.367Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-02-27T06:26:00.781Z] WARNING: An illegal reflective access operation has occurred [2025-02-27T06:26:00.781Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/output_17406366637941/renaissance-dec-tree_0/harness-062552-16804204178446793726/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-02-27T06:26:00.781Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-02-27T06:26:00.781Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-02-27T06:26:00.781Z] WARNING: All illegal access operations will be denied in a future release [2025-02-27T06:26:04.044Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-02-27T06:26:04.044Z] GC before operation: completed in 83.283 ms, heap usage 52.552 MB -> 33.714 MB. [2025-02-27T06:26:20.635Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (16150.942 ms) ====== [2025-02-27T06:26:20.636Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-02-27T06:26:20.636Z] GC before operation: completed in 228.552 ms, heap usage 213.815 MB -> 62.973 MB. [2025-02-27T06:26:27.372Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (6902.131 ms) ====== [2025-02-27T06:26:27.372Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-02-27T06:26:27.372Z] GC before operation: completed in 265.802 ms, heap usage 100.961 MB -> 63.089 MB. [2025-02-27T06:26:32.431Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (5015.800 ms) ====== [2025-02-27T06:26:32.431Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-02-27T06:26:32.431Z] GC before operation: completed in 190.224 ms, heap usage 106.602 MB -> 62.611 MB. [2025-02-27T06:26:36.494Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (4018.476 ms) ====== [2025-02-27T06:26:36.494Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-02-27T06:26:37.160Z] GC before operation: completed in 286.614 ms, heap usage 114.569 MB -> 62.838 MB. [2025-02-27T06:26:40.521Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (3477.939 ms) ====== [2025-02-27T06:26:40.521Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-02-27T06:26:40.521Z] GC before operation: completed in 264.027 ms, heap usage 106.779 MB -> 63.302 MB. [2025-02-27T06:26:43.546Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (3054.977 ms) ====== [2025-02-27T06:26:43.546Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-02-27T06:26:44.237Z] GC before operation: completed in 344.303 ms, heap usage 251.851 MB -> 64.132 MB. [2025-02-27T06:26:49.646Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (5869.808 ms) ====== [2025-02-27T06:26:49.646Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-02-27T06:26:50.430Z] GC before operation: completed in 498.877 ms, heap usage 165.263 MB -> 64.105 MB. [2025-02-27T06:26:54.583Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (3999.115 ms) ====== [2025-02-27T06:26:54.583Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-02-27T06:26:54.583Z] GC before operation: completed in 175.513 ms, heap usage 171.860 MB -> 64.361 MB. [2025-02-27T06:27:01.086Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (5836.342 ms) ====== [2025-02-27T06:27:01.086Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-02-27T06:27:01.086Z] GC before operation: completed in 371.100 ms, heap usage 150.701 MB -> 64.198 MB. [2025-02-27T06:27:05.516Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (4831.573 ms) ====== [2025-02-27T06:27:05.516Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-02-27T06:27:06.203Z] GC before operation: completed in 265.791 ms, heap usage 114.107 MB -> 64.061 MB. [2025-02-27T06:27:10.260Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (3821.590 ms) ====== [2025-02-27T06:27:10.260Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-02-27T06:27:10.260Z] GC before operation: completed in 249.357 ms, heap usage 197.899 MB -> 64.592 MB. [2025-02-27T06:27:13.333Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (3205.011 ms) ====== [2025-02-27T06:27:13.333Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-02-27T06:27:13.333Z] GC before operation: completed in 391.146 ms, heap usage 162.484 MB -> 92.153 MB. [2025-02-27T06:27:17.514Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (3579.783 ms) ====== [2025-02-27T06:27:17.514Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-02-27T06:27:17.514Z] GC before operation: completed in 528.708 ms, heap usage 200.181 MB -> 92.322 MB. [2025-02-27T06:27:21.579Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (3251.435 ms) ====== [2025-02-27T06:27:21.579Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-02-27T06:27:21.579Z] GC before operation: completed in 245.790 ms, heap usage 182.772 MB -> 64.488 MB. [2025-02-27T06:27:24.668Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (2966.015 ms) ====== [2025-02-27T06:27:24.668Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-02-27T06:27:24.668Z] GC before operation: completed in 229.890 ms, heap usage 198.104 MB -> 65.005 MB. [2025-02-27T06:27:27.697Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (3363.531 ms) ====== [2025-02-27T06:27:27.697Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-02-27T06:27:28.362Z] GC before operation: completed in 671.553 ms, heap usage 108.010 MB -> 64.881 MB. [2025-02-27T06:27:31.442Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (3298.922 ms) ====== [2025-02-27T06:27:31.442Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-02-27T06:27:32.096Z] GC before operation: completed in 277.817 ms, heap usage 245.430 MB -> 65.120 MB. [2025-02-27T06:27:35.633Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (3386.162 ms) ====== [2025-02-27T06:27:35.634Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-02-27T06:27:35.634Z] GC before operation: completed in 366.429 ms, heap usage 101.259 MB -> 65.060 MB. [2025-02-27T06:27:40.880Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (4374.089 ms) ====== [2025-02-27T06:27:40.880Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-02-27T06:27:40.880Z] GC before operation: completed in 347.073 ms, heap usage 220.891 MB -> 65.323 MB. [2025-02-27T06:27:46.149Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (5382.546 ms) ====== [2025-02-27T06:27:46.149Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-02-27T06:27:46.149Z] GC before operation: completed in 395.672 ms, heap usage 112.614 MB -> 65.134 MB. [2025-02-27T06:27:50.160Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (3327.019 ms) ====== [2025-02-27T06:27:50.160Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-02-27T06:27:50.160Z] GC before operation: completed in 303.046 ms, heap usage 246.711 MB -> 65.895 MB. [2025-02-27T06:27:54.087Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (4076.750 ms) ====== [2025-02-27T06:27:54.087Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-02-27T06:27:54.087Z] GC before operation: completed in 308.199 ms, heap usage 155.058 MB -> 65.358 MB. [2025-02-27T06:27:57.286Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (3201.887 ms) ====== [2025-02-27T06:27:57.286Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-02-27T06:27:58.046Z] GC before operation: completed in 514.091 ms, heap usage 208.090 MB -> 85.163 MB. [2025-02-27T06:28:01.187Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (3085.510 ms) ====== [2025-02-27T06:28:01.187Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-02-27T06:28:01.187Z] GC before operation: completed in 339.512 ms, heap usage 176.777 MB -> 65.560 MB. [2025-02-27T06:28:05.564Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (3162.869 ms) ====== [2025-02-27T06:28:05.564Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-02-27T06:28:05.564Z] GC before operation: completed in 288.269 ms, heap usage 160.015 MB -> 65.659 MB. [2025-02-27T06:28:07.863Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (3145.725 ms) ====== [2025-02-27T06:28:07.863Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-02-27T06:28:08.563Z] GC before operation: completed in 328.707 ms, heap usage 186.054 MB -> 66.011 MB. [2025-02-27T06:28:11.866Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (3586.139 ms) ====== [2025-02-27T06:28:11.866Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-02-27T06:28:12.543Z] GC before operation: completed in 247.665 ms, heap usage 98.842 MB -> 66.186 MB. [2025-02-27T06:28:15.589Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (3104.974 ms) ====== [2025-02-27T06:28:15.589Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-02-27T06:28:15.589Z] GC before operation: completed in 365.062 ms, heap usage 236.330 MB -> 66.199 MB. [2025-02-27T06:28:18.669Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (2903.373 ms) ====== [2025-02-27T06:28:18.669Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-02-27T06:28:18.669Z] GC before operation: completed in 304.705 ms, heap usage 223.431 MB -> 66.473 MB. [2025-02-27T06:28:22.856Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (3405.631 ms) ====== [2025-02-27T06:28:22.856Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-02-27T06:28:22.856Z] GC before operation: completed in 245.632 ms, heap usage 189.763 MB -> 66.417 MB. [2025-02-27T06:28:25.922Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (2911.167 ms) ====== [2025-02-27T06:28:25.922Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-02-27T06:28:25.922Z] GC before operation: completed in 515.032 ms, heap usage 104.662 MB -> 66.370 MB. [2025-02-27T06:28:28.992Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (2902.640 ms) ====== [2025-02-27T06:28:28.992Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-02-27T06:28:28.992Z] GC before operation: completed in 274.324 ms, heap usage 251.700 MB -> 67.013 MB. [2025-02-27T06:28:32.064Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (3120.412 ms) ====== [2025-02-27T06:28:32.064Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-02-27T06:28:32.791Z] GC before operation: completed in 314.170 ms, heap usage 113.758 MB -> 66.421 MB. [2025-02-27T06:28:35.886Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (2927.098 ms) ====== [2025-02-27T06:28:35.886Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-02-27T06:28:35.886Z] GC before operation: completed in 316.545 ms, heap usage 110.406 MB -> 66.384 MB. [2025-02-27T06:28:38.883Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (2903.831 ms) ====== [2025-02-27T06:28:38.883Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-02-27T06:28:38.883Z] GC before operation: completed in 240.572 ms, heap usage 231.444 MB -> 66.896 MB. [2025-02-27T06:28:41.997Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (2977.326 ms) ====== [2025-02-27T06:28:41.997Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-02-27T06:28:41.997Z] GC before operation: completed in 273.002 ms, heap usage 191.651 MB -> 67.132 MB. [2025-02-27T06:28:46.108Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (3557.098 ms) ====== [2025-02-27T06:28:46.108Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-02-27T06:28:46.763Z] GC before operation: completed in 679.424 ms, heap usage 239.870 MB -> 86.307 MB. [2025-02-27T06:28:46.763Z] 25/02/27 06:28:46 WARN BlockManager: Asked to remove block broadcast_512_piece0, which does not exist [2025-02-27T06:28:48.969Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2748.306 ms) ====== [2025-02-27T06:28:48.969Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-02-27T06:28:49.716Z] GC before operation: completed in 385.711 ms, heap usage 282.300 MB -> 67.315 MB. [2025-02-27T06:28:52.930Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (3623.111 ms) ====== [2025-02-27T06:28:52.930Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-02-27T06:28:53.599Z] GC before operation: completed in 443.213 ms, heap usage 140.502 MB -> 67.396 MB. [2025-02-27T06:28:56.689Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (2980.470 ms) ====== [2025-02-27T06:28:57.339Z] ----------------------------------- [2025-02-27T06:28:57.339Z] renaissance-dec-tree_0_PASSED [2025-02-27T06:28:57.339Z] ----------------------------------- [2025-02-27T06:28:57.339Z] [2025-02-27T06:28:57.339Z] TEST TEARDOWN: [2025-02-27T06:28:57.339Z] Nothing to be done for teardown. [2025-02-27T06:28:57.339Z] renaissance-dec-tree_0 Finish Time: Thu Feb 27 06:28:56 2025 Epoch Time (ms): 1740637736812