LHC@home

I have had a long standing issue with computation errors. I have not been able to participate in this program in quite some time, and finally have some time to address it when it is on my mind. I guess I will have to post the log in chunks as I am not seeing the boot-up bits. So the most recent LHC@home error is listed first, the boot-up bits with system info are listed after.

Here is the LHC@home related messages over the last few days. I tried highlighting a block by coloring the text near the end where I suspect is the pertinent information is, but you evidently cant do that inside a block of code. So about 16 lines from end is where I believe to be the start of the pertinent information for someone who knows what this shit means is.

12/13/2023 12:41:28 PM | LHC@home | Sending scheduler request: To fetch work.
12/13/2023 12:41:28 PM | LHC@home | Requesting new tasks for CPU
12/13/2023 12:41:31 PM | LHC@home | Scheduler request completed: got 0 new tasks
12/13/2023 12:41:31 PM | LHC@home | No tasks sent
12/13/2023 12:41:31 PM | LHC@home | Tasks for AMD/ATI GPU are available, but your preferences are set to not accept them
12/13/2023 12:41:31 PM | LHC@home | This computer has finished a daily quota of 1 tasks
12/13/2023 12:41:31 PM | LHC@home | Project requested delay of 6 seconds
12/13/2023 01:46:11 PM | LHC@home | Sending scheduler request: To fetch work.
12/13/2023 01:46:11 PM | LHC@home | Requesting new tasks for CPU
12/13/2023 01:46:13 PM | LHC@home | Scheduler request completed: got 0 new tasks
12/13/2023 01:46:13 PM | LHC@home | No tasks sent
12/13/2023 01:46:13 PM | LHC@home | Tasks for AMD/ATI GPU are available, but your preferences are set to not accept them
12/13/2023 01:46:13 PM | LHC@home | This computer has finished a daily quota of 1 tasks
12/13/2023 01:46:13 PM | LHC@home | Project requested delay of 6 seconds
12/13/2023 06:46:17 PM | LHC@home | Sending scheduler request: Requested by project.
12/13/2023 06:46:17 PM | LHC@home | Not requesting tasks: don't need (CPU: job cache full; NVIDIA GPU: )
12/13/2023 06:46:19 PM | LHC@home | Scheduler request completed
12/13/2023 06:46:19 PM | LHC@home | Project requested delay of 6 seconds
12/13/2023 09:55:15 PM | LHC@home | Sending scheduler request: To fetch work.
12/13/2023 09:55:15 PM | LHC@home | Requesting new tasks for CPU
12/13/2023 09:55:17 PM | LHC@home | Scheduler request completed: got 1 new tasks
12/13/2023 09:55:17 PM | LHC@home | Project requested delay of 6 seconds
12/13/2023 09:55:17 PM | LHC@home | [task] result state=NEW for CMS_4097907_1702520173.079004_0 from handle_scheduler_reply
12/13/2023 09:55:18 PM | LHC@home | [task] result state=FILES_DOWNLOADING for CMS_4097907_1702520173.079004_0 from CS::update_results
12/13/2023 09:55:19 PM | LHC@home | [task] result state=FILES_DOWNLOADED for CMS_4097907_1702520173.079004_0 from CS::update_results
12/13/2023 09:55:19 PM | LHC@home | [task_debug] task is running in processor group 0
12/13/2023 09:55:19 PM | LHC@home | [task] task_state=EXECUTING for CMS_4097907_1702520173.079004_0 from start
12/13/2023 09:55:19 PM | LHC@home | Starting task CMS_4097907_1702520173.079004_0
12/13/2023 09:55:41 PM | LHC@home | [task] Process for CMS_4097907_1702520173.079004_0 exited, exit code 2147942487, task state 1
12/13/2023 09:55:41 PM | LHC@home | [task] task_state=EXITED for CMS_4097907_1702520173.079004_0 from handle_exited_app
12/13/2023 09:55:41 PM | LHC@home | [task] result state=COMPUTE_ERROR for CMS_4097907_1702520173.079004_0 from CS::report_result_error
12/13/2023 09:55:41 PM | LHC@home | [task] Process for CMS_4097907_1702520173.079004_0 exited
12/13/2023 09:55:41 PM | LHC@home | [task] exit code -2147024809 (0x80070057): The parameter is incorrect. (0x80070057)
12/13/2023 09:55:41 PM | LHC@home | Computation for task CMS_4097907_1702520173.079004_0 finished
12/13/2023 09:55:41 PM | LHC@home | [task] result state=COMPUTE_ERROR for CMS_4097907_1702520173.079004_0 from CS::app_finished
12/13/2023 09:56:59 PM | LHC@home | Sending scheduler request: To report completed tasks.
12/13/2023 09:56:59 PM | LHC@home | Reporting 1 completed tasks
12/13/2023 09:56:59 PM | LHC@home | Requesting new tasks for CPU
12/13/2023 09:57:01 PM | LHC@home | Scheduler request completed: got 0 new tasks
12/13/2023 09:57:01 PM | LHC@home | No tasks sent
12/13/2023 09:57:01 PM | LHC@home | Tasks for AMD/ATI GPU are available, but your preferences are set to not accept them
12/13/2023 09:57:01 PM | LHC@home | This computer has finished a daily quota of 1 tasks
12/13/2023 09:57:01 PM | LHC@home | Project requested delay of 6 seconds
12/13/2023 10:01:15 PM | LHC@home | Sending scheduler request: To fetch work.
12/13/2023 10:01:15 PM | LHC@home | Requesting new tasks for CPU
12/13/2023 10:01:17 PM | LHC@home | Scheduler request completed: got 0 new tasks
12/13/2023 10:01:17 PM | LHC@home | No tasks sent
12/13/2023 10:01:17 PM | LHC@home | Tasks for AMD/ATI GPU are available, but your preferences are set to not accept them
12/13/2023 10:01:17 PM | LHC@home | This computer has finished a daily quota of 1 tasks
12/13/2023 10:01:17 PM | LHC@home | Project requested delay of 6 seconds
12/14/2023 12:30:14 AM | LHC@home | Sending scheduler request: To fetch work.
12/14/2023 12:30:14 AM | LHC@home | Requesting new tasks for CPU
12/14/2023 12:30:26 AM | LHC@home | Scheduler request completed: got 0 new tasks
12/14/2023 12:30:26 AM | LHC@home | No tasks sent
12/14/2023 12:30:26 AM | LHC@home | Tasks for AMD/ATI GPU are available, but your preferences are set to not accept them
12/14/2023 12:30:26 AM | LHC@home | This computer has finished a daily quota of 1 tasks
12/14/2023 12:30:26 AM | LHC@home | Project requested delay of 6 seconds
12/14/2023 02:21:37 AM | LHC@home | Sending scheduler request: To fetch work.
12/14/2023 02:21:37 AM | LHC@home | Requesting new tasks for CPU
12/14/2023 02:21:38 AM | LHC@home | Scheduler request completed: got 0 new tasks
12/14/2023 02:21:38 AM | LHC@home | No tasks sent
12/14/2023 02:21:38 AM | LHC@home | Tasks for AMD/ATI GPU are available, but your preferences are set to not accept them
12/14/2023 02:21:38 AM | LHC@home | This computer has finished a daily quota of 1 tasks
12/14/2023 02:21:38 AM | LHC@home | Project requested delay of 6 seconds
12/14/2023 03:15:48 AM | LHC@home | Sending scheduler request: To fetch work.
12/14/2023 03:15:48 AM | LHC@home | Requesting new tasks for CPU
12/14/2023 03:15:50 AM | LHC@home | Scheduler request completed: got 0 new tasks
12/14/2023 03:15:50 AM | LHC@home | No tasks sent
12/14/2023 03:15:50 AM | LHC@home | Tasks for AMD/ATI GPU are available, but your preferences are set to not accept them
12/14/2023 03:15:50 AM | LHC@home | This computer has finished a daily quota of 1 tasks
12/14/2023 03:15:50 AM | LHC@home | Project requested delay of 6 seconds
12/14/2023 08:15:53 AM | LHC@home | Sending scheduler request: Requested by project.
12/14/2023 08:15:53 AM | LHC@home | Not requesting tasks: don't need (CPU: job cache full; NVIDIA GPU: )
12/14/2023 08:15:55 AM | LHC@home | Scheduler request completed
12/14/2023 08:15:55 AM | LHC@home | Project requested delay of 6 seconds
12/14/2023 09:41:09 AM |  | Suspending computation - user request
12/14/2023 09:41:09 AM |  | request_exit(): PID 7036 has 1 descendants
12/14/2023 09:41:09 AM |  | PID 18512
12/14/2023 01:15:57 PM | LHC@home | Sending scheduler request: Requested by project.
12/14/2023 01:15:57 PM | LHC@home | Requesting new tasks for CPU
12/14/2023 01:15:58 PM | LHC@home | Scheduler request completed: got 0 new tasks
12/14/2023 01:15:58 PM | LHC@home | No tasks sent
12/14/2023 01:15:58 PM | LHC@home | Tasks for AMD/ATI GPU are available, but your preferences are set to not accept them
12/14/2023 01:15:58 PM | LHC@home | This computer has finished a daily quota of 1 tasks
12/14/2023 01:15:58 PM | LHC@home | Project requested delay of 6 seconds
12/14/2023 06:15:59 PM | LHC@home | Sending scheduler request: Requested by project.
12/14/2023 06:15:59 PM | LHC@home | Requesting new tasks for CPU
12/14/2023 06:16:00 PM | LHC@home | Scheduler request completed: got 1 new tasks
12/14/2023 06:16:00 PM | LHC@home | Project requested delay of 6 seconds
12/14/2023 06:16:00 PM | LHC@home | [task] result state=NEW for CMS_255318_1702585949.552076_0 from handle_scheduler_reply
12/14/2023 06:16:01 PM | LHC@home | [task] result state=FILES_DOWNLOADING for CMS_255318_1702585949.552076_0 from CS::update_results
12/14/2023 06:16:02 PM | LHC@home | [task] result state=FILES_DOWNLOADED for CMS_255318_1702585949.552076_0 from CS::update_results
12/14/2023 11:16:02 PM | LHC@home | Sending scheduler request: Requested by project.
12/14/2023 11:16:02 PM | LHC@home | Requesting new tasks for CPU
12/14/2023 11:16:03 PM | LHC@home | Scheduler request completed: got 0 new tasks
12/14/2023 11:16:03 PM | LHC@home | No tasks sent
12/14/2023 11:16:03 PM | LHC@home | Tasks for AMD/ATI GPU are available, but your preferences are set to not accept them
12/14/2023 11:16:03 PM | LHC@home | This computer has finished a daily quota of 1 tasks
12/14/2023 11:16:03 PM | LHC@home | Project requested delay of 6 seconds
12/15/2023 04:16:06 AM | LHC@home | Sending scheduler request: Requested by project.
12/15/2023 04:16:06 AM | LHC@home | Requesting new tasks for CPU
12/15/2023 04:16:07 AM | LHC@home | Scheduler request completed: got 0 new tasks
12/15/2023 04:16:07 AM | LHC@home | No tasks sent
12/15/2023 04:16:07 AM | LHC@home | Tasks for AMD/ATI GPU are available, but your preferences are set to not accept them
12/15/2023 04:16:07 AM | LHC@home | This computer has finished a daily quota of 1 tasks
12/15/2023 04:16:07 AM | LHC@home | Project requested delay of 6 seconds
12/15/2023 09:16:07 AM | LHC@home | Sending scheduler request: Requested by project.
12/15/2023 09:16:07 AM | LHC@home | Requesting new tasks for CPU
12/15/2023 09:16:09 AM | LHC@home | Scheduler request completed: got 0 new tasks
12/15/2023 09:16:09 AM | LHC@home | No tasks sent
12/15/2023 09:16:09 AM | LHC@home | Tasks for AMD/ATI GPU are available, but your preferences are set to not accept them
12/15/2023 09:16:09 AM | LHC@home | This computer has finished a daily quota of 1 tasks
12/15/2023 09:16:09 AM | LHC@home | Project requested delay of 6 seconds
12/15/2023 10:46:31 AM |  | Suspending GPU computation - user request
12/15/2023 10:46:31 AM |  | Resuming computation
12/15/2023 10:46:31 AM |  | request_exit(): PID 3740 has 0 descendants
12/15/2023 10:46:33 AM | LHC@home | [task_debug] task is running in processor group 0
12/15/2023 10:46:33 AM | LHC@home | [task] task_state=EXECUTING for CMS_255318_1702585949.552076_0 from start
12/15/2023 10:46:33 AM | LHC@home | Starting task CMS_255318_1702585949.552076_0
12/15/2023 10:46:35 AM |  | Resuming GPU computation
12/15/2023 10:46:57 AM | LHC@home | [task] Process for CMS_255318_1702585949.552076_0 exited, exit code 2147942487, task state 1
12/15/2023 10:46:57 AM | LHC@home | [task] task_state=EXITED for CMS_255318_1702585949.552076_0 from handle_exited_app
12/15/2023 10:46:57 AM | LHC@home | [task] result state=COMPUTE_ERROR for CMS_255318_1702585949.552076_0 from CS::report_result_error
12/15/2023 10:46:57 AM | LHC@home | [task] Process for CMS_255318_1702585949.552076_0 exited
12/15/2023 10:46:57 AM | LHC@home | [task] exit code -2147024809 (0x80070057): The parameter is incorrect. (0x80070057)
12/15/2023 10:46:57 AM | LHC@home | Computation for task CMS_255318_1702585949.552076_0 finished
12/15/2023 10:46:57 AM | LHC@home | [task] result state=COMPUTE_ERROR for CMS_255318_1702585949.552076_0 from CS::app_finished
12/15/2023 10:48:32 AM | LHC@home | Sending scheduler request: To report completed tasks.
12/15/2023 10:48:32 AM | LHC@home | Reporting 1 completed tasks
12/15/2023 10:48:32 AM | LHC@home | Requesting new tasks for CPU
12/15/2023 10:48:34 AM | LHC@home | Scheduler request completed: got 0 new tasks
12/15/2023 10:48:34 AM | LHC@home | No tasks sent
12/15/2023 10:48:34 AM | LHC@home | Tasks for AMD/ATI GPU are available, but your preferences are set to not accept them
12/15/2023 10:48:34 AM | LHC@home | This computer has finished a daily quota of 1 tasks
12/15/2023 10:48:34 AM | LHC@home | Project requested delay of 6 seconds

