Name Theory_2743-2795211-450_2
Workunit 225597176
Created 26 Sep 2024, 6:26:34 UTC
Sent 26 Sep 2024, 17:51:42 UTC
Report deadline 7 Oct 2024, 17:51:42 UTC
Received 27 Sep 2024, 18:40:44 UTC
Server state Over
Outcome Computation error
Client state Compute error
Exit status -182 (0xFFFFFF4A) ERR_TIMEOUT
Computer ID 10830398
Run time 3 hours 25 min 0 sec
CPU time 48 min 56 sec
Validate state Invalid
Credit 0.00
Device peak FLOPS 6.07 GFLOPS
Application version Theory Simulation v300.30 (vbox64_theory)
windows_x86_64
Peak working set size 84.32 MB
Peak swap size 74.79 MB
Peak disk usage 1.98 GB

Stderr output

<core_client_version>8.0.2</core_client_version>
<![CDATA[
<message>
(unknown error) (317) - exit code 4294967114 (0xffffff4a)</message>
<stderr_txt>
2024-09-27 05:33:48 (56256): vboxwrapper version 26207
2024-09-27 05:33:48 (56256): BOINC client version: 8.0.2
2024-09-27 05:33:49 (56256): Detected: VirtualBox VboxManage Interface (Version: 7.0.8)
2024-09-27 05:33:49 (56256): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-09-27 05:33:49 (56256): Successfully copied 'init_data.xml' to the shared directory.
2024-09-27 05:33:49 (56256): Successfully copied 'input' to the shared directory.
2024-09-27 05:33:49 (56256): Create VM. (boinc_d98b02ccab19f948, slot#7)
2024-09-27 05:33:50 (56256): Setting Memory Size for VM. (630MB)
2024-09-27 05:33:50 (56256): Setting CPU Count for VM. (1)
2024-09-27 05:33:51 (56256): Setting Chipset Options for VM.
2024-09-27 05:33:51 (56256): Setting Graphics Controller Options for VM.
2024-09-27 05:33:52 (56256): Setting Boot Options for VM.
2024-09-27 05:33:52 (56256): Setting Network Configuration for NAT.
2024-09-27 05:33:52 (56256): Enabling VM Network Access.
2024-09-27 05:33:53 (56256): Disabling USB Support for VM.
2024-09-27 05:33:53 (56256): Disabling COM Port Support for VM.
2024-09-27 05:33:53 (56256): Disabling LPT Port Support for VM.
2024-09-27 05:33:53 (56256): Disabling Audio Support for VM.
2024-09-27 05:33:54 (56256): Disabling Clipboard Support for VM.
2024-09-27 05:33:54 (56256): Disabling Drag and Drop Support for VM.
2024-09-27 05:33:54 (56256): Adding storage controller(s) to VM.
2024-09-27 05:33:55 (56256): Adding virtual disk drive to VM. (Theory_2024_04_30_prod.vdi)
2024-09-27 05:33:55 (56256): Adding VirtualBox Guest Additions to VM.
2024-09-27 05:33:56 (56256): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2024-09-27 05:33:56 (56256): forwarding host port 51537 to guest port 80
2024-09-27 05:33:56 (56256): Enabling remote desktop for VM.
2024-09-27 05:33:56 (56256): Required extension pack not installed, remote desktop not enabled.
2024-09-27 05:33:56 (56256): Enabling shared directory for VM.
2024-09-27 05:33:57 (56256): Starting VM using VBoxManage interface. (boinc_d98b02ccab19f948, slot#7)
2024-09-27 05:34:03 (56256): Successfully started VM. (PID = '55384')
2024-09-27 05:34:03 (56256): Reporting VM Process ID to BOINC.
2024-09-27 05:34:03 (56256): Guest Log: BIOS: VirtualBox 7.0.8
2024-09-27 05:34:03 (56256): Guest Log: CPUID EDX: 0x178bfbff
2024-09-27 05:34:03 (56256): Guest Log: BIOS: No PCI IDE controller, not probing IDE
2024-09-27 05:34:03 (56256): Guest Log: BIOS: AHCI 0-P#0: PCHS=16383/16/63 LCHS=1024/255/63 0x0000000002800000 sectors
2024-09-27 05:34:03 (56256): VM state change detected. (old = 'poweredoff', new = 'running')
2024-09-27 05:34:03 (56256): Detected: Web Application Enabled (http://localhost:51537)
2024-09-27 05:34:03 (56256): Preference change detected
2024-09-27 05:34:03 (56256): Setting CPU throttle for VM. (25%)
2024-09-27 05:34:03 (56256): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2024-09-27 05:34:05 (56256): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2024-09-27 05:34:05 (56256): Guest Log: BIOS: Booting from Hard Disk...
2024-09-27 05:34:08 (56256): Guest Log: BIOS: KBD: unsupported int 16h function 03
2024-09-27 05:34:08 (56256): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2024-09-27 05:34:53 (56256): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2024-09-27 05:34:53 (56256): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2024-09-27 05:35:01 (56256): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2024-09-27 05:35:01 (56256): Guest Log: 00:00:00.000101 main     Log opened 2024-09-27T09:35:10.279092000Z
2024-09-27 05:35:01 (56256): Guest Log: 00:00:00.000228 main     OS Product: Linux
2024-09-27 05:35:01 (56256): Guest Log: 00:00:00.000263 main     OS Release: 4.14.76-13.cernvm.x86_64
2024-09-27 05:35:01 (56256): Guest Log: 00:00:00.000290 main     OS Version: #1 SMP Tue Oct 16 18:26:15 CEST 2018
2024-09-27 05:35:01 (56256): Guest Log: 00:00:00.000316 main     Executable: /usr/sbin/VBoxService
2024-09-27 05:35:01 (56256): Guest Log: 00:00:00.000316 main     Process ID: 3227
2024-09-27 05:35:01 (56256): Guest Log: 00:00:00.000317 main     Package type: LINUX_64BITS_GENERIC
2024-09-27 05:35:01 (56256): Guest Log: 00:00:00.002976 main     5.2.6 r120293 started. Verbose level = 0
2024-09-27 05:35:39 (56256): Guest Log: 11:35:48 CEST +02:00 2024-09-27: cranky: [INFO] Detected Theory App
2024-09-27 05:35:39 (56256): Guest Log: 11:35:48 CEST +02:00 2024-09-27: cranky: [INFO] Checking CVMFS.
2024-09-27 05:35:40 (56256): Guest Log: Probing /cvmfs/sft.cern.ch... OK
2024-09-27 05:35:42 (56256): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
2024-09-27 05:35:42 (56256): Guest Log: 2.5.2.0 4116 0 26188 30568 3 1 277750 4096000 0 65024 0 0 n/a 5 2 http://s1ihep-cvmfs.openhtc.io/cvmfs/sft.cern.ch DIRECT 1
2024-09-27 05:35:43 (56256): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2024-09-27 05:35:44 (56256): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
2024-09-27 05:35:44 (56256): Guest Log: 2.5.2.0 4139 0 27500 25333 3 2 277750 4096000 0 65024 0 2 -100 10063 3335 http://s1fnal-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2024-09-27 05:35:46 (56256): Guest Log: Probing /cvmfs/cernvm-prod.cern.ch... OK
2024-09-27 05:35:47 (56256): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
2024-09-27 05:35:47 (56256): Guest Log: 2.5.2.0 4095 0 27244 272 3 1 277750 4096000 0 65024 0 0 n/a 4 3 http://s1fnal-cvmfs.openhtc.io/cvmfs/cernvm-prod.cern.ch DIRECT 1
2024-09-27 05:35:49 (56256): Guest Log: Probing /cvmfs/alice.cern.ch... OK
2024-09-27 05:35:50 (56256): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
2024-09-27 05:35:50 (56256): Guest Log: 2.5.2.0 4068 0 39636 19888 3 1 277750 4096000 0 65024 0 0 n/a 6116 4319 http://s1unl-cvmfs.openhtc.io/cvmfs/alice.cern.ch DIRECT 1
2024-09-27 05:35:50 (56256): Guest Log: 11:35:58 CEST +02:00 2024-09-27: cranky: [INFO] Checking runc.
2024-09-27 05:35:50 (56256): Guest Log: 11:35:58 CEST +02:00 2024-09-27: cranky: [INFO] Creating the filesystem.
2024-09-27 05:35:50 (56256): Guest Log: 11:35:58 CEST +02:00 2024-09-27: cranky: [INFO] Using /cvmfs/cernvm-prod.cern.ch/cvm4
2024-09-27 05:35:50 (56256): Guest Log: 11:35:59 CEST +02:00 2024-09-27: cranky: [INFO] Updating config.json.
2024-09-27 05:35:50 (56256): Guest Log: 11:35:59 CEST +02:00 2024-09-27: cranky: [INFO] Running Container 'runc'.
2024-09-27 05:35:55 (56256): Guest Log: job: htmld=/shared/html/job
2024-09-27 05:35:56 (56256): Guest Log: job: unpack exitcode=0
2024-09-27 05:35:56 (56256): Guest Log: INFO: activated the work-around for ld:
2024-09-27 05:35:56 (56256): Guest Log: lrwxrwxrwx 1 0 0 15 Sep 27 09:36 /tmp/tmp.evtGCF0eEB/ld -> /usr/bin/ld.bfd
2024-09-27 05:35:57 (56256): Guest Log: 11:36:05 CEST +02:00 2024-09-27: cranky: [INFO] ===> [runRivet] Fri Sep 27 09:36:04 UTC 2024 [boinc pp jets 8000 170,-,2960 - pythia8 8.245 early 100000 450]
2024-09-27 05:48:32 (56256): VM state change detected. (old = 'running', new = 'paused')
2024-09-27 05:48:52 (56256): VM state change detected. (old = 'paused', new = 'running')
2024-09-27 06:06:56 (56256): VM state change detected. (old = 'running', new = 'paused')
2024-09-27 06:07:16 (56256): VM state change detected. (old = 'paused', new = 'running')
2024-09-27 06:29:30 (56256): VM state change detected. (old = 'running', new = 'paused')
2024-09-27 06:29:50 (56256): VM state change detected. (old = 'paused', new = 'running')
2024-09-27 06:53:04 (56256): VM state change detected. (old = 'running', new = 'paused')
2024-09-27 06:53:24 (56256): VM state change detected. (old = 'paused', new = 'running')
2024-09-27 07:07:34 (56256): VM state change detected. (old = 'running', new = 'paused')
2024-09-27 08:42:43 (56256): VM state change detected. (old = 'paused', new = 'running')
2024-09-27 08:42:46 (56256): Guest Log: 01:31:18.208978 timesync vgsvcTimeSyncWorker: Radical host time change: 5 718 937 000 000ns (HostNow=1 727 440 965 399 000 000 ns HostLast=1 727 435 246 462 000 000 ns)
2024-09-27 08:42:56 (56256): Guest Log: 01:31:28.249339 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 787 139 568 000ns (GuestNow=1 727 440 975 442 157 000 ns GuestLast=1 727 435 188 302 589 000 ns fSetTimeLastLoop=true )
2024-09-27 08:43:23 (56256): VM state change detected. (old = 'running', new = 'paused')
2024-09-27 08:43:43 (56256): VM state change detected. (old = 'paused', new = 'running')
2024-09-27 08:51:01 (56256): Status Report: Job Duration: '864000.000000'
2024-09-27 08:51:01 (56256): Status Report: Elapsed Time: '6000.000000'
2024-09-27 08:51:01 (56256): Status Report: CPU Time: '1280.562500'
2024-09-27 09:30:01 (56256): VM state change detected. (old = 'running', new = 'paused')
2024-09-27 09:30:21 (56256): VM state change detected. (old = 'paused', new = 'running')
2024-09-27 10:30:11 (56256): VM state change detected. (old = 'running', new = 'paused')
2024-09-27 10:30:31 (56256): VM state change detected. (old = 'paused', new = 'running')
2024-09-27 10:31:46 (56256): Status Report: Job Duration: '864000.000000'
2024-09-27 10:31:46 (56256): Status Report: Elapsed Time: '12000.000000'
2024-09-27 10:31:46 (56256): Status Report: CPU Time: '2867.921875'
2024-09-27 10:36:52 (56256): VM state change detected. (old = 'running', new = 'paused')
2024-09-27 10:37:08 (56256): Stopping VM.
2024-09-27 10:37:08 (56256): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_d98b02ccab19f948" savestate
Output:

2024-09-27 10:37:08 (56256): VM did not stop when requested.
2024-09-27 10:37:08 (56256): VM was NOT successfully terminated.
2024-09-27 10:59:34 (12812): vboxwrapper version 26207
2024-09-27 10:59:34 (12812): BOINC client version: 8.0.2
2024-09-27 10:59:34 (12812): Detected: VirtualBox VboxManage Interface (Version: 7.0.8)
2024-09-27 10:59:35 (12812): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2024-09-27 10:59:35 (12812): Register VM. (boinc_d98b02ccab19f948, slot#7)
2024-09-27 10:59:42 (12812): Error in register for VM: -182
Command:
VBoxManage -q registervm "D:\ProgramData\slots\7/boinc_d98b02ccab19f948/boinc_d98b02ccab19f948.vbox" 
Output:
VBoxManage.exe: error: Trying to open a VM config 'D:\ProgramData\slots\7/boinc_d98b02ccab19f948/boinc_d98b02ccab19f948.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(), Bstr(strPassword).raw(), machine.asOutParam()))" at line 160 of file VBoxManageMisc.cpp

2024-09-27 10:59:42 (12812): Could not register
2024-09-27 10:59:42 (12812): ERROR: VM failed to start
2024-09-27 10:59:42 (12812): Powering off VM.
2024-09-27 10:59:42 (12812): Deregistering VM. (boinc_d98b02ccab19f948, slot#7)
2024-09-27 10:59:42 (12812): Removing network bandwidth throttle group from VM.
2024-09-27 10:59:43 (12812): Attempts: 2
2024-09-27 10:59:43 (12812): Removing VM from VirtualBox.

    Hypervisor System Log:

00:00:09.696318          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:00:09.696321          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:00:09.696324          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:00:09.696328          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:00:09.696331          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:00:09.696334          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:00:09.696337          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:00:09.696341          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:00:09.696344          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:00:09.696347          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:00:09.696350          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:00:09.696353          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:00:09.696357          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:00:09.696368          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:00:09.696372          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:00:09.696375          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:00:09.696379          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:00:09.696382          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:00:09.696385          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:00:09.696389          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:00:09.696392          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:00:09.696395          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:00:09.696399          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:00:09.696402          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:00:09.696405          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:00:09.696408          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:00:09.696412          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:00:09.696415          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:00:09.696418          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:00:09.696422          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:00:09.696426          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:00:09.696430          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:00:09.696441          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:00:09.696445          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:00:09.696448          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:00:09.696451          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:00:09.696455          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={7682d5eb-f00e-44f1-8ca2-99d08b1cd607} aComponent={VirtualBoxWrap} aText={Could not find a registered machine named 'boinc_e58b1c6aa7673ca6'}, preserve=false aResultDetail=0

    VM Execution Log:

7977 /PROF/CPU0/VM/Halt/R0HaltBlockInsomnia   746864 ns/call ( 72820020438 ns,   97501 calls, max 156232386, min       2)
05:04:34.157981 /PROF/CPU0/VM/Halt/R0HaltBlockOnTime   505959 ns/call ( 10791113014 ns,   21328 calls, max  15666447, min     507)
05:04:34.157985 /PROF/CPU0/VM/Halt/R0HaltBlockOverslept  5890951 ns/call (1220027776079 ns,  207102 calls, max 115263564, min   50005)
05:04:34.157988 /PROF/CPU0/VM/Halt/R0HaltExec      111570 times
05:04:34.157991 /PROF/CPU0/VM/Halt/R0HaltExec/FromBlock   110263 times
05:04:34.157994 /PROF/CPU0/VM/Halt/R0HaltExec/FromSpin       91 times
05:04:34.157997 /PROF/CPU0/VM/Halt/R0HaltHistoryCounter   635936 times
05:04:34.158000 /PROF/CPU0/VM/Halt/R0HaltHistorySucceeded       20 times
05:04:34.158002 /PROF/CPU0/VM/Halt/R0HaltHistoryToRing3       15 times
05:04:34.158005 /PROF/CPU0/VM/Halt/R0HaltToR3      524366 times
05:04:34.158008 /PROF/CPU0/VM/Halt/R0HaltToR3/FromSpin        1 times
05:04:34.158010 /PROF/CPU0/VM/Halt/R0HaltToR3/Other        0 times
05:04:34.158013 /PROF/CPU0/VM/Halt/R0HaltToR3/PendingFF   261253 times
05:04:34.158016 /PROF/CPU0/VM/Halt/R0HaltToR3/PostWaitNoInt   210952 times
05:04:34.158019 /PROF/CPU0/VM/Halt/R0HaltToR3/PostWaitPendingFF     4716 times
05:04:34.158022 /PROF/CPU0/VM/Halt/R0HaltToR3/SmallDelta    47444 times
05:04:34.158024 /PROF/CPU0/VM/Halt/Timers            2864 ns/call (   900965414 ns,  314510 calls, max  17255535, min       2)
05:04:34.158028 /PROF/CPU0/VM/Halt/Yield             4571 ns/call (     1952070 ns,     427 calls, max     97469, min       1)
05:04:34.158031 /Public/NetAdapter/0/BytesReceived 801013971 bytes
05:04:34.158034 /Public/NetAdapter/0/BytesTransmitted  3556057 bytes
05:04:34.158037 /Public/NetAdapter/0/e1000              0 
05:04:34.158040 /Public/Storage/AHCI0/Port0/BytesRead 13207042560 bytes
05:04:34.158043 /Public/Storage/AHCI0/Port0/BytesWritten 2154734080 bytes
05:04:34.158046 /Public/Storage/AHCI0/Port0/QueryBufAttempts        0 count
05:04:34.158049 /Public/Storage/AHCI0/Port0/QueryBufSuccess        0 count
05:04:34.158051 /Public/Storage/AHCI0/Port0/ReqsFlush     4315 count
05:04:34.158054 /Public/Storage/AHCI0/Port0/ReqsRead   259827 count
05:04:34.158057 /Public/Storage/AHCI0/Port0/ReqsSubmitted   288238 count
05:04:34.158060 /Public/Storage/AHCI0/Port0/ReqsSucceeded   288238 count
05:04:34.158063 /Public/Storage/AHCI0/Port0/ReqsWrite    24096 count
05:04:34.158066 /Public/Storage/AHCI0/Port1/BytesRead   321536 bytes
05:04:34.158068 /Public/Storage/AHCI0/Port1/QueryBufAttempts        0 count
05:04:34.158071 /Public/Storage/AHCI0/Port1/QueryBufSuccess        0 count
05:04:34.158074 /Public/Storage/AHCI0/Port1/ReqsRead       22 count
05:04:34.158077 /Public/Storage/AHCI0/Port1/ReqsSubmitted       22 count
05:04:34.158079 /Public/Storage/AHCI0/Port1/ReqsSucceeded       22 count
05:04:34.158082 /SELM/LoadHidSel/GstReadErrors          0 times
05:04:34.158085 /SELM/LoadHidSel/NoGoodGuest            0 times
05:04:34.158088 /TM/CPU/00/cNsExecuting          3035912235004 ns
05:04:34.158092 /TM/CPU/00/cNsHalted             173652980336 ns
05:04:34.158095 /TM/CPU/00/cNsOther              9112776858114 ns
05:04:34.158098 /TM/CPU/00/cNsTotal              12322342073454 ns
05:04:34.158101 /TM/CPU/00/cPeriodsExecuting      9275924 count
05:04:34.158104 /TM/CPU/00/cPeriodsHalted          259924 count
05:04:34.158106 /TM/CPU/00/pctExecuting                19 %
05:04:34.158109 /TM/CPU/00/pctHalted                    0 %
05:04:34.158112 /TM/CPU/00/pctOther                    80 %
05:04:34.158115 /TM/CPU/pctExecuting                   19 %
05:04:34.158119 /TM/CPU/pctHalted                       0 %
05:04:34.158123 /TM/CPU/pctOther                       80 %
05:04:34.158126 /TM/MaxHzHint                           0 Hz
05:04:34.158129 /TM/MaxHzHint/real                      0 Hz
05:04:34.158132 /TM/MaxHzHint/tsc                       0 Hz
05:04:34.158135 /TM/MaxHzHint/virtual                   0 Hz
05:04:34.158138 /TM/MaxHzHint/virtual_sync              0 Hz
05:04:34.158141 /TM/PIT/Handler                         0 ticks/call (           0 ticks,       0 calls, max         0, min      -1)
05:04:34.158144 /TM/PIT/Irq                             0 times
05:04:34.158147 /TM/R3/1nsSteps                     68477 times
05:04:34.158150 /TM/TSC/offCPU0                  5023898263844034 ticks
05:04:34.158153 /TM/VirtualSync/CurrentOffset    26998049 ns
05:04:34.158158 /TRPM/ForwardRaw/IRQ/30                57 times
05:04:34.158161 /TRPM/ForwardRaw/IRQ/41             26844 times
05:04:34.158166 /TRPM/ForwardRaw/IRQ/61             42090 times
05:04:34.158169 /TRPM/ForwardRaw/IRQ/71              2160 times
05:04:34.158176 /TRPM/ForwardRaw/IRQ/EF           1125455 times
05:04:34.158180 /TRPM/ForwardRaw/TRAP/08              205 times
05:04:34.158183 /VMM/EmtHashCollisions/Emt00            0 times
05:04:34.158186 /VMM/LogFlush/00-Flushes                0 times
05:04:34.158189 /VMM/LogFlush/00-NoWakups               0 times
05:04:34.158193 /VMM/LogFlush/CPU0/Rel/cbBuf         4096 bytes
05:04:34.158196 ********************* End of statistics **********************
05:04:34.174489 NAT: Zone(nm:mbuf_cluster, used:0)
05:04:34.174756 NAT: Zone(nm:mbuf_packet, used:0)
05:04:34.174828 NAT: Zone(nm:mbuf, used:1)
05:04:34.175090 NAT: Zone(nm:mbuf_jumbo_pagesize, used:0)
05:04:34.175314 NAT: Zone(nm:mbuf_jumbo_9k, used:0)
05:04:34.176096 NAT: Zone(nm:mbuf_jumbo_16k, used:0)
05:04:34.176326 NAT: Zone(nm:mbuf_ext_refcnt, used:0)
05:04:34.191998 E1000#0: Interrupt attempts: 652854
05:04:34.192009 E1000#0: Interrupts raised : 106115
05:04:34.192012 E1000#0: Interrupts lowered: 23050
05:04:34.192015 E1000#0: ICR outside ISR   : 0
05:04:34.192017 E1000#0: IMS raised ints   : 35291
05:04:34.192019 E1000#0: Interrupts skipped: 280802
05:04:34.192022 E1000#0: Masked interrupts : 265937
05:04:34.192024 E1000#0: Early interrupts  : 0
05:04:34.192026 E1000#0: Late interrupts   : 3028
05:04:34.192029 E1000#0: Lost interrupts   : 0
05:04:34.192031 E1000#0: Interrupts by RX  : 564569
05:04:34.192034 E1000#0: Interrupts by TX  : 40134
05:04:34.192036 E1000#0: Interrupts by ICS : 5942
05:04:34.192038 E1000#0: Interrupts by RDTR: 0
05:04:34.192040 E1000#0: Interrupts by RDMT: 0
05:04:34.192043 E1000#0: Interrupts by TXQE: 0
05:04:34.192045 E1000#0: TX int delay asked: 0
05:04:34.192047 E1000#0: TX delayed:         0
05:04:34.192049 E1000#0: TX delay expired:   0
05:04:34.192051 E1000#0: TX no report asked: 40034
05:04:34.192054 E1000#0: TX abs timer expd : 0
05:04:34.192056 E1000#0: TX int timer expd : 0
05:04:34.192058 E1000#0: RX abs timer expd : 0
05:04:34.192060 E1000#0: RX int timer expd : 0
05:04:34.192062 E1000#0: TX CTX descriptors: 40034
05:04:34.192065 E1000#0: TX DAT descriptors: 40034
05:04:34.192067 E1000#0: TX LEG descriptors: 100
05:04:34.192069 E1000#0: Received frames   : 564569
05:04:34.192071 E1000#0: Transmitted frames: 40134
05:04:34.192074 E1000#0: TX frames up to 1514: 40134
05:04:34.192076 E1000#0: TX frames up to 2962: 0
05:04:34.192078 E1000#0: TX frames up to 4410: 0
05:04:34.192080 E1000#0: TX frames up to 5858: 0
05:04:34.192083 E1000#0: TX frames up to 7306: 0
05:04:34.192085 E1000#0: TX frames up to 8754: 0
05:04:34.192087 E1000#0: TX frames up to 16384: 0
05:04:34.192089 E1000#0: TX frames up to 32768: 0
05:04:34.192091 E1000#0: Larger TX frames    : 0
05:04:34.192094 E1000#0: Max TX Delay        : 0
05:04:34.194136 GIM: KVM: Resetting MSRs
05:04:34.194629 vmmR3LogFlusher: Terminating (VERR_OBJECT_DESTROYED)
05:04:34.194692 Changing the VM state from 'DESTROYING' to 'TERMINATED'
05:04:34.194716 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={6ac83d89-6ee7-4e33-8ae6-b257b2e81be8} aComponent={ConsoleWrap} aText={The object is not ready}, preserve=false aResultDetail=0
05:04:34.218008 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={6ac83d89-6ee7-4e33-8ae6-b257b2e81be8} aComponent={ConsoleWrap} aText={The object is not ready}, preserve=false aResultDetail=0
05:04:34.588584 End of log file - Log started 2024-09-27T09:34:00.669698500Z

    VM Startup Log:


    VM Trace Log:

Processor count: 20
Processor online core count: 12
Processor core count: 12
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: no
Processor#0 speed: unknown
Processor#0 description: 12th Gen Intel(R) Core(TM) i7-12700K
Processor#1 speed: unknown
Processor#1 description: 12th Gen Intel(R) Core(TM) i7-12700K
Processor#2 speed: unknown
Processor#2 description: 12th Gen Intel(R) Core(TM) i7-12700K
Processor#3 speed: unknown
Processor#3 description: 12th Gen Intel(R) Core(TM) i7-12700K
Processor#4 speed: unknown
Processor#4 description: 12th Gen Intel(R) Core(TM) i7-12700K
Processor#5 speed: unknown
Processor#5 description: 12th Gen Intel(R) Core(TM) i7-12700K
Processor#6 speed: unknown
Processor#6 description: 12th Gen Intel(R) Core(TM) i7-12700K
Processor#7 speed: unknown
Processor#7 description: 12th Gen Intel(R) Core(TM) i7-12700K
Processor#8 speed: unknown
Processor#8 description: 12th Gen Intel(R) Core(TM) i7-12700K
Processor#9 speed: unknown
Processor#9 description: 12th Gen Intel(R) Core(TM) i7-12700K
Processor#10 speed: unknown
Processor#10 description: 12th Gen Intel(R) Core(TM) i7-12700K
Processor#11 speed: unknown
Processor#11 description: 12th Gen Intel(R) Core(TM) i7-12700K
Processor#12 speed: unknown
Processor#12 description: 12th Gen Intel(R) Core(TM) i7-12700K
Processor#13 speed: unknown
Processor#13 description: 12th Gen Intel(R) Core(TM) i7-12700K
Processor#14 speed: unknown
Processor#14 description: 12th Gen Intel(R) Core(TM) i7-12700K
Processor#15 speed: unknown
Processor#15 description: 12th Gen Intel(R) Core(TM) i7-12700K
Processor#16 speed: unknown
Processor#16 description: 12th Gen Intel(R) Core(TM) i7-12700K
Processor#17 speed: unknown
Processor#17 description: 12th Gen Intel(R) Core(TM) i7-12700K
Processor#18 speed: unknown
Processor#18 description: 12th Gen Intel(R) Core(TM) i7-12700K
Processor#19 speed: unknown
Processor#19 description: 12th Gen Intel(R) Core(TM) i7-12700K
Memory size: 32492 MByte
Memory available: 23835 MByte
Operating system: Windows 11
Operating system version: 10.0.22631

2024-09-27 10:59:35 (12812): 
Command: VBoxManage -q showvminfo "boinc_d98b02ccab19f948" --machinereadable 
Exit Code: -2135228415
Output:
VBoxManage.exe: error: Could not find a registered machine named 'boinc_d98b02ccab19f948'
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

2024-09-27 10:59:35 (12812): 
Command: VBoxManage -q registervm "D:\ProgramData\slots\7/boinc_d98b02ccab19f948/boinc_d98b02ccab19f948.vbox" 
Exit Code: -2147467259
Output:
VBoxManage.exe: error: Trying to open a VM config 'D:\ProgramData\slots\7/boinc_d98b02ccab19f948/boinc_d98b02ccab19f948.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(), Bstr(strPassword).raw(), machine.asOutParam()))" at line 160 of file VBoxManageMisc.cpp

2024-09-27 10:59:37 (12812): 
Command: VBoxManage -q registervm "D:\ProgramData\slots\7/boinc_d98b02ccab19f948/boinc_d98b02ccab19f948.vbox" 
Exit Code: -2147467259
Output:
VBoxManage.exe: error: Trying to open a VM config 'D:\ProgramData\slots\7/boinc_d98b02ccab19f948/boinc_d98b02ccab19f948.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(), Bstr(strPassword).raw(), machine.asOutParam()))" at line 160 of file VBoxManageMisc.cpp

2024-09-27 10:59:38 (12812): 
Command: VBoxManage -q registervm "D:\ProgramData\slots\7/boinc_d98b02ccab19f948/boinc_d98b02ccab19f948.vbox" 
Exit Code: -2147467259
Output:
VBoxManage.exe: error: Trying to open a VM config 'D:\ProgramData\slots\7/boinc_d98b02ccab19f948/boinc_d98b02ccab19f948.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(), Bstr(strPassword).raw(), machine.asOutParam()))" at line 160 of file VBoxManageMisc.cpp

2024-09-27 10:59:39 (12812): 
Command: VBoxManage -q registervm "D:\ProgramData\slots\7/boinc_d98b02ccab19f948/boinc_d98b02ccab19f948.vbox" 
Exit Code: -2147467259
Output:
VBoxManage.exe: error: Trying to open a VM config 'D:\ProgramData\slots\7/boinc_d98b02ccab19f948/boinc_d98b02ccab19f948.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(), Bstr(strPassword).raw(), machine.asOutParam()))" at line 160 of file VBoxManageMisc.cpp

2024-09-27 10:59:40 (12812): 
Command: VBoxManage -q registervm "D:\ProgramData\slots\7/boinc_d98b02ccab19f948/boinc_d98b02ccab19f948.vbox" 
Exit Code: -2147467259
Output:
VBoxManage.exe: error: Trying to open a VM config 'D:\ProgramData\slots\7/boinc_d98b02ccab19f948/boinc_d98b02ccab19f948.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(), Bstr(strPassword).raw(), machine.asOutParam()))" at line 160 of file VBoxManageMisc.cpp

2024-09-27 10:59:42 (12812): 
Command: VBoxManage -q registervm "D:\ProgramData\slots\7/boinc_d98b02ccab19f948/boinc_d98b02ccab19f948.vbox" 
Exit Code: -2147467259
Output:
VBoxManage.exe: error: Trying to open a VM config 'D:\ProgramData\slots\7/boinc_d98b02ccab19f948/boinc_d98b02ccab19f948.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(), Bstr(strPassword).raw(), machine.asOutParam()))" at line 160 of file VBoxManageMisc.cpp

2024-09-27 10:59:42 (12812): 
Command: VBoxManage -q snapshot "boinc_d98b02ccab19f948" list 
Exit Code: -2135228415
Output:
VBoxManage.exe: error: Could not find a registered machine named 'boinc_d98b02ccab19f948'
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 345 of file VBoxManageSnapshot.cpp

2024-09-27 10:59:42 (12812): 
Command: VBoxManage -q bandwidthctl "boinc_d98b02ccab19f948" remove "boinc_d98b02ccab19f948_net" 
Exit Code: -2135228415
Output:
VBoxManage.exe: error: Could not find a registered machine named 'boinc_d98b02ccab19f948'
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 334 of file VBoxManageBandwidthControl.cpp

2024-09-27 10:59:44 (12812): 
Command: VBoxManage -q unregistervm "boinc_d98b02ccab19f948" --delete 
Exit Code: -2135228415
Output:
VBoxManage.exe: error: Could not find a registered machine named 'boinc_d98b02ccab19f948'
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 231 of file VBoxManageMisc.cpp

2024-09-27 10:59:55 (12812): called boinc_finish(-182)

</stderr_txt>
]]>


©2024 CERN