NVP-vProxy: Troubleshooting von VMware-Snapshot-Problemen während NetWorker-Backups.

• VMware-VMs werden über die vProxy-Appliance von NetWorker VMware Protection (NVP) geschützt.
• Backups können keinen VMware-Snapshot erstellen. Es gibt einige Variationen von Snapshot-Fehlern, die auftreten können. Fast alle können durch Befolgen dieses Wissensdatenbank-Artikels behoben werden.
• VM-Backups schlagen aufgrund von übrig gebliebenen Snapshots fehl, die in der VMware-Umgebung nicht bereinigt wurden.

Protokolle:

NetWorker-Server:

Das Backupaktionsprotokoll enthält keine umfassenden Details zu den VM-Backupfehlern und meldet nur, dass die Aktion fehlgeschlagen ist. Es wird angegeben, welche VM-Backups fehlgeschlagen sind. Überprüfen Sie die Policy-Protokolle auf dem NetWorker-Server. Es wird ein Sitzungsprotokoll mit dem VM-Namen angezeigt:
Linux: /nsr/logs/policy/POLICY-NAME/WORKFLOW-NAME/####-VM-NAME-DATUM
.logWindows: C:Program FilesEMC NetWorker srpolicyPOLICY-NAME WORKFLOW-NAME####-VM-NAME-DATUM.log
 

vProxy-Appliance:

Greifen Sie über SSH auf die vProxy-Appliance zu, die das VM-Backup mit dem Administratorkonto durchgeführt hat.

Nachdem das VM-Backup abgeschlossen wurde oder fehlgeschlagen ist, wird das Sitzungsprotokoll an den folgenden Speicherort verschoben:

