[2025-03-06T00:44:53.874Z] Running test renaissance-dec-tree_0 ... [2025-03-06T00:44:53.874Z] =============================================== [2025-03-06T00:44:53.874Z] renaissance-dec-tree_0 Start Time: Thu Mar 6 00:44:53 2025 Epoch Time (ms): 1741221893196 [2025-03-06T00:44:53.874Z] variation: NoOptions [2025-03-06T00:44:53.874Z] JVM_OPTIONS: [2025-03-06T00:44:53.874Z] { \ [2025-03-06T00:44:53.874Z] echo ""; echo "TEST SETUP:"; \ [2025-03-06T00:44:53.874Z] echo "Nothing to be done for setup."; \ [2025-03-06T00:44:53.874Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17412209919842\\renaissance-dec-tree_0"; \ [2025-03-06T00:44:53.874Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17412209919842\\renaissance-dec-tree_0"; \ [2025-03-06T00:44:53.874Z] echo ""; echo "TESTING:"; \ [2025-03-06T00:44:53.874Z] "c:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/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 "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17412209919842\\renaissance-dec-tree_0"\\dec-tree.json" dec-tree; \ [2025-03-06T00:44:53.874Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17412209919842\\renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-03-06T00:44:53.874Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-03-06T00:44:53.874Z] echo "Nothing to be done for teardown."; \ [2025-03-06T00:44:53.874Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17412209919842\\TestTargetResult"; [2025-03-06T00:44:53.874Z] [2025-03-06T00:44:53.874Z] TEST SETUP: [2025-03-06T00:44:53.874Z] Nothing to be done for setup. [2025-03-06T00:44:53.874Z] [2025-03-06T00:44:53.874Z] TESTING: [2025-03-06T00:45:06.859Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-03-06T00:45:07.986Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-03-06T00:45:09.115Z] WARNING: An illegal reflective access operation has occurred [2025-03-06T00:45:09.115Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows/aqa-tests/TKG/output_17412209919842/renaissance-dec-tree_0/harness-004454-9951794154899948771/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2025-03-06T00:45:09.115Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-03-06T00:45:09.115Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-03-06T00:45:09.115Z] WARNING: All illegal access operations will be denied in a future release [2025-03-06T00:45:11.334Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-03-06T00:45:11.666Z] GC before operation: completed in 53.029 ms, heap usage 64.670 MB -> 34.253 MB. [2025-03-06T00:45:22.534Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (9233.031 ms) ====== [2025-03-06T00:45:22.534Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-03-06T00:45:22.534Z] GC before operation: completed in 150.178 ms, heap usage 106.796 MB -> 64.014 MB. [2025-03-06T00:45:23.740Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2908.467 ms) ====== [2025-03-06T00:45:23.740Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-03-06T00:45:24.153Z] GC before operation: completed in 99.949 ms, heap usage 169.540 MB -> 64.202 MB. [2025-03-06T00:45:25.962Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2108.593 ms) ====== [2025-03-06T00:45:25.962Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-03-06T00:45:26.350Z] GC before operation: completed in 108.299 ms, heap usage 204.164 MB -> 75.989 MB. [2025-03-06T00:45:28.255Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2071.366 ms) ====== [2025-03-06T00:45:28.255Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-03-06T00:45:28.255Z] GC before operation: completed in 127.452 ms, heap usage 203.724 MB -> 64.962 MB. [2025-03-06T00:45:30.613Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1944.600 ms) ====== [2025-03-06T00:45:30.613Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-03-06T00:45:30.613Z] GC before operation: completed in 100.940 ms, heap usage 154.490 MB -> 65.166 MB. [2025-03-06T00:45:32.363Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1681.814 ms) ====== [2025-03-06T00:45:32.364Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-03-06T00:45:32.364Z] GC before operation: completed in 120.362 ms, heap usage 198.397 MB -> 65.400 MB. [2025-03-06T00:45:33.673Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1553.835 ms) ====== [2025-03-06T00:45:33.673Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-03-06T00:45:34.036Z] GC before operation: completed in 110.688 ms, heap usage 195.093 MB -> 76.631 MB. [2025-03-06T00:45:35.774Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1602.351 ms) ====== [2025-03-06T00:45:35.774Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-03-06T00:45:35.774Z] GC before operation: completed in 110.698 ms, heap usage 241.210 MB -> 76.821 MB. [2025-03-06T00:45:36.958Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1460.197 ms) ====== [2025-03-06T00:45:36.958Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-03-06T00:45:37.300Z] GC before operation: completed in 115.316 ms, heap usage 168.556 MB -> 65.557 MB. [2025-03-06T00:45:38.621Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1543.525 ms) ====== [2025-03-06T00:45:38.621Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-03-06T00:45:38.942Z] GC before operation: completed in 111.685 ms, heap usage 226.486 MB -> 82.241 MB. [2025-03-06T00:45:40.098Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1361.272 ms) ====== [2025-03-06T00:45:40.098Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-03-06T00:45:40.430Z] GC before operation: completed in 119.910 ms, heap usage 115.707 MB -> 76.995 MB. [2025-03-06T00:45:41.584Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1320.032 ms) ====== [2025-03-06T00:45:41.584Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-03-06T00:45:41.584Z] GC before operation: completed in 122.995 ms, heap usage 250.097 MB -> 77.245 MB. [2025-03-06T00:45:43.250Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1411.915 ms) ====== [2025-03-06T00:45:43.250Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-03-06T00:45:43.250Z] GC before operation: completed in 115.305 ms, heap usage 254.540 MB -> 82.757 MB. [2025-03-06T00:45:44.893Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1318.705 ms) ====== [2025-03-06T00:45:44.893Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-03-06T00:45:44.893Z] GC before operation: completed in 117.948 ms, heap usage 279.061 MB -> 88.528 MB. [2025-03-06T00:45:46.027Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1313.517 ms) ====== [2025-03-06T00:45:46.027Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-03-06T00:45:46.027Z] GC before operation: completed in 120.870 ms, heap usage 301.705 MB -> 83.009 MB. [2025-03-06T00:45:47.652Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1363.697 ms) ====== [2025-03-06T00:45:47.652Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-03-06T00:45:47.652Z] GC before operation: completed in 121.735 ms, heap usage 244.043 MB -> 66.789 MB. [2025-03-06T00:45:48.859Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1318.578 ms) ====== [2025-03-06T00:45:48.859Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-03-06T00:45:49.195Z] GC before operation: completed in 122.683 ms, heap usage 146.862 MB -> 66.706 MB. [2025-03-06T00:45:50.323Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1298.223 ms) ====== [2025-03-06T00:45:50.323Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-03-06T00:45:50.653Z] GC before operation: completed in 125.794 ms, heap usage 127.442 MB -> 66.824 MB. [2025-03-06T00:45:51.785Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1303.424 ms) ====== [2025-03-06T00:45:51.785Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-03-06T00:45:51.785Z] GC before operation: completed in 120.919 ms, heap usage 257.469 MB -> 83.239 MB. [2025-03-06T00:45:53.428Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1304.674 ms) ====== [2025-03-06T00:45:53.428Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-03-06T00:45:53.428Z] GC before operation: completed in 132.146 ms, heap usage 236.045 MB -> 89.157 MB. [2025-03-06T00:45:54.583Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1294.719 ms) ====== [2025-03-06T00:45:54.583Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-03-06T00:45:54.915Z] GC before operation: completed in 154.010 ms, heap usage 392.956 MB -> 67.158 MB. [2025-03-06T00:45:56.170Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1367.569 ms) ====== [2025-03-06T00:45:56.170Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-03-06T00:45:56.170Z] GC before operation: completed in 129.833 ms, heap usage 230.443 MB -> 83.768 MB. [2025-03-06T00:45:57.785Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1259.115 ms) ====== [2025-03-06T00:45:57.785Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-03-06T00:45:57.785Z] GC before operation: completed in 125.138 ms, heap usage 254.031 MB -> 78.438 MB. [2025-03-06T00:45:58.972Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1261.003 ms) ====== [2025-03-06T00:45:58.972Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-03-06T00:45:58.972Z] GC before operation: completed in 126.892 ms, heap usage 182.631 MB -> 72.825 MB. [2025-03-06T00:46:00.329Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1281.583 ms) ====== [2025-03-06T00:46:00.329Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-03-06T00:46:00.661Z] GC before operation: completed in 133.258 ms, heap usage 200.318 MB -> 78.467 MB. [2025-03-06T00:46:01.786Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1330.929 ms) ====== [2025-03-06T00:46:01.786Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-03-06T00:46:02.126Z] GC before operation: completed in 144.526 ms, heap usage 115.145 MB -> 78.455 MB. [2025-03-06T00:46:03.252Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1312.433 ms) ====== [2025-03-06T00:46:03.252Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-03-06T00:46:03.587Z] GC before operation: completed in 132.074 ms, heap usage 246.910 MB -> 84.314 MB. [2025-03-06T00:46:04.728Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1317.807 ms) ====== [2025-03-06T00:46:04.728Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-03-06T00:46:04.728Z] GC before operation: completed in 127.876 ms, heap usage 124.383 MB -> 83.826 MB. [2025-03-06T00:46:06.636Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1306.095 ms) ====== [2025-03-06T00:46:06.636Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-03-06T00:46:06.636Z] GC before operation: completed in 154.785 ms, heap usage 228.734 MB -> 67.983 MB. [2025-03-06T00:46:07.761Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1242.219 ms) ====== [2025-03-06T00:46:07.761Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-03-06T00:46:07.761Z] GC before operation: completed in 126.746 ms, heap usage 106.744 MB -> 78.676 MB. [2025-03-06T00:46:08.886Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1185.964 ms) ====== [2025-03-06T00:46:08.886Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-03-06T00:46:08.886Z] GC before operation: completed in 141.239 ms, heap usage 167.932 MB -> 78.956 MB. [2025-03-06T00:46:10.509Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1235.356 ms) ====== [2025-03-06T00:46:10.509Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-03-06T00:46:10.509Z] GC before operation: completed in 146.675 ms, heap usage 171.982 MB -> 84.489 MB. [2025-03-06T00:46:11.646Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1258.562 ms) ====== [2025-03-06T00:46:11.646Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-03-06T00:46:11.976Z] GC before operation: completed in 154.189 ms, heap usage 270.307 MB -> 79.386 MB. [2025-03-06T00:46:13.138Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1195.451 ms) ====== [2025-03-06T00:46:13.138Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-03-06T00:46:13.138Z] GC before operation: completed in 130.904 ms, heap usage 226.834 MB -> 84.952 MB. [2025-03-06T00:46:14.504Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1270.975 ms) ====== [2025-03-06T00:46:14.504Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-03-06T00:46:16.649Z] GC before operation: completed in 135.224 ms, heap usage 185.757 MB -> 85.001 MB. [2025-03-06T00:46:16.649Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1281.628 ms) ====== [2025-03-06T00:46:16.649Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-03-06T00:46:16.649Z] GC before operation: completed in 135.386 ms, heap usage 234.981 MB -> 85.188 MB. [2025-03-06T00:46:17.377Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1260.498 ms) ====== [2025-03-06T00:46:17.377Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-03-06T00:46:17.377Z] GC before operation: completed in 143.162 ms, heap usage 205.510 MB -> 85.256 MB. [2025-03-06T00:46:19.003Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1319.631 ms) ====== [2025-03-06T00:46:19.003Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-03-06T00:46:19.003Z] GC before operation: completed in 138.349 ms, heap usage 262.355 MB -> 85.329 MB. [2025-03-06T00:46:20.128Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1267.657 ms) ====== [2025-03-06T00:46:20.128Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-03-06T00:46:20.128Z] GC before operation: completed in 137.881 ms, heap usage 222.610 MB -> 91.053 MB. [2025-03-06T00:46:20.128Z] 25/03/06 00:46:20 WARN BlockManager: Asked to remove block rdd_1908_1, which does not exist [2025-03-06T00:46:21.753Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1256.627 ms) ====== [2025-03-06T00:46:21.753Z] ----------------------------------- [2025-03-06T00:46:21.753Z] renaissance-dec-tree_0_PASSED [2025-03-06T00:46:21.753Z] ----------------------------------- [2025-03-06T00:46:21.753Z] [2025-03-06T00:46:21.753Z] TEST TEARDOWN: [2025-03-06T00:46:21.753Z] Nothing to be done for teardown. [2025-03-06T00:46:22.071Z] renaissance-dec-tree_0 Finish Time: Thu Mar 6 00:46:21 2025 Epoch Time (ms): 1741221981809