[2024-11-16T09:18:32.372Z] Running test renaissance-log-regression_0 ... [2024-11-16T09:18:32.372Z] =============================================== [2024-11-16T09:18:32.372Z] renaissance-log-regression_0 Start Time: Sat Nov 16 09:18:31 2024 Epoch Time (ms): 1731748711938 [2024-11-16T09:18:32.372Z] variation: NoOptions [2024-11-16T09:18:32.372Z] JVM_OPTIONS: [2024-11-16T09:18:32.372Z] { \ [2024-11-16T09:18:32.372Z] echo ""; echo "TEST SETUP:"; \ [2024-11-16T09:18:32.372Z] echo "Nothing to be done for setup."; \ [2024-11-16T09:18:32.372Z] mkdir -p "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17317478759248/renaissance-log-regression_0"; \ [2024-11-16T09:18:32.372Z] cd "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17317478759248/renaissance-log-regression_0"; \ [2024-11-16T09:18:32.372Z] echo ""; echo "TESTING:"; \ [2024-11-16T09:18:32.372Z] "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/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_ppc64_aix/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17317478759248/renaissance-log-regression_0"/log-regression.json" log-regression; \ [2024-11-16T09:18:32.372Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-log-regression_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17317478759248/renaissance-log-regression_0"; else echo "-----------------------------------"; echo "renaissance-log-regression_0""_FAILED"; echo "-----------------------------------"; fi; \ [2024-11-16T09:18:32.372Z] echo ""; echo "TEST TEARDOWN:"; \ [2024-11-16T09:18:32.372Z] echo "Nothing to be done for teardown."; \ [2024-11-16T09:18:32.372Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/../TKG/output_17317478759248/TestTargetResult"; [2024-11-16T09:18:32.372Z] [2024-11-16T09:18:32.372Z] TEST SETUP: [2024-11-16T09:18:32.372Z] Nothing to be done for setup. [2024-11-16T09:18:32.372Z] [2024-11-16T09:18:32.372Z] TESTING: [2024-11-16T09:18:37.902Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2024-11-16T09:18:40.326Z] NOTE: 'log-regression' benchmark uses Spark local executor with 12 (out of 24) threads. [2024-11-16T09:18:42.752Z] WARNING: An illegal reflective access operation has occurred [2024-11-16T09:18:42.752Z] WARNING: Illegal reflective access by org.apache.spark.util.SizeEstimator$ (file:/home/jenkins/workspace/Test_openjdk11_hs_extended.perf_ppc64_aix/aqa-tests/TKG/output_17317478759248/renaissance-log-regression_0/harness-091832-4927080957126216190/apache-spark/lib/spark-core_2.13-3.2.0.jar) to field java.net.URI.scheme [2024-11-16T09:18:42.752Z] WARNING: Please consider reporting this to the maintainers of org.apache.spark.util.SizeEstimator$ [2024-11-16T09:18:42.752Z] WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations [2024-11-16T09:18:42.752Z] WARNING: All illegal access operations will be denied in a future release [2024-11-16T09:18:46.127Z] ====== log-regression (apache-spark) [default], iteration 0 started ====== [2024-11-16T09:18:46.127Z] GC before operation: completed in 67.728 ms, heap usage 61.326 MB -> 35.243 MB. [2024-11-16T09:18:57.740Z] ====== log-regression (apache-spark) [default], iteration 0 completed (11403.569 ms) ====== [2024-11-16T09:18:57.740Z] ====== log-regression (apache-spark) [default], iteration 1 started ====== [2024-11-16T09:18:57.740Z] GC before operation: completed in 123.718 ms, heap usage 457.056 MB -> 125.986 MB. [2024-11-16T09:19:01.104Z] ====== log-regression (apache-spark) [default], iteration 1 completed (2895.450 ms) ====== [2024-11-16T09:19:01.104Z] ====== log-regression (apache-spark) [default], iteration 2 started ====== [2024-11-16T09:19:01.104Z] GC before operation: completed in 120.783 ms, heap usage 603.683 MB -> 126.890 MB. [2024-11-16T09:19:03.525Z] ====== log-regression (apache-spark) [default], iteration 2 completed (2511.754 ms) ====== [2024-11-16T09:19:03.525Z] ====== log-regression (apache-spark) [default], iteration 3 started ====== [2024-11-16T09:19:03.525Z] GC before operation: completed in 134.729 ms, heap usage 597.098 MB -> 127.373 MB. [2024-11-16T09:19:05.949Z] ====== log-regression (apache-spark) [default], iteration 3 completed (2204.356 ms) ====== [2024-11-16T09:19:05.949Z] ====== log-regression (apache-spark) [default], iteration 4 started ====== [2024-11-16T09:19:05.949Z] GC before operation: completed in 117.202 ms, heap usage 398.726 MB -> 127.271 MB. [2024-11-16T09:19:08.380Z] ====== log-regression (apache-spark) [default], iteration 4 completed (2212.005 ms) ====== [2024-11-16T09:19:08.380Z] ====== log-regression (apache-spark) [default], iteration 5 started ====== [2024-11-16T09:19:08.380Z] GC before operation: completed in 104.005 ms, heap usage 413.105 MB -> 127.590 MB. [2024-11-16T09:19:10.801Z] ====== log-regression (apache-spark) [default], iteration 5 completed (2300.888 ms) ====== [2024-11-16T09:19:10.801Z] ====== log-regression (apache-spark) [default], iteration 6 started ====== [2024-11-16T09:19:10.801Z] GC before operation: completed in 113.269 ms, heap usage 415.730 MB -> 127.965 MB. [2024-11-16T09:19:12.383Z] ====== log-regression (apache-spark) [default], iteration 6 completed (1964.729 ms) ====== [2024-11-16T09:19:12.383Z] ====== log-regression (apache-spark) [default], iteration 7 started ====== [2024-11-16T09:19:13.140Z] GC before operation: completed in 125.739 ms, heap usage 638.527 MB -> 128.991 MB. [2024-11-16T09:19:14.698Z] ====== log-regression (apache-spark) [default], iteration 7 completed (2173.888 ms) ====== [2024-11-16T09:19:14.698Z] ====== log-regression (apache-spark) [default], iteration 8 started ====== [2024-11-16T09:19:15.459Z] GC before operation: completed in 150.566 ms, heap usage 321.223 MB -> 128.512 MB. [2024-11-16T09:19:17.021Z] ====== log-regression (apache-spark) [default], iteration 8 completed (2039.996 ms) ====== [2024-11-16T09:19:17.021Z] ====== log-regression (apache-spark) [default], iteration 9 started ====== [2024-11-16T09:19:17.021Z] GC before operation: completed in 142.853 ms, heap usage 227.128 MB -> 128.640 MB. [2024-11-16T09:19:19.448Z] ====== log-regression (apache-spark) [default], iteration 9 completed (2148.873 ms) ====== [2024-11-16T09:19:19.448Z] ====== log-regression (apache-spark) [default], iteration 10 started ====== [2024-11-16T09:19:19.448Z] GC before operation: completed in 139.531 ms, heap usage 319.681 MB -> 129.191 MB. [2024-11-16T09:19:21.870Z] ====== log-regression (apache-spark) [default], iteration 10 completed (1950.406 ms) ====== [2024-11-16T09:19:21.870Z] ====== log-regression (apache-spark) [default], iteration 11 started ====== [2024-11-16T09:19:21.870Z] GC before operation: completed in 206.958 ms, heap usage 626.270 MB -> 130.421 MB. [2024-11-16T09:19:24.293Z] ====== log-regression (apache-spark) [default], iteration 11 completed (2106.163 ms) ====== [2024-11-16T09:19:24.293Z] ====== log-regression (apache-spark) [default], iteration 12 started ====== [2024-11-16T09:19:24.293Z] GC before operation: completed in 171.973 ms, heap usage 402.732 MB -> 130.089 MB. [2024-11-16T09:19:25.850Z] ====== log-regression (apache-spark) [default], iteration 12 completed (2133.150 ms) ====== [2024-11-16T09:19:25.850Z] ====== log-regression (apache-spark) [default], iteration 13 started ====== [2024-11-16T09:19:26.603Z] GC before operation: completed in 195.167 ms, heap usage 319.597 MB -> 130.187 MB. [2024-11-16T09:19:28.162Z] ====== log-regression (apache-spark) [default], iteration 13 completed (1948.776 ms) ====== [2024-11-16T09:19:28.162Z] ====== log-regression (apache-spark) [default], iteration 14 started ====== [2024-11-16T09:19:28.162Z] GC before operation: completed in 175.336 ms, heap usage 423.950 MB -> 130.944 MB. [2024-11-16T09:19:30.586Z] ====== log-regression (apache-spark) [default], iteration 14 completed (1906.044 ms) ====== [2024-11-16T09:19:30.586Z] ====== log-regression (apache-spark) [default], iteration 15 started ====== [2024-11-16T09:19:30.586Z] GC before operation: completed in 155.423 ms, heap usage 276.102 MB -> 130.792 MB. [2024-11-16T09:19:32.145Z] ====== log-regression (apache-spark) [default], iteration 15 completed (1905.266 ms) ====== [2024-11-16T09:19:32.145Z] ====== log-regression (apache-spark) [default], iteration 16 started ====== [2024-11-16T09:19:32.900Z] GC before operation: completed in 178.898 ms, heap usage 470.091 MB -> 131.856 MB. [2024-11-16T09:19:34.680Z] ====== log-regression (apache-spark) [default], iteration 16 completed (1943.683 ms) ====== [2024-11-16T09:19:34.680Z] ====== log-regression (apache-spark) [default], iteration 17 started ====== [2024-11-16T09:19:34.680Z] GC before operation: completed in 169.431 ms, heap usage 535.856 MB -> 132.253 MB. [2024-11-16T09:19:36.744Z] ====== log-regression (apache-spark) [default], iteration 17 completed (1939.122 ms) ====== [2024-11-16T09:19:36.744Z] ====== log-regression (apache-spark) [default], iteration 18 started ====== [2024-11-16T09:19:36.744Z] GC before operation: completed in 169.331 ms, heap usage 303.335 MB -> 131.938 MB. [2024-11-16T09:19:38.507Z] ====== log-regression (apache-spark) [default], iteration 18 completed (1941.568 ms) ====== [2024-11-16T09:19:38.507Z] ====== log-regression (apache-spark) [default], iteration 19 started ====== [2024-11-16T09:19:39.260Z] GC before operation: completed in 161.429 ms, heap usage 266.726 MB -> 132.288 MB. [2024-11-16T09:19:40.822Z] ====== log-regression (apache-spark) [default], iteration 19 completed (1881.454 ms) ====== [2024-11-16T09:19:41.577Z] ----------------------------------- [2024-11-16T09:19:41.577Z] renaissance-log-regression_0_PASSED [2024-11-16T09:19:41.577Z] ----------------------------------- [2024-11-16T09:19:41.577Z] [2024-11-16T09:19:41.577Z] TEST TEARDOWN: [2024-11-16T09:19:41.577Z] Nothing to be done for teardown. [2024-11-16T09:19:41.577Z] renaissance-log-regression_0 Finish Time: Sat Nov 16 09:19:40 2024 Epoch Time (ms): 1731748780917