Task 9125069

Name	07868405_54_0
Workunit	7282318
Created	30 Jul 2020, 8:50:52 UTC
Sent	30 Jul 2020, 17:27:23 UTC
Report deadline	4 Aug 2020, 17:27:23 UTC
Received	4 Aug 2020, 22:49:03 UTC
Server state	Over
Outcome	Computation error
Client state	Compute error
Exit status	197 (0x000000C5) EXIT_TIME_LIMIT_EXCEEDED
Computer ID	6013
Run time	3 hours 46 min 30 sec
CPU time	11 sec
Validate state	Invalid
Credit	0.00
Device peak FLOPS	2.32 GFLOPS
Application version	boinc2docker v1.12 (vbox64_mt) windows_x86_64
Peak working set size	92.12 MB
Peak swap size	96.82 MB
Peak disk usage	273.93 MB
Stderr output

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

2020-08-03 21:31:13 (15864): Guest Log: CPUID EDX: 0x078bf3ff

2020-08-03 21:31:13 (15864): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-08-03 21:31:13 (15864): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80

2020-08-03 21:31:13 (15864): Guest Log: BIOS: Boot from Hard Disk 0 failed

2020-08-03 21:31:13 (15864): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003

2020-08-03 21:31:13 (15864): Guest Log: BIOS: Booting from CD-ROM...

2020-08-03 21:31:13 (15864): Successfully started VM. (PID = '20208')
2020-08-03 21:31:13 (15864): Reporting VM Process ID to BOINC.
2020-08-03 21:31:18 (15864): VM state change detected. (old = 'poweroff', new = 'running')
2020-08-03 21:31:28 (15864): Preference change detected
2020-08-03 21:31:28 (15864): Setting CPU throttle for VM. (75%)
2020-08-03 21:31:28 (15864): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-08-03 21:45:14 (15864): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 08:32:56 (5752): vboxwrapper (7.9.26200): starting
2020-08-04 08:32:57 (5752): Feature: Checkpoint interval offset (344 seconds)
2020-08-04 08:32:57 (5752): Detected: VirtualBox COM Interface (Version: 5.2.8)
2020-08-04 08:32:57 (5752): Detected: Minimum checkpoint interval (600.000000 seconds)
2020-08-04 08:32:57 (5752): Guest Log: BIOS: VirtualBox 5.2.8

2020-08-04 08:32:57 (5752): Guest Log: CPUID EDX: 0x078bf3ff

2020-08-04 08:32:57 (5752): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-08-04 08:32:57 (5752): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80

2020-08-04 08:32:57 (5752): Guest Log: BIOS: Boot from Hard Disk 0 failed

2020-08-04 08:32:57 (5752): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003

2020-08-04 08:32:57 (5752): Guest Log: BIOS: Booting from CD-ROM...

