Task 8761787

Name	07836857_15_1
Workunit	6968015
Created	10 Jul 2020, 15:53:36 UTC
Sent	10 Jul 2020, 16:21:38 UTC
Report deadline	15 Jul 2020, 16:21:38 UTC
Received	11 Jul 2020, 23:36:50 UTC
Server state	Over
Outcome	Computation error
Client state	Compute error
Exit status	197 (0x000000C5) EXIT_TIME_LIMIT_EXCEEDED
Computer ID	7053
Run time	2 hours 9 min 53 sec
CPU time	2 sec
Validate state	Invalid
Credit	0.00
Device peak FLOPS	3.91 GFLOPS
Application version	boinc2docker v1.12 (vbox64_mt) windows_x86_64
Peak working set size	94.21 MB
Peak swap size	98.04 MB
Peak disk usage	228.19 MB
Stderr output

<core_client_version>7.14.2</core_client_version>
<![CDATA[
<message>
exceeded elapsed time limit 7775.32 (86400.00G/11.11G)</message>
<stderr_txt>
2020-07-11 17:23:20 (17628): vboxwrapper (7.9.26200): starting
2020-07-11 17:23:20 (17628): Feature: Checkpoint interval offset (454 seconds)
2020-07-11 17:23:20 (17628): Detected: VirtualBox COM Interface (Version: 5.2.8)
2020-07-11 17:23:20 (17628): Detected: Minimum checkpoint interval (600.000000 seconds)
2020-07-11 17:23:20 (17628): Create VM. (boinc_9e5f3dcb8510244f, slot#1)
2020-07-11 17:23:20 (17628): Updating drive controller type and model for desired configuration.
2020-07-11 17:23:20 (17628): Setting Memory Size for VM. (3072MB)
2020-07-11 17:23:20 (17628): Setting CPU Count for VM. (1)
2020-07-11 17:23:20 (17628): Setting Chipset Options for VM.
2020-07-11 17:23:20 (17628): Setting Boot Options for VM.
2020-07-11 17:23:20 (17628): Enabling VM Network Access.
2020-07-11 17:23:20 (17628): Setting Network Configuration for NAT.
2020-07-11 17:23:20 (17628): Disabling USB Support for VM.
2020-07-11 17:23:20 (17628): Disabling COM Port Support for VM.
2020-07-11 17:23:20 (17628): Disabling LPT Port Support for VM.
2020-07-11 17:23:20 (17628): Disabling Audio Support for VM.
2020-07-11 17:23:20 (17628): Disabling Clipboard Support for VM.
2020-07-11 17:23:20 (17628): Disabling Drag and Drop Support for VM.
2020-07-11 17:23:20 (17628): Adding storage controller(s) to VM.
2020-07-11 17:23:20 (17628): Adding virtual ISO 9660 disk drive to VM. (vm_isocontext.iso)
2020-07-11 17:23:20 (17628): Adding VirtualBox Guest Additions to VM.
2020-07-11 17:23:20 (17628): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2020-07-11 17:23:20 (17628): Enabling shared directory for VM.
2020-07-11 17:23:20 (17628): Starting VM. (boinc_9e5f3dcb8510244f, slot#1)
2020-07-11 17:23:28 (17628): Guest Log: BIOS: VirtualBox 5.2.8

2020-07-11 17:23:28 (17628): Guest Log: CPUID EDX: 0x078bf3ff

2020-07-11 17:23:28 (17628): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-07-11 17:23:28 (17628): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80

2020-07-11 17:23:28 (17628): Guest Log: BIOS: Boot from Hard Disk 0 failed

2020-07-11 17:23:28 (17628): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003

2020-07-11 17:23:28 (17628): Guest Log: BIOS: Booting from CD-ROM...

2020-07-11 17:23:28 (17628): Successfully started VM. (PID = '19848')
2020-07-11 17:23:28 (17628): Reporting VM Process ID to BOINC.
2020-07-11 17:23:33 (17628): VM state change detected. (old = 'poweroff', new = 'running')
2020-07-11 17:23:43 (17628): Preference change detected
2020-07-11 17:23:43 (17628): Setting CPU throttle for VM. (100%)
2020-07-11 17:23:43 (17628): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 180 seconds) or (Vbox_job.xml: 600 seconds))
2020-07-11 18:07:43 (17628): VM state change detected. (old = 'running', new = 'paused')
2020-07-11 18:07:53 (17628): VM state change detected. (old = 'paused', new = 'running')
2020-07-11 18:08:03 (17628): VM state change detected. (old = 'running', new = 'paused')
2020-07-11 18:08:13 (17628): VM state change detected. (old = 'paused', new = 'running')
2020-07-11 18:10:54 (17628): VM state change detected. (old = 'running', new = 'paused')
2020-07-11 18:11:04 (17628): VM state change detected. (old = 'paused', new = 'running')
2020-07-11 18:20:15 (17628): VM state change detected. (old = 'running', new = 'paused')
2020-07-11 18:20:25 (17628): VM state change detected. (old = 'paused', new = 'running')
2020-07-11 18:21:55 (17628): VM state change detected. (old = 'running', new = 'paused')
2020-07-11 18:22:05 (17628): VM state change detected. (old = 'paused', new = 'running')
2020-07-11 18:22:15 (17628): VM state change detected. (old = 'running', new = 'paused')
2020-07-11 18:22:35 (17628): VM state change detected. (old = 'paused', new = 'running')
2020-07-11 18:23:05 (17628): VM state change detected. (old = 'running', new = 'paused')
2020-07-11 18:23:25 (17628): VM state change detected. (old = 'paused', new = 'running')
2020-07-11 18:23:55 (17628): VM state change detected. (old = 'running', new = 'paused')
2020-07-11 18:24:15 (17628): VM state change detected. (old = 'paused', new = 'running')
2020-07-11 19:06:14 (17628): Status Report: Elapsed Time: '6000.360653'
2020-07-11 19:06:14 (17628): Status Report: CPU Time: '2.875000'
2020-07-11 19:34:47 (17628): Powering off VM.
2020-07-11 19:34:48 (17628): Successfully stopped VM.
2020-07-11 19:34:53 (17628): Deregistering VM. (boinc_9e5f3dcb8510244f, slot#1)
2020-07-11 19:34:53 (17628): Removing virtual disk drive(s) from VM.
2020-07-11 19:34:53 (17628): Removing network bandwidth throttle group from VM.
2020-07-11 19:34:53 (17628): Removing storage controller(s) from VM.
2020-07-11 19:34:53 (17628): Removing VM from VirtualBox.

    Hypervisor System Log:

08:46:14.312244 USBPROXY Getting USB descriptor failed with error 31
08:46:14.319075 USBPROXY Getting USB descriptor failed with error 31
08:46:24.486937 USBPROXY Getting USB descriptor failed with error 995
08:46:24.494772 USBPROXY Getting USB descriptor failed with error 31
08:46:38.185685 USBPROXY Getting USB descriptor failed with error 995
08:46:38.193496 USBPROXY Getting USB descriptor failed with error 31
08:47:45.386738 USBPROXY Getting USB descriptor failed with error 31
08:47:45.416012 USBPROXY Getting USB descriptor failed with error 31
08:47:55.486234 USBPROXY Getting USB descriptor failed with error 995
08:47:55.494372 USBPROXY Getting USB descriptor failed with error 31
08:56:28.846930 USBPROXY Getting USB descriptor failed with error 31
08:56:28.853763 USBPROXY Getting USB descriptor failed with error 31
08:56:38.981238 USBPROXY Getting USB descriptor failed with error 995
08:56:38.989070 USBPROXY Getting USB descriptor failed with error 31
08:56:51.425584          Saving settings file "C:\ProgramData\BOINC\slots\5\boinc_9b189449f4840c96\boinc_9b189449f4840c96.vbox" with version "1.16-windows"
08:56:55.776701 USBPROXY Getting USB descriptor failed with error 995
08:56:55.784725 USBPROXY Getting USB descriptor failed with error 31
08:56:57.452404          ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={SessionMachine} aText={This machine does not have any snapshots}, preserve=false aResultDetail=0
08:56:57.457305          Saving settings file "C:\ProgramData\BOINC\slots\5\boinc_9b189449f4840c96\boinc_9b189449f4840c96.vbox" with version "1.16-windows"
08:56:57.464115          Saving settings file "C:\ProgramData\BOINC\slots\5\boinc_9b189449f4840c96\boinc_9b189449f4840c96.vbox" with version "1.16-windows"
08:56:57.468996          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={Medium 'C:\Program Files\Oracle\VirtualBox\VBoxGuestAdditions.iso' is locked for reading by another task}, preserve=false aResultDetail=0
08:56:57.469979          Saving settings file "C:\Users\Mike\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
08:56:57.473875          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={The object is not ready}, preserve=false aResultDetail=0
08:56:57.473875          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={Medium 'C:\Program Files\Oracle\VirtualBox\VBoxGuestAdditions.iso' is locked for reading by another task}, preserve=false aResultDetail=0
08:57:02.185926          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={9570b9d5-f1a1-448a-10c5-e12f5285adad} aComponent={VirtualBoxWrap} aText={Could not find a registered machine named 'boinc_ba6392000b84e16c'}, preserve=false aResultDetail=0
08:57:02.192728          Saving settings file "C:\ProgramData\BOINC\slots\3\boinc_ba6392000b84e16c\boinc_ba6392000b84e16c.vbox" with version "1.16-windows"
08:57:02.195657          Saving settings file "C:\Users\Mike\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
08:57:02.217152          Saving settings file "C:\ProgramData\BOINC\slots\3\boinc_ba6392000b84e16c\boinc_ba6392000b84e16c.vbox" with version "1.16-windows"
08:57:05.384788          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The given session is busy}, preserve=false aResultDetail=0
08:57:25.420442          Saving settings file "C:\ProgramData\BOINC\slots\3\boinc_ba6392000b84e16c\boinc_ba6392000b84e16c.vbox" with version "1.16-windows"
09:00:06.132030 USBPROXY Getting USB descriptor failed with error 31
09:00:06.138861 USBPROXY Getting USB descriptor failed with error 31
09:00:16.244023 USBPROXY Getting USB descriptor failed with error 995
09:00:16.251834 USBPROXY Getting USB descriptor failed with error 31
09:04:05.383040 USBPROXY Getting USB descriptor failed with error 31
09:04:05.390848 USBPROXY Getting USB descriptor failed with error 31
09:04:15.524766 USBPROXY Getting USB descriptor failed with error 995
09:04:15.531597 USBPROXY Getting USB descriptor failed with error 31
09:06:41.324257 USBPROXY Getting USB descriptor failed with error 31
09:06:41.353538 USBPROXY Getting USB descriptor failed with error 31
09:06:51.417014 USBPROXY Getting USB descriptor failed with error 995
09:06:51.424830 USBPROXY Getting USB descriptor failed with error 31
09:23:07.322951 USBPROXY Getting USB descriptor failed with error 31
09:23:07.330730 USBPROXY Getting USB descriptor failed with error 31
09:23:17.565304 USBPROXY Getting USB descriptor failed with error 995
09:23:17.572907 USBPROXY Getting USB descriptor failed with error 31
09:29:55.715215          Saving settings file "C:\ProgramData\BOINC\slots\1\boinc_9e5f3dcb8510244f\boinc_9e5f3dcb8510244f.vbox" with version "1.16-windows"
09:29:55.726900          Saving settings file "C:\ProgramData\BOINC\slots\6\boinc_b09d7be34e327dab\boinc_b09d7be34e327dab.vbox" with version "1.16-windows"
09:30:01.740643          ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={SessionMachine} aText={This machine does not have any snapshots}, preserve=false aResultDetail=0
09:30:01.744545          Saving settings file "C:\ProgramData\BOINC\slots\1\boinc_9e5f3dcb8510244f\boinc_9e5f3dcb8510244f.vbox" with version "1.16-windows"
09:30:01.751378          Saving settings file "C:\ProgramData\BOINC\slots\1\boinc_9e5f3dcb8510244f\boinc_9e5f3dcb8510244f.vbox" with version "1.16-windows"
09:30:01.754304          ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={SessionMachine} aText={This machine does not have any snapshots}, preserve=false aResultDetail=0
09:30:01.754304          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={Medium 'C:\Program Files\Oracle\VirtualBox\VBoxGuestAdditions.iso' is locked for reading by another task}, preserve=false aResultDetail=0
09:30:01.756259          Saving settings file "C:\Users\Mike\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
09:30:01.757233          Saving settings file "C:\ProgramData\BOINC\slots\6\boinc_b09d7be34e327dab\boinc_b09d7be34e327dab.vbox" with version "1.16-windows"
09:30:01.759198          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={The object is not ready}, preserve=false aResultDetail=0
09:30:01.759198          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={Medium 'C:\Program Files\Oracle\VirtualBox\VBoxGuestAdditions.iso' is locked for reading by another task}, preserve=false aResultDetail=0
09:30:01.761136          Saving settings file "C:\ProgramData\BOINC\slots\6\boinc_b09d7be34e327dab\boinc_b09d7be34e327dab.vbox" with version "1.16-windows"
09:30:01.765040          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={Medium 'C:\Program Files\Oracle\VirtualBox\VBoxGuestAdditions.iso' is locked for reading by another task}, preserve=false aResultDetail=0
09:30:01.766016          Saving settings file "C:\Users\Mike\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
09:30:01.769920          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={The object is not ready}, preserve=false aResultDetail=0
09:30:01.769920          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={Medium 'C:\Program Files\Oracle\VirtualBox\VBoxGuestAdditions.iso' is locked for reading by another task}, preserve=false aResultDetail=0

    VM Execution Log:


    VM Startup Log:


    VM Trace Log:

19:35:03 (17628): called boinc_finish(194)

</stderr_txt>
]]>