All tasks failing?


Advanced search

Questions and Answers : Windows : All tasks failing?

Author Message
Leonard
Send message
Joined: 18 Nov 14
Posts: 1
Credit: 37,429
RAC: 0
    
Message 1441 - Posted: 25 Nov 2014, 8:20:33 UTC

Hi, I see to have some issue with one of my computers that I've tried to run this on.
I don't really know what the issue is.
Can someone tell me where I should look to fix.

I have reinstalled VirtualBox; stopped restarted Bionic etc, all to no avail.

This is one log that I see, several error indications but nothing I'm seeing to tell me what to fix.

Stderr output
<core_client_version>7.4.27</core_client_version>
<![CDATA[
<message>
(unknown error) - exit code 194 (0xc2)
</message>
<stderr_txt>
2014-11-25 04:28:36 (3196): vboxwrapper (7.5.26110): starting
2014-11-25 04:28:36 (3196): Feature: Checkpoint interval offset (550 seconds)
2014-11-25 04:28:55 (3196): Detected: VirtualBox 4.3.18r96516
2014-11-25 04:28:55 (3196): Detected: Minimum checkpoint interval (900.000000 seconds)
2014-11-25 04:28:55 (3196): successfully copied 'init_data.xml' to the shared directory.
2014-11-25 04:28:57 (3196): Create VM. (boinc_68a2f909e7629377, slot#0)
2014-11-25 04:28:57 (3196): Updating drive controller type and model for desired configuration.
2014-11-25 04:29:00 (3196): Setting CPU Count for VM. (1)
2014-11-25 04:29:01 (3196): Setting Memory Size for VM. (2048MB)
2014-11-25 04:29:01 (3196): Setting Chipset Options for VM.
2014-11-25 04:29:01 (3196): Setting Boot Options for VM.
2014-11-25 04:29:02 (3196): Setting Network Configuration for NAT.
2014-11-25 04:29:02 (3196): Disabling USB Support for VM.
2014-11-25 04:29:03 (3196): Disabling COM Port Support for VM.
2014-11-25 04:29:03 (3196): Disabling LPT Port Support for VM.
2014-11-25 04:29:03 (3196): Disabling Audio Support for VM.
2014-11-25 04:29:04 (3196): Disabling Clipboard Support for VM.
2014-11-25 04:29:05 (3196): Disabling Drag and Drop Support for VM.
2014-11-25 04:29:06 (3196): Adding storage controller to VM.
2014-11-25 04:29:07 (3196): Adding virtual ISO 9660 disk drive to VM. (vm_isocontext.iso)
2014-11-25 04:29:09 (3196): Adding VirtualBox Guest Additions to VM.
2014-11-25 04:29:10 (3196): Adding virtual cache disk drive to VM. (vm_cache.vdi)
2014-11-25 04:29:11 (3196): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2014-11-25 04:29:11 (3196): Enabling network access for VM.
2014-11-25 04:29:12 (3196): forwarding host port 63950 to guest port 80
2014-11-25 04:29:12 (3196): Enabling remote desktop for VM.
2014-11-25 04:29:12 (3196): Required extension pack not installed, remote desktop not enabled.
2014-11-25 04:29:12 (3196): Enabling shared directory for VM.
2014-11-25 04:29:13 (3196): WARNING: Stale VirtualBox VM Log used.
2014-11-25 04:29:13 (3196): WARNING: Stale VirtualBox VM Log Not Found.
2014-11-25 04:29:13 (3196): WARNING: Stale VirtualBox VM Log used.
2014-11-25 04:29:13 (3196): WARNING: Stale VirtualBox VM Log Not Found.
2014-11-25 04:29:13 (3196): Starting VM.
2014-11-25 04:29:27 (3196): Successfully started VM. (PID = '3944')
2014-11-25 04:29:27 (3196): Reporting VM Process ID to BOINC.
2014-11-25 04:29:28 (3196): Guest Log: BIOS: VirtualBox 4.3.18
2014-11-25 04:29:28 (3196): Guest Log: BIOS: AHCI 1-P#1: PCHS=16383/16/63 LCHS=1024/255/63 16777216 sectors
2014-11-25 04:29:28 (3196): VM state change detected. (old = 'poweroff', new = 'running')
2014-11-25 04:29:28 (3196): Detected: Web Application Enabled (2014-11-25 04:29:28 (3196):)
2014-11-25 04:29:28 (3196): Preference change detected
2014-11-25 04:29:28 (3196): Setting CPU throttle for VM. (80%)
2014-11-25 04:29:29 (3196): Checkpoint Interval is now 60 seconds.
2014-11-25 04:29:29 (3196): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2014-11-25 04:29:29 (3196): Guest Log: BIOS: Boot from Hard Disk 0 failed
2014-11-25 04:29:29 (3196): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003
2014-11-25 04:29:29 (3196): Guest Log: BIOS: Booting from CD-ROM...
2014-11-25 04:29:32 (3196): Guest Log: BIOS: KBD: unsupported int 16h function 03
2014-11-25 04:29:32 (3196): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2014-11-25 04:30:28 (3196): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2014-11-25 04:30:59 (3196): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88007bebf610), OR(0x0), NOT(0xffffffff), flags(0x0)
2014-11-25 04:30:59 (3196): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880075699c10), OR(0x0), NOT(0xffffffff), flags(0x0)
2014-11-25 04:30:59 (3196): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880075699010), OR(0x0), NOT(0xffffffff), flags(0x0)
2014-11-25 04:30:59 (3196): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88007be70010), OR(0x0), NOT(0xffffffff), flags(0x0)
2014-11-25 04:31:44 (3196): Guest Log: copied all input files into RunAtlas
2014-11-25 04:31:44 (3196): Guest Log: start to run ATLAS job,output is redirected into runtime_log
2014-11-25 04:53:47 (3196): Creating new snapshot for VM.
2014-11-25 05:22:12 (3196): Error in pause VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_68a2f909e7629377" pause
Output:

