[2024-08-23T22:02:20.542Z] Running test renaissance-dec-tree_0 ... [2024-08-23T22:02:20.542Z] =============================================== [2024-08-23T22:02:20.542Z] renaissance-dec-tree_0 Start Time: Fri Aug 23 22:02:20 2024 Epoch Time (ms): 1724450540089 [2024-08-23T22:02:20.542Z] variation: NoOptions [2024-08-23T22:02:20.542Z] JVM_OPTIONS: [2024-08-23T22:02:20.542Z] { \ [2024-08-23T22:02:20.542Z] echo ""; echo "TEST SETUP:"; \ [2024-08-23T22:02:20.542Z] echo "Nothing to be done for setup."; \ [2024-08-23T22:02:20.542Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17244483233953/renaissance-dec-tree_0"; \ [2024-08-23T22:02:20.542Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17244483233953/renaissance-dec-tree_0"; \ [2024-08-23T22:02:20.542Z] echo ""; echo "TESTING:"; \ [2024-08-23T22:02:20.542Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-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_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17244483233953/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-23T22:02:20.542Z] 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/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17244483233953/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-23T22:02:20.542Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-23T22:02:20.542Z] echo "Nothing to be done for teardown."; \ [2024-08-23T22:02:20.542Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17244483233953/TestTargetResult"; [2024-08-23T22:02:20.542Z] [2024-08-23T22:02:20.542Z] TEST SETUP: [2024-08-23T22:02:20.542Z] Nothing to be done for setup. [2024-08-23T22:02:20.542Z] [2024-08-23T22:02:20.542Z] TESTING: [2024-08-23T22:02:32.086Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-23T22:02:43.752Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-08-23T22:02:49.471Z] WARNING: An illegal reflective access operation has occurred [2024-08-23T22:02:49.471Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/output_17244483233953/renaissance-dec-tree_0/harness-220222-3147292642108090714/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-23T22:02:49.471Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-23T22:02:49.471Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-23T22:02:49.471Z] WARNING: All illegal access operations will be denied in a future release [2024-08-23T22:03:01.090Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-23T22:03:01.090Z] GC before operation: completed in 326.004 ms, heap usage 66.378 MB -> 33.817 MB. [2024-08-23T22:04:08.268Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (62052.077 ms) ====== [2024-08-23T22:04:08.268Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-23T22:04:08.268Z] GC before operation: completed in 1045.820 ms, heap usage 169.489 MB -> 63.643 MB. [2024-08-23T22:04:27.192Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (20759.432 ms) ====== [2024-08-23T22:04:27.192Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-23T22:04:27.192Z] GC before operation: completed in 879.858 ms, heap usage 146.440 MB -> 64.165 MB. [2024-08-23T22:04:43.779Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (17278.813 ms) ====== [2024-08-23T22:04:43.779Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-23T22:04:43.779Z] GC before operation: completed in 691.536 ms, heap usage 157.945 MB -> 64.392 MB. [2024-08-23T22:05:00.590Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (15874.545 ms) ====== [2024-08-23T22:05:00.590Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-23T22:05:00.590Z] GC before operation: completed in 865.766 ms, heap usage 163.147 MB -> 64.867 MB. [2024-08-23T22:05:14.340Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (13762.413 ms) ====== [2024-08-23T22:05:14.340Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-23T22:05:15.100Z] GC before operation: completed in 637.397 ms, heap usage 232.821 MB -> 65.076 MB. [2024-08-23T22:05:28.813Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (12561.296 ms) ====== [2024-08-23T22:05:28.813Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-23T22:05:28.813Z] GC before operation: completed in 793.777 ms, heap usage 208.814 MB -> 64.888 MB. [2024-08-23T22:05:42.721Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (13275.506 ms) ====== [2024-08-23T22:05:42.721Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-23T22:05:42.721Z] GC before operation: completed in 760.890 ms, heap usage 211.347 MB -> 92.675 MB. [2024-08-23T22:05:56.481Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (12662.718 ms) ====== [2024-08-23T22:05:56.481Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-23T22:05:56.481Z] GC before operation: completed in 791.629 ms, heap usage 137.977 MB -> 64.619 MB. [2024-08-23T22:06:08.258Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (11229.852 ms) ====== [2024-08-23T22:06:08.258Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-23T22:06:08.258Z] GC before operation: completed in 707.582 ms, heap usage 232.839 MB -> 92.882 MB. [2024-08-23T22:06:20.895Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (12082.908 ms) ====== [2024-08-23T22:06:20.895Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-23T22:06:20.895Z] GC before operation: completed in 887.520 ms, heap usage 299.173 MB -> 65.430 MB. [2024-08-23T22:06:32.716Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (12340.786 ms) ====== [2024-08-23T22:06:32.716Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-23T22:06:33.491Z] GC before operation: completed in 761.636 ms, heap usage 167.980 MB -> 65.228 MB. [2024-08-23T22:06:43.401Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (9704.911 ms) ====== [2024-08-23T22:06:43.401Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-23T22:06:44.147Z] GC before operation: completed in 952.632 ms, heap usage 183.941 MB -> 66.005 MB. [2024-08-23T22:06:54.074Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (9772.456 ms) ====== [2024-08-23T22:06:54.074Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-23T22:06:54.931Z] GC before operation: completed in 755.575 ms, heap usage 259.662 MB -> 65.914 MB. [2024-08-23T22:07:04.710Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (9391.470 ms) ====== [2024-08-23T22:07:04.710Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-23T22:07:04.710Z] GC before operation: completed in 613.769 ms, heap usage 133.294 MB -> 65.966 MB. [2024-08-23T22:07:14.528Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (9887.221 ms) ====== [2024-08-23T22:07:14.528Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-23T22:07:15.278Z] GC before operation: completed in 586.446 ms, heap usage 166.993 MB -> 65.822 MB. [2024-08-23T22:07:24.990Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (9048.104 ms) ====== [2024-08-23T22:07:24.990Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-23T22:07:24.990Z] GC before operation: completed in 739.441 ms, heap usage 108.799 MB -> 65.972 MB. [2024-08-23T22:07:35.094Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (10148.749 ms) ====== [2024-08-23T22:07:35.094Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-23T22:07:35.844Z] GC before operation: completed in 638.301 ms, heap usage 167.609 MB -> 66.142 MB. [2024-08-23T22:07:45.767Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (8721.599 ms) ====== [2024-08-23T22:07:45.767Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-23T22:07:45.767Z] GC before operation: completed in 815.679 ms, heap usage 248.819 MB -> 93.506 MB. [2024-08-23T22:07:53.953Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (7873.939 ms) ====== [2024-08-23T22:07:53.953Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-23T22:07:53.953Z] GC before operation: completed in 701.890 ms, heap usage 218.110 MB -> 66.401 MB. [2024-08-23T22:08:02.092Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (7850.304 ms) ====== [2024-08-23T22:08:02.092Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-23T22:08:02.846Z] GC before operation: completed in 655.459 ms, heap usage 199.383 MB -> 66.646 MB. [2024-08-23T22:08:10.958Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (7939.102 ms) ====== [2024-08-23T22:08:10.958Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-23T22:08:10.958Z] GC before operation: completed in 683.494 ms, heap usage 207.315 MB -> 66.745 MB. [2024-08-23T22:08:19.118Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (7955.212 ms) ====== [2024-08-23T22:08:19.118Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-23T22:08:19.878Z] GC before operation: completed in 734.203 ms, heap usage 255.256 MB -> 66.701 MB. [2024-08-23T22:08:28.037Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (8482.427 ms) ====== [2024-08-23T22:08:28.037Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-23T22:08:29.586Z] GC before operation: completed in 828.148 ms, heap usage 231.031 MB -> 66.827 MB. [2024-08-23T22:08:37.768Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (8410.790 ms) ====== [2024-08-23T22:08:37.768Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-23T22:08:38.523Z] GC before operation: completed in 736.023 ms, heap usage 104.141 MB -> 67.051 MB. [2024-08-23T22:08:46.655Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (7769.600 ms) ====== [2024-08-23T22:08:46.655Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-23T22:08:46.655Z] GC before operation: completed in 736.885 ms, heap usage 194.077 MB -> 67.025 MB. [2024-08-23T22:08:54.885Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (7352.114 ms) ====== [2024-08-23T22:08:54.885Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-23T22:08:54.885Z] GC before operation: completed in 660.732 ms, heap usage 206.814 MB -> 67.122 MB. [2024-08-23T22:09:03.015Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (7741.007 ms) ====== [2024-08-23T22:09:03.015Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-23T22:09:03.764Z] GC before operation: completed in 857.140 ms, heap usage 271.119 MB -> 67.223 MB. [2024-08-23T22:09:10.482Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (7151.975 ms) ====== [2024-08-23T22:09:10.482Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-23T22:09:11.243Z] GC before operation: completed in 665.158 ms, heap usage 174.568 MB -> 67.225 MB. [2024-08-23T22:09:18.039Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (6842.762 ms) ====== [2024-08-23T22:09:18.039Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-23T22:09:18.812Z] GC before operation: completed in 671.660 ms, heap usage 174.657 MB -> 67.596 MB. [2024-08-23T22:09:26.954Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (7515.821 ms) ====== [2024-08-23T22:09:26.955Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-23T22:09:26.955Z] GC before operation: completed in 741.831 ms, heap usage 231.052 MB -> 67.687 MB. [2024-08-23T22:09:34.458Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (7634.511 ms) ====== [2024-08-23T22:09:34.458Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-23T22:09:35.218Z] GC before operation: completed in 765.800 ms, heap usage 142.968 MB -> 67.357 MB. [2024-08-23T22:09:43.358Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (6988.455 ms) ====== [2024-08-23T22:09:43.358Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-23T22:09:43.358Z] GC before operation: completed in 800.554 ms, heap usage 244.224 MB -> 67.886 MB. [2024-08-23T22:09:51.508Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (7607.429 ms) ====== [2024-08-23T22:09:51.508Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-23T22:09:51.508Z] GC before operation: completed in 765.503 ms, heap usage 244.227 MB -> 67.836 MB. [2024-08-23T22:09:59.666Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (7127.349 ms) ====== [2024-08-23T22:09:59.666Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-23T22:09:59.666Z] GC before operation: completed in 805.490 ms, heap usage 102.496 MB -> 67.528 MB. [2024-08-23T22:10:07.786Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (8249.922 ms) ====== [2024-08-23T22:10:07.786Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-23T22:10:08.534Z] GC before operation: completed in 750.340 ms, heap usage 258.435 MB -> 95.634 MB. [2024-08-23T22:10:16.763Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (8471.928 ms) ====== [2024-08-23T22:10:16.763Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-23T22:10:17.510Z] GC before operation: completed in 777.588 ms, heap usage 142.633 MB -> 67.715 MB. [2024-08-23T22:10:25.681Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (7489.685 ms) ====== [2024-08-23T22:10:25.681Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-23T22:10:26.445Z] GC before operation: completed in 731.624 ms, heap usage 196.164 MB -> 68.367 MB. [2024-08-23T22:10:33.178Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (6584.385 ms) ====== [2024-08-23T22:10:33.178Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-23T22:10:33.178Z] GC before operation: completed in 703.060 ms, heap usage 214.319 MB -> 68.318 MB. [2024-08-23T22:10:41.562Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (7767.092 ms) ====== [2024-08-23T22:10:41.563Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-23T22:10:41.563Z] GC before operation: completed in 791.555 ms, heap usage 184.080 MB -> 68.559 MB. [2024-08-23T22:10:50.113Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (7226.575 ms) ====== [2024-08-23T22:10:50.113Z] ----------------------------------- [2024-08-23T22:10:50.113Z] renaissance-dec-tree_0_PASSED [2024-08-23T22:10:50.113Z] ----------------------------------- [2024-08-23T22:10:50.113Z] [2024-08-23T22:10:50.113Z] TEST TEARDOWN: [2024-08-23T22:10:50.113Z] Nothing to be done for teardown. [2024-08-23T22:10:50.113Z] renaissance-dec-tree_0 Finish Time: Fri Aug 23 22:10:49 2024 Epoch Time (ms): 1724451049862