[2024-06-08T03:56:41.345Z] Running test renaissance-dec-tree_0 ... [2024-06-08T03:56:41.345Z] =============================================== [2024-06-08T03:56:41.673Z] renaissance-dec-tree_0 Start Time: Sat Jun 8 03:56:41 2024 Epoch Time (ms): 1717819001369 [2024-06-08T03:56:41.673Z] variation: NoOptions [2024-06-08T03:56:41.673Z] JVM_OPTIONS: [2024-06-08T03:56:41.673Z] { \ [2024-06-08T03:56:41.673Z] echo ""; echo "TEST SETUP:"; \ [2024-06-08T03:56:41.673Z] echo "Nothing to be done for setup."; \ [2024-06-08T03:56:41.673Z] mkdir -p "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17178181187604\\renaissance-dec-tree_0"; \ [2024-06-08T03:56:41.673Z] cd "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17178181187604\\renaissance-dec-tree_0"; \ [2024-06-08T03:56:41.673Z] echo ""; echo "TESTING:"; \ [2024-06-08T03:56:41.673Z] "c:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_testList_0/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_testList_0/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17178181187604\\renaissance-dec-tree_0"\\dec-tree.json" dec-tree; \ [2024-06-08T03:56:41.673Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17178181187604\\renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-06-08T03:56:41.673Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-06-08T03:56:41.673Z] echo "Nothing to be done for teardown."; \ [2024-06-08T03:56:41.673Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/\\TKG\\output_17178181187604\\TestTargetResult"; [2024-06-08T03:56:42.002Z] [2024-06-08T03:56:42.002Z] TEST SETUP: [2024-06-08T03:56:42.002Z] Nothing to be done for setup. [2024-06-08T03:56:42.002Z] [2024-06-08T03:56:42.002Z] TESTING: [2024-06-08T03:56:52.705Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-06-08T03:56:53.837Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-06-08T03:56:54.963Z] WARNING: An illegal reflective access operation has occurred [2024-06-08T03:56:54.963Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/C:/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_windows_testList_0/aqa-tests/TKG/output_17178181187604/renaissance-dec-tree_0/harness-035642-12091490515267934922/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-06-08T03:56:54.963Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-06-08T03:56:54.963Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-06-08T03:56:54.964Z] WARNING: All illegal access operations will be denied in a future release [2024-06-08T03:56:57.173Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-06-08T03:56:57.173Z] GC before operation: completed in 47.178 ms, heap usage 67.567 MB -> 34.273 MB. [2024-06-08T03:57:06.698Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8487.100 ms) ====== [2024-06-08T03:57:06.698Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-06-08T03:57:06.698Z] GC before operation: completed in 122.605 ms, heap usage 229.389 MB -> 75.405 MB. [2024-06-08T03:57:08.462Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2550.784 ms) ====== [2024-06-08T03:57:08.462Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-06-08T03:57:08.462Z] GC before operation: completed in 116.198 ms, heap usage 218.009 MB -> 75.539 MB. [2024-06-08T03:57:10.423Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2118.065 ms) ====== [2024-06-08T03:57:10.424Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-06-08T03:57:10.424Z] GC before operation: completed in 91.862 ms, heap usage 143.292 MB -> 65.089 MB. [2024-06-08T03:57:12.217Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1799.219 ms) ====== [2024-06-08T03:57:12.217Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-06-08T03:57:12.217Z] GC before operation: completed in 84.984 ms, heap usage 198.927 MB -> 76.068 MB. [2024-06-08T03:57:14.629Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1984.683 ms) ====== [2024-06-08T03:57:14.629Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-06-08T03:57:14.629Z] GC before operation: completed in 101.543 ms, heap usage 235.796 MB -> 65.177 MB. [2024-06-08T03:57:15.870Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1542.372 ms) ====== [2024-06-08T03:57:15.870Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-06-08T03:57:16.201Z] GC before operation: completed in 91.860 ms, heap usage 154.126 MB -> 65.386 MB. [2024-06-08T03:57:17.861Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1487.790 ms) ====== [2024-06-08T03:57:17.861Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-06-08T03:57:17.861Z] GC before operation: completed in 96.606 ms, heap usage 129.520 MB -> 76.407 MB. [2024-06-08T03:57:19.023Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1420.752 ms) ====== [2024-06-08T03:57:19.024Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-06-08T03:57:19.357Z] GC before operation: completed in 110.406 ms, heap usage 204.585 MB -> 76.993 MB. [2024-06-08T03:57:20.495Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1351.921 ms) ====== [2024-06-08T03:57:20.495Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-06-08T03:57:20.495Z] GC before operation: completed in 100.941 ms, heap usage 260.391 MB -> 76.717 MB. [2024-06-08T03:57:22.134Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1391.260 ms) ====== [2024-06-08T03:57:22.134Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-06-08T03:57:22.134Z] GC before operation: completed in 99.555 ms, heap usage 115.645 MB -> 65.657 MB. [2024-06-08T03:57:23.812Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1386.114 ms) ====== [2024-06-08T03:57:23.812Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-06-08T03:57:23.812Z] GC before operation: completed in 103.128 ms, heap usage 165.743 MB -> 82.457 MB. [2024-06-08T03:57:25.001Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1303.509 ms) ====== [2024-06-08T03:57:25.001Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-06-08T03:57:25.001Z] GC before operation: completed in 107.423 ms, heap usage 263.433 MB -> 77.254 MB. [2024-06-08T03:57:26.668Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1320.645 ms) ====== [2024-06-08T03:57:26.668Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-06-08T03:57:26.668Z] GC before operation: completed in 104.423 ms, heap usage 111.073 MB -> 76.866 MB. [2024-06-08T03:57:27.799Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1335.843 ms) ====== [2024-06-08T03:57:27.799Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-06-08T03:57:27.799Z] GC before operation: completed in 102.567 ms, heap usage 194.933 MB -> 77.412 MB. [2024-06-08T03:57:29.426Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1305.902 ms) ====== [2024-06-08T03:57:29.426Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-06-08T03:57:29.426Z] GC before operation: completed in 118.610 ms, heap usage 192.679 MB -> 71.931 MB. [2024-06-08T03:57:30.554Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1243.049 ms) ====== [2024-06-08T03:57:30.554Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-06-08T03:57:30.554Z] GC before operation: completed in 105.423 ms, heap usage 206.709 MB -> 66.813 MB. [2024-06-08T03:57:32.428Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1280.206 ms) ====== [2024-06-08T03:57:32.428Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-06-08T03:57:32.428Z] GC before operation: completed in 107.627 ms, heap usage 106.694 MB -> 66.206 MB. [2024-06-08T03:57:33.577Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1261.194 ms) ====== [2024-06-08T03:57:33.577Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-06-08T03:57:33.577Z] GC before operation: completed in 109.752 ms, heap usage 184.286 MB -> 83.393 MB. [2024-06-08T03:57:34.717Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1271.206 ms) ====== [2024-06-08T03:57:34.717Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-06-08T03:57:34.717Z] GC before operation: completed in 109.387 ms, heap usage 163.045 MB -> 83.171 MB. [2024-06-08T03:57:34.717Z] 24/06/08 03:57:34 WARN BlockManager: Asked to remove block rdd_928_2, which does not exist [2024-06-08T03:57:36.352Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1236.836 ms) ====== [2024-06-08T03:57:36.352Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-06-08T03:57:36.352Z] GC before operation: completed in 111.611 ms, heap usage 170.425 MB -> 88.916 MB. [2024-06-08T03:57:37.493Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1227.058 ms) ====== [2024-06-08T03:57:37.493Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-06-08T03:57:37.493Z] GC before operation: completed in 112.042 ms, heap usage 163.977 MB -> 83.406 MB. [2024-06-08T03:57:38.671Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1240.455 ms) ====== [2024-06-08T03:57:38.671Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-06-08T03:57:39.011Z] GC before operation: completed in 119.899 ms, heap usage 247.406 MB -> 83.976 MB. [2024-06-08T03:57:40.141Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1214.835 ms) ====== [2024-06-08T03:57:40.142Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-06-08T03:57:40.142Z] GC before operation: completed in 111.224 ms, heap usage 164.074 MB -> 78.136 MB. [2024-06-08T03:57:41.395Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1304.198 ms) ====== [2024-06-08T03:57:41.395Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-06-08T03:57:41.736Z] GC before operation: completed in 113.339 ms, heap usage 143.119 MB -> 83.667 MB. [2024-06-08T03:57:42.887Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1223.926 ms) ====== [2024-06-08T03:57:42.887Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-06-08T03:57:42.888Z] GC before operation: completed in 113.383 ms, heap usage 171.857 MB -> 78.189 MB. [2024-06-08T03:57:44.092Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1215.627 ms) ====== [2024-06-08T03:57:44.092Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-06-08T03:57:44.429Z] GC before operation: completed in 115.467 ms, heap usage 252.306 MB -> 89.615 MB. [2024-06-08T03:57:45.565Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1269.996 ms) ====== [2024-06-08T03:57:45.565Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-06-08T03:57:45.565Z] GC before operation: completed in 116.080 ms, heap usage 219.633 MB -> 78.869 MB. [2024-06-08T03:57:47.213Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1212.223 ms) ====== [2024-06-08T03:57:47.213Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-06-08T03:57:47.213Z] GC before operation: completed in 112.732 ms, heap usage 125.817 MB -> 84.088 MB. [2024-06-08T03:57:47.213Z] 24/06/08 03:57:46 WARN BlockManager: Asked to remove block rdd_1369_2, which does not exist [2024-06-08T03:57:48.346Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1246.796 ms) ====== [2024-06-08T03:57:48.346Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-06-08T03:57:48.346Z] GC before operation: completed in 118.331 ms, heap usage 282.433 MB -> 89.739 MB. [2024-06-08T03:57:49.498Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1212.424 ms) ====== [2024-06-08T03:57:49.498Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-06-08T03:57:49.837Z] GC before operation: completed in 123.195 ms, heap usage 224.013 MB -> 90.022 MB. [2024-06-08T03:57:51.008Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1167.947 ms) ====== [2024-06-08T03:57:51.008Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-06-08T03:57:51.008Z] GC before operation: completed in 117.566 ms, heap usage 226.341 MB -> 90.120 MB. [2024-06-08T03:57:52.167Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1283.780 ms) ====== [2024-06-08T03:57:52.167Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-06-08T03:57:52.499Z] GC before operation: completed in 121.948 ms, heap usage 208.428 MB -> 84.773 MB. [2024-06-08T03:57:53.628Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1218.133 ms) ====== [2024-06-08T03:57:53.628Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-06-08T03:57:53.628Z] GC before operation: completed in 130.681 ms, heap usage 286.793 MB -> 84.844 MB. [2024-06-08T03:57:53.628Z] 24/06/08 03:57:53 WARN BlockManager: Asked to remove block rdd_1614_1, which does not exist [2024-06-08T03:57:55.261Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1217.007 ms) ====== [2024-06-08T03:57:55.261Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-06-08T03:57:55.261Z] GC before operation: completed in 115.820 ms, heap usage 256.860 MB -> 84.943 MB. [2024-06-08T03:57:56.394Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1187.566 ms) ====== [2024-06-08T03:57:56.394Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-06-08T03:57:56.394Z] GC before operation: completed in 124.985 ms, heap usage 213.373 MB -> 85.080 MB. [2024-06-08T03:57:56.394Z] 24/06/08 03:57:56 WARN BlockManager: Asked to remove block rdd_1712_1, which does not exist [2024-06-08T03:57:56.394Z] 24/06/08 03:57:56 WARN BlockManager: Asked to remove block rdd_1712_2, which does not exist [2024-06-08T03:57:57.624Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1339.901 ms) ====== [2024-06-08T03:57:57.624Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-06-08T03:57:57.954Z] GC before operation: completed in 136.697 ms, heap usage 452.967 MB -> 85.390 MB. [2024-06-08T03:57:59.093Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1245.776 ms) ====== [2024-06-08T03:57:59.094Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-06-08T03:57:59.094Z] GC before operation: completed in 115.457 ms, heap usage 224.377 MB -> 79.814 MB. [2024-06-08T03:58:00.881Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1210.391 ms) ====== [2024-06-08T03:58:00.881Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-06-08T03:58:00.881Z] GC before operation: completed in 133.944 ms, heap usage 239.820 MB -> 85.339 MB. [2024-06-08T03:58:01.687Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1218.443 ms) ====== [2024-06-08T03:58:01.687Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-06-08T03:58:02.021Z] GC before operation: completed in 122.078 ms, heap usage 160.748 MB -> 79.800 MB. [2024-06-08T03:58:03.227Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1174.206 ms) ====== [2024-06-08T03:58:03.227Z] ----------------------------------- [2024-06-08T03:58:03.227Z] renaissance-dec-tree_0_PASSED [2024-06-08T03:58:03.227Z] ----------------------------------- [2024-06-08T03:58:03.549Z] [2024-06-08T03:58:03.549Z] TEST TEARDOWN: [2024-06-08T03:58:03.549Z] Nothing to be done for teardown. [2024-06-08T03:58:03.549Z] renaissance-dec-tree_0 Finish Time: Sat Jun 8 03:58:03 2024 Epoch Time (ms): 1717819083365