Name CMS_2711341_1737972703.826544_0
Workunit 229995860
Created 27 Jan 2025, 10:11:44 UTC
Sent 27 Jan 2025, 10:23:11 UTC
Report deadline 27 Feb 2025, 10:23:11 UTC
Received 27 Jan 2025, 13:00:55 UTC
Server state Over
Outcome Computation error
Client state Compute error
Exit status 194 (0x000000C2) EXIT_ABORTED_BY_CLIENT
Computer ID 10865685
Run time 48 min 45 sec
CPU time 11 min 34 sec
Validate state Invalid
Credit 0.00
Device peak FLOPS 13.69 GFLOPS
Application version CMS Simulation v70.60 (vbox64_mt_mcore_cms)
windows_x86_64
Peak working set size 87.28 MB
Peak swap size 88.87 MB
Peak disk usage 1.34 GB

Stderr output

<core_client_version>8.0.2</core_client_version>
<![CDATA[
<message>
(unknown error) (0) - exit code 194 (0xc2)</message>
<stderr_txt>
2025-01-27 21:10:31 (5864): vboxwrapper version 26208
2025-01-27 21:10:31 (5864): BOINC client version: 8.0.2
2025-01-27 21:10:31 (5864): Detected: VirtualBox VboxManage Interface (Version: 7.0.6)
2025-01-27 21:10:32 (5864): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2025-01-27 21:10:32 (5864): Successfully copied 'init_data.xml' to the shared directory.
2025-01-27 21:10:32 (5864): Create VM. (boinc_7ed302fcf31763b8, slot#14)
2025-01-27 21:10:33 (5864): Setting Memory Size for VM. (4584MB)
2025-01-27 21:10:33 (5864): Setting CPU Count for VM. (4)
2025-01-27 21:10:33 (5864): Setting Chipset Options for VM.
2025-01-27 21:10:34 (5864): Setting Graphics Controller Options for VM.
2025-01-27 21:10:34 (5864): Setting Boot Options for VM.
2025-01-27 21:10:34 (5864): Setting Network Configuration for NAT.
2025-01-27 21:10:34 (5864): Enabling VM Network Access.
2025-01-27 21:10:35 (5864): Disabling USB Support for VM.
2025-01-27 21:10:35 (5864): Disabling COM Port Support for VM.
2025-01-27 21:10:35 (5864): Disabling LPT Port Support for VM.
2025-01-27 21:10:35 (5864): Disabling Audio Support for VM.
2025-01-27 21:10:36 (5864): Disabling Clipboard Support for VM.
2025-01-27 21:10:36 (5864): Disabling Drag and Drop Support for VM.
2025-01-27 21:10:36 (5864): Adding storage controller(s) to VM.
2025-01-27 21:10:37 (5864): Adding virtual disk drive to VM. (CMS_2025_01_16_prod.vdi)
2025-01-27 21:10:37 (5864): Adding VirtualBox Guest Additions to VM.
2025-01-27 21:10:37 (5864): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2025-01-27 21:10:38 (5864): forwarding host port 63137 to guest port 80
2025-01-27 21:10:38 (5864): Enabling remote desktop for VM.
2025-01-27 21:10:38 (5864): Required extension pack not installed, remote desktop not enabled.
2025-01-27 21:10:38 (5864): Enabling shared directory for VM.
2025-01-27 21:10:39 (5864): Starting VM using VBoxManage interface. (boinc_7ed302fcf31763b8, slot#14)
2025-01-27 21:10:48 (5864): Successfully started VM. (PID = '9664')
2025-01-27 21:10:48 (5864): Reporting VM Process ID to BOINC.
2025-01-27 21:10:48 (5864): Guest Log: BIOS: VirtualBox 7.0.6
2025-01-27 21:10:48 (5864): Guest Log: CPUID EDX: 0x178bfbff
2025-01-27 21:10:48 (5864): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2025-01-27 21:10:48 (5864): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2025-01-27 21:10:48 (5864): VM state change detected. (old = 'poweredoff', new = 'running')
2025-01-27 21:10:48 (5864): Detected: Web Application Enabled (http://localhost:63137)
2025-01-27 21:10:48 (5864): Preference change detected
2025-01-27 21:10:48 (5864): Setting CPU throttle for VM. (90%)
2025-01-27 21:10:48 (5864): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2025-01-27 21:10:50 (5864): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2025-01-27 21:10:50 (5864): Guest Log: BIOS: Booting from Hard Disk...
2025-01-27 21:10:53 (5864): Guest Log: BIOS: KBD: unsupported int 16h function 03
2025-01-27 21:10:53 (5864): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2025-01-27 21:11:26 (5864): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2025-01-27 21:11:26 (5864): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2025-01-27 21:11:27 (5864): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2025-01-27 21:11:27 (5864): Guest Log: 00:00:00.000206 main     Log opened 2025-01-27T12:11:27.493258000Z
2025-01-27 21:11:27 (5864): Guest Log: 00:00:00.000374 main     OS Product: Linux
2025-01-27 21:11:27 (5864): Guest Log: 00:00:00.000431 main     OS Release: 4.14.232-19.cernvm.x86_64
2025-01-27 21:11:27 (5864): Guest Log: 00:00:00.000479 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2025-01-27 21:11:27 (5864): Guest Log: 00:00:00.000525 main     Executable: /usr/sbin/VBoxService
2025-01-27 21:11:27 (5864): Guest Log: 00:00:00.000526 main     Process ID: 2287
2025-01-27 21:11:27 (5864): Guest Log: 00:00:00.000527 main     Package type: LINUX_64BITS_GENERIC
2025-01-27 21:11:27 (5864): Guest Log: 00:00:00.001475 main     5.2.6 r120293 started. Verbose level = 0
2025-01-27 21:11:53 (5864): Guest Log: [INFO] Mounting the shared directory
2025-01-27 21:11:53 (5864): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2025-01-27 21:11:53 (5864): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2025-01-27 21:11:53 (5864): Guest Log: [INFO] Testing connection to cern.ch
2025-01-27 21:11:54 (5864): Guest Log: [INFO] Testing connection to VCCS
2025-01-27 21:11:56 (5864): Guest Log: [INFO] Testing connection to HTCondor
2025-01-27 21:11:57 (5864): Guest Log: [INFO] Testing connection to WMAgent
2025-01-27 21:11:58 (5864): Guest Log: [INFO] Testing connection to EOSCMS
2025-01-27 21:12:01 (5864): Guest Log: [INFO] Testing connection to CMS-Factory
2025-01-27 21:12:01 (5864): Guest Log: [INFO] Testing connection to CMS-Frontier
2025-01-27 21:12:02 (5864): Guest Log: [INFO] Testing connection to Frontier
2025-01-27 21:12:04 (5864): Guest Log: [INFO] Could not find a local HTTP proxy
2025-01-27 21:12:04 (5864): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2025-01-27 21:12:04 (5864): Guest Log: [INFO] This makes the application less efficient
2025-01-27 21:12:05 (5864): Guest Log: [INFO] It also puts higher load on the project servers
2025-01-27 21:12:05 (5864): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2025-01-27 21:12:05 (5864): Guest Log: [INFO] Advice can be found in the project forum
2025-01-27 21:12:06 (5864): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2025-01-27 21:12:15 (5864): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2025-01-27 21:12:18 (5864): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2025-01-27 21:12:19 (5864): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2025-01-27 21:12:20 (5864): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2025-01-27 21:12:21 (5864): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2025-01-27 21:12:23 (5864): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2025-01-27 21:12:23 (5864): Guest Log: [INFO] 2.7.2.0 http://s1swinburne-cvmfs.openhtc.io:8080 DIRECT
2025-01-27 21:12:23 (5864): Guest Log: [INFO] Environment HTTP proxy: not set
2025-01-27 21:12:23 (5864): Guest Log: [INFO] Reading volunteer information
2025-01-27 21:12:49 (5864): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2025-01-27 21:12:52 (5864): Guest Log: [INFO] Requesting an idtoken from LHC@home
2025-01-27 21:12:54 (5864): Guest Log: [INFO] CMS application starting. Check log files.
2025-01-27 21:58:58 (5864): VM is no longer is a running state. It is in 'stuck'.
2025-01-27 21:58:58 (5864): VM state change detected. (old = 'running', new = 'stuck')
2025-01-27 21:58:58 (5864): Powering off VM.
2025-01-27 21:58:58 (5864): Deregistering VM. (boinc_7ed302fcf31763b8, slot#14)
2025-01-27 21:58:58 (5864): CreateProcess failed (299).
2025-01-27 21:58:58 (5864): Removing network bandwidth throttle group from VM.
2025-01-27 21:58:58 (5864): CreateProcess failed (1450).
2025-01-27 21:58:58 (5864): Removing VM from VirtualBox.
2025-01-27 21:58:58 (5864): CreateProcess failed (1450).
2025-01-27 21:59:03 (5864): VM Premature Shutdown Detected.

    Hypervisor System Log:

00:57:44.424599          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:57:44.424614          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:57:44.424637          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:57:44.424652          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:57:44.424668          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:57:44.424687          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:57:44.424705          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:57:44.424721          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:57:44.424751          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:57:44.424772          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:57:44.424788          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:57:44.424804          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={300763af-5d6b-46e6-aa96-273eac15538a} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:57:44.428603          Saving settings file "C:\ProgramData\BOINC\slots\14\boinc_7ed302fcf31763b8\boinc_7ed302fcf31763b8.vbox" with version "1.19-windows"
00:57:44.435645          Finished saving settings file "C:\ProgramData\BOINC\slots\14\boinc_7ed302fcf31763b8\boinc_7ed302fcf31763b8.vbox"
00:58:33.388456 USBPROXY usbLibGetDevices: Starting USB device enumeration
00:58:33.402762 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#5&1e6fb304&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 1
00:58:33.402995 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#5&1e6fb304&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 2
00:58:33.407196 USBPROXY usbLibGetDevices: Found 3 USB devices, 0 captured
00:58:33.919286 USBPROXY usbLibGetDevices: Starting USB device enumeration
00:58:33.931292 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#5&1e6fb304&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 1
00:58:33.931479 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#5&1e6fb304&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 2
00:58:33.931757 USBPROXY usbLibGetDevices: Found 3 USB devices, 0 captured
01:03:15.817457 dns-monitor HostDnsMonitor: updating information
01:03:15.817502 dns-monitor HostDnsMonitor: unchanged
01:03:30.965807 USBPROXY usbLibGetDevices: Starting USB device enumeration
01:03:30.976987 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#5&1e6fb304&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 1
01:03:30.977144 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#5&1e6fb304&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 2
01:03:30.977400 USBPROXY usbLibGetDevices: Found 3 USB devices, 0 captured
01:03:47.181704 USBPROXY usbLibGetDevices: Starting USB device enumeration
01:03:47.193079 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#5&1e6fb304&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 1
01:03:47.193256 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#5&1e6fb304&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 2
01:03:47.193674 USBPROXY usbLibGetDevices: Found 3 USB devices, 0 captured
01:06:05.905464 dns-monitor HostDnsMonitor: updating information
01:06:05.905503 dns-monitor HostDnsMonitor: unchanged
01:08:43.683367 USBPROXY usbLibGetDevices: Starting USB device enumeration
01:08:43.694911 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#5&1e6fb304&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 1
01:08:43.695101 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#5&1e6fb304&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 2
01:08:43.695567 USBPROXY usbLibGetDevices: Found 3 USB devices, 0 captured
01:11:25.260340 USBPROXY usbLibGetDevices: Starting USB device enumeration
01:11:25.271816 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#5&1e6fb304&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 1
01:11:25.271974 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#5&1e6fb304&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 2
01:11:25.272224 USBPROXY usbLibGetDevices: Found 3 USB devices, 0 captured
01:16:23.173035 USBPROXY usbLibGetDevices: Starting USB device enumeration
01:16:23.184657 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#5&1e6fb304&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 1
01:16:23.184805 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#5&1e6fb304&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 2
01:16:23.185090 USBPROXY usbLibGetDevices: Found 3 USB devices, 0 captured
01:16:38.995389 USBPROXY usbLibGetDevices: Starting USB device enumeration
01:16:39.006829 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#5&1e6fb304&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 1
01:16:39.007035 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#5&1e6fb304&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 2
01:16:39.007371 USBPROXY usbLibGetDevices: Found 3 USB devices, 0 captured
01:21:06.038143 dns-monitor HostDnsMonitor: updating information
01:21:06.038293 dns-monitor HostDnsMonitor: unchanged
01:21:35.998989 USBPROXY usbLibGetDevices: Starting USB device enumeration
01:21:36.010476 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#5&1e6fb304&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 1
01:21:36.010593 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#5&1e6fb304&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 2
01:21:36.011044 USBPROXY usbLibGetDevices: Found 3 USB devices, 0 captured
01:33:16.144097 dns-monitor HostDnsMonitor: updating information
01:33:16.144136 dns-monitor HostDnsMonitor: unchanged
01:36:06.226084 dns-monitor HostDnsMonitor: updating information
01:36:06.226131 dns-monitor HostDnsMonitor: unchanged
01:43:34.784877 USBPROXY usbLibGetDevices: Starting USB device enumeration
01:43:34.800589 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#5&1e6fb304&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 1
01:43:34.800914 USBPROXY Getting USB descriptor (id 0) failed (dwErr=31) on hub USB#ROOT_HUB30#5&1e6fb304&0&0#{f18a0e88-c30c-11d0-8815-00a0c906bed8} port 2
01:43:34.806382 USBPROXY usbLibGetDevices: Found 3 USB devices, 0 captured

    VM Execution Log:

00:48:14.055875 PGM: aHandyPages[#0x3b] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055877 PGM: aHandyPages[#0x3c] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055879 PGM: aHandyPages[#0x3d] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055881 PGM: aHandyPages[#0x3e] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055883 *FLUSH DONE*
00:48:14.055928 *FLUSH* idCpu=3 idxLogger=1 idxBuffer=0 cbToFlush=0xf36 fFlushed=false cbDropped=0x0
00:48:14.055887 PGM: aHandyPages[#0x40] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055889 PGM: aHandyPages[#0x41] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055891 PGM: aHandyPages[#0x42] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055892 PGM: aHandyPages[#0x43] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055895 PGM: aHandyPages[#0x44] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055896 PGM: aHandyPages[#0x45] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055898 PGM: aHandyPages[#0x46] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055900 PGM: aHandyPages[#0x47] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055902 PGM: aHandyPages[#0x48] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055903 PGM: aHandyPages[#0x49] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055905 PGM: aHandyPages[#0x4a] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055907 PGM: aHandyPages[#0x4b] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055908 PGM: aHandyPages[#0x4c] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055910 PGM: aHandyPages[#0x4d] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055911 PGM: aHandyPages[#0x4e] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055913 PGM: aHandyPages[#0x4f] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055914 PGM: aHandyPages[#0x50] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055916 PGM: aHandyPages[#0x51] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055918 PGM: aHandyPages[#0x52] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055919 PGM: aHandyPages[#0x53] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055921 PGM: aHandyPages[#0x54] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055922 PGM: aHandyPages[#0x55] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055924 PGM: aHandyPages[#0x56] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055925 PGM: aHandyPages[#0x57] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055927 PGM: aHandyPages[#0x58] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055929 PGM: aHandyPages[#0x59] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055931 PGM: aHandyPages[#0x5a] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055932 PGM: aHandyPages[#0x5b] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055935 PGM: aHandyPages[#0x5c] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055937 PGM: aHandyPages[#0x5d] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055939 PGM: aHandyPages[#0x5e] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055940 PGM: aHandyPages[#0x5f] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055942 PGM: aHandyPages[#0x60] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055944 *FLUSH DONE*
00:48:14.055976 *FLUSH*
00:48:14.055948 PGM: aHandyPages[#0x62] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055949 PGM: aHandyPages[#0x63] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055951 PGM: aHandyPages[#0x64] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055953 PGM: aHandyPages[#0x65] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055954 PGM: aHandyPages[#0x66] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055956 PGM: aHandyPages[#0x67] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055957 PGM: aHandyPages[#0x68] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055959 PGM: aHandyPages[#0x69] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055961 PGM: aHandyPages[#0x6a] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055962 PGM: aHandyPages[#0x6b] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055964 PGM: aHandyPages[#0x6c] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055965 PGM: aHandyPages[#0x6d] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055967 PGM: aHandyPages[#0x6e] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055969 PGM: aHandyPages[#0x6f] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055970 PGM: aHandyPages[#0x70] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055972 PGM: aHandyPages[#0x71] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055973 PGM: aHandyPages[#0x72] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055975 PGM: aHandyPages[#0x73] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055976 PGM: aHandyPages[#0x74] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055978 PGM: aHandyPages[#0x75] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055980 PGM: aHandyPages[#0x76] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055981 PGM: aHandyPages[#0x77] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055983 PGM: aHandyPages[#0x78] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055984 PGM: aHandyPages[#0x79] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055986 PGM: aHandyPages[#0x7a] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055988 PGM: aHandyPages[#0x7b] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055989 PGM: aHandyPages[#0x7c] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055992 PGM: aHandyPages[#0x7d] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055994 PGM: aHandyPages[#0x7e] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}
00:48:14.055995 PGM: aHandyPages[#0x7f] = {.HCPhysGCPhys=7fffffffffffffff, .idPage=0x000000, .idSharedPage=0x000000}

    VM Startup Log:


    VM Trace Log:

rt count:         256
Maximum Devices per virtio-scsi Port:   1
Maximum PIIX3 Floppy Controllers:1
Maximum ICH9 Floppy Controllers: 1
Maximum Floppy Port count:       1
Maximum Devices per Floppy Port: 2
Default machine folder:          D:\vbfs
Raw-mode Supported:              no
Exclusive HW virtualization use: off
Default hard disk format:        VDI
VRDE auth library:               VBoxAuth
Webservice auth. library:        VBoxAuth
Remote desktop ExtPack:          
VM encryption ExtPack:           
Log history count:               3
Default frontend:                
Default audio driver:            Windows Audio Session
Autostart database path:         
Default Guest Additions ISO:     C:\Program Files\Oracle\VirtualBox/VBoxGuestAdditions.iso
Logging Level:                   all
Proxy Mode:                      System
Proxy URL:                       
User language:                   C

2025-01-27 21:10:32 (5864): 
Command: VBoxManage -q list hostinfo 
Exit Code: 0
Output:
Host Information:

Host time: 2025-01-27T12:10:32.048000000Z
Processor online count: 8
Processor count: 8
Processor online core count: 4
Processor core count: 4
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: yes
Processor supports virt. vmsave/vmload: yes
Processor#0 speed: unknown
Processor#0 description: AMD Ryzen 5 3500U with Radeon Vega Mobile Gfx
Processor#1 speed: unknown
Processor#1 description: AMD Ryzen 5 3500U with Radeon Vega Mobile Gfx
Processor#2 speed: unknown
Processor#2 description: AMD Ryzen 5 3500U with Radeon Vega Mobile Gfx
Processor#3 speed: unknown
Processor#3 description: AMD Ryzen 5 3500U with Radeon Vega Mobile Gfx
Processor#4 speed: unknown
Processor#4 description: AMD Ryzen 5 3500U with Radeon Vega Mobile Gfx
Processor#5 speed: unknown
Processor#5 description: AMD Ryzen 5 3500U with Radeon Vega Mobile Gfx
Processor#6 speed: unknown
Processor#6 description: AMD Ryzen 5 3500U with Radeon Vega Mobile Gfx
Processor#7 speed: unknown
Processor#7 description: AMD Ryzen 5 3500U with Radeon Vega Mobile Gfx
Memory size: 6021 MByte
Memory available: 4523 MByte
Operating system: Windows 10
Operating system version: 10.0.19045

2025-01-27 21:10:32 (5864): 
Command: VBoxManage -q showvminfo "boinc_7ed302fcf31763b8" --machinereadable 
Exit Code: -2135228415
Output:
VBoxManage.exe: error: Could not find a registered machine named 'boinc_7ed302fcf31763b8'
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 3139 of file VBoxManageInfo.cpp

2025-01-27 21:10:33 (5864): 
Command: VBoxManage -q createvm --name "boinc_7ed302fcf31763b8" --basefolder "C:\ProgramData\BOINC\slots\14" --ostype "Linux26_64" --register
Exit Code: 0
Output:
Virtual machine 'boinc_7ed302fcf31763b8' is created and registered.
UUID: 58169dc1-7ac1-4f1c-ba1c-0c181cc9dd59
Settings file: 'C:\ProgramData\BOINC\slots\14\boinc_7ed302fcf31763b8\boinc_7ed302fcf31763b8.vbox'

2025-01-27 21:10:33 (5864): 
Command: VBoxManage -q modifyvm "boinc_7ed302fcf31763b8" --description "CMS_2711341_1737972703.826544_0" 
Exit Code: 0
Output:

2025-01-27 21:10:33 (5864): 
Command: VBoxManage -q modifyvm "boinc_7ed302fcf31763b8" --memory 4584 
Exit Code: 0
Output:

2025-01-27 21:10:33 (5864): 
Command: VBoxManage -q modifyvm "boinc_7ed302fcf31763b8" --cpus 4 
Exit Code: 0
Output:

2025-01-27 21:10:34 (5864): 
Command: VBoxManage -q modifyvm "boinc_7ed302fcf31763b8" --acpi on --ioapic on --rtcuseutc off 
Exit Code: 0
Output:

2025-01-27 21:10:34 (5864): 
Command: VBoxManage -q modifyvm "boinc_7ed302fcf31763b8" --vram 16 --graphicscontroller VBoxVGA 
Exit Code: 0
Output:

2025-01-27 21:10:34 (5864): 
Command: VBoxManage -q modifyvm "boinc_7ed302fcf31763b8" --boot1 disk --boot2 dvd --boot3 none --boot4 none 
Exit Code: 0
Output:

2025-01-27 21:10:34 (5864): 
Command: VBoxManage -q modifyvm "boinc_7ed302fcf31763b8" --nic1 nat --natdnsproxy1 on --cableconnected1 off 
Exit Code: 0
Output:

2025-01-27 21:10:35 (5864): 
Command: VBoxManage -q modifyvm "boinc_7ed302fcf31763b8" --cableconnected1 on 
Exit Code: 0
Output:

2025-01-27 21:10:35 (5864): 
Command: VBoxManage -q modifyvm "boinc_7ed302fcf31763b8" --usb off 
Exit Code: 0
Output:

2025-01-27 21:10:35 (5864): 
Command: VBoxManage -q modifyvm "boinc_7ed302fcf31763b8" --uart1 off --uart2 off 
Exit Code: 0
Output:

2025-01-27 21:10:35 (5864): 
Command: VBoxManage -q modifyvm "boinc_7ed302fcf31763b8" --lpt1 off --lpt2 off 
Exit Code: 0
Output:

2025-01-27 21:10:36 (5864): 
Command: VBoxManage -q modifyvm "boinc_7ed302fcf31763b8" --audio-enabled off 
Exit Code: 0
Output:

2025-01-27 21:10:36 (5864): 
Command: VBoxManage -q modifyvm "boinc_7ed302fcf31763b8" --clipboard disabled 
Exit Code: 0
Output:

2025-01-27 21:10:36 (5864): 
Command: VBoxManage -q modifyvm "boinc_7ed302fcf31763b8" --draganddrop disabled 
Exit Code: 0
Output:

2025-01-27 21:10:37 (5864): 
Command: VBoxManage -q storagectl "boinc_7ed302fcf31763b8" --name "Hard Disk Controller" --add "sata" --controller "IntelAHCI" --hostiocache off --portcount 3
Exit Code: 0
Output:

2025-01-27 21:10:37 (5864): 
Command: VBoxManage -q showhdinfo "C:\ProgramData\BOINC/projects/lhcathome.cern.ch_lhcathome/CMS_2025_01_16_prod.vdi" 
Exit Code: 0
Output:
UUID:           3da9ab36-1206-4cc3-8a9a-8d4138ab45c5
Parent UUID:    base
State:          created
Type:           multiattach
Location:       C:\ProgramData\BOINC\projects\lhcathome.cern.ch_lhcathome\CMS_2025_01_16_prod.vdi
Storage format: VDI
Format variant: dynamic default
Capacity:       20480 MBytes
Size on disk:   3853 MBytes
Encryption:     disabled
Property:       AllocationBlockSize=1048576
Child UUIDs:    16fe3935-2844-4907-8926-ee7cdc7235f4
                11932abc-303f-418d-9213-71e509b8c9e7

2025-01-27 21:10:37 (5864): 
Command: VBoxManage -q storageattach "boinc_7ed302fcf31763b8" --storagectl "Hard Disk Controller" --port 0 --device 0 --type hdd --mtype multiattach --medium "C:\ProgramData\BOINC/projects/lhcathome.cern.ch_lhcathome/CMS_2025_01_16_prod.vdi" 
Exit Code: 0
Output:

2025-01-27 21:10:37 (5864): 
Command: VBoxManage -q storageattach "boinc_7ed302fcf31763b8" --storagectl "Hard Disk Controller" --port 1 --device 0 --type dvddrive --medium "C:\Program Files\Oracle\VirtualBox/VBoxGuestAdditions.iso" 
Exit Code: 0
Output:

2025-01-27 21:10:38 (5864): 
Command: VBoxManage -q bandwidthctl "boinc_7ed302fcf31763b8" add "boinc_7ed302fcf31763b8_net" --type network --limit 1024G 
Exit Code: 0
Output:

2025-01-27 21:10:38 (5864): 
Command: VBoxManage -q modifyvm "boinc_7ed302fcf31763b8" --natpf1 ",tcp,127.0.0.1,63137,,80" 
Exit Code: 0
Output:

2025-01-27 21:10:38 (5864): 
Command: VBoxManage -q list extpacks
Exit Code: 0
Output:
Extension Packs: 0

2025-01-27 21:10:38 (5864): 
Command: VBoxManage -q sharedfolder add "boinc_7ed302fcf31763b8" --name "shared" --hostpath "C:\ProgramData\BOINC\slots\14/shared"
Exit Code: 0
Output:

2025-01-27 21:10:47 (5864): 
Command: VBoxManage -q startvm "boinc_7ed302fcf31763b8" --type headless
Exit Code: 0
Output:
Waiting for VM "boinc_7ed302fcf31763b8" to power on...
VM "boinc_7ed302fcf31763b8" has been successfully started.

2025-01-27 21:10:48 (5864): 
Command: VBoxManage -q controlvm "boinc_7ed302fcf31763b8" cpuexecutioncap 90 
Exit Code: 0
Output:

2025-01-27 21:58:58 (5864): 
Command: VBoxManage -q snapshot "boinc_7ed302fcf31763b8" list 
Exit Code: -108
Output:

2025-01-27 21:58:58 (5864): 
Command: VBoxManage -q bandwidthctl "boinc_7ed302fcf31763b8" remove "boinc_7ed302fcf31763b8_net" 
Exit Code: -108
Output:

2025-01-27 21:58:58 (5864): 
Command: VBoxManage -q unregistervm "boinc_7ed302fcf31763b8" --delete 
Exit Code: -108
Output:

2025-01-27 21:59:14 (5864): called boinc_finish(194)

</stderr_txt>
]]>


©2025 CERN