[2024-11-23T02:09:51.779Z] Running test renaissance-log-regression_0 ... [2024-11-23T02:09:51.779Z] =============================================== [2024-11-23T02:09:51.779Z] renaissance-log-regression_0 Start Time: Sat Nov 23 02:09:51 2024 Epoch Time (ms): 1732327791736 [2024-11-23T02:09:51.779Z] variation: NoOptions [2024-11-23T02:09:51.779Z] JVM_OPTIONS: [2024-11-23T02:09:51.779Z] { \ [2024-11-23T02:09:51.779Z] echo ""; echo "TEST SETUP:"; \ [2024-11-23T02:09:51.779Z] echo "Nothing to be done for setup."; \ [2024-11-23T02:09:51.779Z] mkdir -p "/Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17323274597953/renaissance-log-regression_0"; \ [2024-11-23T02:09:51.779Z] cd "/Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17323274597953/renaissance-log-regression_0"; \ [2024-11-23T02:09:51.779Z] echo ""; echo "TESTING:"; \ [2024-11-23T02:09:51.780Z] "/Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/jdkbinary/j2sdk-image/Contents/Home/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 "/Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17323274597953/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-11-23T02:09:51.780Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/..; rm -f -r "/Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17323274597953/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-23T02:09:51.780Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-23T02:09:51.780Z] echo "Nothing to be done for teardown."; \ [2024-11-23T02:09:51.780Z] } 2>&1 | tee -a "/Users/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_mac_testList_1/aqa-tests/TKG/../TKG/output_17323274597953/TestTargetResult"; [2024-11-23T02:09:51.780Z] [2024-11-23T02:09:51.780Z] TEST SETUP: [2024-11-23T02:09:51.780Z] Nothing to be done for setup. [2024-11-23T02:09:51.780Z] [2024-11-23T02:09:51.780Z] TESTING: [2024-11-23T02:09:55.771Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-23T02:09:57.547Z] NOTE: 'log-regression' benchmark uses Spark local executor with 8 (out of 8) threads. [2024-11-23T02:10:00.682Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-11-23T02:10:00.682Z] GC before operation: completed in 42.509 ms, heap usage 46.045 MB -> 36.063 MB. [2024-11-23T02:10:14.203Z] ====== log-regression (apache-spark) [default], iteration 0 completed (11480.593 ms) ====== [2024-11-23T02:10:14.203Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-11-23T02:10:14.203Z] GC before operation: completed in 77.735 ms, heap usage 981.337 MB -> 120.571 MB. [2024-11-23T02:10:17.341Z] ====== log-regression (apache-spark) [default], iteration 1 completed (4742.880 ms) ====== [2024-11-23T02:10:17.341Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-11-23T02:10:17.341Z] GC before operation: completed in 73.335 ms, heap usage 433.505 MB -> 121.019 MB. [2024-11-23T02:10:21.329Z] ====== log-regression (apache-spark) [default], iteration 2 completed (3930.854 ms) ====== [2024-11-23T02:10:21.329Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-11-23T02:10:21.329Z] GC before operation: completed in 82.565 ms, heap usage 777.968 MB -> 121.510 MB. [2024-11-23T02:10:25.312Z] ====== log-regression (apache-spark) [default], iteration 3 completed (3636.674 ms) ====== [2024-11-23T02:10:25.312Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-11-23T02:10:25.312Z] GC before operation: completed in 75.789 ms, heap usage 568.881 MB -> 121.886 MB. [2024-11-23T02:10:28.444Z] ====== log-regression (apache-spark) [default], iteration 4 completed (3750.527 ms) ====== [2024-11-23T02:10:28.444Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-11-23T02:10:28.803Z] GC before operation: completed in 82.552 ms, heap usage 571.811 MB -> 122.246 MB. [2024-11-23T02:10:32.813Z] ====== log-regression (apache-spark) [default], iteration 5 completed (3719.080 ms) ====== [2024-11-23T02:10:32.813Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-11-23T02:10:32.813Z] GC before operation: completed in 84.857 ms, heap usage 279.165 MB -> 122.497 MB. [2024-11-23T02:10:35.967Z] ====== log-regression (apache-spark) [default], iteration 6 completed (3612.285 ms) ====== [2024-11-23T02:10:35.967Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-11-23T02:10:36.328Z] GC before operation: completed in 84.149 ms, heap usage 350.185 MB -> 122.852 MB. [2024-11-23T02:10:40.356Z] ====== log-regression (apache-spark) [default], iteration 7 completed (3549.386 ms) ====== [2024-11-23T02:10:40.357Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-11-23T02:10:40.357Z] GC before operation: completed in 81.294 ms, heap usage 245.410 MB -> 123.158 MB. [2024-11-23T02:10:43.505Z] ====== log-regression (apache-spark) [default], iteration 8 completed (3611.900 ms) ====== [2024-11-23T02:10:43.505Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-11-23T02:10:43.505Z] GC before operation: completed in 80.667 ms, heap usage 391.147 MB -> 123.577 MB. [2024-11-23T02:10:47.513Z] ====== log-regression (apache-spark) [default], iteration 9 completed (3644.760 ms) ====== [2024-11-23T02:10:47.513Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-11-23T02:10:47.513Z] GC before operation: completed in 80.307 ms, heap usage 274.886 MB -> 123.952 MB. [2024-11-23T02:10:50.702Z] ====== log-regression (apache-spark) [default], iteration 10 completed (3420.584 ms) ====== [2024-11-23T02:10:50.702Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-11-23T02:10:50.702Z] GC before operation: completed in 87.343 ms, heap usage 317.257 MB -> 124.301 MB. [2024-11-23T02:10:54.722Z] ====== log-regression (apache-spark) [default], iteration 11 completed (3401.579 ms) ====== [2024-11-23T02:10:54.722Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-11-23T02:10:54.722Z] GC before operation: completed in 89.725 ms, heap usage 738.825 MB -> 124.756 MB. [2024-11-23T02:10:57.861Z] ====== log-regression (apache-spark) [default], iteration 12 completed (3525.375 ms) ====== [2024-11-23T02:10:57.861Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-11-23T02:10:57.861Z] GC before operation: completed in 85.560 ms, heap usage 329.728 MB -> 124.969 MB. [2024-11-23T02:11:01.024Z] ====== log-regression (apache-spark) [default], iteration 13 completed (3319.773 ms) ====== [2024-11-23T02:11:01.024Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-11-23T02:11:01.383Z] GC before operation: completed in 86.436 ms, heap usage 717.713 MB -> 125.331 MB. [2024-11-23T02:11:04.519Z] ====== log-regression (apache-spark) [default], iteration 14 completed (3437.130 ms) ====== [2024-11-23T02:11:04.519Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-11-23T02:11:04.880Z] GC before operation: completed in 81.196 ms, heap usage 434.953 MB -> 125.582 MB. [2024-11-23T02:11:08.871Z] ====== log-regression (apache-spark) [default], iteration 15 completed (3491.254 ms) ====== [2024-11-23T02:11:08.871Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-11-23T02:11:08.871Z] GC before operation: completed in 86.661 ms, heap usage 305.125 MB -> 126.006 MB. [2024-11-23T02:11:12.010Z] ====== log-regression (apache-spark) [default], iteration 16 completed (3464.871 ms) ====== [2024-11-23T02:11:12.010Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-11-23T02:11:12.010Z] GC before operation: completed in 85.045 ms, heap usage 587.108 MB -> 126.422 MB. [2024-11-23T02:11:16.014Z] ====== log-regression (apache-spark) [default], iteration 17 completed (3476.924 ms) ====== [2024-11-23T02:11:16.014Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-11-23T02:11:16.014Z] GC before operation: completed in 89.717 ms, heap usage 640.836 MB -> 126.785 MB. [2024-11-23T02:11:19.148Z] ====== log-regression (apache-spark) [default], iteration 18 completed (3425.181 ms) ====== [2024-11-23T02:11:19.148Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-11-23T02:11:19.148Z] GC before operation: completed in 88.821 ms, heap usage 306.082 MB -> 127.001 MB. [2024-11-23T02:11:22.317Z] ====== log-regression (apache-spark) [default], iteration 19 completed (3368.692 ms) ====== [2024-11-23T02:11:22.677Z] ----------------------------------- [2024-11-23T02:11:22.677Z] renaissance-log-regression_0_PASSED [2024-11-23T02:11:22.677Z] ----------------------------------- [2024-11-23T02:11:22.677Z] [2024-11-23T02:11:22.677Z] TEST TEARDOWN: [2024-11-23T02:11:22.677Z] Nothing to be done for teardown. [2024-11-23T02:11:22.677Z] renaissance-log-regression_0 Finish Time: Sat Nov 23 02:11:22 2024 Epoch Time (ms): 1732327882540