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

Stderr output

<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