Task 1061774

Name	07146692_128_0
Workunit	681181
Created	5 Apr 2019, 17:08:08 UTC
Sent	5 Apr 2019, 17:11:09 UTC
Report deadline	10 Apr 2019, 17:11:09 UTC
Received	6 Apr 2019, 9:11:47 UTC
Server state	Over
Outcome	Computation error
Client state	Compute error
Exit status	197 (0x000000C5) EXIT_TIME_LIMIT_EXCEEDED
Computer ID	1168
Run time	2 hours 39 min 52 sec
CPU time	18 sec
Validate state	Invalid
Credit	0.00
Device peak FLOPS	2.28 GFLOPS
Application version	boinc2docker v1.12 (vbox64_mt) windows_x86_64
Peak working set size	89.57 MB
Peak swap size	95.64 MB
Peak disk usage	227.22 MB
Stderr output

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

2019-04-06 06:28:26 (6932): Guest Log: CPUID EDX: 0x078bf3ff

2019-04-06 06:28:26 (6932): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-04-06 06:28:26 (6932): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80

2019-04-06 06:28:26 (6932): Guest Log: BIOS: Boot from Hard Disk 0 failed

2019-04-06 06:28:26 (6932): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003

2019-04-06 06:28:26 (6932): Guest Log: BIOS: Booting from CD-ROM...