2014-11-25 06:07:36 (3196): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_68a2f909e7629377" resume
Output:

2014-11-25 06:17:53 (3196): Checkpoint completed.
2014-11-25 07:25:49 (3576): vboxwrapper (7.5.26110): starting
2014-11-25 07:25:50 (3576): Feature: Checkpoint interval offset (525 seconds)
2014-11-25 07:30:04 (3576): Detected: VirtualBox 4.3.18r96516
2014-11-25 07:30:05 (3576): Detected: Minimum checkpoint interval (900.000000 seconds)
2014-11-25 07:30:06 (3576): Guest Log: BIOS: VirtualBox 4.3.18
2014-11-25 07:30:06 (3576): Guest Log: BIOS: AHCI 1-P#1: PCHS=16383/16/63 LCHS=1024/255/63 16777216 sectors
2014-11-25 07:30:06 (3576): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2014-11-25 07:30:06 (3576): Guest Log: BIOS: Boot from Hard Disk 0 failed
2014-11-25 07:30:06 (3576): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003
2014-11-25 07:30:06 (3576): Guest Log: BIOS: Booting from CD-ROM...
2014-11-25 07:30:06 (3576): Guest Log: BIOS: KBD: unsupported int 16h function 03
2014-11-25 07:30:06 (3576): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000
2014-11-25 07:30:06 (3576): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2014-11-25 07:30:06 (3576): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88007bebf610), OR(0x0), NOT(0xffffffff), flags(0x0)
2014-11-25 07:30:06 (3576): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880075699c10), OR(0x0), NOT(0xffffffff), flags(0x0)
2014-11-25 07:30:06 (3576): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880075699010), OR(0x0), NOT(0xffffffff), flags(0x0)
2014-11-25 07:30:06 (3576): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88007be70010), OR(0x0), NOT(0xffffffff), flags(0x0)
2014-11-25 07:30:06 (3576): Guest Log: copied all input files into RunAtlas
2014-11-25 07:30:06 (3576): Guest Log: start to run ATLAS job,output is redirected into runtime_log
2014-11-25 07:30:06 (3576): Restore from previously saved snapshot.
2014-11-25 07:30:11 (3576): Restore completed.
2014-11-25 07:30:11 (3576): Starting VM.
2014-11-25 07:35:40 (3576): Successfully started VM. (PID = '5928')
2014-11-25 07:35:40 (3576): Reporting VM Process ID to BOINC.
2014-11-25 07:35:41 (3576): VM state change detected. (old = 'poweroff', new = 'running')
2014-11-25 07:35:41 (3576): Detected: Web Application Enabled (2014-11-25 07:35:41 (3576):)
2014-11-25 07:35:41 (3576): Preference change detected
2014-11-25 07:35:41 (3576): Setting CPU throttle for VM. (80%)
2014-11-25 07:35:42 (3576): Checkpoint Interval is now 60 seconds.
2014-11-25 07:44:09 (3576): Creating new snapshot for VM.
2014-11-25 07:45:11 (3576): Deleting stale snapshot.
2014-11-25 07:45:26 (3576): Checkpoint completed.
2014-11-25 07:58:49 (3576): Preference change detected
2014-11-25 07:58:49 (3576): Setting CPU throttle for VM. (80%)
2014-11-25 07:59:00 (3576): Checkpoint Interval is now 60 seconds.
2014-11-25 08:00:02 (3576): Powering off VM.
2014-11-25 08:00:08 (3576): Successfully powered off VM.
2014-11-25 08:05:05 (6248): vboxwrapper (7.5.26110): starting
2014-11-25 08:05:05 (6248): Feature: Checkpoint interval offset (194 seconds)
2014-11-25 08:05:05 (6248): Detected: VirtualBox 4.3.18r96516
2014-11-25 08:05:06 (6248): Detected: Minimum checkpoint interval (900.000000 seconds)
2014-11-25 08:05:06 (6248): Restore from previously saved snapshot.
2014-11-25 08:05:07 (6248): Restore completed.
2014-11-25 08:05:07 (6248): Starting VM.
2014-11-25 08:07:36 (6248): Successfully started VM. (PID = '3716')
2014-11-25 08:07:36 (6248): Reporting VM Process ID to BOINC.
2014-11-25 08:07:39 (6248): VM state change detected. (old = 'poweroff', new = 'running')
2014-11-25 08:07:39 (6248): Detected: Web Application Enabled (2014-11-25 08:07:39 (6248):)
2014-11-25 08:07:41 (6248): Creating new snapshot for VM.
2014-11-25 08:09:31 (6248): Deleting stale snapshot.
2014-11-25 08:09:36 (6248): Checkpoint completed.
2014-11-25 08:09:36 (6248): Preference change detected
2014-11-25 08:09:36 (6248): Setting CPU throttle for VM. (80%)
2014-11-25 08:09:38 (6248): Checkpoint Interval is now 60 seconds.
2014-11-25 08:25:11 (6248): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880075ba8210), OR(0x0), NOT(0xffffffff), flags(0x0)
2014-11-25 08:53:58 (6248): Error in pause VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_68a2f909e7629377" pause
Output:
VBoxManage.exe: error: Invalid machine state: Aborted
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component Console, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Pause()" at line 114 of file VBoxManageControlVM.cpp

