[2024-08-01T01:03:47.021Z] Running test renaissance-dec-tree_0 ... [2024-08-01T01:03:47.021Z] =============================================== [2024-08-01T01:03:47.021Z] renaissance-dec-tree_0 Start Time: Wed Jul 31 21:03:46 2024 Epoch Time (ms): 1722474226639 [2024-08-01T01:03:47.021Z] variation: NoOptions [2024-08-01T01:03:47.021Z] JVM_OPTIONS: [2024-08-01T01:03:47.021Z] { \ [2024-08-01T01:03:47.021Z] echo ""; echo "TEST SETUP:"; \ [2024-08-01T01:03:47.021Z] echo "Nothing to be done for setup."; \ [2024-08-01T01:03:47.021Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17224737612761/renaissance-dec-tree_0"; \ [2024-08-01T01:03:47.021Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17224737612761/renaissance-dec-tree_0"; \ [2024-08-01T01:03:47.021Z] echo ""; echo "TESTING:"; \ [2024-08-01T01:03:47.021Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_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 "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17224737612761/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-01T01:03:47.021Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17224737612761/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-01T01:03:47.021Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-01T01:03:47.021Z] echo "Nothing to be done for teardown."; \ [2024-08-01T01:03:47.021Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17224737612761/TestTargetResult"; [2024-08-01T01:03:47.021Z] [2024-08-01T01:03:47.021Z] TEST SETUP: [2024-08-01T01:03:47.021Z] Nothing to be done for setup. [2024-08-01T01:03:47.021Z] [2024-08-01T01:03:47.021Z] TESTING: [2024-08-01T01:03:48.993Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-01T01:03:50.722Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-08-01T01:03:51.334Z] WARNING: An illegal reflective access operation has occurred [2024-08-01T01:03:51.334Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/output_17224737612761/renaissance-dec-tree_0/harness-210347-12444359196873383970/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-01T01:03:51.334Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-01T01:03:51.334Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-01T01:03:51.334Z] WARNING: All illegal access operations will be denied in a future release [2024-08-01T01:03:52.587Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-01T01:03:52.587Z] GC before operation: completed in 40.819 ms, heap usage 71.687 MB -> 33.767 MB. [2024-08-01T01:03:59.653Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (6702.866 ms) ====== [2024-08-01T01:03:59.653Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-01T01:03:59.653Z] GC before operation: completed in 107.898 ms, heap usage 141.108 MB -> 62.720 MB. [2024-08-01T01:04:01.656Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2479.583 ms) ====== [2024-08-01T01:04:01.657Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-01T01:04:01.657Z] GC before operation: completed in 129.214 ms, heap usage 160.809 MB -> 62.909 MB. [2024-08-01T01:04:03.683Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1947.685 ms) ====== [2024-08-01T01:04:03.683Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-01T01:04:03.683Z] GC before operation: completed in 113.593 ms, heap usage 195.290 MB -> 63.314 MB. [2024-08-01T01:04:05.682Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2047.073 ms) ====== [2024-08-01T01:04:05.682Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-01T01:04:06.310Z] GC before operation: completed in 161.905 ms, heap usage 205.518 MB -> 63.796 MB. [2024-08-01T01:04:08.323Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1787.420 ms) ====== [2024-08-01T01:04:08.323Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-01T01:04:08.323Z] GC before operation: completed in 132.407 ms, heap usage 178.950 MB -> 63.755 MB. [2024-08-01T01:04:09.586Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1770.086 ms) ====== [2024-08-01T01:04:09.586Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-01T01:04:10.191Z] GC before operation: completed in 127.459 ms, heap usage 122.255 MB -> 63.784 MB. [2024-08-01T01:04:11.499Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1658.824 ms) ====== [2024-08-01T01:04:11.499Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-01T01:04:11.499Z] GC before operation: completed in 135.275 ms, heap usage 127.083 MB -> 64.108 MB. [2024-08-01T01:04:13.517Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1875.510 ms) ====== [2024-08-01T01:04:13.517Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-01T01:04:13.517Z] GC before operation: completed in 142.636 ms, heap usage 104.665 MB -> 64.029 MB. [2024-08-01T01:04:15.537Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1782.780 ms) ====== [2024-08-01T01:04:15.537Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-01T01:04:15.537Z] GC before operation: completed in 154.070 ms, heap usage 231.375 MB -> 91.869 MB. [2024-08-01T01:04:15.537Z] 24/07/31 21:04:15 WARN BlockManager: Asked to remove block rdd_438_0, which does not exist [2024-08-01T01:04:15.537Z] 24/07/31 21:04:15 WARN BlockManager: Asked to remove block rdd_438_1, which does not exist [2024-08-01T01:04:17.547Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1719.407 ms) ====== [2024-08-01T01:04:17.547Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-01T01:04:17.547Z] GC before operation: completed in 112.734 ms, heap usage 151.790 MB -> 64.359 MB. [2024-08-01T01:04:19.518Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1612.361 ms) ====== [2024-08-01T01:04:19.518Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-01T01:04:19.518Z] GC before operation: completed in 138.962 ms, heap usage 211.435 MB -> 92.095 MB. [2024-08-01T01:04:20.783Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1535.771 ms) ====== [2024-08-01T01:04:20.783Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-01T01:04:20.783Z] GC before operation: completed in 117.861 ms, heap usage 216.768 MB -> 64.584 MB. [2024-08-01T01:04:22.802Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1768.339 ms) ====== [2024-08-01T01:04:22.803Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-01T01:04:22.803Z] GC before operation: completed in 149.341 ms, heap usage 177.381 MB -> 92.305 MB. [2024-08-01T01:04:25.093Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1692.715 ms) ====== [2024-08-01T01:04:25.093Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-01T01:04:25.093Z] GC before operation: completed in 179.657 ms, heap usage 223.094 MB -> 64.790 MB. [2024-08-01T01:04:26.380Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1756.329 ms) ====== [2024-08-01T01:04:26.381Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-01T01:04:26.381Z] GC before operation: completed in 169.241 ms, heap usage 235.912 MB -> 92.492 MB. [2024-08-01T01:04:28.363Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1716.725 ms) ====== [2024-08-01T01:04:28.363Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-01T01:04:28.363Z] GC before operation: completed in 147.918 ms, heap usage 195.010 MB -> 64.944 MB. [2024-08-01T01:04:30.348Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1711.926 ms) ====== [2024-08-01T01:04:30.348Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-01T01:04:30.348Z] GC before operation: completed in 142.163 ms, heap usage 152.302 MB -> 65.151 MB. [2024-08-01T01:04:31.607Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1397.508 ms) ====== [2024-08-01T01:04:31.607Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-01T01:04:31.607Z] GC before operation: completed in 129.117 ms, heap usage 168.062 MB -> 65.405 MB. [2024-08-01T01:04:33.637Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1573.207 ms) ====== [2024-08-01T01:04:33.637Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-01T01:04:33.637Z] GC before operation: completed in 169.443 ms, heap usage 216.101 MB -> 92.965 MB. [2024-08-01T01:04:34.906Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1579.610 ms) ====== [2024-08-01T01:04:34.906Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-01T01:04:35.533Z] GC before operation: completed in 141.041 ms, heap usage 193.648 MB -> 65.831 MB. [2024-08-01T01:04:36.800Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1509.783 ms) ====== [2024-08-01T01:04:36.800Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-01T01:04:36.800Z] GC before operation: completed in 171.052 ms, heap usage 197.932 MB -> 93.194 MB. [2024-08-01T01:04:36.800Z] 24/07/31 21:04:36 WARN BlockManager: Asked to remove block broadcast_288, which does not exist [2024-08-01T01:04:38.847Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1492.796 ms) ====== [2024-08-01T01:04:38.847Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-01T01:04:38.847Z] GC before operation: completed in 139.586 ms, heap usage 188.760 MB -> 65.554 MB. [2024-08-01T01:04:40.148Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1571.149 ms) ====== [2024-08-01T01:04:40.148Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-01T01:04:40.148Z] GC before operation: completed in 162.579 ms, heap usage 110.402 MB -> 65.334 MB. [2024-08-01T01:04:42.173Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1527.270 ms) ====== [2024-08-01T01:04:42.173Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-01T01:04:42.173Z] GC before operation: completed in 140.764 ms, heap usage 143.029 MB -> 93.433 MB. [2024-08-01T01:04:44.179Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1720.257 ms) ====== [2024-08-01T01:04:44.179Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-01T01:04:44.179Z] GC before operation: completed in 193.323 ms, heap usage 184.047 MB -> 66.305 MB. [2024-08-01T01:04:45.432Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1674.453 ms) ====== [2024-08-01T01:04:45.432Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-01T01:04:46.044Z] GC before operation: completed in 170.700 ms, heap usage 127.899 MB -> 93.370 MB. [2024-08-01T01:04:46.044Z] 24/07/31 21:04:45 WARN BlockManager: Asked to remove block broadcast_362_piece0, which does not exist [2024-08-01T01:04:47.323Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1636.949 ms) ====== [2024-08-01T01:04:47.323Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-01T01:04:47.963Z] GC before operation: completed in 177.149 ms, heap usage 183.795 MB -> 93.469 MB. [2024-08-01T01:04:49.227Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1475.990 ms) ====== [2024-08-01T01:04:49.227Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-01T01:04:49.227Z] GC before operation: completed in 198.886 ms, heap usage 197.990 MB -> 65.963 MB. [2024-08-01T01:04:51.183Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1509.700 ms) ====== [2024-08-01T01:04:51.183Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-01T01:04:51.183Z] GC before operation: completed in 140.558 ms, heap usage 126.988 MB -> 66.657 MB. [2024-08-01T01:04:52.473Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1773.249 ms) ====== [2024-08-01T01:04:52.473Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-01T01:04:53.093Z] GC before operation: completed in 155.356 ms, heap usage 171.774 MB -> 66.580 MB. [2024-08-01T01:04:54.433Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1396.377 ms) ====== [2024-08-01T01:04:54.433Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-01T01:04:54.433Z] GC before operation: completed in 129.887 ms, heap usage 184.016 MB -> 94.128 MB. [2024-08-01T01:04:56.389Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1566.436 ms) ====== [2024-08-01T01:04:56.389Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-01T01:04:56.389Z] GC before operation: completed in 168.362 ms, heap usage 202.727 MB -> 94.233 MB. [2024-08-01T01:04:58.383Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1893.427 ms) ====== [2024-08-01T01:04:58.383Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-01T01:04:58.383Z] GC before operation: completed in 220.468 ms, heap usage 296.886 MB -> 66.729 MB. [2024-08-01T01:04:59.737Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1669.046 ms) ====== [2024-08-01T01:04:59.737Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-01T01:05:00.373Z] GC before operation: completed in 162.801 ms, heap usage 105.289 MB -> 66.671 MB. [2024-08-01T01:05:01.691Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1476.860 ms) ====== [2024-08-01T01:05:01.691Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-01T01:05:01.691Z] GC before operation: completed in 173.415 ms, heap usage 165.049 MB -> 66.908 MB. [2024-08-01T01:05:03.343Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1491.740 ms) ====== [2024-08-01T01:05:03.343Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-01T01:05:03.343Z] GC before operation: completed in 170.668 ms, heap usage 169.482 MB -> 66.885 MB. [2024-08-01T01:05:04.675Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1335.199 ms) ====== [2024-08-01T01:05:04.675Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-01T01:05:04.675Z] GC before operation: completed in 136.721 ms, heap usage 179.380 MB -> 67.105 MB. [2024-08-01T01:05:06.663Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1485.265 ms) ====== [2024-08-01T01:05:06.663Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-01T01:05:06.663Z] GC before operation: completed in 184.650 ms, heap usage 216.802 MB -> 86.648 MB. [2024-08-01T01:05:07.937Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1456.209 ms) ====== [2024-08-01T01:05:07.937Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-01T01:05:07.937Z] GC before operation: completed in 192.256 ms, heap usage 269.132 MB -> 94.896 MB. [2024-08-01T01:05:10.061Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1467.295 ms) ====== [2024-08-01T01:05:10.061Z] ----------------------------------- [2024-08-01T01:05:10.061Z] renaissance-dec-tree_0_PASSED [2024-08-01T01:05:10.061Z] ----------------------------------- [2024-08-01T01:05:10.061Z] [2024-08-01T01:05:10.061Z] TEST TEARDOWN: [2024-08-01T01:05:10.061Z] Nothing to be done for teardown. [2024-08-01T01:05:10.061Z] renaissance-dec-tree_0 Finish Time: Wed Jul 31 21:05:09 2024 Epoch Time (ms): 1722474309643