Task 3225921

Name	CMS_1175767_1689092499.082829_0
Workunit	2317684
Created	11 Jul 2023, 16:21:40 UTC
Sent	13 Jul 2023, 1:22:18 UTC
Report deadline	20 Jul 2023, 1:22:18 UTC
Received	13 Jul 2023, 3:51:53 UTC
Server state	Over
Outcome	Computation error
Client state	Compute error
Exit status	194 (0x000000C2) EXIT_ABORTED_BY_CLIENT
Computer ID	4934
Run time	1 hours 26 min 35 sec
CPU time	1 hours 42 min 41 sec
Validate state	Invalid
Credit	0.00
Device peak FLOPS	2.00 GFLOPS
Application version	CMS Simulation v60.70 (vbox64_mt_mcore_cms) windows_x86_64
Peak working set size	81.12 MB
Peak swap size	81.38 MB
Peak disk usage	671.36 MB
Stderr output

<core_client_version>7.22.2</core_client_version>
<![CDATA[
<message>
Process still present 5 min after writing finish file; aborting</message>
<stderr_txt>
2023-07-13 11:22:30 (6216): Detected: vboxwrapper 26206
2023-07-13 11:22:30 (6216): Detected: BOINC client v7.22.2
2023-07-13 11:22:32 (6216): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-07-13 11:22:32 (6216): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-07-13 11:22:32 (6216): Successfully copied 'init_data.xml' to the shared directory.
2023-07-13 11:22:33 (6216): Create VM. (boinc_71844fc0eb4d822f, slot#22)
2023-07-13 11:22:33 (6216): Setting Memory Size for VM. (2792MB)
2023-07-13 11:22:34 (6216): Setting CPU Count for VM. (2)
2023-07-13 11:22:34 (6216): Setting Chipset Options for VM.
2023-07-13 11:22:34 (6216): Setting Graphics Controller Options for VM.
2023-07-13 11:22:34 (6216): Setting Boot Options for VM.
2023-07-13 11:22:35 (6216): Setting Network Configuration for NAT.
2023-07-13 11:22:35 (6216): Enabling VM Network Access.
2023-07-13 11:22:35 (6216): Disabling USB Support for VM.
2023-07-13 11:22:35 (6216): Disabling COM Port Support for VM.
2023-07-13 11:22:36 (6216): Disabling LPT Port Support for VM.
2023-07-13 11:22:36 (6216): Disabling Audio Support for VM.
2023-07-13 11:22:36 (6216): Disabling Clipboard Support for VM.
2023-07-13 11:22:37 (6216): Disabling Drag and Drop Support for VM.
2023-07-13 11:22:37 (6216): Adding storage controller(s) to VM.
2023-07-13 11:22:37 (6216): Adding virtual disk drive to VM. (CMS_2022_09_07.vdi)
2023-07-13 11:22:38 (6216): Adding VirtualBox Guest Additions to VM.
2023-07-13 11:22:38 (6216): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2023-07-13 11:22:39 (6216): forwarding host port 55999 to guest port 80
2023-07-13 11:22:39 (6216): Enabling remote desktop for VM.
2023-07-13 11:22:39 (6216): Required extension pack not installed, remote desktop not enabled.
2023-07-13 11:22:39 (6216): Enabling shared directory for VM.
2023-07-13 11:22:40 (6216): Starting VM using VBoxManage interface. (boinc_71844fc0eb4d822f, slot#22)
2023-07-13 11:22:49 (6216): Successfully started VM. (PID = '18284')
2023-07-13 11:22:49 (6216): Reporting VM Process ID to BOINC.
2023-07-13 11:22:49 (6216): Guest Log: BIOS: VirtualBox 6.1.34
2023-07-13 11:22:49 (6216): Guest Log: CPUID EDX: 0x178bfbff
2023-07-13 11:22:49 (6216): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-07-13 11:22:49 (6216): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-07-13 11:22:49 (6216): VM state change detected. (old = 'poweredoff', new = 'running')
2023-07-13 11:22:49 (6216): Detected: Web Application Enabled (http://localhost:55999)
2023-07-13 11:22:49 (6216): Preference change detected
2023-07-13 11:22:49 (6216): Setting CPU throttle for VM. (100%)
2023-07-13 11:22:49 (6216): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-07-13 11:22:51 (6216): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-07-13 11:22:51 (6216): Guest Log: BIOS: Booting from Hard Disk...
2023-07-13 11:22:54 (6216): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-07-13 11:22:54 (6216): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-07-13 11:23:24 (6216): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-07-13 11:23:24 (6216): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-07-13 11:23:25 (6216): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-07-13 11:23:25 (6216): Guest Log: 00:00:00.000275 main     Log opened 2023-07-13T01:23:22.589217000Z
2023-07-13 11:23:25 (6216): Guest Log: 00:00:00.000442 main     OS Product: Linux
2023-07-13 11:23:25 (6216): Guest Log: 00:00:00.000507 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-07-13 11:23:25 (6216): Guest Log: 00:00:00.000567 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-07-13 11:23:25 (6216): Guest Log: 00:00:00.000627 main     Executable: /usr/sbin/VBoxService
2023-07-13 11:23:25 (6216): Guest Log: 00:00:00.000629 main     Process ID: 2207
2023-07-13 11:23:25 (6216): Guest Log: 00:00:00.000630 main     Package type: LINUX_64BITS_GENERIC
2023-07-13 11:23:25 (6216): Guest Log: 00:00:00.001652 main     5.2.6 r120293 started. Verbose level = 0
2023-07-13 11:23:50 (6216): Guest Log: [INFO] Mounting the shared directory
2023-07-13 11:23:50 (6216): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-07-13 11:23:50 (6216): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-07-13 11:23:51 (6216): Guest Log: [INFO] Testing connection to cern.ch
2023-07-13 11:23:52 (6216): Guest Log: [INFO] Testing connection to VCCS
2023-07-13 11:23:53 (6216): Guest Log: [INFO] Testing connection to HTCondor
2023-07-13 11:23:54 (6216): Guest Log: [INFO] Testing connection to WMAgent
2023-07-13 11:23:55 (6216): Guest Log: [INFO] Testing connection to EOSCMS
2023-07-13 11:23:56 (6216): Guest Log: [INFO] Testing connection to CMS-Factory
2023-07-13 11:23:56 (6216): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-07-13 11:23:58 (6216): Guest Log: [INFO] Testing connection to Frontier
2023-07-13 11:23:59 (6216): Guest Log: [INFO] Could not find a local HTTP proxy
2023-07-13 11:23:59 (6216): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-07-13 11:23:59 (6216): Guest Log: [INFO] This makes the application less efficient
2023-07-13 11:23:59 (6216): Guest Log: [INFO] It also puts higher load on the project servers
2023-07-13 11:23:59 (6216): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-07-13 11:23:59 (6216): Guest Log: [INFO] Advice can be found in the project forum
2023-07-13 11:24:01 (6216): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-07-13 11:24:12 (6216): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-07-13 11:24:17 (6216): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-07-13 11:24:18 (6216): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-07-13 11:24:18 (6216): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-07-13 11:24:18 (6216): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-07-13 11:24:20 (6216): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-07-13 11:24:20 (6216): Guest Log: [INFO] 2.7.2.0 http://s1ral-cvmfs.openhtc.io DIRECT
2023-07-13 11:24:20 (6216): Guest Log: [INFO] Environment HTTP proxy: not set
2023-07-13 11:24:20 (6216): Guest Log: [INFO] Reading volunteer information
2023-07-13 11:25:08 (6216): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-07-13 11:25:10 (6216): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-07-13 11:25:13 (6216): Guest Log: [INFO] CMS application starting. Check log files.
2023-07-13 11:25:13 (6216): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-07-13 11:25:15 (6216): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
12:48:25 (15488): Can't acquire lockfile (32) - waiting 35s
12:49:00 (15488): Can't acquire lockfile (32) - exiting
12:49:00 (15488): Error: The process cannot access the file because it is being used by another process.

 (0x20)
12:59:22 (11724): Can't acquire lockfile (32) - waiting 35s
12:59:57 (11724): Can't acquire lockfile (32) - exiting
12:59:57 (11724): Error: The process cannot access the file because it is being used by another process.

 (0x20)
2023-07-13 13:03:03 (6216): Status Report: Job Duration: '64800.000000'
2023-07-13 13:03:03 (6216): Status Report: Elapsed Time: '6000.000000'
2023-07-13 13:03:03 (6216): Status Report: CPU Time: '5454.046875'
13:10:49 (9508): Can't acquire lockfile (32) - waiting 35s
13:11:24 (9508): Can't acquire lockfile (32) - exiting
13:11:24 (9508): Error: The process cannot access the file because it is being used by another process.

 (0x20)
13:21:25 (19192): Can't acquire lockfile (32) - waiting 35s
13:22:00 (19192): Can't acquire lockfile (32) - exiting
13:22:00 (19192): Error: The process cannot access the file because it is being used by another process.

 (0x20)
13:32:29 (17396): Can't acquire lockfile (32) - waiting 35s
13:33:04 (17396): Can't acquire lockfile (32) - exiting
13:33:04 (17396): Error: The process cannot access the file because it is being used by another process.

 (0x20)
13:34:02 (10572): Can't acquire lockfile (32) - waiting 35s
13:34:37 (10572): Can't acquire lockfile (32) - exiting
13:34:37 (10572): Error: The process cannot access the file because it is being used by another process.

 (0x20)
2023-07-13 13:43:05 (6216): VM Heartbeat file specified, but missing heartbeat.
2023-07-13 13:43:05 (6216): Powering off VM.
2023-07-13 13:43:52 (6216): Error in poweroff VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_71844fc0eb4d822f" poweroff
Output:
0%...10%...20%...
2023-07-13 13:43:52 (6216): VM did not power off when requested.
2023-07-13 13:43:52 (6216): VM was successfully terminated.
2023-07-13 13:43:52 (6216): Deregistering VM. (boinc_71844fc0eb4d822f, slot#22)
2023-07-13 13:43:52 (6216): Removing network bandwidth throttle group from VM.
2023-07-13 13:43:52 (6216): Removing VM from VirtualBox.

    Hypervisor System Log:

00:00:07.406107          Saving settings file "C:\ProgramData\BOINC\slots\22\boinc_71844fc0eb4d822f\boinc_71844fc0eb4d822f.vbox" with version "1.16-windows"
00:00:07.411912          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 0 of controller 'Hard Disk Controller'}, preserve=false aResultDetail=0
00:00:07.415123          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=true  aResultDetail=0
00:00:07.415198          Saving settings file "C:\ProgramData\BOINC\slots\22\boinc_71844fc0eb4d822f\boinc_71844fc0eb4d822f.vbox" with version "1.16-windows"
00:00:07.420588          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:07.421251          Saving settings file "C:\ProgramData\BOINC\slots\22\boinc_71844fc0eb4d822f\boinc_71844fc0eb4d822f.vbox" with version "1.16-windows"
00:00:07.661606          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:07.668171          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 1 of controller 'Hard Disk Controller'}, preserve=false aResultDetail=0
00:00:07.672714          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 1 of controller 'Hard Disk Controller'}, preserve=false aResultDetail=0
00:00:07.672909          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:07.673027          Saving settings file "C:\Users\peter\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
00:00:07.677617          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:07.677679          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:07.677755          Saving settings file "C:\Users\peter\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
00:00:07.682492          Saving settings file "C:\ProgramData\BOINC\slots\22\boinc_71844fc0eb4d822f\boinc_71844fc0eb4d822f.vbox" with version "1.16-windows"
00:00:07.917032          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:07.923524          Saving settings file "C:\ProgramData\BOINC\slots\22\boinc_71844fc0eb4d822f\boinc_71844fc0eb4d822f.vbox" with version "1.16-windows"
00:00:08.192974          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:08.262405          Saving settings file "C:\ProgramData\BOINC\slots\22\boinc_71844fc0eb4d822f\boinc_71844fc0eb4d822f.vbox" with version "1.16-windows"
00:00:08.714124          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:08.719706          Saving settings file "C:\ProgramData\BOINC\slots\22\boinc_71844fc0eb4d822f\boinc_71844fc0eb4d822f.vbox" with version "1.16-windows"
00:00:08.964699          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:08.977117          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 2 of controller 'Hard Disk Controller'}, preserve=false aResultDetail=0
00:00:08.977345          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 2 of controller 'Hard Disk Controller'}, preserve=false aResultDetail=0
00:00:08.995860          ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={SessionMachine} aText={This machine does not have any snapshots}, preserve=false aResultDetail=0
00:00:09.239564          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:09.245830          Launched VM: 78476176 pid: 14948 (0x3a64) frontend: headless name: boinc_71844fc0eb4d822f
00:00:18.096517          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.101514          Saving settings file "C:\ProgramData\BOINC\slots\22\boinc_71844fc0eb4d822f\boinc_71844fc0eb4d822f.vbox" with version "1.16-windows"
00:36:27.207330 dns-monitor HostDnsMonitor: updating information
00:36:27.207479 dns-monitor HostDnsMonitor: unchanged
01:03:55.643300 USBPROXY usbLibGetDevices: Starting USB device enumeration
01:03:55.771418 USBPROXY usbLibGetDevices: Found 1 USB devices, 0 captured
01:08:35.080058 USBPROXY usbLibGetDevices: Starting USB device enumeration
01:08:35.208218 USBPROXY usbLibGetDevices: Found 1 USB devices, 0 captured
01:08:37.327366 USBPROXY usbLibGetDevices: Starting USB device enumeration
01:08:37.455986 USBPROXY usbLibGetDevices: Found 1 USB devices, 0 captured
02:20:34.643739          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
02:21:20.959259 Watcher  Saving settings file "C:\ProgramData\BOINC\slots\22\boinc_71844fc0eb4d822f\boinc_71844fc0eb4d822f.vbox" with version "1.16-windows"
02:21:21.027496          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
02:21:21.496079          ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={SessionMachine} aText={This machine does not have any snapshots}, preserve=false aResultDetail=0
02:21:21.601667          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
02:21:21.609025          Saving settings file "C:\ProgramData\BOINC\slots\22\boinc_71844fc0eb4d822f\boinc_71844fc0eb4d822f.vbox" with version "1.16-windows"
02:21:21.900715          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
02:21:21.902965          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
02:21:21.903099          Saving settings file "C:\Users\peter\.VirtualBox\VirtualBox.xml" with version "1.12-windows"

    VM Execution Log:


    VM Startup Log:


    VM Trace Log:

tel(R) CPU @ 2.00GHz
Processor#44 speed: unknown
Processor#44 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#45 speed: unknown
Processor#45 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#46 speed: unknown
Processor#46 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#47 speed: unknown
Processor#47 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#48 speed: unknown
Processor#48 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#49 speed: unknown
Processor#49 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#50 speed: unknown
Processor#50 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#51 speed: unknown
Processor#51 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#52 speed: unknown
Processor#52 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#53 speed: unknown
Processor#53 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#54 speed: unknown
Processor#54 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#55 speed: unknown
Processor#55 description: Genuine Intel(R) CPU @ 2.00GHz
Memory size: 32647 MByte
Memory available: 27841 MByte
Operating system: Windows 10
Operating system version: 10.0.19045

2023-07-13 11:22:33 (6216): 
Command: VBoxManage -q showvminfo "boinc_71844fc0eb4d822f" --machinereadable 
Exit Code: -2135228415
Output:
VBoxManage.exe: error: Could not find a registered machine named 'boinc_71844fc0eb4d822f'
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 2733 of file VBoxManageInfo.cpp

2023-07-13 11:22:33 (6216): 
Command: VBoxManage -q showhdinfo "C:\ProgramData\BOINC\slots\22/vm_image.vdi" 
Exit Code: -2135228412
Output:
VBoxManage.exe: error: Could not find file for the medium 'C:\ProgramData\BOINC\slots\22\vm_image.vdi' (VERR_FILE_NOT_FOUND)
VBoxManage.exe: error: Details: code VBOX_E_FILE_ERROR (0x80bb0004), component MediumWrap, interface IMedium, callee IUnknown
VBoxManage.exe: error: Context: "OpenMedium(Bstr(pszFilenameOrUuid).raw(), enmDevType, enmAccessMode, fForceNewUuidOnOpen, pMedium.asOutParam())" at line 191 of file VBoxManageDisk.cpp

2023-07-13 11:22:33 (6216): 
Command: VBoxManage -q createvm --name "boinc_71844fc0eb4d822f" --basefolder "C:\ProgramData\BOINC\slots\22" --ostype "Linux26_64" --register
Exit Code: 0
Output:
Virtual machine 'boinc_71844fc0eb4d822f' is created and registered.
UUID: ed1667b3-ba71-4e83-8895-1d34aa0a01f6
Settings file: 'C:\ProgramData\BOINC\slots\22\boinc_71844fc0eb4d822f\boinc_71844fc0eb4d822f.vbox'

2023-07-13 11:22:33 (6216): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --description "CMS_1175767_1689092499.082829_0" 
Exit Code: 0
Output:

2023-07-13 11:22:34 (6216): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --memory 2792 
Exit Code: 0
Output:

2023-07-13 11:22:34 (6216): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --cpus 2 
Exit Code: 0
Output:

2023-07-13 11:22:34 (6216): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --acpi on --ioapic on --rtcuseutc off 
Exit Code: 0
Output:

2023-07-13 11:22:34 (6216): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --vram 16 --graphicscontroller VBoxVGA 
Exit Code: 0
Output:

2023-07-13 11:22:35 (6216): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --boot1 disk --boot2 dvd --boot3 none --boot4 none 
Exit Code: 0
Output:

2023-07-13 11:22:35 (6216): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --nic1 nat --natdnsproxy1 on --cableconnected1 off 
Exit Code: 0
Output:

2023-07-13 11:22:35 (6216): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --cableconnected1 on 
Exit Code: 0
Output:

2023-07-13 11:22:35 (6216): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --usb off 
Exit Code: 0
Output:

2023-07-13 11:22:36 (6216): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --uart1 off --uart2 off 
Exit Code: 0
Output:

2023-07-13 11:22:36 (6216): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --lpt1 off --lpt2 off 
Exit Code: 0
Output:

2023-07-13 11:22:36 (6216): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --audio none 
Exit Code: 0
Output:

2023-07-13 11:22:37 (6216): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --clipboard disabled 
Exit Code: 0
Output:

2023-07-13 11:22:37 (6216): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --draganddrop disabled 
Exit Code: 0
Output:

2023-07-13 11:22:37 (6216): 
Command: VBoxManage -q storagectl "boinc_71844fc0eb4d822f" --name "Hard Disk Controller" --add "sata" --controller "IntelAHCI" --hostiocache off --portcount 3
Exit Code: 0
Output:

2023-07-13 11:22:37 (6216): 
Command: VBoxManage -q showhdinfo "C:\ProgramData\BOINC/projects/lhcathomedev.cern.ch_lhcathome-dev/CMS_2022_09_07.vdi" 
Exit Code: 0
Output:
UUID:           8fb925ef-3497-4bfb-88e3-bbab2930787f
Parent UUID:    base
State:          created
Type:           normal (base)
Location:       C:\ProgramData\BOINC\projects\lhcathomedev.cern.ch_lhcathome-dev\CMS_2022_09_07.vdi
Storage format: VDI
Format variant: dynamic default
Capacity:       20480 MBytes
Size on disk:   3854 MBytes
Encryption:     disabled
Property:       AllocationBlockSize=1048576

2023-07-13 11:22:38 (6216): 
Command: VBoxManage -q storageattach "boinc_71844fc0eb4d822f" --storagectl "Hard Disk Controller" --port 0 --device 0 --type hdd --medium "C:\ProgramData\BOINC/projects/lhcathomedev.cern.ch_lhcathome-dev/CMS_2022_09_07.vdi" 
Exit Code: 0
Output:

2023-07-13 11:22:38 (6216): 
Command: VBoxManage -q storageattach "boinc_71844fc0eb4d822f" --storagectl "Hard Disk Controller" --port 0 --device 0 --type hdd --medium none 
Exit Code: 0
Output:

2023-07-13 11:22:38 (6216): 
Command: VBoxManage -q storageattach "boinc_71844fc0eb4d822f" --storagectl "Hard Disk Controller" --port 0 --device 0 --type hdd --mtype multiattach --medium "C:\ProgramData\BOINC/projects/lhcathomedev.cern.ch_lhcathome-dev/CMS_2022_09_07.vdi" 
Exit Code: 0
Output:

2023-07-13 11:22:38 (6216): 
Command: VBoxManage -q storageattach "boinc_71844fc0eb4d822f" --storagectl "Hard Disk Controller" --port 1 --device 0 --type dvddrive --medium "C:\Program Files\Oracle\VirtualBox/VBoxGuestAdditions.iso" 
Exit Code: 0
Output:

2023-07-13 11:22:39 (6216): 
Command: VBoxManage -q bandwidthctl "boinc_71844fc0eb4d822f" add "boinc_71844fc0eb4d822f_net" --type network --limit 1024G 
Exit Code: 0
Output:

2023-07-13 11:22:39 (6216): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --natpf1 ",tcp,127.0.0.1,55999,,80" 
Exit Code: 0
Output:

2023-07-13 11:22:39 (6216): 
Command: VBoxManage -q list extpacks
Exit Code: 0
Output:
Extension Packs: 0

2023-07-13 11:22:39 (6216): 
Command: VBoxManage -q sharedfolder add "boinc_71844fc0eb4d822f" --name "shared" --hostpath "C:\ProgramData\BOINC\slots\22/shared"
Exit Code: 0
Output:

2023-07-13 11:22:48 (6216): 
Command: VBoxManage -q startvm "boinc_71844fc0eb4d822f" --type headless
Exit Code: 0
Output:
Waiting for VM "boinc_71844fc0eb4d822f" to power on...
VM "boinc_71844fc0eb4d822f" has been successfully started.

2023-07-13 11:22:49 (6216): 
Command: VBoxManage -q controlvm "boinc_71844fc0eb4d822f" cpuexecutioncap 100 
Exit Code: 0
Output:

2023-07-13 13:43:52 (6216): 
Command: VBoxManage -q controlvm "boinc_71844fc0eb4d822f" poweroff
Exit Code: -182
Output:
0%...10%...20%...
2023-07-13 13:43:52 (6216): 
Command: VBoxManage -q snapshot "boinc_71844fc0eb4d822f" list 
Exit Code: -108
Output:
This machine does not have any snapshots

2023-07-13 13:43:52 (6216): 
Command: VBoxManage -q bandwidthctl "boinc_71844fc0eb4d822f" remove "boinc_71844fc0eb4d822f_net" 
Exit Code: 0
Output:

2023-07-13 13:43:53 (6216): 
Command: VBoxManage -q unregistervm "boinc_71844fc0eb4d822f" --delete 
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%

13:44:03 (6216): called boinc_finish(194)
2023-07-13 13:44:45 (6340): Detected: vboxwrapper 26206
2023-07-13 13:44:45 (6340): Detected: BOINC client v7.22.2
2023-07-13 13:44:46 (6340): Detected: VirtualBox VboxManage Interface (Version: 6.1.34)
2023-07-13 13:44:46 (6340): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-07-13 13:44:47 (6340): Create VM. (boinc_71844fc0eb4d822f, slot#22)
2023-07-13 13:44:47 (6340): Setting Memory Size for VM. (2792MB)
2023-07-13 13:44:47 (6340): Setting CPU Count for VM. (2)
2023-07-13 13:44:48 (6340): Setting Chipset Options for VM.
2023-07-13 13:44:48 (6340): Setting Graphics Controller Options for VM.
2023-07-13 13:44:48 (6340): Setting Boot Options for VM.
2023-07-13 13:44:48 (6340): Setting Network Configuration for NAT.
2023-07-13 13:44:49 (6340): Enabling VM Network Access.
2023-07-13 13:44:49 (6340): Disabling USB Support for VM.
2023-07-13 13:44:49 (6340): Disabling COM Port Support for VM.
2023-07-13 13:44:50 (6340): Disabling LPT Port Support for VM.
2023-07-13 13:44:50 (6340): Disabling Audio Support for VM.
2023-07-13 13:44:50 (6340): Disabling Clipboard Support for VM.
2023-07-13 13:44:50 (6340): Disabling Drag and Drop Support for VM.
2023-07-13 13:44:51 (6340): Adding storage controller(s) to VM.
2023-07-13 13:44:51 (6340): Adding virtual disk drive to VM. (CMS_2022_09_07.vdi)
2023-07-13 13:44:51 (6340): Adding VirtualBox Guest Additions to VM.
2023-07-13 13:44:52 (6340): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2023-07-13 13:44:52 (6340): forwarding host port 55999 to guest port 80
2023-07-13 13:44:52 (6340): Enabling remote desktop for VM.
2023-07-13 13:44:52 (6340): Required extension pack not installed, remote desktop not enabled.
2023-07-13 13:44:52 (6340): Enabling shared directory for VM.
2023-07-13 13:44:53 (6340): Starting VM using VBoxManage interface. (boinc_71844fc0eb4d822f, slot#22)
2023-07-13 13:45:05 (6340): Successfully started VM. (PID = '20160')
2023-07-13 13:45:05 (6340): Reporting VM Process ID to BOINC.
2023-07-13 13:45:05 (6340): Guest Log: BIOS: VirtualBox 6.1.34
2023-07-13 13:45:05 (6340): Guest Log: CPUID EDX: 0x178bfbff
2023-07-13 13:45:05 (6340): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2023-07-13 13:45:05 (6340): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-07-13 13:45:05 (6340): VM state change detected. (old = 'poweredoff', new = 'running')
2023-07-13 13:45:05 (6340): Detected: Web Application Enabled (http://localhost:55999)
2023-07-13 13:45:05 (6340): Status Report: Job Duration: '64800.000000'
2023-07-13 13:45:05 (6340): Status Report: Elapsed Time: '8377.000000'
2023-07-13 13:45:05 (6340): Status Report: CPU Time: '5995.500000'
2023-07-13 13:45:05 (6340): Preference change detected
2023-07-13 13:45:05 (6340): Setting CPU throttle for VM. (100%)
2023-07-13 13:45:05 (6340): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2023-07-13 13:45:07 (6340): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-07-13 13:45:07 (6340): Guest Log: BIOS: Booting from Hard Disk...
2023-07-13 13:45:10 (6340): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-07-13 13:45:10 (6340): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-07-13 13:45:45 (6340): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-07-13 13:45:45 (6340): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-07-13 13:45:46 (6340): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-07-13 13:45:46 (6340): Guest Log: 00:00:00.001296 main     Log opened 2023-07-13T03:45:44.341658000Z
2023-07-13 13:45:46 (6340): Guest Log: 00:00:00.001596 main     OS Product: Linux
2023-07-13 13:45:46 (6340): Guest Log: 00:00:00.001679 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-07-13 13:45:46 (6340): Guest Log: 00:00:00.001745 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-07-13 13:45:46 (6340): Guest Log: 00:00:00.001803 main     Executable: /usr/sbin/VBoxService
2023-07-13 13:45:46 (6340): Guest Log: 00:00:00.001805 main     Process ID: 2205
2023-07-13 13:45:46 (6340): Guest Log: 00:00:00.001807 main     Package type: LINUX_64BITS_GENERIC
2023-07-13 13:45:46 (6340): Guest Log: 00:00:00.005884 main     5.2.6 r120293 started. Verbose level = 0
2023-07-13 13:46:16 (6340): Guest Log: [INFO] Mounting the shared directory
2023-07-13 13:46:16 (6340): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-07-13 13:46:17 (6340): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-07-13 13:46:17 (6340): Guest Log: [INFO] Testing connection to cern.ch
2023-07-13 13:46:18 (6340): Guest Log: [INFO] Testing connection to VCCS
2023-07-13 13:46:19 (6340): Guest Log: [INFO] Testing connection to HTCondor
2023-07-13 13:46:19 (6340): Guest Log: [INFO] Testing connection to WMAgent
2023-07-13 13:46:20 (6340): Guest Log: [INFO] Testing connection to EOSCMS
2023-07-13 13:46:21 (6340): Guest Log: [INFO] Testing connection to CMS-Factory
2023-07-13 13:46:22 (6340): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-07-13 13:46:23 (6340): Guest Log: [INFO] Testing connection to Frontier
2023-07-13 13:46:25 (6340): Guest Log: [INFO] Could not find a local HTTP proxy
2023-07-13 13:46:25 (6340): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-07-13 13:46:26 (6340): Guest Log: [INFO] This makes the application less efficient
2023-07-13 13:46:26 (6340): Guest Log: [INFO] It also puts higher load on the project servers
2023-07-13 13:46:26 (6340): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-07-13 13:46:26 (6340): Guest Log: [INFO] Advice can be found in the project forum
2023-07-13 13:46:28 (6340): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-07-13 13:46:40 (6340): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-07-13 13:46:48 (6340): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-07-13 13:46:48 (6340): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-07-13 13:46:48 (6340): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-07-13 13:46:50 (6340): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-07-13 13:46:51 (6340): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-07-13 13:46:51 (6340): Guest Log: [INFO] 2.7.2.0 http://s1fnal-cvmfs.openhtc.io:8080 DIRECT
2023-07-13 13:46:51 (6340): Guest Log: [INFO] Environment HTTP proxy: not set
2023-07-13 13:46:52 (6340): Guest Log: [INFO] Reading volunteer information
2023-07-13 13:47:18 (6340): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-07-13 13:47:20 (6340): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-07-13 13:47:22 (6340): Guest Log: [INFO] CMS application starting. Check log files.
2023-07-13 13:47:22 (6340): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-07-13 13:47:25 (6340): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-07-13 13:50:10 (6340): Powering off VM.
2023-07-13 13:50:11 (6340): Successfully stopped VM.
2023-07-13 13:50:11 (6340): Deregistering VM. (boinc_71844fc0eb4d822f, slot#22)
2023-07-13 13:50:11 (6340): Removing network bandwidth throttle group from VM.
2023-07-13 13:50:11 (6340): Removing VM from VirtualBox.

    Hypervisor System Log:

02:22:20.164019          Saving settings file "C:\ProgramData\BOINC\slots\22\boinc_71844fc0eb4d822f\boinc_71844fc0eb4d822f.vbox" with version "1.16-windows"
02:22:20.678888          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
02:22:20.687266          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 0 of controller 'Hard Disk Controller'}, preserve=false aResultDetail=0
02:22:20.689243          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 0 of controller 'Hard Disk Controller'}, preserve=false aResultDetail=0
02:22:20.689420          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
02:22:20.693455          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=true  aResultDetail=0
02:22:20.693507          Saving settings file "C:\ProgramData\BOINC\slots\22\boinc_71844fc0eb4d822f\boinc_71844fc0eb4d822f.vbox" with version "1.16-windows"
02:22:20.697599          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
02:22:20.698078          Saving settings file "C:\ProgramData\BOINC\slots\22\boinc_71844fc0eb4d822f\boinc_71844fc0eb4d822f.vbox" with version "1.16-windows"
02:22:20.953312          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
02:22:20.961565          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 1 of controller 'Hard Disk Controller'}, preserve=false aResultDetail=0
02:22:20.963644          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 1 of controller 'Hard Disk Controller'}, preserve=false aResultDetail=0
02:22:20.963789          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
02:22:20.964010          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
02:22:20.964054          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
02:22:20.964491          Saving settings file "C:\ProgramData\BOINC\slots\22\boinc_71844fc0eb4d822f\boinc_71844fc0eb4d822f.vbox" with version "1.16-windows"
02:22:21.212198          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
02:22:21.220307          Saving settings file "C:\ProgramData\BOINC\slots\22\boinc_71844fc0eb4d822f\boinc_71844fc0eb4d822f.vbox" with version "1.16-windows"
02:22:21.486842          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
02:22:21.500683          Saving settings file "C:\ProgramData\BOINC\slots\22\boinc_71844fc0eb4d822f\boinc_71844fc0eb4d822f.vbox" with version "1.16-windows"
02:22:22.003518          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
02:22:22.011384          Saving settings file "C:\ProgramData\BOINC\slots\22\boinc_71844fc0eb4d822f\boinc_71844fc0eb4d822f.vbox" with version "1.16-windows"
02:22:22.274954          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
02:22:22.296672          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 2 of controller 'Hard Disk Controller'}, preserve=false aResultDetail=0
02:22:22.296840          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 2 of controller 'Hard Disk Controller'}, preserve=false aResultDetail=0
02:22:22.318205          ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={SessionMachine} aText={This machine does not have any snapshots}, preserve=false aResultDetail=0
02:22:22.542060          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
02:22:22.549101          Launched VM: 78734000 pid: 7208 (0x1c28) frontend: headless name: boinc_71844fc0eb4d822f
02:22:34.215152          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
02:22:34.223125          Saving settings file "C:\ProgramData\BOINC\slots\22\boinc_71844fc0eb4d822f\boinc_71844fc0eb4d822f.vbox" with version "1.16-windows"
02:27:39.424520          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
02:27:39.725434          Saving settings file "C:\ProgramData\BOINC\slots\22\boinc_71844fc0eb4d822f\boinc_71844fc0eb4d822f.vbox" with version "1.16-windows"
02:27:40.259120          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
02:27:40.267575          ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={SessionMachine} aText={This machine does not have any snapshots}, preserve=false aResultDetail=0
02:27:40.398735          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
02:27:40.404970          Saving settings file "C:\ProgramData\BOINC\slots\22\boinc_71844fc0eb4d822f\boinc_71844fc0eb4d822f.vbox" with version "1.16-windows"
02:27:40.662317          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
02:27:40.664057          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={85632c68-b5bb-4316-a900-5eb28d3413df} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
02:27:40.664198          Saving settings file "C:\Users\peter\.VirtualBox\VirtualBox.xml" with version "1.12-windows"

    VM Execution Log:


    VM Startup Log:


    VM Trace Log:

