[2025-08-28T12:45:04.882Z] Running test renaissance-dec-tree_0 ... [2025-08-28T12:45:04.882Z] =============================================== [2025-08-28T12:45:04.882Z] renaissance-dec-tree_0 Start Time: Thu Aug 28 12:45:04 2025 Epoch Time (ms): 1756385104837 [2025-08-28T12:45:05.503Z] variation: NoOptions [2025-08-28T12:45:05.503Z] JVM_OPTIONS: [2025-08-28T12:45:05.503Z] { \ [2025-08-28T12:45:05.503Z] echo ""; echo "TEST SETUP:"; \ [2025-08-28T12:45:05.503Z] echo "Nothing to be done for setup."; \ [2025-08-28T12:45:05.503Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17563834421410/renaissance-dec-tree_0"; \ [2025-08-28T12:45:05.503Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17563834421410/renaissance-dec-tree_0"; \ [2025-08-28T12:45:05.503Z] echo ""; echo "TESTING:"; \ [2025-08-28T12:45:05.503Z] "/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_17563834421410/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-08-28T12:45:05.503Z] 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_17563834421410/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-28T12:45:05.503Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-28T12:45:05.503Z] echo "Nothing to be done for teardown."; \ [2025-08-28T12:45:05.503Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17563834421410/TestTargetResult"; [2025-08-28T12:45:05.503Z] [2025-08-28T12:45:05.503Z] TEST SETUP: [2025-08-28T12:45:05.503Z] Nothing to be done for setup. [2025-08-28T12:45:05.503Z] [2025-08-28T12:45:05.503Z] TESTING: [2025-08-28T12:45:22.602Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-08-28T12:45:25.943Z] WARNING: An illegal reflective access operation has occurred [2025-08-28T12:45:25.943Z] 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_17563834421410/renaissance-dec-tree_0/harness-124507-13062908248828815624/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-08-28T12:45:25.943Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-08-28T12:45:25.943Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-08-28T12:45:25.943Z] WARNING: All illegal access operations will be denied in a future release [2025-08-28T12:45:28.045Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-08-28T12:45:28.045Z] GC before operation: completed in 79.378 ms, heap usage 42.859 MB -> 34.353 MB. [2025-08-28T12:45:45.913Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (16942.433 ms) ====== [2025-08-28T12:45:45.913Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-08-28T12:45:45.913Z] GC before operation: completed in 288.769 ms, heap usage 294.398 MB -> 94.765 MB. [2025-08-28T12:45:50.936Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (5874.864 ms) ====== [2025-08-28T12:45:50.936Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-08-28T12:45:51.594Z] GC before operation: completed in 369.645 ms, heap usage 276.373 MB -> 95.079 MB. [2025-08-28T12:45:55.468Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (3975.969 ms) ====== [2025-08-28T12:45:55.468Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-08-28T12:45:55.468Z] GC before operation: completed in 272.725 ms, heap usage 230.077 MB -> 67.721 MB. [2025-08-28T12:45:59.338Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (3174.308 ms) ====== [2025-08-28T12:45:59.338Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-08-28T12:45:59.338Z] GC before operation: completed in 305.488 ms, heap usage 99.568 MB -> 67.629 MB. [2025-08-28T12:46:03.100Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (3380.279 ms) ====== [2025-08-28T12:46:03.100Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-08-28T12:46:03.100Z] GC before operation: completed in 187.713 ms, heap usage 275.444 MB -> 68.139 MB. [2025-08-28T12:46:06.941Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (3606.329 ms) ====== [2025-08-28T12:46:06.941Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-08-28T12:46:06.941Z] GC before operation: completed in 242.378 ms, heap usage 102.139 MB -> 68.015 MB. [2025-08-28T12:46:10.742Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (3506.626 ms) ====== [2025-08-28T12:46:10.743Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-08-28T12:46:10.743Z] GC before operation: completed in 250.156 ms, heap usage 259.049 MB -> 96.125 MB. [2025-08-28T12:46:13.664Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2785.866 ms) ====== [2025-08-28T12:46:13.664Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-08-28T12:46:13.664Z] GC before operation: completed in 404.516 ms, heap usage 237.317 MB -> 68.845 MB. [2025-08-28T12:46:17.438Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (3426.217 ms) ====== [2025-08-28T12:46:17.438Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-08-28T12:46:17.438Z] GC before operation: completed in 356.166 ms, heap usage 149.702 MB -> 68.952 MB. [2025-08-28T12:46:20.344Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (3209.799 ms) ====== [2025-08-28T12:46:20.344Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-08-28T12:46:20.972Z] GC before operation: completed in 263.803 ms, heap usage 284.353 MB -> 69.074 MB. [2025-08-28T12:46:24.197Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (3140.546 ms) ====== [2025-08-28T12:46:24.197Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-08-28T12:46:24.197Z] GC before operation: completed in 325.759 ms, heap usage 212.176 MB -> 69.082 MB. [2025-08-28T12:46:27.929Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (3122.284 ms) ====== [2025-08-28T12:46:27.929Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-08-28T12:46:27.929Z] GC before operation: completed in 285.390 ms, heap usage 123.136 MB -> 69.196 MB. [2025-08-28T12:46:31.029Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (3140.062 ms) ====== [2025-08-28T12:46:31.029Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-08-28T12:46:31.029Z] GC before operation: completed in 254.558 ms, heap usage 252.540 MB -> 69.391 MB. [2025-08-28T12:46:34.916Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (3188.579 ms) ====== [2025-08-28T12:46:34.916Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-08-28T12:46:34.916Z] GC before operation: completed in 317.936 ms, heap usage 168.085 MB -> 69.531 MB. [2025-08-28T12:46:37.869Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (3029.975 ms) ====== [2025-08-28T12:46:37.869Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-08-28T12:46:37.869Z] GC before operation: completed in 424.409 ms, heap usage 109.713 MB -> 69.320 MB. [2025-08-28T12:46:41.742Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (3411.097 ms) ====== [2025-08-28T12:46:41.742Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-08-28T12:46:41.742Z] GC before operation: completed in 340.255 ms, heap usage 212.994 MB -> 69.654 MB. [2025-08-28T12:46:44.750Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (3174.176 ms) ====== [2025-08-28T12:46:44.750Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-08-28T12:46:45.395Z] GC before operation: completed in 326.035 ms, heap usage 235.744 MB -> 97.361 MB. [2025-08-28T12:46:48.300Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (3184.335 ms) ====== [2025-08-28T12:46:48.300Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-08-28T12:46:49.014Z] GC before operation: completed in 199.958 ms, heap usage 195.231 MB -> 69.881 MB. [2025-08-28T12:46:51.906Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (3316.090 ms) ====== [2025-08-28T12:46:51.906Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-08-28T12:46:52.531Z] GC before operation: completed in 391.058 ms, heap usage 235.887 MB -> 70.136 MB. [2025-08-28T12:46:56.375Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (3348.706 ms) ====== [2025-08-28T12:46:56.375Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-08-28T12:46:56.375Z] GC before operation: completed in 369.629 ms, heap usage 166.492 MB -> 69.955 MB. [2025-08-28T12:46:59.466Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (2779.453 ms) ====== [2025-08-28T12:46:59.466Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-08-28T12:46:59.466Z] GC before operation: completed in 301.398 ms, heap usage 243.594 MB -> 70.356 MB. [2025-08-28T12:47:02.683Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (3177.929 ms) ====== [2025-08-28T12:47:02.683Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-08-28T12:47:02.683Z] GC before operation: completed in 277.056 ms, heap usage 101.113 MB -> 70.127 MB. [2025-08-28T12:47:06.603Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (3609.683 ms) ====== [2025-08-28T12:47:06.603Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-08-28T12:47:06.603Z] GC before operation: completed in 389.133 ms, heap usage 301.505 MB -> 70.402 MB. [2025-08-28T12:47:09.800Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (2991.009 ms) ====== [2025-08-28T12:47:09.800Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-08-28T12:47:09.800Z] GC before operation: completed in 259.045 ms, heap usage 252.265 MB -> 98.107 MB. [2025-08-28T12:47:09.800Z] 12:47:09.644 WARN [block-manager-storage-async-thread-pool-75] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_331_piece0, which does not exist [2025-08-28T12:47:09.800Z] 12:47:09.659 WARN [block-manager-storage-async-thread-pool-75] org.apache.spark.storage.BlockManager - Asked to remove block broadcast_331, which does not exist [2025-08-28T12:47:13.646Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (3353.538 ms) ====== [2025-08-28T12:47:13.646Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-08-28T12:47:13.646Z] GC before operation: completed in 279.084 ms, heap usage 178.509 MB -> 70.635 MB. [2025-08-28T12:47:16.682Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (2971.307 ms) ====== [2025-08-28T12:47:16.682Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-08-28T12:47:17.349Z] GC before operation: completed in 436.316 ms, heap usage 72.661 MB -> 70.402 MB. [2025-08-28T12:47:20.331Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (3038.334 ms) ====== [2025-08-28T12:47:20.332Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-08-28T12:47:20.332Z] GC before operation: completed in 390.184 ms, heap usage 203.341 MB -> 70.830 MB. [2025-08-28T12:47:24.321Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (3784.638 ms) ====== [2025-08-28T12:47:24.321Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-08-28T12:47:24.321Z] GC before operation: completed in 323.232 ms, heap usage 258.703 MB -> 98.572 MB. [2025-08-28T12:47:27.274Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (2791.884 ms) ====== [2025-08-28T12:47:27.275Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-08-28T12:47:27.961Z] GC before operation: completed in 375.145 ms, heap usage 142.181 MB -> 70.839 MB. [2025-08-28T12:47:31.767Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (3498.110 ms) ====== [2025-08-28T12:47:31.767Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-08-28T12:47:31.767Z] GC before operation: completed in 347.983 ms, heap usage 153.202 MB -> 70.794 MB. [2025-08-28T12:47:34.711Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (3054.523 ms) ====== [2025-08-28T12:47:34.711Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-08-28T12:47:35.404Z] GC before operation: completed in 527.412 ms, heap usage 199.939 MB -> 98.821 MB. [2025-08-28T12:47:39.317Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (3455.977 ms) ====== [2025-08-28T12:47:39.317Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-08-28T12:47:39.317Z] GC before operation: completed in 319.856 ms, heap usage 231.658 MB -> 71.340 MB. [2025-08-28T12:47:42.852Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (3364.387 ms) ====== [2025-08-28T12:47:42.852Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-08-28T12:47:42.852Z] GC before operation: completed in 339.482 ms, heap usage 163.733 MB -> 71.441 MB. [2025-08-28T12:47:45.842Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (3146.331 ms) ====== [2025-08-28T12:47:45.842Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-08-28T12:47:46.472Z] GC before operation: completed in 334.881 ms, heap usage 243.316 MB -> 71.687 MB. [2025-08-28T12:47:49.379Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (2981.840 ms) ====== [2025-08-28T12:47:49.379Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-08-28T12:47:49.379Z] GC before operation: completed in 273.070 ms, heap usage 289.598 MB -> 71.645 MB. [2025-08-28T12:47:52.415Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (3301.237 ms) ====== [2025-08-28T12:47:52.415Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-08-28T12:47:53.099Z] GC before operation: completed in 294.504 ms, heap usage 171.410 MB -> 99.034 MB. [2025-08-28T12:47:56.058Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (2987.278 ms) ====== [2025-08-28T12:47:56.058Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-08-28T12:47:56.717Z] GC before operation: completed in 436.619 ms, heap usage 329.258 MB -> 72.209 MB. [2025-08-28T12:47:59.629Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2784.874 ms) ====== [2025-08-28T12:47:59.629Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-08-28T12:47:59.629Z] GC before operation: completed in 303.080 ms, heap usage 111.608 MB -> 99.527 MB. [2025-08-28T12:47:59.629Z] 12:47:59.193 WARN [block-manager-storage-async-thread-pool-38] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1821_0, which does not exist [2025-08-28T12:47:59.629Z] 12:47:59.197 WARN [block-manager-storage-async-thread-pool-30] org.apache.spark.storage.BlockManager - Asked to remove block rdd_1821_1, which does not exist [2025-08-28T12:48:02.509Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (2998.456 ms) ====== [2025-08-28T12:48:02.509Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-08-28T12:48:03.226Z] GC before operation: completed in 313.217 ms, heap usage 341.593 MB -> 72.426 MB. [2025-08-28T12:48:06.150Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (3206.302 ms) ====== [2025-08-28T12:48:06.774Z] ----------------------------------- [2025-08-28T12:48:06.774Z] renaissance-dec-tree_0_PASSED [2025-08-28T12:48:06.774Z] ----------------------------------- [2025-08-28T12:48:06.774Z] [2025-08-28T12:48:06.774Z] TEST TEARDOWN: [2025-08-28T12:48:06.774Z] Nothing to be done for teardown. [2025-08-28T12:48:06.774Z] renaissance-dec-tree_0 Finish Time: Thu Aug 28 12:48:06 2025 Epoch Time (ms): 1756385286388