Task 7941333

Name	07597968_47_0
Workunit	6466533
Created	30 Nov 2019, 8:05:35 UTC
Sent	1 Dec 2019, 0:29:36 UTC
Report deadline	6 Dec 2019, 0:29:36 UTC
Received	4 Dec 2019, 4:30:30 UTC
Server state	Over
Outcome	Computation error
Client state	Compute error
Exit status	197 (0x000000C5) EXIT_TIME_LIMIT_EXCEEDED
Computer ID	4237
Run time	2 hours 31 min 18 sec
CPU time	3 sec
Validate state	Invalid
Credit	0.00
Device peak FLOPS	3.57 GFLOPS
Application version	boinc2docker v1.12 (vbox64_mt) windows_x86_64
Peak working set size	93.54 MB
Peak swap size	100.76 MB
Peak disk usage	273.92 MB
Stderr output

<core_client_version>7.14.2</core_client_version>
<![CDATA[
<message>
exceeded elapsed time limit 9057.11 (86400.00G/9.54G)</message>
<stderr_txt>
2019-12-03 18:00:57 (5652): vboxwrapper (7.9.26200): starting
2019-12-03 18:00:58 (5652): Feature: Checkpoint interval offset (338 seconds)
2019-12-03 18:00:58 (5652): Detected: VirtualBox COM Interface (Version: 5.2.8)
2019-12-03 18:00:58 (5652): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-12-03 18:00:58 (5652): Create VM. (boinc_f26c53c756787e70, slot#3)
2019-12-03 18:00:58 (5652): Updating drive controller type and model for desired configuration.
2019-12-03 18:00:58 (5652): Setting Memory Size for VM. (3072MB)
2019-12-03 18:00:58 (5652): Setting CPU Count for VM. (1)
2019-12-03 18:00:58 (5652): Setting Chipset Options for VM.
2019-12-03 18:00:58 (5652): Setting Boot Options for VM.
2019-12-03 18:00:58 (5652): Enabling VM Network Access.
2019-12-03 18:00:58 (5652): Setting Network Configuration for NAT.
2019-12-03 18:00:58 (5652): Disabling USB Support for VM.
2019-12-03 18:00:58 (5652): Disabling COM Port Support for VM.
2019-12-03 18:00:58 (5652): Disabling LPT Port Support for VM.
2019-12-03 18:00:58 (5652): Disabling Audio Support for VM.
2019-12-03 18:00:58 (5652): Disabling Clipboard Support for VM.
2019-12-03 18:00:58 (5652): Disabling Drag and Drop Support for VM.
2019-12-03 18:00:58 (5652): Adding storage controller(s) to VM.
2019-12-03 18:00:58 (5652): Adding virtual ISO 9660 disk drive to VM. (vm_isocontext.iso)
2019-12-03 18:00:58 (5652): Adding VirtualBox Guest Additions to VM.
2019-12-03 18:00:58 (5652): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2019-12-03 18:00:58 (5652): Enabling shared directory for VM.
2019-12-03 18:00:58 (5652): Starting VM. (boinc_f26c53c756787e70, slot#3)
2019-12-03 18:01:06 (5652): Guest Log: BIOS: VirtualBox 5.2.8

2019-12-03 18:01:06 (5652): Guest Log: CPUID EDX: 0x078bf3ff

2019-12-03 18:01:06 (5652): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-12-03 18:01:06 (5652): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80

2019-12-03 18:01:06 (5652): Guest Log: BIOS: Boot from Hard Disk 0 failed

2019-12-03 18:01:06 (5652): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003

2019-12-03 18:01:06 (5652): Guest Log: BIOS: Booting from CD-ROM...

2019-12-03 18:01:06 (5652): Successfully started VM. (PID = '17252')
2019-12-03 18:01:06 (5652): Reporting VM Process ID to BOINC.
2019-12-03 18:01:11 (5652): VM state change detected. (old = 'poweroff', new = 'running')
2019-12-03 18:01:21 (5652): Preference change detected
2019-12-03 18:01:21 (5652): Setting CPU throttle for VM. (100%)
2019-12-03 18:01:21 (5652): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 600 seconds) or (Vbox_job.xml: 600 seconds))
2019-12-03 18:09:12 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 18:09:22 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 18:10:02 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 18:10:12 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 18:11:02 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 18:11:22 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 18:12:32 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 18:12:52 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 18:20:03 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 18:20:13 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 18:26:54 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 18:27:44 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 18:28:24 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 18:28:34 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 18:36:55 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 18:37:05 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 18:56:57 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 18:57:07 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 18:57:57 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 18:58:07 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 19:21:19 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 19:21:29 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 19:21:49 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 19:21:59 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 19:25:29 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 19:25:39 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 19:27:00 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 19:29:00 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 19:29:10 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 19:29:30 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 19:33:40 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 19:33:50 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 19:37:36 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 19:37:56 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 19:43:46 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 19:43:56 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 19:44:16 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 19:45:06 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 19:46:37 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 19:47:17 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 19:47:27 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 19:47:47 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 19:50:47 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 19:50:52 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 19:51:22 (5652): Status Report: Elapsed Time: '6003.888395'
2019-12-03 19:51:22 (5652): Status Report: CPU Time: '3.734375'
2019-12-03 19:51:47 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 19:51:52 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 19:52:07 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 19:52:18 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 20:01:08 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 20:01:18 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 20:01:39 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 20:01:49 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 20:02:39 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 20:03:09 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 20:04:09 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 20:04:14 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 20:14:10 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 20:14:20 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 20:40:02 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 20:40:12 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 20:40:22 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 20:40:32 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 20:41:13 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 20:41:23 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 20:41:33 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 20:41:53 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 20:42:23 (5652): VM state change detected. (old = 'running', new = 'paused')
2019-12-03 20:42:33 (5652): VM state change detected. (old = 'paused', new = 'running')
2019-12-03 20:42:38 (5652): Powering off VM.
2019-12-03 20:42:39 (5652): Successfully stopped VM.
2019-12-03 20:42:44 (5652): Deregistering VM. (boinc_f26c53c756787e70, slot#3)
2019-12-03 20:42:44 (5652): Removing virtual disk drive(s) from VM.
2019-12-03 20:42:44 (5652): Removing network bandwidth throttle group from VM.
2019-12-03 20:42:44 (5652): Removing storage controller(s) from VM.
2019-12-03 20:42:44 (5652): Removing VM from VirtualBox.

    Hypervisor System Log:

07:46:45.861000          Saving settings file "D:\BOINC\data\slots\9\boinc_f0c2abdac537c6bd\boinc_f0c2abdac537c6bd.vbox" with version "1.16-windows"
07:46:45.899613          Saving settings file "C:\Users\Jeff Davis\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
07:46:45.935737          Saving settings file "D:\BOINC\data\slots\9\boinc_f0c2abdac537c6bd\boinc_f0c2abdac537c6bd.vbox" with version "1.16-windows"
07:46:50.035261          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
07:47:10.077851          Saving settings file "D:\BOINC\data\slots\9\boinc_f0c2abdac537c6bd\boinc_f0c2abdac537c6bd.vbox" with version "1.16-windows"
07:56:51.682792          Saving settings file "D:\BOINC\data\slots\3\boinc_9f269609c37c26d4\boinc_9f269609c37c26d4.vbox" with version "1.16-windows"
07:56:57.734099          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
07:56:57.739962          Saving settings file "D:\BOINC\data\slots\3\boinc_9f269609c37c26d4\boinc_9f269609c37c26d4.vbox" with version "1.16-windows"
07:56:57.759486          Saving settings file "D:\BOINC\data\slots\3\boinc_9f269609c37c26d4\boinc_9f269609c37c26d4.vbox" with version "1.16-windows"
07:56:57.775110          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
07:56:57.777063          Saving settings file "C:\Users\Jeff Davis\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
07:56:57.785852          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={The object is not ready}, preserve=false aResultDetail=0
07:56:57.785852          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
07:57:11.434667          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_f26c53c756787e70'}, preserve=false aResultDetail=0
07:57:11.443450          Saving settings file "D:\BOINC\data\slots\3\boinc_f26c53c756787e70\boinc_f26c53c756787e70.vbox" with version "1.16-windows"
07:57:11.466886          Saving settings file "C:\Users\Jeff Davis\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
07:57:11.526465          Saving settings file "D:\BOINC\data\slots\3\boinc_f26c53c756787e70\boinc_f26c53c756787e70.vbox" with version "1.16-windows"
07:57:15.023481          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
07:57:35.068353          Saving settings file "D:\BOINC\data\slots\3\boinc_f26c53c756787e70\boinc_f26c53c756787e70.vbox" with version "1.16-windows"
08:56:21.007478          Saving settings file "D:\BOINC\data\slots\6\boinc_edcaf6e490546743\boinc_edcaf6e490546743.vbox" with version "1.16-windows"
08:56:27.065683          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:27.069590          Saving settings file "D:\BOINC\data\slots\6\boinc_edcaf6e490546743\boinc_edcaf6e490546743.vbox" with version "1.16-windows"
08:56:27.104743          Saving settings file "D:\BOINC\data\slots\6\boinc_edcaf6e490546743\boinc_edcaf6e490546743.vbox" with version "1.16-windows"
08:56:27.126226          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:27.128179          Saving settings file "C:\Users\Jeff Davis\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
08:56:27.136967          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:27.136967          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
10:27:56.277906          Saving settings file "D:\BOINC\data\slots\9\boinc_f0c2abdac537c6bd\boinc_f0c2abdac537c6bd.vbox" with version "1.16-windows"
10:28:02.343263          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
10:28:02.359594          Saving settings file "D:\BOINC\data\slots\9\boinc_f0c2abdac537c6bd\boinc_f0c2abdac537c6bd.vbox" with version "1.16-windows"
10:28:02.388512          Saving settings file "D:\BOINC\data\slots\9\boinc_f0c2abdac537c6bd\boinc_f0c2abdac537c6bd.vbox" with version "1.16-windows"
10:28:02.407951          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
10:28:02.409465          Saving settings file "C:\Users\Jeff Davis\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
10:28:02.418143          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={The object is not ready}, preserve=false aResultDetail=0
10:28:02.418143          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
10:38:51.670862          Saving settings file "D:\BOINC\data\slots\3\boinc_f26c53c756787e70\boinc_f26c53c756787e70.vbox" with version "1.16-windows"
10:38:57.742557          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
10:38:57.748557          Saving settings file "D:\BOINC\data\slots\3\boinc_f26c53c756787e70\boinc_f26c53c756787e70.vbox" with version "1.16-windows"
10:38:57.768557          Saving settings file "D:\BOINC\data\slots\3\boinc_f26c53c756787e70\boinc_f26c53c756787e70.vbox" with version "1.16-windows"
10:38:57.785559          Saving settings file "D:\BOINC\data\slots\3\boinc_f26c53c756787e70\boinc_f26c53c756787e70.vbox" with version "1.16-windows"
10:38:57.811556          Saving settings file "C:\Users\Jeff Davis\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
10:38:57.817557          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={The object is not ready}, preserve=false aResultDetail=0
10:38:57.817557          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={The object is not ready}, preserve=false aResultDetail=0

    VM Execution Log:


    VM Startup Log:


    VM Trace Log:

20:42:54 (5652): called boinc_finish(194)

</stderr_txt>
]]>