e Intel(R) CPU @ 2.00GHz
Processor#39 speed: unknown
Processor#39 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#40 speed: unknown
Processor#40 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#41 speed: unknown
Processor#41 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#42 speed: unknown
Processor#42 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#43 speed: unknown
Processor#43 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#44 speed: unknown
Processor#44 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#45 speed: unknown
Processor#45 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#46 speed: unknown
Processor#46 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#47 speed: unknown
Processor#47 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#48 speed: unknown
Processor#48 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#49 speed: unknown
Processor#49 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#50 speed: unknown
Processor#50 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#51 speed: unknown
Processor#51 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#52 speed: unknown
Processor#52 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#53 speed: unknown
Processor#53 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#54 speed: unknown
Processor#54 description: Genuine Intel(R) CPU @ 2.00GHz
Processor#55 speed: unknown
Processor#55 description: Genuine Intel(R) CPU @ 2.00GHz
Memory size: 32647 MByte
Memory available: 3655 MByte
Operating system: Windows 10
Operating system version: 10.0.19045

2023-07-13 13:44:46 (6340): 
Command: VBoxManage -q showvminfo "boinc_71844fc0eb4d822f" --machinereadable 
Exit Code: -2135228415
Output:
VBoxManage.exe: error: Could not find a registered machine named 'boinc_71844fc0eb4d822f'
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 2733 of file VBoxManageInfo.cpp

