Task 6534031

Name	07522480_008_2
Workunit	5064833
Created	17 Oct 2019, 6:16:55 UTC
Sent	17 Oct 2019, 6:59:35 UTC
Report deadline	22 Oct 2019, 6:59:35 UTC
Received	21 Oct 2019, 8:26:07 UTC
Server state	Over
Outcome	Computation error
Client state	Compute error
Exit status	197 (0x000000C5) EXIT_TIME_LIMIT_EXCEEDED
Computer ID	3567
Run time	2 hours 20 min 52 sec
CPU time	6 sec
Validate state	Invalid
Credit	0.00
Device peak FLOPS	3.98 GFLOPS
Application version	boinc2docker v1.12 (vbox64_mt) windows_x86_64
Peak working set size	92.45 MB
Peak swap size	97.05 MB
Peak disk usage	601.09 MB
Stderr output

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

2019-10-18 07:11:22 (8280): Guest Log: CPUID EDX: 0x078bf3ff

2019-10-18 07:11:22 (8280): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-10-18 07:11:22 (8280): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80

2019-10-18 07:11:22 (8280): Guest Log: BIOS: Boot from Hard Disk 0 failed

2019-10-18 07:11:22 (8280): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003

2019-10-18 07:11:22 (8280): Guest Log: BIOS: Booting from CD-ROM...

2019-10-18 07:11:22 (8280): Successfully started VM. (PID = '57592')
2019-10-18 07:11:22 (8280): Reporting VM Process ID to BOINC.
2019-10-18 07:11:27 (8280): VM state change detected. (old = 'poweroff', new = 'running')
2019-10-18 07:11:37 (8280): Preference change detected
2019-10-18 07:11:37 (8280): Setting CPU throttle for VM. (100%)
2019-10-18 07:11:37 (8280): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-10-18 08:37:44 (8280): VM state change detected. (old = 'running', new = 'paused')
2019-10-21 09:49:35 (34444): vboxwrapper (7.9.26200): starting
2019-10-21 09:49:36 (34444): Feature: Checkpoint interval offset (260 seconds)
2019-10-21 09:49:36 (34444): Detected: VirtualBox COM Interface (Version: 5.2.8)
2019-10-21 09:49:36 (34444): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-10-21 09:49:36 (34444): Guest Log: BIOS: VirtualBox 5.2.8

2019-10-21 09:49:36 (34444): Guest Log: CPUID EDX: 0x078bf3ff

2019-10-21 09:49:36 (34444): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-10-21 09:49:36 (34444): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80

2019-10-21 09:49:36 (34444): Guest Log: BIOS: Boot from Hard Disk 0 failed

2019-10-21 09:49:36 (34444): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003

2019-10-21 09:49:36 (34444): Guest Log: BIOS: Booting from CD-ROM...

