[2024-11-21T11:40:10.087Z] Running test renaissance-dec-tree_0 ... [2024-11-21T11:40:10.087Z] =============================================== [2024-11-21T11:40:10.087Z] renaissance-dec-tree_0 Start Time: Thu Nov 21 11:40:09 2024 Epoch Time (ms): 1732189209422 [2024-11-21T11:40:10.087Z] variation: NoOptions [2024-11-21T11:40:10.087Z] JVM_OPTIONS: [2024-11-21T11:40:10.087Z] { \ [2024-11-21T11:40:10.087Z] echo ""; echo "TEST SETUP:"; \ [2024-11-21T11:40:10.087Z] echo "Nothing to be done for setup."; \ [2024-11-21T11:40:10.087Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17321875451188/renaissance-dec-tree_0"; \ [2024-11-21T11:40:10.087Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17321875451188/renaissance-dec-tree_0"; \ [2024-11-21T11:40:10.087Z] echo ""; echo "TESTING:"; \ [2024-11-21T11:40:10.087Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/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_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17321875451188/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-11-21T11:40:10.087Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17321875451188/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-21T11:40:10.087Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-21T11:40:10.087Z] echo "Nothing to be done for teardown."; \ [2024-11-21T11:40:10.087Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17321875451188/TestTargetResult"; [2024-11-21T11:40:10.087Z] [2024-11-21T11:40:10.087Z] TEST SETUP: [2024-11-21T11:40:10.087Z] Nothing to be done for setup. [2024-11-21T11:40:10.087Z] [2024-11-21T11:40:10.087Z] TESTING: [2024-11-21T11:40:23.105Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-21T11:40:26.365Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2024-11-21T11:40:36.364Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-11-21T11:40:36.364Z] GC before operation: completed in 147.528 ms, heap usage 76.305 MB -> 34.949 MB. [2024-11-21T11:40:57.629Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (19654.783 ms) ====== [2024-11-21T11:40:57.629Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-11-21T11:40:57.629Z] GC before operation: completed in 213.624 ms, heap usage 166.628 MB -> 90.942 MB. [2024-11-21T11:41:06.086Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (7623.235 ms) ====== [2024-11-21T11:41:06.086Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-11-21T11:41:06.086Z] GC before operation: completed in 205.256 ms, heap usage 140.056 MB -> 63.329 MB. [2024-11-21T11:41:14.901Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (9752.683 ms) ====== [2024-11-21T11:41:14.901Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-11-21T11:41:14.901Z] GC before operation: completed in 720.107 ms, heap usage 96.602 MB -> 91.618 MB. [2024-11-21T11:41:14.901Z] 24/11/21 11:41:14 WARN BlockManager: Asked to remove block broadcast_36, which does not exist [2024-11-21T11:41:21.412Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (6633.183 ms) ====== [2024-11-21T11:41:21.412Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-11-21T11:41:21.412Z] GC before operation: completed in 346.828 ms, heap usage 167.494 MB -> 64.481 MB. [2024-11-21T11:41:27.853Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (5913.094 ms) ====== [2024-11-21T11:41:27.853Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-11-21T11:41:27.853Z] GC before operation: completed in 290.146 ms, heap usage 279.219 MB -> 64.857 MB. [2024-11-21T11:41:27.853Z] 24/11/21 11:41:27 WARN BlockManager: Asked to remove block broadcast_64_piece0, which does not exist [2024-11-21T11:41:27.853Z] 24/11/21 11:41:27 WARN BlockManager: Asked to remove block broadcast_64, which does not exist [2024-11-21T11:41:35.651Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (7236.120 ms) ====== [2024-11-21T11:41:35.651Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-11-21T11:41:35.651Z] GC before operation: completed in 297.419 ms, heap usage 267.994 MB -> 64.641 MB. [2024-11-21T11:41:42.092Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (6681.570 ms) ====== [2024-11-21T11:41:42.092Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-11-21T11:41:42.092Z] GC before operation: completed in 367.541 ms, heap usage 286.289 MB -> 64.821 MB. [2024-11-21T11:41:48.809Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (6839.052 ms) ====== [2024-11-21T11:41:48.809Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-11-21T11:41:50.749Z] GC before operation: completed in 1110.876 ms, heap usage 260.655 MB -> 65.013 MB. [2024-11-21T11:41:56.766Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (6862.054 ms) ====== [2024-11-21T11:41:56.766Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-11-21T11:41:57.467Z] GC before operation: completed in 216.430 ms, heap usage 168.737 MB -> 64.970 MB. [2024-11-21T11:42:04.090Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (5435.236 ms) ====== [2024-11-21T11:42:04.090Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-11-21T11:42:04.090Z] GC before operation: completed in 456.102 ms, heap usage 96.550 MB -> 65.598 MB. [2024-11-21T11:42:08.585Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (5470.652 ms) ====== [2024-11-21T11:42:08.585Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-11-21T11:42:09.380Z] GC before operation: completed in 510.443 ms, heap usage 410.433 MB -> 84.852 MB. [2024-11-21T11:42:16.813Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (7703.700 ms) ====== [2024-11-21T11:42:16.813Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-11-21T11:42:17.484Z] GC before operation: completed in 330.532 ms, heap usage 260.555 MB -> 65.444 MB. [2024-11-21T11:42:22.767Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (5759.036 ms) ====== [2024-11-21T11:42:22.767Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-11-21T11:42:23.633Z] GC before operation: completed in 462.481 ms, heap usage 176.686 MB -> 65.594 MB. [2024-11-21T11:42:29.423Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (5614.005 ms) ====== [2024-11-21T11:42:29.423Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-11-21T11:42:30.127Z] GC before operation: completed in 974.040 ms, heap usage 175.727 MB -> 65.659 MB. [2024-11-21T11:42:36.696Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (6077.477 ms) ====== [2024-11-21T11:42:36.696Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-11-21T11:42:36.696Z] GC before operation: completed in 518.664 ms, heap usage 97.533 MB -> 65.422 MB. [2024-11-21T11:42:43.368Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (5888.967 ms) ====== [2024-11-21T11:42:43.368Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-11-21T11:42:43.368Z] GC before operation: completed in 224.275 ms, heap usage 382.409 MB -> 65.936 MB. [2024-11-21T11:42:46.406Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (3783.850 ms) ====== [2024-11-21T11:42:46.406Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-11-21T11:42:47.070Z] GC before operation: completed in 692.745 ms, heap usage 223.611 MB -> 66.222 MB. [2024-11-21T11:42:52.774Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (4947.256 ms) ====== [2024-11-21T11:42:52.774Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-11-21T11:42:52.774Z] GC before operation: completed in 308.766 ms, heap usage 207.747 MB -> 66.149 MB. [2024-11-21T11:42:57.496Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (5280.471 ms) ====== [2024-11-21T11:42:57.496Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-11-21T11:42:58.285Z] GC before operation: completed in 355.559 ms, heap usage 150.114 MB -> 66.138 MB. [2024-11-21T11:43:02.546Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (4119.229 ms) ====== [2024-11-21T11:43:02.546Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-11-21T11:43:02.546Z] GC before operation: completed in 281.252 ms, heap usage 181.513 MB -> 66.504 MB. [2024-11-21T11:43:06.585Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (3743.850 ms) ====== [2024-11-21T11:43:06.585Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-11-21T11:43:06.585Z] GC before operation: completed in 442.360 ms, heap usage 159.963 MB -> 66.220 MB. [2024-11-21T11:43:10.664Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (3827.501 ms) ====== [2024-11-21T11:43:10.664Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-11-21T11:43:10.664Z] GC before operation: completed in 290.326 ms, heap usage 270.895 MB -> 66.633 MB. [2024-11-21T11:43:14.921Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (3406.074 ms) ====== [2024-11-21T11:43:14.921Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-11-21T11:43:14.921Z] GC before operation: completed in 493.886 ms, heap usage 165.216 MB -> 66.908 MB. [2024-11-21T11:43:20.124Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (4556.053 ms) ====== [2024-11-21T11:43:20.124Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-11-21T11:43:20.124Z] GC before operation: completed in 364.739 ms, heap usage 115.155 MB -> 66.225 MB. [2024-11-21T11:43:25.498Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (5125.991 ms) ====== [2024-11-21T11:43:25.498Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-11-21T11:43:25.498Z] GC before operation: completed in 220.456 ms, heap usage 305.131 MB -> 67.476 MB. [2024-11-21T11:43:33.497Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (7913.240 ms) ====== [2024-11-21T11:43:33.497Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-11-21T11:43:33.497Z] GC before operation: completed in 259.034 ms, heap usage 180.183 MB -> 67.012 MB. [2024-11-21T11:43:36.796Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (3925.274 ms) ====== [2024-11-21T11:43:36.796Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-11-21T11:43:37.476Z] GC before operation: completed in 342.861 ms, heap usage 323.279 MB -> 67.256 MB. [2024-11-21T11:43:41.813Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (4638.508 ms) ====== [2024-11-21T11:43:41.813Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-11-21T11:43:42.594Z] GC before operation: completed in 307.233 ms, heap usage 245.815 MB -> 67.215 MB. [2024-11-21T11:43:48.712Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (5292.612 ms) ====== [2024-11-21T11:43:48.712Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-11-21T11:43:48.712Z] GC before operation: completed in 331.149 ms, heap usage 241.555 MB -> 67.455 MB. [2024-11-21T11:43:54.177Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (5688.037 ms) ====== [2024-11-21T11:43:54.177Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-11-21T11:43:54.177Z] GC before operation: completed in 301.185 ms, heap usage 250.121 MB -> 67.511 MB. [2024-11-21T11:43:59.856Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (5880.654 ms) ====== [2024-11-21T11:43:59.857Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-11-21T11:44:00.585Z] GC before operation: completed in 327.079 ms, heap usage 277.280 MB -> 67.652 MB. [2024-11-21T11:44:05.829Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (5012.707 ms) ====== [2024-11-21T11:44:05.829Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-11-21T11:44:05.829Z] GC before operation: completed in 284.472 ms, heap usage 110.077 MB -> 67.224 MB. [2024-11-21T11:44:11.597Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (5523.300 ms) ====== [2024-11-21T11:44:11.597Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-11-21T11:44:11.597Z] GC before operation: completed in 299.536 ms, heap usage 111.686 MB -> 67.792 MB. [2024-11-21T11:44:16.936Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (5820.883 ms) ====== [2024-11-21T11:44:16.936Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-11-21T11:44:17.710Z] GC before operation: completed in 508.599 ms, heap usage 395.466 MB -> 68.209 MB. [2024-11-21T11:44:24.420Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (6742.643 ms) ====== [2024-11-21T11:44:24.420Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-11-21T11:44:25.145Z] GC before operation: completed in 670.144 ms, heap usage 220.985 MB -> 68.678 MB. [2024-11-21T11:44:32.441Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (6554.321 ms) ====== [2024-11-21T11:44:32.441Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-11-21T11:44:32.441Z] GC before operation: completed in 781.985 ms, heap usage 110.915 MB -> 95.092 MB. [2024-11-21T11:44:37.754Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (5103.160 ms) ====== [2024-11-21T11:44:37.754Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-11-21T11:44:38.701Z] GC before operation: completed in 648.335 ms, heap usage 129.932 MB -> 67.769 MB. [2024-11-21T11:44:43.504Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (4909.953 ms) ====== [2024-11-21T11:44:43.504Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-11-21T11:44:43.504Z] GC before operation: completed in 688.500 ms, heap usage 145.678 MB -> 67.931 MB. [2024-11-21T11:44:49.972Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (5920.192 ms) ====== [2024-11-21T11:44:49.973Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-11-21T11:44:49.973Z] GC before operation: completed in 313.605 ms, heap usage 388.024 MB -> 68.284 MB. [2024-11-21T11:44:55.788Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (4783.424 ms) ====== [2024-11-21T11:44:56.456Z] ----------------------------------- [2024-11-21T11:44:56.456Z] renaissance-dec-tree_0_PASSED [2024-11-21T11:44:56.456Z] ----------------------------------- [2024-11-21T11:44:56.456Z] [2024-11-21T11:44:56.456Z] TEST TEARDOWN: [2024-11-21T11:44:56.456Z] Nothing to be done for teardown. [2024-11-21T11:44:56.456Z] renaissance-dec-tree_0 Finish Time: Thu Nov 21 11:44:56 2024 Epoch Time (ms): 1732189496058