[2024-11-22T23:14:45.337Z] Running test renaissance-log-regression_0 ... [2024-11-22T23:14:45.337Z] =============================================== [2024-11-22T23:14:45.337Z] renaissance-log-regression_0 Start Time: Fri Nov 22 23:14:44 2024 Epoch Time (ms): 1732317284811 [2024-11-22T23:14:45.337Z] variation: NoOptions [2024-11-22T23:14:45.337Z] JVM_OPTIONS: [2024-11-22T23:14:45.337Z] { \ [2024-11-22T23:14:45.337Z] echo ""; echo "TEST SETUP:"; \ [2024-11-22T23:14:45.337Z] echo "Nothing to be done for setup."; \ [2024-11-22T23:14:45.337Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17323165998723/renaissance-log-regression_0"; \ [2024-11-22T23:14:45.337Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17323165998723/renaissance-log-regression_0"; \ [2024-11-22T23:14:45.337Z] echo ""; echo "TESTING:"; \ [2024-11-22T23:14:45.337Z] "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_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_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17323165998723/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-11-22T23:14:45.337Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17323165998723/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-22T23:14:45.337Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-22T23:14:45.337Z] echo "Nothing to be done for teardown."; \ [2024-11-22T23:14:45.337Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_ppc64le_linux/aqa-tests/TKG/../TKG/output_17323165998723/TestTargetResult"; [2024-11-22T23:14:45.337Z] [2024-11-22T23:14:45.337Z] TEST SETUP: [2024-11-22T23:14:45.337Z] Nothing to be done for setup. [2024-11-22T23:14:45.337Z] [2024-11-22T23:14:45.337Z] TESTING: [2024-11-22T23:14:48.316Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-22T23:14:50.240Z] NOTE: 'log-regression' benchmark uses Spark local executor with 4 (out of 4) threads. [2024-11-22T23:14:53.201Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-11-22T23:14:53.201Z] GC before operation: completed in 51.799 ms, heap usage 42.737 MB -> 35.205 MB. [2024-11-22T23:14:59.812Z] ====== log-regression (apache-spark) [default], iteration 0 completed (6267.164 ms) ====== [2024-11-22T23:14:59.812Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-11-22T23:14:59.812Z] GC before operation: completed in 62.051 ms, heap usage 383.608 MB -> 113.531 MB. [2024-11-22T23:15:00.747Z] ====== log-regression (apache-spark) [default], iteration 1 completed (1688.279 ms) ====== [2024-11-22T23:15:00.747Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-11-22T23:15:00.747Z] GC before operation: completed in 56.215 ms, heap usage 388.603 MB -> 114.096 MB. [2024-11-22T23:15:02.670Z] ====== log-regression (apache-spark) [default], iteration 2 completed (1486.596 ms) ====== [2024-11-22T23:15:02.670Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-11-22T23:15:02.670Z] GC before operation: completed in 62.507 ms, heap usage 419.218 MB -> 114.665 MB. [2024-11-22T23:15:03.625Z] ====== log-regression (apache-spark) [default], iteration 3 completed (1389.108 ms) ====== [2024-11-22T23:15:03.625Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-11-22T23:15:03.625Z] GC before operation: completed in 64.007 ms, heap usage 438.677 MB -> 114.951 MB. [2024-11-22T23:15:06.406Z] ====== log-regression (apache-spark) [default], iteration 4 completed (1309.467 ms) ====== [2024-11-22T23:15:06.406Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-11-22T23:15:06.406Z] GC before operation: completed in 66.506 ms, heap usage 423.445 MB -> 115.105 MB. [2024-11-22T23:15:07.852Z] ====== log-regression (apache-spark) [default], iteration 5 completed (1281.313 ms) ====== [2024-11-22T23:15:07.852Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-11-22T23:15:07.852Z] GC before operation: completed in 69.861 ms, heap usage 412.102 MB -> 115.293 MB. [2024-11-22T23:15:07.852Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1239.258 ms) ====== [2024-11-22T23:15:07.852Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-11-22T23:15:07.852Z] GC before operation: completed in 80.758 ms, heap usage 412.623 MB -> 115.526 MB. [2024-11-22T23:15:09.794Z] ====== log-regression (apache-spark) [default], iteration 7 completed (1328.814 ms) ====== [2024-11-22T23:15:09.794Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-11-22T23:15:09.794Z] GC before operation: completed in 65.453 ms, heap usage 398.451 MB -> 115.718 MB. [2024-11-22T23:15:10.731Z] ====== log-regression (apache-spark) [default], iteration 8 completed (1208.362 ms) ====== [2024-11-22T23:15:10.731Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-11-22T23:15:10.731Z] GC before operation: completed in 61.361 ms, heap usage 399.695 MB -> 115.989 MB. [2024-11-22T23:15:11.665Z] ====== log-regression (apache-spark) [default], iteration 9 completed (1260.679 ms) ====== [2024-11-22T23:15:11.665Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-11-22T23:15:11.665Z] GC before operation: completed in 62.557 ms, heap usage 407.813 MB -> 116.073 MB. [2024-11-22T23:15:14.497Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1184.717 ms) ====== [2024-11-22T23:15:14.497Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-11-22T23:15:14.497Z] GC before operation: completed in 76.027 ms, heap usage 409.256 MB -> 116.349 MB. [2024-11-22T23:15:14.497Z] ====== log-regression (apache-spark) [default], iteration 11 completed (1286.037 ms) ====== [2024-11-22T23:15:14.497Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-11-22T23:15:14.497Z] GC before operation: completed in 70.200 ms, heap usage 359.681 MB -> 116.477 MB. [2024-11-22T23:15:15.604Z] ====== log-regression (apache-spark) [default], iteration 12 completed (1194.035 ms) ====== [2024-11-22T23:15:15.604Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-11-22T23:15:15.604Z] GC before operation: completed in 71.963 ms, heap usage 408.687 MB -> 116.774 MB. [2024-11-22T23:15:16.714Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1192.533 ms) ====== [2024-11-22T23:15:16.714Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-11-22T23:15:16.714Z] GC before operation: completed in 76.584 ms, heap usage 396.544 MB -> 116.927 MB. [2024-11-22T23:15:18.632Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1221.566 ms) ====== [2024-11-22T23:15:18.632Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-11-22T23:15:18.632Z] GC before operation: completed in 74.352 ms, heap usage 406.399 MB -> 117.137 MB. [2024-11-22T23:15:19.573Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1247.216 ms) ====== [2024-11-22T23:15:19.573Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-11-22T23:15:19.573Z] GC before operation: completed in 106.337 ms, heap usage 395.544 MB -> 117.344 MB. [2024-11-22T23:15:21.496Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1321.371 ms) ====== [2024-11-22T23:15:21.496Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-11-22T23:15:21.496Z] GC before operation: completed in 84.332 ms, heap usage 501.175 MB -> 117.783 MB. [2024-11-22T23:15:22.528Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1198.653 ms) ====== [2024-11-22T23:15:22.528Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-11-22T23:15:22.528Z] GC before operation: completed in 67.751 ms, heap usage 314.513 MB -> 117.498 MB. [2024-11-22T23:15:23.463Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1168.546 ms) ====== [2024-11-22T23:15:23.463Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-11-22T23:15:23.463Z] GC before operation: completed in 68.940 ms, heap usage 323.160 MB -> 117.816 MB. [2024-11-22T23:15:25.395Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1273.498 ms) ====== [2024-11-22T23:15:25.395Z] ----------------------------------- [2024-11-22T23:15:25.395Z] renaissance-log-regression_0_PASSED [2024-11-22T23:15:25.395Z] ----------------------------------- [2024-11-22T23:15:25.395Z] [2024-11-22T23:15:25.395Z] TEST TEARDOWN: [2024-11-22T23:15:25.395Z] Nothing to be done for teardown. [2024-11-22T23:15:25.395Z] renaissance-log-regression_0 Finish Time: Fri Nov 22 23:15:24 2024 Epoch Time (ms): 1732317324689