Task 4200422

Name	07458762_077_0
Workunit	3360966
Created	15 Aug 2019, 21:14:00 UTC
Sent	15 Aug 2019, 21:58:16 UTC
Report deadline	20 Aug 2019, 21:58:16 UTC
Received	16 Aug 2019, 14:39:03 UTC
Server state	Over
Outcome	Computation error
Client state	Compute error
Exit status	197 (0x000000C5) EXIT_TIME_LIMIT_EXCEEDED
Computer ID	2833
Run time	3 hours 21 min 59 sec
CPU time	41 sec
Validate state	Invalid
Credit	0.00
Device peak FLOPS	3.84 GFLOPS
Application version	boinc2docker v1.12 (vbox64_mt) windows_x86_64
Peak working set size	91.05 MB
Peak swap size	97.66 MB
Peak disk usage	499.03 MB
Stderr output

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

2019-08-16 05:14:44 (24404): Guest Log: CPUID EDX: 0x078bf3ff

2019-08-16 05:14:44 (24404): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-08-16 05:14:44 (24404): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80

2019-08-16 05:14:44 (24404): Guest Log: BIOS: Boot from Hard Disk 0 failed

2019-08-16 05:14:44 (24404): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003

2019-08-16 05:14:44 (24404): Guest Log: BIOS: Booting from CD-ROM...

2019-08-16 05:14:44 (24404): Successfully started VM. (PID = '23292')
2019-08-16 05:14:44 (24404): Reporting VM Process ID to BOINC.
2019-08-16 05:14:49 (24404): VM state change detected. (old = 'poweroff', new = 'running')
2019-08-16 05:14:59 (24404): Preference change detected
2019-08-16 05:14:59 (24404): Setting CPU throttle for VM. (100%)
2019-08-16 05:14:59 (24404): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-08-16 06:55:01 (24404): Status Report: Elapsed Time: '6001.851946'
2019-08-16 06:55:01 (24404): Status Report: CPU Time: '3.203125'
2019-08-16 08:26:52 (24404): Powering off VM.
2019-08-16 08:26:53 (24404): Successfully stopped VM.
2019-08-16 08:31:41 (12744): vboxwrapper (7.9.26200): starting
2019-08-16 08:31:41 (12744): Feature: Checkpoint interval offset (162 seconds)
2019-08-16 08:31:41 (12744): Detected: VirtualBox COM Interface (Version: 5.2.8)
2019-08-16 08:31:41 (12744): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-08-16 08:31:41 (12744): Guest Log: BIOS: VirtualBox 5.2.8

2019-08-16 08:31:41 (12744): Guest Log: CPUID EDX: 0x078bf3ff

2019-08-16 08:31:41 (12744): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-08-16 08:31:41 (12744): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80

2019-08-16 08:31:41 (12744): Guest Log: BIOS: Boot from Hard Disk 0 failed

2019-08-16 08:31:41 (12744): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003

2019-08-16 08:31:41 (12744): Guest Log: BIOS: Booting from CD-ROM...

