[2024-11-16T09:12:22.141Z] Running test renaissance-dec-tree_0 ... [2024-11-16T09:12:22.141Z] =============================================== [2024-11-16T09:12:22.141Z] renaissance-dec-tree_0 Start Time: Sat Nov 16 09:12:21 2024 Epoch Time (ms): 1731748341860 [2024-11-16T09:12:22.141Z] variation: NoOptions [2024-11-16T09:12:22.141Z] JVM_OPTIONS: [2024-11-16T09:12:22.141Z] { \ [2024-11-16T09:12:22.141Z] echo ""; echo "TEST SETUP:"; \ [2024-11-16T09:12:22.141Z] echo "Nothing to be done for setup."; \ [2024-11-16T09:12:22.141Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17317478759248/renaissance-dec-tree_0"; \ [2024-11-16T09:12:22.141Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17317478759248/renaissance-dec-tree_0"; \ [2024-11-16T09:12:22.141Z] echo ""; echo "TESTING:"; \ [2024-11-16T09:12:22.141Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/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_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17317478759248/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-11-16T09:12:22.141Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17317478759248/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-16T09:12:22.141Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-16T09:12:22.141Z] echo "Nothing to be done for teardown."; \ [2024-11-16T09:12:22.141Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17317478759248/TestTargetResult"; [2024-11-16T09:12:22.141Z] [2024-11-16T09:12:22.141Z] TEST SETUP: [2024-11-16T09:12:22.141Z] Nothing to be done for setup. [2024-11-16T09:12:22.141Z] [2024-11-16T09:12:22.141Z] TESTING: [2024-11-16T09:12:27.642Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-16T09:12:30.072Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 6 (out of 24) threads. [2024-11-16T09:12:32.498Z] WARNING: An illegal reflective access operation has occurred [2024-11-16T09:12:32.498Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/output_17317478759248/renaissance-dec-tree_0/harness-091222-12529933294665365784/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-11-16T09:12:32.498Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-11-16T09:12:32.498Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-11-16T09:12:32.498Z] WARNING: All illegal access operations will be denied in a future release [2024-11-16T09:12:35.867Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-11-16T09:12:35.867Z] GC before operation: completed in 68.787 ms, heap usage 67.149 MB -> 35.131 MB. [2024-11-16T09:12:45.622Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (10142.810 ms) ====== [2024-11-16T09:12:45.622Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-11-16T09:12:45.622Z] GC before operation: completed in 95.728 ms, heap usage 236.413 MB -> 67.180 MB. [2024-11-16T09:12:48.986Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2575.458 ms) ====== [2024-11-16T09:12:48.986Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-11-16T09:12:48.986Z] GC before operation: completed in 95.343 ms, heap usage 172.303 MB -> 67.277 MB. [2024-11-16T09:12:51.408Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2299.417 ms) ====== [2024-11-16T09:12:51.408Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-11-16T09:12:51.408Z] GC before operation: completed in 99.711 ms, heap usage 113.159 MB -> 67.260 MB. [2024-11-16T09:12:52.972Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (2161.982 ms) ====== [2024-11-16T09:12:52.972Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-11-16T09:12:52.972Z] GC before operation: completed in 106.954 ms, heap usage 290.552 MB -> 68.041 MB. [2024-11-16T09:12:55.410Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (2106.228 ms) ====== [2024-11-16T09:12:55.410Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-11-16T09:12:55.410Z] GC before operation: completed in 109.779 ms, heap usage 151.803 MB -> 68.190 MB. [2024-11-16T09:12:57.832Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (2048.142 ms) ====== [2024-11-16T09:12:57.832Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-11-16T09:12:57.832Z] GC before operation: completed in 147.846 ms, heap usage 223.611 MB -> 95.920 MB. [2024-11-16T09:12:59.398Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (2039.013 ms) ====== [2024-11-16T09:12:59.398Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-11-16T09:13:00.162Z] GC before operation: completed in 121.248 ms, heap usage 249.911 MB -> 68.701 MB. [2024-11-16T09:13:01.732Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (2016.920 ms) ====== [2024-11-16T09:13:01.732Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-11-16T09:13:01.732Z] GC before operation: completed in 125.391 ms, heap usage 222.761 MB -> 79.882 MB. [2024-11-16T09:13:04.158Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (2022.094 ms) ====== [2024-11-16T09:13:04.158Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-11-16T09:13:04.158Z] GC before operation: completed in 115.512 ms, heap usage 230.846 MB -> 68.886 MB. [2024-11-16T09:13:06.594Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (2005.603 ms) ====== [2024-11-16T09:13:06.594Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-11-16T09:13:06.594Z] GC before operation: completed in 119.065 ms, heap usage 197.857 MB -> 69.023 MB. [2024-11-16T09:13:08.161Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1909.568 ms) ====== [2024-11-16T09:13:08.161Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-11-16T09:13:08.161Z] GC before operation: completed in 151.172 ms, heap usage 179.624 MB -> 96.340 MB. [2024-11-16T09:13:10.585Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1933.718 ms) ====== [2024-11-16T09:13:10.585Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-11-16T09:13:10.585Z] GC before operation: completed in 144.580 ms, heap usage 242.759 MB -> 69.492 MB. [2024-11-16T09:13:10.585Z] 24/11/16 09:13:09 WARN BlockManager: Asked to remove block broadcast_162, which does not exist [2024-11-16T09:13:12.151Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1943.365 ms) ====== [2024-11-16T09:13:12.151Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-11-16T09:13:12.151Z] GC before operation: completed in 132.995 ms, heap usage 233.215 MB -> 69.209 MB. [2024-11-16T09:13:14.578Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1926.877 ms) ====== [2024-11-16T09:13:14.578Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-11-16T09:13:14.578Z] GC before operation: completed in 163.314 ms, heap usage 122.643 MB -> 69.179 MB. [2024-11-16T09:13:17.264Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1947.170 ms) ====== [2024-11-16T09:13:17.264Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-11-16T09:13:17.264Z] GC before operation: completed in 163.798 ms, heap usage 178.707 MB -> 74.937 MB. [2024-11-16T09:13:19.508Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1926.603 ms) ====== [2024-11-16T09:13:19.508Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-11-16T09:13:19.508Z] GC before operation: completed in 160.843 ms, heap usage 112.487 MB -> 69.172 MB. [2024-11-16T09:13:21.257Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1944.985 ms) ====== [2024-11-16T09:13:21.257Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-11-16T09:13:21.257Z] GC before operation: completed in 157.520 ms, heap usage 317.902 MB -> 69.686 MB. [2024-11-16T09:13:22.992Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1878.819 ms) ====== [2024-11-16T09:13:22.992Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-11-16T09:13:22.992Z] GC before operation: completed in 172.600 ms, heap usage 297.797 MB -> 75.563 MB. [2024-11-16T09:13:24.550Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1888.737 ms) ====== [2024-11-16T09:13:24.550Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-11-16T09:13:25.314Z] GC before operation: completed in 136.377 ms, heap usage 264.407 MB -> 70.070 MB. [2024-11-16T09:13:26.872Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1896.583 ms) ====== [2024-11-16T09:13:26.872Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-11-16T09:13:26.872Z] GC before operation: completed in 176.708 ms, heap usage 288.898 MB -> 81.271 MB. [2024-11-16T09:13:26.872Z] 24/11/16 09:13:26 WARN BlockManager: Asked to remove block broadcast_274, which does not exist [2024-11-16T09:13:29.302Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1867.964 ms) ====== [2024-11-16T09:13:29.302Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-11-16T09:13:29.302Z] GC before operation: completed in 147.848 ms, heap usage 148.819 MB -> 70.477 MB. [2024-11-16T09:13:30.869Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1927.304 ms) ====== [2024-11-16T09:13:30.869Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-11-16T09:13:30.869Z] GC before operation: completed in 153.247 ms, heap usage 285.162 MB -> 75.774 MB. [2024-11-16T09:13:33.298Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1867.933 ms) ====== [2024-11-16T09:13:33.298Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-11-16T09:13:33.298Z] GC before operation: completed in 170.024 ms, heap usage 299.431 MB -> 81.602 MB. [2024-11-16T09:13:34.865Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1894.670 ms) ====== [2024-11-16T09:13:34.865Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-11-16T09:13:34.865Z] GC before operation: completed in 145.322 ms, heap usage 306.961 MB -> 76.002 MB. [2024-11-16T09:13:37.341Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1814.577 ms) ====== [2024-11-16T09:13:37.341Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-11-16T09:13:37.341Z] GC before operation: completed in 135.106 ms, heap usage 270.412 MB -> 81.624 MB. [2024-11-16T09:13:38.904Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1855.682 ms) ====== [2024-11-16T09:13:38.904Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-11-16T09:13:38.904Z] GC before operation: completed in 153.577 ms, heap usage 258.455 MB -> 70.734 MB. [2024-11-16T09:13:41.328Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1834.807 ms) ====== [2024-11-16T09:13:41.328Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-11-16T09:13:41.328Z] GC before operation: completed in 144.204 ms, heap usage 122.159 MB -> 70.495 MB. [2024-11-16T09:13:42.888Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1843.804 ms) ====== [2024-11-16T09:13:42.888Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-11-16T09:13:42.888Z] GC before operation: completed in 153.271 ms, heap usage 118.437 MB -> 70.629 MB. [2024-11-16T09:13:45.320Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1821.678 ms) ====== [2024-11-16T09:13:45.320Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-11-16T09:13:45.320Z] GC before operation: completed in 153.536 ms, heap usage 245.853 MB -> 82.183 MB. [2024-11-16T09:13:46.875Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1790.259 ms) ====== [2024-11-16T09:13:46.875Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-11-16T09:13:46.875Z] GC before operation: completed in 176.488 ms, heap usage 272.172 MB -> 82.065 MB. [2024-11-16T09:13:49.308Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1863.926 ms) ====== [2024-11-16T09:13:49.308Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-11-16T09:13:49.308Z] GC before operation: completed in 160.660 ms, heap usage 255.177 MB -> 71.469 MB. [2024-11-16T09:13:50.870Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1880.410 ms) ====== [2024-11-16T09:13:50.870Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-11-16T09:13:50.870Z] GC before operation: completed in 150.301 ms, heap usage 135.897 MB -> 76.695 MB. [2024-11-16T09:13:53.308Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1797.105 ms) ====== [2024-11-16T09:13:53.308Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-11-16T09:13:53.308Z] GC before operation: completed in 172.598 ms, heap usage 202.679 MB -> 82.157 MB. [2024-11-16T09:13:54.869Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1805.059 ms) ====== [2024-11-16T09:13:54.869Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-11-16T09:13:54.869Z] GC before operation: completed in 173.878 ms, heap usage 224.250 MB -> 77.202 MB. [2024-11-16T09:13:57.314Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1853.430 ms) ====== [2024-11-16T09:13:57.314Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-11-16T09:13:57.314Z] GC before operation: completed in 147.761 ms, heap usage 144.948 MB -> 71.351 MB. [2024-11-16T09:13:58.874Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1780.542 ms) ====== [2024-11-16T09:13:58.874Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-11-16T09:13:58.874Z] GC before operation: completed in 160.431 ms, heap usage 263.555 MB -> 82.875 MB. [2024-11-16T09:14:01.301Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1835.286 ms) ====== [2024-11-16T09:14:01.301Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-11-16T09:14:01.301Z] GC before operation: completed in 181.766 ms, heap usage 188.084 MB -> 82.312 MB. [2024-11-16T09:14:02.867Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1826.996 ms) ====== [2024-11-16T09:14:02.868Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-11-16T09:14:02.868Z] GC before operation: completed in 189.705 ms, heap usage 207.107 MB -> 77.194 MB. [2024-11-16T09:14:05.292Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1804.726 ms) ====== [2024-11-16T09:14:05.292Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-11-16T09:14:05.292Z] GC before operation: completed in 169.561 ms, heap usage 250.182 MB -> 83.131 MB. [2024-11-16T09:14:06.858Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1837.791 ms) ====== [2024-11-16T09:14:07.611Z] ----------------------------------- [2024-11-16T09:14:07.611Z] renaissance-dec-tree_0_PASSED [2024-11-16T09:14:07.611Z] ----------------------------------- [2024-11-16T09:14:07.611Z] [2024-11-16T09:14:07.611Z] TEST TEARDOWN: [2024-11-16T09:14:07.611Z] Nothing to be done for teardown. [2024-11-16T09:14:07.611Z] renaissance-dec-tree_0 Finish Time: Sat Nov 16 09:14:06 2024 Epoch Time (ms): 1731748446815