VM kommt nicht mehr hoch - Snapshotdatei wird nicht gefunden

[Maze13]

Hallo alle zusammen,

habe scheinbar ein größeres Problem: habe meine VM eben heruntergefahren und als als ich sie wieder hochfahren wollte, kam folgende Meldung:

VMware Server cannot find the virtual disk "VMAGLBO-000001.vmdk". Please verify the path is valid and try again.
Cannot open the disk 'VMAGLBO-000001.vmdk' or one of the snapshot disks it depends on.
Reason: The system cannot find the file specified.

Das Ulkige an der Sache ist: ich habe gar keinen Snapshot gemacht und wollte das auch tunlichst vermeiden, da wir neulich schonmal ein Problem damit hatten und wir eine zuvor gemachte Offline-Sicherung wieder einspielen mussten. Seit 1 Woche lief die VM nun wieder und nun das ...

Ich habe tatsächlich keine Datei in meinem Verzeichnis, die "VMAGLBO-000001.vmdk" heißt. Von daher ist die Fehlermeldung richtig!

Ich glaube irgendwie ist der Dienst "VMWare VSS Writer der Übeltäter. Ich habe vorhin eine ANDERE VM heruntergefahren und dann per NTBackup sichern wollen. NTBackup zeigte mir an: "Sicherung mit Schattenkopien wird vorbereitet.", hängte sich dann aber auf. Im Ereignislog fand sich ein Fehler der Quelle "vmwriter" (ID: 109) - interessanterweise zur gleichen Zeit wie diese Meldung aus dem Logfile der VM:

