[2025-02-13T23:42:52.106Z] Running test renaissance-movie-lens_0 ... [2025-02-13T23:42:52.106Z] =============================================== [2025-02-13T23:42:52.106Z] renaissance-movie-lens_0 Start Time: Thu Feb 13 23:42:49 2025 Epoch Time (ms): 1739490169955 [2025-02-13T23:42:52.106Z] variation: NoOptions [2025-02-13T23:42:52.106Z] JVM_OPTIONS: [2025-02-13T23:42:52.106Z] { \ [2025-02-13T23:42:52.106Z] echo ""; echo "TEST SETUP:"; \ [2025-02-13T23:42:52.106Z] echo "Nothing to be done for setup."; \ [2025-02-13T23:42:52.106Z] mkdir -p "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17394846239083/renaissance-movie-lens_0"; \ [2025-02-13T23:42:52.106Z] cd "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17394846239083/renaissance-movie-lens_0"; \ [2025-02-13T23:42:52.106Z] echo ""; echo "TESTING:"; \ [2025-02-13T23:42:52.106Z] "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/jdkbinary/j2sdk-image/bin/java" -jar "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar" --json ""/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17394846239083/renaissance-movie-lens_0"/movie-lens.json" movie-lens; \ [2025-02-13T23:42:52.106Z] if [ $? -eq 0 ]; then echo "-----------------------------------"; echo "renaissance-movie-lens_0""_PASSED"; echo "-----------------------------------"; cd /home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/..; rm -f -r "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17394846239083/renaissance-movie-lens_0"; else echo "-----------------------------------"; echo "renaissance-movie-lens_0""_FAILED"; echo "-----------------------------------"; fi; \ [2025-02-13T23:42:52.106Z] echo ""; echo "TEST TEARDOWN:"; \ [2025-02-13T23:42:52.106Z] echo "Nothing to be done for teardown."; \ [2025-02-13T23:42:52.106Z] } 2>&1 | tee -a "/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17394846239083/TestTargetResult"; [2025-02-13T23:42:52.106Z] [2025-02-13T23:42:52.106Z] TEST SETUP: [2025-02-13T23:42:52.106Z] Nothing to be done for setup. [2025-02-13T23:42:52.106Z] [2025-02-13T23:42:52.106Z] TESTING: [2025-02-13T23:43:14.734Z] Using Spark's default log4j profile: org/apache/spark/log4j-defaults.properties [2025-02-13T23:43:45.566Z] NOTE: 'movie-lens' benchmark uses Spark local executor with 4 (out of 4) threads. [2025-02-13T23:44:35.775Z] Got 100004 ratings from 671 users on 9066 movies. [2025-02-13T23:44:35.775Z] Training: 60056, validation: 20285, test: 19854 [2025-02-13T23:44:35.775Z] ====== movie-lens (apache-spark) [default], iteration 0 started ====== [2025-02-13T23:44:36.649Z] GC before operation: completed in 1970.348 ms, heap usage 88.267 MB -> 27.095 MB. [2025-02-13T23:45:57.632Z] RMSE (validation) = 3.6219689545487617 for the model trained with rank = 8, lambda = 5.0, and numIter = 20. [2025-02-13T23:46:33.332Z] RMSE (validation) = 2.1340923220285766 for the model trained with rank = 10, lambda = 2.0, and numIter = 20. [2025-02-13T23:47:15.949Z] RMSE (validation) = 1.3105186462138083 for the model trained with rank = 12, lambda = 1.0, and numIter = 20. [2025-02-13T23:47:57.970Z] RMSE (validation) = 0.9919630851507418 for the model trained with rank = 8, lambda = 0.05, and numIter = 20. [2025-02-13T23:48:17.534Z] RMSE (validation) = 1.2070175276098327 for the model trained with rank = 10, lambda = 0.01, and numIter = 10. [2025-02-13T23:48:36.771Z] RMSE (validation) = 1.1146801722401463 for the model trained with rank = 8, lambda = 0.02, and numIter = 10. [2025-02-13T23:48:59.327Z] RMSE (validation) = 0.9227419497655963 for the model trained with rank = 12, lambda = 0.1, and numIter = 10. [2025-02-13T23:49:21.672Z] RMSE (validation) = 0.8980643995666189 for the model trained with rank = 8, lambda = 0.2, and numIter = 10. [2025-02-13T23:49:24.231Z] The best model was trained with rank = 8 and lambda = 0.2, and numIter = 10, and its RMSE on the test set is 0.9063252187379536. [2025-02-13T23:49:25.005Z] The best model improves the baseline by 14.52%. [2025-02-13T23:49:25.814Z] Movies recommended for you: [2025-02-13T23:49:25.815Z] WARNING: This benchmark provides no result that can be validated. [2025-02-13T23:49:25.815Z] There is no way to check that no silent failure occurred. [2025-02-13T23:49:25.815Z] ====== movie-lens (apache-spark) [default], iteration 0 completed (289482.128 ms) ====== [2025-02-13T23:49:25.815Z] ====== movie-lens (apache-spark) [default], iteration 1 started ====== [2025-02-13T23:49:29.310Z] GC before operation: completed in 3219.299 ms, heap usage 493.183 MB -> 47.980 MB. [2025-02-13T23:50:11.583Z] RMSE (validation) = 3.621968954548761 for the model trained with rank = 8, lambda = 5.0, and numIter = 20. [2025-02-13T23:50:53.513Z] RMSE (validation) = 2.1340923220285766 for the model trained with rank = 10, lambda = 2.0, and numIter = 20. [2025-02-13T23:51:29.054Z] RMSE (validation) = 1.3105186462138083 for the model trained with rank = 12, lambda = 1.0, and numIter = 20. [2025-02-14T23:06:18.652Z] Cancelling nested steps due to timeout [2025-02-14T23:06:18.780Z] Sending interrupt signal to process [2025-02-14T23:06:36.752Z] Terminated [2025-02-14T23:06:36.752Z] Terminated [2025-02-14T23:06:36.752Z] make[4]: *** [autoGen.mk:255: renaissance-movie-lens_0] Error 143 [2025-02-14T23:06:36.752Z] make[4]: Leaving directory '/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/perf/renaissance' [2025-02-14T23:06:36.752Z] make[3]: *** [/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/settings.mk:362: extended.perf-renaissance] Error 2 [2025-02-14T23:06:36.752Z] make[3]: Leaving directory '/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/perf' [2025-02-14T23:06:36.752Z] make[2]: *** [/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/settings.mk:362: extended.perf-perf] Error 2 [2025-02-14T23:06:36.752Z] make[2]: Leaving directory '/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests' [2025-02-14T23:06:36.752Z] make[1]: *** [settings.mk:362: extended.perf-..] Error 2 [2025-02-14T23:06:36.752Z] make[1]: Leaving directory '/home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG' [2025-02-14T23:06:36.752Z] make: *** [makefile:62: _extended.perf] Error 2 [2025-02-14T23:06:37.126Z] script returned exit code 2 [Pipeline] sh [2025-02-14T23:06:38.643Z] + uname [2025-02-14T23:06:38.644Z] + '[' Linux '=' AIX ] [2025-02-14T23:06:38.644Z] + uname [2025-02-14T23:06:38.644Z] + '[' Linux '=' SunOS ] [2025-02-14T23:06:38.644Z] + uname [2025-02-14T23:06:38.644Z] + '[' Linux '=' '*BSD' ] [2025-02-14T23:06:38.644Z] + MAKE=make [2025-02-14T23:06:38.644Z] + make -f ./aqa-tests/TKG/testEnv.mk testEnvTeardown [2025-02-14T23:06:38.644Z] make: Nothing to be done for 'testEnvTeardown'. [Pipeline] } [2025-02-14T23:06:39.022Z] Xvfb stopping [Pipeline] // wrap [Pipeline] echo [2025-02-14T23:06:39.446Z] Could not find test result, set build result to FAILURE. [Pipeline] } [Pipeline] // stage [Pipeline] stage [Pipeline] { (Post) [Pipeline] step [2025-02-14T23:06:39.646Z] TAP Reports Processing: START [2025-02-14T23:06:39.647Z] Looking for TAP results report in workspace using pattern: aqa-tests/TKG/**/*.tap [2025-02-14T23:06:39.795Z] Did not find any matching files. Setting build result to FAILURE. [Pipeline] echo [2025-02-14T23:06:39.806Z] Saving aqa-tests/testenv/testenv.properties file on jenkins. [Pipeline] archiveArtifacts [2025-02-14T23:06:39.944Z] Archiving artifacts [2025-02-14T23:06:40.205Z] Recording fingerprints [Pipeline] echo [2025-02-14T23:06:40.348Z] Saving aqa-tests/TKG/**/*.tap file on jenkins. [Pipeline] archiveArtifacts [2025-02-14T23:06:40.479Z] Archiving artifacts [Pipeline] sh [2025-02-14T23:06:42.023Z] + tar -cf benchmark_test_output.tar.gz ./aqa-tests/TKG/output_17394846239083 [Pipeline] echo [2025-02-14T23:06:43.146Z] ARTIFACTORY_SERVER is not set. Saving artifacts on jenkins. [Pipeline] archiveArtifacts [2025-02-14T23:06:43.278Z] Archiving artifacts [2025-02-14T23:07:18.655Z] Body did not finish within grace period; terminating with extreme prejudice [Pipeline] } [Pipeline] // stage [Pipeline] echo [2025-02-14T23:07:18.693Z] PROCESSCATCH: Terminating any hung/left over test processes: [Pipeline] sh [2025-02-14T23:07:18.797Z] Recording fingerprints [2025-02-14T23:07:20.299Z] + aqa-tests/terminateTestProcesses.sh jenkins [2025-02-14T23:07:20.300Z] Unix type machine.. [2025-02-14T23:07:20.300Z] Running on a Linux host [2025-02-14T23:07:20.300Z] - 2465064 S ? 00:02:42 /home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/jdkbinary/j2sdk-image/bin/java -jar /home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar --json /home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17394846239083/renaissance-movie-lens_0/movie-lens.json movie-lens [2025-02-14T23:07:20.300Z] Boooo - there are rogue processes kicking about [2025-02-14T23:07:20.300Z] Issuing a kill to all processes shown above [2025-02-14T23:07:20.300Z] Sleeping for 10 seconds... [2025-02-14T23:07:30.837Z] - 2465064 S ? 00:02:42 /home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/jdkbinary/j2sdk-image/bin/java -jar /home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../../jvmtest/perf/renaissance/renaissance.jar --json /home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux/aqa-tests/TKG/../TKG/output_17394846239083/renaissance-movie-lens_0/movie-lens.json movie-lens [2025-02-14T23:07:30.837Z] Still processes left going to remove those with kill -KILL ... [2025-02-14T23:07:30.837Z] DONE - One final check ... [2025-02-14T23:07:30.837Z] Processes stopped successfully [Pipeline] cleanWs [2025-02-14T23:07:31.590Z] [WS-CLEANUP] Deleting project workspace... [2025-02-14T23:07:31.590Z] [WS-CLEANUP] Deferred wipeout is disabled by the job configuration... [2025-02-14T23:07:32.353Z] [WS-CLEANUP] done [Pipeline] sh [2025-02-14T23:07:34.382Z] + find /tmp -name '*core*' -print -exec rm -f '{}' ';' [Pipeline] } [Pipeline] // timeout [Pipeline] echo [2025-02-14T23:07:34.865Z] Exception: org.jenkinsci.plugins.workflow.steps.FlowInterruptedException [Pipeline] timeout [2025-02-14T23:07:34.873Z] Timeout set to expire in 5 min 0 sec [Pipeline] { [Pipeline] } [Pipeline] // timeout [Pipeline] } [Pipeline] // node [Pipeline] } [Pipeline] // stage [Pipeline] echo [2025-02-14T23:07:35.015Z] SETUP_LABEL: ci.role.test [Pipeline] stage [Pipeline] { (Parallel Tests) [Pipeline] parallel [2025-02-14T23:07:35.042Z] No branches to run [Pipeline] // parallel [Pipeline] node [2025-02-14T23:07:35.087Z] Running on test-rise-ubuntu2404-riscv64-3 in /home/jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux [Pipeline] { [Pipeline] cleanWs [2025-02-14T23:07:35.181Z] [WS-CLEANUP] Deleting project workspace... [2025-02-14T23:07:35.181Z] [WS-CLEANUP] Deferred wipeout is disabled by the job configuration... [2025-02-14T23:07:35.264Z] [WS-CLEANUP] done [Pipeline] findFiles [Pipeline] cleanWs [2025-02-14T23:07:35.355Z] [WS-CLEANUP] Deleting project workspace... [2025-02-14T23:07:35.355Z] [WS-CLEANUP] Deferred wipeout is disabled by the job configuration... [2025-02-14T23:07:35.376Z] [WS-CLEANUP] done [Pipeline] } [Pipeline] // node [Pipeline] } [Pipeline] // stage [Pipeline] } [Pipeline] // timestamps [Pipeline] End of Pipeline /home/jenkins/.jenkins/workspace/Test_openjdk8_hs_extended.perf_x86-64_alpine-linux@tmp/jfrog/127/.jfrog deleted Finished: ABORTED