2014-11-25 08:53:58 (6248): VM state change detected. (old = 'running', new = 'aborted')
2014-11-25 08:53:58 (6248): Powering off VM.
2014-11-25 08:53:58 (6248): Deregistering VM.
2014-11-25 08:54:00 (6248): Deleting stale snapshot.
2014-11-25 08:54:05 (6248): Removing network bandwidth throttle group from VM.
2014-11-25 08:54:05 (6248): Removing storage controller(s) from VM.
2014-11-25 08:54:06 (6248): Removing VM from VirtualBox.
2014-11-25 08:54:08 (6248): Removing virtual ISO 9660 disk from VirtualBox.
2014-11-25 08:54:08 (6248): Removing virtual cache disk from VirtualBox.
2014-11-25 08:54:14 (6248): VM Premature Shutdown Detected.
2014-11-25 08:54:14 (6248): WARNING: Stale VirtualBox VM Log used.

Hypervisor System Log:

VirtualBox COM Server 4.3.18 r96516 win.amd64 (Oct 11 2014 13:21:57) release log
00:00:00.031200 main Log opened 2014-11-24T20:27:55.076299600Z
00:00:00.031200 main Build Type: release
00:00:00.031200 main OS Product: Windows 7
00:00:00.031200 main OS Release: 6.1.7601
00:00:00.031200 main OS Service Pack: 1
00:01:59.059409 main DMI Product Name: P35-DQ6
00:01:59.090609 main DMI Product Version:
00:01:59.090609 main Host RAM: 4094MB total, 2259MB available
00:01:59.090609 main Executable: C:\Program Files\Oracle\VirtualBox\VBoxSVC.exe
00:01:59.090609 main Process ID: 4320
00:01:59.090609 main Package type: WINDOWS_64BITS_GENERIC
00:01:59.090609 Loading settings file "C:\Users\Leonard/.VirtualBox\VirtualBox.xml" with version "1.12-windows"
00:02:02.070214 HostDnsMonitorProxy: Host's DNS information updated:
00:02:02.070214 DNS server 1: 192.168.1.1
00:02:02.709815 VDInit finished
00:02:05.299420 Loading settings file "C:\ProgramData\BOINC\slots\0\boinc_68a2f909e7629377\boinc_68a2f909e7629377.vbox" with version "1.14-windows"
00:02:06.843822 Loading settings file "C:\ProgramData\BOINC\slots\1\boinc_da7ec0afe2e93608\boinc_da7ec0afe2e93608.vbox" with version "1.14-windows"
00:17:17.556267 DeleteSnapshot ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={05f2bbb6-a3a6-4fb9-9b49-6d0dda7142ac} aComponent={Medium} aText={Medium 'C:\ProgramData\BOINC\slots\0\vm_cache.vdi' is locked for reading by another task}, preserve=false
00:29:32.799131 ERROR [COM]: aRC=VBOX_E_VM_ERROR (0x80bb0003) aIID={480cf695-2d8d-4256-9c7c-cce4184fa048} aComponent={Machine} aText={Failed to assign the machine to the session (RPC_S_SERVER_UNAVAILABLE 0x800706BA)}, preserve=false
00:41:37.161735 DeleteSnapshot ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={05f2bbb6-a3a6-4fb9-9b49-6d0dda7142ac} aComponent={Medium} aText={Medium 'C:\ProgramData\BOINC\slots\0\vm_cache.vdi' is locked for reading by another task}, preserve=false