Jul 23 16:50:06.836: vmx| GuestRpcSendTimedOut: message to toolbox timed out.
Jul 23 16:50:13.851: vmx| DISKLIB-LIB : numIOs = 3650000 numMergedIOs = 0 numSplitIOs = 0
Jul 23 16:50:39.272: vmx| SnapshotVMX_TakeSnapshot start: 'VMware Server Undopoint', powerState=0, deviceState=0, logging=0, cb=1401481C0, cbData=5FA420
Jul 23 16:50:39.272: vmx| CPT current = 0, requesting 1
Jul 23 16:50:39.272: vcpu-0| Sync monModules(1).
Jul 23 16:50:39.272: vcpu-0| E1000: Syncing with mode 1.
Jul 23 16:50:39.272: vcpu-0| Done Sync monModules(1).
Jul 23 16:50:39.272: vmx| CPT: monitor ACKing mode 1
Jul 23 16:50:39.288: vmx| CPT current = 1, requesting 2
Jul 23 16:50:39.288: vmx| CPT: monitor ACKing mode 2
Jul 23 16:50:39.319: vmx| DUMPER: Creating checkpoint file E:\Virtual Machines\VMAGLBO\VMAGLBO-Snapshot9.vmsn
Jul 23 16:50:39.319: vmx| DISKLIB-LIB : CREATE CHILD: "E:\Virtual Machines\VMAGLBO\VMAGLBO-000001.vmdk" -- monolithicSparse grainSize=128
Jul 23 16:50:40.225: vmx| DISKLIB-LINK : "E:\Virtual Machines\VMAGLBO\VMAGLBO-000001.vmdk" : creation successful.
Jul 23 16:50:40.366: vmx| VMXVmdb_LoadRawConfig: Loading raw config
Jul 23 16:50:40.366: vmx| CPT current = 2, requesting 6
Jul 23 16:50:40.366: vcpu-0| Sync monModules(6).
Jul 23 16:50:40.366: vcpu-0| E1000: Syncing with mode 6.
Jul 23 16:50:40.366: vcpu-0| Done Sync monModules(6).
Jul 23 16:50:40.366: vmx| CPT: monitor ACKing mode 6
Jul 23 16:50:40.366: vmx| DISK: OPEN scsi0:0 'E:\Virtual Machines\VMAGLBO\VMAGLBO-000001.vmdk' persistent R[]
Jul 23 16:50:40.538: vmx| DISKLIB-DSCPTR: Opened [0]: "VMAGLBO-000001.vmdk" (0x2a)
Jul 23 16:50:40.538: vmx| DISKLIB-LINK : Opened 'E:\Virtual Machines\VMAGLBO\VMAGLBO-000001.vmdk' (0x2a): monolithicSparse, 209715200 sectors / 100 GB.
Jul 23 16:50:40.538: vmx| DISKLIB-DSCPTR: Opened [0]: "VMAGLBO-flat.vmdk" 0 (0x2e)
Jul 23 16:50:40.538: vmx| DISKLIB-LINK : Opened 'E:\Virtual Machines\VMAGLBO\VMAGLBO.vmdk' (0x2e): monolithicFlat, 209715200 sectors / 100 GB.
Jul 23 16:50:40.538: vmx| DISKLIB-LIB : Opened "E:\Virtual Machines\VMAGLBO\VMAGLBO-000001.vmdk" (flags 0x2a).
Jul 23 16:50:40.538: vmx| DiskGetGeometry: Reading of disk partition table
Jul 23 16:50:40.554: vmx| DISK: OPEN 'E:\Virtual Machines\VMAGLBO\VMAGLBO-000001.vmdk' Geo (13054/255/63) BIOS Geo (13054/255/63) freeSpace=16495 MB, lsilogic
Jul 23 16:50:40.554: vmx| SnapshotVMX done with snapshot 'VMware Server Undopoint': 9
Jul 23 16:50:52.022: vmx| SnapshotVMX_Consolidate: starting
Jul 23 16:50:52.038: vmx| CPT current = 0, requesting 1
Jul 23 16:50:52.038: vcpu-0| Sync monModules(1).
Jul 23 16:50:52.038: vcpu-0| E1000: Syncing with mode 1.
Jul 23 16:50:52.038: vcpu-0| Done Sync monModules(1).
Jul 23 16:50:52.038: vmx| CPT: monitor ACKing mode 1
Jul 23 16:50:52.225: vmx| CPT current = 1, requesting 2
Jul 23 16:50:52.225: vmx| CPT: monitor ACKing mode 2
Jul 23 16:50:52.225: vmx| SNAPSHOT: Consolidating from 'E:\Virtual Machines\VMAGLBO\VMAGLBO-000001.vmdk' to 'E:\Virtual Machines\VMAGLBO\VMAGLBO.vmdk'.
Jul 23 16:50:52.225: vmx| DISKLIB-DSCPTR: Opened [0]: "VMAGLBO-flat.vmdk" 0 (0x18)
Jul 23 16:50:52.225: vmx| DISKLIB-LINK : Opened 'E:\Virtual Machines\VMAGLBO\VMAGLBO.vmdk' (0x18): monolithicFlat, 209715200 sectors / 100 GB.
Jul 23 16:50:52.225: vmx| DISKLIB-LIB : Opened "E:\Virtual Machines\VMAGLBO\VMAGLBO.vmdk" (flags 0x18).
Jul 23 16:50:52.413: vmx| DISKLIB-DSCPTR: Opened [0]: "VMAGLBO-000001.vmdk" (0x18)
Jul 23 16:50:52.413: vmx| DISKLIB-LINK : Opened 'E:\Virtual Machines\VMAGLBO\VMAGLBO-000001.vmdk' (0x18): monolithicSparse, 209715200 sectors / 100 GB.
Jul 23 16:50:52.413: vmx| DISKLIB-LIB : Opened "E:\Virtual Machines\VMAGLBO\VMAGLBO-000001.vmdk" (flags 0x18).
Jul 23 16:50:52.413: vmx| DISKLIB-LIB : Upward Combine 2 links at offset 0 in chain 1E2A698.
Jul 23 16:50:52.772: vmx| VMXVmdb_LoadRawConfig: Loading raw config
Jul 23 16:50:52.788: vmx| CPT current = 2, requesting 6
Jul 23 16:50:52.788: vcpu-0| Sync monModules(6).
Jul 23 16:50:52.788: vcpu-0| E1000: Syncing with mode 6.
Jul 23 16:50:52.788: vcpu-0| Done Sync monModules(6).
Jul 23 16:50:52.788: vmx| CPT: monitor ACKing mode 6
Jul 23 16:50:52.788: vmx| DISK: OPEN scsi0:0 'E:\Virtual Machines\VMAGLBO\VMAGLBO.vmdk' persistent R[]
Jul 23 16:50:52.788: vmx| DISKLIB-DSCPTR: Opened [0]: "VMAGLBO-flat.vmdk" 0 (0x2a)
Jul 23 16:50:52.788: vmx| DISKLIB-LINK : Opened 'E:\Virtual Machines\VMAGLBO\VMAGLBO.vmdk' (0x2a): monolithicFlat, 209715200 sectors / 100 GB.
Jul 23 16:50:52.788: vmx| DISKLIB-LIB : Opened "E:\Virtual Machines\VMAGLBO\VMAGLBO.vmdk" (flags 0x2a).
Jul 23 16:50:52.788: vmx| DiskGetGeometry: Reading of disk partition table
Jul 23 16:50:52.803: vmx| DISK: OPEN 'E:\Virtual Machines\VMAGLBO\VMAGLBO.vmdk' Geo (13054/255/63) BIOS Geo (13054/255/63) freeSpace=16508 MB, lsilogic

Wie gesagt, ich habe per Hand keinen Snapshot gemacht und NTBackup habe ich auf eine ganz andere VM losgelassen ...

Ich hoffe, dass mir jemand erklären kann was eigentlich passiert ist und wie ich die Maschine evtl. noch retten kann. Jetzt schon mal vielen Dank im voraus für Eure Hilfe!!!

Viele Grüße, Maze

