[2025-08-01T21:36:06.394Z] Running test renaissance-dec-tree_0 ... [2025-08-01T21:36:06.394Z] =============================================== [2025-08-01T21:36:06.394Z] renaissance-dec-tree_0 Start Time: Fri Aug 1 21:36:05 2025 Epoch Time (ms): 1754084165516 [2025-08-01T21:36:06.394Z] variation: NoOptions [2025-08-01T21:36:06.394Z] JVM_OPTIONS: [2025-08-01T21:36:06.394Z] { \ [2025-08-01T21:36:06.394Z] echo ""; echo "TEST SETUP:"; \ [2025-08-01T21:36:06.394Z] echo "Nothing to be done for setup."; \ [2025-08-01T21:36:06.394Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1754083055940/renaissance-dec-tree_0"; \ [2025-08-01T21:36:06.394Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1754083055940/renaissance-dec-tree_0"; \ [2025-08-01T21:36:06.394Z] echo ""; echo "TESTING:"; \ [2025-08-01T21:36:06.394Z] "/home/jenkins/workspace/Test_openjdk11_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_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1754083055940/renaissance-dec-tree_0"/dec-tree.json" dec-tree; \ [2025-08-01T21:36:06.394Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-dec-tree_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1754083055940/renaissance-dec-tree_0"; else echo "-----------------------------------"; echo "renaissance-dec-tree_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-01T21:36:06.394Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-01T21:36:06.394Z] echo "Nothing to be done for teardown."; \ [2025-08-01T21:36:06.394Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_1754083055940/TestTargetResult"; [2025-08-01T21:36:06.394Z] [2025-08-01T21:36:06.394Z] TEST SETUP: [2025-08-01T21:36:06.394Z] Nothing to be done for setup. [2025-08-01T21:36:06.394Z] [2025-08-01T21:36:06.394Z] TESTING: [2025-08-01T21:36:27.257Z] NOTE: 'dec-tree' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-08-01T21:36:27.988Z] WARNING: An illegal reflective access operation has occurred [2025-08-01T21:36:27.988Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_s390x_linux/aqa-tests/TKG/output_1754083055940/renaissance-dec-tree_0/harness-213609-3638161059280296677/apache-spark/lib/spark-core_2.13-3.5.3.jar) to field java.net.URI.scheme [2025-08-01T21:36:27.988Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2025-08-01T21:36:27.988Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2025-08-01T21:36:27.988Z] WARNING: All illegal access operations will be denied in a future release [2025-08-01T21:36:30.182Z] ====== dec-tree (apache-spark) [default], iteration 0 started ====== [2025-08-01T21:36:30.182Z] GC before operation: completed in 104.810 ms, heap usage 59.836 MB -> 34.362 MB. [2025-08-01T21:36:51.195Z] ====== dec-tree (apache-spark) [default], iteration 0 completed (20622.515 ms) ====== [2025-08-01T21:36:51.195Z] ====== dec-tree (apache-spark) [default], iteration 1 started ====== [2025-08-01T21:36:51.926Z] GC before operation: completed in 494.538 ms, heap usage 278.778 MB -> 67.291 MB. [2025-08-01T21:36:56.951Z] ====== dec-tree (apache-spark) [default], iteration 1 completed (5710.981 ms) ====== [2025-08-01T21:36:56.951Z] ====== dec-tree (apache-spark) [default], iteration 2 started ====== [2025-08-01T21:36:57.627Z] GC before operation: completed in 286.716 ms, heap usage 138.761 MB -> 95.054 MB. [2025-08-01T21:36:57.627Z] 21:36:57.234 WARN [block-manager-storage-async-thread-pool-7] org.apache.spark.storage.BlockManager - Block rdd_93_1 could not be removed as it was not found on disk or in memory [2025-08-01T21:36:58.330Z] 21:36:57.896 WARN [block-manager-storage-async-thread-pool-7] org.apache.spark.storage.BlockManager - Block rdd_93_1 was not removed normally. [2025-08-01T21:36:58.330Z] 21:36:57.897 ERROR [block-manager-storage-async-thread-pool-16] org.apache.spark.storage.BlockManagerStorageEndpoint - Error in removing RDD 93 [2025-08-01T21:36:58.330Z] org.apache.spark.SparkException: Block rdd_93_1 does not exist [2025-08-01T21:36:58.330Z] at org.apache.spark.errors.SparkCoreErrors$.blockDoesNotExistError(SparkCoreErrors.scala:318) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.330Z] at org.apache.spark.storage.BlockInfoManager.blockInfo(BlockInfoManager.scala:269) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.330Z] at org.apache.spark.storage.BlockInfoManager.removeBlock(BlockInfoManager.scala:547) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.330Z] at org.apache.spark.storage.BlockManager.removeBlockInternal(BlockManager.scala:2091) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.330Z] at org.apache.spark.storage.BlockManager.removeBlock(BlockManager.scala:2057) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.330Z] at org.apache.spark.storage.BlockManager.$anonfun$removeRdd$4(BlockManager.scala:1993) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.330Z] at org.apache.spark.storage.BlockManager.$anonfun$removeRdd$4$adapted(BlockManager.scala:1993) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at scala.collection.IterableOnceOps.foreach(IterableOnce.scala:619) ~[scala-library-2.13.15.jar:?] [2025-08-01T21:36:58.331Z] at scala.collection.IterableOnceOps.foreach$(IterableOnce.scala:617) ~[scala-library-2.13.15.jar:?] [2025-08-01T21:36:58.331Z] at scala.collection.AbstractIterator.foreach(Iterator.scala:1303) ~[scala-library-2.13.15.jar:?] [2025-08-01T21:36:58.331Z] at org.apache.spark.storage.BlockManager.removeRdd(BlockManager.scala:1993) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.storage.BlockManagerStorageEndpoint$$anonfun$receiveAndReply$1.$anonfun$applyOrElse$2(BlockManagerStorageEndpoint.scala:53) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at scala.runtime.java8.JFunction0$mcI$sp.apply(JFunction0$mcI$sp.scala:17) ~[scala-library-2.13.15.jar:?] [2025-08-01T21:36:58.331Z] at org.apache.spark.storage.BlockManagerStorageEndpoint.$anonfun$doAsync$1(BlockManagerStorageEndpoint.scala:101) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at scala.concurrent.Future$.$anonfun$apply$1(Future.scala:687) ~[scala-library-2.13.15.jar:?] [2025-08-01T21:36:58.331Z] at scala.concurrent.impl.Promise$Transformation.run(Promise.scala:467) [scala-library-2.13.15.jar:?] [2025-08-01T21:36:58.331Z] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?] [2025-08-01T21:36:58.331Z] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?] [2025-08-01T21:36:58.331Z] at java.lang.Thread.run(Thread.java:829) [?:?] [2025-08-01T21:36:58.331Z] 21:36:57.983 ERROR [Spark Context Cleaner] org.apache.spark.ContextCleaner - Error cleaning RDD 93 [2025-08-01T21:36:58.331Z] org.apache.spark.SparkException: Exception thrown in awaitResult: [2025-08-01T21:36:58.331Z] at org.apache.spark.util.SparkThreadUtils$.awaitResult(SparkThreadUtils.scala:56) ~[spark-common-utils_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.util.ThreadUtils$.awaitResult(ThreadUtils.scala:310) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.rpc.RpcTimeout.awaitResult(RpcTimeout.scala:75) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.storage.BlockManagerMaster.removeRdd(BlockManagerMaster.scala:196) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.SparkContext.unpersistRDD(SparkContext.scala:2070) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.ContextCleaner.doCleanupRDD(ContextCleaner.scala:225) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.ContextCleaner.$anonfun$keepCleaning$3(ContextCleaner.scala:200) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.ContextCleaner.$anonfun$keepCleaning$3$adapted(ContextCleaner.scala:195) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at scala.Option.foreach(Option.scala:437) ~[scala-library-2.13.15.jar:?] [2025-08-01T21:36:58.331Z] at org.apache.spark.ContextCleaner.$anonfun$keepCleaning$1(ContextCleaner.scala:195) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.util.Utils$.tryOrStopSparkContext(Utils.scala:1356) [spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.ContextCleaner.org$apache$spark$ContextCleaner$$keepCleaning(ContextCleaner.scala:189) [spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.ContextCleaner$$anon$1.run(ContextCleaner.scala:79) [spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] Caused by: org.apache.spark.SparkException: Block rdd_93_1 does not exist [2025-08-01T21:36:58.331Z] at org.apache.spark.errors.SparkCoreErrors$.blockDoesNotExistError(SparkCoreErrors.scala:318) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.storage.BlockInfoManager.blockInfo(BlockInfoManager.scala:269) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.storage.BlockInfoManager.removeBlock(BlockInfoManager.scala:547) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.storage.BlockManager.removeBlockInternal(BlockManager.scala:2091) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.storage.BlockManager.removeBlock(BlockManager.scala:2057) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.storage.BlockManager.$anonfun$removeRdd$4(BlockManager.scala:1993) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.storage.BlockManager.$anonfun$removeRdd$4$adapted(BlockManager.scala:1993) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at scala.collection.IterableOnceOps.foreach(IterableOnce.scala:619) ~[scala-library-2.13.15.jar:?] [2025-08-01T21:36:58.331Z] at scala.collection.IterableOnceOps.foreach$(IterableOnce.scala:617) ~[scala-library-2.13.15.jar:?] [2025-08-01T21:36:58.331Z] at scala.collection.AbstractIterator.foreach(Iterator.scala:1303) ~[scala-library-2.13.15.jar:?] [2025-08-01T21:36:58.331Z] at org.apache.spark.storage.BlockManager.removeRdd(BlockManager.scala:1993) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.storage.BlockManagerStorageEndpoint$$anonfun$receiveAndReply$1.$anonfun$applyOrElse$2(BlockManagerStorageEndpoint.scala:53) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at scala.runtime.java8.JFunction0$mcI$sp.apply(JFunction0$mcI$sp.scala:17) ~[scala-library-2.13.15.jar:?] [2025-08-01T21:36:58.331Z] at org.apache.spark.storage.BlockManagerStorageEndpoint.$anonfun$doAsync$1(BlockManagerStorageEndpoint.scala:101) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at scala.concurrent.Future$.$anonfun$apply$1(Future.scala:687) ~[scala-library-2.13.15.jar:?] [2025-08-01T21:36:58.331Z] at scala.concurrent.impl.Promise$Transformation.run(Promise.scala:467) ~[scala-library-2.13.15.jar:?] [2025-08-01T21:36:58.331Z] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) ~[?:?] [2025-08-01T21:36:58.331Z] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) ~[?:?] [2025-08-01T21:36:58.331Z] at java.lang.Thread.run(Thread.java:829) ~[?:?] [2025-08-01T21:36:58.331Z] 21:36:57.987 WARN [block-manager-ask-thread-pool-77] org.apache.spark.storage.BlockManagerMaster - Failed to remove RDD 93 - Block rdd_93_1 does not exist [2025-08-01T21:36:58.331Z] org.apache.spark.SparkException: Block rdd_93_1 does not exist [2025-08-01T21:36:58.331Z] at org.apache.spark.errors.SparkCoreErrors$.blockDoesNotExistError(SparkCoreErrors.scala:318) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.storage.BlockInfoManager.blockInfo(BlockInfoManager.scala:269) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.storage.BlockInfoManager.removeBlock(BlockInfoManager.scala:547) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.storage.BlockManager.removeBlockInternal(BlockManager.scala:2091) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.storage.BlockManager.removeBlock(BlockManager.scala:2057) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.storage.BlockManager.$anonfun$removeRdd$4(BlockManager.scala:1993) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.storage.BlockManager.$anonfun$removeRdd$4$adapted(BlockManager.scala:1993) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at scala.collection.IterableOnceOps.foreach(IterableOnce.scala:619) ~[scala-library-2.13.15.jar:?] [2025-08-01T21:36:58.331Z] at scala.collection.IterableOnceOps.foreach$(IterableOnce.scala:617) ~[scala-library-2.13.15.jar:?] [2025-08-01T21:36:58.331Z] at scala.collection.AbstractIterator.foreach(Iterator.scala:1303) ~[scala-library-2.13.15.jar:?] [2025-08-01T21:36:58.331Z] at org.apache.spark.storage.BlockManager.removeRdd(BlockManager.scala:1993) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at org.apache.spark.storage.BlockManagerStorageEndpoint$$anonfun$receiveAndReply$1.$anonfun$applyOrElse$2(BlockManagerStorageEndpoint.scala:53) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at scala.runtime.java8.JFunction0$mcI$sp.apply(JFunction0$mcI$sp.scala:17) ~[scala-library-2.13.15.jar:?] [2025-08-01T21:36:58.331Z] at org.apache.spark.storage.BlockManagerStorageEndpoint.$anonfun$doAsync$1(BlockManagerStorageEndpoint.scala:101) ~[spark-core_2.13-3.5.3.jar:3.5.3] [2025-08-01T21:36:58.331Z] at scala.concurrent.Future$.$anonfun$apply$1(Future.scala:687) ~[scala-library-2.13.15.jar:?] [2025-08-01T21:36:58.331Z] at scala.concurrent.impl.Promise$Transformation.run(Promise.scala:467) [scala-library-2.13.15.jar:?] [2025-08-01T21:36:58.331Z] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) [?:?] [2025-08-01T21:36:58.331Z] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) [?:?] [2025-08-01T21:36:58.331Z] at java.lang.Thread.run(Thread.java:829) [?:?] [2025-08-01T21:37:03.649Z] ====== dec-tree (apache-spark) [default], iteration 2 completed (6272.267 ms) ====== [2025-08-01T21:37:03.649Z] ====== dec-tree (apache-spark) [default], iteration 3 started ====== [2025-08-01T21:37:04.377Z] GC before operation: completed in 267.911 ms, heap usage 185.514 MB -> 68.678 MB. [2025-08-01T21:37:08.520Z] ====== dec-tree (apache-spark) [default], iteration 3 completed (4476.746 ms) ====== [2025-08-01T21:37:08.520Z] ====== dec-tree (apache-spark) [default], iteration 4 started ====== [2025-08-01T21:37:09.244Z] GC before operation: completed in 321.314 ms, heap usage 269.778 MB -> 96.518 MB. [2025-08-01T21:37:12.351Z] ====== dec-tree (apache-spark) [default], iteration 4 completed (3365.474 ms) ====== [2025-08-01T21:37:12.351Z] ====== dec-tree (apache-spark) [default], iteration 5 started ====== [2025-08-01T21:37:13.042Z] GC before operation: completed in 449.390 ms, heap usage 262.106 MB -> 69.245 MB. [2025-08-01T21:37:18.384Z] ====== dec-tree (apache-spark) [default], iteration 5 completed (5625.439 ms) ====== [2025-08-01T21:37:18.384Z] ====== dec-tree (apache-spark) [default], iteration 6 started ====== [2025-08-01T21:37:18.384Z] GC before operation: completed in 310.611 ms, heap usage 324.517 MB -> 69.428 MB. [2025-08-01T21:37:23.620Z] ====== dec-tree (apache-spark) [default], iteration 6 completed (4636.549 ms) ====== [2025-08-01T21:37:23.620Z] ====== dec-tree (apache-spark) [default], iteration 7 started ====== [2025-08-01T21:37:23.620Z] GC before operation: completed in 368.352 ms, heap usage 281.834 MB -> 69.616 MB. [2025-08-01T21:37:28.691Z] ====== dec-tree (apache-spark) [default], iteration 7 completed (4294.259 ms) ====== [2025-08-01T21:37:28.691Z] ====== dec-tree (apache-spark) [default], iteration 8 started ====== [2025-08-01T21:37:28.691Z] GC before operation: completed in 348.902 ms, heap usage 107.733 MB -> 69.334 MB. [2025-08-01T21:37:33.190Z] ====== dec-tree (apache-spark) [default], iteration 8 completed (4510.697 ms) ====== [2025-08-01T21:37:33.190Z] ====== dec-tree (apache-spark) [default], iteration 9 started ====== [2025-08-01T21:37:33.190Z] GC before operation: completed in 491.756 ms, heap usage 204.324 MB -> 69.908 MB. [2025-08-01T21:37:37.268Z] ====== dec-tree (apache-spark) [default], iteration 9 completed (3890.346 ms) ====== [2025-08-01T21:37:37.268Z] ====== dec-tree (apache-spark) [default], iteration 10 started ====== [2025-08-01T21:37:37.965Z] GC before operation: completed in 449.446 ms, heap usage 245.181 MB -> 70.014 MB. [2025-08-01T21:37:42.292Z] ====== dec-tree (apache-spark) [default], iteration 10 completed (4623.899 ms) ====== [2025-08-01T21:37:42.292Z] ====== dec-tree (apache-spark) [default], iteration 11 started ====== [2025-08-01T21:37:43.004Z] GC before operation: completed in 620.582 ms, heap usage 218.993 MB -> 70.129 MB. [2025-08-01T21:37:47.143Z] ====== dec-tree (apache-spark) [default], iteration 11 completed (3754.718 ms) ====== [2025-08-01T21:37:47.143Z] ====== dec-tree (apache-spark) [default], iteration 12 started ====== [2025-08-01T21:37:47.143Z] GC before operation: completed in 276.542 ms, heap usage 210.497 MB -> 89.535 MB. [2025-08-01T21:37:50.272Z] ====== dec-tree (apache-spark) [default], iteration 12 completed (3370.247 ms) ====== [2025-08-01T21:37:50.272Z] ====== dec-tree (apache-spark) [default], iteration 13 started ====== [2025-08-01T21:37:51.002Z] GC before operation: completed in 396.174 ms, heap usage 256.867 MB -> 97.816 MB. [2025-08-01T21:37:55.053Z] ====== dec-tree (apache-spark) [default], iteration 13 completed (3958.319 ms) ====== [2025-08-01T21:37:55.053Z] ====== dec-tree (apache-spark) [default], iteration 14 started ====== [2025-08-01T21:37:55.053Z] GC before operation: completed in 524.505 ms, heap usage 278.301 MB -> 70.669 MB. [2025-08-01T21:37:59.364Z] ====== dec-tree (apache-spark) [default], iteration 14 completed (4403.876 ms) ====== [2025-08-01T21:37:59.364Z] ====== dec-tree (apache-spark) [default], iteration 15 started ====== [2025-08-01T21:38:00.053Z] GC before operation: completed in 419.775 ms, heap usage 140.354 MB -> 70.237 MB. [2025-08-01T21:38:04.062Z] ====== dec-tree (apache-spark) [default], iteration 15 completed (3442.271 ms) ====== [2025-08-01T21:38:04.062Z] ====== dec-tree (apache-spark) [default], iteration 16 started ====== [2025-08-01T21:38:04.062Z] GC before operation: completed in 336.121 ms, heap usage 195.694 MB -> 70.726 MB. [2025-08-01T21:38:07.236Z] ====== dec-tree (apache-spark) [default], iteration 16 completed (3476.887 ms) ====== [2025-08-01T21:38:07.236Z] ====== dec-tree (apache-spark) [default], iteration 17 started ====== [2025-08-01T21:38:07.941Z] GC before operation: completed in 430.192 ms, heap usage 286.558 MB -> 70.702 MB. [2025-08-01T21:38:11.186Z] ====== dec-tree (apache-spark) [default], iteration 17 completed (3498.535 ms) ====== [2025-08-01T21:38:11.186Z] ====== dec-tree (apache-spark) [default], iteration 18 started ====== [2025-08-01T21:38:11.875Z] GC before operation: completed in 436.537 ms, heap usage 181.882 MB -> 70.960 MB. [2025-08-01T21:38:16.053Z] ====== dec-tree (apache-spark) [default], iteration 18 completed (3798.952 ms) ====== [2025-08-01T21:38:16.053Z] ====== dec-tree (apache-spark) [default], iteration 19 started ====== [2025-08-01T21:38:16.053Z] GC before operation: completed in 513.077 ms, heap usage 233.768 MB -> 71.321 MB. [2025-08-01T21:38:19.177Z] ====== dec-tree (apache-spark) [default], iteration 19 completed (3128.326 ms) ====== [2025-08-01T21:38:19.177Z] ====== dec-tree (apache-spark) [default], iteration 20 started ====== [2025-08-01T21:38:19.874Z] GC before operation: completed in 446.841 ms, heap usage 256.082 MB -> 98.640 MB. [2025-08-01T21:38:23.043Z] ====== dec-tree (apache-spark) [default], iteration 20 completed (3023.288 ms) ====== [2025-08-01T21:38:23.043Z] ====== dec-tree (apache-spark) [default], iteration 21 started ====== [2025-08-01T21:38:23.043Z] GC before operation: completed in 400.641 ms, heap usage 288.311 MB -> 71.297 MB. [2025-08-01T21:38:26.178Z] ====== dec-tree (apache-spark) [default], iteration 21 completed (3357.304 ms) ====== [2025-08-01T21:38:26.178Z] ====== dec-tree (apache-spark) [default], iteration 22 started ====== [2025-08-01T21:38:26.901Z] GC before operation: completed in 356.452 ms, heap usage 148.298 MB -> 98.826 MB. [2025-08-01T21:38:30.040Z] ====== dec-tree (apache-spark) [default], iteration 22 completed (2902.548 ms) ====== [2025-08-01T21:38:30.040Z] ====== dec-tree (apache-spark) [default], iteration 23 started ====== [2025-08-01T21:38:30.040Z] GC before operation: completed in 354.626 ms, heap usage 226.687 MB -> 98.947 MB. [2025-08-01T21:38:33.194Z] ====== dec-tree (apache-spark) [default], iteration 23 completed (3294.726 ms) ====== [2025-08-01T21:38:33.194Z] ====== dec-tree (apache-spark) [default], iteration 24 started ====== [2025-08-01T21:38:33.867Z] GC before operation: completed in 460.144 ms, heap usage 298.612 MB -> 71.804 MB. [2025-08-01T21:38:37.037Z] ====== dec-tree (apache-spark) [default], iteration 24 completed (3472.266 ms) ====== [2025-08-01T21:38:37.037Z] ====== dec-tree (apache-spark) [default], iteration 25 started ====== [2025-08-01T21:38:37.744Z] GC before operation: completed in 380.443 ms, heap usage 175.210 MB -> 99.146 MB. [2025-08-01T21:38:40.970Z] ====== dec-tree (apache-spark) [default], iteration 25 completed (3680.475 ms) ====== [2025-08-01T21:38:40.970Z] ====== dec-tree (apache-spark) [default], iteration 26 started ====== [2025-08-01T21:38:41.685Z] GC before operation: completed in 361.831 ms, heap usage 122.787 MB -> 79.427 MB. [2025-08-01T21:38:44.796Z] ====== dec-tree (apache-spark) [default], iteration 26 completed (3415.877 ms) ====== [2025-08-01T21:38:44.796Z] ====== dec-tree (apache-spark) [default], iteration 27 started ====== [2025-08-01T21:38:45.520Z] GC before operation: completed in 431.437 ms, heap usage 209.639 MB -> 80.298 MB. [2025-08-01T21:38:48.658Z] ====== dec-tree (apache-spark) [default], iteration 27 completed (3271.836 ms) ====== [2025-08-01T21:38:48.658Z] ====== dec-tree (apache-spark) [default], iteration 28 started ====== [2025-08-01T21:38:49.396Z] GC before operation: completed in 473.223 ms, heap usage 173.123 MB -> 71.988 MB. [2025-08-01T21:38:52.512Z] ====== dec-tree (apache-spark) [default], iteration 28 completed (3661.560 ms) ====== [2025-08-01T21:38:52.512Z] ====== dec-tree (apache-spark) [default], iteration 29 started ====== [2025-08-01T21:38:53.193Z] GC before operation: completed in 487.166 ms, heap usage 198.999 MB -> 80.494 MB. [2025-08-01T21:38:57.031Z] ====== dec-tree (apache-spark) [default], iteration 29 completed (3495.727 ms) ====== [2025-08-01T21:38:57.031Z] ====== dec-tree (apache-spark) [default], iteration 30 started ====== [2025-08-01T21:38:57.735Z] GC before operation: completed in 614.217 ms, heap usage 129.569 MB -> 71.561 MB. [2025-08-01T21:39:00.811Z] ====== dec-tree (apache-spark) [default], iteration 30 completed (3081.527 ms) ====== [2025-08-01T21:39:00.811Z] ====== dec-tree (apache-spark) [default], iteration 31 started ====== [2025-08-01T21:39:00.811Z] GC before operation: completed in 409.165 ms, heap usage 221.459 MB -> 72.342 MB. [2025-08-01T21:39:03.965Z] ====== dec-tree (apache-spark) [default], iteration 31 completed (2904.440 ms) ====== [2025-08-01T21:39:03.965Z] ====== dec-tree (apache-spark) [default], iteration 32 started ====== [2025-08-01T21:39:03.965Z] GC before operation: completed in 286.874 ms, heap usage 126.043 MB -> 99.849 MB. [2025-08-01T21:39:08.109Z] ====== dec-tree (apache-spark) [default], iteration 32 completed (3719.584 ms) ====== [2025-08-01T21:39:08.109Z] ====== dec-tree (apache-spark) [default], iteration 33 started ====== [2025-08-01T21:39:08.109Z] GC before operation: completed in 403.857 ms, heap usage 167.756 MB -> 72.366 MB. [2025-08-01T21:39:11.242Z] ====== dec-tree (apache-spark) [default], iteration 33 completed (3303.152 ms) ====== [2025-08-01T21:39:11.242Z] ====== dec-tree (apache-spark) [default], iteration 34 started ====== [2025-08-01T21:39:11.977Z] GC before operation: completed in 519.383 ms, heap usage 202.907 MB -> 72.610 MB. [2025-08-01T21:39:15.141Z] ====== dec-tree (apache-spark) [default], iteration 34 completed (3084.895 ms) ====== [2025-08-01T21:39:15.141Z] ====== dec-tree (apache-spark) [default], iteration 35 started ====== [2025-08-01T21:39:15.818Z] GC before operation: completed in 596.752 ms, heap usage 200.160 MB -> 72.564 MB. [2025-08-01T21:39:19.070Z] ====== dec-tree (apache-spark) [default], iteration 35 completed (2980.117 ms) ====== [2025-08-01T21:39:19.070Z] ====== dec-tree (apache-spark) [default], iteration 36 started ====== [2025-08-01T21:39:19.070Z] GC before operation: completed in 683.831 ms, heap usage 279.174 MB -> 73.061 MB. [2025-08-01T21:39:22.169Z] ====== dec-tree (apache-spark) [default], iteration 36 completed (2979.050 ms) ====== [2025-08-01T21:39:22.169Z] ====== dec-tree (apache-spark) [default], iteration 37 started ====== [2025-08-01T21:39:22.877Z] GC before operation: completed in 309.268 ms, heap usage 194.726 MB -> 100.402 MB. [2025-08-01T21:39:25.998Z] ====== dec-tree (apache-spark) [default], iteration 37 completed (2884.770 ms) ====== [2025-08-01T21:39:25.998Z] ====== dec-tree (apache-spark) [default], iteration 38 started ====== [2025-08-01T21:39:25.998Z] GC before operation: completed in 438.031 ms, heap usage 196.451 MB -> 73.043 MB. [2025-08-01T21:39:29.133Z] ====== dec-tree (apache-spark) [default], iteration 38 completed (2909.497 ms) ====== [2025-08-01T21:39:29.133Z] ====== dec-tree (apache-spark) [default], iteration 39 started ====== [2025-08-01T21:39:29.133Z] GC before operation: completed in 275.181 ms, heap usage 196.679 MB -> 73.008 MB. [2025-08-01T21:39:32.300Z] ====== dec-tree (apache-spark) [default], iteration 39 completed (3061.263 ms) ====== [2025-08-01T21:39:33.013Z] ----------------------------------- [2025-08-01T21:39:33.013Z] renaissance-dec-tree_0_PASSED [2025-08-01T21:39:33.013Z] ----------------------------------- [2025-08-01T21:39:33.013Z] [2025-08-01T21:39:33.013Z] TEST TEARDOWN: [2025-08-01T21:39:33.013Z] Nothing to be done for teardown. [2025-08-01T21:39:33.013Z] renaissance-dec-tree_0 Finish Time: Fri Aug 1 21:39:32 2025 Epoch Time (ms): 1754084372856