Name CMS_2666014_1680289695.076526_0
Workunit 2291700
Created 31 Mar 2023, 19:08:16 UTC
Sent 1 Apr 2023, 16:38:55 UTC
Report deadline 8 Apr 2023, 16:38:55 UTC
Received 2 Apr 2023, 4:37:36 UTC
Server state Over
Outcome Computation error
Client state Compute error
Exit status 1 (0x00000001) Unknown error code
Computer ID 1598
Run time 5 min 46 sec
CPU time 2 min 58 sec
Validate state Invalid
Credit 0.00
Device peak FLOPS 6.32 GFLOPS
Application version CMS Simulation v60.70 (vbox64_mt_mcore_cms)
windows_x86_64
Peak working set size 11.12 MB
Peak swap size 6.22 MB
Peak disk usage 469.46 MB

Stderr output

<core_client_version>7.16.20</core_client_version>
<![CDATA[
<message>
Incorrect function.
 (0x1) - exit code 1 (0x1)</message>
<stderr_txt>
2023-04-01 18:20:05 (7712): Detected: vboxwrapper 26206
2023-04-01 18:20:05 (7712): Detected: BOINC client v7.16.20
2023-04-01 18:20:08 (7712): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2023-04-01 18:20:10 (7712): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-04-01 18:20:10 (7712): Successfully copied 'init_data.xml' to the shared directory.
2023-04-01 18:20:14 (7712): Create VM. (boinc_8e404e37f32b75c5, slot#4)
2023-04-01 18:20:17 (7712): Setting Memory Size for VM. (2792MB)
2023-04-01 18:20:18 (7712): Setting CPU Count for VM. (2)
2023-04-01 18:20:18 (7712): Setting Chipset Options for VM.
2023-04-01 18:20:19 (7712): Setting Graphics Controller Options for VM.
2023-04-01 18:20:19 (7712): Setting Boot Options for VM.
2023-04-01 18:20:20 (7712): Setting Network Configuration for NAT.
2023-04-01 18:20:20 (7712): Enabling VM Network Access.
2023-04-01 18:20:21 (7712): Disabling USB Support for VM.
2023-04-01 18:20:21 (7712): Disabling COM Port Support for VM.
2023-04-01 18:20:21 (7712): Disabling LPT Port Support for VM.
2023-04-01 18:20:22 (7712): Disabling Audio Support for VM.
2023-04-01 18:20:22 (7712): Disabling Clipboard Support for VM.
2023-04-01 18:20:22 (7712): Disabling Drag and Drop Support for VM.
2023-04-01 18:20:23 (7712): Adding storage controller(s) to VM.
2023-04-01 18:20:23 (7712): Adding virtual disk drive to VM. (CMS_2022_09_07.vdi)
2023-04-01 18:20:24 (7712): Adding VirtualBox Guest Additions to VM.
2023-04-01 18:20:24 (7712): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2023-04-01 18:20:25 (7712): forwarding host port 61167 to guest port 80
2023-04-01 18:20:25 (7712): Enabling remote desktop for VM.
2023-04-01 18:20:25 (7712): Required extension pack not installed, remote desktop not enabled.
2023-04-01 18:20:25 (7712): Enabling shared directory for VM.
2023-04-01 18:20:26 (7712): Starting VM using VBoxManage interface. (boinc_8e404e37f32b75c5, slot#4)
2023-04-01 18:20:39 (7712): Successfully started VM. (PID = '10576')
2023-04-01 18:20:39 (7712): Reporting VM Process ID to BOINC.
2023-04-01 18:20:39 (7712): Guest Log: BIOS: VirtualBox 6.0.14
2023-04-01 18:20:39 (7712): Guest Log: CPUID EDX: 0x178bfbff
2023-04-01 18:20:39 (7712): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2023-04-01 18:20:39 (7712): VM state change detected. (old = 'poweredoff', new = 'running')
2023-04-01 18:20:39 (7712): Detected: Web Application Enabled (http://localhost:61167)
2023-04-01 18:20:39 (7712): Preference change detected
2023-04-01 18:20:39 (7712): Setting CPU throttle for VM. (90%)
2023-04-01 18:20:39 (7712): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 300 seconds) or (Vbox_job.xml: 600 seconds))
2023-04-01 18:20:41 (7712): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2023-04-01 18:20:41 (7712): Guest Log: BIOS: Booting from Hard Disk...
2023-04-01 18:20:44 (7712): Guest Log: BIOS: KBD: unsupported int 16h function 03
2023-04-01 18:20:44 (7712): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2023-04-01 18:21:27 (7712): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2023-04-01 18:21:27 (7712): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2023-04-01 18:21:29 (7712): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2023-04-01 18:21:29 (7712): Guest Log: 00:00:00.000179 main     Log opened 2023-04-01T22:21:30.021690000Z
2023-04-01 18:21:29 (7712): Guest Log: 00:00:00.000408 main     OS Product: Linux
2023-04-01 18:21:29 (7712): Guest Log: 00:00:00.000461 main     OS Release: 4.14.232-19.cernvm.x86_64
2023-04-01 18:21:29 (7712): Guest Log: 00:00:00.000508 main     OS Version: #1 SMP Fri Apr 30 17:12:25 CEST 2021
2023-04-01 18:21:29 (7712): Guest Log: 00:00:00.000554 main     Executable: /usr/sbin/VBoxService
2023-04-01 18:21:29 (7712): Guest Log: 00:00:00.000555 main     Process ID: 2196
2023-04-01 18:21:29 (7712): Guest Log: 00:00:00.000557 main     Package type: LINUX_64BITS_GENERIC
2023-04-01 18:21:29 (7712): Guest Log: 00:00:00.007600 main     5.2.6 r120293 started. Verbose level = 0
2023-04-01 18:22:09 (7712): Guest Log: [INFO] Mounting the shared directory
2023-04-01 18:22:09 (7712): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2023-04-01 18:22:10 (7712): Guest Log: [INFO] Sourcing essential functions from /cvmfs/grid.cern.ch
2023-04-01 18:22:10 (7712): Guest Log: [INFO] Testing connection to cern.ch
2023-04-01 18:22:11 (7712): Guest Log: [INFO] Testing connection to VCCS
2023-04-01 18:22:12 (7712): Guest Log: [INFO] Testing connection to HTCondor
2023-04-01 18:22:12 (7712): Guest Log: [INFO] Testing connection to WMAgent
2023-04-01 18:22:13 (7712): Guest Log: [INFO] Testing connection to EOSCMS
2023-04-01 18:22:13 (7712): Guest Log: [INFO] Testing connection to CMS-Factory
2023-04-01 18:22:14 (7712): Guest Log: [INFO] Testing connection to CMS-Frontier
2023-04-01 18:22:15 (7712): Guest Log: [INFO] Testing connection to Frontier
2023-04-01 18:22:17 (7712): Guest Log: [INFO] Could not find a local HTTP proxy
2023-04-01 18:22:17 (7712): Guest Log: [INFO] CVMFS and Frontier will have to use DIRECT connections
2023-04-01 18:22:17 (7712): Guest Log: [INFO] This makes the application less efficient
2023-04-01 18:22:17 (7712): Guest Log: [INFO] It also puts higher load on the project servers
2023-04-01 18:22:17 (7712): Guest Log: [INFO] Setting up a local HTTP proxy is highly recommended
2023-04-01 18:22:18 (7712): Guest Log: [INFO] Advice can be found in the project forum
2023-04-01 18:22:19 (7712): Guest Log: [INFO] Reloading and probing the CVMFS configuration
2023-04-01 18:22:32 (7712): Guest Log: [INFO] Probing /cvmfs/grid.cern.ch... OK
2023-04-01 18:22:42 (7712): Guest Log: [INFO] Probing /cvmfs/oasis.opensciencegrid.org... OK
2023-04-01 18:22:42 (7712): Guest Log: [INFO] Probing /cvmfs/cms.cern.ch... OK
2023-04-01 18:22:43 (7712): Guest Log: [INFO] Probing /cvmfs/singularity.opensciencegrid.org... OK
2023-04-01 18:22:47 (7712): Guest Log: [INFO] Probing /cvmfs/cms-ib.cern.ch... OK
2023-04-01 18:22:49 (7712): Guest Log: [INFO] Excerpt from "cvmfs_config stat": VERSION HOST PROXY
2023-04-01 18:22:50 (7712): Guest Log: [INFO] 2.7.2.0 http://s1bnl-cvmfs.openhtc.io DIRECT
2023-04-01 18:22:50 (7712): Guest Log: [INFO] Environment HTTP proxy: not set
2023-04-01 18:22:50 (7712): Guest Log: [INFO] Reading volunteer information
2023-04-01 18:23:04 (7712): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2023-04-01 18:23:06 (7712): Guest Log: [INFO] Requesting an X509 credential from vLHC@home-dev
2023-04-01 18:23:09 (7712): Guest Log: [INFO] CMS application starting. Check log files.
2023-04-01 18:23:10 (7712): Guest Log: [INFO] Requesting an idtoken from LHC@home
2023-04-01 18:23:11 (7712): Guest Log: [INFO] Requesting an idtoken from vLHC@home-dev
2023-04-01 20:02:05 (7712): Guest Log: 01:07:26.442935 timesync vgsvcTimeSyncWorker: Radical host time change: 2 912 185 000 000ns (HostNow=1 680 391 693 447 000 000 ns HostLast=1 680 388 781 262 000 000 ns)
2023-04-01 20:02:18 (7712): Guest Log: 01:07:34.466670 timesync vgsvcTimeSyncWorker: Radical guest time change: 2 900 286 698 000ns (GuestNow=1 680 391 736 464 381 000 ns GuestLast=1 680 388 836 177 683 000 ns fSetTimeLastLoop=false)
2023-04-01 20:02:18 (7712): Guest Log: [INFO] glidein exited with return value 1.
2023-04-01 20:02:18 (7712): Guest Log: [INFO] Shutting Down.
2023-04-02 00:32:20 (8684): Detected: vboxwrapper 26206
2023-04-02 00:32:20 (8684): Detected: BOINC client v7.16.20
2023-04-02 00:32:25 (8684): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2023-04-02 00:32:26 (8684): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2023-04-02 00:32:26 (8684): Register VM. (boinc_8e404e37f32b75c5, slot#4)
2023-04-02 00:32:35 (8684): Error in register for VM: -2147467259
Command:
VBoxManage -q registervm "C:\ProgramData\BOINC\slots\4/boinc_8e404e37f32b75c5/boinc_8e404e37f32b75c5.vbox" 
Output:
VBoxManage.exe: error: Trying to open a VM config 'C:\ProgramData\BOINC\slots\4/boinc_8e404e37f32b75c5/boinc_8e404e37f32b75c5.vbox' which has the same UUID as an existing virtual machine
VBoxManage.exe: error: Details: code E_FAIL (0x80004005), component MachineWrap, interface IMachine, callee IUnknown
VBoxManage.exe: error: Context: "OpenMachine(Bstr(a->argv[0]).raw(), machine.asOutParam())" at line 86 of file VBoxManageMisc.cpp

2023-04-02 00:32:35 (8684): Could not register
2023-04-02 00:32:35 (8684): ERROR: VM failed to start
2023-04-02 00:32:35 (8684): Powering off VM.
2023-04-02 00:32:35 (8684): Deregistering VM. (boinc_8e404e37f32b75c5, slot#4)
2023-04-02 00:32:35 (8684): Removing network bandwidth throttle group from VM.
2023-04-02 00:32:35 (8684): Removing VM from VirtualBox.

    Hypervisor System Log:

00:00:18.382925          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.382944          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.382962          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.382980          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.382998          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383017          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383036          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383057          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383076          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383095          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383113          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383132          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383151          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383174          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383194          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383212          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383230          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383248          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383264          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383282          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383298          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383316          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383333          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383359          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383378          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383396          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383414          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383432          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383451          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383468          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383487          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383505          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383527          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383546          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383563          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383579          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
00:00:18.383597          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={5047460a-265d-4538-b23e-ddba5fb84976} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0

    VM Execution Log:

04:35:31.822545 TM: Not bothering to attempt catching up a 197 633 285 580 ns lag; new total: 8 961 760 294 006
04:36:34.517423 TM: Not bothering to attempt catching up a 76 223 695 327 ns lag; new total: 9 037 983 989 333
04:36:56.147443 VMMDev: GuestHeartBeat: Guest is alive (gone 394 602 389 744 ns)
04:37:41.225927 TM: Not bothering to attempt catching up a 69 694 220 155 ns lag; new total: 9 107 678 209 488
04:38:06.178049 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 72 seconds ago
04:38:43.473800 TM: Giving up catch-up attempt at a 61 253 961 183 ns lag; new total: 9 168 932 170 671 ns
04:38:54.156146 VMMDev: GuestHeartBeat: Guest is alive (gone 121 252 063 614 ns)
04:38:59.269947 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 6 seconds ago
04:38:59.845091 VMMDev: GuestHeartBeat: Guest is alive (gone 7 084 998 060 ns)
04:41:54.954419 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 5 seconds ago
04:42:14.910782 TM: Giving up catch-up attempt at a 64 776 810 654 ns lag; new total: 9 233 708 981 325 ns
04:42:21.466446 VMMDev: GuestHeartBeat: Guest is alive (gone 30 490 468 122 ns)
04:43:36.844104 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 4 seconds ago
04:43:41.777844 VMMDev: GuestHeartBeat: Guest is alive (gone 12 463 694 472 ns)
04:43:46.005278 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 4 seconds ago
04:43:46.005819 VMMDev: GuestHeartBeat: Guest is alive (gone 4 228 020 775 ns)
04:46:23.749227 TM: Giving up catch-up attempt at a 60 003 260 189 ns lag; new total: 9 293 712 241 514 ns
04:46:31.864857 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 12 seconds ago
04:46:31.875762 VMMDev: GuestHeartBeat: Guest is alive (gone 14 487 793 536 ns)
04:47:54.358314 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 6 seconds ago
04:48:00.980102 VMMDev: GuestHeartBeat: Guest is alive (gone 8 744 115 738 ns)
04:48:32.361998 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 4 seconds ago
04:48:59.451085 TM: Giving up catch-up attempt at a 73 092 125 557 ns lag; new total: 9 366 804 367 071 ns
04:49:13.891282 VMMDev: GuestHeartBeat: Guest is alive (gone 52 277 356 118 ns)
04:50:50.938745 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 5 seconds ago
04:50:53.933712 VMMDev: GuestHeartBeat: Guest is alive (gone 10 430 702 529 ns)
04:51:00.246457 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 4 seconds ago
04:51:01.903160 VMMDev: GuestHeartBeat: Guest is alive (gone 5 657 448 739 ns)
04:51:09.328649 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 4 seconds ago
04:51:39.602917 TM: Giving up catch-up attempt at a 67 722 370 421 ns lag; new total: 9 434 526 737 492 ns
04:51:51.636141 VMMDev: GuestHeartBeat: Guest is alive (gone 46 492 606 245 ns)
04:52:02.842452 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 6 seconds ago
04:52:03.206438 VMMDev: GuestHeartBeat: Guest is alive (gone 363 700 548 ns)
04:52:09.228865 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 6 seconds ago
04:52:09.229932 VMMDev: GuestHeartBeat: Guest is alive (gone 6 023 495 490 ns)
04:52:45.592069 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 4 seconds ago
04:52:49.698470 VMMDev: GuestHeartBeat: Guest is alive (gone 10 071 218 854 ns)
04:53:51.304806 TM: Giving up catch-up attempt at a 60 931 244 200 ns lag; new total: 9 495 457 981 692 ns
04:53:56.221335 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 14 seconds ago
04:53:56.222105 VMMDev: GuestHeartBeat: Guest is alive (gone 14 639 217 351 ns)
04:57:11.984798 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 6 seconds ago
04:57:16.441399 VMMDev: GuestHeartBeat: Guest is alive (gone 12 715 977 563 ns)
04:58:20.188103 TM: Giving up catch-up attempt at a 60 000 255 909 ns lag; new total: 9 555 458 237 601 ns
04:58:27.324703 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 6 seconds ago
04:58:27.326037 VMMDev: GuestHeartBeat: Guest is alive (gone 9 579 831 029 ns)
05:00:46.632006 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 5 seconds ago
05:01:26.053020 TM: Giving up catch-up attempt at a 76 470 337 368 ns lag; new total: 9 631 928 574 969 ns
05:05:40.954490 TM: Not bothering to attempt catching up a 249 559 164 326 ns lag; new total: 9 881 487 739 295
05:06:15.408413 VMMDev: GuestHeartBeat: Guest is alive (gone 336 156 662 813 ns)
05:06:29.788850 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 19 seconds ago
05:06:51.067417 TM: Giving up catch-up attempt at a 77 822 648 346 ns lag; new total: 9 959 310 387 641 ns
05:07:00.896883 VMMDev: GuestHeartBeat: Guest is alive (gone 50 646 413 867 ns)
05:07:05.184778 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 4 seconds ago
05:07:24.009908 VMMDev: GuestHeartBeat: Guest is alive (gone 22 261 967 358 ns)
05:10:23.778683 TM: Giving up catch-up attempt at a 69 307 941 283 ns lag; new total: 10 028 618 328 924 ns
05:10:49.105906 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 12 seconds ago
05:12:36.126778 TM: Not bothering to attempt catching up a 118 684 492 403 ns lag; new total: 10 147 302 821 327
05:14:18.167272 TM: Not bothering to attempt catching up a 103 699 911 203 ns lag; new total: 10 251 002 732 530
05:15:49.725102 TM: Not bothering to attempt catching up a 102 034 452 972 ns lag; new total: 10 353 037 185 502
05:17:21.190404 TM: Not bothering to attempt catching up a 93 007 452 490 ns lag; new total: 10 446 044 637 992
05:18:10.706896 VMMDev: GuestHeartBeat: Guest is alive (gone 457 364 197 356 ns)
05:18:27.881622 TM: Giving up catch-up attempt at a 68 049 298 436 ns lag; new total: 10 514 093 936 428 ns
05:18:34.770285 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 26 seconds ago
05:19:02.548783 VMMDev: GuestHeartBeat: Guest is alive (gone 50 574 938 927 ns)
05:19:19.829370 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 19 seconds ago
05:19:54.179313 TM: Giving up catch-up attempt at a 86 452 095 208 ns lag; new total: 10 600 546 031 636 ns
05:20:07.410166 VMMDev: GuestHeartBeat: Guest is alive (gone 49 301 230 726 ns)
05:21:36.922157 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 4 seconds ago
05:21:39.407596 VMMDev: GuestHeartBeat: Guest is alive (gone 8 965 015 067 ns)
05:23:58.830351 TM: Giving up catch-up attempt at a 60 075 582 943 ns lag; new total: 10 660 621 614 579 ns
05:24:01.201145 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 10 seconds ago
05:24:01.500066 VMMDev: GuestHeartBeat: Guest is alive (gone 11 020 067 699 ns)
05:27:44.241160 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 6 seconds ago
05:27:48.312361 VMMDev: GuestHeartBeat: Guest is alive (gone 13 739 403 602 ns)
05:28:24.788196 VMMDev: vmmDevHeartbeatFlatlinedTimer: Guest seems to be unresponsive. Last heartbeat received 4 seconds ago
05:28:24.826059 VMMDev: GuestHeartBeat: Guest is alive (gone 4 213 843 574 ns)
05:28:43.439675 TM: Giving up catch-up attempt at a 60 000 202 128 ns lag; new total: 10 720 621 816 707 ns

    VM Startup Log:


    VM Trace Log:

ial Port count:       4
Maximum Parallel Port count:     2
Maximum Boot Position:           4
Maximum PIIX3 Network Adapter count:   8
Maximum ICH9 Network Adapter count:   36
Maximum PIIX3 IDE Controllers:   1
Maximum ICH9 IDE Controllers:    1
Maximum IDE Port count:          2
Maximum Devices per IDE Port:    2
Maximum PIIX3 SATA Controllers:  1
Maximum ICH9 SATA Controllers:   8
Maximum SATA Port count:         30
Maximum Devices per SATA Port:   1
Maximum PIIX3 SCSI Controllers:  1
Maximum ICH9 SCSI Controllers:   8
Maximum SCSI Port count:         16
Maximum Devices per SCSI Port:   1
Maximum SAS PIIX3 Controllers:   1
Maximum SAS ICH9 Controllers:    8
Maximum SAS Port count:          255
Maximum Devices per SAS Port:    1
Maximum NVMe PIIX3 Controllers:  1
Maximum NVMe ICH9 Controllers:   8
Maximum NVMe Port count:         255
Maximum Devices per NVMe 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:          C:\Users\kamco\VirtualBox VMs
Raw-mode Supported:              yes
Exclusive HW virtualization use: off
Default hard disk format:        VDI
VRDE auth library:               VBoxAuth
Webservice auth. library:        VBoxAuth
Remote desktop ExtPack:          
Log history count:               3
Default frontend:                
Default audio driver:            DirectSound
Autostart database path:         
Default Guest Additions ISO:     C:\Program Files\Oracle\VirtualBox/VBoxGuestAdditions.iso
Logging Level:                   all
Proxy Mode:                      System
Proxy URL:                       

2023-04-02 00:32:26 (8684): 
Command: VBoxManage -q list hostinfo 
Exit Code: 0
Output:
Host Information:

Host time: 2023-04-02T04:32:26.194000000Z
Processor online count: 2
Processor count: 2
Processor online core count: 2
Processor core count: 2
Processor supports HW virtualization: yes
Processor supports PAE: yes
Processor supports long mode: yes
Processor supports nested paging: yes
Processor#0 speed: unknown
Processor#0 description: Intel(R) Pentium(R) CPU G3220T @ 2.60GHz
Processor#1 speed: unknown
Processor#1 description: Intel(R) Pentium(R) CPU G3220T @ 2.60GHz
Memory size: 4013 MByte
Memory available: 1712 MByte
Operating system: Windows 10
Operating system version: 10.0.19045

2023-04-02 00:32:26 (8684): 
Command: VBoxManage -q showvminfo "boinc_8e404e37f32b75c5" --machinereadable 
Exit Code: -2135228415
Output:
VBoxManage.exe: error: Could not find a registered machine named 'boinc_8e404e37f32b75c5'
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 2621 of file VBoxManageInfo.cpp

2023-04-02 00:32:27 (8684): 
Command: VBoxManage -q registervm "C:\ProgramData\BOINC\slots\4/boinc_8e404e37f32b75c5/boinc_8e404e37f32b75c5.vbox" 
Exit Code: -2147467259
Output:
VBoxManage.exe: error: Trying to open a VM config 'C:\ProgramData\BOINC\slots\4/boinc_8e404e37f32b75c5/boinc_8e404e37f32b75c5.vbox' which has the same UUID as an existing virtual machine
VBoxManage.exe: error: Details: code E_FAIL (0x80004005), component MachineWrap, interface IMachine, callee IUnknown
VBoxManage.exe: error: Context: "OpenMachine(Bstr(a->argv[0]).raw(), machine.asOutParam())" at line 86 of file VBoxManageMisc.cpp

2023-04-02 00:32:29 (8684): 
Command: VBoxManage -q registervm "C:\ProgramData\BOINC\slots\4/boinc_8e404e37f32b75c5/boinc_8e404e37f32b75c5.vbox" 
Exit Code: -2147467259
Output:
VBoxManage.exe: error: Trying to open a VM config 'C:\ProgramData\BOINC\slots\4/boinc_8e404e37f32b75c5/boinc_8e404e37f32b75c5.vbox' which has the same UUID as an existing virtual machine
VBoxManage.exe: error: Details: code E_FAIL (0x80004005), component MachineWrap, interface IMachine, callee IUnknown
VBoxManage.exe: error: Context: "OpenMachine(Bstr(a->argv[0]).raw(), machine.asOutParam())" at line 86 of file VBoxManageMisc.cpp

2023-04-02 00:32:30 (8684): 
Command: VBoxManage -q registervm "C:\ProgramData\BOINC\slots\4/boinc_8e404e37f32b75c5/boinc_8e404e37f32b75c5.vbox" 
Exit Code: -2147467259
Output:
VBoxManage.exe: error: Trying to open a VM config 'C:\ProgramData\BOINC\slots\4/boinc_8e404e37f32b75c5/boinc_8e404e37f32b75c5.vbox' which has the same UUID as an existing virtual machine
VBoxManage.exe: error: Details: code E_FAIL (0x80004005), component MachineWrap, interface IMachine, callee IUnknown
VBoxManage.exe: error: Context: "OpenMachine(Bstr(a->argv[0]).raw(), machine.asOutParam())" at line 86 of file VBoxManageMisc.cpp

2023-04-02 00:32:31 (8684): 
Command: VBoxManage -q registervm "C:\ProgramData\BOINC\slots\4/boinc_8e404e37f32b75c5/boinc_8e404e37f32b75c5.vbox" 
Exit Code: -2147467259
Output:
VBoxManage.exe: error: Trying to open a VM config 'C:\ProgramData\BOINC\slots\4/boinc_8e404e37f32b75c5/boinc_8e404e37f32b75c5.vbox' which has the same UUID as an existing virtual machine
VBoxManage.exe: error: Details: code E_FAIL (0x80004005), component MachineWrap, interface IMachine, callee IUnknown
VBoxManage.exe: error: Context: "OpenMachine(Bstr(a->argv[0]).raw(), machine.asOutParam())" at line 86 of file VBoxManageMisc.cpp

2023-04-02 00:32:33 (8684): 
Command: VBoxManage -q registervm "C:\ProgramData\BOINC\slots\4/boinc_8e404e37f32b75c5/boinc_8e404e37f32b75c5.vbox" 
Exit Code: -2147467259
Output:
VBoxManage.exe: error: Trying to open a VM config 'C:\ProgramData\BOINC\slots\4/boinc_8e404e37f32b75c5/boinc_8e404e37f32b75c5.vbox' which has the same UUID as an existing virtual machine
VBoxManage.exe: error: Details: code E_FAIL (0x80004005), component MachineWrap, interface IMachine, callee IUnknown
VBoxManage.exe: error: Context: "OpenMachine(Bstr(a->argv[0]).raw(), machine.asOutParam())" at line 86 of file VBoxManageMisc.cpp

2023-04-02 00:32:35 (8684): 
Command: VBoxManage -q registervm "C:\ProgramData\BOINC\slots\4/boinc_8e404e37f32b75c5/boinc_8e404e37f32b75c5.vbox" 
Exit Code: -2147467259
Output:
VBoxManage.exe: error: Trying to open a VM config 'C:\ProgramData\BOINC\slots\4/boinc_8e404e37f32b75c5/boinc_8e404e37f32b75c5.vbox' which has the same UUID as an existing virtual machine
VBoxManage.exe: error: Details: code E_FAIL (0x80004005), component MachineWrap, interface IMachine, callee IUnknown
VBoxManage.exe: error: Context: "OpenMachine(Bstr(a->argv[0]).raw(), machine.asOutParam())" at line 86 of file VBoxManageMisc.cpp

2023-04-02 00:32:35 (8684): 
Command: VBoxManage -q snapshot "boinc_8e404e37f32b75c5" list 
Exit Code: -2135228415
Output:
VBoxManage.exe: error: Could not find a registered machine named 'boinc_8e404e37f32b75c5'
VBoxManage.exe: error: Details: code VBOX_E_OBJECT_NOT_FOUND (0x80bb0001), component VirtualBoxWrap, interface IVirtualBox, callee IUnknown
VBoxManage.exe: error: Context: "FindMachine(bstrMachine.raw(), pMachine.asOutParam())" at line 326 of file VBoxManageSnapshot.cpp

2023-04-02 00:32:35 (8684): 
Command: VBoxManage -q bandwidthctl "boinc_8e404e37f32b75c5" remove "boinc_8e404e37f32b75c5_net" 
Exit Code: -2135228415
Output:
VBoxManage.exe: error: Could not find a registered machine named 'boinc_8e404e37f32b75c5'
VBoxManage.exe: error: Details: code VBOX_E_OBJECT_NOT_FOUND (0x80bb0001), component VirtualBoxWrap, interface IVirtualBox, callee IUnknown
VBoxManage.exe: error: Context: "FindMachine(Bstr(a->argv[0]).raw(), machine.asOutParam())" at line 320 of file VBoxManageBandwidthControl.cpp

2023-04-02 00:32:36 (8684): 
Command: VBoxManage -q unregistervm "boinc_8e404e37f32b75c5" --delete 
Exit Code: -2135228415
Output:
VBoxManage.exe: error: Could not find a registered machine named 'boinc_8e404e37f32b75c5'
VBoxManage.exe: error: Details: code VBOX_E_OBJECT_NOT_FOUND (0x80bb0001), component VirtualBoxWrap, interface IVirtualBox, callee IUnknown
VBoxManage.exe: error: Context: "FindMachine(Bstr(VMName).raw(), machine.asOutParam())" at line 150 of file VBoxManageMisc.cpp

00:32:46 (8684): called boinc_finish(-2147467259)
2023-04-02 00:36:12 (10132): Detected: vboxwrapper 26206
2023-04-02 00:36:12 (10132): Detected: BOINC client v7.16.20
2023-04-02 00:36:14 (10132): Detected: VirtualBox VboxManage Interface (Version: 6.0.14)
2023-04-02 00:36:15 (10132): WARNING: Communication with VM Hypervisor failed. (Possibly Out of Memory).
2023-04-02 00:36:15 (10132): WARNING: Communication with VM Hypervisor failed.
2023-04-02 00:36:15 (10132): ERROR: VBoxManage list hostinfo failed
00:36:15 (10132): called boinc_finish(1)

</stderr_txt>
]]>


©2024 CERN