2023-07-13 13:44:47 (6340): 
Command: VBoxManage -q showhdinfo "C:\ProgramData\BOINC\slots\22/vm_image.vdi" 
Exit Code: -2135228412
Output:
VBoxManage.exe: error: Could not find file for the medium 'C:\ProgramData\BOINC\slots\22\vm_image.vdi' (VERR_FILE_NOT_FOUND)
VBoxManage.exe: error: Details: code VBOX_E_FILE_ERROR (0x80bb0004), component MediumWrap, interface IMedium, callee IUnknown
VBoxManage.exe: error: Context: "OpenMedium(Bstr(pszFilenameOrUuid).raw(), enmDevType, enmAccessMode, fForceNewUuidOnOpen, pMedium.asOutParam())" at line 191 of file VBoxManageDisk.cpp

2023-07-13 13:44:47 (6340): 
Command: VBoxManage -q createvm --name "boinc_71844fc0eb4d822f" --basefolder "C:\ProgramData\BOINC\slots\22" --ostype "Linux26_64" --register
Exit Code: 0
Output:
Virtual machine 'boinc_71844fc0eb4d822f' is created and registered.
UUID: fe3b6807-94d9-4c4c-b3ae-f122a452c008
Settings file: 'C:\ProgramData\BOINC\slots\22\boinc_71844fc0eb4d822f\boinc_71844fc0eb4d822f.vbox'