2020-08-04 08:32:57 (5752): Starting VM. (boinc_fb8974cac16f8b34, slot#2)
2020-08-04 08:33:07 (5752): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-08-04 08:33:07 (5752): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80

2020-08-04 08:33:07 (5752): Guest Log: BIOS: Boot from Hard Disk 0 failed

2020-08-04 08:33:07 (5752): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003

2020-08-04 08:33:07 (5752): Guest Log: BIOS: Booting from CD-ROM...

2020-08-04 08:33:07 (5752): Successfully started VM. (PID = '6528')
2020-08-04 08:33:07 (5752): Reporting VM Process ID to BOINC.
2020-08-04 08:33:12 (5752): VM state change detected. (old = 'poweroff', new = 'running')
2020-08-04 08:33:22 (5752): Preference change detected
2020-08-04 08:33:22 (5752): Setting CPU throttle for VM. (75%)
2020-08-04 08:33:22 (5752): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2020-08-04 08:36:28 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 08:39:28 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 08:40:28 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 08:53:40 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 09:04:51 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 10:21:40 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 10:22:00 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 10:37:57 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 10:40:12 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 10:56:34 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 10:57:09 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 11:00:09 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 11:01:19 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 11:05:35 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 11:06:05 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 11:09:10 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 11:22:42 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 11:30:58 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 11:36:08 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 12:08:42 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 12:08:57 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 12:12:07 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 12:13:52 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 12:19:38 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 12:21:38 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 13:24:09 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 13:28:55 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 13:53:18 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 13:53:58 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 14:04:24 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 14:08:24 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 14:14:15 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 14:15:50 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 15:06:40 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 15:09:26 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 15:12:26 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 15:12:36 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 15:21:32 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 15:22:07 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 15:27:33 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 15:39:54 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 15:48:40 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 15:51:56 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 15:58:11 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 16:14:08 (5752): Status Report: Elapsed Time: '6002.034310'
2020-08-04 16:14:08 (5752): Status Report: CPU Time: '10.640625'
2020-08-04 16:22:24 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 16:28:45 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 16:40:46 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 16:43:47 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 16:45:32 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 16:50:17 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 16:50:37 (5752): VM state change detected. (old = 'running', new = 'paused')
2020-08-04 16:53:38 (5752): VM state change detected. (old = 'paused', new = 'running')
2020-08-04 18:11:38 (5752): Status Report: Elapsed Time: '12004.446434'
2020-08-04 18:11:38 (5752): Status Report: CPU Time: '11.109375'
2020-08-04 18:46:11 (5752): Powering off VM.
2020-08-04 18:46:13 (5752): Successfully stopped VM.
2020-08-04 18:46:18 (5752): Deregistering VM. (boinc_fb8974cac16f8b34, slot#2)
2020-08-04 18:46:18 (5752): Removing virtual disk drive(s) from VM.
2020-08-04 18:46:18 (5752): Removing network bandwidth throttle group from VM.
2020-08-04 18:46:18 (5752): Removing storage controller(s) from VM.
2020-08-04 18:46:18 (5752): Removing VM from VirtualBox.

    Hypervisor System Log:

07:41:54.772831          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:42:14.841146          Saving settings file "C:\ProgramData\BOINC\slots\3\boinc_03b1555f285572a3\boinc_03b1555f285572a3.vbox" with version "1.16-windows"
09:39:31.855417          Saving settings file "C:\ProgramData\BOINC\slots\5\boinc_f1b8ad1ab20ffc94\boinc_f1b8ad1ab20ffc94.vbox" with version "1.16-windows"
09:39:37.899261          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
09:39:37.905116          Saving settings file "C:\ProgramData\BOINC\slots\5\boinc_f1b8ad1ab20ffc94\boinc_f1b8ad1ab20ffc94.vbox" with version "1.16-windows"
09:39:37.919708          Saving settings file "C:\ProgramData\BOINC\slots\5\boinc_f1b8ad1ab20ffc94\boinc_f1b8ad1ab20ffc94.vbox" with version "1.16-windows"
09:39:37.933377          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
09:39:37.935331          Saving settings file "C:\Users\snapp\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
09:39:37.948026          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={The object is not ready}, preserve=false aResultDetail=0
09:39:37.949003          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
09:39:52.212512          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_03ed338d3e0f613e'}, preserve=false aResultDetail=0
09:39:52.224231          Saving settings file "C:\ProgramData\BOINC\slots\5\boinc_03ed338d3e0f613e\boinc_03ed338d3e0f613e.vbox" with version "1.16-windows"
09:39:52.237903          Saving settings file "C:\Users\snapp\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
09:39:52.285305          Saving settings file "C:\ProgramData\BOINC\slots\5\boinc_03ed338d3e0f613e\boinc_03ed338d3e0f613e.vbox" with version "1.16-windows"
09:39:56.586584          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
09:40:16.655852          Saving settings file "C:\ProgramData\BOINC\slots\5\boinc_03ed338d3e0f613e\boinc_03ed338d3e0f613e.vbox" with version "1.16-windows"
10:13:15.633307          Saving settings file "C:\ProgramData\BOINC\slots\2\boinc_fb8974cac16f8b34\boinc_fb8974cac16f8b34.vbox" with version "1.16-windows"
10:13:17.599615          Saving settings file "C:\ProgramData\BOINC\slots\4\boinc_f174711d22e0e00a\boinc_f174711d22e0e00a.vbox" with version "1.16-windows"
10:13:19.729148          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_07c662084b141ca8'}, preserve=false aResultDetail=0
10:13:19.741843          Saving settings file "C:\ProgramData\BOINC\slots\8\boinc_07c662084b141ca8\boinc_07c662084b141ca8.vbox" with version "1.16-windows"
10:13:19.756491          Saving settings file "C:\Users\snapp\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
10:13:19.807268          Saving settings file "C:\ProgramData\BOINC\slots\8\boinc_07c662084b141ca8\boinc_07c662084b141ca8.vbox" with version "1.16-windows"
10:13:19.823869          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_09193448b3cf3e30'}, preserve=false aResultDetail=0
10:13:19.837541          Saving settings file "C:\ProgramData\BOINC\slots\9\boinc_09193448b3cf3e30\boinc_09193448b3cf3e30.vbox" with version "1.16-windows"
10:13:19.853164          Saving settings file "C:\Users\snapp\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
10:13:19.910778          Saving settings file "C:\ProgramData\BOINC\slots\9\boinc_09193448b3cf3e30\boinc_09193448b3cf3e30.vbox" with version "1.16-windows"
10:13:21.676289          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:13:21.682149          Saving settings file "C:\ProgramData\BOINC\slots\2\boinc_fb8974cac16f8b34\boinc_fb8974cac16f8b34.vbox" with version "1.16-windows"
10:13:21.699727          Saving settings file "C:\ProgramData\BOINC\slots\2\boinc_fb8974cac16f8b34\boinc_fb8974cac16f8b34.vbox" with version "1.16-windows"
10:13:21.717303          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:13:21.719256          Saving settings file "C:\Users\snapp\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
10:13:21.735856          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:13:21.735856          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:13:23.647192          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:13:23.654029          Saving settings file "C:\ProgramData\BOINC\slots\4\boinc_f174711d22e0e00a\boinc_f174711d22e0e00a.vbox" with version "1.16-windows"
10:13:23.671606          Saving settings file "C:\ProgramData\BOINC\slots\4\boinc_f174711d22e0e00a\boinc_f174711d22e0e00a.vbox" with version "1.16-windows"
10:13:23.689182          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:13:23.691136          Saving settings file "C:\Users\snapp\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
10:13:23.707736          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:13:23.708713          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:13:24.480148          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
10:13:24.485030          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

    VM Execution Log:


    VM Startup Log:


    VM Trace Log:

18:46:28 (5752): called boinc_finish(194)

</stderr_txt>
]]>