[2024-09-05T06:12:53.738Z] Running test renaissance-dec-tree_0 ... [2024-09-05T06:12:53.738Z] =============================================== [2024-09-05T06:12:53.738Z] renaissance-dec-tree_0 Start Time: Thu Sep 5 01:12:53 2024 Epoch Time (ms): 1725516773448 [2024-09-05T06:12:53.738Z] variation: NoOptions [2024-09-05T06:12:53.738Z] JVM_OPTIONS: [2024-09-05T06:12:53.738Z] { \ [2024-09-05T06:12:53.738Z] echo ""; echo "TEST SETUP:"; \ [2024-09-05T06:12:53.738Z] echo "Nothing to be done for setup."; \ [2024-09-05T06:12:53.738Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17255165195221/renaissance-dec-tree_0"; \ [2024-09-05T06:12:53.738Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17255165195221/renaissance-dec-tree_0"; \ [2024-09-05T06:12:53.738Z] echo ""; echo "TESTING:"; \ [2024-09-05T06:12:53.738Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/jdkbinary/j2sdk-image/jdk-17.0.13+6/bin/..//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_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17255165195221/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-09-05T06:12:53.738Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17255165195221/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-09-05T06:12:53.738Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-09-05T06:12:53.738Z] echo "Nothing to be done for teardown."; \ [2024-09-05T06:12:53.738Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17255165195221/TestTargetResult"; [2024-09-05T06:12:53.738Z] [2024-09-05T06:12:53.738Z] TEST SETUP: [2024-09-05T06:12:53.738Z] Nothing to be done for setup. [2024-09-05T06:12:53.738Z] [2024-09-05T06:12:53.738Z] TESTING: [2024-09-05T06:12:56.815Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-09-05T06:12:58.234Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 6 (out of 8) threads. [2024-09-05T06:13:01.312Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-09-05T06:13:01.312Z] GC before operation: completed in 67.018 ms, heap usage 40.673 MB -> 35.602 MB. [2024-09-05T06:13:09.076Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (7161.324 ms) ====== [2024-09-05T06:13:09.076Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-09-05T06:13:09.076Z] GC before operation: completed in 143.960 ms, heap usage 440.353 MB -> 65.066 MB. [2024-09-05T06:13:11.289Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2217.968 ms) ====== [2024-09-05T06:13:11.289Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-09-05T06:13:11.289Z] GC before operation: completed in 87.399 ms, heap usage 201.312 MB -> 65.597 MB. [2024-09-05T06:13:12.713Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1764.967 ms) ====== [2024-09-05T06:13:12.713Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-09-05T06:13:12.713Z] GC before operation: completed in 87.472 ms, heap usage 325.189 MB -> 77.024 MB. [2024-09-05T06:13:14.135Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1384.568 ms) ====== [2024-09-05T06:13:14.135Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-09-05T06:13:14.135Z] GC before operation: completed in 83.671 ms, heap usage 434.638 MB -> 66.197 MB. [2024-09-05T06:13:15.556Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1357.764 ms) ====== [2024-09-05T06:13:15.556Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-09-05T06:13:15.556Z] GC before operation: completed in 92.782 ms, heap usage 277.105 MB -> 82.867 MB. [2024-09-05T06:13:16.978Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1408.912 ms) ====== [2024-09-05T06:13:16.978Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-09-05T06:13:17.669Z] GC before operation: completed in 100.372 ms, heap usage 378.913 MB -> 82.906 MB. [2024-09-05T06:13:19.089Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1601.402 ms) ====== [2024-09-05T06:13:19.089Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-09-05T06:13:19.089Z] GC before operation: completed in 97.015 ms, heap usage 356.066 MB -> 88.800 MB. [2024-09-05T06:13:20.510Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1545.001 ms) ====== [2024-09-05T06:13:20.510Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-09-05T06:13:20.510Z] GC before operation: completed in 86.753 ms, heap usage 293.817 MB -> 66.893 MB. [2024-09-05T06:13:22.117Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1265.894 ms) ====== [2024-09-05T06:13:22.117Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-09-05T06:13:22.117Z] GC before operation: completed in 95.790 ms, heap usage 288.052 MB -> 83.607 MB. [2024-09-05T06:13:23.556Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1331.030 ms) ====== [2024-09-05T06:13:23.556Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-09-05T06:13:23.556Z] GC before operation: completed in 126.515 ms, heap usage 369.799 MB -> 94.584 MB. [2024-09-05T06:13:24.994Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1538.296 ms) ====== [2024-09-05T06:13:24.994Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-09-05T06:13:24.994Z] GC before operation: completed in 96.158 ms, heap usage 243.289 MB -> 67.101 MB. [2024-09-05T06:13:26.441Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1520.593 ms) ====== [2024-09-05T06:13:26.441Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-09-05T06:13:27.126Z] GC before operation: completed in 93.752 ms, heap usage 281.715 MB -> 94.922 MB. [2024-09-05T06:13:28.561Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1543.166 ms) ====== [2024-09-05T06:13:28.561Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-09-05T06:13:28.561Z] GC before operation: completed in 96.062 ms, heap usage 265.922 MB -> 83.844 MB. [2024-09-05T06:13:29.991Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1430.686 ms) ====== [2024-09-05T06:13:29.991Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-09-05T06:13:29.991Z] GC before operation: completed in 103.518 ms, heap usage 376.920 MB -> 89.454 MB. [2024-09-05T06:13:31.409Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1384.045 ms) ====== [2024-09-05T06:13:31.409Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-09-05T06:13:31.409Z] GC before operation: completed in 101.323 ms, heap usage 273.643 MB -> 73.375 MB. [2024-09-05T06:13:32.830Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1350.494 ms) ====== [2024-09-05T06:13:32.830Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-09-05T06:13:32.830Z] GC before operation: completed in 89.302 ms, heap usage 112.539 MB -> 67.358 MB. [2024-09-05T06:13:34.251Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1382.661 ms) ====== [2024-09-05T06:13:34.251Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-09-05T06:13:34.251Z] GC before operation: completed in 95.415 ms, heap usage 429.848 MB -> 68.267 MB. [2024-09-05T06:13:35.675Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1327.555 ms) ====== [2024-09-05T06:13:35.675Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-09-05T06:13:35.675Z] GC before operation: completed in 90.110 ms, heap usage 249.579 MB -> 79.163 MB. [2024-09-05T06:13:37.096Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1321.399 ms) ====== [2024-09-05T06:13:37.096Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-09-05T06:13:37.096Z] GC before operation: completed in 89.021 ms, heap usage 115.127 MB -> 84.474 MB. [2024-09-05T06:13:38.513Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1579.717 ms) ====== [2024-09-05T06:13:38.513Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-09-05T06:13:39.197Z] GC before operation: completed in 116.787 ms, heap usage 233.276 MB -> 68.152 MB. [2024-09-05T06:13:40.616Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1355.673 ms) ====== [2024-09-05T06:13:40.616Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-09-05T06:13:40.616Z] GC before operation: completed in 107.168 ms, heap usage 253.014 MB -> 95.975 MB. [2024-09-05T06:13:41.411Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1390.245 ms) ====== [2024-09-05T06:13:41.412Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-09-05T06:13:42.106Z] GC before operation: completed in 121.963 ms, heap usage 273.575 MB -> 96.275 MB. [2024-09-05T06:13:42.106Z] 24/09/05 01:13:41 WARN BlockManager: Asked to remove block rdd_1075_0, which does not exist [2024-09-05T06:13:42.106Z] 24/09/05 01:13:41 WARN BlockManager: Asked to remove block rdd_1075_2, which does not exist [2024-09-05T06:13:43.527Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1576.066 ms) ====== [2024-09-05T06:13:43.527Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-09-05T06:13:43.527Z] GC before operation: completed in 112.115 ms, heap usage 264.820 MB -> 79.660 MB. [2024-09-05T06:13:44.948Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1558.730 ms) ====== [2024-09-05T06:13:44.948Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-09-05T06:13:44.948Z] GC before operation: completed in 88.517 ms, heap usage 434.827 MB -> 68.860 MB. [2024-09-05T06:13:46.366Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1266.011 ms) ====== [2024-09-05T06:13:46.366Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-09-05T06:13:46.366Z] GC before operation: completed in 112.698 ms, heap usage 233.942 MB -> 96.473 MB. [2024-09-05T06:13:47.786Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1486.220 ms) ====== [2024-09-05T06:13:47.786Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-09-05T06:13:48.479Z] GC before operation: completed in 109.832 ms, heap usage 280.843 MB -> 68.803 MB. [2024-09-05T06:13:49.904Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1338.371 ms) ====== [2024-09-05T06:13:49.904Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-09-05T06:13:49.904Z] GC before operation: completed in 122.333 ms, heap usage 282.761 MB -> 85.671 MB. [2024-09-05T06:13:49.904Z] 24/09/05 01:13:49 WARN BlockManager: Asked to remove block rdd_1320_1, which does not exist [2024-09-05T06:13:51.325Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1288.433 ms) ====== [2024-09-05T06:13:51.325Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-09-05T06:13:51.325Z] GC before operation: completed in 120.379 ms, heap usage 342.390 MB -> 96.585 MB. [2024-09-05T06:13:51.325Z] 24/09/05 01:13:50 WARN BlockManager: Asked to remove block broadcast_386, which does not exist [2024-09-05T06:13:51.325Z] 24/09/05 01:13:50 WARN BlockManager: Asked to remove block broadcast_386_piece0, which does not exist [2024-09-05T06:13:52.008Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1272.477 ms) ====== [2024-09-05T06:13:52.008Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-09-05T06:13:52.691Z] GC before operation: completed in 112.341 ms, heap usage 241.506 MB -> 91.304 MB. [2024-09-05T06:13:54.110Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1476.591 ms) ====== [2024-09-05T06:13:54.110Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-09-05T06:13:54.110Z] GC before operation: completed in 119.941 ms, heap usage 303.485 MB -> 96.945 MB. [2024-09-05T06:13:55.528Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1501.623 ms) ====== [2024-09-05T06:13:55.528Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-09-05T06:13:55.528Z] GC before operation: completed in 105.889 ms, heap usage 254.006 MB -> 69.464 MB. [2024-09-05T06:13:56.948Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1472.555 ms) ====== [2024-09-05T06:13:56.948Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-09-05T06:13:56.948Z] GC before operation: completed in 97.782 ms, heap usage 103.626 MB -> 80.842 MB. [2024-09-05T06:13:58.398Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1433.511 ms) ====== [2024-09-05T06:13:58.398Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-09-05T06:13:59.085Z] GC before operation: completed in 121.033 ms, heap usage 265.251 MB -> 97.516 MB. [2024-09-05T06:14:00.508Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1425.047 ms) ====== [2024-09-05T06:14:00.508Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-09-05T06:14:00.508Z] GC before operation: completed in 114.828 ms, heap usage 267.385 MB -> 97.539 MB. [2024-09-05T06:14:00.508Z] 24/09/05 01:13:59 WARN BlockManager: Asked to remove block rdd_1663_0, which does not exist [2024-09-05T06:14:00.508Z] 24/09/05 01:13:59 WARN BlockManager: Asked to remove block rdd_1663_2, which does not exist [2024-09-05T06:14:01.932Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1343.609 ms) ====== [2024-09-05T06:14:01.932Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-09-05T06:14:01.932Z] GC before operation: completed in 115.028 ms, heap usage 260.204 MB -> 80.646 MB. [2024-09-05T06:14:03.353Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1241.732 ms) ====== [2024-09-05T06:14:03.353Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-09-05T06:14:03.353Z] GC before operation: completed in 102.351 ms, heap usage 439.803 MB -> 75.889 MB. [2024-09-05T06:14:04.769Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1367.640 ms) ====== [2024-09-05T06:14:04.769Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-09-05T06:14:04.769Z] GC before operation: completed in 106.040 ms, heap usage 121.243 MB -> 80.260 MB. [2024-09-05T06:14:06.191Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1670.471 ms) ====== [2024-09-05T06:14:06.191Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-09-05T06:14:06.191Z] GC before operation: completed in 126.167 ms, heap usage 268.846 MB -> 97.659 MB. [2024-09-05T06:14:07.615Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1495.997 ms) ====== [2024-09-05T06:14:07.615Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-09-05T06:14:08.299Z] GC before operation: completed in 110.949 ms, heap usage 265.374 MB -> 98.026 MB. [2024-09-05T06:14:09.740Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1361.895 ms) ====== [2024-09-05T06:14:09.740Z] ----------------------------------- [2024-09-05T06:14:09.740Z] renaissance-dec-tree_0_PASSED [2024-09-05T06:14:09.740Z] ----------------------------------- [2024-09-05T06:14:09.740Z] [2024-09-05T06:14:09.740Z] TEST TEARDOWN: [2024-09-05T06:14:09.740Z] Nothing to be done for teardown. [2024-09-05T06:14:09.740Z] renaissance-dec-tree_0 Finish Time: Thu Sep 5 01:14:09 2024 Epoch Time (ms): 1725516849472