VM Execution Log:

00:04:22.247400 DrvBlock: Flushes will be ignored
00:04:22.247427 DrvBlock: Async flushes will be passed to the disk
00:04:22.452765 VD: Opening the disk took 205303498 ns
00:04:22.452822 AHCI: LUN#1: disk, PCHS=16383/16/63, total number of sectors 16777216
00:04:22.453228 ************************* CFGM dump *************************
00:04:22.453230 [/Devices/ahci/0/LUN#1/] (level 0)
00:04:22.453232 Driver <string> = "Block" (cb=6)
00:04:22.453233
00:04:22.453234 [/Devices/ahci/0/LUN#1/AttachedDriver/] (level 1)
00:04:22.453236 Driver <string> = "VD" (cb=3)
00:04:22.453237
00:04:22.453237 [/Devices/ahci/0/LUN#1/AttachedDriver/Config/] (level 2) (restricted root)
00:04:22.453239 BlockCache <integer> = 0x0000000000000001 (1)
00:04:22.453241 Format <string> = "VDI" (cb=4)
00:04:22.453242 Path <string> = "C:\ProgramData\BOINC\slots\0\boinc_68a2f909e7629377\Snapshots\{18ba2603-f73a-4a05-938f-a97bb07e70d1}.vdi" (cb=105)
00:04:22.453243 SetupMerge <integer> = 0x0000000000000001 (1)
00:04:22.453244 Type <string> = "HardDisk" (cb=9)
00:04:22.453245 UseNewIo <integer> = 0x0000000000000001 (1)
00:04:22.453246
00:04:22.453247 [/Devices/ahci/0/LUN#1/AttachedDriver/Config/Parent/] (level 3)
00:04:22.453249 Format <string> = "VDI" (cb=4)
00:04:22.453250 MergeSource <integer> = 0x0000000000000001 (1)
00:04:22.453251 Path <string> = "C:\ProgramData\BOINC\slots\0\boinc_68a2f909e7629377\Snapshots\{d51a17f9-461f-4dcd-8f38-88da193e3530}.vdi" (cb=105)
00:04:22.453252
00:04:22.453253 [/Devices/ahci/0/LUN#1/AttachedDriver/Config/Parent/Parent/] (level 4)
00:04:22.453255 Format <string> = "VDI" (cb=4)
00:04:22.453256 MergeTarget <integer> = 0x0000000000000001 (1)
00:04:22.453257 Path <string> = "C:\ProgramData\BOINC\slots\0\vm_cache.vdi" (cb=42)
00:04:22.453258
00:04:22.453259 [/Devices/ahci/0/LUN#1/Config/] (level 1) (restricted root)
00:04:22.453261 Mountable <integer> = 0x0000000000000000 (0)
00:04:22.453262 Type <string> = "HardDisk" (cb=9)
00:04:22.453262
00:04:22.453263 ********************* End of CFGM dump **********************
00:04:22.453288 Changing the VM state from 'SUSPENDED' to 'RESUMING'.
00:04:22.454260 Changing the VM state from 'RESUMING' to 'RUNNING'.
00:04:23.120309 Changing the VM state from 'RUNNING' to 'SUSPENDING'.
00:04:23.153983 PDMR3Suspend: 33 640 255 ns run time
00:04:23.154008 Changing the VM state from 'SUSPENDING' to 'SUSPENDED'.
00:04:23.855529 DrvBlock: Flushes will be ignored
00:04:23.855560 DrvBlock: Async flushes will be passed to the disk
00:04:23.857004 AIOMgr: Endpoint for file 'C:\ProgramData\BOINC\slots\0\vm_cache.vdi' (flags 000c0781) created successfully
00:04:24.149856 AIOMgr: Endpoint for file 'C:\ProgramData\BOINC\slots\0\boinc_68a2f909e7629377\Snapshots\{18ba2603-f73a-4a05-938f-a97bb07e70d1}.vdi' (flags 000c0723) created successfully
00:04:24.183829 VD: Opening the disk took 328233867 ns
00:04:24.183898 AHCI: LUN#1: disk, PCHS=16383/16/63, total number of sectors 16777216
00:04:24.184124 ************************* CFGM dump *************************
00:04:24.184126 [/Devices/ahci/0/LUN#1/] (level 0)
00:04:24.184130 Driver <string> = "Block" (cb=6)
00:04:24.184131
00:04:24.184132 [/Devices/ahci/0/LUN#1/AttachedDriver/] (level 1)
00:04:24.184135 Driver <string> = "VD" (cb=3)
00:04:24.184136
00:04:24.184137 [/Devices/ahci/0/LUN#1/AttachedDriver/Config/] (level 2) (restricted root)
00:04:24.184140 BlockCache <integer> = 0x0000000000000001 (1)
00:04:24.184143 Format <string> = "VDI" (cb=4)
00:04:24.184144 Path <string> = "C:\ProgramData\BOINC\slots\0\boinc_68a2f909e7629377\Snapshots\{18ba2603-f73a-4a05-938f-a97bb07e70d1}.vdi" (cb=105)
00:04:24.184146 Type <string> = "HardDisk" (cb=9)
00:04:24.184148 UseNewIo <integer> = 0x0000000000000001 (1)
00:04:24.184149
00:04:24.184150 [/Devices/ahci/0/LUN#1/AttachedDriver/Config/Parent/] (level 3)
00:04:24.184154 Format <string> = "VDI" (cb=4)
00:04:24.184155 Path <string> = "C:\ProgramData\BOINC\slots\0\vm_cache.vdi" (cb=42)
00:04:24.184157
00:04:24.184157 [/Devices/ahci/0/LUN#1/Config/] (level 1) (restricted root)
00:04:24.184160 Mountable <integer> = 0x0000000000000000 (0)
00:04:24.184162 Type <string> = "HardDisk" (cb=9)
00:04:24.184163
00:04:24.184164 ********************* End of CFGM dump **********************
00:04:24.184187 Changing the VM state from 'SUSPENDED' to 'RESUMING'.
00:04:24.184306 Changing the VM state from 'RESUMING' to 'RUNNING'.
00:04:43.050452 AIOMgr: Preparing flush failed with VERR_NOT_SUPPORTED, disabling async flushes
00:08:42.823293 AHCI#0: Port 1 reset
00:08:45.209400 AHCI#0P1: Canceled write at offset 1165558272 (458752 bytes left) returned rc=VINF_SUCCESS
00:09:02.510864 AHCI#0: Port 1 reset
00:10:18.367614 AsyncCompletion: Task 0x0000000748ac40 completed after 43 seconds
00:10:29.008296 AHCI#0: Port 1 reset
00:10:30.887183 AHCI#0P1: Cancelled task 4
00:10:37.269589 AHCI#0: Port 1 reset
00:10:44.474505 AsyncCompletion: Task 0x0000000748af80 completed after 75 seconds
00:10:44.474570 AsyncCompletion: Task 0x0000000748af40 completed after 75 seconds
00:10:44.474592 AsyncCompletion: Task 0x0000000748af00 completed after 75 seconds
00:10:44.474611 AsyncCompletion: Task 0x0000000748aec0 completed after 75 seconds
00:10:44.474629 AsyncCompletion: Task 0x0000000748ae80 completed after 75 seconds
00:10:44.474648 AsyncCompletion: Task 0x0000000748ae40 completed after 75 seconds
00:10:44.474666 AsyncCompletion: Task 0x0000000748ae00 completed after 75 seconds
00:10:44.474685 AsyncCompletion: Task 0x0000000748adc0 completed after 75 seconds
00:10:44.474703 AsyncCompletion: Task 0x0000000748ad80 completed after 75 seconds
00:10:44.474721 AsyncCompletion: Task 0x0000000748ad40 completed after 75 seconds
00:10:44.474739 AsyncCompletion: Task 0x0000000748ad00 completed after 75 seconds
00:10:44.474759 AsyncCompletion: Task 0x0000000748acc0 completed after 75 seconds
00:10:44.474786 AsyncCompletion: Task 0x0000000748ac80 completed after 75 seconds
00:10:44.474805 AsyncCompletion: Task 0x0000000748ac00 completed after 72 seconds
00:10:44.474823 AsyncCompletion: Task 0x0000000748abc0 completed after 72 seconds
00:10:44.474839 AsyncCompletion: Task 0x0000000748ab80 completed after 72 seconds
00:10:47.722549 AsyncCompletion: Task 0x0000000748a080 completed after 32 seconds
00:10:52.815016 AHCI#0: Port 1 reset
00:10:53.843612 AHCI#0P1: Cancelled task 31
00:11:04.659544 AHCI#0P1: Flush request was active for 105 seconds
00:11:04.659588 AHCI#0P1: Canceled flush returned rc=VINF_SUCCESS
00:11:09.099615 AHCI#0P1: Flush request was active for 31 seconds
00:11:09.099636 AHCI#0P1: Canceled flush returned rc=VINF_SUCCESS
00:11:13.747255 TM: Giving up catch-up attempt at a 70 199 920 112 ns lag; new total: 202 278 143 461 ns
00:12:21.854740 TM: Giving up catch-up attempt at a 62 019 680 657 ns lag; new total: 264 297 824 118 ns
00:13:53.795603 TM: Giving up catch-up attempt at a 82 448 511 717 ns lag; new total: 346 746 335 835 ns
00:14:02.821417 Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880075ba8210), OR(0x0), NOT(0xffffffff), flags(0x0)
00:15:13.622435 TM: Giving up catch-up attempt at a 60 248 392 765 ns lag; new total: 406 994 728 600 ns
00:17:15.886701 TM: Giving up catch-up attempt at a 63 541 065 510 ns lag; new total: 470 535 794 110 ns
00:18:21.007538 TM: Giving up catch-up attempt at a 60 201 751 193 ns lag; new total: 530 737 545 303 ns
00:23:45.460907 TM: Giving up catch-up attempt at a 76 191 453 073 ns lag; new total: 606 928 998 376 ns
00:25:00.048768 TM: Giving up catch-up attempt at a 70 580 305 621 ns lag; new total: 677 509 303 997 ns
00:26:11.899331 TM: Giving up catch-up attempt at a 69 110 477 067 ns lag; new total: 746 619 781 064 ns
00:27:23.814309 TM: Giving up catch-up attempt at a 67 294 616 906 ns lag; new total: 813 914 397 970 ns
00:48:32.750671 Changing the VM state from 'RUNNING' to 'SUSPENDING'.

VM Trace Log:

ts\\0\\boinc_68a2f909e7629377\\Logs"
hardwareuuid="63de0536-447a-4d1f-b2f6-52ad917d4a1d"
memory=2048
pagefusion="off"
vram=8
cpuexecutioncap=80
hpet="off"
chipset="piix3"
firmware="BIOS"
cpus=1
pae="on"
longmode="on"
synthcpu="off"
bootmenu="messageandmenu"
boot1="disk"
boot2="dvd"
boot3="none"
boot4="none"
acpi="on"
ioapic="on"
biossystemtimeoffset=0
rtcuseutc="off"
hwvirtex="on"
nestedpaging="on"
largepages="on"
vtxvpid="on"
vtxux="on"
VMState="poweroff"
VMStateChangeTime="2014-11-24T21:00:07.429000000"
monitorcount=1
accelerate3d="off"
accelerate2dvideo="off"
teleporterenabled="off"
teleporterport=0
teleporteraddress=""
teleporterpassword=""
tracing-enabled="off"
tracing-allow-vm-access="off"
tracing-config=""
autostart-enabled="off"
autostart-delay=0
defaultfrontend=""
storagecontrollername0="Hard Disk Controller"
storagecontrollertype0="IntelAhci"
storagecontrollerinstance0="0"
storagecontrollermaxportcount0="30"
storagecontrollerportcount0="3"
storagecontrollerbootable0="on"
"Hard Disk Controller-0-0"="C:\ProgramData\BOINC\slots\0\vm_isocontext.iso"
"Hard Disk Controller-ImageUUID-0-0"="4b23b328-9105-4df8-823d-e434e52cea39"
"Hard Disk Controller-tempeject"="off"
"Hard Disk Controller-IsEjected"="off"
"Hard Disk Controller-1-0"="C:\ProgramData\BOINC\slots\0\boinc_68a2f909e7629377\Snapshots\{a60aa216-dba6-4643-9654-79b209743d1b}.vdi"
"Hard Disk Controller-ImageUUID-1-0"="a60aa216-dba6-4643-9654-79b209743d1b"
"Hard Disk Controller-2-0"="C:\Program Files\Oracle\VirtualBox\VBoxGuestAdditions.iso"
"Hard Disk Controller-ImageUUID-2-0"="0c315952-5c90-4612-9ba7-aa5aa94c5e1e"
"Hard Disk Controller-tempeject"="off"
"Hard Disk Controller-IsEjected"="off"
natnet1="nat"
macaddress1="0800271E77FB"
cableconnected1="on"
nic1="nat"
nictype1="82540EM"
nicspeed1="0"
mtu="0"
sockSnd="64"
sockRcv="64"
tcpWndSnd="64"
tcpWndRcv="64"
Forwarding(0)="tcp_63950_80,tcp,127.0.0.1,63950,,80"
nic2="none"
nic3="none"
nic4="none"
nic5="none"
nic6="none"
nic7="none"
nic8="none"
hidpointing="ps2mouse"
hidkeyboard="ps2kbd"
uart1="off"
uart2="off"
lpt1="off"
lpt2="off"
audio="none"
clipboard="disabled"
draganddrop="disabled"
vrde="off"
usb="off"
ehci="off"
BandwidthGroup0=boinc_68a2f909e7629377_net,Network,1099511627776
SharedFolderNameMachineMapping1="shared"
SharedFolderPathMachineMapping1="C:\ProgramData\BOINC\slots\0/shared"
VRDEActiveConnection="off"
VRDEClients=0
vcpenabled="off"
vcpscreens=0
vcpfile="C:\ProgramData\BOINC\slots\0\boinc_68a2f909e7629377\boinc_68a2f909e7629377.webm"
vcpwidth=1024
vcpheight=768
vcprate=512
vcpfps=25
description="HjEMDmLuuClnDDn7oo6G73TpABFKDmABFKDmFYNKDmABFKDmlUxatm_1"
GuestMemoryBalloon=0
SnapshotName="boinc_1957"
SnapshotUUID="f5bda6be-2d49-443d-8aa6-ad2bf6793ff8"
CurrentSnapshotName="boinc_1957"
CurrentSnapshotUUID="f5bda6be-2d49-443d-8aa6-ad2bf6793ff8"
CurrentSnapshotNode="SnapshotName"

2014-11-25 08:05:07 (6248):
Command: VBoxManage -q snapshot "boinc_68a2f909e7629377" restorecurrent
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%
Restoring snapshot f5bda6be-2d49-443d-8aa6-ad2bf6793ff8

2014-11-25 08:07:34 (6248):
Command: VBoxManage -q startvm "boinc_68a2f909e7629377" --type headless
Exit Code: 0
Output:
Waiting for VM "boinc_68a2f909e7629377" to power on...
VM "boinc_68a2f909e7629377" has been successfully started.

2014-11-25 08:07:43 (6248):
Command: VBoxManage -q controlvm "boinc_68a2f909e7629377" pause
Exit Code: 0
Output:

2014-11-25 08:09:14 (6248):
Command: VBoxManage -q snapshot "boinc_68a2f909e7629377" take boinc_1957
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%

2014-11-25 08:09:25 (6248):
Command: VBoxManage -q controlvm "boinc_68a2f909e7629377" resume
Exit Code: 0
Output:

2014-11-25 08:09:31 (6248):
Command: VBoxManage -q snapshot "boinc_68a2f909e7629377" list
Exit Code: 0
Output:
Name: boinc_1957 (UUID: f5bda6be-2d49-443d-8aa6-ad2bf6793ff8)
Name: boinc_1957 (UUID: c06a6f9b-ee16-4ece-a671-59186efacf29) *

2014-11-25 08:09:36 (6248):
Command: VBoxManage -q snapshot "boinc_68a2f909e7629377" delete "f5bda6be-2d49-443d-8aa6-ad2bf6793ff8"
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%

2014-11-25 08:09:38 (6248):
Command: VBoxManage -q controlvm "boinc_68a2f909e7629377" cpuexecutioncap 80
Exit Code: 0
Output:

2014-11-25 08:53:50 (6248):
Command: VBoxManage -q controlvm "boinc_68a2f909e7629377" pause
Exit Code: -2147023170
Output:
VBoxManage.exe: error: Code RPC_S_CALL_FAILED 0x800706BE (0x800706BE) - RPC_S_CALL_FAILED 0x800706BE (extended info not available)
VBoxManage.exe: error: Context: "Pause()" at line 114 of file VBoxManageControlVM.cpp

2014-11-25 08:53:52 (6248):
Command: VBoxManage -q controlvm "boinc_68a2f909e7629377" pause
Exit Code: -2135228414
Output:
VBoxManage.exe: error: Invalid machine state: Aborted
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component Console, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Pause()" at line 114 of file VBoxManageControlVM.cpp

2014-11-25 08:53:54 (6248):
Command: VBoxManage -q controlvm "boinc_68a2f909e7629377" pause
Exit Code: -2135228414
Output:
VBoxManage.exe: error: Invalid machine state: Aborted
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component Console, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Pause()" at line 114 of file VBoxManageControlVM.cpp

2014-11-25 08:53:55 (6248):
Command: VBoxManage -q controlvm "boinc_68a2f909e7629377" pause
Exit Code: -2135228414
Output:
VBoxManage.exe: error: Invalid machine state: Aborted
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component Console, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Pause()" at line 114 of file VBoxManageControlVM.cpp

2014-11-25 08:53:56 (6248):
Command: VBoxManage -q controlvm "boinc_68a2f909e7629377" pause
Exit Code: -2135228414
Output:
VBoxManage.exe: error: Invalid machine state: Aborted
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component Console, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Pause()" at line 114 of file VBoxManageControlVM.cpp

2014-11-25 08:53:58 (6248):
Command: VBoxManage -q controlvm "boinc_68a2f909e7629377" pause
Exit Code: -2135228414
Output:
VBoxManage.exe: error: Invalid machine state: Aborted
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component Console, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Pause()" at line 114 of file VBoxManageControlVM.cpp

2014-11-25 08:53:59 (6248):
Command: VBoxManage -q snapshot "boinc_68a2f909e7629377" list
Exit Code: 0
Output:
Name: boinc_1957 (UUID: c06a6f9b-ee16-4ece-a671-59186efacf29) *

2014-11-25 08:54:05 (6248):
Command: VBoxManage -q snapshot "boinc_68a2f909e7629377" delete "c06a6f9b-ee16-4ece-a671-59186efacf29"
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%

2014-11-25 08:54:05 (6248):
Command: VBoxManage -q bandwidthctl "boinc_68a2f909e7629377" remove "boinc_68a2f909e7629377_net"
Exit Code: 0
Output:

2014-11-25 08:54:06 (6248):
Command: VBoxManage -q storagectl "boinc_68a2f909e7629377" --name "Hard Disk Controller" --remove
Exit Code: 0
Output:

2014-11-25 08:54:08 (6248):
Command: VBoxManage -q unregistervm "boinc_68a2f909e7629377" --delete
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%

2014-11-25 08:54:08 (6248):
Command: VBoxManage -q closemedium dvd "C:\ProgramData\BOINC\slots\0/vm_isocontext.iso" --delete
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%

2014-11-25 08:54:09 (6248):
Command: VBoxManage -q closemedium disk "C:\ProgramData\BOINC\slots\0/vm_cache.vdi" --delete
Exit Code: 0
Output:
0%...10%...20%...30%...40%...50%...60%...70%...80%...90%...100%

08:54:14 (6248): called boinc_finish(194)

</stderr_txt>
]]>

Jonathon
Send message
Joined: 2 Dec 14
Posts: 1
Credit: 0
RAC: 0
Message 1508 - Posted: 6 Dec 2014, 23:09:39 UTC

I have a task that went to 50% and then gave a status of, "Postponed: VM job unmanageable, restarting later." It has been in this mode for 19 hours while other tasks for other projects continue to work well (only project of 4 giving me wu's right now is rosetta@home).
Can anyone give me some insight as to what this means, and if there's something I need to do to correct this?

Questions and Answers : Windows : All tasks failing?