2023-07-13 13:44:47 (6340): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --description "CMS_1175767_1689092499.082829_0" 
Exit Code: 0
Output:

2023-07-13 13:44:47 (6340): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --memory 2792 
Exit Code: 0
Output:

2023-07-13 13:44:48 (6340): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --cpus 2 
Exit Code: 0
Output:

2023-07-13 13:44:48 (6340): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --acpi on --ioapic on --rtcuseutc off 
Exit Code: 0
Output:

2023-07-13 13:44:48 (6340): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --vram 16 --graphicscontroller VBoxVGA 
Exit Code: 0
Output:

2023-07-13 13:44:48 (6340): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --boot1 disk --boot2 dvd --boot3 none --boot4 none 
Exit Code: 0
Output:

2023-07-13 13:44:49 (6340): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --nic1 nat --natdnsproxy1 on --cableconnected1 off 
Exit Code: 0
Output:

2023-07-13 13:44:49 (6340): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --cableconnected1 on 
Exit Code: 0
Output:

2023-07-13 13:44:49 (6340): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --usb off 
Exit Code: 0
Output:

2023-07-13 13:44:50 (6340): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --uart1 off --uart2 off 
Exit Code: 0
Output:

2023-07-13 13:44:50 (6340): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --lpt1 off --lpt2 off 
Exit Code: 0
Output:

