[2025-05-08T08:11:44.598Z] Running test renaissance-als_0 ... [2025-05-08T08:11:44.598Z] =============================================== [2025-05-08T08:11:44.598Z] renaissance-als_0 Start Time: Thu May 8 08:11:44 2025 Epoch Time (ms): 1746691904403 [2025-05-08T08:11:44.598Z] variation: NoOptions [2025-05-08T08:11:44.598Z] JVM_OPTIONS: [2025-05-08T08:11:44.598Z] { \ [2025-05-08T08:11:44.598Z] echo ""; echo "TEST SETUP:"; \ [2025-05-08T08:11:44.598Z] echo "Nothing to be done for setup."; \ [2025-05-08T08:11:44.598Z] mkdir -p "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17466919033356/renaissance-als_0"; \ [2025-05-08T08:11:44.598Z] cd "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17466919033356/renaissance-als_0"; \ [2025-05-08T08:11:44.598Z] echo ""; echo "TESTING:"; \ [2025-05-08T08:11:44.598Z] "/home/jenkins/workspace/Test_openjdk17_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_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17466919033356/renaissance-als_0"/als.json" als; \ [2025-05-08T08:11:44.598Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-als_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17466919033356/renaissance-als_0"; else echo "-----------------------------------"; echo "renaissance-als_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-05-08T08:11:44.598Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-05-08T08:11:44.598Z] echo "Nothing to be done for teardown."; \ [2025-05-08T08:11:44.598Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk17_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17466919033356/TestTargetResult"; [2025-05-08T08:11:44.598Z] [2025-05-08T08:11:44.598Z] TEST SETUP: [2025-05-08T08:11:44.598Z] Nothing to be done for setup. [2025-05-08T08:11:44.598Z] [2025-05-08T08:11:44.598Z] TESTING: [2025-05-08T08:12:19.811Z] NOTE: 'als' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-05-08T08:12:45.964Z] ====== als (apache-spark) [default], iteration 0 started ====== [2025-05-08T08:12:45.964Z] GC before operation: completed in 339.357 ms, heap usage 57.691 MB -> 34.937 MB. [2025-05-08T08:15:07.258Z] ====== als (apache-spark) [default], iteration 0 completed (137941.691 ms) ====== [2025-05-08T08:15:07.258Z] ====== als (apache-spark) [default], iteration 1 started ====== [2025-05-08T08:15:07.258Z] GC before operation: completed in 711.302 ms, heap usage 745.449 MB -> 62.362 MB. [2025-05-08T08:16:14.868Z] ====== als (apache-spark) [default], iteration 1 completed (62175.322 ms) ====== [2025-05-08T08:16:14.868Z] ====== als (apache-spark) [default], iteration 2 started ====== [2025-05-08T08:16:14.868Z] GC before operation: completed in 488.839 ms, heap usage 294.872 MB -> 62.662 MB. [2025-05-08T08:17:22.208Z] ====== als (apache-spark) [default], iteration 2 completed (61335.253 ms) ====== [2025-05-08T08:17:22.208Z] ====== als (apache-spark) [default], iteration 3 started ====== [2025-05-08T08:17:22.208Z] GC before operation: completed in 493.441 ms, heap usage 420.562 MB -> 63.320 MB. [2025-05-08T08:18:19.195Z] ====== als (apache-spark) [default], iteration 3 completed (58732.078 ms) ====== [2025-05-08T08:18:19.195Z] ====== als (apache-spark) [default], iteration 4 started ====== [2025-05-08T08:18:19.195Z] GC before operation: completed in 676.486 ms, heap usage 829.034 MB -> 64.597 MB. [2025-05-08T08:19:49.194Z] ====== als (apache-spark) [default], iteration 4 completed (61961.085 ms) ====== [2025-05-08T08:19:49.194Z] ====== als (apache-spark) [default], iteration 5 started ====== [2025-05-08T08:19:49.194Z] GC before operation: completed in 520.316 ms, heap usage 784.579 MB -> 64.933 MB. [2025-05-08T08:20:15.702Z] ====== als (apache-spark) [default], iteration 5 completed (54738.718 ms) ====== [2025-05-08T08:20:15.702Z] ====== als (apache-spark) [default], iteration 6 started ====== [2025-05-08T08:20:16.512Z] GC before operation: completed in 599.466 ms, heap usage 259.964 MB -> 65.024 MB. [2025-05-08T08:21:23.946Z] ====== als (apache-spark) [default], iteration 6 completed (58674.882 ms) ====== [2025-05-08T08:21:23.946Z] ====== als (apache-spark) [default], iteration 7 started ====== [2025-05-08T08:21:23.946Z] GC before operation: completed in 667.162 ms, heap usage 747.675 MB -> 66.074 MB. [2025-05-08T08:22:13.126Z] ====== als (apache-spark) [default], iteration 7 completed (49292.675 ms) ====== [2025-05-08T08:22:13.126Z] ====== als (apache-spark) [default], iteration 8 started ====== [2025-05-08T08:22:13.126Z] GC before operation: completed in 576.350 ms, heap usage 438.348 MB -> 66.161 MB. [2025-05-08T08:23:10.679Z] ====== als (apache-spark) [default], iteration 8 completed (53276.454 ms) ====== [2025-05-08T08:23:10.680Z] ====== als (apache-spark) [default], iteration 9 started ====== [2025-05-08T08:23:10.680Z] GC before operation: completed in 690.422 ms, heap usage 571.004 MB -> 66.975 MB. [2025-05-08T08:23:59.750Z] ====== als (apache-spark) [default], iteration 9 completed (54320.765 ms) ====== [2025-05-08T08:23:59.750Z] ====== als (apache-spark) [default], iteration 10 started ====== [2025-05-08T08:24:00.570Z] GC before operation: completed in 822.381 ms, heap usage 506.516 MB -> 67.147 MB. [2025-05-08T08:24:58.055Z] ====== als (apache-spark) [default], iteration 10 completed (52774.976 ms) ====== [2025-05-08T08:24:58.055Z] ====== als (apache-spark) [default], iteration 11 started ====== [2025-05-08T08:24:58.055Z] GC before operation: completed in 608.754 ms, heap usage 279.142 MB -> 66.985 MB. [2025-05-08T08:25:47.157Z] ====== als (apache-spark) [default], iteration 11 completed (45900.176 ms) ====== [2025-05-08T08:25:47.157Z] ====== als (apache-spark) [default], iteration 12 started ====== [2025-05-08T08:25:47.157Z] GC before operation: completed in 539.653 ms, heap usage 493.786 MB -> 67.752 MB. [2025-05-08T08:26:35.712Z] ====== als (apache-spark) [default], iteration 12 completed (49795.168 ms) ====== [2025-05-08T08:26:35.713Z] ====== als (apache-spark) [default], iteration 13 started ====== [2025-05-08T08:26:35.713Z] GC before operation: completed in 613.054 ms, heap usage 440.089 MB -> 67.725 MB. [2025-05-08T08:27:33.221Z] ====== als (apache-spark) [default], iteration 13 completed (52139.860 ms) ====== [2025-05-08T08:27:33.221Z] ====== als (apache-spark) [default], iteration 14 started ====== [2025-05-08T08:27:33.221Z] GC before operation: completed in 766.717 ms, heap usage 840.725 MB -> 68.638 MB. [2025-05-08T08:28:30.261Z] ====== als (apache-spark) [default], iteration 14 completed (55002.948 ms) ====== [2025-05-08T08:28:30.261Z] ====== als (apache-spark) [default], iteration 15 started ====== [2025-05-08T08:28:30.261Z] GC before operation: completed in 586.213 ms, heap usage 376.404 MB -> 68.246 MB. [2025-05-08T08:29:18.437Z] ====== als (apache-spark) [default], iteration 15 completed (48856.292 ms) ====== [2025-05-08T08:29:18.437Z] ====== als (apache-spark) [default], iteration 16 started ====== [2025-05-08T08:29:18.437Z] GC before operation: completed in 760.098 ms, heap usage 686.312 MB -> 68.946 MB. [2025-05-08T08:30:06.872Z] ====== als (apache-spark) [default], iteration 16 completed (47040.851 ms) ====== [2025-05-08T08:30:06.872Z] ====== als (apache-spark) [default], iteration 17 started ====== [2025-05-08T08:30:06.872Z] GC before operation: completed in 791.439 ms, heap usage 203.033 MB -> 68.572 MB. [2025-05-08T08:30:55.396Z] ====== als (apache-spark) [default], iteration 17 completed (46999.913 ms) ====== [2025-05-08T08:30:55.396Z] ====== als (apache-spark) [default], iteration 18 started ====== [2025-05-08T08:30:55.396Z] GC before operation: completed in 704.645 ms, heap usage 181.221 MB -> 68.803 MB. [2025-05-08T08:31:44.859Z] ====== als (apache-spark) [default], iteration 18 completed (47002.656 ms) ====== [2025-05-08T08:31:44.859Z] ====== als (apache-spark) [default], iteration 19 started ====== [2025-05-08T08:31:44.859Z] GC before operation: completed in 587.526 ms, heap usage 296.968 MB -> 69.214 MB. [2025-05-08T08:32:35.095Z] ====== als (apache-spark) [default], iteration 19 completed (46577.392 ms) ====== [2025-05-08T08:32:35.095Z] ====== als (apache-spark) [default], iteration 20 started ====== [2025-05-08T08:32:35.095Z] GC before operation: completed in 603.787 ms, heap usage 417.228 MB -> 69.585 MB. [2025-05-08T08:33:24.674Z] ====== als (apache-spark) [default], iteration 20 completed (46513.219 ms) ====== [2025-05-08T08:33:24.674Z] ====== als (apache-spark) [default], iteration 21 started ====== [2025-05-08T08:33:24.674Z] GC before operation: completed in 761.356 ms, heap usage 486.724 MB -> 70.000 MB. [2025-05-08T08:34:07.062Z] ====== als (apache-spark) [default], iteration 21 completed (44667.191 ms) ====== [2025-05-08T08:34:07.062Z] ====== als (apache-spark) [default], iteration 22 started ====== [2025-05-08T08:34:07.062Z] GC before operation: completed in 695.136 ms, heap usage 241.739 MB -> 69.874 MB. [2025-05-08T08:34:56.223Z] ====== als (apache-spark) [default], iteration 22 completed (47118.114 ms) ====== [2025-05-08T08:34:56.223Z] ====== als (apache-spark) [default], iteration 23 started ====== [2025-05-08T08:34:56.223Z] GC before operation: completed in 871.355 ms, heap usage 570.290 MB -> 70.068 MB. [2025-05-08T08:35:45.530Z] ====== als (apache-spark) [default], iteration 23 completed (43028.372 ms) ====== [2025-05-08T08:35:45.530Z] ====== als (apache-spark) [default], iteration 24 started ====== [2025-05-08T08:35:45.530Z] GC before operation: completed in 705.968 ms, heap usage 378.434 MB -> 69.629 MB. [2025-05-08T08:36:34.615Z] ====== als (apache-spark) [default], iteration 24 completed (50079.330 ms) ====== [2025-05-08T08:36:34.615Z] ====== als (apache-spark) [default], iteration 25 started ====== [2025-05-08T08:36:34.615Z] GC before operation: completed in 838.579 ms, heap usage 747.954 MB -> 68.608 MB. [2025-05-08T08:37:32.857Z] ====== als (apache-spark) [default], iteration 25 completed (52319.628 ms) ====== [2025-05-08T08:37:32.857Z] ====== als (apache-spark) [default], iteration 26 started ====== [2025-05-08T08:37:32.857Z] GC before operation: completed in 833.722 ms, heap usage 158.722 MB -> 68.075 MB. [2025-05-08T08:38:15.106Z] ====== als (apache-spark) [default], iteration 26 completed (44958.500 ms) ====== [2025-05-08T08:38:15.106Z] ====== als (apache-spark) [default], iteration 27 started ====== [2025-05-08T08:38:15.106Z] GC before operation: completed in 752.064 ms, heap usage 315.785 MB -> 68.492 MB. [2025-05-08T08:39:04.756Z] ====== als (apache-spark) [default], iteration 27 completed (49742.870 ms) ====== [2025-05-08T08:39:04.756Z] ====== als (apache-spark) [default], iteration 28 started ====== [2025-05-08T08:39:04.756Z] GC before operation: completed in 762.615 ms, heap usage 479.810 MB -> 68.276 MB. [2025-05-08T08:40:02.944Z] ====== als (apache-spark) [default], iteration 28 completed (52145.842 ms) ====== [2025-05-08T08:40:02.944Z] ====== als (apache-spark) [default], iteration 29 started ====== [2025-05-08T08:40:02.944Z] GC before operation: completed in 584.357 ms, heap usage 147.863 MB -> 68.287 MB. [2025-05-08T08:40:45.033Z] ====== als (apache-spark) [default], iteration 29 completed (42074.230 ms) ====== [2025-05-08T08:40:45.033Z] ----------------------------------- [2025-05-08T08:40:45.033Z] renaissance-als_0_PASSED [2025-05-08T08:40:45.033Z] ----------------------------------- [2025-05-08T08:40:45.033Z] [2025-05-08T08:40:45.033Z] TEST TEARDOWN: [2025-05-08T08:40:45.033Z] Nothing to be done for teardown. [2025-05-08T08:40:45.033Z] renaissance-als_0 Finish Time: Thu May 8 08:40:43 2025 Epoch Time (ms): 1746693643405