[2024-08-22T04:39:53.241Z] Running test renaissance-dec-tree_0 ... [2024-08-22T04:39:53.241Z] =============================================== [2024-08-22T04:39:53.241Z] renaissance-dec-tree_0 Start Time: Wed Aug 21 23:39:52 2024 Epoch Time (ms): 1724301592773 [2024-08-22T04:39:53.241Z] variation: NoOptions [2024-08-22T04:39:53.241Z] JVM_OPTIONS: [2024-08-22T04:39:53.241Z] { \ [2024-08-22T04:39:53.241Z] echo ""; echo "TEST SETUP:"; \ [2024-08-22T04:39:53.241Z] echo "Nothing to be done for setup."; \ [2024-08-22T04:39:53.241Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17243011117876/renaissance-dec-tree_0"; \ [2024-08-22T04:39:53.241Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17243011117876/renaissance-dec-tree_0"; \ [2024-08-22T04:39:53.241Z] echo ""; echo "TESTING:"; \ [2024-08-22T04:39:53.241Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/jdkbinary/j2sdk-image/jdk-11.0.25+4/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_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17243011117876/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2024-08-22T04:39:53.241Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17243011117876/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-08-22T04:39:53.241Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-08-22T04:39:53.241Z] echo "Nothing to be done for teardown."; \ [2024-08-22T04:39:53.241Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17243011117876/TestTargetResult"; [2024-08-22T04:39:53.241Z] [2024-08-22T04:39:53.241Z] TEST SETUP: [2024-08-22T04:39:53.241Z] Nothing to be done for setup. [2024-08-22T04:39:53.241Z] [2024-08-22T04:39:53.241Z] TESTING: [2024-08-22T04:39:56.269Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-08-22T04:39:57.658Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 6 (out of 8) threads. [2024-08-22T04:39:59.050Z] WARNING: An illegal reflective access operation has occurred [2024-08-22T04:39:59.050Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_x86-64_linux/aqa-tests/TKG/output_17243011117876/renaissance-dec-tree_0/harness-233953-14626214315263349799/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-08-22T04:39:59.050Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-08-22T04:39:59.050Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-08-22T04:39:59.050Z] WARNING: All illegal access operations will be denied in a future release [2024-08-22T04:40:01.258Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2024-08-22T04:40:01.259Z] GC before operation: completed in 73.253 ms, heap usage 64.149 MB -> 34.346 MB. [2024-08-22T04:40:10.356Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (8895.303 ms) ====== [2024-08-22T04:40:10.356Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2024-08-22T04:40:10.356Z] GC before operation: completed in 108.832 ms, heap usage 216.678 MB -> 64.235 MB. [2024-08-22T04:40:13.402Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (2418.654 ms) ====== [2024-08-22T04:40:13.402Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2024-08-22T04:40:13.402Z] GC before operation: completed in 96.620 ms, heap usage 148.036 MB -> 64.576 MB. [2024-08-22T04:40:14.796Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (2057.981 ms) ====== [2024-08-22T04:40:14.796Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2024-08-22T04:40:15.466Z] GC before operation: completed in 98.333 ms, heap usage 131.260 MB -> 64.793 MB. [2024-08-22T04:40:16.857Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (1673.438 ms) ====== [2024-08-22T04:40:16.857Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2024-08-22T04:40:16.857Z] GC before operation: completed in 118.316 ms, heap usage 145.035 MB -> 65.216 MB. [2024-08-22T04:40:18.701Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (1697.229 ms) ====== [2024-08-22T04:40:18.701Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2024-08-22T04:40:18.701Z] GC before operation: completed in 117.579 ms, heap usage 257.816 MB -> 87.311 MB. [2024-08-22T04:40:18.701Z] 24/08/21 23:40:18 WARN BlockManager: Asked to remove block rdd_242_0, which does not exist [2024-08-22T04:40:20.158Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (1562.214 ms) ====== [2024-08-22T04:40:20.158Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2024-08-22T04:40:20.833Z] GC before operation: completed in 108.603 ms, heap usage 179.427 MB -> 65.393 MB. [2024-08-22T04:40:22.226Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (1544.409 ms) ====== [2024-08-22T04:40:22.226Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2024-08-22T04:40:22.226Z] GC before operation: completed in 112.650 ms, heap usage 253.759 MB -> 76.798 MB. [2024-08-22T04:40:23.624Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (1487.137 ms) ====== [2024-08-22T04:40:23.624Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2024-08-22T04:40:23.624Z] GC before operation: completed in 112.505 ms, heap usage 206.486 MB -> 87.968 MB. [2024-08-22T04:40:25.013Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (1397.166 ms) ====== [2024-08-22T04:40:25.013Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2024-08-22T04:40:25.013Z] GC before operation: completed in 116.886 ms, heap usage 259.534 MB -> 66.076 MB. [2024-08-22T04:40:26.409Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (1459.577 ms) ====== [2024-08-22T04:40:26.409Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2024-08-22T04:40:27.084Z] GC before operation: completed in 133.320 ms, heap usage 229.629 MB -> 66.189 MB. [2024-08-22T04:40:28.476Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (1466.473 ms) ====== [2024-08-22T04:40:28.476Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2024-08-22T04:40:28.476Z] GC before operation: completed in 119.481 ms, heap usage 240.823 MB -> 66.152 MB. [2024-08-22T04:40:29.872Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (1283.005 ms) ====== [2024-08-22T04:40:29.872Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2024-08-22T04:40:29.872Z] GC before operation: completed in 167.477 ms, heap usage 286.722 MB -> 82.930 MB. [2024-08-22T04:40:31.266Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (1317.631 ms) ====== [2024-08-22T04:40:31.266Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2024-08-22T04:40:31.266Z] GC before operation: completed in 138.150 ms, heap usage 235.764 MB -> 66.401 MB. [2024-08-22T04:40:32.656Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (1318.783 ms) ====== [2024-08-22T04:40:32.656Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2024-08-22T04:40:32.656Z] GC before operation: completed in 124.688 ms, heap usage 174.127 MB -> 66.380 MB. [2024-08-22T04:40:34.050Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (1402.278 ms) ====== [2024-08-22T04:40:34.051Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2024-08-22T04:40:34.051Z] GC before operation: completed in 118.457 ms, heap usage 128.959 MB -> 82.988 MB. [2024-08-22T04:40:34.051Z] 24/08/21 23:40:33 WARN BlockManager: Asked to remove block broadcast_204, which does not exist [2024-08-22T04:40:35.450Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (1360.033 ms) ====== [2024-08-22T04:40:35.450Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2024-08-22T04:40:36.120Z] GC before operation: completed in 168.335 ms, heap usage 295.715 MB -> 66.926 MB. [2024-08-22T04:40:37.538Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (1342.640 ms) ====== [2024-08-22T04:40:37.538Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2024-08-22T04:40:37.538Z] GC before operation: completed in 126.951 ms, heap usage 174.360 MB -> 66.880 MB. [2024-08-22T04:40:38.942Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (1431.750 ms) ====== [2024-08-22T04:40:38.942Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2024-08-22T04:40:38.942Z] GC before operation: completed in 104.471 ms, heap usage 163.818 MB -> 83.424 MB. [2024-08-22T04:40:38.942Z] 24/08/21 23:40:38 WARN BlockManager: Asked to remove block rdd_879_2, which does not exist [2024-08-22T04:40:40.333Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (1373.385 ms) ====== [2024-08-22T04:40:40.333Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2024-08-22T04:40:40.333Z] GC before operation: completed in 119.026 ms, heap usage 132.382 MB -> 72.342 MB. [2024-08-22T04:40:41.723Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (1310.233 ms) ====== [2024-08-22T04:40:41.723Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2024-08-22T04:40:41.723Z] GC before operation: completed in 116.713 ms, heap usage 238.555 MB -> 89.195 MB. [2024-08-22T04:40:43.118Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (1310.027 ms) ====== [2024-08-22T04:40:43.118Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2024-08-22T04:40:43.118Z] GC before operation: completed in 128.324 ms, heap usage 186.054 MB -> 83.714 MB. [2024-08-22T04:40:44.509Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (1393.323 ms) ====== [2024-08-22T04:40:44.509Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2024-08-22T04:40:44.509Z] GC before operation: completed in 141.778 ms, heap usage 357.763 MB -> 84.086 MB. [2024-08-22T04:40:44.509Z] 24/08/21 23:40:44 WARN BlockManager: Asked to remove block rdd_1075_2, which does not exist [2024-08-22T04:40:45.934Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (1400.763 ms) ====== [2024-08-22T04:40:45.934Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2024-08-22T04:40:46.604Z] GC before operation: completed in 115.369 ms, heap usage 236.344 MB -> 89.692 MB. [2024-08-22T04:40:47.326Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (1272.371 ms) ====== [2024-08-22T04:40:47.326Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2024-08-22T04:40:47.997Z] GC before operation: completed in 124.395 ms, heap usage 170.303 MB -> 67.463 MB. [2024-08-22T04:40:49.391Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (1410.385 ms) ====== [2024-08-22T04:40:49.391Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2024-08-22T04:40:49.391Z] GC before operation: completed in 132.685 ms, heap usage 247.266 MB -> 84.231 MB. [2024-08-22T04:40:50.788Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (1195.496 ms) ====== [2024-08-22T04:40:50.788Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2024-08-22T04:40:50.789Z] GC before operation: completed in 125.852 ms, heap usage 178.646 MB -> 89.500 MB. [2024-08-22T04:40:52.181Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (1243.386 ms) ====== [2024-08-22T04:40:52.181Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2024-08-22T04:40:52.181Z] GC before operation: completed in 118.393 ms, heap usage 162.064 MB -> 95.219 MB. [2024-08-22T04:40:53.574Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (1506.843 ms) ====== [2024-08-22T04:40:53.574Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2024-08-22T04:40:53.574Z] GC before operation: completed in 121.146 ms, heap usage 188.010 MB -> 95.318 MB. [2024-08-22T04:40:54.966Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (1358.723 ms) ====== [2024-08-22T04:40:54.966Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2024-08-22T04:40:54.966Z] GC before operation: completed in 149.121 ms, heap usage 314.782 MB -> 90.274 MB. [2024-08-22T04:40:54.966Z] 24/08/21 23:40:54 WARN BlockManager: Asked to remove block broadcast_400_piece0, which does not exist [2024-08-22T04:40:54.966Z] 24/08/21 23:40:54 WARN BlockManager: Asked to remove block broadcast_400, which does not exist [2024-08-22T04:40:56.358Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (1309.408 ms) ====== [2024-08-22T04:40:56.358Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2024-08-22T04:40:56.358Z] GC before operation: completed in 127.311 ms, heap usage 318.117 MB -> 90.155 MB. [2024-08-22T04:40:56.358Z] 24/08/21 23:40:56 WARN BlockManager: Asked to remove block broadcast_414_piece0, which does not exist [2024-08-22T04:40:57.749Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (1203.219 ms) ====== [2024-08-22T04:40:57.749Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2024-08-22T04:40:57.749Z] GC before operation: completed in 119.930 ms, heap usage 154.748 MB -> 90.454 MB. [2024-08-22T04:40:59.147Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (1339.841 ms) ====== [2024-08-22T04:40:59.147Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2024-08-22T04:40:59.147Z] GC before operation: completed in 163.190 ms, heap usage 315.711 MB -> 68.383 MB. [2024-08-22T04:41:00.542Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (1198.935 ms) ====== [2024-08-22T04:41:00.542Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2024-08-22T04:41:00.542Z] GC before operation: completed in 114.123 ms, heap usage 140.367 MB -> 95.787 MB. [2024-08-22T04:41:01.934Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (1211.495 ms) ====== [2024-08-22T04:41:01.934Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2024-08-22T04:41:01.934Z] GC before operation: completed in 117.710 ms, heap usage 262.544 MB -> 96.194 MB. [2024-08-22T04:41:03.323Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (1285.142 ms) ====== [2024-08-22T04:41:03.323Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2024-08-22T04:41:03.323Z] GC before operation: completed in 141.287 ms, heap usage 388.208 MB -> 96.271 MB. [2024-08-22T04:41:04.718Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (1310.279 ms) ====== [2024-08-22T04:41:04.718Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2024-08-22T04:41:04.718Z] GC before operation: completed in 126.062 ms, heap usage 266.134 MB -> 79.671 MB. [2024-08-22T04:41:04.718Z] 24/08/21 23:41:04 WARN BlockManager: Asked to remove block rdd_1761_0, which does not exist [2024-08-22T04:41:06.906Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (1671.836 ms) ====== [2024-08-22T04:41:06.907Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2024-08-22T04:41:06.907Z] GC before operation: completed in 538.528 ms, heap usage 290.011 MB -> 69.024 MB. [2024-08-22T04:41:08.303Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (1541.364 ms) ====== [2024-08-22T04:41:08.303Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2024-08-22T04:41:08.976Z] GC before operation: completed in 120.677 ms, heap usage 241.673 MB -> 96.570 MB. [2024-08-22T04:41:08.976Z] 24/08/21 23:41:08 WARN BlockManager: Asked to remove block broadcast_526_piece0, which does not exist [2024-08-22T04:41:08.976Z] 24/08/21 23:41:08 WARN BlockManager: Asked to remove block rdd_1859_0, which does not exist [2024-08-22T04:41:08.976Z] 24/08/21 23:41:08 WARN BlockManager: Asked to remove block rdd_1859_2, which does not exist [2024-08-22T04:41:10.367Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (1608.099 ms) ====== [2024-08-22T04:41:10.367Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2024-08-22T04:41:10.367Z] GC before operation: completed in 122.422 ms, heap usage 239.551 MB -> 80.177 MB. [2024-08-22T04:41:11.761Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (1450.127 ms) ====== [2024-08-22T04:41:12.433Z] ----------------------------------- [2024-08-22T04:41:12.433Z] renaissance-dec-tree_0_PASSED [2024-08-22T04:41:12.433Z] ----------------------------------- [2024-08-22T04:41:12.433Z] [2024-08-22T04:41:12.433Z] TEST TEARDOWN: [2024-08-22T04:41:12.433Z] Nothing to be done for teardown. [2024-08-22T04:41:12.433Z] renaissance-dec-tree_0 Finish Time: Wed Aug 21 23:41:12 2024 Epoch Time (ms): 1724301672032