Stderr output

<core_client_version>7.20.2</core_client_version>
<![CDATA[
<message>
(unknown error) - exit code 4294967114 (0xffffff4a)</message>
<stderr_txt>
2025-12-15 13:30:19 (6936): vboxwrapper version 26208
2025-12-15 13:30:19 (6936): BOINC client version: 7.20.2
2025-12-15 13:30:20 (6936): Detected: VirtualBox VboxManage Interface (Version: 7.1.6)
2025-12-15 13:30:20 (6936): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2025-12-15 13:30:20 (6936): Successfully copied 'init_data.xml' to the shared directory.
2025-12-15 13:30:21 (6936): Create VM. (boinc_618cff2bcddcf373, slot#7)
2025-12-15 13:30:21 (6936): Setting Memory Size for VM. (4584MB)
2025-12-15 13:30:21 (6936): Setting CPU Count for VM. (4)
2025-12-15 13:30:22 (6936): Setting Chipset Options for VM.
2025-12-15 13:30:22 (6936): Setting Graphics Controller Options for VM.
2025-12-15 13:30:22 (6936): Setting Boot Options for VM.
2025-12-15 13:30:22 (6936): Setting Network Configuration for NAT.
2025-12-15 13:30:23 (6936): Enabling VM Network Access.
2025-12-15 13:30:23 (6936): Disabling USB Support for VM.
2025-12-15 13:30:23 (6936): Disabling COM Port Support for VM.
2025-12-15 13:30:23 (6936): Disabling LPT Port Support for VM.
2025-12-15 13:30:24 (6936): Disabling Audio Support for VM.
2025-12-15 13:30:24 (6936): Disabling Clipboard Support for VM.
2025-12-15 13:30:24 (6936): Disabling Drag and Drop Support for VM.
2025-12-15 13:30:24 (6936): Adding storage controller(s) to VM.
2025-12-15 13:30:25 (6936): Adding virtual disk drive to VM. (CMS_2024_11_28_dev.vdi)
2025-12-15 13:30:27 (6936): Adding VirtualBox Guest Additions to VM.
2025-12-15 13:30:27 (6936): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2025-12-15 13:30:27 (6936): forwarding host port 49685 to guest port 80
2025-12-15 13:30:28 (6936): Enabling remote desktop for VM.
2025-12-15 13:30:28 (6936): Required extension pack not installed, remote desktop not enabled.
2025-12-15 13:30:28 (6936): Enabling shared directory for VM.
2025-12-15 13:30:28 (6936): Starting VM using VBoxManage interface. (boinc_618cff2bcddcf373, slot#7)
2025-12-15 13:30:35 (6936): Successfully started VM. (PID = '7408')
2025-12-15 13:30:35 (6936): Reporting VM Process ID to BOINC.
2025-12-15 13:30:35 (6936): Guest Log: BIOS: VirtualBox 7.1.6
2025-12-15 13:30:35 (6936): Guest Log: CPUID EDX: 0x178bfbff
2025-12-15 13:30:35 (6936): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-12-15 13:30:35 (6936): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-12-15 13:30:35 (6936): VM state change detected. (old = 'poweredoff', new = 'running')
2025-12-15 13:30:35 (6936): Detected: Web Application Enabled (http://localhost:49685)
2025-12-15 13:30:35 (6936): Preference change detected
2025-12-15 13:30:35 (6936): Setting CPU throttle for VM. (100%)
2025-12-15 13:30:35 (6936): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 600 seconds) or (Vbox_job.xml: 600 seconds))
2025-12-15 13:30:37 (6936): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-12-15 13:30:37 (6936): Guest Log: BIOS: Booting from Hard Disk...
2025-12-15 13:30:40 (6936): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-12-15 13:30:40 (6936): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2025-12-15 13:31:13 (6936): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-12-15 13:31:13 (6936): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-12-15 13:31:14 (6936): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-12-15 13:31:14 (6936): Guest Log: 00:00:00.000161 main     Log opened 2025-12-15T13:31:20.858040000Z
2025-12-15 13:31:14 (6936): Guest Log: 00:00:00.000347 main     OS Product: Linux
2025-12-15 13:31:14 (6936): Guest Log: 00:00:00.000402 main     OS Release: 4.14.232-19.cernvm.x86_64
2025-12-15 13:31:14 (6936): Guest Log: 00:00:00.000441 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2025-12-15 13:31:14 (6936): Guest Log: 00:00:00.000471 main     Executable: /usr/sbin/VBoxService
2025-12-15 13:31:14 (6936): Guest Log: 00:00:00.000472 main     Process ID: 2279
2025-12-15 13:31:14 (6936): Guest Log: 00:00:00.000473 main     Package type: LINUX_64BITS_GENERIC
2025-12-15 13:31:14 (6936): Guest Log: 00:00:00.002778 main     5.2.6 r120293 started. Verbose level = 0
2025-12-15 13:31:27 (6936): Guest Log: [INFO] Mounting the shared directory
2025-12-15 13:31:27 (6936): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2025-12-15 13:31:28 (6936): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2025-12-15 13:31:28 (6936): Guest Log: [INFO] Testing connection to cern.ch
2025-12-15 13:31:28 (6936): Guest Log: [INFO] Testing connection to VCCS
2025-12-15 13:31:28 (6936): Guest Log: [INFO] Testing connection to HTCondor-Collector
2025-12-15 13:31:29 (6936): Guest Log: [INFO] Testing connection to HTCondor-CCB
2025-12-15 13:31:29 (6936): Guest Log: [INFO] Testing connection to WMAgent
2025-12-15 13:31:29 (6936): Guest Log: [INFO] Testing connection to EOSCMS
2025-12-15 13:31:30 (6936): Guest Log: [INFO] Testing connection to CMS-Factory
2025-12-15 13:31:30 (6936): Guest Log: [INFO] Testing connection to CMS-Frontier
2025-12-15 13:31:30 (6936): Guest Log: [INFO] Testing connection to Frontier
2025-12-15 13:31:30 (6936): Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/
2025-12-15 13:31:32 (6936): Guest Log: [INFO] Could not find a local HTTP proxy
2025-12-15 13:31:32 (6936): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2025-12-15 13:31:32 (6936): Guest Log: [INFO] This makes the application less efficient
2025-12-15 13:31:32 (6936): Guest Log: [INFO] It also puts higher load on the project servers
2025-12-15 13:31:32 (6936): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2025-12-15 13:31:32 (6936): Guest Log: [INFO] Advice can be found in the project forum
2025-12-15 13:31:33 (6936): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2025-12-15 13:31:38 (6936): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2025-12-15 13:31:40 (6936): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2025-12-15 13:31:41 (6936): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2025-12-15 13:31:41 (6936): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2025-12-15 13:31:42 (6936): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2025-12-15 13:31:43 (6936): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2025-12-15 13:31:43 (6936): Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT
2025-12-15 13:31:44 (6936): Guest Log: [INFO] Environment HTTP proxy: not set
2025-12-15 13:31:44 (6936): Guest Log: [INFO] Reading volunteer information
2025-12-15 13:31:51 (6936): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2025-12-15 13:31:52 (6936): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2025-12-15 13:31:53 (6936): Guest Log: [INFO] Requesting an idtoken from LHC@home
2025-12-15 13:31:54 (6936): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2025-12-15 13:31:55 (6936): Guest Log: [INFO] CMS application starting. Check log files.
2025-12-15 15:11:00 (6936): Status Report: Job Duration: '64800.000000'
2025-12-15 15:11:00 (6936): Status Report: Elapsed Time: '6000.000000'
2025-12-15 15:11:00 (6936): Status Report: CPU Time: '20462.328125'
2025-12-15 16:51:22 (6936): Status Report: Job Duration: '64800.000000'
2025-12-15 16:51:22 (6936): Status Report: Elapsed Time: '12000.000000'
2025-12-15 16:51:22 (6936): Status Report: CPU Time: '42587.890625'
2025-12-15 18:31:38 (6936): Status Report: Job Duration: '64800.000000'
2025-12-15 18:31:38 (6936): Status Report: Elapsed Time: '18000.000000'
2025-12-15 18:31:38 (6936): Status Report: CPU Time: '63600.234375'
2025-12-15 20:11:57 (6936): Status Report: Job Duration: '64800.000000'
2025-12-15 20:11:57 (6936): Status Report: Elapsed Time: '24000.000000'
2025-12-15 20:11:57 (6936): Status Report: CPU Time: '83163.937500'
2025-12-15 21:52:04 (6936): Status Report: Job Duration: '64800.000000'
2025-12-15 21:52:04 (6936): Status Report: Elapsed Time: '30000.885956'
2025-12-15 21:52:04 (6936): Status Report: CPU Time: '104294.718750'
2025-12-15 23:32:20 (6936): Status Report: Job Duration: '64800.000000'
2025-12-15 23:32:20 (6936): Status Report: Elapsed Time: '36000.991636'
2025-12-15 23:32:20 (6936): Status Report: CPU Time: '124433.750000'
2025-12-15 23:59:04 (6936): VM Heartbeat file specified, but missing heartbeat.
2025-12-15 23:59:07 (6936): Powering off VM.
2025-12-15 23:59:54 (6936): Error in poweroff VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_618cff2bcddcf373" poweroff
Output:
0%...10%...20%...
2025-12-15 23:59:54 (6936): VM did not power off when requested.
2025-12-15 23:59:54 (6936): VM was NOT successfully terminated.
2025-12-15 23:59:54 (6936): Deregistering VM. (boinc_618cff2bcddcf373, slot#7)
2025-12-15 23:59:56 (6936): Removing network bandwidth throttle group from VM.
2025-12-15 23:59:56 (6936): Removing VM from VirtualBox.

    Hypervisor System Log:

43:54:37.372218          Finished saving settings file "C:\ProgramData\BOINC\slots\5\USPEX_VMVASP_52769183\USPEX_VMVASP_52769183.vbox"
43:54:37.767726          Platform architecture set to 'x86'
43:54:37.778031          Saving settings file "C:\ProgramData\BOINC\slots\5\USPEX_VMVASP_52769183\USPEX_VMVASP_52769183.vbox" with version "1.19-windows"
43:54:37.784446          Finished saving settings file "C:\ProgramData\BOINC\slots\5\USPEX_VMVASP_52769183\USPEX_VMVASP_52769183.vbox"
43:54:38.173240          Platform architecture set to 'x86'
43:54:38.176185          Saving settings file "C:\ProgramData\BOINC\slots\5\USPEX_VMVASP_52769183\USPEX_VMVASP_52769183.vbox" with version "1.19-windows"
43:54:38.179861          Finished saving settings file "C:\ProgramData\BOINC\slots\5\USPEX_VMVASP_52769183\USPEX_VMVASP_52769183.vbox"
43:54:40.441345          Launched VM: 2554861536 pid: 8528 (0x2150) frontend: headless name: USPEX_VMVASP_52769183
43:54:46.226812          Platform architecture set to 'x86'
43:54:50.422466          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={7d510820-a678-4730-a862-818dcd3fbed0} aComponent={MediumWrap} aText={Property 'CRYPT/KeyId' does not exist}, preserve=false aResultDetail=0
43:54:50.431618          HostDnsMonitorProxy::GetDomainName: no domain set
43:54:50.432039          HostDnsMonitorProxy::GetNameServers:
43:54:50.432061            name server 1: 194.168.4.100
43:54:50.432084            name server 2: 194.168.8.100
43:54:50.432183          HostDnsMonitorProxy::GetSearchStrings:
43:54:50.432199            no search string entries
43:54:54.282743          Saving settings file "C:\ProgramData\BOINC\slots\5\USPEX_VMVASP_52769183\USPEX_VMVASP_52769183.vbox" with version "1.19-windows"
43:54:54.318065          Finished saving settings file "C:\ProgramData\BOINC\slots\5\USPEX_VMVASP_52769183\USPEX_VMVASP_52769183.vbox"
43:54:59.372420          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 0 of controller 'sata-controller_9744165289872471'}, preserve=false aResultDetail=0
43:54:59.401633          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 0 of controller 'sata-controller_9744165289872471'}, preserve=false aResultDetail=0
43:54:59.453392          Saving settings file "C:\Users\micro\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
43:54:59.472010          Finished saving settings file "C:\Users\micro\.VirtualBox\VirtualBox.xml"
43:54:59.483828          Saving settings file "C:\ProgramData\BOINC\slots\5\USPEX_VMVASP_52769183\USPEX_VMVASP_52769183.vbox" with version "1.19-windows"
43:54:59.500037          Finished saving settings file "C:\ProgramData\BOINC\slots\5\USPEX_VMVASP_52769183\USPEX_VMVASP_52769183.vbox"
43:55:03.010058 TakeSnap Taking snapshot checkpoint_0
43:55:03.012509 TakeSnap Saving settings file "C:\ProgramData\BOINC\slots\5\USPEX_VMVASP_52769183\USPEX_VMVASP_52769183.vbox" with version "1.19-windows"
43:55:03.020046 TakeSnap Finished saving settings file "C:\ProgramData\BOINC\slots\5\USPEX_VMVASP_52769183\USPEX_VMVASP_52769183.vbox"
43:55:03.023062 TakeSnap Platform architecture set to 'x86'
43:55:05.639640 TakeSnap Saving settings file "C:\ProgramData\BOINC\slots\5\USPEX_VMVASP_52769183\USPEX_VMVASP_52769183.vbox" with version "1.19-windows"
43:55:05.647017 TakeSnap Finished saving settings file "C:\ProgramData\BOINC\slots\5\USPEX_VMVASP_52769183\USPEX_VMVASP_52769183.vbox"
43:55:05.647942 TakeSnap Saving settings file "C:\ProgramData\BOINC\slots\5\USPEX_VMVASP_52769183\USPEX_VMVASP_52769183.vbox" with version "1.19-windows"
43:55:05.653510 TakeSnap Finished saving settings file "C:\ProgramData\BOINC\slots\5\USPEX_VMVASP_52769183\USPEX_VMVASP_52769183.vbox"
43:55:05.656164 TakeSnap Finished taking snapshot checkpoint_0
43:58:59.381793 dns-monitor HostDnsMonitor: updating information
43:58:59.383524 dns-monitor HostDnsMonitor: unchanged
44:02:45.721128          Saving settings file "C:\ProgramData\BOINC\slots\5\USPEX_VMVASP_52769183\USPEX_VMVASP_52769183.vbox" with version "1.19-windows"
44:02:45.966201          Finished saving settings file "C:\ProgramData\BOINC\slots\5\USPEX_VMVASP_52769183\USPEX_VMVASP_52769183.vbox"
44:02:46.112499 DeleteSnap ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={7d510820-a678-4730-a862-818dcd3fbed0} aComponent={MediumWrap} aText={Medium 'C:\ProgramData\BOINC\slots\5\USPEX_VMVASP_52769183\Snapshots\{fd95889e-f1a2-412b-9963-67bdfe023a0a}.vdi' is locked for writing by another task}, preserve=true aResultDetail=0
44:02:46.116194 DeleteSnap ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={7d510820-a678-4730-a862-818dcd3fbed0} aComponent={MediumWrap} aText={Medium 'C:\ProgramData\BOINC\slots\5\deb_port.vdi' is locked for reading by another task}, preserve=true aResultDetail=0
44:02:46.444665 DeleteSnap Saving settings file "C:\ProgramData\BOINC\slots\5\USPEX_VMVASP_52769183\USPEX_VMVASP_52769183.vbox" with version "1.19-windows"
44:02:46.454569 DeleteSnap Finished saving settings file "C:\ProgramData\BOINC\slots\5\USPEX_VMVASP_52769183\USPEX_VMVASP_52769183.vbox"
44:02:46.456737          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={6cc49055-dad4-4496-85cf-3f76bcb3b5fa} aComponent={SnapshotWrap} aText={The object is not ready}, preserve=false aResultDetail=0
44:02:47.238485          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={Cannot unregister the machine 'USPEX_VMVASP_52769183' while it is locked}, preserve=false aResultDetail=0
44:02:51.295942 Watcher  Saving settings file "C:\ProgramData\BOINC\slots\5\USPEX_VMVASP_52769183\USPEX_VMVASP_52769183.vbox" with version "1.19-windows"
44:02:51.447324 Watcher  Finished saving settings file "C:\ProgramData\BOINC\slots\5\USPEX_VMVASP_52769183\USPEX_VMVASP_52769183.vbox"
44:02:56.824307 Watcher  Reaper: Pid 8528 (0x2150) exited normally: 194 (0xc2)
44:21:17.106914          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
44:21:17.655185          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={Cannot unregister the machine 'boinc_618cff2bcddcf373' while it is locked}, preserve=false aResultDetail=0
44:21:18.918945          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={Cannot unregister the machine 'boinc_618cff2bcddcf373' while it is locked}, preserve=false aResultDetail=0
44:21:21.238852          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={Cannot unregister the machine 'boinc_618cff2bcddcf373' while it is locked}, preserve=false aResultDetail=0
44:21:26.098169          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={Cannot unregister the machine 'boinc_618cff2bcddcf373' while it is locked}, preserve=false aResultDetail=0
44:21:35.388274          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={Cannot unregister the machine 'boinc_618cff2bcddcf373' while it is locked}, preserve=false aResultDetail=0
44:21:52.689310          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={e36a5081-a82a-40bd-9e4e-42a44d6ce50f} aComponent={MachineWrap} aText={Cannot unregister the machine 'boinc_618cff2bcddcf373' while it is locked}, preserve=false aResultDetail=0

    VM Execution Log:

00:00:02.457911 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=0000000000000000 w=720 h=400 bpp=0 cbLine=0x0 flags=0x0 origin=0,0
00:00:02.458192 VMMDev: Guest Log: CPUID EDX: 0x178bfbff
00:00:02.458695 VMMDev: Guest Log: BIOS: No PCI IDE controller, not probing IDE
00:00:02.458969 AHCI#0: Reset the HBA
00:00:02.458981 VD#0: Cancelling all active requests
00:00:02.459045 AHCI#0: Port 0 reset
00:00:02.459096 VD#0: Cancelling all active requests
00:00:02.459972 VMMDev: Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
00:00:02.460110 AHCI#0: Port 1 reset
00:00:02.460408 AHCI#0: Port 2 reset
00:00:02.505459 VBoxHeadless: starting event loop
00:00:02.613265 PIT: mode=2 count=0x48d3 (18643) - 64.00 Hz (ch=0)
00:00:02.661706 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=000001c1f2d30000 w=640 h=480 bpp=32 cbLine=0xA00 flags=0x0 origin=0,0
00:00:05.111845 PIT: mode=2 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:00:05.112230 VMMDev: Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
00:00:05.113111 VMMDev: Guest Log: BIOS: Booting from Hard Disk...
00:00:05.167015 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=0000000000000000 w=720 h=400 bpp=0 cbLine=0x0 flags=0x0 origin=0,0
00:00:05.221898 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=000001c1f2d30000 w=640 h=480 bpp=24 cbLine=0x780 flags=0x0 origin=0,0
00:00:07.529951 VMMDev: Guest Log: BIOS: KBD: unsupported int 16h function 03
00:00:07.530248 VMMDev: Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
00:00:07.583756 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=0000000000000000 w=720 h=400 bpp=0 cbLine=0x0 flags=0x0 origin=0,0
00:00:08.647660 GIM: KVM: VCPU  0: Enabled system-time struct. at 0x000000013e3f7000 - u32TscScale=0xa04d07fe i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x49fa90007 uVirtNanoTS=0x17296e565 TscKHz=3193992
00:00:08.647769 TM: Switching TSC mode from 'VirtTSCEmulated' to 'RealTSCOffset'
00:00:09.703462 GIM: KVM: Enabled wall-clock struct. at 0x0000000002b40348 - u32Sec=1765805441 u32Nano=942401434 uVersion=2
00:00:09.711160 PIT: mode=2 count=0x4a9 (1193) - 1000.15 Hz (ch=0)
00:00:09.861563 PIT: mode=0 count=0x10000 (65536) - 18.20 Hz (ch=0)
00:00:09.863187 GIM: KVM: VCPU  1: Enabled system-time struct. at 0x000000013e3f7040 - u32TscScale=0xa04d07fe i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x587195627 uVirtNanoTS=0x1bb0cd251 TscKHz=3193992
00:00:09.866413 GIM: KVM: VCPU  2: Enabled system-time struct. at 0x000000013e3f7080 - u32TscScale=0xa04d07fe i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x587b6d195 uVirtNanoTS=0x1bb3e2095 TscKHz=3193992
00:00:09.869580 GIM: KVM: VCPU  3: Enabled system-time struct. at 0x000000013e3f70c0 - u32TscScale=0xa04d07fe i8TscShift=-1 uVersion=2 fFlags=0x1 uTsc=0x58850cd87 uVirtNanoTS=0x1bb6e567b TscKHz=3193992
00:00:11.870855 AHCI#0: Reset the HBA
00:00:11.870960 VD#0: Cancelling all active requests
00:00:11.872814 AHCI#0: Port 0 reset
00:00:11.874043 VD#0: Cancelling all active requests
00:00:11.880599 Display::i_handleDisplayResize: uScreenId=0 pvVRAM=000001c1f2d30000 w=800 h=600 bpp=32 cbLine=0xC80 flags=0x1 origin=0,0
00:00:12.191149 AHCI#0: Port 1 reset
00:00:30.124723 NAT: Link up
00:00:37.747736 AIOMgr: Async flushes not supported
00:00:40.690259 VMMDev: Guest Additions information report: Version 5.2.6 r120293 '5.2.6'
00:00:40.690312 VMMDev: Guest Additions information report: Interface = 0x00010004 osType = 0x00053100 (Linux >= 2.6, 64-bit)
00:00:40.690434 VMMDev: Guest Additions capability report: (0x0 -> 0x0) seamless: no, hostWindowMapping: no, graphics: no
00:00:40.690528 VMMDev: vmmDevReqHandler_HeartbeatConfigure: No change (fHeartbeatActive=false)
00:00:40.690544 VMMDev: Heartbeat flatline timer set to trigger after 4 000 000 000 ns
00:00:40.690710 VMMDev: Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
00:00:40.692846 VMMDev: Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
00:00:41.423181 VMMDev: Guest Additions capability report: (0x0 -> 0x0) seamless: no, hostWindowMapping: no, graphics: no
00:00:41.541204 VMMDev: Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
00:00:41.541250 VMMDev: Guest Log: 00:00:00.000161 main     Log opened 2025-12-15T13:31:20.858040000Z
00:00:41.541363 VMMDev: Guest Log: 00:00:00.000347 main     OS Product: Linux
00:00:41.541416 VMMDev: Guest Log: 00:00:00.000402 main     OS Release: 4.14.232-19.cernvm.x86_64
00:00:41.541450 VMMDev: Guest Log: 00:00:00.000441 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
00:00:41.541481 VMMDev: Guest Log: 00:00:00.000471 main     Executable: /usr/sbin/VBoxService
00:00:41.541489 VMMDev: Guest Log: 00:00:00.000472 main     Process ID: 2279
00:00:41.541495 VMMDev: Guest Log: 00:00:00.000473 main     Package type: LINUX_64BITS_GENERIC
00:00:41.543792 VMMDev: Guest Log: 00:00:00.002778 main     5.2.6 r120293 started. Verbose level = 0
00:00:55.105321 VMMDev: Guest Log: [INFO] Mounting the shared directory
00:00:55.302310 VMMDev: Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
00:00:55.525389 VMMDev: Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
00:00:55.704455 VMMDev: Guest Log: [INFO] Testing connection to cern.ch
00:00:56.036819 VMMDev: Guest Log: [INFO] Testing connection to VCCS
00:00:56.332799 VMMDev: Guest Log: [INFO] Testing connection to HTCondor-Collector
00:00:56.589909 VMMDev: Guest Log: [INFO] Testing connection to HTCondor-CCB
00:00:56.870503 VMMDev: Guest Log: [INFO] Testing connection to WMAgent
00:00:57.169716 VMMDev: Guest Log: [INFO] Testing connection to EOSCMS
00:00:57.537002 VMMDev: Guest Log: [INFO] Testing connection to CMS-Factory
00:00:57.839527 VMMDev: Guest Log: [INFO] Testing connection to CMS-Frontier
00:00:58.172781 VMMDev: Guest Log: [INFO] Testing connection to Frontier
00:00:58.455185 VMMDev: Guest Log: [INFO] Testing connection to http://cms-frontier.openhtc.io:8080/FrontierProd/Frontier/
00:00:59.471611 VMMDev: Guest Log: [INFO] Could not find a local HTTP proxy
00:00:59.627460 VMMDev: Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
00:00:59.782452 VMMDev: Guest Log: [INFO] This makes the application less efficient
00:00:59.925196 VMMDev: Guest Log: [INFO] It also puts higher load on the project servers
00:01:00.059836 VMMDev: Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
00:01:00.191197 VMMDev: Guest Log: [INFO] Advice can be found in the project forum
00:01:00.663321 VMMDev: Guest Log: [INFO] Reloading and probing the CVMFS configuration
00:01:05.968004 VMMDev: Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
00:01:08.078075 VMMDev: Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
00:01:08.722036 VMMDev: Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
00:01:09.326405 VMMDev: Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
00:01:09.971775 VMMDev: Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
00:01:11.204841 VMMDev: Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
00:01:11.365746 VMMDev: Guest Log: [INFO] 2.7.2.0 http://s1cern-cvmfs.openhtc.io DIRECT
00:01:11.511905 VMMDev: Guest Log: [INFO] Environment HTTP proxy: not set
00:01:11.763897 VMMDev: Guest Log: [INFO] Reading volunteer information
00:01:19.090966 VMMDev: Guest Log: [INFO] Requesting an X509 credential from LHC@home
00:01:19.894996 VMMDev: Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
00:01:21.349511 VMMDev: Guest Log: [INFO] Requesting an idtoken from LHC@home
00:01:22.053965 VMMDev: Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
00:01:22.883628 VMMDev: Guest Log: [INFO] CMS application starting. Check log files.
02:21:23.096238 NAT: Link up
10:28:34.604233 Console: Machine state changed to 'Stopping'
10:28:34.634291 Console::powerDown(): A request to power off the VM has been issued (mMachineState=Stopping, InUninit=0)

    VM Startup Log:


    VM Trace Log:

024_11_28_dev.vdi" 
Exit Code: -2135228409
Output:
VBoxManage.exe: error: Cannot attach medium 'C:\ProgramData\BOINC\projects\lhcathomedev.cern.ch_lhcathome-dev\CMS_2024_11_28_dev.vdi': the media type 'MultiAttach' can only be attached to machines that were created with VirtualBox 4.0 or later
VBoxManage.exe: error: Details: code VBOX_E_INVALID_OBJECT_STATE (0x80bb0007), component SessionMachine, interface IMachine, callee IUnknown
VBoxManage.exe: error: Context: "AttachDevice(Bstr(pszCtl).raw(), port, device, DeviceType_HardDisk, pMedium2Mount)" at line 786 of file VBoxManageStorageController.cpp

2025-12-15 13:30:26 (6936): 
Command: VBoxManage -q showhdinfo "C:\ProgramData\BOINC/projects/lhcathomedev.cern.ch_lhcathome-dev/CMS_2024_11_28_dev.vdi" 
Exit Code: 0
Output:
UUID:           f8b5c18a-130e-4b17-91e2-fccf5c7977aa
Parent UUID:    base
State:          created
Type:           multiattach
Location:       C:\ProgramData\BOINC\projects\lhcathomedev.cern.ch_lhcathome-dev\CMS_2024_11_28_dev.vdi
Storage format: VDI
Format variant: dynamic default
Capacity:       20480 MBytes
Size on disk:   3853 MBytes
Encryption:     disabled
Property:       AllocationBlockSize=1048576

2025-12-15 13:30:26 (6936): 
Command: VBoxManage -q closemedium disk "C:\ProgramData\BOINC\projects\lhcathomedev.cern.ch_lhcathome-dev\CMS_2024_11_28_dev.vdi" 
Exit Code: 0
Output:

2025-12-15 13:30:26 (6936): 
Command: VBoxManage -q showhdinfo "C:\ProgramData\BOINC/projects/lhcathomedev.cern.ch_lhcathome-dev/CMS_2024_11_28_dev.vdi" 
Exit Code: 0
Output:
UUID:           f8b5c18a-130e-4b17-91e2-fccf5c7977aa
Parent UUID:    base
State:          created
Type:           normal (base)
Location:       C:\ProgramData\BOINC\projects\lhcathomedev.cern.ch_lhcathome-dev\CMS_2024_11_28_dev.vdi
Storage format: VDI
Format variant: dynamic default
Capacity:       20480 MBytes
Size on disk:   3853 MBytes
Encryption:     disabled
Property:       AllocationBlockSize=1048576

2025-12-15 13:30:26 (6936): 
Command: VBoxManage -q storageattach "boinc_618cff2bcddcf373" --storagectl "Hard Disk Controller" --port 0 --device 0 --type hdd --medium "C:\ProgramData\BOINC/projects/lhcathomedev.cern.ch_lhcathome-dev/CMS_2024_11_28_dev.vdi" 
Exit Code: 0
Output:

2025-12-15 13:30:27 (6936): 
Command: VBoxManage -q storageattach "boinc_618cff2bcddcf373" --storagectl "Hard Disk Controller" --port 0 --device 0 --type hdd --medium none 
Exit Code: 0
Output:

2025-12-15 13:30:27 (6936): 
Command: VBoxManage -q storageattach "boinc_618cff2bcddcf373" --storagectl "Hard Disk Controller" --port 0 --device 0 --type hdd --mtype multiattach --medium "C:\ProgramData\BOINC/projects/lhcathomedev.cern.ch_lhcathome-dev/CMS_2024_11_28_dev.vdi" 
Exit Code: 0
Output:

2025-12-15 13:30:27 (6936): 
Command: VBoxManage -q storageattach "boinc_618cff2bcddcf373" --storagectl "Hard Disk Controller" --port 1 --device 0 --type dvddrive --medium "C:\Program Files\Oracle\VirtualBox/VBoxGuestAdditions.iso" 
Exit Code: 0
Output:

2025-12-15 13:30:27 (6936): 
Command: VBoxManage -q bandwidthctl "boinc_618cff2bcddcf373" add "boinc_618cff2bcddcf373_net" --type network --limit 1024G 
Exit Code: 0
Output:

2025-12-15 13:30:28 (6936): 
Command: VBoxManage -q modifyvm "boinc_618cff2bcddcf373" --natpf1 ",tcp,127.0.0.1,49685,,80" 
Exit Code: 0
Output:

2025-12-15 13:30:28 (6936): 
Command: VBoxManage -q list extpacks
Exit Code: 0
Output:
Extension Packs: 0

2025-12-15 13:30:28 (6936): 
Command: VBoxManage -q sharedfolder add "boinc_618cff2bcddcf373" --name "shared" --hostpath "C:\ProgramData\BOINC\slots\7/shared"
Exit Code: 0
Output:

2025-12-15 13:30:34 (6936): 
Command: VBoxManage -q startvm "boinc_618cff2bcddcf373" --type headless
Exit Code: 0
Output:
Waiting for VM "boinc_618cff2bcddcf373" to power on...
VM "boinc_618cff2bcddcf373" has been successfully started.

2025-12-15 13:30:35 (6936): 
Command: VBoxManage -q controlvm "boinc_618cff2bcddcf373" cpuexecutioncap 100 
Exit Code: 0
Output:

2025-12-15 23:59:54 (6936): 
Command: VBoxManage -q controlvm "boinc_618cff2bcddcf373" poweroff
Exit Code: -182
Output:
0%...10%...20%...
2025-12-15 23:59:56 (6936): 
Command: VBoxManage -q snapshot "boinc_618cff2bcddcf373" list 
Exit Code: -108
Output:
This machine does not have any snapshots

2025-12-15 23:59:56 (6936): 
Command: VBoxManage -q bandwidthctl "boinc_618cff2bcddcf373" remove "boinc_618cff2bcddcf373_net" 
Exit Code: 0
Output:
VBoxManage.exe: error: Bandwidth groups cannot be deleted while the VM is running

2025-12-15 23:59:56 (6936): 
Command: VBoxManage -q unregistervm "boinc_618cff2bcddcf373" --delete 
Exit Code: -2135228409
Output:
VBoxManage.exe: error: Cannot unregister the machine 'boinc_618cff2bcddcf373' 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-12-15 23:59:57 (6936): 
Command: VBoxManage -q unregistervm "boinc_618cff2bcddcf373" --delete 
Exit Code: -2135228409
Output:
VBoxManage.exe: error: Cannot unregister the machine 'boinc_618cff2bcddcf373' 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-12-16 00:00:00 (6936): 
Command: VBoxManage -q unregistervm "boinc_618cff2bcddcf373" --delete 
Exit Code: -2135228409
Output:
VBoxManage.exe: error: Cannot unregister the machine 'boinc_618cff2bcddcf373' 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-12-16 00:00:05 (6936): 
Command: VBoxManage -q unregistervm "boinc_618cff2bcddcf373" --delete 
Exit Code: -2135228409
Output:
VBoxManage.exe: error: Cannot unregister the machine 'boinc_618cff2bcddcf373' 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-12-16 00:00:14 (6936): 
Command: VBoxManage -q unregistervm "boinc_618cff2bcddcf373" --delete 
Exit Code: -2135228409
Output:
VBoxManage.exe: error: Cannot unregister the machine 'boinc_618cff2bcddcf373' 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-12-16 00:00:31 (6936): 
Command: VBoxManage -q unregistervm "boinc_618cff2bcddcf373" --delete 
Exit Code: -2135228409
Output:
VBoxManage.exe: error: Cannot unregister the machine 'boinc_618cff2bcddcf373' 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-12-16 00:00:48 (6936): called boinc_finish(-182)

</stderr_txt>
]]>

©2025 CERN