Task 7941334

Name	07597968_48_0
Workunit	6466534
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 3 sec
CPU time	4 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	92.75 MB
Peak swap size	100.70 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 17:50:32 (8452): vboxwrapper (7.9.26200): starting
2019-12-03 17:50:32 (8452): Feature: Checkpoint interval offset (506 seconds)
2019-12-03 17:50:32 (8452): Detected: VirtualBox COM Interface (Version: 5.2.8)
2019-12-03 17:50:32 (8452): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-12-03 17:50:32 (8452): Create VM. (boinc_f0c2abdac537c6bd, slot#9)
2019-12-03 17:50:32 (8452): Updating drive controller type and model for desired configuration.
2019-12-03 17:50:32 (8452): Setting Memory Size for VM. (3072MB)
2019-12-03 17:50:32 (8452): Setting CPU Count for VM. (1)
2019-12-03 17:50:32 (8452): Setting Chipset Options for VM.
2019-12-03 17:50:32 (8452): Setting Boot Options for VM.
2019-12-03 17:50:32 (8452): Enabling VM Network Access.
2019-12-03 17:50:32 (8452): Setting Network Configuration for NAT.
2019-12-03 17:50:32 (8452): Disabling USB Support for VM.
2019-12-03 17:50:32 (8452): Disabling COM Port Support for VM.
2019-12-03 17:50:32 (8452): Disabling LPT Port Support for VM.
2019-12-03 17:50:32 (8452): Disabling Audio Support for VM.
2019-12-03 17:50:32 (8452): Disabling Clipboard Support for VM.
2019-12-03 17:50:32 (8452): Disabling Drag and Drop Support for VM.
2019-12-03 17:50:32 (8452): Adding storage controller(s) to VM.
2019-12-03 17:50:32 (8452): Adding virtual ISO 9660 disk drive to VM. (vm_isocontext.iso)
2019-12-03 17:50:32 (8452): Adding VirtualBox Guest Additions to VM.
2019-12-03 17:50:32 (8452): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2019-12-03 17:50:32 (8452): Enabling shared directory for VM.
2019-12-03 17:50:32 (8452): Starting VM. (boinc_f0c2abdac537c6bd, slot#9)
2019-12-03 17:50:41 (8452): Guest Log: BIOS: VirtualBox 5.2.8

2019-12-03 17:50:41 (8452): Guest Log: CPUID EDX: 0x078bf3ff

2019-12-03 17:50:41 (8452): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-12-03 17:50:41 (8452): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80

2019-12-03 17:50:41 (8452): Guest Log: BIOS: Boot from Hard Disk 0 failed

2019-12-03 17:50:41 (8452): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003

2019-12-03 17:50:41 (8452): Guest Log: BIOS: Booting from CD-ROM...

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

    Hypervisor System Log:

07:46:44.797516          Saving settings file "D:\BOINC\data\slots\5\boinc_8fd64a26cca3107f\boinc_8fd64a26cca3107f.vbox" with version "1.16-windows"
07:46:44.825835          Saving settings file "D:\BOINC\data\slots\5\boinc_8fd64a26cca3107f\boinc_8fd64a26cca3107f.vbox" with version "1.16-windows"
07:46:44.841461          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:46:44.843411          Saving settings file "C:\Users\Jeff Davis\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
07:46:44.851228          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:46:44.851228          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:46:45.851235          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_f0c2abdac537c6bd'}, preserve=false aResultDetail=0
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

    VM Execution Log:


    VM Startup Log:


    VM Trace Log:

20:31:59 (8452): called boinc_finish(194)

</stderr_txt>
]]>