2023-07-13 13:44:50 (6340): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --audio none 
Exit Code: 0
Output:

2023-07-13 13:44:50 (6340): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --clipboard disabled 
Exit Code: 0
Output:

2023-07-13 13:44:51 (6340): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --draganddrop disabled 
Exit Code: 0
Output:

2023-07-13 13:44:51 (6340): 
Command: VBoxManage -q storagectl "boinc_71844fc0eb4d822f" --name "Hard Disk Controller" --add "sata" --controller "IntelAHCI" --hostiocache off --portcount 3
Exit Code: 0
Output:

2023-07-13 13:44:51 (6340): 
Command: VBoxManage -q showhdinfo "C:\ProgramData\BOINC/projects/lhcathomedev.cern.ch_lhcathome-dev/CMS_2022_09_07.vdi" 
Exit Code: 0
Output:
UUID:           8fb925ef-3497-4bfb-88e3-bbab2930787f
Parent UUID:    base
State:          created
Type:           multiattach
Location:       C:\ProgramData\BOINC\projects\lhcathomedev.cern.ch_lhcathome-dev\CMS_2022_09_07.vdi
Storage format: VDI
Format variant: dynamic default
Capacity:       20480 MBytes
Size on disk:   3854 MBytes
Encryption:     disabled
Property:       AllocationBlockSize=1048576

