[2025-03-27T05:01:36.430Z] Running test renaissance-als_0 ... [2025-03-27T05:01:36.430Z] =============================================== [2025-03-27T05:01:36.430Z] renaissance-als_0 Start Time: Thu Mar 27 05:01:35 2025 Epoch Time (ms): 1743051695970 [2025-03-27T05:01:36.430Z] variation: NoOptions [2025-03-27T05:01:36.430Z] JVM_OPTIONS: [2025-03-27T05:01:36.430Z] { \ [2025-03-27T05:01:36.430Z] echo ""; echo "TEST SETUP:"; \ [2025-03-27T05:01:36.430Z] echo "Nothing to be done for setup."; \ [2025-03-27T05:01:36.430Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17430516197944/renaissance-als_0"; \ [2025-03-27T05:01:36.430Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17430516197944/renaissance-als_0"; \ [2025-03-27T05:01:36.430Z] echo ""; echo "TESTING:"; \ [2025-03-27T05:01:36.430Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17430516197944/renaissance-als_0"/als.json" als; \ [2025-03-27T05:01:36.431Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17430516197944/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-03-27T05:01:36.431Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-03-27T05:01:36.431Z] echo "Nothing to be done for teardown."; \ [2025-03-27T05:01:36.431Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17430516197944/TestTargetResult"; [2025-03-27T05:01:36.431Z] [2025-03-27T05:01:36.431Z] TEST SETUP: [2025-03-27T05:01:36.431Z] Nothing to be done for setup. [2025-03-27T05:01:36.431Z] [2025-03-27T05:01:36.431Z] TESTING: [2025-03-27T05:01:45.336Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-03-27T05:01:54.467Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-03-27T05:01:54.467Z] GC before operation: completed in 144.126 ms, heap usage 81.914 MB -> 34.888 MB. [2025-03-27T05:03:00.393Z] ====== als (apache-spark) [default], iteration 0 completed (57044.316 ms) ====== [2025-03-27T05:03:00.393Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-03-27T05:03:00.393Z] GC before operation: completed in 412.095 ms, heap usage 129.232 MB -> 61.571 MB. [2025-03-27T05:03:38.293Z] ====== als (apache-spark) [default], iteration 1 completed (40858.521 ms) ====== [2025-03-27T05:03:38.293Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-03-27T05:03:38.293Z] GC before operation: completed in 185.167 ms, heap usage 221.613 MB -> 62.536 MB. [2025-03-27T05:04:15.832Z] ====== als (apache-spark) [default], iteration 2 completed (39092.344 ms) ====== [2025-03-27T05:04:15.832Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-03-27T05:04:15.832Z] GC before operation: completed in 251.778 ms, heap usage 137.676 MB -> 63.227 MB. [2025-03-27T05:04:51.953Z] ====== als (apache-spark) [default], iteration 3 completed (31214.768 ms) ====== [2025-03-27T05:04:51.953Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-03-27T05:04:51.953Z] GC before operation: completed in 169.877 ms, heap usage 113.497 MB -> 63.642 MB. [2025-03-27T05:05:27.158Z] ====== als (apache-spark) [default], iteration 4 completed (34590.764 ms) ====== [2025-03-27T05:05:27.158Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-03-27T05:05:27.158Z] GC before operation: completed in 192.731 ms, heap usage 146.085 MB -> 64.235 MB. [2025-03-27T05:05:44.849Z] ====== als (apache-spark) [default], iteration 5 completed (20506.098 ms) ====== [2025-03-27T05:05:44.849Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-03-27T05:05:44.849Z] GC before operation: completed in 152.101 ms, heap usage 275.696 MB -> 64.964 MB. [2025-03-27T05:06:13.959Z] ====== als (apache-spark) [default], iteration 6 completed (27313.179 ms) ====== [2025-03-27T05:06:13.959Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-03-27T05:06:13.959Z] GC before operation: completed in 245.398 ms, heap usage 465.023 MB -> 65.715 MB. [2025-03-27T05:06:44.336Z] ====== als (apache-spark) [default], iteration 7 completed (29403.378 ms) ====== [2025-03-27T05:06:44.336Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-03-27T05:06:44.336Z] GC before operation: completed in 141.075 ms, heap usage 181.405 MB -> 66.055 MB. [2025-03-27T05:07:18.650Z] ====== als (apache-spark) [default], iteration 8 completed (33838.580 ms) ====== [2025-03-27T05:07:18.650Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-03-27T05:07:18.650Z] GC before operation: completed in 252.435 ms, heap usage 368.061 MB -> 66.555 MB. [2025-03-27T05:07:53.353Z] ====== als (apache-spark) [default], iteration 9 completed (33825.554 ms) ====== [2025-03-27T05:07:53.353Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-03-27T05:07:53.353Z] GC before operation: completed in 899.495 ms, heap usage 474.129 MB -> 66.971 MB. [2025-03-27T05:08:29.027Z] ====== als (apache-spark) [default], iteration 10 completed (33786.657 ms) ====== [2025-03-27T05:08:29.027Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-03-27T05:08:29.027Z] GC before operation: completed in 283.595 ms, heap usage 233.472 MB -> 66.925 MB. [2025-03-27T05:09:03.305Z] ====== als (apache-spark) [default], iteration 11 completed (32732.757 ms) ====== [2025-03-27T05:09:03.305Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-03-27T05:09:03.305Z] GC before operation: completed in 174.458 ms, heap usage 503.582 MB -> 67.491 MB. [2025-03-27T05:09:29.448Z] ====== als (apache-spark) [default], iteration 12 completed (29021.183 ms) ====== [2025-03-27T05:09:29.448Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-03-27T05:09:29.448Z] GC before operation: completed in 214.236 ms, heap usage 243.404 MB -> 67.420 MB. [2025-03-27T05:09:53.834Z] ====== als (apache-spark) [default], iteration 13 completed (21541.554 ms) ====== [2025-03-27T05:09:53.834Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-03-27T05:09:53.834Z] GC before operation: completed in 157.861 ms, heap usage 237.008 MB -> 67.929 MB. [2025-03-27T05:10:24.743Z] ====== als (apache-spark) [default], iteration 14 completed (32347.009 ms) ====== [2025-03-27T05:10:24.743Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-03-27T05:10:24.743Z] GC before operation: completed in 191.499 ms, heap usage 355.530 MB -> 68.191 MB. [2025-03-27T05:10:49.673Z] ====== als (apache-spark) [default], iteration 15 completed (24689.448 ms) ====== [2025-03-27T05:10:49.673Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-03-27T05:10:50.600Z] GC before operation: completed in 431.220 ms, heap usage 223.623 MB -> 68.320 MB. [2025-03-27T05:11:31.361Z] ====== als (apache-spark) [default], iteration 16 completed (37260.927 ms) ====== [2025-03-27T05:11:31.361Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-03-27T05:11:31.361Z] GC before operation: completed in 164.024 ms, heap usage 140.778 MB -> 68.501 MB. [2025-03-27T05:11:52.677Z] ====== als (apache-spark) [default], iteration 17 completed (22758.588 ms) ====== [2025-03-27T05:11:52.677Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-03-27T05:11:52.677Z] GC before operation: completed in 151.545 ms, heap usage 257.213 MB -> 68.773 MB. [2025-03-27T05:12:21.605Z] ====== als (apache-spark) [default], iteration 18 completed (26446.380 ms) ====== [2025-03-27T05:12:21.605Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-03-27T05:12:21.605Z] GC before operation: completed in 167.724 ms, heap usage 273.179 MB -> 69.033 MB. [2025-03-27T05:12:52.179Z] ====== als (apache-spark) [default], iteration 19 completed (32647.432 ms) ====== [2025-03-27T05:12:52.179Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-03-27T05:12:52.179Z] GC before operation: completed in 397.729 ms, heap usage 378.539 MB -> 69.447 MB. [2025-03-27T05:13:21.634Z] ====== als (apache-spark) [default], iteration 20 completed (28754.970 ms) ====== [2025-03-27T05:13:21.634Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-03-27T05:13:21.634Z] GC before operation: completed in 165.575 ms, heap usage 251.377 MB -> 69.872 MB. [2025-03-27T05:13:58.114Z] ====== als (apache-spark) [default], iteration 21 completed (34700.190 ms) ====== [2025-03-27T05:13:58.114Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-03-27T05:13:58.114Z] GC before operation: completed in 269.093 ms, heap usage 184.552 MB -> 69.855 MB. [2025-03-27T05:14:47.913Z] ====== als (apache-spark) [default], iteration 22 completed (47824.482 ms) ====== [2025-03-27T05:14:47.913Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-03-27T05:14:47.913Z] GC before operation: completed in 223.421 ms, heap usage 160.357 MB -> 70.027 MB. [2025-03-27T05:15:38.656Z] ====== als (apache-spark) [default], iteration 23 completed (51664.064 ms) ====== [2025-03-27T05:15:38.656Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-03-27T05:15:38.656Z] GC before operation: completed in 193.976 ms, heap usage 144.762 MB -> 70.208 MB. [2025-03-27T05:16:10.092Z] ====== als (apache-spark) [default], iteration 24 completed (29866.155 ms) ====== [2025-03-27T05:16:10.092Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-03-27T05:16:10.092Z] GC before operation: completed in 219.085 ms, heap usage 321.457 MB -> 69.788 MB. [2025-03-27T05:16:36.684Z] ====== als (apache-spark) [default], iteration 25 completed (28410.237 ms) ====== [2025-03-27T05:16:36.684Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-03-27T05:16:36.684Z] GC before operation: completed in 230.083 ms, heap usage 181.533 MB -> 68.043 MB. [2025-03-27T05:17:08.657Z] ====== als (apache-spark) [default], iteration 26 completed (31825.931 ms) ====== [2025-03-27T05:17:08.657Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-03-27T05:17:09.365Z] GC before operation: completed in 198.241 ms, heap usage 257.001 MB -> 67.956 MB. [2025-03-27T05:17:43.959Z] ====== als (apache-spark) [default], iteration 27 completed (31680.047 ms) ====== [2025-03-27T05:17:43.959Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-03-27T05:17:43.959Z] GC before operation: completed in 357.361 ms, heap usage 328.904 MB -> 68.359 MB. [2025-03-27T05:18:14.672Z] ====== als (apache-spark) [default], iteration 28 completed (32262.530 ms) ====== [2025-03-27T05:18:14.672Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-03-27T05:18:14.672Z] GC before operation: completed in 338.605 ms, heap usage 254.001 MB -> 68.073 MB. [2025-03-27T05:18:39.273Z] ====== als (apache-spark) [default], iteration 29 completed (21327.517 ms) ====== [2025-03-27T05:18:39.273Z] ----------------------------------- [2025-03-27T05:18:39.273Z] renaissance-als_0_PASSED [2025-03-27T05:18:39.273Z] ----------------------------------- [2025-03-27T05:18:39.273Z] [2025-03-27T05:18:39.273Z] TEST TEARDOWN: [2025-03-27T05:18:39.273Z] Nothing to be done for teardown. [2025-03-27T05:18:39.273Z] renaissance-als_0 Finish Time: Thu Mar 27 05:18:36 2025 Epoch Time (ms): 1743052716670