[2025-05-08T01:20:27.013Z] Running test renaissance-als_0 ... [2025-05-08T01:20:27.013Z] =============================================== [2025-05-08T01:20:27.013Z] renaissance-als_0 Start Time: Thu May 8 01:20:26 2025 Epoch Time (ms): 1746667226687 [2025-05-08T01:20:27.013Z] variation: NoOptions [2025-05-08T01:20:27.013Z] JVM_OPTIONS: [2025-05-08T01:20:27.013Z] { \ [2025-05-08T01:20:27.013Z] echo ""; echo "TEST SETUP:"; \ [2025-05-08T01:20:27.013Z] echo "Nothing to be done for setup."; \ [2025-05-08T01:20:27.013Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17466671573413/renaissance-als_0"; \ [2025-05-08T01:20:27.013Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17466671573413/renaissance-als_0"; \ [2025-05-08T01:20:27.013Z] echo ""; echo "TESTING:"; \ [2025-05-08T01:20:27.013Z] "/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_17466671573413/renaissance-als_0"/als.json" als; \ [2025-05-08T01:20:27.013Z] 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_17466671573413/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-08T01:20:27.013Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-08T01:20:27.013Z] echo "Nothing to be done for teardown."; \ [2025-05-08T01:20:27.013Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_s390x_linux/aqa-tests/TKG/../TKG/output_17466671573413/TestTargetResult"; [2025-05-08T01:20:27.013Z] [2025-05-08T01:20:27.013Z] TEST SETUP: [2025-05-08T01:20:27.013Z] Nothing to be done for setup. [2025-05-08T01:20:27.013Z] [2025-05-08T01:20:27.013Z] TESTING: [2025-05-08T01:20:34.803Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-05-08T01:20:42.490Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-05-08T01:20:42.490Z] GC before operation: completed in 291.921 ms, heap usage 65.051 MB -> 34.911 MB. [2025-05-08T01:21:39.439Z] ====== als (apache-spark) [default], iteration 0 completed (51476.102 ms) ====== [2025-05-08T01:21:39.439Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-05-08T01:21:40.172Z] GC before operation: completed in 550.372 ms, heap usage 342.564 MB -> 61.937 MB. [2025-05-08T01:22:20.853Z] ====== als (apache-spark) [default], iteration 1 completed (37211.797 ms) ====== [2025-05-08T01:22:20.853Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-05-08T01:22:20.853Z] GC before operation: completed in 204.597 ms, heap usage 428.703 MB -> 62.875 MB. [2025-05-08T01:22:51.060Z] ====== als (apache-spark) [default], iteration 2 completed (28865.982 ms) ====== [2025-05-08T01:22:51.060Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-05-08T01:22:51.060Z] GC before operation: completed in 233.615 ms, heap usage 580.649 MB -> 63.812 MB. [2025-05-08T01:23:21.013Z] ====== als (apache-spark) [default], iteration 3 completed (29199.330 ms) ====== [2025-05-08T01:23:21.013Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-05-08T01:23:21.013Z] GC before operation: completed in 116.087 ms, heap usage 118.889 MB -> 63.518 MB. [2025-05-08T01:23:42.562Z] ====== als (apache-spark) [default], iteration 4 completed (23142.313 ms) ====== [2025-05-08T01:23:42.562Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-05-08T01:23:42.562Z] GC before operation: completed in 192.505 ms, heap usage 255.642 MB -> 64.402 MB. [2025-05-08T01:24:17.889Z] ====== als (apache-spark) [default], iteration 5 completed (31655.444 ms) ====== [2025-05-08T01:24:17.889Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-05-08T01:24:17.889Z] GC before operation: completed in 201.475 ms, heap usage 250.686 MB -> 64.989 MB. [2025-05-08T01:24:52.346Z] ====== als (apache-spark) [default], iteration 6 completed (33197.142 ms) ====== [2025-05-08T01:24:52.346Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-05-08T01:24:52.346Z] GC before operation: completed in 194.686 ms, heap usage 489.204 MB -> 65.913 MB. [2025-05-08T01:25:14.208Z] ====== als (apache-spark) [default], iteration 7 completed (23136.350 ms) ====== [2025-05-08T01:25:14.208Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-05-08T01:25:14.208Z] GC before operation: completed in 173.963 ms, heap usage 255.646 MB -> 66.150 MB. [2025-05-08T01:25:44.457Z] ====== als (apache-spark) [default], iteration 8 completed (31560.486 ms) ====== [2025-05-08T01:25:44.457Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-05-08T01:25:44.457Z] GC before operation: completed in 292.122 ms, heap usage 245.264 MB -> 66.725 MB. [2025-05-08T01:26:33.420Z] ====== als (apache-spark) [default], iteration 9 completed (43206.841 ms) ====== [2025-05-08T01:26:33.420Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-05-08T01:26:33.420Z] GC before operation: completed in 675.785 ms, heap usage 155.496 MB -> 67.151 MB. [2025-05-08T01:27:09.322Z] ====== als (apache-spark) [default], iteration 10 completed (33460.623 ms) ====== [2025-05-08T01:27:09.322Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-05-08T01:27:09.322Z] GC before operation: completed in 905.331 ms, heap usage 347.159 MB -> 67.210 MB. [2025-05-08T01:27:34.877Z] ====== als (apache-spark) [default], iteration 11 completed (25232.941 ms) ====== [2025-05-08T01:27:34.877Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-05-08T01:27:34.877Z] GC before operation: completed in 283.913 ms, heap usage 413.209 MB -> 67.560 MB. [2025-05-08T01:28:11.606Z] ====== als (apache-spark) [default], iteration 12 completed (35235.374 ms) ====== [2025-05-08T01:28:11.606Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-05-08T01:28:11.606Z] GC before operation: completed in 192.042 ms, heap usage 136.291 MB -> 67.813 MB. [2025-05-08T01:28:37.174Z] ====== als (apache-spark) [default], iteration 13 completed (24979.246 ms) ====== [2025-05-08T01:28:37.174Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-05-08T01:28:37.174Z] GC before operation: completed in 392.126 ms, heap usage 226.621 MB -> 67.857 MB. [2025-05-08T01:29:13.903Z] ====== als (apache-spark) [default], iteration 14 completed (37289.789 ms) ====== [2025-05-08T01:29:13.903Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-05-08T01:29:13.903Z] GC before operation: completed in 493.608 ms, heap usage 499.112 MB -> 68.528 MB. [2025-05-08T01:29:39.045Z] ====== als (apache-spark) [default], iteration 15 completed (24590.695 ms) ====== [2025-05-08T01:29:39.045Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-05-08T01:29:39.045Z] GC before operation: completed in 480.293 ms, heap usage 140.163 MB -> 68.804 MB. [2025-05-08T01:30:03.701Z] ====== als (apache-spark) [default], iteration 16 completed (23281.655 ms) ====== [2025-05-08T01:30:03.701Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-05-08T01:30:03.701Z] GC before operation: completed in 265.135 ms, heap usage 513.881 MB -> 69.121 MB. [2025-05-08T01:30:35.717Z] ====== als (apache-spark) [default], iteration 17 completed (27979.382 ms) ====== [2025-05-08T01:30:35.717Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-05-08T01:30:35.717Z] GC before operation: completed in 389.321 ms, heap usage 109.448 MB -> 68.803 MB. [2025-05-08T01:31:05.000Z] ====== als (apache-spark) [default], iteration 18 completed (28326.638 ms) ====== [2025-05-08T01:31:05.000Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-05-08T01:31:05.000Z] GC before operation: completed in 407.662 ms, heap usage 253.738 MB -> 69.123 MB. [2025-05-08T01:31:35.267Z] ====== als (apache-spark) [default], iteration 19 completed (29717.473 ms) ====== [2025-05-08T01:31:35.267Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-05-08T01:31:35.267Z] GC before operation: completed in 388.413 ms, heap usage 126.123 MB -> 69.261 MB. [2025-05-08T01:32:06.856Z] ====== als (apache-spark) [default], iteration 20 completed (30366.690 ms) ====== [2025-05-08T01:32:06.857Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-05-08T01:32:06.857Z] GC before operation: completed in 250.597 ms, heap usage 356.531 MB -> 69.845 MB. [2025-05-08T01:32:29.002Z] ====== als (apache-spark) [default], iteration 21 completed (24361.822 ms) ====== [2025-05-08T01:32:29.002Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-05-08T01:32:29.002Z] GC before operation: completed in 125.565 ms, heap usage 503.121 MB -> 70.261 MB. [2025-05-08T01:33:00.909Z] ====== als (apache-spark) [default], iteration 22 completed (30706.502 ms) ====== [2025-05-08T01:33:00.909Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-05-08T01:33:00.909Z] GC before operation: completed in 282.363 ms, heap usage 234.873 MB -> 70.233 MB. [2025-05-08T01:33:37.575Z] ====== als (apache-spark) [default], iteration 23 completed (32387.570 ms) ====== [2025-05-08T01:33:37.575Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-05-08T01:33:37.575Z] GC before operation: completed in 831.296 ms, heap usage 410.565 MB -> 70.639 MB. [2025-05-08T01:34:15.012Z] ====== als (apache-spark) [default], iteration 24 completed (35386.618 ms) ====== [2025-05-08T01:34:15.012Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-05-08T01:34:15.012Z] GC before operation: completed in 444.626 ms, heap usage 149.573 MB -> 70.603 MB. [2025-05-08T01:34:45.074Z] ====== als (apache-spark) [default], iteration 25 completed (32162.491 ms) ====== [2025-05-08T01:34:45.074Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-05-08T01:34:45.074Z] GC before operation: completed in 255.311 ms, heap usage 117.224 MB -> 70.715 MB. [2025-05-08T01:35:20.350Z] ====== als (apache-spark) [default], iteration 26 completed (33168.633 ms) ====== [2025-05-08T01:35:20.350Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-05-08T01:35:20.350Z] GC before operation: completed in 517.601 ms, heap usage 498.931 MB -> 68.664 MB. [2025-05-08T01:35:56.639Z] ====== als (apache-spark) [default], iteration 27 completed (37899.993 ms) ====== [2025-05-08T01:35:56.639Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-05-08T01:35:56.639Z] GC before operation: completed in 372.343 ms, heap usage 515.143 MB -> 68.387 MB. [2025-05-08T01:36:17.765Z] ====== als (apache-spark) [default], iteration 28 completed (19736.712 ms) ====== [2025-05-08T01:36:17.765Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-05-08T01:36:17.765Z] GC before operation: completed in 308.616 ms, heap usage 297.199 MB -> 67.852 MB. [2025-05-08T01:36:46.999Z] ====== als (apache-spark) [default], iteration 29 completed (24948.165 ms) ====== [2025-05-08T01:36:46.999Z] ----------------------------------- [2025-05-08T01:36:46.999Z] renaissance-als_0_PASSED [2025-05-08T01:36:46.999Z] ----------------------------------- [2025-05-08T01:36:46.999Z] [2025-05-08T01:36:46.999Z] TEST TEARDOWN: [2025-05-08T01:36:46.999Z] Nothing to be done for teardown. [2025-05-08T01:36:46.999Z] renaissance-als_0 Finish Time: Thu May 8 01:36:44 2025 Epoch Time (ms): 1746668204848