YYYY-MM-DDTHH:mm:SS INFO:   [@(#) Build number: 39] Creating snapshot of virtual machine ...
YYYY-MM-DDTHH:mm:SS INFO:   [@(#) Build number: 39] Create snapshot request succeeded.
YYYY-MM-DDTHH:mm:SS INFO:   [@(#) Build number: 39] Found snapshot "snapshot-4301".
YYYY-MM-DDTHH:mm:SS INFO:   [@(#) Build number: 39] Set custom attribute 'Dell EMC vProxy Cleanup Descriptor' value for object vm-1011 to '{"DataMover":"","SessionType":"BackupVmSessions","SessionUuid":"e282db86-a1b3-4599-ba97-89b5625df0e0","SessionStartTime":"2024-02-29T03:59:59Z","VmMORef":"vm-1011","VmName":"win-client02","VProxyMORef":"vm-4214","SnapshotMORef":"snapshot-4301","VmdkBaseFileNames":["","[esx02-datastore] win-client02/win-client02.vmdk"]}'.
YYYY-MM-DDTHH:mm:SS INFO:   [@(#) Build number: 39] Remove snapshot request accepted by Snapshot Manager.
YYYY-MM-DDTHH:mm:SS INFO:   [@(#) Build number: 39] Sent snapshot removal.

YYYY-MM-DDTHH:mm:SS NOTICE: Created snapshot 'NetWorker Backup Snapshot' (snapshot-4301) on 'vcsa.amer.lan' for virtual machine 'win-client02' (vm-1011) in 1.125928806s.
YYYY-MM-DDTHH:mm:SS NOTICE: Removed snapshot 'snapshot-4301' from VM 'win-client02' on vCenter 'vcsa.amer.lan' in 2.266872212s.
YYYY-MM-DDTHH:mm:SS INFO:   Found virtual machine 'win-client02' with MORef 'vm-1011'.
YYYY-MM-DDTHH:mm:SS NOTICE: Consolidated disks of VM 'snapshot-4301 (win-client02)' on vCenter 'vcsa.amer.lan' in 6.983942ms.
YYYY-MM-DDTHH:mm:SS NOTICE: Snapshot 'snapshot-4301' of virtual machine 'win-client02' (vm-1011) was removed.

VMware:

Die vmware.log Datei der VM enthält Meldungen zur Verarbeitung der Anforderungen zum Erstellen, Löschen und Konsolidieren von VM-Snapshots durch VMware. Auf dieses Protokoll kann über die vSphere-Webnutzeroberfläche zugegriffen werden. Die Protokolldatei ist im VM-Ordner auf dem Datenspeicher enthalten:

Ein Beispiel für die Art der Protokollierung, die während NetWorker-VM-Backups angezeigt wird:

YYYY-MM-DDTHH:mm:SS In(05) vmx - VigorTransportProcessClientPayload: opID=6c61ca63-a-ab35 seq=1177113: Receiving Snapshot.Take request.
YYYY-MM-DDTHH:mm:SS In(05) vmx 6c61ca63-a-ab35 SnapshotVMX_TakeSnapshot start: 'NetWorker Backup Snapshot', deviceState=0, lazy=0, quiesced=0, forceNative=0, tryNative=1, saveAllocMaps=0
YYYY-MM-DDTHH:mm:SS In(05) vmx 6c61ca63-a-ab35 VVolObjNotifySnapshotPrepare: Changing state to VEC_SNAP_PREPARE_IN_PROG.
YYYY-MM-DDTHH:mm:SS In(05) vmx 6c61ca63-a-ab35 SNAPSHOT: SnapshotPrepareTakeDoneCB: Prepare phase complete (The operation completed successfully).
YYYY-MM-DDTHH:mm:SS In(05) vcpu-0 6c61ca63-a-ab35 SnapshotVMXTakeSnapshotWork: Transition to mode 0.
YYYY-MM-DDTHH:mm:SS In(05) vcpu-0 6c61ca63-a-ab35 SNAPSHOT: SnapshotConfigInfoOpenVmsd: Creating new snapshot dictionary, '/vmfs/volumes/64a73f34-81454bf4-704e-00505606578d/win-client02/win-client02.vmsd.usd'.
YYYY-MM-DDTHH:mm:SS In(05) vcpu-0 6c61ca63-a-ab35 SNAPSHOT: SnapshotDumperOpenFromInfo: Creating checkpoint file /vmfs/volumes/64a73f34-81454bf4-704e-00505606578d/win-client02/win-client02-Snapshot31.vmsn
YYYY-MM-DDTHH:mm:SS In(05) vcpu-0 6c61ca63-a-ab35 VVolObjNotifySnapshot: isEnabled: 1, state: 1 
YYYY-MM-DDTHH:mm:SS In(05) vcpu-0 6c61ca63-a-ab35 VVolObjNotifySnapshot: State changed to VEC_SNAP_SNAPSHOT_IN_PROG.
YYYY-MM-DDTHH:mm:SS In(05) vcpu-0 6c61ca63-a-ab35 SnapshotVMXTakeSnapshotWork: Transition to mode 1.
YYYY-MM-DDTHH:mm:SS In(05) vcpu-0 6c61ca63-a-ab35 SnapshotVMXTakeSnapshotComplete: Done with snapshot 'NetWorker Backup Snapshot': 31
YYYY-MM-DDTHH:mm:SS In(05) vcpu-0 6c61ca63-a-ab35 VVolObjNotifySnapshotDone: isEnabled: 1
YYYY-MM-DDTHH:mm:SS In(05) vcpu-0 6c61ca63-a-ab35 VigorTransport_ServerSendResponse opID=6c61ca63-a-ab35 seq=1177113: Completed Snapshot request.
YYYY-MM-DDTHH:mm:SS In(05) vmx - VigorTransportProcessClientPayload: opID=2d4be388-e6-ad11 seq=1177390: Receiving Snapshot.Delete request.
YYYY-MM-DDTHH:mm:SS In(05) vmx 2d4be388-e6-ad11 SNAPSHOT: SnapshotDeleteWork '/vmfs/volumes/64a73f34-81454bf4-704e-00505606578d/win-client02/win-client02.vmx' : 31
YYYY-MM-DDTHH:mm:SS In(05) vmx 2d4be388-e6-ad11 VigorTransport_ServerSendResponse opID=2d4be388-e6-ad11 seq=1177390: Completed Snapshot request.
YYYY-MM-DDTHH:mm:SS In(05) vmx - VigorTransportProcessClientPayload: opID=2d4be388-e6-ad11 seq=1177391: Receiving Snapshot.Consolidate request.
YYYY-MM-DDTHH:mm:SS In(05) vmx 2d4be388-e6-ad11 SnapshotVMX_Consolidate: Starting online snapshot consolidate operation.
YYYY-MM-DDTHH:mm:SS In(05) vmx 2d4be388-e6-ad11 Turning on snapshot info cache. VM=win-client02.vmx.
YYYY-MM-DDTHH:mm:SS In(05) vmx 2d4be388-e6-ad11 SNAPSHOT: Turning on snapshot disk cache.
YYYY-MM-DDTHH:mm:SS In(05) vmx 2d4be388-e6-ad11 SnapshotESXCombineProgressTotal: Snapshot consolidation progress: 0
YYYY-MM-DDTHH:mm:SS In(05) vmx - SnapshotESXCombineProgressTotal: Snapshot consolidation progress: 0
YYYY-MM-DDTHH:mm:SS In(05) vmx - SnapshotESXCombineProgressTotal: Snapshot consolidation progress: 0
YYYY-MM-DDTHH:mm:SS In(05) vmx - SnapshotESXCombineProgressTotal: Snapshot consolidation progress: 7
YYYY-MM-DDTHH:mm:SS In(05) vmx - SnapshotESXCombineProgressTotal: Snapshot consolidation progress: 7
YYYY-MM-DDTHH:mm:SS In(05) vmx - SnapshotESXCombineProgressTotal: Snapshot consolidation progress: 9
YYYY-MM-DDTHH:mm:SS In(05) vmx - SnapshotESXCombineProgressTotal: Snapshot consolidation progress: 10
YYYY-MM-DDTHH:mm:SS In(05) vmx - SnapshotESXCombineProgressTotal: Snapshot consolidation progress: 10
YYYY-MM-DDTHH:mm:SS In(05) vcpu-0 - VigorTransport_ServerSendResponse opID=2d4be388-e6-ad11 seq=1177391: Completed Snapshot request.
YYYY-MM-DDTHH:mm:SS In(05) vcpu-0 - Turning off snapshot info cache.
YYYY-MM-DDTHH:mm:SS In(05) vcpu-0 - Turning off snapshot disk cache.
YYYY-MM-DDTHH:mm:SS In(05) vcpu-0 - ConsolidateEnd: Snapshot consolidate complete: The operation completed successfully (0).

Hier sind einige erste Schritte zur Fehlerbehebung:

Protokolle:
Überprüfen Sie die im obigen Feld "Symptome " genannten Protokolle auf Anzeichen dafür, warum der Snapshot- oder Konsolidierungsvorgang fehlgeschlagen ist.

Manuelles Testen von Snapshots:
Wenn VMware-Snapshots während eines VM-Backupvorgangs fehlschlagen, sollte die VMware-Snapshot-Funktion auch über den vSphere-Webclient überprüft werden. 
Wenn es sich bei dem VM-Backup um ein standardmäßiges Image-basiertes Backup handelt, ist ein stillgelegter Snapshot nicht erforderlich. Wenn es sich bei der VM um eine SQL-VM handelt, die anwendungskonsistente MSVMAPPAGENT-Backups durchführt, muss ein stillgelegter Snapshot ausgeführt werden.

Wenn der Snapshot erfolgreich erstellt wurde, konsolidieren Sie die VM und löschen Sie den VM-Snapshot aus VMware vSphere. Wenn derselbe Snapshot-Fehler bei Snapshots beobachtet wird, die außerhalb von NetWorker durchgeführt wurden, wenden Sie sich an den VMware-Support, um die Ursache für das Snapshot-Problem zu ermitteln. Selbst wenn die manuellen Snapshots abgeschlossen sind, können andere Faktoren dazu beitragen. Zum Beispiel: VMware vCenter- und Datastore-/Storage-I/O zum Zeitpunkt der Erstellung des Snapshots im Vergleich zum Zeitpunkt der Durchführung des Backups. 

Eine Übersicht über VM-Snapshots finden Sie unter VMware KB: https://kb.vmware.com/s/article/1015180

Snapshot Error Specific KBs:

• NVP-vProxy: Das VM-Backup schlägt mit der Meldung "Snapshot wurde bereits gelöscht oder nicht vollständig erstellt" fehl.
• NVP-vProxy: VM-Backup schlägt fehl mit "Fehler beim Abrufen des Status der Aufgabe zur Erstellung eines Snapshots: Zeitüberschreitung beim Vorgang"
• NVP-vProxy: Virtuelle Maschine kann nicht gesichert werden mit der Meldung "Snapshot der virtuellen Maschine kann nicht erstellt werden: Snapshot konnte nicht erstellt werden."
• NetWorker vProxy-Backup schlägt fehl mit der Meldung "Cannot to create nonquiesced snapshot after a retry attempt: Es wurde eine ungültige Snapshot-Konfiguration erkannt.
• NVP-vProxy: Die VM verliert die Verbindung während des Snapshot-Entfernungsvorgangs.
• NVP-vProxy – Triage-Handbuch: NetWorker VMware Protection vProxy – Triage- und Protokollerfassungshandbuch

VMware:

• Durch das Entfernen von Snapshots wird eine virtuelle Maschine für eine lange Zeit angehalten (1002836).  
• Schätzen der Zeit, die für die Konsolidierung von Snapshots virtueller Maschinen erforderlich ist (2053758)