[2025-07-10T15:34:37.300Z] Running test renaissance-als_0 ... [2025-07-10T15:34:37.300Z] =============================================== [2025-07-10T15:34:37.300Z] renaissance-als_0 Start Time: Thu Jul 10 15:34:37 2025 Epoch Time (ms): 1752161677088 [2025-07-10T15:34:37.300Z] variation: NoOptions [2025-07-10T15:34:37.300Z] JVM_OPTIONS: [2025-07-10T15:34:37.300Z] { \ [2025-07-10T15:34:37.300Z] echo ""; echo "TEST SETUP:"; \ [2025-07-10T15:34:37.300Z] echo "Nothing to be done for setup."; \ [2025-07-10T15:34:37.300Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17521616363552/renaissance-als_0"; \ [2025-07-10T15:34:37.300Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17521616363552/renaissance-als_0"; \ [2025-07-10T15:34:37.300Z] echo ""; echo "TESTING:"; \ [2025-07-10T15:34:37.300Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_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_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17521616363552/renaissance-als_0"/als.json" als; \ [2025-07-10T15:34:37.300Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17521616363552/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-07-10T15:34:37.300Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-07-10T15:34:37.300Z] echo "Nothing to be done for teardown."; \ [2025-07-10T15:34:37.300Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_linux/aqa-tests/TKG/../TKG/output_17521616363552/TestTargetResult"; [2025-07-10T15:34:37.300Z] [2025-07-10T15:34:37.300Z] TEST SETUP: [2025-07-10T15:34:37.300Z] Nothing to be done for setup. [2025-07-10T15:34:37.300Z] [2025-07-10T15:34:37.300Z] TESTING: [2025-07-10T15:34:46.123Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-07-10T15:34:51.978Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-07-10T15:34:51.978Z] GC before operation: completed in 67.704 ms, heap usage 51.500 MB -> 34.971 MB. [2025-07-10T15:35:25.291Z] ====== als (apache-spark) [default], iteration 0 completed (32016.270 ms) ====== [2025-07-10T15:35:25.291Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-07-10T15:35:25.291Z] GC before operation: completed in 199.225 ms, heap usage 264.717 MB -> 61.694 MB. [2025-07-10T15:35:44.543Z] ====== als (apache-spark) [default], iteration 1 completed (17463.181 ms) ====== [2025-07-10T15:35:44.543Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-07-10T15:35:44.543Z] GC before operation: completed in 118.609 ms, heap usage 168.694 MB -> 62.416 MB. [2025-07-10T15:36:00.299Z] ====== als (apache-spark) [default], iteration 2 completed (15527.472 ms) ====== [2025-07-10T15:36:00.299Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-07-10T15:36:00.299Z] GC before operation: completed in 122.976 ms, heap usage 220.133 MB -> 62.999 MB. [2025-07-10T15:36:16.076Z] ====== als (apache-spark) [default], iteration 3 completed (16376.908 ms) ====== [2025-07-10T15:36:16.076Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-07-10T15:36:16.076Z] GC before operation: completed in 127.671 ms, heap usage 274.654 MB -> 63.751 MB. [2025-07-10T15:36:31.858Z] ====== als (apache-spark) [default], iteration 4 completed (14974.872 ms) ====== [2025-07-10T15:36:31.858Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-07-10T15:36:31.858Z] GC before operation: completed in 113.602 ms, heap usage 120.629 MB -> 64.059 MB. [2025-07-10T15:36:47.620Z] ====== als (apache-spark) [default], iteration 5 completed (15007.321 ms) ====== [2025-07-10T15:36:47.620Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-07-10T15:36:47.620Z] GC before operation: completed in 131.114 ms, heap usage 489.092 MB -> 65.026 MB. [2025-07-10T15:37:03.381Z] ====== als (apache-spark) [default], iteration 6 completed (14509.934 ms) ====== [2025-07-10T15:37:03.381Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-07-10T15:37:03.381Z] GC before operation: completed in 207.412 ms, heap usage 264.939 MB -> 65.370 MB. [2025-07-10T15:37:16.409Z] ====== als (apache-spark) [default], iteration 7 completed (14479.928 ms) ====== [2025-07-10T15:37:16.409Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-07-10T15:37:16.409Z] GC before operation: completed in 126.972 ms, heap usage 107.346 MB -> 65.707 MB. [2025-07-10T15:37:32.336Z] ====== als (apache-spark) [default], iteration 8 completed (14646.850 ms) ====== [2025-07-10T15:37:32.336Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-07-10T15:37:32.336Z] GC before operation: completed in 147.489 ms, heap usage 210.269 MB -> 66.412 MB. [2025-07-10T15:37:48.158Z] ====== als (apache-spark) [default], iteration 9 completed (14529.413 ms) ====== [2025-07-10T15:37:48.158Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-07-10T15:37:48.158Z] GC before operation: completed in 126.406 ms, heap usage 229.099 MB -> 66.682 MB. [2025-07-10T15:38:03.936Z] ====== als (apache-spark) [default], iteration 10 completed (14826.167 ms) ====== [2025-07-10T15:38:03.936Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-07-10T15:38:03.936Z] GC before operation: completed in 133.152 ms, heap usage 432.174 MB -> 67.303 MB. [2025-07-10T15:38:16.961Z] ====== als (apache-spark) [default], iteration 11 completed (14842.077 ms) ====== [2025-07-10T15:38:16.961Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-07-10T15:38:16.961Z] GC before operation: completed in 125.137 ms, heap usage 192.573 MB -> 67.002 MB. [2025-07-10T15:38:32.733Z] ====== als (apache-spark) [default], iteration 12 completed (14469.760 ms) ====== [2025-07-10T15:38:32.733Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-07-10T15:38:32.733Z] GC before operation: completed in 134.836 ms, heap usage 279.381 MB -> 67.404 MB. [2025-07-10T15:38:48.507Z] ====== als (apache-spark) [default], iteration 13 completed (14228.949 ms) ====== [2025-07-10T15:38:48.507Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-07-10T15:38:48.507Z] GC before operation: completed in 128.718 ms, heap usage 385.969 MB -> 67.881 MB. [2025-07-10T15:39:01.583Z] ====== als (apache-spark) [default], iteration 14 completed (14326.753 ms) ====== [2025-07-10T15:39:01.583Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-07-10T15:39:01.583Z] GC before operation: completed in 138.699 ms, heap usage 278.246 MB -> 67.974 MB. [2025-07-10T15:39:17.463Z] ====== als (apache-spark) [default], iteration 15 completed (14342.003 ms) ====== [2025-07-10T15:39:17.463Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-07-10T15:39:17.463Z] GC before operation: completed in 188.432 ms, heap usage 116.826 MB -> 68.121 MB. [2025-07-10T15:39:30.512Z] ====== als (apache-spark) [default], iteration 16 completed (13986.545 ms) ====== [2025-07-10T15:39:30.512Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-07-10T15:39:30.512Z] GC before operation: completed in 126.318 ms, heap usage 216.568 MB -> 68.453 MB. [2025-07-10T15:39:46.275Z] ====== als (apache-spark) [default], iteration 17 completed (14081.703 ms) ====== [2025-07-10T15:39:46.275Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-07-10T15:39:46.275Z] GC before operation: completed in 127.337 ms, heap usage 401.858 MB -> 68.744 MB. [2025-07-10T15:39:59.336Z] ====== als (apache-spark) [default], iteration 18 completed (14019.964 ms) ====== [2025-07-10T15:39:59.336Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-07-10T15:39:59.336Z] GC before operation: completed in 133.242 ms, heap usage 388.688 MB -> 69.071 MB. [2025-07-10T15:40:15.130Z] ====== als (apache-spark) [default], iteration 19 completed (14192.082 ms) ====== [2025-07-10T15:40:15.130Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-07-10T15:40:15.130Z] GC before operation: completed in 124.197 ms, heap usage 265.778 MB -> 69.239 MB. [2025-07-10T15:40:30.951Z] ====== als (apache-spark) [default], iteration 20 completed (14427.513 ms) ====== [2025-07-10T15:40:30.951Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-07-10T15:40:30.951Z] GC before operation: completed in 123.614 ms, heap usage 479.941 MB -> 69.830 MB. [2025-07-10T15:40:44.098Z] ====== als (apache-spark) [default], iteration 21 completed (15214.048 ms) ====== [2025-07-10T15:40:44.098Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-07-10T15:40:44.427Z] GC before operation: completed in 123.741 ms, heap usage 260.864 MB -> 69.764 MB. [2025-07-10T15:41:00.236Z] ====== als (apache-spark) [default], iteration 22 completed (14054.322 ms) ====== [2025-07-10T15:41:00.236Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-07-10T15:41:00.236Z] GC before operation: completed in 142.539 ms, heap usage 193.571 MB -> 69.902 MB. [2025-07-10T15:41:13.254Z] ====== als (apache-spark) [default], iteration 23 completed (14160.915 ms) ====== [2025-07-10T15:41:13.254Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-07-10T15:41:13.583Z] GC before operation: completed in 135.313 ms, heap usage 449.143 MB -> 70.595 MB. [2025-07-10T15:41:29.367Z] ====== als (apache-spark) [default], iteration 24 completed (14466.027 ms) ====== [2025-07-10T15:41:29.367Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-07-10T15:41:29.367Z] GC before operation: completed in 136.511 ms, heap usage 229.847 MB -> 70.484 MB. [2025-07-10T15:41:45.128Z] ====== als (apache-spark) [default], iteration 25 completed (14038.942 ms) ====== [2025-07-10T15:41:45.128Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-07-10T15:41:45.128Z] GC before operation: completed in 122.243 ms, heap usage 118.851 MB -> 70.720 MB. [2025-07-10T15:41:58.166Z] ====== als (apache-spark) [default], iteration 26 completed (14538.110 ms) ====== [2025-07-10T15:41:58.166Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-07-10T15:41:58.166Z] GC before operation: completed in 133.296 ms, heap usage 118.684 MB -> 71.035 MB. [2025-07-10T15:42:14.003Z] ====== als (apache-spark) [default], iteration 27 completed (13855.029 ms) ====== [2025-07-10T15:42:14.003Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-07-10T15:42:14.003Z] GC before operation: completed in 136.152 ms, heap usage 506.023 MB -> 71.656 MB. [2025-07-10T15:42:27.137Z] ====== als (apache-spark) [default], iteration 28 completed (14336.282 ms) ====== [2025-07-10T15:42:27.137Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-07-10T15:42:27.137Z] GC before operation: completed in 125.276 ms, heap usage 253.648 MB -> 71.646 MB. [2025-07-10T15:42:42.897Z] ====== als (apache-spark) [default], iteration 29 completed (13884.169 ms) ====== [2025-07-10T15:42:42.897Z] ----------------------------------- [2025-07-10T15:42:42.897Z] renaissance-als_0_PASSED [2025-07-10T15:42:42.897Z] ----------------------------------- [2025-07-10T15:42:42.897Z] [2025-07-10T15:42:42.897Z] TEST TEARDOWN: [2025-07-10T15:42:42.897Z] Nothing to be done for teardown. [2025-07-10T15:42:42.897Z] renaissance-als_0 Finish Time: Thu Jul 10 15:42:41 2025 Epoch Time (ms): 1752162161086