PS: Bitte sagt mir was ihr sonst noch an Infos braucht! Ist eine komplette Windows-Umgebung (Windows Server 2003 R2 x64 - sowohl Host als auch Guest). Die Verzeichnisstruktur sieht so aus:

23.07.2009 17:23 107.374.182.400 VMAGLBO-flat.vmdk
23.07.2009 17:23 8.684 VMAGLBO.nvram
23.07.2009 16:50 438 VMAGLBO.vmdk
23.07.2009 16:50 526 VMAGLBO.vmsd
23.07.2009 16:50 2.551 VMAGLBO.vmx
18.05.2009 12:10 262 VMAGLBO.vmxf
23.07.2009 17:23 35.064 vmware-0.log
23.07.2009 17:23 35.063 vmware-1.log
23.07.2009 17:23 121.767 vmware-2.log
23.07.2009 17:25 35.063 vmware.log

[continuum]

Hi
geh mal auf meine Seite http://sanbarrow.com/sickbay.html

da beschreibe ich welche Infos ich fuer solche faelle brauche - bitte pack alles zusammen in ein zip-archive und lade es bei ifile.it hoch - ich guck dann mal

[Maze13]

Hallo continuum,

vielen Dank, dass Du Dich meines Problems annimmst!!

hier der Link: http://ifile.it/80l12ce

Kennwort folgt per PN.

Gruß, Maze

[continuum]

Hi
komisch, komisch

kann ich nicht nach vollziehen - stell die normale Platte ein und probiers vorsichtig zu starten. Falls ein checkdisk gefragt wird - brech ab. Aber sonst ... ist da Sabotage am Werk ?

[Maze13]

Hi,

stell die normale Platte ein und probiers vorsichtig zu starten

Du meinst, einfach in der .VMX diesen Abschnitt ersetzen:

scsi0:0.fileName = "VMAGLBO-000001.vmdk"

auf: VMAGLBO-flat.vmdk

Den Hinweis mit der Sabotage finde ich gar nicht so abwegig ...

[continuum]

nein - nicht die flat - das ist keine virtuelle disk - du musst die VMAGLBO.vmdk einstellen

[Maze13]

Wow, die VM ist tatsächlich wieder oben. Folgenden Eintrag finde ich in den Ereignislog um 16:50 Uhr den VMWare Snapshot Provider betreffend:

Das Zeitlimit für den Lösch- und Speicherschreibvorgang für Volume "C:" wurde beim Warten auf eine Schreibvorgangfreigabe überschritten.

Die Frage bleibt: wieso wurde da ein Snapshot versucht bzw. und warum ist er überhaupt fehlgeschlagen? Wie kann ich so etwas zukünftig verhindern?

Aber erstmal vielen Dank!

Die Idee mit dem Umstellen in der .VMX kam mir als erstes, aber ich habe mich echt nicht getraut, die Maschine nochmal zu starten ...

Gruß, Maze

[Dayworker]

Das an der Stelle mit dem nicht trauen, erspart dir bei "Vmware" jede Menge Ärger wie ein langwieriges Restore oder sogar die Re-Inst. VMware selber kann oder will nur bei 50% der Fälle helfen, unser Ulli ist da mit über 90% wesentlich ausgeschlafener.

[Maze13]

Wow, das ist mal ne gute Quote Großer Respekt an Ulli!!

@continuum: Ich hatte Dir noch mal ne PN geschrieben wegen einer anderen VM, die anscheinend ebenfalls Schaden durch einen Backupversuch mit VSS genommen hat. WIr haben es nur erst jetzt nach dem Versuch eines erneuten Hochfahrens bemerkt. Diese Maschine ist nicht immens kritisch, aber es würde uns trotzdem eine Menge Arbeit ersparen, wenn wir sie wieder zum Laufen bekämen.

Ich kann natürlich nicht erwarten, dass Du mir auch bei dieser VM nochmal hilfst, aber falls Du doch die Zeit finden solltest, sag mir bitte Bescheid, ob Dir noch Infos dazu fehlen. Ich hatte jetzt "nur" wieder die Infos bereitgestellt, die Du auf Deiner Website anforderst und hier im Forum nichts mehr weiter dazu gesagt.

Ansonsten werde ich einfach nochmal die Tipps auf Deiner Site durchgehen und selbst versuchen die VM wieder zu reparieren.

Viele Grüße, Maze

------------------------------------------------

Ergänzung: die VM ist wieder oben (CID-Ketten-Reparatur war dieses Mal erfolgreich). Was mich ein wenig nervös gemacht, war die Anmerkung von contiunuum etwas weiter oben in diesem thread, dass ich chckdsk abbrechen soll, wenn es startet. Ich hab's mal durchlaufen lassen (im 2.Versuch) und danach sieht alles okay aus mit 1 Ausnahme. Der SSIS des MS SQL Servers läuft beim Starten auf ein Timeout. Den Fehler muss ich mir nochmal näher anschauen ...

Gruß, Maze