[2025-03-06T00:05:21.979Z] Running test renaissance-dec-tree_0 ... [2025-03-06T00:05:21.979Z] =============================================== [2025-03-06T00:05:21.979Z] renaissance-dec-tree_0 Start Time: Thu Mar 6 00:05:21 2025 Epoch Time (ms): 1741219521612 [2025-03-06T00:05:21.979Z] variation: NoOptions [2025-03-06T00:05:21.979Z] JVM_OPTIONS: [2025-03-06T00:05:21.979Z] { \ [2025-03-06T00:05:21.979Z] echo ""; echo "TEST SETUP:"; \ [2025-03-06T00:05:21.979Z] echo "Nothing to be done for setup."; \ [2025-03-06T00:05:21.979Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17412166579484/renaissance-dec-tree_0"; \ [2025-03-06T00:05:21.979Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17412166579484/renaissance-dec-tree_0"; \ [2025-03-06T00:05:21.979Z] echo ""; echo "TESTING:"; \ [2025-03-06T00:05:21.979Z] "/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_17412166579484/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-03-06T00:05:21.979Z] 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_17412166579484/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-03-06T00:05:21.979Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-03-06T00:05:21.979Z] echo "Nothing to be done for teardown."; \ [2025-03-06T00:05:21.979Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17412166579484/TestTargetResult"; [2025-03-06T00:05:21.979Z] [2025-03-06T00:05:21.979Z] TEST SETUP: [2025-03-06T00:05:21.979Z] Nothing to be done for setup. [2025-03-06T00:05:21.979Z] [2025-03-06T00:05:21.979Z] TESTING: [2025-03-06T00:05:44.572Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-03-06T00:06:03.908Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-03-06T00:06:15.855Z] WARNING: An illegal reflective access operation has occurred [2025-03-06T00:06:15.855Z] 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_17412166579484/renaissance-dec-tree_0/harness-000526-5633047516083370087/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-03-06T00:06:15.855Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-03-06T00:06:15.855Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-03-06T00:06:15.855Z] WARNING: All illegal access operations will be denied in a future release [2025-03-06T00:06:39.564Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-03-06T00:06:39.564Z] GC before operation: completed in 544.089 ms, heap usage 65.383 MB -> 33.810 MB. [2025-03-06T00:08:13.876Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (84897.612 ms) ====== [2025-03-06T00:08:13.876Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-03-06T00:08:13.876Z] GC before operation: completed in 848.366 ms, heap usage 68.468 MB -> 63.652 MB. [2025-03-06T00:08:27.584Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (23964.175 ms) ====== [2025-03-06T00:08:27.584Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-03-06T00:08:28.359Z] GC before operation: completed in 1056.357 ms, heap usage 163.766 MB -> 64.148 MB. [2025-03-06T00:08:50.380Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (20010.916 ms) ====== [2025-03-06T00:08:50.380Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-03-06T00:08:50.380Z] GC before operation: completed in 941.745 ms, heap usage 216.477 MB -> 64.238 MB. [2025-03-06T00:09:09.150Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (19948.326 ms) ====== [2025-03-06T00:09:09.150Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-03-06T00:09:09.943Z] GC before operation: completed in 833.107 ms, heap usage 186.817 MB -> 64.440 MB. [2025-03-06T00:09:29.205Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (17248.334 ms) ====== [2025-03-06T00:09:29.205Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-03-06T00:09:29.205Z] GC before operation: completed in 985.112 ms, heap usage 158.307 MB -> 65.048 MB. [2025-03-06T00:09:48.547Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (18506.562 ms) ====== [2025-03-06T00:09:48.547Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-03-06T00:09:48.547Z] GC before operation: completed in 955.146 ms, heap usage 104.204 MB -> 64.743 MB. [2025-03-06T00:10:04.978Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (16891.262 ms) ====== [2025-03-06T00:10:04.978Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-03-06T00:10:05.784Z] GC before operation: completed in 1198.663 ms, heap usage 161.579 MB -> 64.783 MB. [2025-03-06T00:10:25.044Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (17527.128 ms) ====== [2025-03-06T00:10:25.044Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-03-06T00:10:25.044Z] GC before operation: completed in 1261.740 ms, heap usage 189.991 MB -> 65.407 MB. [2025-03-06T00:10:39.660Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (14655.336 ms) ====== [2025-03-06T00:10:39.660Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-03-06T00:10:40.461Z] GC before operation: completed in 947.072 ms, heap usage 200.139 MB -> 65.459 MB. [2025-03-06T00:10:54.464Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (13591.155 ms) ====== [2025-03-06T00:10:54.464Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-03-06T00:10:54.464Z] GC before operation: completed in 781.044 ms, heap usage 221.114 MB -> 65.378 MB. [2025-03-06T00:11:08.506Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (12648.540 ms) ====== [2025-03-06T00:11:08.506Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-03-06T00:11:08.506Z] GC before operation: completed in 830.248 ms, heap usage 101.556 MB -> 65.394 MB. [2025-03-06T00:11:20.376Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (11722.058 ms) ====== [2025-03-06T00:11:20.376Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-03-06T00:11:20.376Z] GC before operation: completed in 945.821 ms, heap usage 196.748 MB -> 65.750 MB. [2025-03-06T00:11:34.458Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (12246.826 ms) ====== [2025-03-06T00:11:34.458Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-03-06T00:11:34.458Z] GC before operation: completed in 929.109 ms, heap usage 265.834 MB -> 65.724 MB. [2025-03-06T00:11:46.615Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (11517.239 ms) ====== [2025-03-06T00:11:46.615Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-03-06T00:11:46.615Z] GC before operation: completed in 929.230 ms, heap usage 162.231 MB -> 65.982 MB. [2025-03-06T00:11:58.520Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (11513.074 ms) ====== [2025-03-06T00:11:58.520Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-03-06T00:11:58.520Z] GC before operation: completed in 926.519 ms, heap usage 274.882 MB -> 65.938 MB. [2025-03-06T00:12:10.545Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (11364.103 ms) ====== [2025-03-06T00:12:10.545Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-03-06T00:12:11.330Z] GC before operation: completed in 857.870 ms, heap usage 129.878 MB -> 66.231 MB. [2025-03-06T00:12:23.374Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (11144.544 ms) ====== [2025-03-06T00:12:23.374Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-03-06T00:12:23.374Z] GC before operation: completed in 942.026 ms, heap usage 169.413 MB -> 65.941 MB. [2025-03-06T00:12:33.559Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (9977.636 ms) ====== [2025-03-06T00:12:33.559Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-03-06T00:12:34.378Z] GC before operation: completed in 840.551 ms, heap usage 116.822 MB -> 66.010 MB. [2025-03-06T00:12:46.525Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (11413.395 ms) ====== [2025-03-06T00:12:46.525Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-03-06T00:12:46.525Z] GC before operation: completed in 1107.617 ms, heap usage 266.080 MB -> 66.396 MB. [2025-03-06T00:12:57.324Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (10350.050 ms) ====== [2025-03-06T00:12:57.324Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-03-06T00:12:58.113Z] GC before operation: completed in 962.654 ms, heap usage 162.245 MB -> 66.373 MB. [2025-03-06T00:13:06.732Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (8743.905 ms) ====== [2025-03-06T00:13:06.732Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-03-06T00:13:07.544Z] GC before operation: completed in 946.955 ms, heap usage 160.282 MB -> 66.593 MB. [2025-03-06T00:13:17.853Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (9650.413 ms) ====== [2025-03-06T00:13:17.853Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-03-06T00:13:17.853Z] GC before operation: completed in 919.456 ms, heap usage 183.029 MB -> 66.780 MB. [2025-03-06T00:13:28.018Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (9196.969 ms) ====== [2025-03-06T00:13:28.018Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-03-06T00:13:28.018Z] GC before operation: completed in 812.644 ms, heap usage 207.108 MB -> 67.159 MB. [2025-03-06T00:13:38.202Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (9194.672 ms) ====== [2025-03-06T00:13:38.202Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-03-06T00:13:38.202Z] GC before operation: completed in 828.904 ms, heap usage 153.955 MB -> 66.636 MB. [2025-03-06T00:13:46.786Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (8575.117 ms) ====== [2025-03-06T00:13:46.786Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-03-06T00:13:47.565Z] GC before operation: completed in 914.745 ms, heap usage 201.579 MB -> 67.005 MB. [2025-03-06T00:13:56.613Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (8986.636 ms) ====== [2025-03-06T00:13:56.613Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-03-06T00:13:57.431Z] GC before operation: completed in 1055.202 ms, heap usage 174.425 MB -> 66.826 MB. [2025-03-06T00:14:09.644Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (10878.748 ms) ====== [2025-03-06T00:14:09.644Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-03-06T00:14:09.644Z] GC before operation: completed in 843.536 ms, heap usage 156.516 MB -> 66.952 MB. [2025-03-06T00:14:18.307Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (8987.695 ms) ====== [2025-03-06T00:14:18.307Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-03-06T00:14:19.109Z] GC before operation: completed in 768.734 ms, heap usage 104.740 MB -> 67.166 MB. [2025-03-06T00:14:29.346Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (9108.215 ms) ====== [2025-03-06T00:14:29.346Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-03-06T00:14:29.346Z] GC before operation: completed in 996.652 ms, heap usage 113.944 MB -> 66.992 MB. [2025-03-06T00:14:39.614Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (9832.998 ms) ====== [2025-03-06T00:14:39.614Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-03-06T00:14:39.614Z] GC before operation: completed in 791.410 ms, heap usage 197.433 MB -> 67.685 MB. [2025-03-06T00:14:49.756Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (8946.275 ms) ====== [2025-03-06T00:14:49.756Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-03-06T00:14:49.756Z] GC before operation: completed in 863.065 ms, heap usage 112.852 MB -> 67.457 MB. [2025-03-06T00:14:59.955Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (9710.731 ms) ====== [2025-03-06T00:14:59.955Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-03-06T00:15:00.761Z] GC before operation: completed in 925.392 ms, heap usage 189.538 MB -> 67.741 MB. [2025-03-06T00:15:09.923Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (9230.680 ms) ====== [2025-03-06T00:15:09.923Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-03-06T00:15:10.742Z] GC before operation: completed in 945.437 ms, heap usage 128.774 MB -> 67.810 MB. [2025-03-06T00:15:20.970Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (9373.605 ms) ====== [2025-03-06T00:15:20.970Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-03-06T00:15:20.970Z] GC before operation: completed in 929.881 ms, heap usage 145.524 MB -> 67.633 MB. [2025-03-06T00:15:31.221Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (9172.693 ms) ====== [2025-03-06T00:15:31.221Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-03-06T00:15:31.221Z] GC before operation: completed in 993.497 ms, heap usage 195.090 MB -> 68.173 MB. [2025-03-06T00:15:39.927Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (9217.177 ms) ====== [2025-03-06T00:15:39.927Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-03-06T00:15:40.732Z] GC before operation: completed in 872.146 ms, heap usage 105.758 MB -> 67.830 MB. [2025-03-06T00:15:50.929Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (9521.790 ms) ====== [2025-03-06T00:15:50.929Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-03-06T00:15:51.725Z] GC before operation: completed in 946.411 ms, heap usage 164.920 MB -> 68.153 MB. [2025-03-06T00:16:00.263Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (8001.563 ms) ====== [2025-03-06T00:16:00.263Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-03-06T00:16:00.263Z] GC before operation: completed in 933.596 ms, heap usage 182.582 MB -> 68.338 MB. [2025-03-06T00:16:09.341Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (9014.888 ms) ====== [2025-03-06T00:16:09.341Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-03-06T00:16:11.017Z] GC before operation: completed in 1364.100 ms, heap usage 222.846 MB -> 68.402 MB. [2025-03-06T00:16:21.063Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (9744.825 ms) ====== [2025-03-06T00:16:22.704Z] ----------------------------------- [2025-03-06T00:16:22.704Z] renaissance-dec-tree_0_PASSED [2025-03-06T00:16:22.704Z] ----------------------------------- [2025-03-06T00:16:22.704Z] [2025-03-06T00:16:22.704Z] TEST TEARDOWN: [2025-03-06T00:16:22.704Z] Nothing to be done for teardown. [2025-03-06T00:16:22.704Z] renaissance-dec-tree_0 Finish Time: Thu Mar 6 00:16:21 2025 Epoch Time (ms): 1741220181960