2019-04-06 06:28:26 (6932): Successfully started VM. (PID = '224')
2019-04-06 06:28:26 (6932): Reporting VM Process ID to BOINC.
2019-04-06 06:28:31 (6932): VM state change detected. (old = 'poweroff', new = 'running')
2019-04-06 06:28:41 (6932): Preference change detected
2019-04-06 06:28:41 (6932): Setting CPU throttle for VM. (60%)
2019-04-06 06:28:41 (6932): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-04-06 08:08:44 (6932): Status Report: Elapsed Time: '6002.259459'
2019-04-06 08:08:44 (6932): Status Report: CPU Time: '8.812500'
2019-04-06 08:58:11 (6932): VM state change detected. (old = 'running', new = 'paused')
2019-04-06 08:58:21 (6932): VM state change detected. (old = 'paused', new = 'running')
2019-04-06 08:58:51 (6932): VM state change detected. (old = 'running', new = 'paused')
2019-04-06 08:59:11 (6932): VM state change detected. (old = 'paused', new = 'running')
2019-04-06 08:59:23 (6932): VM state change detected. (old = 'running', new = 'aborted')
2019-04-06 08:59:23 (6932): Powering off VM.
2019-04-06 08:59:23 (6932): Deregistering VM. (boinc_cb7c95732a145631, slot#0)
2019-04-06 08:59:23 (6932): Removing virtual disk drive(s) from VM.
2019-04-06 08:59:23 (6932): Removing network bandwidth throttle group from VM.
2019-04-06 08:59:23 (6932): Removing storage controller(s) from VM.
2019-04-06 08:59:23 (6932): Removing VM from VirtualBox.
2019-04-06 08:59:28 (6932): VM Premature Shutdown Detected.
2019-04-06 09:00:59 (9036): vboxwrapper (7.9.26200): starting
2019-04-06 09:01:01 (9036): Feature: Checkpoint interval offset (541 seconds)
2019-04-06 09:01:01 (9036): Detected: VirtualBox COM Interface (Version: 5.2.8)
2019-04-06 09:01:01 (9036): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-04-06 09:01:01 (9036): Create VM. (boinc_cb7c95732a145631, slot#0)
2019-04-06 09:01:01 (9036): Updating drive controller type and model for desired configuration.
2019-04-06 09:01:01 (9036): Setting Memory Size for VM. (3072MB)
2019-04-06 09:01:01 (9036): Setting CPU Count for VM. (1)
2019-04-06 09:01:01 (9036): Setting Chipset Options for VM.
2019-04-06 09:01:01 (9036): Setting Boot Options for VM.
2019-04-06 09:01:01 (9036): Enabling VM Network Access.
2019-04-06 09:01:01 (9036): Setting Network Configuration for NAT.
2019-04-06 09:01:01 (9036): Disabling USB Support for VM.
2019-04-06 09:01:01 (9036): Disabling COM Port Support for VM.
2019-04-06 09:01:01 (9036): Disabling LPT Port Support for VM.
2019-04-06 09:01:01 (9036): Disabling Audio Support for VM.
2019-04-06 09:01:01 (9036): Disabling Clipboard Support for VM.
2019-04-06 09:01:01 (9036): Disabling Drag and Drop Support for VM.
2019-04-06 09:01:01 (9036): Adding storage controller(s) to VM.
2019-04-06 09:01:01 (9036): Adding virtual ISO 9660 disk drive to VM. (vm_isocontext.iso)
2019-04-06 09:01:01 (9036): Adding VirtualBox Guest Additions to VM.
2019-04-06 09:01:02 (9036): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2019-04-06 09:01:02 (9036): Enabling shared directory for VM.
2019-04-06 09:01:02 (9036): Starting VM. (boinc_cb7c95732a145631, slot#0)
2019-04-06 09:01:18 (9036): Guest Log: BIOS: VirtualBox 5.2.8

2019-04-06 09:01:18 (9036): Guest Log: CPUID EDX: 0x078bf3ff

2019-04-06 09:01:18 (9036): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-04-06 09:01:18 (9036): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80

2019-04-06 09:01:18 (9036): Guest Log: BIOS: Boot from Hard Disk 0 failed

2019-04-06 09:01:18 (9036): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003

2019-04-06 09:01:18 (9036): Guest Log: BIOS: Booting from CD-ROM...

2019-04-06 09:01:18 (9036): Successfully started VM. (PID = '1008')
2019-04-06 09:01:18 (9036): Reporting VM Process ID to BOINC.
2019-04-06 09:01:23 (9036): VM state change detected. (old = 'poweroff', new = 'running')
2019-04-06 09:01:33 (9036): Status Report: Elapsed Time: '8991.029902'
2019-04-06 09:01:33 (9036): Status Report: CPU Time: '9.312500'
2019-04-06 09:01:33 (9036): Preference change detected
2019-04-06 09:01:33 (9036): Setting CPU throttle for VM. (60%)
2019-04-06 09:01:33 (9036): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-04-06 09:10:50 (9036): Powering off VM.
2019-04-06 09:10:51 (9036): Successfully stopped VM.
2019-04-06 09:10:56 (9036): Deregistering VM. (boinc_cb7c95732a145631, slot#0)
2019-04-06 09:10:56 (9036): Removing virtual disk drive(s) from VM.
2019-04-06 09:10:56 (9036): Removing network bandwidth throttle group from VM.
2019-04-06 09:10:56 (9036): Removing storage controller(s) from VM.
2019-04-06 09:10:56 (9036): Removing VM from VirtualBox.

    Hypervisor System Log:

00:00:00.484387 main     1234: create key '{dd3fc71d-26c0-4fe1-bf6f-67f633265bba}' -> 5
00:00:00.484387 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:00.484387 main     1234: create key '{dd3fc71d-26c0-4fe1-bf6f-67f633265bba}'(/Default='VirtualBoxClient Class') -> 5
00:00:00.500001          VirtualBox: object creation starts
00:00:00.500001          Home directory: 'C:\Users\CM\.VirtualBox'
00:00:00.500001          Installed Drivers:
00:00:00.500001            C:\WINDOWS\system32\DRIVERS\VBoxNetLwf.sys (Version: 5.2.8.21009)
00:00:00.500001            C:\WINDOWS\system32\DRIVERS\VBoxUSBMon.sys (Version: 5.2.8.21009)
00:00:00.500001            C:\WINDOWS\system32\DRIVERS\VBoxDrv.sys (Version: 5.2.8.21009)
00:00:00.500001            C:\WINDOWS\System32\drivers\VBoxNetAdp6.sys (Version: 5.2.8.21009)
00:00:00.515626          Loading settings file "C:\Users\CM\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
00:00:00.531249          Getting USB descriptor failed with error 31
00:00:00.546876          USB: Unknown USB device detected (idVendor: 0x0bda, idProduct: 0xb008)
00:00:00.859414          HostDnsMonitor: old information
00:00:00.859414            no server entries
00:00:00.859414            no domain set
00:00:00.859414            no search string entries
00:00:00.859414          HostDnsMonitor: new information
00:00:00.859414            server 1: 192.168.1.254
00:00:00.859414            domain: lan
00:00:00.859414            no search string entries
00:00:00.859414          HostDnsMonitorProxy::notify
00:00:01.234374          SUPR0QueryVTCaps -> VERR_SVM_DISABLED
00:00:01.234374          VD: VDInit finished with VINF_SUCCESS
00:00:01.234374          VirtualBox: object created
00:00:01.312502          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_cb7c95732a145631'}, preserve=false aResultDetail=0
00:00:01.312502          Saving settings file "C:\ProgramData\BOINC\slots\0\boinc_cb7c95732a145631\boinc_cb7c95732a145631.vbox" with version "1.16-windows"
00:00:01.390625          Saving settings file "C:\Users\CM\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
00:00:01.656355          Saving settings file "C:\ProgramData\BOINC\slots\0\boinc_cb7c95732a145631\boinc_cb7c95732a145631.vbox" with version "1.16-windows"
00:00:05.828125          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_ce8389b4fd42b48d'}, preserve=false aResultDetail=0
00:00:05.843749          Saving settings file "C:\ProgramData\BOINC\slots\4\boinc_ce8389b4fd42b48d\boinc_ce8389b4fd42b48d.vbox" with version "1.16-windows"
00:00:06.140649          Saving settings file "C:\Users\CM\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
00:00:06.234374          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_d492e58406f528db'}, preserve=false aResultDetail=0
00:00:06.250001          Saving settings file "C:\ProgramData\BOINC\slots\5\boinc_d492e58406f528db\boinc_d492e58406f528db.vbox" with version "1.16-windows"
00:00:06.281252          Saving settings file "C:\ProgramData\BOINC\slots\4\boinc_ce8389b4fd42b48d\boinc_ce8389b4fd42b48d.vbox" with version "1.16-windows"
00:00:06.343749          Saving settings file "C:\Users\CM\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
00:00:06.609373          Saving settings file "C:\ProgramData\BOINC\slots\5\boinc_d492e58406f528db\boinc_d492e58406f528db.vbox" with version "1.16-windows"
00:00:12.796246          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:13.173172          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:13.325504          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:33.111015          Saving settings file "C:\ProgramData\BOINC\slots\0\boinc_cb7c95732a145631\boinc_cb7c95732a145631.vbox" with version "1.16-windows"
00:00:33.283479          Saving settings file "C:\ProgramData\BOINC\slots\4\boinc_ce8389b4fd42b48d\boinc_ce8389b4fd42b48d.vbox" with version "1.16-windows"
00:00:33.422475          Saving settings file "C:\ProgramData\BOINC\slots\5\boinc_d492e58406f528db\boinc_d492e58406f528db.vbox" with version "1.16-windows"
00:01:10.672620 USBPROXY Getting USB descriptor failed with error 31
00:01:24.594238 USBPROXY Getting USB descriptor failed with error 31
00:04:14.451136          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_3d1fbc903c4b0837'}, preserve=false aResultDetail=0
00:04:14.472014          Saving settings file "C:\ProgramData\BOINC\slots\6\boinc_3d1fbc903c4b0837\boinc_3d1fbc903c4b0837.vbox" with version "1.16-windows"
00:04:14.531361          Saving settings file "C:\Users\CM\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
00:04:14.626448          Saving settings file "C:\ProgramData\BOINC\slots\6\boinc_3d1fbc903c4b0837\boinc_3d1fbc903c4b0837.vbox" with version "1.16-windows"
00:04:20.395760          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:04:40.484345          Saving settings file "C:\ProgramData\BOINC\slots\6\boinc_3d1fbc903c4b0837\boinc_3d1fbc903c4b0837.vbox" with version "1.16-windows"
00:06:46.954227 USBPROXY Getting USB descriptor failed with error 31
00:09:50.081017          Saving settings file "C:\ProgramData\BOINC\slots\0\boinc_cb7c95732a145631\boinc_cb7c95732a145631.vbox" with version "1.16-windows"
00:09:56.209827          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:09:56.264499          Saving settings file "C:\ProgramData\BOINC\slots\0\boinc_cb7c95732a145631\boinc_cb7c95732a145631.vbox" with version "1.16-windows"
00:09:56.376803          Saving settings file "C:\ProgramData\BOINC\slots\0\boinc_cb7c95732a145631\boinc_cb7c95732a145631.vbox" with version "1.16-windows"
00:09:56.408048          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:09:56.410975          Saving settings file "C:\Users\CM\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
00:09:56.451990          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:09:56.451990          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:11:07 (9036): called boinc_finish(194)

</stderr_txt>
]]>