[2023-04-19T00:04:59.534Z] Running test renaissance-dec-tree_0 ... [2023-04-19T00:04:59.534Z] =============================================== [2023-04-19T00:04:59.534Z] renaissance-dec-tree_0 Start Time: Wed Apr 19 00:04:34 2023 Epoch Time (ms): 1681862674803 [2023-04-19T00:04:59.534Z] variation: NoOptions [2023-04-19T00:04:59.534Z] JVM_OPTIONS: [2023-04-19T00:04:59.534Z] { \ [2023-04-19T00:04:59.534Z] echo ""; echo "TEST SETUP:"; \ [2023-04-19T00:04:59.534Z] echo "Nothing to be done for setup."; \ [2023-04-19T00:04:59.534Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_16818622254084/renaissance-dec-tree_0"; \ [2023-04-19T00:04:59.534Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_16818622254084/renaissance-dec-tree_0"; \ [2023-04-19T00:04:59.534Z] echo ""; echo "TESTING:"; \ [2023-04-19T00:04:59.534Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/openjdkbinary/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_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_16818622254084/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2023-04-19T00:04:59.534Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_16818622254084/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2023-04-19T00:04:59.534Z] echo ""; echo "TEST TEARDOWN:"; \ [2023-04-19T00:04:59.534Z] echo "Nothing to be done for teardown."; \ [2023-04-19T00:04:59.534Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_16818622254084/TestTargetResult"; [2023-04-19T00:04:59.534Z] [2023-04-19T00:04:59.534Z] TEST SETUP: [2023-04-19T00:04:59.534Z] Nothing to be done for setup. [2023-04-19T00:04:59.534Z] [2023-04-19T00:04:59.534Z] TESTING: [2023-04-19T00:04:59.534Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2023-04-19T00:04:59.534Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2023-04-19T00:04:59.534Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2023-04-19T00:04:59.534Z] GC before operation: completed in 68.267 ms, heap usage 85.236 MB -> 35.220 MB. [2023-04-19T00:04:59.534Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (6989.855 ms) ====== [2023-04-19T00:04:59.534Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2023-04-19T00:04:59.534Z] GC before operation: completed in 94.362 ms, heap usage 297.638 MB -> 64.760 MB. [2023-04-19T00:04:59.534Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2305.084 ms) ====== [2023-04-19T00:04:59.534Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2023-04-19T00:04:59.534Z] GC before operation: completed in 79.746 ms, heap usage 195.731 MB -> 76.264 MB. [2023-04-19T00:04:59.534Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (1765.318 ms) ====== [2023-04-19T00:04:59.534Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2023-04-19T00:04:59.534Z] GC before operation: completed in 81.535 ms, heap usage 285.257 MB -> 65.426 MB. [2023-04-19T00:04:59.534Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1748.777 ms) ====== [2023-04-19T00:04:59.534Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2023-04-19T00:04:59.534Z] GC before operation: completed in 81.302 ms, heap usage 149.854 MB -> 65.393 MB. [2023-04-19T00:04:59.534Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1746.701 ms) ====== [2023-04-19T00:04:59.534Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2023-04-19T00:04:59.534Z] GC before operation: completed in 99.105 ms, heap usage 319.899 MB -> 65.930 MB. [2023-04-19T00:05:00.834Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1699.760 ms) ====== [2023-04-19T00:05:00.834Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2023-04-19T00:05:00.834Z] GC before operation: completed in 86.695 ms, heap usage 140.363 MB -> 65.900 MB. [2023-04-19T00:05:02.132Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1681.955 ms) ====== [2023-04-19T00:05:02.132Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2023-04-19T00:05:02.132Z] GC before operation: completed in 120.875 ms, heap usage 286.283 MB -> 66.578 MB. [2023-04-19T00:05:03.434Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1625.437 ms) ====== [2023-04-19T00:05:03.434Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2023-04-19T00:05:03.434Z] GC before operation: completed in 117.168 ms, heap usage 327.787 MB -> 77.797 MB. [2023-04-19T00:05:03.435Z] 23/04/19 00:05:03 WARN BlockManager: Asked to remove block broadcast_106_piece0, which does not exist [2023-04-19T00:05:03.435Z] 23/04/19 00:05:03 WARN BlockManager: Asked to remove block broadcast_106, which does not exist [2023-04-19T00:05:05.352Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1607.495 ms) ====== [2023-04-19T00:05:05.352Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2023-04-19T00:05:05.352Z] GC before operation: completed in 101.809 ms, heap usage 180.252 MB -> 66.500 MB. [2023-04-19T00:05:06.872Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1599.264 ms) ====== [2023-04-19T00:05:06.872Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2023-04-19T00:05:06.872Z] GC before operation: completed in 108.567 ms, heap usage 317.475 MB -> 66.771 MB. [2023-04-19T00:05:08.342Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1614.682 ms) ====== [2023-04-19T00:05:08.342Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2023-04-19T00:05:09.275Z] GC before operation: completed in 93.220 ms, heap usage 334.366 MB -> 66.897 MB. [2023-04-19T00:05:10.206Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1588.778 ms) ====== [2023-04-19T00:05:10.206Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2023-04-19T00:05:10.206Z] GC before operation: completed in 95.611 ms, heap usage 339.588 MB -> 67.002 MB. [2023-04-19T00:05:12.484Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1658.561 ms) ====== [2023-04-19T00:05:12.484Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2023-04-19T00:05:12.484Z] GC before operation: completed in 104.048 ms, heap usage 298.633 MB -> 78.400 MB. [2023-04-19T00:05:14.761Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1579.597 ms) ====== [2023-04-19T00:05:14.761Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2023-04-19T00:05:14.761Z] GC before operation: completed in 91.498 ms, heap usage 201.583 MB -> 67.229 MB. [2023-04-19T00:05:15.690Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1597.194 ms) ====== [2023-04-19T00:05:15.690Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2023-04-19T00:05:15.690Z] GC before operation: completed in 103.397 ms, heap usage 320.462 MB -> 84.074 MB. [2023-04-19T00:05:17.586Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1624.077 ms) ====== [2023-04-19T00:05:17.586Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2023-04-19T00:05:17.586Z] GC before operation: completed in 99.471 ms, heap usage 161.770 MB -> 67.377 MB. [2023-04-19T00:05:19.496Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1638.442 ms) ====== [2023-04-19T00:05:19.496Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2023-04-19T00:05:19.496Z] GC before operation: completed in 103.175 ms, heap usage 280.047 MB -> 67.626 MB. [2023-04-19T00:05:20.425Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1615.877 ms) ====== [2023-04-19T00:05:20.425Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2023-04-19T00:05:21.355Z] GC before operation: completed in 96.393 ms, heap usage 306.440 MB -> 67.903 MB. [2023-04-19T00:05:22.872Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1522.554 ms) ====== [2023-04-19T00:05:22.872Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2023-04-19T00:05:22.872Z] GC before operation: completed in 91.025 ms, heap usage 291.889 MB -> 84.363 MB. [2023-04-19T00:05:24.512Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1568.789 ms) ====== [2023-04-19T00:05:24.512Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2023-04-19T00:05:24.512Z] GC before operation: completed in 108.528 ms, heap usage 262.339 MB -> 67.984 MB. [2023-04-19T00:05:25.801Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1495.285 ms) ====== [2023-04-19T00:05:25.801Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2023-04-19T00:05:25.801Z] GC before operation: completed in 102.452 ms, heap usage 310.168 MB -> 84.825 MB. [2023-04-19T00:05:27.094Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1607.293 ms) ====== [2023-04-19T00:05:27.094Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2023-04-19T00:05:27.094Z] GC before operation: completed in 129.071 ms, heap usage 398.311 MB -> 68.129 MB. [2023-04-19T00:05:29.009Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1583.601 ms) ====== [2023-04-19T00:05:29.009Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2023-04-19T00:05:29.009Z] GC before operation: completed in 98.047 ms, heap usage 275.140 MB -> 68.411 MB. [2023-04-19T00:05:32.023Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1579.721 ms) ====== [2023-04-19T00:05:32.023Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2023-04-19T00:05:32.023Z] GC before operation: completed in 104.071 ms, heap usage 300.003 MB -> 85.059 MB. [2023-04-19T00:05:32.023Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1483.035 ms) ====== [2023-04-19T00:05:32.023Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2023-04-19T00:05:32.952Z] GC before operation: completed in 128.169 ms, heap usage 398.380 MB -> 68.620 MB. [2023-04-19T00:05:34.253Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1569.070 ms) ====== [2023-04-19T00:05:34.253Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2023-04-19T00:05:34.253Z] GC before operation: completed in 125.768 ms, heap usage 278.301 MB -> 90.685 MB. [2023-04-19T00:05:36.533Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1578.550 ms) ====== [2023-04-19T00:05:36.533Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2023-04-19T00:05:36.533Z] GC before operation: completed in 110.437 ms, heap usage 334.958 MB -> 96.322 MB. [2023-04-19T00:05:36.533Z] 23/04/19 00:05:35 WARN BlockManager: Asked to remove block broadcast_372, which does not exist [2023-04-19T00:05:38.373Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1515.143 ms) ====== [2023-04-19T00:05:38.373Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2023-04-19T00:05:38.373Z] GC before operation: completed in 100.473 ms, heap usage 303.648 MB -> 74.185 MB. [2023-04-19T00:05:39.313Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1588.136 ms) ====== [2023-04-19T00:05:39.313Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2023-04-19T00:05:39.313Z] GC before operation: completed in 114.566 ms, heap usage 229.647 MB -> 80.168 MB. [2023-04-19T00:05:41.226Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1574.982 ms) ====== [2023-04-19T00:05:41.226Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2023-04-19T00:05:41.226Z] GC before operation: completed in 100.390 ms, heap usage 142.532 MB -> 79.727 MB. [2023-04-19T00:05:42.157Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1512.025 ms) ====== [2023-04-19T00:05:42.157Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2023-04-19T00:05:42.157Z] GC before operation: completed in 104.638 ms, heap usage 429.346 MB -> 69.232 MB. [2023-04-19T00:05:44.813Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1554.200 ms) ====== [2023-04-19T00:05:44.813Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2023-04-19T00:05:44.813Z] GC before operation: completed in 123.247 ms, heap usage 314.189 MB -> 69.208 MB. [2023-04-19T00:05:46.121Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1596.153 ms) ====== [2023-04-19T00:05:46.121Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2023-04-19T00:05:46.121Z] GC before operation: completed in 107.637 ms, heap usage 274.682 MB -> 69.473 MB. [2023-04-19T00:05:48.162Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1509.070 ms) ====== [2023-04-19T00:05:48.162Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2023-04-19T00:05:48.162Z] GC before operation: completed in 102.184 ms, heap usage 136.984 MB -> 74.623 MB. [2023-04-19T00:05:49.090Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1505.424 ms) ====== [2023-04-19T00:05:49.090Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2023-04-19T00:05:49.090Z] GC before operation: completed in 91.232 ms, heap usage 126.972 MB -> 68.916 MB. [2023-04-19T00:05:51.739Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1471.782 ms) ====== [2023-04-19T00:05:51.739Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2023-04-19T00:05:51.739Z] GC before operation: completed in 116.100 ms, heap usage 135.056 MB -> 97.060 MB. [2023-04-19T00:05:52.670Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1575.269 ms) ====== [2023-04-19T00:05:52.671Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2023-04-19T00:05:52.671Z] GC before operation: completed in 112.638 ms, heap usage 267.889 MB -> 86.135 MB. [2023-04-19T00:05:53.963Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1470.059 ms) ====== [2023-04-19T00:05:53.964Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2023-04-19T00:05:53.964Z] GC before operation: completed in 110.307 ms, heap usage 248.441 MB -> 86.231 MB. [2023-04-19T00:05:55.263Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1514.953 ms) ====== [2023-04-19T00:05:55.263Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2023-04-19T00:05:55.263Z] GC before operation: completed in 106.027 ms, heap usage 242.472 MB -> 70.029 MB. [2023-04-19T00:05:58.462Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1549.134 ms) ====== [2023-04-19T00:05:58.462Z] ----------------------------------- [2023-04-19T00:05:58.462Z] renaissance-dec-tree_0_PASSED [2023-04-19T00:05:58.462Z] ----------------------------------- [2023-04-19T00:05:58.462Z] [2023-04-19T00:05:58.462Z] TEST TEARDOWN: [2023-04-19T00:05:58.462Z] Nothing to be done for teardown. [2023-04-19T00:05:58.462Z] renaissance-dec-tree_0 Finish Time: Wed Apr 19 00:05:56 2023 Epoch Time (ms): 1681862756829