[2024-08-28T23:47:03.475Z] Running test renaissance-dec-tree_0 ... [2024-08-28T23:47:03.475Z] =============================================== [2024-08-28T23:47:03.475Z] renaissance-dec-tree_0 Start Time: Wed Aug 28 23:47:02 2024 Epoch Time (ms): 1724888822945 [2024-08-28T23:47:03.475Z] variation: NoOptions [2024-08-28T23:47:03.475Z] JVM_OPTIONS: [2024-08-28T23:47:03.475Z] { \ [2024-08-28T23:47:03.475Z] echo ""; echo "TEST SETUP:"; \ [2024-08-28T23:47:03.475Z] echo "Nothing to be done for setup."; \ [2024-08-28T23:47:03.475Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17248880149161/renaissance-dec-tree_0"; \ [2024-08-28T23:47:03.475Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17248880149161/renaissance-dec-tree_0"; \ [2024-08-28T23:47:03.475Z] echo ""; echo "TESTING:"; \ [2024-08-28T23:47:03.475Z] "/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_17248880149161/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-28T23:47:03.475Z] 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_17248880149161/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-28T23:47:03.475Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-28T23:47:03.475Z] echo "Nothing to be done for teardown."; \ [2024-08-28T23:47:03.475Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17248880149161/TestTargetResult"; [2024-08-28T23:47:03.475Z] [2024-08-28T23:47:03.475Z] TEST SETUP: [2024-08-28T23:47:03.475Z] Nothing to be done for setup. [2024-08-28T23:47:03.475Z] [2024-08-28T23:47:03.475Z] TESTING: [2024-08-28T23:47:08.167Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-28T23:47:10.227Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-08-28T23:47:12.677Z] WARNING: An illegal reflective access operation has occurred [2024-08-28T23:47:12.677Z] 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_17248880149161/renaissance-dec-tree_0/harness-234704-15279915363768881009/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-28T23:47:12.677Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-28T23:47:12.677Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-28T23:47:12.677Z] WARNING: All illegal access operations will be denied in a future release [2024-08-28T23:47:14.743Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-28T23:47:14.743Z] GC before operation: completed in 103.582 ms, heap usage 80.749 MB -> 33.542 MB. [2024-08-28T23:47:26.849Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (11657.157 ms) ====== [2024-08-28T23:47:26.849Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-28T23:47:26.849Z] GC before operation: completed in 216.277 ms, heap usage 111.911 MB -> 62.488 MB. [2024-08-28T23:47:30.603Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (3965.575 ms) ====== [2024-08-28T23:47:30.603Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-28T23:47:30.604Z] GC before operation: completed in 236.953 ms, heap usage 169.334 MB -> 89.839 MB. [2024-08-28T23:47:34.306Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (3226.968 ms) ====== [2024-08-28T23:47:34.306Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-28T23:47:34.306Z] GC before operation: completed in 193.247 ms, heap usage 218.936 MB -> 82.427 MB. [2024-08-28T23:47:37.161Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2507.212 ms) ====== [2024-08-28T23:47:37.161Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-28T23:47:37.161Z] GC before operation: completed in 169.265 ms, heap usage 172.917 MB -> 90.824 MB. [2024-08-28T23:47:40.022Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2887.890 ms) ====== [2024-08-28T23:47:40.022Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-28T23:47:40.022Z] GC before operation: completed in 198.375 ms, heap usage 214.834 MB -> 63.409 MB. [2024-08-28T23:47:42.836Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (2674.048 ms) ====== [2024-08-28T23:47:42.836Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-28T23:47:42.836Z] GC before operation: completed in 198.456 ms, heap usage 152.900 MB -> 63.329 MB. [2024-08-28T23:47:45.621Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (2761.966 ms) ====== [2024-08-28T23:47:45.621Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-28T23:47:45.621Z] GC before operation: completed in 271.200 ms, heap usage 138.935 MB -> 63.632 MB. [2024-08-28T23:47:48.403Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2697.065 ms) ====== [2024-08-28T23:47:48.403Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-28T23:47:49.038Z] GC before operation: completed in 190.771 ms, heap usage 150.925 MB -> 63.961 MB. [2024-08-28T23:47:51.460Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (2316.032 ms) ====== [2024-08-28T23:47:51.460Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-28T23:47:51.460Z] GC before operation: completed in 219.114 ms, heap usage 148.346 MB -> 64.217 MB. [2024-08-28T23:47:54.313Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (2690.197 ms) ====== [2024-08-28T23:47:54.313Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-28T23:47:54.313Z] GC before operation: completed in 287.987 ms, heap usage 191.357 MB -> 64.167 MB. [2024-08-28T23:47:56.433Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (2392.875 ms) ====== [2024-08-28T23:47:56.433Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-28T23:47:57.056Z] GC before operation: completed in 277.188 ms, heap usage 97.625 MB -> 63.986 MB. [2024-08-28T23:48:00.037Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (2744.027 ms) ====== [2024-08-28T23:48:00.037Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-28T23:48:00.037Z] GC before operation: completed in 311.661 ms, heap usage 191.474 MB -> 64.533 MB. [2024-08-28T23:48:02.822Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (2617.951 ms) ====== [2024-08-28T23:48:02.822Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-28T23:48:02.822Z] GC before operation: completed in 261.137 ms, heap usage 269.328 MB -> 64.636 MB. [2024-08-28T23:48:05.673Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (2300.880 ms) ====== [2024-08-28T23:48:05.673Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-28T23:48:05.673Z] GC before operation: completed in 293.829 ms, heap usage 205.446 MB -> 64.732 MB. [2024-08-28T23:48:07.692Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (2537.634 ms) ====== [2024-08-28T23:48:07.692Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-28T23:48:08.311Z] GC before operation: completed in 266.290 ms, heap usage 164.033 MB -> 65.060 MB. [2024-08-28T23:48:10.296Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (2283.668 ms) ====== [2024-08-28T23:48:10.296Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-28T23:48:10.928Z] GC before operation: completed in 237.754 ms, heap usage 260.331 MB -> 64.853 MB. [2024-08-28T23:48:13.770Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (2527.869 ms) ====== [2024-08-28T23:48:13.770Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-28T23:48:13.770Z] GC before operation: completed in 222.099 ms, heap usage 156.785 MB -> 64.684 MB. [2024-08-28T23:48:15.819Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (2308.375 ms) ====== [2024-08-28T23:48:15.819Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-28T23:48:15.819Z] GC before operation: completed in 190.034 ms, heap usage 149.833 MB -> 65.459 MB. [2024-08-28T23:48:18.624Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (2403.801 ms) ====== [2024-08-28T23:48:18.624Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-28T23:48:18.624Z] GC before operation: completed in 250.215 ms, heap usage 103.353 MB -> 65.025 MB. [2024-08-28T23:48:21.461Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (2312.834 ms) ====== [2024-08-28T23:48:21.461Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-28T23:48:21.461Z] GC before operation: completed in 290.211 ms, heap usage 251.622 MB -> 92.861 MB. [2024-08-28T23:48:23.610Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (2507.272 ms) ====== [2024-08-28T23:48:23.610Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-28T23:48:24.240Z] GC before operation: completed in 289.447 ms, heap usage 219.643 MB -> 65.354 MB. [2024-08-28T23:48:26.266Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (2318.445 ms) ====== [2024-08-28T23:48:26.266Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-28T23:48:26.904Z] GC before operation: completed in 275.691 ms, heap usage 123.163 MB -> 65.463 MB. [2024-08-28T23:48:29.406Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (2401.312 ms) ====== [2024-08-28T23:48:29.406Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-28T23:48:29.406Z] GC before operation: completed in 346.244 ms, heap usage 167.564 MB -> 65.445 MB. [2024-08-28T23:48:32.338Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (2492.778 ms) ====== [2024-08-28T23:48:32.338Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-28T23:48:32.338Z] GC before operation: completed in 311.172 ms, heap usage 233.294 MB -> 65.811 MB. [2024-08-28T23:48:35.290Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (3215.206 ms) ====== [2024-08-28T23:48:35.290Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-28T23:48:35.924Z] GC before operation: completed in 292.473 ms, heap usage 157.267 MB -> 65.760 MB. [2024-08-28T23:48:38.044Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (2371.395 ms) ====== [2024-08-28T23:48:38.044Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-28T23:48:38.696Z] GC before operation: completed in 256.325 ms, heap usage 108.291 MB -> 65.708 MB. [2024-08-28T23:48:41.558Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (2705.420 ms) ====== [2024-08-28T23:48:41.558Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-28T23:48:41.558Z] GC before operation: completed in 240.580 ms, heap usage 157.173 MB -> 65.677 MB. [2024-08-28T23:48:43.577Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (2479.652 ms) ====== [2024-08-28T23:48:43.578Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-28T23:48:44.233Z] GC before operation: completed in 417.549 ms, heap usage 210.681 MB -> 66.029 MB. [2024-08-28T23:48:47.072Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (2573.069 ms) ====== [2024-08-28T23:48:47.072Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-28T23:48:47.072Z] GC before operation: completed in 302.503 ms, heap usage 187.244 MB -> 93.725 MB. [2024-08-28T23:48:49.897Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (2433.218 ms) ====== [2024-08-28T23:48:49.897Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-28T23:48:49.897Z] GC before operation: completed in 259.697 ms, heap usage 263.550 MB -> 66.359 MB. [2024-08-28T23:48:51.874Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (2117.460 ms) ====== [2024-08-28T23:48:51.874Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-28T23:48:51.874Z] GC before operation: completed in 247.590 ms, heap usage 116.269 MB -> 65.899 MB. [2024-08-28T23:48:54.786Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (2466.091 ms) ====== [2024-08-28T23:48:54.786Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-28T23:48:54.786Z] GC before operation: completed in 238.754 ms, heap usage 168.233 MB -> 66.587 MB. [2024-08-28T23:48:57.660Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (2345.579 ms) ====== [2024-08-28T23:48:57.660Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-28T23:48:57.660Z] GC before operation: completed in 283.047 ms, heap usage 182.862 MB -> 66.680 MB. [2024-08-28T23:48:59.717Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (2269.367 ms) ====== [2024-08-28T23:48:59.717Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-28T23:48:59.717Z] GC before operation: completed in 205.101 ms, heap usage 219.826 MB -> 66.780 MB. [2024-08-28T23:49:02.095Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (2022.368 ms) ====== [2024-08-28T23:49:02.095Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-28T23:49:02.095Z] GC before operation: completed in 271.687 ms, heap usage 234.572 MB -> 66.738 MB. [2024-08-28T23:49:04.920Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (2320.529 ms) ====== [2024-08-28T23:49:04.920Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-28T23:49:04.920Z] GC before operation: completed in 308.427 ms, heap usage 200.470 MB -> 67.217 MB. [2024-08-28T23:49:06.996Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (2322.738 ms) ====== [2024-08-28T23:49:06.996Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-28T23:49:07.608Z] GC before operation: completed in 264.544 ms, heap usage 234.143 MB -> 66.957 MB. [2024-08-28T23:49:09.613Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2092.271 ms) ====== [2024-08-28T23:49:09.613Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-28T23:49:10.259Z] GC before operation: completed in 504.326 ms, heap usage 198.092 MB -> 67.159 MB. [2024-08-28T23:49:12.301Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (2491.582 ms) ====== [2024-08-28T23:49:12.301Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-28T23:49:12.917Z] GC before operation: completed in 230.898 ms, heap usage 167.216 MB -> 66.987 MB. [2024-08-28T23:49:15.774Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (2496.233 ms) ====== [2024-08-28T23:49:15.774Z] ----------------------------------- [2024-08-28T23:49:15.774Z] renaissance-dec-tree_0_PASSED [2024-08-28T23:49:15.774Z] ----------------------------------- [2024-08-28T23:49:15.774Z] [2024-08-28T23:49:15.774Z] TEST TEARDOWN: [2024-08-28T23:49:15.774Z] Nothing to be done for teardown. [2024-08-28T23:49:15.774Z] renaissance-dec-tree_0 Finish Time: Wed Aug 28 23:49:15 2024 Epoch Time (ms): 1724888955350