[2025-06-18T20:43:39.570Z] Running test renaissance-als_0 ... [2025-06-18T20:43:39.570Z] =============================================== [2025-06-18T20:43:39.570Z] renaissance-als_0 Start Time: Wed Jun 18 20:43:39 2025 Epoch Time (ms): 1750279419296 [2025-06-18T20:43:39.570Z] variation: NoOptions [2025-06-18T20:43:39.570Z] JVM_OPTIONS: [2025-06-18T20:43:39.570Z] { \ [2025-06-18T20:43:39.570Z] echo ""; echo "TEST SETUP:"; \ [2025-06-18T20:43:39.570Z] echo "Nothing to be done for setup."; \ [2025-06-18T20:43:39.570Z] mkdir -p "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17502794188704/renaissance-als_0"; \ [2025-06-18T20:43:39.570Z] cd "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17502794188704/renaissance-als_0"; \ [2025-06-18T20:43:39.570Z] echo ""; echo "TESTING:"; \ [2025-06-18T20:43:39.570Z] "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-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_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17502794188704/renaissance-als_0"/als.json" als; \ [2025-06-18T20:43:39.570Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17502794188704/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-06-18T20:43:39.570Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-06-18T20:43:39.570Z] echo "Nothing to be done for teardown."; \ [2025-06-18T20:43:39.570Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk21_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17502794188704/TestTargetResult"; [2025-06-18T20:43:39.570Z] [2025-06-18T20:43:39.570Z] TEST SETUP: [2025-06-18T20:43:39.570Z] Nothing to be done for setup. [2025-06-18T20:43:39.570Z] [2025-06-18T20:43:39.570Z] TESTING: [2025-06-18T20:44:15.395Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-06-18T20:44:36.720Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-06-18T20:44:36.720Z] GC before operation: completed in 253.222 ms, heap usage 51.500 MB -> 35.208 MB. [2025-06-18T20:46:43.960Z] ====== als (apache-spark) [default], iteration 0 completed (119435.592 ms) ====== [2025-06-18T20:46:43.960Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-06-18T20:46:43.960Z] GC before operation: completed in 848.821 ms, heap usage 446.018 MB -> 62.111 MB. [2025-06-18T20:47:52.507Z] ====== als (apache-spark) [default], iteration 1 completed (63022.584 ms) ====== [2025-06-18T20:47:52.507Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-06-18T20:47:52.507Z] GC before operation: completed in 534.172 ms, heap usage 218.872 MB -> 62.746 MB. [2025-06-18T20:48:42.125Z] ====== als (apache-spark) [default], iteration 2 completed (51943.570 ms) ====== [2025-06-18T20:48:42.125Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-06-18T20:48:42.125Z] GC before operation: completed in 550.696 ms, heap usage 478.006 MB -> 63.700 MB. [2025-06-18T20:49:40.257Z] ====== als (apache-spark) [default], iteration 3 completed (54480.101 ms) ====== [2025-06-18T20:49:40.257Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-06-18T20:49:40.257Z] GC before operation: completed in 607.506 ms, heap usage 293.500 MB -> 63.952 MB. [2025-06-18T20:50:29.318Z] ====== als (apache-spark) [default], iteration 4 completed (51639.154 ms) ====== [2025-06-18T20:50:29.318Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-06-18T20:50:29.318Z] GC before operation: completed in 608.891 ms, heap usage 237.885 MB -> 64.601 MB. [2025-06-18T20:51:27.183Z] ====== als (apache-spark) [default], iteration 5 completed (52316.094 ms) ====== [2025-06-18T20:51:27.183Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-06-18T20:51:27.183Z] GC before operation: completed in 539.079 ms, heap usage 232.886 MB -> 64.989 MB. [2025-06-18T20:52:25.126Z] ====== als (apache-spark) [default], iteration 6 completed (53884.605 ms) ====== [2025-06-18T20:52:25.126Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-06-18T20:52:25.126Z] GC before operation: completed in 697.487 ms, heap usage 361.056 MB -> 65.733 MB. [2025-06-18T20:53:23.166Z] ====== als (apache-spark) [default], iteration 7 completed (53884.255 ms) ====== [2025-06-18T20:53:23.166Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-06-18T20:53:23.166Z] GC before operation: completed in 840.940 ms, heap usage 454.231 MB -> 66.359 MB. [2025-06-18T20:54:14.955Z] ====== als (apache-spark) [default], iteration 8 completed (52466.978 ms) ====== [2025-06-18T20:54:14.955Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-06-18T20:54:14.955Z] GC before operation: completed in 607.770 ms, heap usage 332.820 MB -> 66.625 MB. [2025-06-18T20:55:05.767Z] ====== als (apache-spark) [default], iteration 9 completed (53264.000 ms) ====== [2025-06-18T20:55:05.767Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-06-18T20:55:06.602Z] GC before operation: completed in 709.979 ms, heap usage 241.321 MB -> 66.762 MB. [2025-06-18T20:56:06.239Z] ====== als (apache-spark) [default], iteration 10 completed (52206.339 ms) ====== [2025-06-18T20:56:06.239Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-06-18T20:56:06.239Z] GC before operation: completed in 766.783 ms, heap usage 331.944 MB -> 67.076 MB. [2025-06-18T20:56:55.913Z] ====== als (apache-spark) [default], iteration 11 completed (52054.232 ms) ====== [2025-06-18T20:56:55.913Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-06-18T20:56:55.913Z] GC before operation: completed in 910.320 ms, heap usage 480.368 MB -> 67.607 MB. [2025-06-18T20:57:53.799Z] ====== als (apache-spark) [default], iteration 12 completed (48671.640 ms) ====== [2025-06-18T20:57:53.799Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-06-18T20:57:53.799Z] GC before operation: completed in 737.381 ms, heap usage 125.173 MB -> 67.461 MB. [2025-06-18T20:58:43.892Z] ====== als (apache-spark) [default], iteration 13 completed (51746.411 ms) ====== [2025-06-18T20:58:43.892Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-06-18T20:58:43.892Z] GC before operation: completed in 788.397 ms, heap usage 115.825 MB -> 67.414 MB. [2025-06-18T20:59:33.691Z] ====== als (apache-spark) [default], iteration 14 completed (49673.345 ms) ====== [2025-06-18T20:59:33.691Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-06-18T20:59:33.691Z] GC before operation: completed in 733.808 ms, heap usage 234.108 MB -> 68.159 MB. [2025-06-18T21:00:31.673Z] ====== als (apache-spark) [default], iteration 15 completed (50687.128 ms) ====== [2025-06-18T21:00:31.673Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-06-18T21:00:31.673Z] GC before operation: completed in 653.175 ms, heap usage 160.000 MB -> 68.426 MB. [2025-06-18T21:01:14.022Z] ====== als (apache-spark) [default], iteration 16 completed (48458.370 ms) ====== [2025-06-18T21:01:14.022Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-06-18T21:01:14.836Z] GC before operation: completed in 683.544 ms, heap usage 133.154 MB -> 68.592 MB. [2025-06-18T21:02:04.985Z] ====== als (apache-spark) [default], iteration 17 completed (47412.982 ms) ====== [2025-06-18T21:02:04.985Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-06-18T21:02:04.985Z] GC before operation: completed in 660.903 ms, heap usage 277.256 MB -> 68.904 MB. [2025-06-18T21:02:54.585Z] ====== als (apache-spark) [default], iteration 18 completed (46826.741 ms) ====== [2025-06-18T21:02:54.585Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-06-18T21:02:54.585Z] GC before operation: completed in 736.700 ms, heap usage 254.737 MB -> 69.272 MB. [2025-06-18T21:03:44.219Z] ====== als (apache-spark) [default], iteration 19 completed (47522.119 ms) ====== [2025-06-18T21:03:44.219Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-06-18T21:03:44.219Z] GC before operation: completed in 648.782 ms, heap usage 122.056 MB -> 69.455 MB. [2025-06-18T21:04:33.908Z] ====== als (apache-spark) [default], iteration 20 completed (50600.234 ms) ====== [2025-06-18T21:04:33.908Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-06-18T21:04:35.643Z] GC before operation: completed in 1068.401 ms, heap usage 780.568 MB -> 70.449 MB. [2025-06-18T21:05:34.187Z] ====== als (apache-spark) [default], iteration 21 completed (52519.299 ms) ====== [2025-06-18T21:05:34.187Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-06-18T21:05:34.187Z] GC before operation: completed in 406.520 ms, heap usage 141.119 MB -> 70.151 MB. [2025-06-18T21:06:32.209Z] ====== als (apache-spark) [default], iteration 22 completed (54693.518 ms) ====== [2025-06-18T21:06:32.209Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-06-18T21:06:32.209Z] GC before operation: completed in 504.101 ms, heap usage 562.075 MB -> 69.992 MB. [2025-06-18T21:07:21.829Z] ====== als (apache-spark) [default], iteration 23 completed (53144.501 ms) ====== [2025-06-18T21:07:21.829Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-06-18T21:07:21.829Z] GC before operation: completed in 740.492 ms, heap usage 504.191 MB -> 68.394 MB. [2025-06-18T21:08:20.645Z] ====== als (apache-spark) [default], iteration 24 completed (57196.441 ms) ====== [2025-06-18T21:08:20.645Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-06-18T21:08:20.645Z] GC before operation: completed in 630.466 ms, heap usage 358.206 MB -> 68.425 MB. [2025-06-18T21:09:20.272Z] ====== als (apache-spark) [default], iteration 25 completed (52386.324 ms) ====== [2025-06-18T21:09:20.272Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-06-18T21:09:20.272Z] GC before operation: completed in 629.552 ms, heap usage 505.993 MB -> 68.429 MB. [2025-06-18T21:10:02.807Z] ====== als (apache-spark) [default], iteration 26 completed (46464.620 ms) ====== [2025-06-18T21:10:02.807Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-06-18T21:10:02.807Z] GC before operation: completed in 613.888 ms, heap usage 226.625 MB -> 68.433 MB. [2025-06-18T21:11:00.501Z] ====== als (apache-spark) [default], iteration 27 completed (50118.814 ms) ====== [2025-06-18T21:11:00.501Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-06-18T21:11:00.501Z] GC before operation: completed in 700.073 ms, heap usage 296.403 MB -> 68.656 MB. [2025-06-18T21:11:58.042Z] ====== als (apache-spark) [default], iteration 28 completed (56798.171 ms) ====== [2025-06-18T21:11:58.042Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-06-18T21:11:58.042Z] GC before operation: completed in 831.085 ms, heap usage 209.621 MB -> 68.406 MB. [2025-06-18T21:12:47.790Z] ====== als (apache-spark) [default], iteration 29 completed (53724.570 ms) ====== [2025-06-18T21:12:51.337Z] ----------------------------------- [2025-06-18T21:12:51.337Z] renaissance-als_0_PASSED [2025-06-18T21:12:51.337Z] ----------------------------------- [2025-06-18T21:12:51.337Z] [2025-06-18T21:12:51.337Z] TEST TEARDOWN: [2025-06-18T21:12:51.337Z] Nothing to be done for teardown. [2025-06-18T21:12:51.337Z] renaissance-als_0 Finish Time: Wed Jun 18 21:12:50 2025 Epoch Time (ms): 1750281170907