[2024-08-10T07:52:24.053Z] Running test renaissance-dec-tree_0 ... [2024-08-10T07:52:24.053Z] =============================================== [2024-08-10T07:52:24.053Z] renaissance-dec-tree_0 Start Time: Sat Aug 10 07:52:23 2024 Epoch Time (ms): 1723276343456 [2024-08-10T07:52:24.053Z] variation: NoOptions [2024-08-10T07:52:24.053Z] JVM_OPTIONS: [2024-08-10T07:52:24.053Z] { \ [2024-08-10T07:52:24.053Z] echo ""; echo "TEST SETUP:"; \ [2024-08-10T07:52:24.053Z] echo "Nothing to be done for setup."; \ [2024-08-10T07:52:24.053Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17232755595588/renaissance-dec-tree_0"; \ [2024-08-10T07:52:24.053Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17232755595588/renaissance-dec-tree_0"; \ [2024-08-10T07:52:24.053Z] echo ""; echo "TESTING:"; \ [2024-08-10T07:52:24.053Z] "/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_17232755595588/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-10T07:52:24.053Z] 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_17232755595588/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-10T07:52:24.053Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-10T07:52:24.053Z] echo "Nothing to be done for teardown."; \ [2024-08-10T07:52:24.054Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux_testList_0/aqa-tests/TKG/../TKG/output_17232755595588/TestTargetResult"; [2024-08-10T07:52:24.054Z] [2024-08-10T07:52:24.054Z] TEST SETUP: [2024-08-10T07:52:24.054Z] Nothing to be done for setup. [2024-08-10T07:52:24.054Z] [2024-08-10T07:52:24.054Z] TESTING: [2024-08-10T07:52:28.899Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-10T07:52:32.749Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-08-10T07:52:34.945Z] WARNING: An illegal reflective access operation has occurred [2024-08-10T07:52:34.945Z] 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_17232755595588/renaissance-dec-tree_0/harness-075225-3187559652052296627/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-10T07:52:34.945Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-10T07:52:34.945Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-10T07:52:34.945Z] WARNING: All illegal access operations will be denied in a future release [2024-08-10T07:52:38.875Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-10T07:52:38.875Z] GC before operation: completed in 478.201 ms, heap usage 75.622 MB -> 33.551 MB. [2024-08-10T07:53:00.054Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (18954.227 ms) ====== [2024-08-10T07:53:00.054Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-10T07:53:00.054Z] GC before operation: completed in 494.559 ms, heap usage 170.818 MB -> 62.512 MB. [2024-08-10T07:53:03.870Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (5683.356 ms) ====== [2024-08-10T07:53:03.870Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-10T07:53:04.533Z] GC before operation: completed in 309.999 ms, heap usage 177.581 MB -> 62.742 MB. [2024-08-10T07:53:09.544Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (5375.605 ms) ====== [2024-08-10T07:53:09.544Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-10T07:53:10.244Z] GC before operation: completed in 259.948 ms, heap usage 216.442 MB -> 82.439 MB. [2024-08-10T07:53:15.054Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (4585.169 ms) ====== [2024-08-10T07:53:15.054Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-10T07:53:15.054Z] GC before operation: completed in 270.206 ms, heap usage 183.896 MB -> 62.960 MB. [2024-08-10T07:53:17.978Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (3303.237 ms) ====== [2024-08-10T07:53:17.978Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-10T07:53:18.586Z] GC before operation: completed in 212.331 ms, heap usage 101.164 MB -> 63.282 MB. [2024-08-10T07:53:21.494Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (2890.520 ms) ====== [2024-08-10T07:53:21.494Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-10T07:53:21.494Z] GC before operation: completed in 220.680 ms, heap usage 99.294 MB -> 63.548 MB. [2024-08-10T07:53:24.365Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (3011.985 ms) ====== [2024-08-10T07:53:24.365Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-10T07:53:24.365Z] GC before operation: completed in 186.318 ms, heap usage 151.021 MB -> 91.117 MB. [2024-08-10T07:53:27.306Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2736.145 ms) ====== [2024-08-10T07:53:27.306Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-10T07:53:27.932Z] GC before operation: completed in 323.201 ms, heap usage 238.348 MB -> 63.973 MB. [2024-08-10T07:53:30.769Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (2755.879 ms) ====== [2024-08-10T07:53:30.769Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-10T07:53:30.769Z] GC before operation: completed in 220.580 ms, heap usage 99.164 MB -> 64.157 MB. [2024-08-10T07:53:30.769Z] 24/08/10 07:53:30 WARN BlockManager: Asked to remove block broadcast_120, which does not exist [2024-08-10T07:53:33.669Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (2795.747 ms) ====== [2024-08-10T07:53:33.669Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-10T07:53:33.669Z] GC before operation: completed in 245.079 ms, heap usage 202.669 MB -> 64.168 MB. [2024-08-10T07:53:36.591Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (2590.829 ms) ====== [2024-08-10T07:53:36.591Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-10T07:53:36.591Z] GC before operation: completed in 246.640 ms, heap usage 203.122 MB -> 64.673 MB. [2024-08-10T07:53:39.487Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (2510.801 ms) ====== [2024-08-10T07:53:39.487Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-10T07:53:39.487Z] GC before operation: completed in 250.206 ms, heap usage 127.322 MB -> 64.365 MB. [2024-08-10T07:53:41.504Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (2418.724 ms) ====== [2024-08-10T07:53:41.504Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-10T07:53:42.136Z] GC before operation: completed in 240.501 ms, heap usage 114.729 MB -> 64.592 MB. [2024-08-10T07:53:44.235Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (2230.249 ms) ====== [2024-08-10T07:53:44.235Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-10T07:53:44.235Z] GC before operation: completed in 203.417 ms, heap usage 187.659 MB -> 64.713 MB. [2024-08-10T07:53:47.078Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (2459.602 ms) ====== [2024-08-10T07:53:47.078Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-10T07:53:47.078Z] GC before operation: completed in 206.918 ms, heap usage 152.779 MB -> 64.412 MB. [2024-08-10T07:53:49.103Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (2198.085 ms) ====== [2024-08-10T07:53:49.103Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-10T07:53:49.768Z] GC before operation: completed in 229.762 ms, heap usage 298.549 MB -> 65.013 MB. [2024-08-10T07:53:52.632Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (2762.414 ms) ====== [2024-08-10T07:53:52.632Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-10T07:53:52.632Z] GC before operation: completed in 255.125 ms, heap usage 161.453 MB -> 64.712 MB. [2024-08-10T07:53:55.657Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (2886.717 ms) ====== [2024-08-10T07:53:55.657Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-10T07:53:55.657Z] GC before operation: completed in 278.116 ms, heap usage 137.090 MB -> 64.926 MB. [2024-08-10T07:53:58.508Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (2411.257 ms) ====== [2024-08-10T07:53:58.508Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-10T07:53:58.508Z] GC before operation: completed in 196.601 ms, heap usage 242.222 MB -> 65.173 MB. [2024-08-10T07:54:01.446Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (2593.828 ms) ====== [2024-08-10T07:54:01.446Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-10T07:54:01.446Z] GC before operation: completed in 335.306 ms, heap usage 165.704 MB -> 65.372 MB. [2024-08-10T07:54:04.376Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (2615.363 ms) ====== [2024-08-10T07:54:04.376Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-10T07:54:04.376Z] GC before operation: completed in 223.512 ms, heap usage 103.928 MB -> 65.245 MB. [2024-08-10T07:54:07.218Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (2728.783 ms) ====== [2024-08-10T07:54:07.218Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-10T07:54:07.218Z] GC before operation: completed in 344.944 ms, heap usage 194.702 MB -> 65.606 MB. [2024-08-10T07:54:10.033Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (2654.569 ms) ====== [2024-08-10T07:54:10.033Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-10T07:54:10.033Z] GC before operation: completed in 217.516 ms, heap usage 158.580 MB -> 65.702 MB. [2024-08-10T07:54:12.928Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (2507.085 ms) ====== [2024-08-10T07:54:12.928Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-10T07:54:12.928Z] GC before operation: completed in 225.203 ms, heap usage 198.864 MB -> 65.811 MB. [2024-08-10T07:54:14.982Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (2409.450 ms) ====== [2024-08-10T07:54:14.982Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-10T07:54:15.626Z] GC before operation: completed in 214.731 ms, heap usage 137.956 MB -> 85.183 MB. [2024-08-10T07:54:15.626Z] 24/08/10 07:54:15 WARN BlockManager: Asked to remove block broadcast_344, which does not exist [2024-08-10T07:54:18.575Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (2694.021 ms) ====== [2024-08-10T07:54:18.575Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-10T07:54:18.575Z] GC before operation: completed in 224.294 ms, heap usage 127.511 MB -> 65.703 MB. [2024-08-10T07:54:21.751Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (3014.749 ms) ====== [2024-08-10T07:54:21.751Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-10T07:54:21.751Z] GC before operation: completed in 225.550 ms, heap usage 177.681 MB -> 65.950 MB. [2024-08-10T07:54:23.828Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (2491.174 ms) ====== [2024-08-10T07:54:23.828Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-10T07:54:24.500Z] GC before operation: completed in 290.960 ms, heap usage 103.911 MB -> 65.477 MB. [2024-08-10T07:54:26.535Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (2339.007 ms) ====== [2024-08-10T07:54:26.535Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-10T07:54:27.165Z] GC before operation: completed in 299.284 ms, heap usage 149.428 MB -> 65.862 MB. [2024-08-10T07:54:29.249Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (2400.631 ms) ====== [2024-08-10T07:54:29.249Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-10T07:54:29.908Z] GC before operation: completed in 245.106 ms, heap usage 242.819 MB -> 66.371 MB. [2024-08-10T07:54:31.954Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (2442.000 ms) ====== [2024-08-10T07:54:31.954Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-10T07:54:31.954Z] GC before operation: completed in 176.420 ms, heap usage 151.796 MB -> 66.051 MB. [2024-08-10T07:54:34.911Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (2458.447 ms) ====== [2024-08-10T07:54:34.911Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-10T07:54:34.911Z] GC before operation: completed in 244.700 ms, heap usage 209.797 MB -> 66.438 MB. [2024-08-10T07:54:36.978Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (2212.867 ms) ====== [2024-08-10T07:54:36.978Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-10T07:54:37.594Z] GC before operation: completed in 383.793 ms, heap usage 203.368 MB -> 66.529 MB. [2024-08-10T07:54:39.628Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (2297.055 ms) ====== [2024-08-10T07:54:39.628Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-10T07:54:40.287Z] GC before operation: completed in 212.939 ms, heap usage 129.760 MB -> 66.616 MB. [2024-08-10T07:54:42.336Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (2331.513 ms) ====== [2024-08-10T07:54:42.336Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-10T07:54:42.336Z] GC before operation: completed in 219.785 ms, heap usage 190.334 MB -> 67.089 MB. [2024-08-10T07:54:45.253Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (2433.785 ms) ====== [2024-08-10T07:54:45.253Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-10T07:54:45.253Z] GC before operation: completed in 230.306 ms, heap usage 241.726 MB -> 66.951 MB. [2024-08-10T07:54:48.036Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (2526.967 ms) ====== [2024-08-10T07:54:48.036Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-10T07:54:48.036Z] GC before operation: completed in 413.461 ms, heap usage 142.958 MB -> 67.041 MB. [2024-08-10T07:54:50.959Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2825.595 ms) ====== [2024-08-10T07:54:50.959Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-10T07:54:51.599Z] GC before operation: completed in 312.397 ms, heap usage 222.978 MB -> 86.303 MB. [2024-08-10T07:54:53.570Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (2355.123 ms) ====== [2024-08-10T07:54:53.570Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-10T07:54:54.186Z] GC before operation: completed in 309.067 ms, heap usage 217.087 MB -> 67.085 MB. [2024-08-10T07:54:56.214Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (2326.892 ms) ====== [2024-08-10T07:54:56.838Z] ----------------------------------- [2024-08-10T07:54:56.838Z] renaissance-dec-tree_0_PASSED [2024-08-10T07:54:56.838Z] ----------------------------------- [2024-08-10T07:54:56.838Z] [2024-08-10T07:54:56.838Z] TEST TEARDOWN: [2024-08-10T07:54:56.838Z] Nothing to be done for teardown. [2024-08-10T07:54:56.838Z] renaissance-dec-tree_0 Finish Time: Sat Aug 10 07:54:56 2024 Epoch Time (ms): 1723276496442