Task 6774644

Name	07537397_144_0
Workunit	5548418
Created	21 Oct 2019, 19:15:20 UTC
Sent	21 Oct 2019, 21:28:03 UTC
Report deadline	26 Oct 2019, 21:28:03 UTC
Received	21 Oct 2019, 22:30:44 UTC
Server state	Over
Outcome	Computation error
Client state	Compute error
Exit status	194 (0x000000C2) EXIT_ABORTED_BY_CLIENT
Computer ID	3201
Run time	24 sec
CPU time
Validate state	Invalid
Credit	0.00
Device peak FLOPS	2.88 GFLOPS
Application version	boinc2docker v1.12 (vbox64_mt) windows_x86_64
Peak working set size	72.90 MB
Peak swap size	84.28 MB
Peak disk usage	226.97 MB
Stderr output

<core_client_version>7.6.33</core_client_version>
<![CDATA[
<message>
finish file present too long
</message>
<stderr_txt>
2019-10-22 00:23:08 (4596): vboxwrapper (7.9.26200): starting
2019-10-22 00:23:10 (4596): Feature: Checkpoint interval offset (275 seconds)
2019-10-22 00:23:10 (4596): Detected: VirtualBox COM Interface (Version: 5.1.14)
2019-10-22 00:23:10 (4596): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-10-22 00:23:10 (4596): Create VM. (boinc_fb24f22d1e29bbf5, slot#2)
2019-10-22 00:23:10 (4596): Updating drive controller type and model for desired configuration.
2019-10-22 00:23:11 (4596): Setting Memory Size for VM. (3072MB)
2019-10-22 00:23:11 (4596): Setting CPU Count for VM. (1)
2019-10-22 00:23:11 (4596): Setting Chipset Options for VM.
2019-10-22 00:23:11 (4596): Setting Boot Options for VM.
2019-10-22 00:23:11 (4596): Enabling VM Network Access.
2019-10-22 00:23:11 (4596): Setting Network Configuration for NAT.
2019-10-22 00:23:11 (4596): Disabling USB Support for VM.
2019-10-22 00:23:11 (4596): Disabling COM Port Support for VM.
2019-10-22 00:23:11 (4596): Disabling LPT Port Support for VM.
2019-10-22 00:23:12 (4596): Disabling Audio Support for VM.
2019-10-22 00:23:12 (4596): Disabling Clipboard Support for VM.
2019-10-22 00:23:12 (4596): Disabling Drag and Drop Support for VM.
2019-10-22 00:23:12 (4596): Adding storage controller(s) to VM.
2019-10-22 00:23:12 (4596): Adding virtual ISO 9660 disk drive to VM. (vm_isocontext.iso)
2019-10-22 00:23:13 (4596): Adding VirtualBox Guest Additions to VM.
2019-10-22 00:23:13 (4596): Error 0x80bb0001 in vbox51::VBOX_VM::create_vm (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:643)
2019-10-22 00:23:13 (4596): Error Source     : VirtualBoxWrap
2019-10-22 00:23:13 (4596): Error Description: Cannot register the DVD image 'C:\Program Files\Oracle\VirtualBox/VBoxGuestAdditions.iso' {a64034ad-8f9f-4ffc-8e02-370d6e06c48e} because a CD/DVD image 'C:\Program Files\Oracle\VirtualBox/VBoxGuestAdditions.iso' with UUID {eafa34b8-7dc5-4438-b60d-c904844221b6} already exists
2019-10-22 00:23:13 (4596): Could not create VM
2019-10-22 00:23:13 (4596): Powering off VM.
2019-10-22 00:23:13 (4596): Deregistering VM. (boinc_fb24f22d1e29bbf5, slot#2)
2019-10-22 00:23:13 (4596): Removing virtual disk drive(s) from VM.
2019-10-22 00:23:13 (4596): Removing network bandwidth throttle group from VM.
2019-10-22 00:23:13 (4596): Removing storage controller(s) from VM.
2019-10-22 00:23:14 (4596): Removing VM from VirtualBox.

    Hypervisor System Log:

01:31:00.796916          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.796916          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.796916          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.796916          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.796916          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.796916          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.812520          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.812520          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.812520          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.812520          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.812520          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.812520          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.812520          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.812520          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.812520          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.812520          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.812520          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.812520          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.812520          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.812520          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.812520          ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={SessionMachine} aText={This machine does not have any snapshots}, preserve=false aResultDetail=0
01:31:00.812520          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.812520          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.812520          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.812520          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.812520          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.812520          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.812520          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.812520          ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={48c7f4c0-c9d6-4742-957c-a6fd52e8c4ae} aComponent={BandwidthControlWrap} aText={Could not find a bandwidth group named 'boinc_fb24f22d1e29bbf5_net'}, preserve=false aResultDetail=0
01:31:00.906297          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.906297          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.906297          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.906297          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.906297          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.906297          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:00.906297          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The object functionality is limited}, preserve=false aResultDetail=0
01:31:01.953205          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={The object is not ready}, preserve=false aResultDetail=0

    VM Execution Log:


    VM Startup Log:


    VM Trace Log:

00:23:25 (4596): called boinc_finish(-2135228415)
2019-10-22 00:26:41 (5004): vboxwrapper (7.9.26200): starting
2019-10-22 00:26:42 (5004): Feature: Checkpoint interval offset (299 seconds)
2019-10-22 00:26:42 (5004): Detected: VirtualBox COM Interface (Version: 5.1.14)
2019-10-22 00:26:42 (5004): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-10-22 00:26:42 (5004): Create VM. (boinc_fb24f22d1e29bbf5, slot#2)
2019-10-22 00:26:42 (5004): Updating drive controller type and model for desired configuration.
2019-10-22 00:26:42 (5004): Setting Memory Size for VM. (3072MB)
2019-10-22 00:26:42 (5004): Setting CPU Count for VM. (1)
2019-10-22 00:26:42 (5004): Setting Chipset Options for VM.
2019-10-22 00:26:42 (5004): Setting Boot Options for VM.
2019-10-22 00:26:42 (5004): Enabling VM Network Access.
2019-10-22 00:26:42 (5004): Setting Network Configuration for NAT.
2019-10-22 00:26:42 (5004): Disabling USB Support for VM.
2019-10-22 00:26:42 (5004): Disabling COM Port Support for VM.
2019-10-22 00:26:42 (5004): Disabling LPT Port Support for VM.
2019-10-22 00:26:42 (5004): Disabling Audio Support for VM.
2019-10-22 00:26:42 (5004): Disabling Clipboard Support for VM.
2019-10-22 00:26:42 (5004): Disabling Drag and Drop Support for VM.
2019-10-22 00:26:42 (5004): Adding storage controller(s) to VM.
2019-10-22 00:26:42 (5004): Adding virtual ISO 9660 disk drive to VM. (vm_isocontext.iso)
2019-10-22 00:26:42 (5004): Adding VirtualBox Guest Additions to VM.
2019-10-22 00:26:42 (5004): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2019-10-22 00:26:42 (5004): Enabling shared directory for VM.
2019-10-22 00:26:42 (5004): Starting VM. (boinc_fb24f22d1e29bbf5, slot#2)
2019-10-22 00:26:52 (5004): Guest Log: BIOS: VirtualBox 5.1.14

2019-10-22 00:26:52 (5004): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-10-22 00:26:52 (5004): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80

2019-10-22 00:26:52 (5004): Guest Log: BIOS: Boot from Hard Disk 0 failed

2019-10-22 00:26:52 (5004): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003

2019-10-22 00:26:52 (5004): Guest Log: BIOS: Booting from CD-ROM...

2019-10-22 00:26:52 (5004): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-10-22 00:26:52 (5004): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-10-22 00:26:52 (5004): Successfully started VM. (PID = '3068')
2019-10-22 00:26:52 (5004): Reporting VM Process ID to BOINC.
2019-10-22 00:26:57 (5004): VM state change detected. (old = 'poweroff', new = 'running')
2019-10-22 00:27:07 (5004): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2019-10-22 00:27:07 (5004): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-10-22 00:27:07 (5004): Guest Log: net.ipv4.ip_forward = 1

2019-10-22 00:27:07 (5004): Guest Log: sysctl: cannot stat /proc/sys/net/ipv6/conf/all/forwarding: No such file or directory

2019-10-22 00:27:07 (5004): Guest Log: sysctl: setting key "cannot stat %s": No such file or directory

2019-10-22 00:27:07 (5004): Guest Log: sysctl: "cannot stat %s" is an unknown key

2019-10-22 00:27:07 (5004): Guest Log: sysctl: setting key "cannot stat %s": No such file or directory

2019-10-22 00:27:07 (5004): Guest Log: Segmentation fault

2019-10-22 00:27:07 (5004): Guest Log: automount ...

2019-10-22 00:27:07 (5004): Guest Log: Is the disk unpartitioned?, test for the 'boot2docker format-me' string

2019-10-22 00:27:07 (5004): Guest Log: automount over.

2019-10-22 00:27:07 (5004): Guest Log: [1;32mSetting hostname to [1;35mboot2docker[0;39m [1;32mDone.[0;39m

2019-10-22 00:27:07 (5004): Guest Log: Tue Oct 22 00:27:01 UTC 2019 dhcp -------------------------------

2019-10-22 00:27:07 (5004): Powering off VM.

</stderr_txt>
]]>