[2025-11-12T23:29:21.532Z] Running test renaissance-als_0 ... [2025-11-12T23:29:21.532Z] =============================================== [2025-11-12T23:29:21.532Z] renaissance-als_0 Start Time: Wed Nov 12 23:29:21 2025 Epoch Time (ms): 1762990161470 [2025-11-12T23:29:21.872Z] variation: NoOptions [2025-11-12T23:29:21.872Z] JVM_OPTIONS: [2025-11-12T23:29:21.872Z] { \ [2025-11-12T23:29:21.872Z] echo ""; echo "TEST SETUP:"; \ [2025-11-12T23:29:21.872Z] echo "Nothing to be done for setup."; \ [2025-11-12T23:29:21.872Z] mkdir -p "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17629898278816\\renaissance-als_0"; \ [2025-11-12T23:29:21.872Z] cd "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17629898278816\\renaissance-als_0"; \ [2025-11-12T23:29:21.872Z] echo ""; echo "TESTING:"; \ [2025-11-12T23:29:21.872Z] "c:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/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 "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests///..//jvmtest\\perf\\renaissance\\renaissance.jar" --json ""C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17629898278816\\renaissance-als_0"\\als.json" als; \ [2025-11-12T23:29:21.872Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/; rm -f -r "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17629898278816\\renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-11-12T23:29:21.872Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-11-12T23:29:21.872Z] echo "Nothing to be done for teardown."; \ [2025-11-12T23:29:21.872Z] } 2>&1 | tee -a "C:/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_windows/aqa-tests/\\TKG\\output_17629898278816\\TestTargetResult"; [2025-11-12T23:29:21.872Z] [2025-11-12T23:29:21.872Z] TEST SETUP: [2025-11-12T23:29:21.872Z] Nothing to be done for setup. [2025-11-12T23:29:21.872Z] [2025-11-12T23:29:21.872Z] TESTING: [2025-11-12T23:29:40.894Z] NOTE: 'als' benchmark uses Spark local executor with 2 (out of 2) threads. [2025-11-12T23:29:44.948Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-11-12T23:29:44.948Z] GC before operation: completed in 72.740 ms, heap usage 50.490 MB -> 35.287 MB. [2025-11-12T23:30:44.452Z] ====== als (apache-spark) [default], iteration 0 completed (54958.957 ms) ====== [2025-11-12T23:30:44.452Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-11-12T23:30:44.452Z] GC before operation: completed in 270.816 ms, heap usage 422.080 MB -> 62.167 MB. [2025-11-12T23:31:12.158Z] ====== als (apache-spark) [default], iteration 1 completed (29394.969 ms) ====== [2025-11-12T23:31:12.158Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-11-12T23:31:12.158Z] GC before operation: completed in 134.631 ms, heap usage 119.364 MB -> 62.354 MB. [2025-11-12T23:31:45.841Z] ====== als (apache-spark) [default], iteration 2 completed (28718.978 ms) ====== [2025-11-12T23:31:45.841Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-11-12T23:31:45.841Z] GC before operation: completed in 131.538 ms, heap usage 105.000 MB -> 62.938 MB. [2025-11-12T23:32:14.492Z] ====== als (apache-spark) [default], iteration 3 completed (28774.692 ms) ====== [2025-11-12T23:32:14.492Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-11-12T23:32:14.492Z] GC before operation: completed in 150.418 ms, heap usage 176.397 MB -> 63.990 MB. [2025-11-12T23:32:43.016Z] ====== als (apache-spark) [default], iteration 4 completed (29528.873 ms) ====== [2025-11-12T23:32:43.016Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-11-12T23:32:43.016Z] GC before operation: completed in 147.597 ms, heap usage 250.704 MB -> 64.612 MB. [2025-11-12T23:33:11.133Z] ====== als (apache-spark) [default], iteration 5 completed (28799.772 ms) ====== [2025-11-12T23:33:11.133Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-11-12T23:33:11.133Z] GC before operation: completed in 253.179 ms, heap usage 247.042 MB -> 65.213 MB. [2025-11-12T23:33:39.278Z] ====== als (apache-spark) [default], iteration 6 completed (28753.757 ms) ====== [2025-11-12T23:33:39.278Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-11-12T23:33:39.278Z] GC before operation: completed in 142.843 ms, heap usage 77.473 MB -> 65.906 MB. [2025-11-12T23:34:12.815Z] ====== als (apache-spark) [default], iteration 7 completed (28530.711 ms) ====== [2025-11-12T23:34:12.815Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-11-12T23:34:12.815Z] GC before operation: completed in 136.956 ms, heap usage 382.123 MB -> 66.028 MB. [2025-11-12T23:34:36.831Z] ====== als (apache-spark) [default], iteration 8 completed (28158.940 ms) ====== [2025-11-12T23:34:36.831Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-11-12T23:34:36.831Z] GC before operation: completed in 170.416 ms, heap usage 299.810 MB -> 66.926 MB. [2025-11-12T23:35:05.761Z] ====== als (apache-spark) [default], iteration 9 completed (27928.324 ms) ====== [2025-11-12T23:35:05.761Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-11-12T23:35:05.761Z] GC before operation: completed in 135.708 ms, heap usage 256.561 MB -> 67.045 MB. [2025-11-12T23:35:33.993Z] ====== als (apache-spark) [default], iteration 10 completed (27681.872 ms) ====== [2025-11-12T23:35:33.993Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-11-12T23:35:33.993Z] GC before operation: completed in 132.724 ms, heap usage 161.679 MB -> 66.679 MB. [2025-11-12T23:36:02.169Z] ====== als (apache-spark) [default], iteration 11 completed (28610.686 ms) ====== [2025-11-12T23:36:02.169Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-11-12T23:36:02.169Z] GC before operation: completed in 144.276 ms, heap usage 184.014 MB -> 67.457 MB. [2025-11-12T23:36:36.779Z] ====== als (apache-spark) [default], iteration 12 completed (29103.744 ms) ====== [2025-11-12T23:36:36.779Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-11-12T23:36:36.779Z] GC before operation: completed in 128.886 ms, heap usage 185.499 MB -> 67.677 MB. [2025-11-12T23:37:04.471Z] ====== als (apache-spark) [default], iteration 13 completed (28015.794 ms) ====== [2025-11-12T23:37:04.471Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-11-12T23:37:04.471Z] GC before operation: completed in 138.525 ms, heap usage 112.552 MB -> 67.481 MB. [2025-11-12T23:37:32.767Z] ====== als (apache-spark) [default], iteration 14 completed (28461.617 ms) ====== [2025-11-12T23:37:32.767Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-11-12T23:37:32.767Z] GC before operation: completed in 130.145 ms, heap usage 453.142 MB -> 68.618 MB. [2025-11-12T23:38:01.281Z] ====== als (apache-spark) [default], iteration 15 completed (28159.826 ms) ====== [2025-11-12T23:38:01.281Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-11-12T23:38:01.281Z] GC before operation: completed in 135.033 ms, heap usage 352.563 MB -> 68.683 MB. [2025-11-12T23:38:29.418Z] ====== als (apache-spark) [default], iteration 16 completed (27608.267 ms) ====== [2025-11-12T23:38:29.418Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-11-12T23:38:29.418Z] GC before operation: completed in 126.098 ms, heap usage 350.728 MB -> 68.936 MB. [2025-11-12T23:38:54.506Z] ====== als (apache-spark) [default], iteration 17 completed (27643.589 ms) ====== [2025-11-12T23:38:54.506Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-11-12T23:38:54.506Z] GC before operation: completed in 152.486 ms, heap usage 503.962 MB -> 69.329 MB. [2025-11-12T23:39:22.760Z] ====== als (apache-spark) [default], iteration 18 completed (27491.897 ms) ====== [2025-11-12T23:39:22.760Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-11-12T23:39:22.760Z] GC before operation: completed in 137.392 ms, heap usage 397.432 MB -> 69.360 MB. [2025-11-12T23:39:51.075Z] ====== als (apache-spark) [default], iteration 19 completed (28015.365 ms) ====== [2025-11-12T23:39:51.075Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-11-12T23:39:51.075Z] GC before operation: completed in 141.696 ms, heap usage 139.255 MB -> 69.414 MB. [2025-11-12T23:40:19.982Z] ====== als (apache-spark) [default], iteration 20 completed (28704.453 ms) ====== [2025-11-12T23:40:19.982Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-11-12T23:40:19.982Z] GC before operation: completed in 536.085 ms, heap usage 302.053 MB -> 69.880 MB. [2025-11-12T23:40:54.280Z] ====== als (apache-spark) [default], iteration 21 completed (28665.340 ms) ====== [2025-11-12T23:40:54.280Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-11-12T23:40:54.280Z] GC before operation: completed in 137.249 ms, heap usage 102.961 MB -> 69.509 MB. [2025-11-12T23:41:17.788Z] ====== als (apache-spark) [default], iteration 22 completed (27829.968 ms) ====== [2025-11-12T23:41:17.788Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-11-12T23:41:17.788Z] GC before operation: completed in 131.513 ms, heap usage 238.794 MB -> 70.319 MB. [2025-11-12T23:41:47.110Z] ====== als (apache-spark) [default], iteration 23 completed (28427.706 ms) ====== [2025-11-12T23:41:47.110Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-11-12T23:41:47.110Z] GC before operation: completed in 231.661 ms, heap usage 498.782 MB -> 70.922 MB. [2025-11-12T23:42:15.697Z] ====== als (apache-spark) [default], iteration 24 completed (27699.446 ms) ====== [2025-11-12T23:42:15.697Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-11-12T23:42:15.697Z] GC before operation: completed in 172.259 ms, heap usage 444.412 MB -> 71.210 MB. [2025-11-12T23:42:44.210Z] ====== als (apache-spark) [default], iteration 25 completed (27963.175 ms) ====== [2025-11-12T23:42:44.210Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-11-12T23:42:44.210Z] GC before operation: completed in 135.029 ms, heap usage 474.394 MB -> 71.394 MB. [2025-11-12T23:43:13.851Z] ====== als (apache-spark) [default], iteration 26 completed (28067.845 ms) ====== [2025-11-12T23:43:13.851Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-11-12T23:43:13.851Z] GC before operation: completed in 672.626 ms, heap usage 115.006 MB -> 70.756 MB. [2025-11-12T23:43:42.146Z] ====== als (apache-spark) [default], iteration 27 completed (27792.050 ms) ====== [2025-11-12T23:43:42.146Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-11-12T23:43:42.146Z] GC before operation: completed in 132.156 ms, heap usage 226.602 MB -> 71.127 MB. [2025-11-12T23:44:11.186Z] ====== als (apache-spark) [default], iteration 28 completed (27999.236 ms) ====== [2025-11-12T23:44:11.186Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-11-12T23:44:11.186Z] GC before operation: completed in 140.365 ms, heap usage 470.257 MB -> 71.753 MB. [2025-11-12T23:44:38.641Z] ====== als (apache-spark) [default], iteration 29 completed (27515.872 ms) ====== [2025-11-12T23:44:38.641Z] [2025-11-12T23:44:36.762+0000] org.renaissance.core (org.renaissance.core.DirUtils lambda$createScratchDirectory$1) [2025-11-12T23:44:38.641Z] WARNING: Error deleting scratch directory C:\jenkins\workspace\Test_openjdk21_hs_extended.perf_x86-64_windows\aqa-tests\TKG\output_17629898278816\renaissance-als_0\harness-232922-17039633599959569999: harness-232922-17039633599959569999\apache-spark\als\hadoop\bin\hadoop.dll [2025-11-12T23:44:38.641Z] ----------------------------------- [2025-11-12T23:44:38.641Z] renaissance-als_0_PASSED [2025-11-12T23:44:38.641Z] ----------------------------------- [2025-11-12T23:44:38.642Z] [2025-11-12T23:44:38.642Z] TEST TEARDOWN: [2025-11-12T23:44:38.642Z] Nothing to be done for teardown. [2025-11-12T23:44:38.642Z] renaissance-als_0 Finish Time: Wed Nov 12 23:44:37 2025 Epoch Time (ms): 1762991077257