Here is the log related to a fresh launch of BOINC

12/15/2023 10:57:14 AM |  | Starting BOINC client version 7.24.1 for windows_x86_64
12/15/2023 10:57:14 AM |  | log flags: file_xfer, sched_ops, task, task_debug
12/15/2023 10:57:14 AM |  | Libraries: libcurl/8.2.1-DEV Schannel zlib/1.2.13
12/15/2023 10:57:14 AM |  | Data directory: XXXXXXXXX
12/15/2023 10:57:14 AM |  | Running under account XXXXXXXX
12/15/2023 10:57:14 AM |  | CUDA: NVIDIA GPU 0: NVIDIA GeForce GTX 1080 Ti (driver version 537.13, CUDA version 12.2, compute capability 6.1, 11264MB, 11264MB available, 12064 GFLOPS peak)
12/15/2023 10:57:14 AM |  | OpenCL: NVIDIA GPU 0: NVIDIA GeForce GTX 1080 Ti (driver version 537.13, device version OpenCL 3.0 CUDA, 11264MB, 11264MB available, 12064 GFLOPS peak)
12/15/2023 10:57:14 AM |  | Windows processor group 0: 16 processors
12/15/2023 10:57:14 AM |  | Host name: 5800X3d
12/15/2023 10:57:14 AM |  | Processor: 16 AuthenticAMD AMD Ryzen 7 5800X3D 8-Core Processor [Family 25 Model 33 Stepping 2]
12/15/2023 10:57:14 AM |  | Processor features: fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush mmx fxsr sse sse2 htt pni ssse3 fma cx16 sse4_1 sse4_2 movebe popcnt aes f16c rdrandsyscall nx lm avx avx2 svm sse4a osvw ibs skinit wdt tce topx page1gb rdtscp fsgsbase bmi1 smep bmi2
12/15/2023 10:57:14 AM |  | OS: Microsoft Windows 11: Professional x64 Edition, (10.00.22621.00)
12/15/2023 10:57:14 AM |  | Memory: 15.92 GB physical, 22.67 GB virtual
12/15/2023 10:57:14 AM |  | Disk: 297.36 GB total, 290.21 GB free
12/15/2023 10:57:14 AM |  | Local time is UTC -5 hours
12/15/2023 10:57:14 AM |  | No WSL found.
12/15/2023 10:57:14 AM |  | VirtualBox version: 7.0.6