2019-08-16 08:31:41 (12744): Starting VM. (boinc_401fb20d15423b4f, slot#8)
2019-08-16 08:31:49 (12744): Successfully started VM. (PID = '2056')
2019-08-16 08:31:49 (12744): Reporting VM Process ID to BOINC.
2019-08-16 08:31:54 (12744): VM state change detected. (old = 'poweroff', new = 'running')
2019-08-16 08:32:04 (12744): Status Report: Elapsed Time: '11468.227341'
2019-08-16 08:32:04 (12744): Status Report: CPU Time: '3.296875'
2019-08-16 08:32:04 (12744): Preference change detected
2019-08-16 08:32:04 (12744): Setting CPU throttle for VM. (100%)
2019-08-16 08:32:04 (12744): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-08-16 08:32:39 (12744): Error 0x80bb0002 in vbox52::VBOX_VM::pause (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1570)
2019-08-16 08:32:39 (12744): Error Source     : ConsoleWrap
2019-08-16 08:32:39 (12744): Error Description: Already paused
2019-08-16 08:32:39 (12744): VM state change detected. (old = 'running', new = 'paused')
2019-08-16 08:33:44 (12744): Error 0x80bb0002 in vbox52::VBOX_VM::resume (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1599)
2019-08-16 08:33:44 (12744): Error Source     : ConsoleWrap
2019-08-16 08:33:44 (12744): Error Description: Cannot resume the machine as it is not paused (machine state: Running)
2019-08-16 08:33:45 (12744): VM state change detected. (old = 'paused', new = 'running')
2019-08-16 08:36:05 (12744): VM state change detected. (old = 'running', new = 'paused')
2019-08-16 08:37:11 (12744): Error 0x80bb0002 in vbox52::VBOX_VM::resume (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1599)
2019-08-16 08:37:11 (12744): Error Source     : ConsoleWrap
2019-08-16 08:37:11 (12744): Error Description: Cannot resume the machine as it is not paused (machine state: Running)
2019-08-16 08:37:12 (12744): VM state change detected. (old = 'paused', new = 'running')
2019-08-16 08:39:37 (12744): Error 0x80bb0002 in vbox52::VBOX_VM::pause (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1570)
2019-08-16 08:39:37 (12744): Error Source     : ConsoleWrap
2019-08-16 08:39:37 (12744): Error Description: Already paused
2019-08-16 08:39:37 (12744): VM state change detected. (old = 'running', new = 'paused')
2019-08-16 08:40:43 (12744): Error 0x80bb0002 in vbox52::VBOX_VM::resume (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1599)
2019-08-16 08:40:43 (12744): Error Source     : ConsoleWrap
2019-08-16 08:40:43 (12744): Error Description: Cannot resume the machine as it is not paused (machine state: Running)
2019-08-16 08:40:44 (12744): VM state change detected. (old = 'paused', new = 'running')
2019-08-16 08:41:12 (12744): ERROR: Vboxwrapper lost communication with VirtualBox, rescheduling task for a later time.
2019-08-16 08:41:12 (12744): Powering off VM.
2019-08-16 08:41:12 (12744): Error 0x800706ba in vbox52::VBOX_VM::poweroff (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1510)
2019-08-16 08:41:12 (12744): Error: Getting Error Info! hr = 0x1
2019-08-16 09:28:43 (8992): vboxwrapper (7.9.26200): starting
2019-08-16 09:28:55 (8992): Feature: Checkpoint interval offset (538 seconds)
2019-08-16 09:28:55 (8992): Detected: VirtualBox COM Interface (Version: 5.2.8)
2019-08-16 09:28:55 (8992): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-08-16 09:28:55 (8992): Guest Log: BIOS: VirtualBox 5.2.8

2019-08-16 09:28:55 (8992): Guest Log: CPUID EDX: 0x078bf3ff

2019-08-16 09:28:55 (8992): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-08-16 09:28:55 (8992): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80

2019-08-16 09:28:55 (8992): Guest Log: BIOS: Boot from Hard Disk 0 failed

2019-08-16 09:28:55 (8992): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003

2019-08-16 09:28:55 (8992): Guest Log: BIOS: Booting from CD-ROM...

2019-08-16 09:28:55 (8992): Starting VM. (boinc_401fb20d15423b4f, slot#8)
2019-08-16 09:29:30 (8992): Guest Log: BIOS: VirtualBox 5.2.8

2019-08-16 09:29:30 (8992): Guest Log: CPUID EDX: 0x078bf3ff

2019-08-16 09:29:30 (8992): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-08-16 09:29:30 (8992): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80

2019-08-16 09:29:30 (8992): Guest Log: BIOS: Boot from Hard Disk 0 failed

2019-08-16 09:29:30 (8992): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003

2019-08-16 09:29:30 (8992): Guest Log: BIOS: Booting from CD-ROM...

2019-08-16 09:29:30 (8992): Successfully started VM. (PID = '16876')
2019-08-16 09:29:30 (8992): Reporting VM Process ID to BOINC.
2019-08-16 09:29:35 (8992): VM state change detected. (old = 'poweroff', new = 'running')
2019-08-16 09:29:45 (8992): Status Report: Elapsed Time: '11783.618241'
2019-08-16 09:29:45 (8992): Status Report: CPU Time: '8.203125'
2019-08-16 09:29:45 (8992): Preference change detected
2019-08-16 09:29:45 (8992): Setting CPU throttle for VM. (100%)
2019-08-16 09:29:46 (8992): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-08-16 09:38:31 (8992): VM state change detected. (old = 'running', new = 'paused')
2019-08-16 09:38:41 (8992): VM state change detected. (old = 'paused', new = 'running')
2019-08-16 09:38:46 (8992): Powering off VM.
2019-08-16 09:38:48 (8992): Successfully stopped VM.
2019-08-16 09:38:53 (8992): Deregistering VM. (boinc_401fb20d15423b4f, slot#8)
2019-08-16 09:38:53 (8992): Removing virtual disk drive(s) from VM.
2019-08-16 09:38:53 (8992): Removing network bandwidth throttle group from VM.
2019-08-16 09:38:53 (8992): Removing storage controller(s) from VM.
2019-08-16 09:38:53 (8992): Removing VM from VirtualBox.

    Hypervisor System Log:

00:00:02.906011 main     1148: create key 'VirtualBox.VirtualBoxClient.1' -> 5
00:00:02.906011 main     AssertLogRel F:\tinderbox\win-5.2\src\VBox\Main\src-all\win\VBoxProxyStub.c(937) long __cdecl vbpsCreateRegKeyWithDefaultValueAAEx(struct VBPSREGSTATE *,struct HKEY__ *,const char *,const char *,struct HKEY__ **,unsigned int): VBPS_LOGREL_NO_ASSERT(rc == ERROR_ACCESS_DENIED)
00:00:02.906011 main     1148: create key 'VirtualBox.VirtualBoxClient.1'(/Default='VirtualBoxClient Class') -> 5
00:00:02.907006 main     AssertLogRel F:\tinderbox\win-5.2\src\VBox\Main\src-all\win\VBoxProxyStub.c(842) long __cdecl vbpsCreateRegKeyA(struct VBPSREGSTATE *,struct HKEY__ *,const char *,struct HKEY__ **,unsigned int): VBPS_LOGREL_NO_ASSERT(rc == ERROR_ACCESS_DENIED)
00:00:02.907006 main     1148: create key 'VirtualBox.VirtualBoxClient' -> 5
00:00:02.907006 main     AssertLogRel F:\tinderbox\win-5.2\src\VBox\Main\src-all\win\VBoxProxyStub.c(937) long __cdecl vbpsCreateRegKeyWithDefaultValueAAEx(struct VBPSREGSTATE *,struct HKEY__ *,const char *,const char *,struct HKEY__ **,unsigned int): VBPS_LOGREL_NO_ASSERT(rc == ERROR_ACCESS_DENIED)
00:00:02.907006 main     1148: create key 'VirtualBox.VirtualBoxClient'(/Default='VirtualBoxClient Class') -> 5
00:00:02.907006 main     AssertLogRel F:\tinderbox\win-5.2\src\VBox\Main\src-all\win\VBoxProxyStub.c(842) long __cdecl vbpsCreateRegKeyA(struct VBPSREGSTATE *,struct HKEY__ *,const char *,struct HKEY__ **,unsigned int): VBPS_LOGREL_NO_ASSERT(rc == ERROR_ACCESS_DENIED)
00:00:02.907006 main     1234: create key '{dd3fc71d-26c0-4fe1-bf6f-67f633265bba}' -> 5
00:00:02.907006 main     AssertLogRel F:\tinderbox\win-5.2\src\VBox\Main\src-all\win\VBoxProxyStub.c(937) long __cdecl vbpsCreateRegKeyWithDefaultValueAAEx(struct VBPSREGSTATE *,struct HKEY__ *,const char *,const char *,struct HKEY__ **,unsigned int): VBPS_LOGREL_NO_ASSERT(rc == ERROR_ACCESS_DENIED)
00:00:02.907006 main     1234: create key '{dd3fc71d-26c0-4fe1-bf6f-67f633265bba}'(/Default='VirtualBoxClient Class') -> 5
00:00:02.909998          VirtualBox: object creation starts
00:00:02.910997          Home directory: 'C:\Users\Mark\.VirtualBox'
00:00:02.910997          Installed Drivers:
00:00:02.924123            C:\WINDOWS\system32\DRIVERS\VBoxNetLwf.sys (Version: 5.2.8.21009)
00:00:02.941734            C:\WINDOWS\system32\DRIVERS\VBoxUSBMon.sys (Version: 5.2.8.21009)
00:00:02.972880            C:\WINDOWS\system32\DRIVERS\VBoxDrv.sys (Version: 5.2.8.21009)
00:00:02.990900            C:\WINDOWS\system32\DRIVERS\VBoxNetAdp6.sys (Version: 5.2.8.21009)
00:00:03.128526          Loading settings file "C:\Users\Mark\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
00:00:03.254695          Getting USB descriptor failed with error 31
00:00:03.254695          Getting USB descriptor failed with error 31
00:00:03.255715          USB: Unknown USB device detected (idVendor: 0x0bda, idProduct: 0x58e6)
00:00:03.255715          USB: Unknown USB device detected (idVendor: 0x8087, idProduct: 0x0a2a)
00:00:06.002377          netIfIsWireless: CreateFile on '\\.\{850C14C8-C8EE-425A-819E-1D5319EC7CB2}' failed with rcWin=2 (0x2) - ignoring
00:00:06.045263          HostDnsMonitor: old information
00:00:06.045263            no server entries
00:00:06.045263            no domain set
00:00:06.045263            no search string entries
00:00:06.045263          HostDnsMonitor: new information
00:00:06.045263            server 1: 8.8.8.8
00:00:06.045263            server 2: 208.67.222.222
00:00:06.045263            domain: medusa.mezzonet.net
00:00:06.045263            no search string entries
00:00:06.046260          HostDnsMonitorProxy::notify
00:00:07.655329          SUPR0QueryVTCaps -> VERR_VMX_MSR_ALL_VMX_DISABLED
00:00:07.726702          VD: VDInit finished with VINF_SUCCESS
00:00:07.789620          Loading settings file "C:\ProgramData\BOINC\slots\12\boinc_3c513c0807778959\boinc_3c513c0807778959.vbox" with version "1.16-windows"
00:00:07.960330          Loading settings file "C:\ProgramData\BOINC\slots\8\boinc_401fb20d15423b4f\boinc_401fb20d15423b4f.vbox" with version "1.16-windows"
00:00:08.275456          Loading settings file "C:\ProgramData\BOINC\slots\13\boinc_402bd8bbc44685cd\boinc_402bd8bbc44685cd.vbox" with version "1.16-windows"
00:00:08.396135          Loading settings file "C:\ProgramData\BOINC\slots\9\boinc_4216ffe873795a0c\boinc_4216ffe873795a0c.vbox" with version "1.16-windows"
00:00:08.435058          Loading settings file "C:\ProgramData\BOINC\slots\14\boinc_4b4130270e3fb2ac\boinc_4b4130270e3fb2ac.vbox" with version "1.16-windows"
00:00:08.509263          Loading settings file "C:\ProgramData\BOINC\slots\16\boinc_4cb15345fe16bc3e\boinc_4cb15345fe16bc3e.vbox" with version "1.16-windows"
00:00:08.510225          VirtualBox: object created
00:00:38.342707          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
00:00:38.345636          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
00:00:38.345636          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
00:00:38.345636          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
00:00:58.794662          Saving settings file "C:\ProgramData\BOINC\slots\13\boinc_402bd8bbc44685cd\boinc_402bd8bbc44685cd.vbox" with version "1.16-windows"
00:00:58.794662          Saving settings file "C:\ProgramData\BOINC\slots\12\boinc_3c513c0807778959\boinc_3c513c0807778959.vbox" with version "1.16-windows"
00:00:58.796613          Saving settings file "C:\ProgramData\BOINC\slots\8\boinc_401fb20d15423b4f\boinc_401fb20d15423b4f.vbox" with version "1.16-windows"
00:00:59.042565          Saving settings file "C:\ProgramData\BOINC\slots\9\boinc_4216ffe873795a0c\boinc_4216ffe873795a0c.vbox" with version "1.16-windows"
00:10:00.099803          Saving settings file "C:\ProgramData\BOINC\slots\8\boinc_401fb20d15423b4f\boinc_401fb20d15423b4f.vbox" with version "1.16-windows"
00:10:06.166777          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
00:10:06.171656          Saving settings file "C:\ProgramData\BOINC\slots\8\boinc_401fb20d15423b4f\boinc_401fb20d15423b4f.vbox" with version "1.16-windows"
00:10:06.362360          Saving settings file "C:\ProgramData\BOINC\slots\8\boinc_401fb20d15423b4f\boinc_401fb20d15423b4f.vbox" with version "1.16-windows"
00:10:06.529507          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
00:10:06.531478          Saving settings file "C:\Users\Mark\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
00:10:06.901116          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={The object is not ready}, preserve=false aResultDetail=0
00:10:06.901116          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:

09:39:03 (8992): called boinc_finish(194)

</stderr_txt>
]]>