Name | CMS_948376_1742299066.320501_0 |
Workunit | 230883539 |
Created | 18 Mar 2025, 11:57:48 UTC |
Sent | 18 Mar 2025, 12:55:20 UTC |
Report deadline | 18 Apr 2025, 12:55:20 UTC |
Received | 19 Mar 2025, 1:37:23 UTC |
Server state | Over |
Outcome | Computation error |
Client state | Compute error |
Exit status | -182 (0xFFFFFF4A) ERR_TIMEOUT |
Computer ID | 10870575 |
Run time | 5 hours 56 min 13 sec |
CPU time | 13 hours 26 min 15 sec |
Validate state | Invalid |
Credit | 0.00 |
Device peak FLOPS | 20.37 GFLOPS |
Application version | CMS Simulation v70.60 (vbox64_mt_mcore_cms) windows_x86_64 |
Peak working set size | 78.12 MB |
Peak swap size | 66.98 MB |
Peak disk usage | 1.63 GB |
<core_client_version>8.0.2</core_client_version> <![CDATA[ <message> (unknown error) (317) - exit code 4294967114 (0xffffff4a)</message> <stderr_txt> 2025-03-18 15:29:37 (2016692): vboxwrapper version 26208 2025-03-18 15:29:37 (2016692): BOINC client version: 8.0.2 2025-03-18 15:29:37 (2016692): Detected: VirtualBox VboxManage Interface (Version: 7.1.6) 2025-03-18 15:29:38 (2016692): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-03-18 15:29:38 (2016692): Successfully copied 'init_data.xml' to the shared directory. 2025-03-18 15:29:38 (2016692): Create VM. (boinc_15f2295477a96407, slot#2) 2025-03-18 15:29:38 (2016692): Setting Memory Size for VM. (4584MB) 2025-03-18 15:29:39 (2016692): Setting CPU Count for VM. (4) 2025-03-18 15:29:39 (2016692): Setting Chipset Options for VM. 2025-03-18 15:29:39 (2016692): Setting Graphics Controller Options for VM. 2025-03-18 15:29:39 (2016692): Setting Boot Options for VM. 2025-03-18 15:29:40 (2016692): Setting Network Configuration for NAT. 2025-03-18 15:29:40 (2016692): Enabling VM Network Access. 2025-03-18 15:29:40 (2016692): Disabling USB Support for VM. 2025-03-18 15:29:40 (2016692): Disabling COM Port Support for VM. 2025-03-18 15:29:41 (2016692): Disabling LPT Port Support for VM. 2025-03-18 15:29:41 (2016692): Disabling Audio Support for VM. 2025-03-18 15:29:41 (2016692): Disabling Clipboard Support for VM. 2025-03-18 15:29:41 (2016692): Disabling Drag and Drop Support for VM. 2025-03-18 15:29:42 (2016692): Adding storage controller(s) to VM. 2025-03-18 15:29:42 (2016692): Adding virtual disk drive to VM. (CMS_2025_01_16_prod.vdi) 2025-03-18 15:29:42 (2016692): Adding VirtualBox Guest Additions to VM. 2025-03-18 15:29:43 (2016692): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB) 2025-03-18 15:29:43 (2016692): forwarding host port 58787 to guest port 80 2025-03-18 15:29:43 (2016692): Enabling remote desktop for VM. 2025-03-18 15:29:43 (2016692): Required extension pack not installed, remote desktop not enabled. 2025-03-18 15:29:43 (2016692): Enabling shared directory for VM. 2025-03-18 15:29:44 (2016692): Starting VM using VBoxManage interface. (boinc_15f2295477a96407, slot#2) 2025-03-18 15:29:51 (2016692): Successfully started VM. (PID = '2016276') 2025-03-18 15:29:51 (2016692): Reporting VM Process ID to BOINC. 2025-03-18 15:29:51 (2016692): Guest Log: BIOS: VirtualBox 7.1.6 2025-03-18 15:29:51 (2016692): Guest Log: CPUID EDX: 0x178bfbff 2025-03-18 15:29:51 (2016692): Guest Log: BIOS: No PCI IDE controller, not probing IDE 2025-03-18 15:29:51 (2016692): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 2025-03-18 15:29:51 (2016692): VM state change detected. (old = 'poweredoff', new = 'running') 2025-03-18 15:29:51 (2016692): Detected: Web Application Enabled (http://localhost:58787) 2025-03-18 15:29:51 (2016692): Preference change detected 2025-03-18 15:29:51 (2016692): Setting CPU throttle for VM. (50%) 2025-03-18 15:29:51 (2016692): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-03-18 15:29:53 (2016692): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 2025-03-18 15:29:53 (2016692): Guest Log: BIOS: Booting from Hard Disk... 2025-03-18 15:29:55 (2016692): Guest Log: BIOS: KBD: unsupported int 16h function 03 2025-03-18 15:29:55 (2016692): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 2025-03-18 15:30:29 (2016692): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 2025-03-18 15:30:29 (2016692): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 2025-03-18 15:30:30 (2016692): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 2025-03-18 15:30:30 (2016692): Guest Log: 00:00:00.000075 main Log opened 2025-03-18T19:30:30.479216000Z 2025-03-18 15:30:30 (2016692): Guest Log: 00:00:00.000179 main OS Product: Linux 2025-03-18 15:30:30 (2016692): Guest Log: 00:00:00.000209 main OS Release: 4.14.232-19.cernvm.x86_64 2025-03-18 15:30:30 (2016692): Guest Log: 00:00:00.000230 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 2025-03-18 15:30:30 (2016692): Guest Log: 00:00:00.000245 main Executable: /usr/sbin/VBoxService 2025-03-18 15:30:30 (2016692): Guest Log: 00:00:00.000245 main Process ID: 2291 2025-03-18 15:30:30 (2016692): Guest Log: 00:00:00.000245 main Package type: LINUX_64BITS_GENERIC 2025-03-18 15:30:30 (2016692): Guest Log: 00:00:00.001326 main 5.2.6 r120293 started. Verbose level = 0 2025-03-18 15:30:52 (2016692): Guest Log: [INFO] Mounting the shared directory 2025-03-18 15:30:53 (2016692): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 2025-03-18 15:30:53 (2016692): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 2025-03-18 15:30:53 (2016692): Guest Log: [INFO] Testing connection to cern.ch 2025-03-18 15:30:54 (2016692): Guest Log: [INFO] Testing connection to VCCS 2025-03-18 15:30:54 (2016692): Guest Log: [INFO] Testing connection to HTCondor 2025-03-18 15:30:55 (2016692): Guest Log: [INFO] Testing connection to WMAgent 2025-03-18 15:30:55 (2016692): Guest Log: [INFO] Testing connection to EOSCMS 2025-03-18 15:30:56 (2016692): Guest Log: [INFO] Testing connection to CMS-Factory 2025-03-18 15:30:56 (2016692): Guest Log: [INFO] Testing connection to CMS-Frontier 2025-03-18 15:30:57 (2016692): Guest Log: [INFO] Testing connection to Frontier 2025-03-18 15:30:58 (2016692): Guest Log: [INFO] Could not find a local HTTP proxy 2025-03-18 15:30:58 (2016692): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 2025-03-18 15:30:58 (2016692): Guest Log: [INFO] This makes the application less efficient 2025-03-18 15:30:58 (2016692): Guest Log: [INFO] It also puts higher load on the project servers 2025-03-18 15:30:58 (2016692): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 2025-03-18 15:30:58 (2016692): Guest Log: [INFO] Advice can be found in the project forum 2025-03-18 15:31:01 (2016692): Guest Log: [INFO] Reloading and probing the CVMFS configuration 2025-03-18 15:31:35 (2016692): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 2025-03-18 15:31:38 (2016692): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 2025-03-18 15:31:38 (2016692): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 2025-03-18 15:31:39 (2016692): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 2025-03-18 15:31:40 (2016692): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 2025-03-18 15:31:41 (2016692): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 2025-03-18 15:31:41 (2016692): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 2025-03-18 15:31:41 (2016692): Guest Log: [INFO] Environment HTTP proxy: not set 2025-03-18 15:31:41 (2016692): Guest Log: [INFO] Reading volunteer information 2025-03-18 15:32:33 (2016692): Guest Log: [INFO] Requesting an X509 credential from LHC@home 2025-03-18 15:32:35 (2016692): Guest Log: [INFO] Requesting an idtoken from LHC@home 2025-03-18 15:32:36 (2016692): Guest Log: [INFO] CMS application starting. Check log files. 2025-03-18 17:10:05 (2016692): Status Report: Job Duration: '64800.000000' 2025-03-18 17:10:05 (2016692): Status Report: Elapsed Time: '6000.627863' 2025-03-18 17:10:05 (2016692): Status Report: CPU Time: '11567.328125' 2025-03-18 18:50:21 (2016692): Status Report: Job Duration: '64800.000000' 2025-03-18 18:50:21 (2016692): Status Report: Elapsed Time: '12001.140779' 2025-03-18 18:50:21 (2016692): Status Report: CPU Time: '25055.562500' 2025-03-18 20:30:35 (2016692): Status Report: Job Duration: '64800.000000' 2025-03-18 20:30:35 (2016692): Status Report: Elapsed Time: '18001.700163' 2025-03-18 20:30:35 (2016692): Status Report: CPU Time: '39520.875000' 2025-03-18 21:17:11 (2016692): Preference change detected 2025-03-18 21:17:11 (2016692): Setting CPU throttle for VM. (100%) 2025-03-18 21:17:11 (2016692): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-03-18 21:18:09 (2016692): Preference change detected 2025-03-18 21:18:09 (2016692): Setting CPU throttle for VM. (100%) 2025-03-18 21:18:09 (2016692): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds)) 2025-03-18 21:36:23 (20744): vboxwrapper version 26208 2025-03-18 21:36:23 (20744): BOINC client version: 8.0.2 2025-03-18 21:36:24 (20744): Detected: VirtualBox VboxManage Interface (Version: 7.1.6) 2025-03-18 21:36:24 (20744): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds) 2025-03-18 21:36:24 (20744): Register VM. (boinc_15f2295477a96407, slot#2) 2025-03-18 21:36:31 (20744): Error in register for VM: -182 Command: VBoxManage -q registervm "C:\ProgramData\BOINC\slots\2/boinc_15f2295477a96407/boinc_15f2295477a96407.vbox" Output: VBoxManage.exe: error: Trying to open a VM config 'C:\ProgramData\BOINC\slots\2/boinc_15f2295477a96407/boinc_15f2295477a96407.vbox' which has the same UUID as an existing virtual machine VBoxManage.exe: error: Details: code E_FAIL (0x80004005), component MachineWrap, interface IMachine, callee IUnknown VBoxManage.exe: error: Context: "OpenMachine(Bstr(a->argv[0]).raw(), Bstr(strPassword).raw(), machine.asOutParam()))" at line 159 of file VBoxManageMisc.cpp 2025-03-18 21:36:31 (20744): Could not register 2025-03-18 21:36:31 (20744): ERROR: VM failed to start 2025-03-18 21:36:31 (20744): Powering off VM. 2025-03-18 21:36:31 (20744): Deregistering VM. (boinc_15f2295477a96407, slot#2) 2025-03-18 21:36:31 (20744): Removing network bandwidth throttle group from VM. 2025-03-18 21:36:31 (20744): Removing VM from VirtualBox. Hypervisor System Log: 00:00:07.858977 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.858981 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.858985 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.858989 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.858998 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.859004 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.859008 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.859012 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.859017 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.859021 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.859025 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.859029 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.859033 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={d644ad1e-c501-4fc7-9ab6-aa6d763bc540} aComponent={VirtualBoxWrap} aText={Could not find a registered machine named 'boinc_eeeff1bdd777758d'}, preserve=false aResultDetail=0 00:00:07.884428 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.884451 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.884457 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.884462 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.884466 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.884470 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.884474 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.884480 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.884484 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.884488 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.884495 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.884502 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.884506 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.884510 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.884514 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0 00:00:07.884518 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={d644ad1e-c501-4fc7-9ab6-aa6d763bc540} aComponent={VirtualBoxWrap} aText={Could not find a registered machine named 'boinc_15f2295477a96407'}, preserve=false aResultDetail=0 00:00:11.883278 USBPROXY usbLibGetDevices: Starting USB device enumeration 00:00:11.932678 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#VID_059F&PID_10D4#7&d3ed8ee&0&3#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 5 00:00:11.932765 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#VID_059F&PID_10D4#7&d3ed8ee&0&4#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 5 00:00:11.934630 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&cdbaec3&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 11 00:00:11.934670 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&cdbaec3&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 14 00:00:11.972893 USBPROXY Getting USB descriptor (id 3) failed (dwErr=31) on hub USB#VID_059F&PID_10D3#7&1589afa&0&4#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 4 00:00:12.028712 USBPROXY usbLibGetDevices: Found 15 USB devices, 0 captured 00:00:13.455815 USBPROXY usbLibGetDevices: Starting USB device enumeration 00:00:13.505317 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#VID_059F&PID_10D4#7&d3ed8ee&0&3#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 5 00:00:13.505395 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#VID_059F&PID_10D4#7&d3ed8ee&0&4#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 5 00:00:13.507335 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&cdbaec3&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 11 00:00:13.507380 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&cdbaec3&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 14 00:00:13.581115 USBPROXY usbLibGetDevices: Found 14 USB devices, 0 captured VM Execution Log: 00:00:02.174221 PCI: Setting up resources and interrupts 00:00:02.174469 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0) 00:00:02.196905 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=0000000000000000 w=720 h=400 bpp=0 cbLine=0x0 flags=0x0 origin=0,0 00:00:02.197053 VMMDev: Guest Log: CPUID EDX: 0x178bfbff 00:00:02.197324 VMMDev: Guest Log: BIOS: No PCI IDE controller, not probing IDE 00:00:02.197455 AHCI#0: Reset the HBA 00:00:02.197459 VD#0: Cancelling all active requests 00:00:02.197488 AHCI#0: Port 0 reset 00:00:02.197510 VD#0: Cancelling all active requests 00:00:02.197825 VMMDev: Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors 00:00:02.197853 AHCI#0: Port 1 reset 00:00:02.197910 AHCI#0: Port 2 reset 00:00:02.341196 PIT: mode=2 count=0x48d3 (18643) - 64.00 Hz (ch=0) 00:00:02.394172 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=0000019c5e7f0000 w=640 h=480 bpp=32 cbLine=0xA00 flags=0x0 origin=0,0 00:00:02.671717 VBoxHeadless: starting event loop 00:00:04.830859 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0) 00:00:04.831035 VMMDev: Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032 00:00:04.833065 VMMDev: Guest Log: BIOS: Booting from Hard Disk... 00:00:04.841892 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=0000000000000000 w=720 h=400 bpp=0 cbLine=0x0 flags=0x0 origin=0,0 00:00:04.886746 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=0000019c5e7f0000 w=640 h=480 bpp=24 cbLine=0x780 flags=0x0 origin=0,0 00:00:07.320214 VMMDev: Guest Log: BIOS: KBD: unsupported int 16h function 03 00:00:07.320380 VMMDev: Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 00:00:07.410771 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=0000000000000000 w=720 h=400 bpp=0 cbLine=0x0 flags=0x0 origin=0,0 00:00:08.436488 GIM: KVM: VCPU 0: Enabled system-time struct. at 0x000000013e3f7000 - u32TscScale=0xc587eb05 i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x3c8203753 uVirtNanoTS=0x17581662a TscKHz=2591998 00:00:08.436549 TM: Switching TSC mode from 'VirtTSCEmulated' to 'RealTSCOffset' 00:00:09.116634 GIM: KVM: Enabled wall-clock struct. at 0x0000000002b40348 - u32Sec=1742326197 u32Nano=9831400 uVersion=2 00:00:09.148800 PIT: mode=2 count=0x4a9 (1193) - 1000.15 Hz (ch=0) 00:00:09.279230 PIT: mode=0 count=0x10000 (65536) - 18.20 Hz (ch=0) 00:00:09.279672 GIM: KVM: VCPU 1: Enabled system-time struct. at 0x000000013e3f7040 - u32TscScale=0xc587eb05 i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x44a66f089 uVirtNanoTS=0x1a7c431e0 TscKHz=2591998 00:00:09.282126 GIM: KVM: VCPU 2: Enabled system-time struct. at 0x000000013e3f7080 - u32TscScale=0xc587eb05 i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x44ac810d0 uVirtNanoTS=0x1a7e9aa94 TscKHz=2591998 00:00:09.284635 GIM: KVM: VCPU 3: Enabled system-time struct. at 0x000000013e3f70c0 - u32TscScale=0xc587eb05 i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x44b2b643b uVirtNanoTS=0x1a80ffc8a TscKHz=2591998 00:00:11.395836 AHCI#0: Reset the HBA 00:00:11.395869 VD#0: Cancelling all active requests 00:00:11.396761 AHCI#0: Port 0 reset 00:00:11.397951 VD#0: Cancelling all active requests 00:00:11.401686 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=0000019c5e7f0000 w=800 h=600 bpp=32 cbLine=0xC80 flags=0x1 origin=0,0 00:00:12.140024 AHCI#0: Port 1 reset 00:00:33.849568 NAT: Link up 00:00:35.677626 AIOMgr: Async flushes not supported 00:00:41.465713 VMMDev: Guest Additions information report: Version 5.2.6 r120293 '5.2.6' 00:00:41.465759 VMMDev: Guest Additions information report: Interface = 0x00010004 osType = 0x00053100 (Linux >= 2.6, 64-bit) 00:00:41.465844 VMMDev: Guest Additions capability report: (0x0 -> 0x0) seamless: no, hostWindowMapping: no, graphics: no 00:00:41.465910 VMMDev: vmmDevReqHandler_HeartbeatConfigure: No change (fHeartbeatActive=false) 00:00:41.465922 VMMDev: Heartbeat flatline timer set to trigger after 4 000 000 000 ns 00:00:41.465953 VMMDev: Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds 00:00:41.466358 VMMDev: Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000) 00:00:42.141600 VMMDev: Guest Additions capability report: (0x0 -> 0x0) seamless: no, hostWindowMapping: no, graphics: no 00:00:42.226603 VMMDev: Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log 00:00:42.226630 VMMDev: Guest Log: 00:00:00.000075 main Log opened 2025-03-18T19:30:30.479216000Z 00:00:42.226689 VMMDev: Guest Log: 00:00:00.000179 main OS Product: Linux 00:00:42.226717 VMMDev: Guest Log: 00:00:00.000209 main OS Release: 4.14.232-19.cernvm.x86_64 00:00:42.226738 VMMDev: Guest Log: 00:00:00.000230 main OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021 00:00:42.226753 VMMDev: Guest Log: 00:00:00.000245 main Executable: /usr/sbin/VBoxService 00:00:42.226756 VMMDev: Guest Log: 00:00:00.000245 main Process ID: 2291 00:00:42.226758 VMMDev: Guest Log: 00:00:00.000245 main Package type: LINUX_64BITS_GENERIC 00:00:42.227838 VMMDev: Guest Log: 00:00:00.001326 main 5.2.6 r120293 started. Verbose level = 0 00:01:04.507269 VMMDev: Guest Log: [INFO] Mounting the shared directory 00:01:04.642424 VMMDev: Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor 00:01:04.749263 VMMDev: Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch 00:01:05.412299 VMMDev: Guest Log: [INFO] Testing connection to cern.ch 00:01:05.896221 VMMDev: Guest Log: [INFO] Testing connection to VCCS 00:01:06.330447 VMMDev: Guest Log: [INFO] Testing connection to HTCondor 00:01:06.742318 VMMDev: Guest Log: [INFO] Testing connection to WMAgent 00:01:07.234333 VMMDev: Guest Log: [INFO] Testing connection to EOSCMS 00:01:07.769908 VMMDev: Guest Log: [INFO] Testing connection to CMS-Factory 00:01:08.301777 VMMDev: Guest Log: [INFO] Testing connection to CMS-Frontier 00:01:08.835564 VMMDev: Guest Log: [INFO] Testing connection to Frontier 00:01:09.879481 VMMDev: Guest Log: [INFO] Could not find a local HTTP proxy 00:01:09.935278 VMMDev: Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections 00:01:09.989760 VMMDev: Guest Log: [INFO] This makes the application less efficient 00:01:10.085216 VMMDev: Guest Log: [INFO] It also puts higher load on the project servers 00:01:10.139278 VMMDev: Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended 00:01:10.210619 VMMDev: Guest Log: [INFO] Advice can be found in the project forum 00:01:12.777817 VMMDev: Guest Log: [INFO] Reloading and probing the CVMFS configuration 00:01:46.908110 VMMDev: Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK 00:01:50.265402 VMMDev: Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK 00:01:50.325121 VMMDev: Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK 00:01:51.335618 VMMDev: Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK 00:01:51.985710 VMMDev: Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK 00:01:52.932004 VMMDev: Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY 00:01:52.982725 VMMDev: Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT 00:01:53.070633 VMMDev: Guest Log: [INFO] Environment HTTP proxy: not set 00:01:53.174141 VMMDev: Guest Log: [INFO] Reading volunteer information 00:02:45.323754 VMMDev: Guest Log: [INFO] Requesting an X509 credential from LHC@home 00:02:46.874065 VMMDev: Guest Log: [INFO] Requesting an idtoken from LHC@home 00:02:48.479669 VMMDev: Guest Log: [INFO] CMS application starting. Check log files. 01:05:35.998237 AssertLogRel D:\tinderboxa\win-7.1\src\VBox\Runtime\win\RTErrConvertFromWin32.cpp(465) int __cdecl RTErrConvertFromWin32(unsigned int): <NULL> 01:05:36.001434 Unhandled error 1453 01:05:36.001667 AIO/win: Request 0x00019c5be16480 returned rc=VERR_UNRESOLVED_ERROR (native 1453 01:05:36.001880 )AioMgr0-N: Request 0x00019c5c129a40 failed with rc=VERR_UNRESOLVED_ERROR, migrating endpoint C:\ProgramData\BOINC\slots\2\boinc_15f2295477a96407\Snapshots\{d1cfb2a9-4744-4507-8585-76ec0efe7537}.vdi to failsafe manager. VM Startup Log: VM Trace Log: lers: 1 Maximum PIIX3 Floppy Controllers:1 ICH9 chipset properties: Maximum ICH9 Network Adapter count: 36 Maximum ICH9 IDE Controllers: 1 Maximum ICH9 SATA Controllers: 8 Maximum ICH9 SCSI Controllers: 8 Maximum ICH9 SAS Controllers: 8 Maximum ICH9 NVMe Controllers: 8 Maximum ICH9 virtio-scsi Controllers: 8 Maximum ICH9 Floppy Controllers:1 2025-03-18 21:36:24 (20744): Command: VBoxManage -q list hostinfo Exit Code: 0 Output: Host Information: Host time: 2025-03-19T01:36:24.216000000Z Processor online count: 12 Processor count: 12 Processor online core count: 6 Processor core count: 6 Processor supports HW virtualization: yes Processor supports PAE: yes Processor supports long mode: yes Processor supports nested paging: yes Processor supports unrestricted guest: yes Processor supports nested HW virtualization: no Processor supports virt. vmsave/vmload: no Processor#0 speed: unknown Processor#0 description: 11th Gen Intel(R) Core(TM) i5-11400F @ 2.60GHz Processor#1 speed: unknown Processor#1 description: 11th Gen Intel(R) Core(TM) i5-11400F @ 2.60GHz Processor#2 speed: unknown Processor#2 description: 11th Gen Intel(R) Core(TM) i5-11400F @ 2.60GHz Processor#3 speed: unknown Processor#3 description: 11th Gen Intel(R) Core(TM) i5-11400F @ 2.60GHz Processor#4 speed: unknown Processor#4 description: 11th Gen Intel(R) Core(TM) i5-11400F @ 2.60GHz Processor#5 speed: unknown Processor#5 description: 11th Gen Intel(R) Core(TM) i5-11400F @ 2.60GHz Processor#6 speed: unknown Processor#6 description: 11th Gen Intel(R) Core(TM) i5-11400F @ 2.60GHz Processor#7 speed: unknown Processor#7 description: 11th Gen Intel(R) Core(TM) i5-11400F @ 2.60GHz Processor#8 speed: unknown Processor#8 description: 11th Gen Intel(R) Core(TM) i5-11400F @ 2.60GHz Processor#9 speed: unknown Processor#9 description: 11th Gen Intel(R) Core(TM) i5-11400F @ 2.60GHz Processor#10 speed: unknown Processor#10 description: 11th Gen Intel(R) Core(TM) i5-11400F @ 2.60GHz Processor#11 speed: unknown Processor#11 description: 11th Gen Intel(R) Core(TM) i5-11400F @ 2.60GHz Memory size: 16250 MByte Memory available: 10664 MByte Operating system: Windows 11 Operating system version: 10.0.26100 2025-03-18 21:36:24 (20744): Command: VBoxManage -q showvminfo "boinc_15f2295477a96407" --machinereadable Exit Code: -2135228415 Output: VBoxManage.exe: error: Could not find a registered machine named 'boinc_15f2295477a96407' VBoxManage.exe: error: Details: code VBOX_E_OBJECT_NOT_FOUND (0x80bb0001), component VirtualBoxWrap, interface IVirtualBox, callee IUnknown VBoxManage.exe: error: Context: "FindMachine(Bstr(VMNameOrUuid).raw(), machine.asOutParam())" at line 3219 of file VBoxManageInfo.cpp 2025-03-18 21:36:25 (20744): Command: VBoxManage -q registervm "C:\ProgramData\BOINC\slots\2/boinc_15f2295477a96407/boinc_15f2295477a96407.vbox" Exit Code: -2147467259 Output: VBoxManage.exe: error: Trying to open a VM config 'C:\ProgramData\BOINC\slots\2/boinc_15f2295477a96407/boinc_15f2295477a96407.vbox' which has the same UUID as an existing virtual machine VBoxManage.exe: error: Details: code E_FAIL (0x80004005), component MachineWrap, interface IMachine, callee IUnknown VBoxManage.exe: error: Context: "OpenMachine(Bstr(a->argv[0]).raw(), Bstr(strPassword).raw(), machine.asOutParam()))" at line 159 of file VBoxManageMisc.cpp 2025-03-18 21:36:26 (20744): Command: VBoxManage -q registervm "C:\ProgramData\BOINC\slots\2/boinc_15f2295477a96407/boinc_15f2295477a96407.vbox" Exit Code: -2147467259 Output: VBoxManage.exe: error: Trying to open a VM config 'C:\ProgramData\BOINC\slots\2/boinc_15f2295477a96407/boinc_15f2295477a96407.vbox' which has the same UUID as an existing virtual machine VBoxManage.exe: error: Details: code E_FAIL (0x80004005), component MachineWrap, interface IMachine, callee IUnknown VBoxManage.exe: error: Context: "OpenMachine(Bstr(a->argv[0]).raw(), Bstr(strPassword).raw(), machine.asOutParam()))" at line 159 of file VBoxManageMisc.cpp 2025-03-18 21:36:27 (20744): Command: VBoxManage -q registervm "C:\ProgramData\BOINC\slots\2/boinc_15f2295477a96407/boinc_15f2295477a96407.vbox" Exit Code: -2147467259 Output: VBoxManage.exe: error: Trying to open a VM config 'C:\ProgramData\BOINC\slots\2/boinc_15f2295477a96407/boinc_15f2295477a96407.vbox' which has the same UUID as an existing virtual machine VBoxManage.exe: error: Details: code E_FAIL (0x80004005), component MachineWrap, interface IMachine, callee IUnknown VBoxManage.exe: error: Context: "OpenMachine(Bstr(a->argv[0]).raw(), Bstr(strPassword).raw(), machine.asOutParam()))" at line 159 of file VBoxManageMisc.cpp 2025-03-18 21:36:28 (20744): Command: VBoxManage -q registervm "C:\ProgramData\BOINC\slots\2/boinc_15f2295477a96407/boinc_15f2295477a96407.vbox" Exit Code: -2147467259 Output: VBoxManage.exe: error: Trying to open a VM config 'C:\ProgramData\BOINC\slots\2/boinc_15f2295477a96407/boinc_15f2295477a96407.vbox' which has the same UUID as an existing virtual machine VBoxManage.exe: error: Details: code E_FAIL (0x80004005), component MachineWrap, interface IMachine, callee IUnknown VBoxManage.exe: error: Context: "OpenMachine(Bstr(a->argv[0]).raw(), Bstr(strPassword).raw(), machine.asOutParam()))" at line 159 of file VBoxManageMisc.cpp 2025-03-18 21:36:30 (20744): Command: VBoxManage -q registervm "C:\ProgramData\BOINC\slots\2/boinc_15f2295477a96407/boinc_15f2295477a96407.vbox" Exit Code: -2147467259 Output: VBoxManage.exe: error: Trying to open a VM config 'C:\ProgramData\BOINC\slots\2/boinc_15f2295477a96407/boinc_15f2295477a96407.vbox' which has the same UUID as an existing virtual machine VBoxManage.exe: error: Details: code E_FAIL (0x80004005), component MachineWrap, interface IMachine, callee IUnknown VBoxManage.exe: error: Context: "OpenMachine(Bstr(a->argv[0]).raw(), Bstr(strPassword).raw(), machine.asOutParam()))" at line 159 of file VBoxManageMisc.cpp 2025-03-18 21:36:31 (20744): Command: VBoxManage -q registervm "C:\ProgramData\BOINC\slots\2/boinc_15f2295477a96407/boinc_15f2295477a96407.vbox" Exit Code: -2147467259 Output: VBoxManage.exe: error: Trying to open a VM config 'C:\ProgramData\BOINC\slots\2/boinc_15f2295477a96407/boinc_15f2295477a96407.vbox' which has the same UUID as an existing virtual machine VBoxManage.exe: error: Details: code E_FAIL (0x80004005), component MachineWrap, interface IMachine, callee IUnknown VBoxManage.exe: error: Context: "OpenMachine(Bstr(a->argv[0]).raw(), Bstr(strPassword).raw(), machine.asOutParam()))" at line 159 of file VBoxManageMisc.cpp 2025-03-18 21:36:31 (20744): Command: VBoxManage -q snapshot "boinc_15f2295477a96407" list Exit Code: -2135228415 Output: VBoxManage.exe: error: Could not find a registered machine named 'boinc_15f2295477a96407' VBoxManage.exe: error: Details: code VBOX_E_OBJECT_NOT_FOUND (0x80bb0001), component VirtualBoxWrap, interface IVirtualBox, callee IUnknown VBoxManage.exe: error: Context: "FindMachine(bstrMachine.raw(), pMachine.asOutParam())" at line 345 of file VBoxManageSnapshot.cpp 2025-03-18 21:36:31 (20744): Command: VBoxManage -q bandwidthctl "boinc_15f2295477a96407" remove "boinc_15f2295477a96407_net" Exit Code: -2135228415 Output: VBoxManage.exe: error: Could not find a registered machine named 'boinc_15f2295477a96407' VBoxManage.exe: error: Details: code VBOX_E_OBJECT_NOT_FOUND (0x80bb0001), component VirtualBoxWrap, interface IVirtualBox, callee IUnknown VBoxManage.exe: error: Context: "FindMachine(Bstr(a->argv[0]).raw(), machine.asOutParam())" at line 334 of file VBoxManageBandwidthControl.cpp 2025-03-18 21:36:31 (20744): Command: VBoxManage -q unregistervm "boinc_15f2295477a96407" --delete Exit Code: -2135228415 Output: VBoxManage.exe: error: Could not find a registered machine named 'boinc_15f2295477a96407' VBoxManage.exe: error: Details: code VBOX_E_OBJECT_NOT_FOUND (0x80bb0001), component VirtualBoxWrap, interface IVirtualBox, callee IUnknown VBoxManage.exe: error: Context: "FindMachine(Bstr(VMName).raw(), machine.asOutParam())" at line 230 of file VBoxManageMisc.cpp 2025-03-18 21:36:41 (20744): called boinc_finish(-182) </stderr_txt> ]]>
©2025 CERN