Stderr output

<core_client_version>8.2.4</core_client_version>
<![CDATA[
<message>
(unknown error) (317) - exit code 4294967114 (0xffffff4a)</message>
<stderr_txt>
2025-11-15 11:56:07 (2408): vboxwrapper version 26210
2025-11-15 11:56:07 (2408): BOINC client version: 8.2.4
2025-11-15 11:56:08 (2408): Detected: VirtualBox VboxManage Interface (Version: 7.1.12)
2025-11-15 11:56:08 (2408): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2025-11-15 11:56:08 (2408): Successfully copied 'init_data.xml' to the shared directory.
2025-11-15 11:56:08 (2408): Create VM. (boinc_8dbc715f5e91e7a0, slot#0)
2025-11-15 11:56:09 (2408): Setting Memory Size for VM. (4584MB)
2025-11-15 11:56:09 (2408): Setting CPU Count for VM. (4)
2025-11-15 11:56:09 (2408): Setting Chipset Options for VM.
2025-11-15 11:56:10 (2408): Setting Graphics Controller Options for VM. (Driver: VBoxVGA, 16MB)
2025-11-15 11:56:10 (2408): Setting Boot Options for VM.
2025-11-15 11:56:11 (2408): Setting Network Configuration for NAT. (Driver: virtio)
2025-11-15 11:56:11 (2408): Enabling VM Network Access.
2025-11-15 11:56:11 (2408): Disabling USB Support for VM.
2025-11-15 11:56:11 (2408): Disabling COM Port Support for VM.
2025-11-15 11:56:12 (2408): Disabling LPT Port Support for VM.
2025-11-15 11:56:12 (2408): Disabling Audio Support for VM.
2025-11-15 11:56:12 (2408): Disabling Clipboard Support for VM.
2025-11-15 11:56:12 (2408): Disabling Drag and Drop Support for VM.
2025-11-15 11:56:13 (2408): Adding storage controller(s) to VM.
2025-11-15 11:56:13 (2408): Adding virtual disk drive to VM. (CMS_2025_04_08_prod.vdi)
2025-11-15 11:56:13 (2408): Adding VirtualBox Guest Additions to VM.
2025-11-15 11:56:14 (2408): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2025-11-15 11:56:14 (2408): forwarding host port 60356 to guest port 80
2025-11-15 11:56:14 (2408): Enabling remote desktop for VM.
2025-11-15 11:56:15 (2408): Enabling shared directory for VM.
2025-11-15 11:56:15 (2408): Starting VM using VBoxManage interface. (boinc_8dbc715f5e91e7a0, slot#0)
2025-11-15 11:56:21 (2408): Successfully started VM. (PID = '25940')
2025-11-15 11:56:21 (2408): Reporting VM Process ID to BOINC.
2025-11-15 11:56:21 (2408): Guest Log: BIOS: VirtualBox 7.1.12
2025-11-15 11:56:21 (2408): Guest Log: CPUID EDX: 0x178bfbff
2025-11-15 11:56:21 (2408): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-11-15 11:56:21 (2408): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-11-15 11:56:21 (2408): VM state change detected. (old = 'poweredoff', new = 'running')
2025-11-15 11:56:21 (2408): Detected: Web Application Enabled (http://localhost:60356)
2025-11-15 11:56:21 (2408): Detected: Remote Desktop Enabled (localhost:60357)
2025-11-15 11:56:21 (2408): Preference change detected
2025-11-15 11:56:21 (2408): Setting CPU throttle for VM. (100%)
2025-11-15 11:56:21 (2408): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2025-11-15 11:56:23 (2408): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-11-15 11:56:23 (2408): Guest Log: BIOS: Booting from Hard Disk...
2025-11-15 11:56:25 (2408): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-11-15 11:56:25 (2408): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2025-11-15 11:56:48 (2408): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-11-15 11:56:48 (2408): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-11-15 11:56:49 (2408): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-11-15 11:56:49 (2408): Guest Log: 00:00:00.000100 main     Log opened 2025-11-15T11:56:49.069855000Z
2025-11-15 11:56:49 (2408): Guest Log: 00:00:00.000258 main     OS Product: Linux
2025-11-15 11:56:49 (2408): Guest Log: 00:00:00.000295 main     OS Release: 4.14.232-19.cernvm.x86_64
2025-11-15 11:56:49 (2408): Guest Log: 00:00:00.000325 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2025-11-15 11:56:49 (2408): Guest Log: 00:00:00.000353 main     Executable: /usr/sbin/VBoxService
2025-11-15 11:56:49 (2408): Guest Log: 00:00:00.000353 main     Process ID: 2291
2025-11-15 11:56:49 (2408): Guest Log: 00:00:00.000354 main     Package type: LINUX_64BITS_GENERIC
2025-11-15 11:56:49 (2408): Guest Log: 00:00:00.001114 main     5.2.6 r120293 started. Verbose level = 0
2025-11-15 11:57:02 (2408): Guest Log: [INFO] Mounting the shared directory
2025-11-15 11:57:03 (2408): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2025-11-15 11:57:03 (2408): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2025-11-15 11:57:03 (2408): Guest Log: [INFO] Testing connection to cern.ch
2025-11-15 11:57:03 (2408): Guest Log: [INFO] Testing connection to VCCS
2025-11-15 11:57:03 (2408): Guest Log: [INFO] Testing connection to HTCondor-Collector
2025-11-15 11:57:03 (2408): Guest Log: [INFO] Testing connection to HTCondor-CCB
2025-11-15 11:57:03 (2408): Guest Log: [INFO] Testing connection to WMAgent
2025-11-15 11:57:04 (2408): Guest Log: [INFO] Testing connection to EOSCMS
2025-11-15 11:57:04 (2408): Guest Log: [INFO] Testing connection to CMS-Factory
2025-11-15 11:57:04 (2408): Guest Log: [INFO] Testing connection to CMS-Frontier
2025-11-15 11:57:04 (2408): Guest Log: [INFO] Testing connection to Frontier
2025-11-15 11:57:04 (2408): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/
2025-11-15 11:57:06 (2408): Guest Log: [INFO] Got a proxy from the local BOINC client
2025-11-15 11:57:07 (2408): Guest Log: [INFO] Will use it for CVMFS and Frontier
2025-11-15 11:57:07 (2408): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2025-11-15 11:57:13 (2408): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2025-11-15 11:57:15 (2408): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2025-11-15 11:57:16 (2408): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2025-11-15 11:57:16 (2408): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2025-11-15 11:57:16 (2408): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2025-11-15 11:57:16 (2408): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2025-11-15 11:57:16 (2408): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io http://192.168.1.130:3128
2025-11-15 11:57:17 (2408): Guest Log: [INFO] Environment HTTP proxy: http://192.168.1.130:3128
2025-11-15 11:57:17 (2408): Guest Log: [INFO] Reading volunteer information
2025-11-15 11:57:21 (2408): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2025-11-15 11:57:22 (2408): Guest Log: [INFO] Requesting an idtoken from LHC@home
2025-11-15 11:57:23 (2408): Guest Log: [INFO] CMS application starting. Check log files.
2025-11-15 13:29:46 (2408): VM Heartbeat file specified, but missing heartbeat.
2025-11-15 13:29:46 (2408): Powering off VM.
2025-11-15 13:30:32 (2408): Error in poweroff VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_8dbc715f5e91e7a0" poweroff
Output:
0%...10%...20%...
2025-11-15 13:30:32 (2408): VM did not power off when requested.
2025-11-15 13:30:32 (2408): VM was NOT successfully terminated.
2025-11-15 13:30:32 (2408): Deregistering VM. (boinc_8dbc715f5e91e7a0, slot#0)
2025-11-15 13:30:32 (2408): Removing network bandwidth throttle group from VM.
2025-11-15 13:30:32 (2408): Removing VM from VirtualBox.

    Hypervisor System Log:

65:35:43.449525 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#VID_2109&PID_2812#5&2a753ff6&0&5#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 4
65:35:43.453726 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&143f169d&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 13
65:35:43.453865 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&143f169d&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 14
65:35:43.454442 USBPROXY usbLibGetDevices: Found 5 USB devices, 0 captured
65:42:50.000541 USBPROXY usbLibGetDevices: Starting USB device enumeration
65:42:50.001134 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#VID_2109&PID_2812#5&2a753ff6&0&5#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 4
65:42:50.004643 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&143f169d&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 13
65:42:50.004738 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&143f169d&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 14
65:42:50.005276 USBPROXY usbLibGetDevices: Found 5 USB devices, 0 captured
65:42:51.474628 USBPROXY usbLibGetDevices: Starting USB device enumeration
65:42:51.475230 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#VID_2109&PID_2812#5&2a753ff6&0&5#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 4
65:42:51.479211 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&143f169d&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 13
65:42:51.479293 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&143f169d&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 14
65:42:51.479816 USBPROXY usbLibGetDevices: Found 5 USB devices, 0 captured
65:42:52.470074 USBPROXY usbLibGetDevices: Starting USB device enumeration
65:42:52.470577 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#VID_2109&PID_2812#5&2a753ff6&0&5#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 4
65:42:52.473896 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&143f169d&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 13
65:42:52.473958 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&143f169d&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 14
65:42:52.474518 USBPROXY usbLibGetDevices: Found 5 USB devices, 0 captured
65:44:27.992709 USBPROXY usbLibGetDevices: Starting USB device enumeration
65:44:27.993336 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#VID_2109&PID_2812#5&2a753ff6&0&5#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 4
65:44:27.996675 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&143f169d&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 13
65:44:27.996779 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&143f169d&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 14
65:44:27.997333 USBPROXY usbLibGetDevices: Found 5 USB devices, 0 captured
65:44:52.653015 USBPROXY usbLibGetDevices: Starting USB device enumeration
65:44:52.653559 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#VID_2109&PID_2812#5&2a753ff6&0&5#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 4
65:44:52.657027 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&143f169d&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 13
65:44:52.657112 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&143f169d&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 14
65:44:52.657657 USBPROXY usbLibGetDevices: Found 5 USB devices, 0 captured
65:50:32.027705 USBPROXY usbLibGetDevices: Starting USB device enumeration
65:50:32.028248 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#VID_2109&PID_2812#5&2a753ff6&0&5#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 4
65:50:32.031866 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&143f169d&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 13
65:50:32.031948 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&143f169d&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 14
65:50:32.032480 USBPROXY usbLibGetDevices: Found 5 USB devices, 0 captured
65:50:32.664405 USBPROXY usbLibGetDevices: Starting USB device enumeration
65:50:32.664912 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#VID_2109&PID_2812#5&2a753ff6&0&5#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 4
65:50:32.668245 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&143f169d&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 13
65:50:32.668326 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&143f169d&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 14
65:50:32.668867 USBPROXY usbLibGetDevices: Found 5 USB devices, 0 captured
65:54:05.768248 dns-monitor HostDnsMonitor: updating information
65:54:05.768281 dns-monitor HostDnsMonitor: unchanged
65:57:40.659001 USBPROXY usbLibGetDevices: Starting USB device enumeration
65:57:40.659550 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#VID_2109&PID_2812#5&2a753ff6&0&5#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 4
65:57:40.663657 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&143f169d&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 13
65:57:40.663741 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&143f169d&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 14
65:57:40.664328 USBPROXY usbLibGetDevices: Found 5 USB devices, 0 captured
65:57:42.259181 USBPROXY usbLibGetDevices: Starting USB device enumeration
65:57:42.259751 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#VID_2109&PID_2812#5&2a753ff6&0&5#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 4
65:57:42.263182 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&143f169d&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 13
65:57:42.263246 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#4&143f169d&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 14
65:57:42.263674 USBPROXY usbLibGetDevices: Found 5 USB devices, 0 captured
66:02:16.628458          ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={SessionMachine} aText={This machine does not have any snapshots}, preserve=false aResultDetail=0
66:02:16.934837          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={Cannot unregister the machine 'boinc_8dbc715f5e91e7a0' while it is locked}, preserve=false aResultDetail=0
66:02:18.189065          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={Cannot unregister the machine 'boinc_8dbc715f5e91e7a0' while it is locked}, preserve=false aResultDetail=0
66:02:20.444107          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={Cannot unregister the machine 'boinc_8dbc715f5e91e7a0' while it is locked}, preserve=false aResultDetail=0
66:02:24.699262          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={Cannot unregister the machine 'boinc_8dbc715f5e91e7a0' while it is locked}, preserve=false aResultDetail=0
66:02:32.954427          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={Cannot unregister the machine 'boinc_8dbc715f5e91e7a0' while it is locked}, preserve=false aResultDetail=0
66:02:49.206063          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={Cannot unregister the machine 'boinc_8dbc715f5e91e7a0' while it is locked}, preserve=false aResultDetail=0

    VM Execution Log:

00:00:02.087006 VMMDev: Guest Log: CPUID EDX: 0x178bfbff
00:00:02.087444 VMMDev: Guest Log: BIOS: No PCI IDE controller, not probing IDE
00:00:02.087645 AHCI#0: Reset the HBA
00:00:02.087650 VD#0: Cancelling all active requests
00:00:02.087682 AHCI#0: Port 0 reset
00:00:02.087708 VD#0: Cancelling all active requests
00:00:02.088246 VMMDev: Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
00:00:02.088284 AHCI#0: Port 1 reset
00:00:02.088363 AHCI#0: Port 2 reset
00:00:02.229173 PIT: mode=2 count=0x48d3 (18643) - 64.00 Hz (ch=0)
00:00:02.248103 VBoxHeadless: starting event loop
00:00:02.277966 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=000002981a1a0000 w=640 h=480 bpp=32 cbLine=0xA00 flags=0x0 origin=0,0
00:00:04.744737 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:00:04.744990 VMMDev: Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
00:00:04.745478 VMMDev: Guest Log: BIOS: Booting from Hard Disk...
00:00:04.760852 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=0000000000000000 w=720 h=400 bpp=0 cbLine=0x0 flags=0x0 origin=0,0
00:00:04.854958 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=000002981a1a0000 w=640 h=480 bpp=24 cbLine=0x780 flags=0x0 origin=0,0
00:00:06.948019 VMMDev: Guest Log: BIOS: KBD: unsupported int 16h function 03
00:00:06.948329 VMMDev: Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
00:00:07.049808 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=0000000000000000 w=720 h=400 bpp=0 cbLine=0x0 flags=0x0 origin=0,0
00:00:07.676448 GIM: KVM: VCPU  0: Enabled system-time struct. at 0x000000013e3f7000 - u32TscScale=0x9a96d751 i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x454e02a1a uVirtNanoTS=0x14ece1ca8 TscKHz=3312002
00:00:07.678419 TM: Switching TSC mode from 'VirtTSCEmulated' to 'RealTSCOffset'
00:00:08.174032 GIM: KVM: Enabled wall-clock struct. at 0x0000000002b40348 - u32Sec=1763207786 u32Nano=769701664 uVersion=2
00:00:08.183030 PIT: mode=2 count=0x4a9 (1193) - 1000.15 Hz (ch=0)
00:00:08.187199 IEM: rdmsr(0x4e) -> #GP(0)
00:00:08.292162 PIT: mode=0 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:00:08.292752 GIM: KVM: VCPU  1: Enabled system-time struct. at 0x000000013e3f7040 - u32TscScale=0x9a96d751 i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x4ce8c62e2 uVirtNanoTS=0x1738ac25c TscKHz=3312002
00:00:08.293043 IEM: rdmsr(0x4e) -> #GP(0)
00:00:08.295537 GIM: KVM: VCPU  2: Enabled system-time struct. at 0x000000013e3f7080 - u32TscScale=0x9a96d751 i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x4cf195463 uVirtNanoTS=0x173b55086 TscKHz=3312002
00:00:08.295833 IEM: rdmsr(0x4e) -> #GP(0)
00:00:08.298311 GIM: KVM: VCPU  3: Enabled system-time struct. at 0x000000013e3f70c0 - u32TscScale=0x9a96d751 i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x4cfa5b43a uVirtNanoTS=0x173dfb2b5 TscKHz=3312002
00:00:08.298600 IEM: rdmsr(0x4e) -> #GP(0)
00:00:09.540459 AHCI#0: Reset the HBA
00:00:09.540490 VD#0: Cancelling all active requests
00:00:09.541958 AHCI#0: Port 0 reset
00:00:09.543084 VD#0: Cancelling all active requests
00:00:09.547858 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=000002981a1a0000 w=800 h=600 bpp=32 cbLine=0xC80 flags=0x1 origin=0,0
00:00:10.051337 AHCI#0: Port 1 reset
00:00:27.184081 AIOMgr: Async flushes not supported
00:00:29.842680 VMMDev: Guest Additions information report: Version 5.2.6 r120293 '5.2.6'
00:00:29.842728 VMMDev: Guest Additions information report: Interface = 0x00010004 osType = 0x00053100 (Linux >= 2.6, 64-bit)
00:00:29.842848 VMMDev: Guest Additions capability report: (0x0 -> 0x0) seamless: no, hostWindowMapping: no, graphics: no
00:00:29.842930 VMMDev: vmmDevReqHandler_HeartbeatConfigure: No change (fHeartbeatActive=false)
00:00:29.842944 VMMDev: Heartbeat flatline timer set to trigger after 4 000 000 000 ns
00:00:29.842982 VMMDev: Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
00:00:29.843182 VMMDev: Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
00:00:30.487175 VMMDev: Guest Additions capability report: (0x0 -> 0x0) seamless: no, hostWindowMapping: no, graphics: no
00:00:30.547314 VMMDev: Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
00:00:30.547314 VMMDev: Guest Log: 00:00:00.000100 main     Log opened 2025-11-15T11:56:49.069855000Z
00:00:30.547314 VMMDev: Guest Log: 00:00:00.000258 main     OS Product: Linux
00:00:30.547314 VMMDev: Guest Log: 00:00:00.000295 main     OS Release: 4.14.232-19.cernvm.x86_64
00:00:30.547314 VMMDev: Guest Log: 00:00:00.000325 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
00:00:30.547628 VMMDev: Guest Log: 00:00:00.000353 main     Executable: /usr/sbin/VBoxService
00:00:30.547635 VMMDev: Guest Log: 00:00:00.000353 main     Process ID: 2291
00:00:30.547639 VMMDev: Guest Log: 00:00:00.000354 main     Package type: LINUX_64BITS_GENERIC
00:00:30.548390 VMMDev: Guest Log: 00:00:00.001114 main     5.2.6 r120293 started. Verbose level = 0
00:00:44.047822 VMMDev: Guest Log: [INFO] Mounting the shared directory
00:00:44.159954 VMMDev: Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
00:00:44.281073 VMMDev: Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
00:00:44.418697 VMMDev: Guest Log: [INFO] Testing connection to cern.ch
00:00:44.584916 VMMDev: Guest Log: [INFO] Testing connection to VCCS
00:00:44.741813 VMMDev: Guest Log: [INFO] Testing connection to HTCondor-Collector
00:00:44.918146 VMMDev: Guest Log: [INFO] Testing connection to HTCondor-CCB
00:00:45.082231 VMMDev: Guest Log: [INFO] Testing connection to WMAgent
00:00:45.263622 VMMDev: Guest Log: [INFO] Testing connection to EOSCMS
00:00:45.484164 VMMDev: Guest Log: [INFO] Testing connection to CMS-Factory
00:00:45.639768 VMMDev: Guest Log: [INFO] Testing connection to CMS-Frontier
00:00:45.848937 VMMDev: Guest Log: [INFO] Testing connection to Frontier
00:00:45.983879 VMMDev: Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/
00:00:48.094211 VMMDev: Guest Log: [INFO] Got a proxy from the local BOINC client
00:00:48.175101 VMMDev: Guest Log: [INFO] Will use it for CVMFS and Frontier
00:00:48.844619 VMMDev: Guest Log: [INFO] Reloading and probing the CVMFS configuration
00:00:54.418942 VMMDev: Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
00:00:56.880960 VMMDev: Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
00:00:57.244861 VMMDev: Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
00:00:57.283322 VMMDev: Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
00:00:57.322886 VMMDev: Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
00:00:57.992066 VMMDev: Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
00:00:58.073174 VMMDev: Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io http://192.168.1.130:3128
00:00:58.151303 VMMDev: Guest Log: [INFO] Environment HTTP proxy: http://192.168.1.130:3128
00:00:58.294371 VMMDev: Guest Log: [INFO] Reading volunteer information
00:01:03.102204 VMMDev: Guest Log: [INFO] Requesting an X509 credential from LHC@home
00:01:03.922414 VMMDev: Guest Log: [INFO] Requesting an idtoken from LHC@home
00:01:04.439196 VMMDev: Guest Log: [INFO] CMS application starting. Check log files.
01:10:13.696456 I/O cache: Error while writing entry at offset 8833188352 (65536 bytes) to medium "ahci-0-0" (rc=VERR_NO_MEMORY)
01:10:13.697503 VM: Raising runtime error 'BLKCACHE_IOERR' (fFlags=0x6)
01:10:13.697620 Changing the VM state from 'RUNNING' to 'SUSPENDING'
01:10:13.697724 PDMR3Suspend: after     0 ms, 1 loops: 1 async tasks - ahci/0
01:33:27.482088 Console: Machine state changed to 'Stopping'
01:33:27.483023 Console::powerDown(): A request to power off the VM has been issued (mMachineState=Stopping, InUninit=0)
01:33:27.661671 VRDP: TCP server closed.
01:33:27.662209 PDMR3Suspend: after 1393964 ms, 2 loops: 1 async tasks - ahci/0

    VM Startup Log:


    VM Trace Log:

on 
Exit Code: 0
Output:

2025-11-15 11:56:11 (2408): 
Command: VBoxManage -q modifyvm "boinc_8dbc715f5e91e7a0" --cable-connected1 on 
Exit Code: 0
Output:

2025-11-15 11:56:11 (2408): 
Command: VBoxManage -q modifyvm "boinc_8dbc715f5e91e7a0" --usb off 
Exit Code: 0
Output:

2025-11-15 11:56:12 (2408): 
Command: VBoxManage -q modifyvm "boinc_8dbc715f5e91e7a0" --uart1 off --uart2 off 
Exit Code: 0
Output:

2025-11-15 11:56:12 (2408): 
Command: VBoxManage -q modifyvm "boinc_8dbc715f5e91e7a0" --lpt1 off --lpt2 off 
Exit Code: 0
Output:

2025-11-15 11:56:12 (2408): 
Command: VBoxManage -q modifyvm "boinc_8dbc715f5e91e7a0" --audio-enabled off 
Exit Code: 0
Output:

2025-11-15 11:56:12 (2408): 
Command: VBoxManage -q modifyvm "boinc_8dbc715f5e91e7a0" --clipboard disabled 
Exit Code: 0
Output:

2025-11-15 11:56:13 (2408): 
Command: VBoxManage -q modifyvm "boinc_8dbc715f5e91e7a0" --draganddrop disabled 
Exit Code: 0
Output:

2025-11-15 11:56:13 (2408): 
Command: VBoxManage -q storagectl "boinc_8dbc715f5e91e7a0" --name "Hard Disk Controller" --add "sata" --controller "IntelAHCI" --hostiocache off --portcount 3
Exit Code: 0
Output:

2025-11-15 11:56:13 (2408): 
Command: VBoxManage -q showhdinfo "D:\BOINC1/projects/lhcathome.cern.ch_lhcathome/CMS_2025_04_08_prod.vdi" 
Exit Code: 0
Output:
UUID:           fa49c4e2-ad29-47a5-b96a-3c89ce80d84c
Parent UUID:    base
State:          locked read
Type:           multiattach
Location:       D:\BOINC1\projects\lhcathome.cern.ch_lhcathome\CMS_2025_04_08_prod.vdi
Storage format: VDI
Format variant: dynamic default
Capacity:       20480 MBytes
Size on disk:   3853 MBytes
Encryption:     disabled
Property:       AllocationBlockSize=1048576
Child UUIDs:    cbe85910-f4a5-49f4-98c7-8dfa530b1991
                b4b6bc47-df42-4774-991d-bc39fac3c058
                05df7225-87d0-4e8b-a3a7-8bb204e30c82

2025-11-15 11:56:13 (2408): 
Command: VBoxManage -q storageattach "boinc_8dbc715f5e91e7a0" --storagectl "Hard Disk Controller" --port 0 --device 0 --type hdd --mtype multiattach --medium "D:\BOINC1/projects/lhcathome.cern.ch_lhcathome/CMS_2025_04_08_prod.vdi" 
Exit Code: 0
Output:

2025-11-15 11:56:14 (2408): 
Command: VBoxManage -q storageattach "boinc_8dbc715f5e91e7a0" --storagectl "Hard Disk Controller" --port 1 --device 0 --type dvddrive --medium "C:\Program Files\Oracle\VirtualBox/VBoxGuestAdditions.iso" 
Exit Code: 0
Output:

2025-11-15 11:56:14 (2408): 
Command: VBoxManage -q bandwidthctl "boinc_8dbc715f5e91e7a0" add "boinc_8dbc715f5e91e7a0_net" --type network --limit 1024G 
Exit Code: 0
Output:

2025-11-15 11:56:14 (2408): 
Command: VBoxManage -q modifyvm "boinc_8dbc715f5e91e7a0" --natpf1 ",tcp,127.0.0.1,60356,,80" 
Exit Code: 0
Output:

2025-11-15 11:56:14 (2408): 
Command: VBoxManage -q list extpacks
Exit Code: 0
Output:
Extension Packs: 1
Pack no. 0:   Oracle VirtualBox Extension Pack
Version:        7.1.12
Revision:       169651
Edition:        
Description:    Oracle Cloud Infrastructure integration, Host Webcam, VirtualBox RDP, PXE ROM, Disk Encryption, NVMe, full VM encryption.
VRDE Module:    VBoxVRDP
Crypto Module:  VBoxPuelCrypto
Usable:         true
Why unusable:   

2025-11-15 11:56:15 (2408): 
Command: VBoxManage -q modifyvm "boinc_8dbc715f5e91e7a0" --vrde on --vrdeextpack default --vrdeauthlibrary default --vrdeauthtype null --vrdeport 60357 --vrde-property "Security/Method=RDP" 
Exit Code: 0
Output:

2025-11-15 11:56:15 (2408): 
Command: VBoxManage -q sharedfolder add "boinc_8dbc715f5e91e7a0" --name "shared" --hostpath "D:\BOINC1\slots\0/shared"
Exit Code: 0
Output:

2025-11-15 11:56:20 (2408): 
Command: VBoxManage -q startvm "boinc_8dbc715f5e91e7a0" --type headless
Exit Code: 0
Output:
Waiting for VM "boinc_8dbc715f5e91e7a0" to power on...
VM "boinc_8dbc715f5e91e7a0" has been successfully started.

2025-11-15 11:56:21 (2408): 
Command: VBoxManage -q controlvm "boinc_8dbc715f5e91e7a0" cpuexecutioncap 100 
Exit Code: 0
Output:

2025-11-15 13:30:32 (2408): 
Command: VBoxManage -q controlvm "boinc_8dbc715f5e91e7a0" poweroff
Exit Code: -182
Output:
0%...10%...20%...
2025-11-15 13:30:32 (2408): 
Command: VBoxManage -q snapshot "boinc_8dbc715f5e91e7a0" list 
Exit Code: -108
Output:
This machine does not have any snapshots

2025-11-15 13:30:32 (2408): 
Command: VBoxManage -q bandwidthctl "boinc_8dbc715f5e91e7a0" remove "boinc_8dbc715f5e91e7a0_net" 
Exit Code: 0
Output:
VBoxManage.exe: error: Bandwidth groups cannot be deleted while the VM is running

2025-11-15 13:30:32 (2408): 
Command: VBoxManage -q unregistervm "boinc_8dbc715f5e91e7a0" --delete 
Exit Code: -2135228409
Output:
VBoxManage.exe: error: Cannot unregister the machine 'boinc_8dbc715f5e91e7a0' while it is locked
VBoxManage.exe: error: Details: code VBOX_E_INVALID_OBJECT_STATE (0x80bb0007), component MachineWrap, interface IMachine, callee IUnknown
VBoxManage.exe: error: Context: "Unregister(fDeleteAll ? CleanupMode_DetachAllReturnHardDisksAndVMRemovable :CleanupMode_DetachAllReturnHardDisksOnly, ComSafeArrayAsOutParam(aMedia))" at line 235 of file VBoxManageMisc.cpp

2025-11-15 13:30:34 (2408): 
Command: VBoxManage -q unregistervm "boinc_8dbc715f5e91e7a0" --delete 
Exit Code: -2135228409
Output:
VBoxManage.exe: error: Cannot unregister the machine 'boinc_8dbc715f5e91e7a0' while it is locked
VBoxManage.exe: error: Details: code VBOX_E_INVALID_OBJECT_STATE (0x80bb0007), component MachineWrap, interface IMachine, callee IUnknown
VBoxManage.exe: error: Context: "Unregister(fDeleteAll ? CleanupMode_DetachAllReturnHardDisksAndVMRemovable :CleanupMode_DetachAllReturnHardDisksOnly, ComSafeArrayAsOutParam(aMedia))" at line 235 of file VBoxManageMisc.cpp

2025-11-15 13:30:36 (2408): 
Command: VBoxManage -q unregistervm "boinc_8dbc715f5e91e7a0" --delete 
Exit Code: -2135228409
Output:
VBoxManage.exe: error: Cannot unregister the machine 'boinc_8dbc715f5e91e7a0' while it is locked
VBoxManage.exe: error: Details: code VBOX_E_INVALID_OBJECT_STATE (0x80bb0007), component MachineWrap, interface IMachine, callee IUnknown
VBoxManage.exe: error: Context: "Unregister(fDeleteAll ? CleanupMode_DetachAllReturnHardDisksAndVMRemovable :CleanupMode_DetachAllReturnHardDisksOnly, ComSafeArrayAsOutParam(aMedia))" at line 235 of file VBoxManageMisc.cpp

2025-11-15 13:30:40 (2408): 
Command: VBoxManage -q unregistervm "boinc_8dbc715f5e91e7a0" --delete 
Exit Code: -2135228409
Output:
VBoxManage.exe: error: Cannot unregister the machine 'boinc_8dbc715f5e91e7a0' while it is locked
VBoxManage.exe: error: Details: code VBOX_E_INVALID_OBJECT_STATE (0x80bb0007), component MachineWrap, interface IMachine, callee IUnknown
VBoxManage.exe: error: Context: "Unregister(fDeleteAll ? CleanupMode_DetachAllReturnHardDisksAndVMRemovable :CleanupMode_DetachAllReturnHardDisksOnly, ComSafeArrayAsOutParam(aMedia))" at line 235 of file VBoxManageMisc.cpp

2025-11-15 13:30:48 (2408): 
Command: VBoxManage -q unregistervm "boinc_8dbc715f5e91e7a0" --delete 
Exit Code: -2135228409
Output:
VBoxManage.exe: error: Cannot unregister the machine 'boinc_8dbc715f5e91e7a0' while it is locked
VBoxManage.exe: error: Details: code VBOX_E_INVALID_OBJECT_STATE (0x80bb0007), component MachineWrap, interface IMachine, callee IUnknown
VBoxManage.exe: error: Context: "Unregister(fDeleteAll ? CleanupMode_DetachAllReturnHardDisksAndVMRemovable :CleanupMode_DetachAllReturnHardDisksOnly, ComSafeArrayAsOutParam(aMedia))" at line 235 of file VBoxManageMisc.cpp

2025-11-15 13:31:05 (2408): 
Command: VBoxManage -q unregistervm "boinc_8dbc715f5e91e7a0" --delete 
Exit Code: -2135228409
Output:
VBoxManage.exe: error: Cannot unregister the machine 'boinc_8dbc715f5e91e7a0' while it is locked
VBoxManage.exe: error: Details: code VBOX_E_INVALID_OBJECT_STATE (0x80bb0007), component MachineWrap, interface IMachine, callee IUnknown
VBoxManage.exe: error: Context: "Unregister(fDeleteAll ? CleanupMode_DetachAllReturnHardDisksAndVMRemovable :CleanupMode_DetachAllReturnHardDisksOnly, ComSafeArrayAsOutParam(aMedia))" at line 235 of file VBoxManageMisc.cpp

2025-11-15 13:31:20 (2408): called boinc_finish(-182)

</stderr_txt>
]]>

©2025 CERN