Please make your Computer visable in prefs of LHCatHome.

Please make your Computer visable in prefs of LHCatHome.

done

2023-12-11 18:12:03 (2752): Setting Memory Size for VM. (2048MB)

2023-12-11 18:12:07 (2752): Adding virtual disk drive to VM. (CMS_2022_09_07_prod.vdi)
2023-12-11 18:12:14 (2752): Error in check if parent hdd is registered.

2 GByte RAM can be to small for CMS.

parent hdd - what is your SSD or HDD definition, to use it for CMS?

btw CMS need a very good ISP connection, because of a lot of traffic. squid-proxy is also useful.

2 GByte RAM can be to small for CMS.

Nope.
2048 MB is the default RAM size set for CMS VMs and doesn't need to be changed.


This is the relevant error reported by VirtualBox.

VBoxManage.exe: error: Cannot register the hard disk 'E:\Boinc\Project Data\projects\lhcathome.cern.ch_lhcathome\CMS_2022_09_07_prod.vdi' {dae25e8f-de18-4971-b11c-eca764ede402} because a hard disk 'C:\ProgramData\BOINC\projects\lhcathome.cern.ch_lhcathome\CMS_2022_09_07_prod.vdi' with UUID {dae25e8f-de18-4971-b11c-eca764ede402} already exists

