Opened 11 years ago
Closed 11 years ago
#12160 closed defect (duplicate)
v4.2.18 Snapshot cannot be resumed - aRC=VBOX_E_OBJECT_NOT_FOUND - No storage device attached
Reported by: | Jacob Klein | Owned by: | |
---|---|---|---|
Component: | other | Version: | VirtualBox 4.2.18 |
Keywords: | snapshot VBOX_E_OBJECT_NOT_FOUND storage device attached | Cc: | Jacob_W_Klein@… |
Guest type: | other | Host type: | other |
Description
Hello,
I use VirtualBox to do work on certain BOINC projects on my Windows 8.1 x64 host machine, but the 4.2.18 release appears to have a big problem, where it cannot resume a snapshot. I've heard of a couple projects that are reporting this same issue. This issue was not present in 4.2.16.
The main project I use is Test4Theory, and the administrators have created a news post advising their users to not use 4.2.18. I replied in that post, with details of the error I saw, based on my log file found in C:\Users\Jacob\.VirtualBox\VBoxSVC.log
The link to the news post is: http://lhcathome2.cern.ch/test4theory/forum_thread.php?id=1339
The link to the post (within that thread) where I report my findings is: http://lhcathome2.cern.ch/test4theory/forum_thread.php?id=1339&postid=15334#15334
I'll include the findings within this Bug Description (pasted below).
Could you please find out what causes this problem, and also, how to fix it?
Thanks, Jacob Klein
I too just realized I have the issue you are describing, with VirtualBox 4.2.18, where attempting to restore the snapshot will make the task crash.
Here is a snippet of my file: C:\Users\Jacob\.VirtualBox\VBoxSVC.log
... which shows that it gets in a loop trying to find the devices attached to the 'Floppy Controller' and 'Hard Disk Controller'
... and continually says: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001)
... and then after about 5 minutes, gets: aRC=VBOX_E_INVALID_VM_STATE (0x80bb0002) aRC=VBOX_E_INVALID_VM_STATE (0x80bb0002) aRC=E_ACCESSDENIED (0x80070005)
... which results in a Computation Error.
Did anybody notify Oracle??
LOG FILE SNIPPET
VirtualBox COM Server 4.2.18 r88780 win.amd64 (Sep 6 2013 14:21:01) release log 00:00:00.000000 main Log opened 2013-10-04T03:19:02.147990100Z 00:00:00.000000 main OS Product: Windows 8 00:00:00.000000 main OS Release: 6.2.9200 00:00:00.000000 main OS Service Pack: 00:00:00.031000 main DMI Product Name: XPS 730X 00:00:00.031000 main DMI Product Version: 00 00:00:00.031000 main Host RAM: 12278MB total, 9126MB available 00:00:00.031000 main Executable: C:\Program Files\Oracle\VirtualBox\VBoxSVC.exe 00:00:00.031000 main Process ID: 2436 00:00:00.031000 main Package type: WINDOWS_64BITS_GENERIC 00:00:00.031000 Loading settings file "C:\Users\Jacob/.VirtualBox\VirtualBox.xml" with version "1.12-windows" 00:00:00.813000 usbLibDevStrDrEntryGet: DeviceIoControl 1 fail winEr (31) 00:00:01.859000 VDInit finished 00:00:01.875000 Loading settings file "C:\ProgramData\BOINC\slots\0\boinc_wu_1378991704_20531_0\boinc_wu_1378991704_20531_0.vbox" with version "1.12-windows" 00:00:02.234000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 0 of controller 'Hard Disk Controller'}, preserve=false 00:00:02.234000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 0 of controller 'Hard Disk Controller'}, preserve=false 00:00:02.234000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 1 on port 0 of controller 'Hard Disk Controller'}, preserve=false 00:00:02.234000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 1 on port 0 of controller 'Hard Disk Controller'}, preserve=false 00:00:02.234000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 1 of controller 'Hard Disk Controller'}, preserve=false 00:00:02.234000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 1 of controller 'Hard Disk Controller'}, preserve=false 00:00:02.234000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 1 on port 1 of controller 'Hard Disk Controller'}, preserve=false 00:00:02.234000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 1 on port 1 of controller 'Hard Disk Controller'}, preserve=false 00:00:02.234000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 0 of controller 'Floppy Controller'}, preserve=false 00:00:02.234000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 0 of controller 'Floppy Controller'}, preserve=false 00:00:02.234000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 1 on port 0 of controller 'Floppy Controller'}, preserve=false 00:00:02.250000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 1 on port 0 of controller 'Floppy Controller'}, preserve=false 00:00:02.484000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 0 of controller 'Hard Disk Controller'}, preserve=false 00:00:02.484000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 0 of controller 'Hard Disk Controller'}, preserve=false 00:00:02.484000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 1 on port 0 of controller 'Hard Disk Controller'}, preserve=false 00:00:02.484000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 1 on port 0 of controller 'Hard Disk Controller'}, preserve=false 00:00:02.484000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 1 of controller 'Hard Disk Controller'}, preserve=false 00:00:02.484000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 1 of controller 'Hard Disk Controller'}, preserve=false 00:00:02.484000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 1 on port 1 of controller 'Hard Disk Controller'}, preserve=false 00:00:02.484000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 1 on port 1 of controller 'Hard Disk Controller'}, preserve=false 00:00:02.484000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 0 of controller 'Floppy Controller'}, preserve=false 00:00:02.484000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 0 of controller 'Floppy Controller'}, preserve=false 00:00:02.484000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 1 on port 0 of controller 'Floppy Controller'}, preserve=false 00:00:02.484000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 1 on port 0 of controller 'Floppy Controller'}, preserve=false ... ... [Repeatedly tries to find those devices, over and over and over again] ... ... 00:05:04.609000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 0 of controller 'Floppy Controller'}, preserve=false 00:05:04.609000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 0 on port 0 of controller 'Floppy Controller'}, preserve=false 00:05:04.609000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 1 on port 0 of controller 'Floppy Controller'}, preserve=false 00:05:04.609000 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={No storage device attached to device slot 1 on port 0 of controller 'Floppy Controller'}, preserve=false 00:05:09.781000 ERROR [COM]: aRC=VBOX_E_INVALID_VM_STATE (0x80bb0002) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={The machine is not mutable (state is Saved)}, preserve=false 00:05:10.016000 ERROR [COM]: aRC=VBOX_E_INVALID_VM_STATE (0x80bb0002) aIID={22781af3-1c96-4126-9edf-67a020e0e858} aComponent={SessionMachine} aText={The machine is not mutable (state is Saved)}, preserve=false 00:05:15.766000 Watcher ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={3b2f08eb-b810-4715-bee0-bb06b9880ad2} aComponent={VirtualBox} aText={The object is not ready}, preserve=false
Attachments (1)
Change History (8)
comment:1 by , 11 years ago
comment:2 by , 11 years ago
Note:
I'm not sure if the messages that say "aRC=VBOX_E_OBJECT_NOT_FOUND" and "No storage device attached" are relevant or not.
But, after 5 minutes of trying, the snapshot is still not resumed.
comment:3 by , 11 years ago
One of the Test4Theory moderators replied by saying:
As far as I investigated, the problem is caused during the phase of creating the snapshot. Both device controllers (disk and floppy) are saved in the snapshot, but not the related devices, hence restoring from the snapshot VirtualBox can't find the first device needed: harddisk. The wrapper keeps on trying until 5 minutes are over.
http://lhcathome2.cern.ch/test4theory/forum_thread.php?id=1339&postid=15343
by , 11 years ago
comment:4 by , 11 years ago
Similar problem here: I got an error _deleting_ a snapshot of a running machine:
Progress state: NS_ERROR_FAILURE VBoxManage: error: Snapshot operation failed VBoxManage: error: Medium '/Users/ks/Library/VirtualBox/Machines/Repo-Test/Snapshots/{069321fd-6afd-4634-9 273-90733f1a69c7}.vdi' is not locked for writing VBoxManage: error: Details: code VBOX_E_INVALID_OBJECT_STATE (0x80bb0007), component Medium, interface IMedium VBoxManage: error: Context: "int handleSnapshot(HandlerArg*)" at line 431 of file VBoxManageSnapshot.cpp
Next day, there's an error _taking_ a new snapshot:
Progress state: NS_ERROR_FAILURE VBoxManage: error: Failed to take snapshot VBoxManage: error: Assertion failed: [m->state == MediumState::LockedRead] at '/Users/vbox/tinderbox/4.2-mac-rel/src/VBox/Main/src-server/MediumImpl.cpp' (4069) in nsresult Medium::createDiffStorage(ComObjPtr<Medium>&, PRUint32, MediumLockList*, ComObjPtr<Progress>*, bool). VBoxManage: error: Please contact the product vendor! VBoxManage: error: Details: code NS_ERROR_FAILURE (0x80004005), component Medium, interface IMedium VBoxManage: error: Context: "int handleSnapshot(HandlerArg*)" at line 364 of file VBoxManageSnapshot.cpp
Problem is: All disks of the snapshoted VM are gone (see attached sceenshot, VBox.png) and have to be reattached. This is a real no-go and I'll have to revert to 4.2.16 for the time beeing...
comment:5 by , 11 years ago
The errors I'm seeing in my Test4Theory logs read: "The target VM is missing a primary master device. Please make sure the source and target VMs have compatible storage configurations"
00:00:01.741744 Changing the VM state from 'DESTROYING' to 'TERMINATED'. 00:00:01.784034 ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={db7ab4ca-2a3f-4183-9243-c1208da92392} aComponent={Console} aText={piix3ide#0: The target VM is missing a primary master device. Please make sure the source and target VMs have compatible storage configurations [ver=20 pass=final] (VERR_SSM_LOAD_CONFIG_MISMATCH)}, preserve=false 00:00:01.799011 Power up failed (vrc=VERR_SSM_LOAD_CONFIG_MISMATCH, rc=E_FAIL (0X80004005)) 00:00:01.972224 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={b83ee395-8679-40ca-8d60-1a0cbe724930} aComponent={Display} aText={The console is not powered up}, preserve=false 00:00:02.016995 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={db7ab4ca-2a3f-4183-9243-c1208da92392} aComponent={Console} aText={The virtual machine is not powered up}, preserve=false
comment:6 by , 11 years ago
Here's a better text block that shows the error (where a primary master device was not saved to the snapshot):
00:00:00.360768 Changing the VM state from 'CREATING' to 'CREATED'. 00:00:00.360827 Changing the VM state from 'CREATED' to 'LOADING'. 00:00:00.361210 SSM: File header: Format 2.0, VirtualBox Version 4.2.18 r88780, 64-bit host, cbGCPhys=8, cbGCPtr=8 00:00:00.361668 SSM: Saved state info: 00:00:00.361678 SSM: Build Type: release 00:00:00.361683 SSM: Host OS: win.amd64 00:00:00.361834 PCI: PIIX3/0: register 60: 0b -> 80 00:00:00.361842 PCI: PIIX3/0: register 61: 09 -> 8b 00:00:00.361847 PCI: PIIX3/0: register 62: 0b -> 8a 00:00:00.361851 PCI: PIIX3/0: register 63: 09 -> 80 00:00:00.361855 PCI: PIIX3/0: register ad: 00 -> ef 00:00:00.361859 PCI: PIIX3/0: register de: 00 -> be 00:00:00.361883 PCI: piix3ide/0: register 40: 00 -> 07 00:00:00.361888 PCI: piix3ide/0: register 41: 80 -> a3 00:00:00.361892 PCI: piix3ide/0: register 48: 00 -> 01 00:00:00.361895 PCI: piix3ide/0: register 4a: 00 -> 02 00:00:00.361900 PCI: vga/0: 8-bit field INTERRUPT_LINE: 9 -> b 00:00:00.361905 PCI: vga/0: 16-bit field COMMAND: 3 -> 7 00:00:00.362100 PCI: e1000/0: 8-bit field LATENCY_TIMER: 0 -> 40 - !READ ONLY! 00:00:00.362108 PCI: e1000/0: 8-bit field INTERRUPT_LINE: b -> a 00:00:00.362113 PCI: e1000/0: 16-bit field COMMAND: 3 -> 7 00:00:00.362142 PCI: VMMDev/0: 16-bit field COMMAND: 3 -> 7 00:00:00.362174 PCI: acpi/0: 16-bit field COMMAND: 1 -> 7 00:00:01.378913 TM: cTSCTicksPerSecond=0xbab66a88 (3 132 517 000) fTSCVirtualized=true fTSCUseRealTSC=false (state load) 00:00:01.514593 PIT: mode=2 count=0x2e9c (11932) - 99.99 Hz (ch=0) (restore) 00:00:01.514627 RTC: stopped the periodic timer (restore) 00:00:01.514655 HGCM: restoring [VBoxSharedFolders] 00:00:01.514666 HGCM: restoring [VBoxGuestControlSvc] 00:00:01.514671 HGCM: restoring [VBoxGuestPropSvc] 00:00:01.514675 HGCM: restoring [VBoxDragAndDropSvc] 00:00:01.514679 HGCM: restoring [VBoxSharedClipboard] !!! !!! !!! 00:00:01.514810 VMSetError: D:\tinderbox\win-4.2\src\VBox\Devices\Storage\DevATA.cpp(7004) int __cdecl ataLoadExec(struct PDMDEVINS *,struct SSMHANDLE *,unsigned int,unsigned int); rc=VERR_SSM_LOAD_CONFIG_MISMATCH 00:00:01.519787 VMSetError: piix3ide#0: The target VM is missing a primary master device. Please make sure the source and target VMs have compatible storage configurations [ver=20 pass=final] 00:00:01.519787 SSM: LoadExec failed for 'piix3ide' instance #0 (version 20, pass 0xffffffff): VERR_SSM_LOAD_CONFIG_MISMATCH !!! !!! !!! 00:00:01.519787 NAT: set redirect TCP host port 61242 => guest port 80 @ 10.0.2.15 00:00:01.520517 Changing the VM state from 'LOADING' to 'LOAD_FAILURE'. 00:00:01.520552 Changing the VM state from 'LOAD_FAILURE' to 'POWERING_OFF'. ... ... ... 00:00:01.520715 Changing the VM state from 'POWERING_OFF' to 'OFF'. 00:00:01.520827 Console::powerDown(): A request to power off the VM has been issued (mMachineState=Restoring, InUninit=0) 00:00:01.521325 Changing the VM state from 'OFF' to 'DESTROYING'. ... ... ... 00:00:01.527302 Changing the VM state from 'DESTROYING' to 'TERMINATED'. 00:00:01.585921 ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={db7ab4ca-2a3f-4183-9243-c1208da92392} aComponent={Console} aText={piix3ide#0: The target VM is missing a primary master device. Please make sure the source and target VMs have compatible storage configurations [ver=20 pass=final] (VERR_SSM_LOAD_CONFIG_MISMATCH)}, preserve=false 00:00:01.586083 Power up failed (vrc=VERR_SSM_LOAD_CONFIG_MISMATCH, rc=E_FAIL (0X80004005))
Here is the code block segment, again, this time placed inside a code block tag:
LOG FILE SNIPPET