[2025-08-27T19:56:15.039Z] Running test renaissance-als_0 ... [2025-08-27T19:56:15.039Z] =============================================== [2025-08-27T19:56:15.039Z] renaissance-als_0 Start Time: Wed Aug 27 20:56:14 2025 Epoch Time (ms): 1756324574863 [2025-08-27T19:56:15.039Z] variation: NoOptions [2025-08-27T19:56:15.039Z] JVM_OPTIONS: [2025-08-27T19:56:15.039Z] { \ [2025-08-27T19:56:15.039Z] echo ""; echo "TEST SETUP:"; \ [2025-08-27T19:56:15.039Z] echo "Nothing to be done for setup."; \ [2025-08-27T19:56:15.039Z] mkdir -p "/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17563245742158/renaissance-als_0"; \ [2025-08-27T19:56:15.039Z] cd "/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17563245742158/renaissance-als_0"; \ [2025-08-27T19:56:15.039Z] echo ""; echo "TESTING:"; \ [2025-08-27T19:56:15.039Z] "/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/jdkbinary/j2sdk-image/Contents/Home/bin/..//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 "/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17563245742158/renaissance-als_0"/als.json" als; \ [2025-08-27T19:56:15.039Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/..; rm -f -r "/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17563245742158/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-08-27T19:56:15.039Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-08-27T19:56:15.039Z] echo "Nothing to be done for teardown."; \ [2025-08-27T19:56:15.039Z] } 2>&1 | tee -a "/Users/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_mac/aqa-tests/TKG/../TKG/output_17563245742158/TestTargetResult"; [2025-08-27T19:56:15.039Z] [2025-08-27T19:56:15.039Z] TEST SETUP: [2025-08-27T19:56:15.039Z] Nothing to be done for setup. [2025-08-27T19:56:15.039Z] [2025-08-27T19:56:15.039Z] TESTING: [2025-08-27T19:56:21.184Z] NOTE: 'als' benchmark uses Spark local executor with 8 (out of 8) threads. [2025-08-27T19:56:25.186Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-08-27T19:56:25.186Z] GC before operation: completed in 40.088 ms, heap usage 439.966 MB -> 35.684 MB. [2025-08-27T19:59:19.578Z] ====== als (apache-spark) [default], iteration 0 completed (171919.383 ms) ====== [2025-08-27T19:59:19.578Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-08-27T19:59:19.578Z] GC before operation: completed in 112.652 ms, heap usage 1.268 GB -> 64.623 MB. [2025-08-27T20:02:13.917Z] ====== als (apache-spark) [default], iteration 1 completed (161030.540 ms) ====== [2025-08-27T20:02:13.917Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-08-27T20:02:13.917Z] GC before operation: completed in 85.389 ms, heap usage 563.755 MB -> 65.377 MB. [2025-08-27T20:04:53.072Z] ====== als (apache-spark) [default], iteration 2 completed (160830.791 ms) ====== [2025-08-27T20:04:53.072Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-08-27T20:04:53.072Z] GC before operation: completed in 80.601 ms, heap usage 1.038 GB -> 66.078 MB. [2025-08-27T20:07:32.286Z] ====== als (apache-spark) [default], iteration 3 completed (160294.647 ms) ====== [2025-08-27T20:07:32.286Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-08-27T20:07:32.286Z] GC before operation: completed in 77.732 ms, heap usage 514.993 MB -> 66.585 MB. [2025-08-27T20:10:11.445Z] ====== als (apache-spark) [default], iteration 4 completed (166645.936 ms) ====== [2025-08-27T20:10:11.445Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-08-27T20:10:11.445Z] GC before operation: completed in 75.489 ms, heap usage 465.774 MB -> 67.103 MB. [2025-08-27T20:12:50.674Z] ====== als (apache-spark) [default], iteration 5 completed (162278.310 ms) ====== [2025-08-27T20:12:50.674Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-08-27T20:12:50.674Z] GC before operation: completed in 80.521 ms, heap usage 1.127 GB -> 67.774 MB. [2025-08-27T20:15:44.986Z] ====== als (apache-spark) [default], iteration 6 completed (166464.292 ms) ====== [2025-08-27T20:15:44.986Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-08-27T20:15:44.987Z] GC before operation: completed in 71.457 ms, heap usage 770.174 MB -> 68.185 MB. [2025-08-27T20:18:24.230Z] ====== als (apache-spark) [default], iteration 7 completed (161793.171 ms) ====== [2025-08-27T20:18:24.230Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-08-27T20:18:24.230Z] GC before operation: completed in 86.413 ms, heap usage 1.749 GB -> 68.937 MB. [2025-08-27T20:21:03.408Z] ====== als (apache-spark) [default], iteration 8 completed (159154.628 ms) ====== [2025-08-27T20:21:03.408Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-08-27T20:21:03.408Z] GC before operation: completed in 79.662 ms, heap usage 1.069 GB -> 69.399 MB. [2025-08-27T20:23:42.598Z] ====== als (apache-spark) [default], iteration 9 completed (158404.280 ms) ====== [2025-08-27T20:23:42.598Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-08-27T20:23:42.598Z] GC before operation: completed in 79.830 ms, heap usage 977.428 MB -> 70.054 MB. [2025-08-27T20:26:21.844Z] ====== als (apache-spark) [default], iteration 10 completed (159123.124 ms) ====== [2025-08-27T20:26:21.844Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-08-27T20:26:21.844Z] GC before operation: completed in 73.621 ms, heap usage 1.175 GB -> 70.217 MB. [2025-08-27T20:29:16.806Z] ====== als (apache-spark) [default], iteration 11 completed (172428.968 ms) ====== [2025-08-27T20:29:16.806Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-08-27T20:29:16.806Z] GC before operation: completed in 73.401 ms, heap usage 643.648 MB -> 70.291 MB. [2025-08-27T20:31:55.996Z] ====== als (apache-spark) [default], iteration 12 completed (157796.098 ms) ====== [2025-08-27T20:31:55.996Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-08-27T20:31:55.996Z] GC before operation: completed in 85.702 ms, heap usage 436.538 MB -> 70.464 MB. [2025-08-27T20:34:35.208Z] ====== als (apache-spark) [default], iteration 13 completed (157577.981 ms) ====== [2025-08-27T20:34:35.208Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-08-27T20:34:35.208Z] GC before operation: completed in 73.391 ms, heap usage 679.550 MB -> 70.859 MB. [2025-08-27T20:37:14.885Z] ====== als (apache-spark) [default], iteration 14 completed (158136.001 ms) ====== [2025-08-27T20:37:14.885Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-08-27T20:37:14.885Z] GC before operation: completed in 81.861 ms, heap usage 831.560 MB -> 71.100 MB. [2025-08-27T20:39:54.131Z] ====== als (apache-spark) [default], iteration 15 completed (157539.549 ms) ====== [2025-08-27T20:39:54.131Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-08-27T20:39:54.131Z] GC before operation: completed in 78.704 ms, heap usage 533.690 MB -> 71.349 MB. [2025-08-27T20:42:33.358Z] ====== als (apache-spark) [default], iteration 16 completed (157103.497 ms) ====== [2025-08-27T20:42:33.358Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-08-27T20:42:33.358Z] GC before operation: completed in 88.353 ms, heap usage 998.173 MB -> 71.687 MB. [2025-08-27T20:44:57.423Z] ====== als (apache-spark) [default], iteration 17 completed (157845.108 ms) ====== [2025-08-27T20:44:57.423Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-08-27T20:44:57.423Z] GC before operation: completed in 84.819 ms, heap usage 851.371 MB -> 71.928 MB. [2025-08-27T20:47:36.645Z] ====== als (apache-spark) [default], iteration 18 completed (156651.718 ms) ====== [2025-08-27T20:47:36.645Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-08-27T20:47:36.645Z] GC before operation: completed in 81.828 ms, heap usage 376.456 MB -> 72.144 MB. [2025-08-27T20:50:15.903Z] ====== als (apache-spark) [default], iteration 19 completed (156751.440 ms) ====== [2025-08-27T20:50:15.903Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-08-27T20:50:15.903Z] GC before operation: completed in 80.772 ms, heap usage 755.445 MB -> 72.506 MB. [2025-08-27T20:52:55.155Z] ====== als (apache-spark) [default], iteration 20 completed (157944.591 ms) ====== [2025-08-27T20:52:55.155Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-08-27T20:52:55.155Z] GC before operation: completed in 80.639 ms, heap usage 1.415 GB -> 72.809 MB. [2025-08-27T20:55:34.316Z] ====== als (apache-spark) [default], iteration 21 completed (157364.847 ms) ====== [2025-08-27T20:55:34.316Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-08-27T20:55:34.316Z] GC before operation: completed in 87.027 ms, heap usage 837.015 MB -> 73.078 MB. [2025-08-27T20:58:13.531Z] ====== als (apache-spark) [default], iteration 22 completed (157799.667 ms) ====== [2025-08-27T20:58:13.531Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-08-27T20:58:13.531Z] GC before operation: completed in 82.482 ms, heap usage 1.025 GB -> 72.817 MB. [2025-08-27T21:00:52.690Z] ====== als (apache-spark) [default], iteration 23 completed (160627.084 ms) ====== [2025-08-27T21:00:52.690Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-08-27T21:00:52.690Z] GC before operation: completed in 91.053 ms, heap usage 678.667 MB -> 70.403 MB. [2025-08-27T21:03:31.899Z] ====== als (apache-spark) [default], iteration 24 completed (157524.370 ms) ====== [2025-08-27T21:03:31.899Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-08-27T21:03:31.899Z] GC before operation: completed in 89.711 ms, heap usage 638.951 MB -> 70.008 MB. [2025-08-27T21:06:11.097Z] ====== als (apache-spark) [default], iteration 25 completed (156993.856 ms) ====== [2025-08-27T21:06:11.097Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-08-27T21:06:11.097Z] GC before operation: completed in 81.276 ms, heap usage 1.480 GB -> 70.486 MB. [2025-08-27T21:08:50.285Z] ====== als (apache-spark) [default], iteration 26 completed (156753.647 ms) ====== [2025-08-27T21:08:50.285Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-08-27T21:08:50.285Z] GC before operation: completed in 78.085 ms, heap usage 1.184 GB -> 70.300 MB. [2025-08-27T21:11:29.476Z] ====== als (apache-spark) [default], iteration 27 completed (157525.031 ms) ====== [2025-08-27T21:11:29.476Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-08-27T21:11:29.476Z] GC before operation: completed in 89.425 ms, heap usage 1.526 GB -> 70.569 MB. [2025-08-27T21:14:08.746Z] ====== als (apache-spark) [default], iteration 28 completed (159416.401 ms) ====== [2025-08-27T21:14:08.746Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-08-27T21:14:08.746Z] GC before operation: completed in 69.512 ms, heap usage 887.724 MB -> 70.670 MB. [2025-08-27T21:16:48.036Z] ====== als (apache-spark) [default], iteration 29 completed (159210.102 ms) ====== [2025-08-27T21:16:48.036Z] ----------------------------------- [2025-08-27T21:16:48.036Z] renaissance-als_0_PASSED [2025-08-27T21:16:48.036Z] ----------------------------------- [2025-08-27T21:16:48.036Z] [2025-08-27T21:16:48.036Z] TEST TEARDOWN: [2025-08-27T21:16:48.036Z] Nothing to be done for teardown. [2025-08-27T21:16:48.036Z] renaissance-als_0 Finish Time: Wed Aug 27 22:16:34 2025 Epoch Time (ms): 1756329394241