[2024-08-17T01:41:02.692Z] Running test renaissance-dec-tree_0 ... [2024-08-17T01:41:02.692Z] =============================================== [2024-08-17T01:41:02.692Z] renaissance-dec-tree_0 Start Time: Sat Aug 17 01:41:02 2024 Epoch Time (ms): 1723858862276 [2024-08-17T01:41:02.692Z] variation: NoOptions [2024-08-17T01:41:02.692Z] JVM_OPTIONS: [2024-08-17T01:41:02.692Z] { \ [2024-08-17T01:41:02.692Z] echo ""; echo "TEST SETUP:"; \ [2024-08-17T01:41:02.692Z] echo "Nothing to be done for setup."; \ [2024-08-17T01:41:02.692Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17238570491189/renaissance-dec-tree_0"; \ [2024-08-17T01:41:02.692Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17238570491189/renaissance-dec-tree_0"; \ [2024-08-17T01:41:02.692Z] echo ""; echo "TESTING:"; \ [2024-08-17T01:41:02.692Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-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_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17238570491189/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-17T01:41:02.692Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17238570491189/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-17T01:41:02.692Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-17T01:41:02.692Z] echo "Nothing to be done for teardown."; \ [2024-08-17T01:41:02.692Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/../TKG/output_17238570491189/TestTargetResult"; [2024-08-17T01:41:02.692Z] [2024-08-17T01:41:02.692Z] TEST SETUP: [2024-08-17T01:41:02.692Z] Nothing to be done for setup. [2024-08-17T01:41:02.692Z] [2024-08-17T01:41:02.692Z] TESTING: [2024-08-17T01:41:12.355Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-17T01:41:20.443Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-08-17T01:41:24.979Z] WARNING: An illegal reflective access operation has occurred [2024-08-17T01:41:24.979Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux_testList_0/aqa-tests/TKG/output_17238570491189/renaissance-dec-tree_0/harness-014104-8232243455357644659/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-17T01:41:24.979Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-17T01:41:24.979Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-17T01:41:24.979Z] WARNING: All illegal access operations will be denied in a future release [2024-08-17T01:41:35.361Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-17T01:41:35.361Z] GC before operation: completed in 290.448 ms, heap usage 47.498 MB -> 33.791 MB. [2024-08-17T01:42:25.155Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (47576.424 ms) ====== [2024-08-17T01:42:25.155Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-17T01:42:25.155Z] GC before operation: completed in 640.272 ms, heap usage 103.384 MB -> 63.754 MB. [2024-08-17T01:42:37.579Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (14999.381 ms) ====== [2024-08-17T01:42:37.579Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-17T01:42:38.431Z] GC before operation: completed in 689.019 ms, heap usage 206.351 MB -> 63.977 MB. [2024-08-17T01:42:53.079Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (13655.038 ms) ====== [2024-08-17T01:42:53.079Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-17T01:42:53.079Z] GC before operation: completed in 603.132 ms, heap usage 225.682 MB -> 64.460 MB. [2024-08-17T01:43:05.508Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (11793.537 ms) ====== [2024-08-17T01:43:05.508Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-17T01:43:05.508Z] GC before operation: completed in 644.175 ms, heap usage 132.183 MB -> 64.453 MB. [2024-08-17T01:43:15.950Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (10505.801 ms) ====== [2024-08-17T01:43:15.950Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-17T01:43:15.950Z] GC before operation: completed in 515.630 ms, heap usage 114.038 MB -> 64.590 MB. [2024-08-17T01:43:27.039Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (10400.813 ms) ====== [2024-08-17T01:43:27.039Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-17T01:43:27.039Z] GC before operation: completed in 566.438 ms, heap usage 240.030 MB -> 92.482 MB. [2024-08-17T01:43:37.495Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (9017.526 ms) ====== [2024-08-17T01:43:37.495Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-17T01:43:37.495Z] GC before operation: completed in 586.665 ms, heap usage 343.423 MB -> 65.075 MB. [2024-08-17T01:43:46.318Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (9079.856 ms) ====== [2024-08-17T01:43:46.318Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-17T01:43:46.318Z] GC before operation: completed in 517.307 ms, heap usage 204.807 MB -> 65.181 MB. [2024-08-17T01:43:55.135Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (8662.652 ms) ====== [2024-08-17T01:43:55.135Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-17T01:43:55.967Z] GC before operation: completed in 549.591 ms, heap usage 287.497 MB -> 92.888 MB. [2024-08-17T01:44:04.763Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (7978.763 ms) ====== [2024-08-17T01:44:04.763Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-17T01:44:04.763Z] GC before operation: completed in 529.154 ms, heap usage 145.459 MB -> 65.542 MB. [2024-08-17T01:44:12.103Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (7685.513 ms) ====== [2024-08-17T01:44:12.103Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-17T01:44:12.103Z] GC before operation: completed in 505.067 ms, heap usage 254.893 MB -> 65.658 MB. [2024-08-17T01:44:20.831Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (7567.537 ms) ====== [2024-08-17T01:44:20.831Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-17T01:44:20.831Z] GC before operation: completed in 515.134 ms, heap usage 158.953 MB -> 65.346 MB. [2024-08-17T01:44:28.166Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (7141.152 ms) ====== [2024-08-17T01:44:28.167Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-17T01:44:28.167Z] GC before operation: completed in 469.882 ms, heap usage 100.814 MB -> 65.451 MB. [2024-08-17T01:44:34.824Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (6603.432 ms) ====== [2024-08-17T01:44:34.825Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-17T01:44:35.643Z] GC before operation: completed in 533.428 ms, heap usage 285.965 MB -> 65.977 MB. [2024-08-17T01:44:42.933Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (7051.357 ms) ====== [2024-08-17T01:44:42.933Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-17T01:44:42.933Z] GC before operation: completed in 528.715 ms, heap usage 255.382 MB -> 66.083 MB. [2024-08-17T01:44:50.258Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (6644.557 ms) ====== [2024-08-17T01:44:50.258Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-17T01:44:50.258Z] GC before operation: completed in 500.115 ms, heap usage 171.469 MB -> 65.834 MB. [2024-08-17T01:44:56.303Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (6619.008 ms) ====== [2024-08-17T01:44:56.303Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-17T01:44:57.124Z] GC before operation: completed in 609.815 ms, heap usage 118.799 MB -> 66.075 MB. [2024-08-17T01:45:04.514Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (7250.131 ms) ====== [2024-08-17T01:45:04.514Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-17T01:45:05.344Z] GC before operation: completed in 574.504 ms, heap usage 161.451 MB -> 93.650 MB. [2024-08-17T01:45:11.380Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (6037.979 ms) ====== [2024-08-17T01:45:11.380Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-17T01:45:11.380Z] GC before operation: completed in 627.923 ms, heap usage 357.297 MB -> 66.560 MB. [2024-08-17T01:45:18.715Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (6601.641 ms) ====== [2024-08-17T01:45:18.715Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-17T01:45:18.715Z] GC before operation: completed in 528.348 ms, heap usage 129.270 MB -> 66.639 MB. [2024-08-17T01:45:24.824Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (5303.728 ms) ====== [2024-08-17T01:45:24.824Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-17T01:45:24.824Z] GC before operation: completed in 615.121 ms, heap usage 198.764 MB -> 66.757 MB. [2024-08-17T01:45:30.835Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (6069.043 ms) ====== [2024-08-17T01:45:30.835Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-17T01:45:32.023Z] GC before operation: completed in 622.113 ms, heap usage 144.534 MB -> 66.590 MB. [2024-08-17T01:45:37.989Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (6157.866 ms) ====== [2024-08-17T01:45:37.989Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-17T01:45:37.989Z] GC before operation: completed in 602.547 ms, heap usage 254.100 MB -> 66.830 MB. [2024-08-17T01:45:45.275Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (6190.696 ms) ====== [2024-08-17T01:45:45.275Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-17T01:45:45.275Z] GC before operation: completed in 525.590 ms, heap usage 227.467 MB -> 67.075 MB. [2024-08-17T01:45:51.215Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (5517.641 ms) ====== [2024-08-17T01:45:51.215Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-17T01:45:51.215Z] GC before operation: completed in 549.752 ms, heap usage 176.011 MB -> 66.894 MB. [2024-08-17T01:45:57.165Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (5512.964 ms) ====== [2024-08-17T01:45:57.165Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-17T01:45:57.165Z] GC before operation: completed in 562.396 ms, heap usage 146.050 MB -> 66.962 MB. [2024-08-17T01:46:03.327Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (5958.670 ms) ====== [2024-08-17T01:46:03.327Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-17T01:46:03.327Z] GC before operation: completed in 572.614 ms, heap usage 267.055 MB -> 94.811 MB. [2024-08-17T01:46:03.327Z] 24/08/17 01:46:03 WARN BlockManager: Asked to remove block broadcast_372, which does not exist [2024-08-17T01:46:09.253Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (5289.294 ms) ====== [2024-08-17T01:46:09.253Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-17T01:46:09.253Z] GC before operation: completed in 475.208 ms, heap usage 318.114 MB -> 67.453 MB. [2024-08-17T01:46:15.237Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (5070.042 ms) ====== [2024-08-17T01:46:15.237Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-17T01:46:15.237Z] GC before operation: completed in 495.305 ms, heap usage 205.062 MB -> 67.554 MB. [2024-08-17T01:46:21.203Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (5422.018 ms) ====== [2024-08-17T01:46:21.203Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-17T01:46:21.203Z] GC before operation: completed in 489.966 ms, heap usage 158.858 MB -> 67.380 MB. [2024-08-17T01:46:27.175Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (6036.407 ms) ====== [2024-08-17T01:46:27.175Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-17T01:46:27.175Z] GC before operation: completed in 555.429 ms, heap usage 216.417 MB -> 67.743 MB. [2024-08-17T01:46:33.743Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (5433.221 ms) ====== [2024-08-17T01:46:33.743Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-17T01:46:33.743Z] GC before operation: completed in 550.315 ms, heap usage 142.227 MB -> 67.411 MB. [2024-08-17T01:46:39.703Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (5466.682 ms) ====== [2024-08-17T01:46:39.703Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-17T01:46:39.703Z] GC before operation: completed in 618.815 ms, heap usage 147.190 MB -> 67.648 MB. [2024-08-17T01:46:44.458Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (4683.518 ms) ====== [2024-08-17T01:46:44.458Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-17T01:46:44.458Z] GC before operation: completed in 586.026 ms, heap usage 320.065 MB -> 84.605 MB. [2024-08-17T01:46:50.395Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (5282.780 ms) ====== [2024-08-17T01:46:50.395Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-17T01:46:50.395Z] GC before operation: completed in 503.851 ms, heap usage 185.813 MB -> 67.899 MB. [2024-08-17T01:46:57.689Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (6376.547 ms) ====== [2024-08-17T01:46:57.689Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-17T01:46:57.689Z] GC before operation: completed in 533.396 ms, heap usage 188.786 MB -> 68.259 MB. [2024-08-17T01:47:03.656Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (5650.193 ms) ====== [2024-08-17T01:47:03.656Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-17T01:47:03.656Z] GC before operation: completed in 596.527 ms, heap usage 135.502 MB -> 68.350 MB. [2024-08-17T01:47:09.620Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (5566.350 ms) ====== [2024-08-17T01:47:09.620Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-17T01:47:09.621Z] GC before operation: completed in 534.431 ms, heap usage 254.774 MB -> 68.451 MB. [2024-08-17T01:47:15.600Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (5799.372 ms) ====== [2024-08-17T01:47:15.600Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-17T01:47:16.428Z] GC before operation: completed in 594.254 ms, heap usage 195.001 MB -> 68.544 MB. [2024-08-17T01:47:21.171Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (5226.372 ms) ====== [2024-08-17T01:47:22.863Z] ----------------------------------- [2024-08-17T01:47:22.863Z] renaissance-dec-tree_0_PASSED [2024-08-17T01:47:22.863Z] ----------------------------------- [2024-08-17T01:47:22.863Z] [2024-08-17T01:47:22.863Z] TEST TEARDOWN: [2024-08-17T01:47:22.863Z] Nothing to be done for teardown. [2024-08-17T01:47:22.863Z] renaissance-dec-tree_0 Finish Time: Sat Aug 17 01:47:22 2024 Epoch Time (ms): 1723859242071