This means:
Sometime in the past the VirtualBox media registry wasn't cleaned up (for an unknown reason; those logs are not available any more).
You will have to remove the old entry manually:
1. Pause all VM tasks that are not yet started
2. Let all running VM tasks finish
3. Stop BOINC
4. Start the VirtualBox Media Manager via the VirtualBox GUI
5. Remove the disk entry pointing to "CMS_2022_09_07_prod.vdi" but do NOT remove the file itself if asked
6. Restart BOINC
7. Resume 1 VM task and wait a few seconds
8. Resume all other VM tasks


As for ATLAS/Theory work ensure "Run native ...?" is disabled at your prefs page:
https://lhcathome.cern.ch/lhcathome/prefs.php?subset=project

This is the relevant error reported by VirtualBox.

VBoxManage.exe: error: Cannot register the hard disk 'E:\Boinc\Project Data\projects\lhcathome.cern.ch_lhcathome\CMS_2022_09_07_prod.vdi' {dae25e8f-de18-4971-b11c-eca764ede402} because a hard disk 'C:\ProgramData\BOINC\projects\lhcathome.cern.ch_lhcathome\CMS_2022_09_07_prod.vdi' with UUID {dae25e8f-de18-4971-b11c-eca764ede402} already exists

This means:
Sometime in the past the VirtualBox media registry wasn't cleaned up (for an unknown reason; those logs are not available any more).
You will have to remove the old entry manually:
1. Pause all VM tasks that are not yet started
2. Let all running VM tasks finish
3. Stop BOINC
4. Start the VirtualBox Media Manager via the VirtualBox GUI
5. Remove the disk entry pointing to "CMS_2022_09_07_prod.vdi" but do NOT remove the file itself if asked
6. Restart BOINC
7. Resume 1 VM task and wait a few seconds
8. Resume all other VM tasks

Sorry for the delay, COVID knocked me out for a couple days. This seems to have done the trick, I am currently running a task and everything looks as it should at the moment. I greatly appreciate the assistance.

To me this appears to be a case of the VM files being moved from "C:" to "E:" outside of the VM utilities. VB does not like that.

VirtualBox identifies a disk by UUID (here: dae25e8f-de18-4971-b11c-eca764ede402).
The vdi file contains that UUID.
A UUID that is already registered can't be registered again.
That's what happened here.

The path is not relevant as long as the access rights are correctly set.

Since I deleted that disk entry everything has been running as it should. Tasks have been launching, running, and completing in VM without an issue. I had one that failed since, but that coincided with a power failure during the computation so I am attributing it to that since all after have been successful. Thanks again!