[2025-01-07T21:54:47.876Z] Running test renaissance-log-regression_0 ... [2025-01-07T21:54:47.876Z] =============================================== [2025-01-07T21:54:47.876Z] renaissance-log-regression_0 Start Time: Tue Jan 7 21:54:47 2025 Epoch Time (ms): 1736286887220 [2025-01-07T21:54:47.876Z] variation: NoOptions [2025-01-07T21:54:47.876Z] JVM_OPTIONS: [2025-01-07T21:54:47.876Z] { \ [2025-01-07T21:54:47.876Z] echo ""; echo "TEST SETUP:"; \ [2025-01-07T21:54:47.876Z] echo "Nothing to be done for setup."; \ [2025-01-07T21:54:47.876Z] mkdir -p "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17362860137546/renaissance-log-regression_0"; \ [2025-01-07T21:54:47.876Z] cd "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17362860137546/renaissance-log-regression_0"; \ [2025-01-07T21:54:47.876Z] echo ""; echo "TESTING:"; \ [2025-01-07T21:54:47.876Z] "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/jdkbinary/j2sdk-image/bin/java" -jar "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17362860137546/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2025-01-07T21:54:47.876Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17362860137546/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-01-07T21:54:47.876Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-01-07T21:54:47.876Z] echo "Nothing to be done for teardown."; \ [2025-01-07T21:54:47.876Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17362860137546/TestTargetResult"; [2025-01-07T21:54:47.876Z] [2025-01-07T21:54:47.876Z] TEST SETUP: [2025-01-07T21:54:47.876Z] Nothing to be done for setup. [2025-01-07T21:54:47.876Z] [2025-01-07T21:54:47.876Z] TESTING: [2025-01-07T21:54:53.517Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-01-07T21:54:56.973Z] NOTE: 'log-regression' benchmark uses Spark local executor with 12 (out of 24) threads. [2025-01-07T21:55:03.990Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2025-01-07T21:55:03.990Z] GC before operation: completed in 217.004 ms, heap usage 95.336 MB -> 26.549 MB. [2025-01-07T21:55:18.020Z] ====== log-regression (apache-spark) [default], iteration 0 completed (13670.313 ms) ====== [2025-01-07T21:55:18.020Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2025-01-07T21:55:18.020Z] GC before operation: completed in 415.486 ms, heap usage 148.045 MB -> 101.486 MB. [2025-01-07T21:55:20.514Z] ====== log-regression (apache-spark) [default], iteration 1 completed (3053.595 ms) ====== [2025-01-07T21:55:20.514Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2025-01-07T21:55:21.290Z] GC before operation: completed in 347.634 ms, heap usage 272.664 MB -> 102.886 MB. [2025-01-07T21:55:23.786Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2784.718 ms) ====== [2025-01-07T21:55:23.786Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2025-01-07T21:55:23.786Z] GC before operation: completed in 282.106 ms, heap usage 244.711 MB -> 103.154 MB. [2025-01-07T21:55:27.258Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2598.076 ms) ====== [2025-01-07T21:55:27.258Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2025-01-07T21:55:27.258Z] GC before operation: completed in 289.098 ms, heap usage 241.978 MB -> 103.500 MB. [2025-01-07T21:55:29.749Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2607.480 ms) ====== [2025-01-07T21:55:29.749Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2025-01-07T21:55:29.749Z] GC before operation: completed in 243.255 ms, heap usage 213.214 MB -> 103.749 MB. [2025-01-07T21:55:32.240Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2371.098 ms) ====== [2025-01-07T21:55:32.240Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2025-01-07T21:55:32.240Z] GC before operation: completed in 285.742 ms, heap usage 232.632 MB -> 104.397 MB. [2025-01-07T21:55:34.741Z] ====== log-regression (apache-spark) [default], iteration 6 completed (2394.201 ms) ====== [2025-01-07T21:55:34.742Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2025-01-07T21:55:35.518Z] GC before operation: completed in 297.385 ms, heap usage 239.494 MB -> 104.906 MB. [2025-01-07T21:55:38.010Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2353.911 ms) ====== [2025-01-07T21:55:38.010Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2025-01-07T21:55:38.010Z] GC before operation: completed in 204.496 ms, heap usage 220.534 MB -> 105.240 MB. [2025-01-07T21:55:40.513Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2328.818 ms) ====== [2025-01-07T21:55:40.513Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2025-01-07T21:55:40.513Z] GC before operation: completed in 188.559 ms, heap usage 226.741 MB -> 105.765 MB. [2025-01-07T21:55:43.021Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2308.870 ms) ====== [2025-01-07T21:55:43.021Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2025-01-07T21:55:43.021Z] GC before operation: completed in 228.493 ms, heap usage 195.339 MB -> 105.941 MB. [2025-01-07T21:55:45.517Z] ====== log-regression (apache-spark) [default], iteration 10 completed (2182.293 ms) ====== [2025-01-07T21:55:45.517Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2025-01-07T21:55:45.517Z] GC before operation: completed in 240.602 ms, heap usage 198.321 MB -> 106.395 MB. [2025-01-07T21:55:47.121Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2206.644 ms) ====== [2025-01-07T21:55:47.121Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2025-01-07T21:55:47.899Z] GC before operation: completed in 223.452 ms, heap usage 264.710 MB -> 107.443 MB. [2025-01-07T21:55:50.394Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2277.811 ms) ====== [2025-01-07T21:55:50.394Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2025-01-07T21:55:50.394Z] GC before operation: completed in 222.667 ms, heap usage 207.639 MB -> 107.258 MB. [2025-01-07T21:55:51.996Z] ====== log-regression (apache-spark) [default], iteration 13 completed (2148.589 ms) ====== [2025-01-07T21:55:51.996Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2025-01-07T21:55:52.777Z] GC before operation: completed in 217.005 ms, heap usage 248.426 MB -> 108.163 MB. [2025-01-07T21:55:55.275Z] ====== log-regression (apache-spark) [default], iteration 14 completed (2206.863 ms) ====== [2025-01-07T21:55:55.275Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2025-01-07T21:55:55.275Z] GC before operation: completed in 293.604 ms, heap usage 277.118 MB -> 108.863 MB. [2025-01-07T21:55:56.887Z] ====== log-regression (apache-spark) [default], iteration 15 completed (2163.828 ms) ====== [2025-01-07T21:55:56.887Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2025-01-07T21:55:57.670Z] GC before operation: completed in 258.593 ms, heap usage 317.738 MB -> 109.653 MB. [2025-01-07T21:55:59.278Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1998.171 ms) ====== [2025-01-07T21:55:59.278Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2025-01-07T21:56:00.055Z] GC before operation: completed in 243.495 ms, heap usage 268.381 MB -> 109.905 MB. [2025-01-07T21:56:01.657Z] ====== log-regression (apache-spark) [default], iteration 17 completed (2023.667 ms) ====== [2025-01-07T21:56:01.657Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2025-01-07T21:56:02.433Z] GC before operation: completed in 276.254 ms, heap usage 319.166 MB -> 110.666 MB. [2025-01-07T21:56:04.056Z] ====== log-regression (apache-spark) [default], iteration 18 completed (2005.365 ms) ====== [2025-01-07T21:56:04.056Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2025-01-07T21:56:04.056Z] GC before operation: completed in 275.997 ms, heap usage 319.658 MB -> 111.165 MB. [2025-01-07T21:56:06.552Z] ====== log-regression (apache-spark) [default], iteration 19 completed (2086.501 ms) ====== [2025-01-07T21:56:07.329Z] ----------------------------------- [2025-01-07T21:56:07.329Z] renaissance-log-regression_0_PASSED [2025-01-07T21:56:07.329Z] ----------------------------------- [2025-01-07T21:56:07.329Z] [2025-01-07T21:56:07.329Z] TEST TEARDOWN: [2025-01-07T21:56:07.329Z] Nothing to be done for teardown. [2025-01-07T21:56:07.329Z] renaissance-log-regression_0 Finish Time: Tue Jan 7 21:56:06 2025 Epoch Time (ms): 1736286966727