[2024-08-15T03:17:43.563Z] Running test renaissance-dec-tree_0 ... [2024-08-15T03:17:43.563Z] =============================================== [2024-08-15T03:17:43.563Z] renaissance-dec-tree_0 Start Time: Wed Aug 14 22:17:42 2024 Epoch Time (ms): 1723691862593 [2024-08-15T03:17:43.563Z] variation: NoOptions [2024-08-15T03:17:43.563Z] JVM_OPTIONS: [2024-08-15T03:17:43.563Z] { \ [2024-08-15T03:17:43.563Z] echo ""; echo "TEST SETUP:"; \ [2024-08-15T03:17:43.563Z] echo "Nothing to be done for setup."; \ [2024-08-15T03:17:43.563Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_1723691364640/renaissance-dec-tree_0"; \ [2024-08-15T03:17:43.563Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_1723691364640/renaissance-dec-tree_0"; \ [2024-08-15T03:17:43.563Z] echo ""; echo "TESTING:"; \ [2024-08-15T03:17:43.563Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_0/jdkbinary/j2sdk-image/jdk-11.0.25+3/bin/..//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_linux_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_1723691364640/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-15T03:17:43.563Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_1723691364640/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-15T03:17:43.563Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-15T03:17:43.563Z] echo "Nothing to be done for teardown."; \ [2024-08-15T03:17:43.563Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/../TKG/output_1723691364640/TestTargetResult"; [2024-08-15T03:17:43.563Z] [2024-08-15T03:17:43.563Z] TEST SETUP: [2024-08-15T03:17:43.563Z] Nothing to be done for setup. [2024-08-15T03:17:43.563Z] [2024-08-15T03:17:43.563Z] TESTING: [2024-08-15T03:17:45.758Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-15T03:17:47.959Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 6 (out of 8) threads. [2024-08-15T03:17:49.362Z] WARNING: An illegal reflective access operation has occurred [2024-08-15T03:17:49.362Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux_testList_0/aqa-tests/TKG/output_1723691364640/renaissance-dec-tree_0/harness-221743-2424351166657352303/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-15T03:17:49.363Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-15T03:17:49.363Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-15T03:17:49.363Z] WARNING: All illegal access operations will be denied in a future release [2024-08-15T03:17:51.630Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-15T03:17:51.630Z] GC before operation: completed in 83.240 ms, heap usage 84.235 MB -> 34.347 MB. [2024-08-15T03:18:00.752Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8817.455 ms) ====== [2024-08-15T03:18:00.752Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-15T03:18:00.752Z] GC before operation: completed in 146.786 ms, heap usage 228.376 MB -> 64.380 MB. [2024-08-15T03:18:02.949Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2435.976 ms) ====== [2024-08-15T03:18:02.949Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-15T03:18:02.949Z] GC before operation: completed in 112.235 ms, heap usage 240.439 MB -> 64.954 MB. [2024-08-15T03:18:05.153Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2144.171 ms) ====== [2024-08-15T03:18:05.153Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-15T03:18:05.153Z] GC before operation: completed in 90.780 ms, heap usage 102.951 MB -> 64.705 MB. [2024-08-15T03:18:07.349Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1653.523 ms) ====== [2024-08-15T03:18:07.349Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-15T03:18:07.349Z] GC before operation: completed in 116.061 ms, heap usage 219.066 MB -> 65.238 MB. [2024-08-15T03:18:08.783Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1608.132 ms) ====== [2024-08-15T03:18:08.783Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-15T03:18:08.783Z] GC before operation: completed in 123.578 ms, heap usage 212.100 MB -> 65.297 MB. [2024-08-15T03:18:10.187Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1558.041 ms) ====== [2024-08-15T03:18:10.187Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-15T03:18:10.862Z] GC before operation: completed in 109.461 ms, heap usage 277.607 MB -> 65.871 MB. [2024-08-15T03:18:12.305Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1363.558 ms) ====== [2024-08-15T03:18:12.305Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-15T03:18:12.305Z] GC before operation: completed in 97.213 ms, heap usage 111.891 MB -> 65.443 MB. [2024-08-15T03:18:13.710Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1553.616 ms) ====== [2024-08-15T03:18:13.710Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-15T03:18:13.710Z] GC before operation: completed in 120.043 ms, heap usage 242.324 MB -> 76.952 MB. [2024-08-15T03:18:15.113Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1242.948 ms) ====== [2024-08-15T03:18:15.113Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-15T03:18:15.113Z] GC before operation: completed in 129.341 ms, heap usage 236.554 MB -> 76.994 MB. [2024-08-15T03:18:16.517Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1556.210 ms) ====== [2024-08-15T03:18:16.517Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-15T03:18:16.517Z] GC before operation: completed in 129.495 ms, heap usage 262.167 MB -> 82.538 MB. [2024-08-15T03:18:17.922Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1310.470 ms) ====== [2024-08-15T03:18:17.922Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-15T03:18:17.922Z] GC before operation: completed in 107.576 ms, heap usage 256.870 MB -> 66.181 MB. [2024-08-15T03:18:19.326Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1378.037 ms) ====== [2024-08-15T03:18:19.326Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-15T03:18:20.008Z] GC before operation: completed in 121.362 ms, heap usage 217.798 MB -> 77.184 MB. [2024-08-15T03:18:21.419Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1546.714 ms) ====== [2024-08-15T03:18:21.419Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-15T03:18:21.419Z] GC before operation: completed in 126.786 ms, heap usage 250.104 MB -> 77.301 MB. [2024-08-15T03:18:22.848Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1412.047 ms) ====== [2024-08-15T03:18:22.848Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-15T03:18:22.848Z] GC before operation: completed in 121.299 ms, heap usage 207.357 MB -> 77.402 MB. [2024-08-15T03:18:24.260Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1442.402 ms) ====== [2024-08-15T03:18:24.260Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-15T03:18:24.260Z] GC before operation: completed in 145.122 ms, heap usage 253.432 MB -> 83.147 MB. [2024-08-15T03:18:24.260Z] 24/08/14 22:18:24 WARN BlockManager: Asked to remove block rdd_732_2, which does not exist [2024-08-15T03:18:25.669Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1316.456 ms) ====== [2024-08-15T03:18:25.669Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-15T03:18:25.669Z] GC before operation: completed in 124.796 ms, heap usage 203.510 MB -> 66.940 MB. [2024-08-15T03:18:27.073Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1287.862 ms) ====== [2024-08-15T03:18:27.073Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-15T03:18:27.073Z] GC before operation: completed in 125.411 ms, heap usage 203.679 MB -> 66.872 MB. [2024-08-15T03:18:28.477Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1269.095 ms) ====== [2024-08-15T03:18:28.477Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-15T03:18:28.477Z] GC before operation: completed in 120.488 ms, heap usage 193.112 MB -> 66.971 MB. [2024-08-15T03:18:29.885Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1418.119 ms) ====== [2024-08-15T03:18:29.885Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-15T03:18:30.563Z] GC before operation: completed in 119.192 ms, heap usage 188.298 MB -> 83.568 MB. [2024-08-15T03:18:31.968Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1444.253 ms) ====== [2024-08-15T03:18:31.968Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-15T03:18:31.968Z] GC before operation: completed in 133.897 ms, heap usage 124.208 MB -> 83.148 MB. [2024-08-15T03:18:31.968Z] 24/08/14 22:18:31 WARN BlockManager: Asked to remove block broadcast_274, which does not exist [2024-08-15T03:18:33.373Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1353.132 ms) ====== [2024-08-15T03:18:33.373Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-15T03:18:33.373Z] GC before operation: completed in 122.139 ms, heap usage 256.532 MB -> 67.284 MB. [2024-08-15T03:18:34.782Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1465.158 ms) ====== [2024-08-15T03:18:34.782Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-15T03:18:34.782Z] GC before operation: completed in 121.754 ms, heap usage 112.649 MB -> 66.822 MB. [2024-08-15T03:18:36.192Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1360.039 ms) ====== [2024-08-15T03:18:36.192Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-15T03:18:36.192Z] GC before operation: completed in 125.785 ms, heap usage 285.307 MB -> 67.485 MB. [2024-08-15T03:18:38.387Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1607.381 ms) ====== [2024-08-15T03:18:38.387Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-15T03:18:38.387Z] GC before operation: completed in 132.566 ms, heap usage 173.682 MB -> 67.509 MB. [2024-08-15T03:18:39.798Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1412.070 ms) ====== [2024-08-15T03:18:39.798Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-15T03:18:39.798Z] GC before operation: completed in 123.976 ms, heap usage 210.219 MB -> 78.954 MB. [2024-08-15T03:18:41.206Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1276.258 ms) ====== [2024-08-15T03:18:41.206Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-15T03:18:41.206Z] GC before operation: completed in 116.016 ms, heap usage 258.509 MB -> 84.284 MB. [2024-08-15T03:18:42.617Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1235.241 ms) ====== [2024-08-15T03:18:42.617Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-15T03:18:42.617Z] GC before operation: completed in 124.376 ms, heap usage 245.674 MB -> 95.498 MB. [2024-08-15T03:18:44.024Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1432.000 ms) ====== [2024-08-15T03:18:44.024Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-15T03:18:44.024Z] GC before operation: completed in 130.949 ms, heap usage 306.821 MB -> 78.908 MB. [2024-08-15T03:18:45.435Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1389.129 ms) ====== [2024-08-15T03:18:45.435Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-15T03:18:45.435Z] GC before operation: completed in 120.018 ms, heap usage 111.207 MB -> 67.978 MB. [2024-08-15T03:18:46.849Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1326.450 ms) ====== [2024-08-15T03:18:46.849Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-15T03:18:46.849Z] GC before operation: completed in 116.748 ms, heap usage 195.220 MB -> 95.789 MB. [2024-08-15T03:18:48.268Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1277.474 ms) ====== [2024-08-15T03:18:48.268Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-15T03:18:48.268Z] GC before operation: completed in 144.425 ms, heap usage 310.879 MB -> 90.258 MB. [2024-08-15T03:18:49.679Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1271.664 ms) ====== [2024-08-15T03:18:49.679Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-15T03:18:49.679Z] GC before operation: completed in 131.126 ms, heap usage 184.259 MB -> 79.226 MB. [2024-08-15T03:18:51.115Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1292.721 ms) ====== [2024-08-15T03:18:51.115Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-15T03:18:51.115Z] GC before operation: completed in 129.552 ms, heap usage 241.161 MB -> 79.557 MB. [2024-08-15T03:18:52.531Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1412.973 ms) ====== [2024-08-15T03:18:52.531Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-15T03:18:52.531Z] GC before operation: completed in 128.768 ms, heap usage 303.379 MB -> 85.078 MB. [2024-08-15T03:18:52.531Z] 24/08/14 22:18:52 WARN BlockManager: Asked to remove block rdd_1663_2, which does not exist [2024-08-15T03:18:53.939Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1338.920 ms) ====== [2024-08-15T03:18:53.939Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-15T03:18:53.939Z] GC before operation: completed in 125.317 ms, heap usage 235.552 MB -> 68.686 MB. [2024-08-15T03:18:55.357Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1400.922 ms) ====== [2024-08-15T03:18:55.357Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-15T03:18:56.035Z] GC before operation: completed in 129.743 ms, heap usage 128.303 MB -> 85.480 MB. [2024-08-15T03:18:56.715Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1244.741 ms) ====== [2024-08-15T03:18:56.715Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-15T03:18:57.395Z] GC before operation: completed in 146.956 ms, heap usage 251.061 MB -> 96.466 MB. [2024-08-15T03:18:58.802Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1284.205 ms) ====== [2024-08-15T03:18:58.802Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-15T03:18:58.802Z] GC before operation: completed in 127.089 ms, heap usage 274.136 MB -> 96.561 MB. [2024-08-15T03:19:00.210Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1394.610 ms) ====== [2024-08-15T03:19:00.210Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-15T03:19:00.210Z] GC before operation: completed in 133.204 ms, heap usage 147.023 MB -> 79.676 MB. [2024-08-15T03:19:01.618Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1524.039 ms) ====== [2024-08-15T03:19:02.296Z] ----------------------------------- [2024-08-15T03:19:02.296Z] renaissance-dec-tree_0_PASSED [2024-08-15T03:19:02.296Z] ----------------------------------- [2024-08-15T03:19:02.296Z] [2024-08-15T03:19:02.296Z] TEST TEARDOWN: [2024-08-15T03:19:02.296Z] Nothing to be done for teardown. [2024-08-15T03:19:02.296Z] renaissance-dec-tree_0 Finish Time: Wed Aug 14 22:19:01 2024 Epoch Time (ms): 1723691941784