2019-10-21 09:49:36 (34444): Starting VM. (boinc_3c1404a358cfafe4, slot#5)
2019-10-21 09:49:51 (34444): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-10-21 09:49:51 (34444): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80

2019-10-21 09:49:51 (34444): Guest Log: BIOS: Boot from Hard Disk 0 failed

2019-10-21 09:49:51 (34444): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003

2019-10-21 09:49:51 (34444): Guest Log: BIOS: Booting from CD-ROM...

2019-10-21 09:49:51 (34444): Successfully started VM. (PID = '72628')
2019-10-21 09:49:51 (34444): Reporting VM Process ID to BOINC.
2019-10-21 09:49:56 (34444): VM state change detected. (old = 'poweroff', new = 'running')
2019-10-21 09:50:06 (34444): VM state change detected. (old = 'running', new = 'paused')
2019-10-21 09:50:16 (34444): Preference change detected
2019-10-21 09:50:16 (34444): Setting CPU throttle for VM. (100%)
2019-10-21 09:50:16 (34444): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-10-21 09:50:16 (34444): VM state change detected. (old = 'paused', new = 'running')
2019-10-21 09:50:36 (34444): VM state change detected. (old = 'running', new = 'paused')
2019-10-21 09:51:36 (34444): VM state change detected. (old = 'paused', new = 'running')
2019-10-21 09:52:46 (34444): VM state change detected. (old = 'running', new = 'paused')
2019-10-21 09:53:06 (34444): VM state change detected. (old = 'paused', new = 'running')
2019-10-21 10:05:49 (34444): VM state change detected. (old = 'running', new = 'paused')
2019-10-21 10:06:09 (34444): VM state change detected. (old = 'paused', new = 'running')
2019-10-21 10:06:19 (34444): VM state change detected. (old = 'running', new = 'paused')
2019-10-21 10:07:30 (34444): VM state change detected. (old = 'paused', new = 'running')
2019-10-21 10:08:15 (34444): Status Report: Elapsed Time: '6000.373423'
2019-10-21 10:08:15 (34444): Status Report: CPU Time: '6.562500'
2019-10-21 10:50:58 (34444): VM state change detected. (old = 'running', new = 'paused')
2019-10-21 10:51:08 (34444): VM state change detected. (old = 'paused', new = 'running')
2019-10-21 10:54:39 (34444): VM state change detected. (old = 'running', new = 'paused')
2019-10-21 10:55:29 (34444): VM state change detected. (old = 'paused', new = 'running')
2019-10-21 10:55:59 (34444): VM state change detected. (old = 'running', new = 'paused')
2019-10-21 10:56:19 (34444): VM state change detected. (old = 'paused', new = 'running')
2019-10-21 11:03:50 (34444): VM state change detected. (old = 'running', new = 'paused')
2019-10-21 11:04:10 (34444): VM state change detected. (old = 'paused', new = 'running')
2019-10-21 11:04:20 (34444): VM state change detected. (old = 'running', new = 'paused')
2019-10-21 11:04:40 (34444): VM state change detected. (old = 'paused', new = 'running')
2019-10-21 11:24:22 (34444): Powering off VM.
2019-10-21 11:24:23 (34444): Successfully stopped VM.
2019-10-21 11:24:28 (34444): Deregistering VM. (boinc_3c1404a358cfafe4, slot#5)
2019-10-21 11:24:28 (34444): Removing virtual disk drive(s) from VM.
2019-10-21 11:24:28 (34444): Removing network bandwidth throttle group from VM.
2019-10-21 11:24:28 (34444): Removing storage controller(s) from VM.
2019-10-21 11:24:28 (34444): Removing VM from VirtualBox.

    Hypervisor System Log:

96:46:21.965327          Saving settings file "C:\Users\mikal\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
96:46:21.969232          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={The object is not ready}, preserve=false aResultDetail=0
96:46:21.969232          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
96:46:34.323327          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
96:46:34.342850          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
96:46:54.354055          Saving settings file "C:\ProgramData\BOINC\slots\9\boinc_6306f3391e6805d1\boinc_6306f3391e6805d1.vbox" with version "1.16-windows"
96:46:54.372277          Saving settings file "C:\ProgramData\BOINC\slots\7\boinc_5e8fcce58a0cb1d7\boinc_5e8fcce58a0cb1d7.vbox" with version "1.16-windows"
96:55:35.882636 USBPROXY Getting USB descriptor failed with error 31
96:55:35.883612 USBPROXY Getting USB descriptor failed with error 31
96:55:35.883612 USBPROXY Getting USB descriptor failed with error 31
96:55:35.883612 USBPROXY Getting USB descriptor failed with error 31
96:55:35.884589 USBPROXY Getting USB descriptor failed with error 31
96:55:35.884589 USBPROXY Getting USB descriptor failed with error 31
96:56:32.591928 USBPROXY Getting USB descriptor failed with error 31
96:56:32.592930 USBPROXY Getting USB descriptor failed with error 31
96:56:32.592930 USBPROXY Getting USB descriptor failed with error 31
96:56:32.592930 USBPROXY Getting USB descriptor failed with error 31
96:56:32.593881 USBPROXY Getting USB descriptor failed with error 31
96:56:32.593881 USBPROXY Getting USB descriptor failed with error 31
96:56:34.128542 USBPROXY Getting USB descriptor failed with error 31
96:56:34.129518 USBPROXY Getting USB descriptor failed with error 31
96:56:34.129518 USBPROXY Getting USB descriptor failed with error 31
96:56:34.129518 USBPROXY Getting USB descriptor failed with error 31
96:56:34.129518 USBPROXY Getting USB descriptor failed with error 31
96:56:34.129518 USBPROXY Getting USB descriptor failed with error 31
96:57:07.323027 USBPROXY Getting USB descriptor failed with error 31
96:57:07.324003 USBPROXY Getting USB descriptor failed with error 31
96:57:07.324003 USBPROXY Getting USB descriptor failed with error 31
96:57:07.324003 USBPROXY Getting USB descriptor failed with error 31
96:57:07.324003 USBPROXY Getting USB descriptor failed with error 31
96:57:07.324979 USBPROXY Getting USB descriptor failed with error 31
96:57:09.537979 USBPROXY Getting USB descriptor failed with error 31
96:57:09.539931 USBPROXY Getting USB descriptor failed with error 31
96:57:09.539931 USBPROXY Getting USB descriptor failed with error 31
96:57:09.539931 USBPROXY Getting USB descriptor failed with error 31
96:57:09.539931 USBPROXY Getting USB descriptor failed with error 31
96:57:09.539931 USBPROXY Getting USB descriptor failed with error 31
96:57:11.614338 USBPROXY Getting USB descriptor failed with error 31
96:57:11.616506 USBPROXY Getting USB descriptor failed with error 31
96:57:11.616506 USBPROXY Getting USB descriptor failed with error 31
96:57:11.616506 USBPROXY Getting USB descriptor failed with error 31
96:57:11.616506 USBPROXY Getting USB descriptor failed with error 31
96:57:11.616506 USBPROXY Getting USB descriptor failed with error 31
96:57:21.497411 USBPROXY Getting USB descriptor failed with error 31
96:57:21.498388 USBPROXY Getting USB descriptor failed with error 31
96:57:21.499363 USBPROXY Getting USB descriptor failed with error 31
96:57:21.499363 USBPROXY Getting USB descriptor failed with error 31
96:57:21.499363 USBPROXY Getting USB descriptor failed with error 31
96:57:21.499363 USBPROXY Getting USB descriptor failed with error 31
96:57:22.231507          Saving settings file "C:\ProgramData\BOINC\slots\8\boinc_5dc30eed7dcabe4f\boinc_5dc30eed7dcabe4f.vbox" with version "1.16-windows"
96:57:22.799351          Saving settings file "C:\ProgramData\BOINC\slots\3\boinc_4454808e83d700f7\boinc_4454808e83d700f7.vbox" with version "1.16-windows"
96:57:23.464982          Saving settings file "C:\ProgramData\BOINC\slots\6\boinc_5a209de5a8d92c9e\boinc_5a209de5a8d92c9e.vbox" with version "1.16-windows"
96:57:54.196176 USBPROXY Getting USB descriptor failed with error 31
96:57:54.198126 USBPROXY Getting USB descriptor failed with error 31
96:57:54.198126 USBPROXY Getting USB descriptor failed with error 31
96:57:54.198126 USBPROXY Getting USB descriptor failed with error 31
96:57:54.199108 USBPROXY Getting USB descriptor failed with error 31
96:57:54.199108 USBPROXY Getting USB descriptor failed with error 31
97:02:21.201260          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
97:02:21.240302          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
97:02:21.304729          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
97:02:41.233894          Saving settings file "C:\ProgramData\BOINC\slots\6\boinc_5a209de5a8d92c9e\boinc_5a209de5a8d92c9e.vbox" with version "1.16-windows"
97:02:41.270730          Saving settings file "C:\ProgramData\BOINC\slots\3\boinc_4454808e83d700f7\boinc_4454808e83d700f7.vbox" with version "1.16-windows"
97:02:41.336155          Saving settings file "C:\ProgramData\BOINC\slots\8\boinc_5dc30eed7dcabe4f\boinc_5dc30eed7dcabe4f.vbox" with version "1.16-windows"
97:25:05.139004          Saving settings file "C:\ProgramData\BOINC\slots\5\boinc_3c1404a358cfafe4\boinc_3c1404a358cfafe4.vbox" with version "1.16-windows"
97:25:11.170443          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
97:25:11.176296          Saving settings file "C:\ProgramData\BOINC\slots\5\boinc_3c1404a358cfafe4\boinc_3c1404a358cfafe4.vbox" with version "1.16-windows"
97:25:11.183254          Saving settings file "C:\ProgramData\BOINC\slots\5\boinc_3c1404a358cfafe4\boinc_3c1404a358cfafe4.vbox" with version "1.16-windows"
97:25:11.188121          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
97:25:11.190078          Saving settings file "C:\Users\mikal\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
97:25:11.194941          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={The object is not ready}, preserve=false aResultDetail=0
97:25:11.195914          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:

11:24:39 (34444): called boinc_finish(194)

</stderr_txt>
]]>