2023-07-13 13:44:51 (6340): 
Command: VBoxManage -q storageattach "boinc_71844fc0eb4d822f" --storagectl "Hard Disk Controller" --port 0 --device 0 --type hdd --mtype multiattach --medium "C:\ProgramData\BOINC/projects/lhcathomedev.cern.ch_lhcathome-dev/CMS_2022_09_07.vdi" 
Exit Code: 0
Output:

2023-07-13 13:44:52 (6340): 
Command: VBoxManage -q storageattach "boinc_71844fc0eb4d822f" --storagectl "Hard Disk Controller" --port 1 --device 0 --type dvddrive --medium "C:\Program Files\Oracle\VirtualBox/VBoxGuestAdditions.iso" 
Exit Code: 0
Output:

2023-07-13 13:44:52 (6340): 
Command: VBoxManage -q bandwidthctl "boinc_71844fc0eb4d822f" add "boinc_71844fc0eb4d822f_net" --type network --limit 1024G 
Exit Code: 0
Output:

2023-07-13 13:44:52 (6340): 
Command: VBoxManage -q modifyvm "boinc_71844fc0eb4d822f" --natpf1 ",tcp,127.0.0.1,55999,,80" 
Exit Code: 0
Output:

2023-07-13 13:44:52 (6340): 
Command: VBoxManage -q list extpacks
Exit Code: 0
Output:
Extension Packs: 0

2023-07-13 13:44:53 (6340): 
Command: VBoxManage -q sharedfolder add "boinc_71844fc0eb4d822f" --name "shared" --hostpath "C:\ProgramData\BOINC\slots\22/shared"
Exit Code: 0
Output:

2023-07-13 13:45:04 (6340): 
Command: VBoxManage -q startvm "boinc_71844fc0eb4d822f" --type headless
Exit Code: 0
Output:
Waiting for VM "boinc_71844fc0eb4d822f" to power on...
VM "boinc_71844fc0eb4d822f" has been successfully started.

2023-07-13 13:45:05 (6340): 
Command: VBoxManage -q controlvm "boinc_71844fc0eb4d822f" cpuexecutioncap 100 
Exit Code: 0
Output:

2023-07-13 13:50:11 (6340): 
Command: VBoxManage -q controlvm "boinc_71844fc0eb4d822f" poweroff
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%

2023-07-13 13:50:11 (6340): 
Command: VBoxManage -q snapshot "boinc_71844fc0eb4d822f" list 
Exit Code: -108
Output:
This machine does not have any snapshots

2023-07-13 13:50:11 (6340): 
Command: VBoxManage -q bandwidthctl "boinc_71844fc0eb4d822f" remove "boinc_71844fc0eb4d822f_net" 
Exit Code: 0
Output:

2023-07-13 13:50:11 (6340): 
Command: VBoxManage -q unregistervm "boinc_71844fc0eb4d822f" --delete 
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%

13:50:22 (6340): called boinc_finish(194)

</stderr_txt>
]]>
Development for LHC@home

Stderr output
