NVP vProxy: Risoluzione dei problemi relativi alle istantanee VMware durante i backup di NetWorker.

• Le VM VMware sono protette tramite l'appliance vProxy NetWorker VMware Protection (NVP).
• I backup non riescono a creare un'istantanea VMware. Possono essere visualizzate alcune varianti degli errori delle istantanee. quasi tutti possono essere risolti seguendo questo KB.
• I backup delle VM hanno esito negativo a causa di snapshot residue che non sono state pulite nell'ambiente VMware.

Registri

Server NetWorker:

Il log delle azioni di backup non fornisce dettagli completi sugli errori di backup delle VM e segnalerà solo che l'azione non è riuscita. Indicherà quali backup delle VM non sono riusciti. Controllare i log delle policy sul server NetWorker; sarà presente un log della sessione contenente il nome VM:
Linux: /nsr/logs/policy/POLICY-NAME/WORKFLOW-NAME/####-VM-NAME-DATE
.logWindows: C:Program FilesEMC NetWorker srpolicyPOLICY-NAMEWORKFLOW-NAME####-VM-NAME-DATE.log
 

Appliance vProxy:

Accedere all'appliance vProxy che ha eseguito il backup della VM utilizzando l'account admin su SSH.

Dopo il completamento o l'errore del backup della VM, il registro della sessione viene spostato nella seguente posizione:

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:

Il file di vmware.log della VM includerà messaggi relativi all'elaborazione da parte di VMware delle richieste di creazione, eliminazione e consolidamento delle snapshot della VM. È possibile accedere a questo registro dall'interfaccia utente web di vSphere. Il file di log è contenuto all'interno della cartella della VM nel datastore:

Esempio del tipo di registrazione visualizzato durante i backup delle VM NetWorker:

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).

Di seguito sono riportati alcuni passaggi iniziali per la risoluzione dei problemi:

Logs:
esaminare i log menzionati nel campo Symptoms precedente per individuare eventuali indicatori del motivo per cui l'operazione di creazione di istantanee o consolidamento non è riuscita.

Test istantanee manuali:
Se le istantanee VMware hanno esito negativo durante un'operazione di backup di una VM, è necessario verificare anche la funzionalità delle istantanee VMware dal vSphere Web Client. 
Se il backup delle VM è un backup standard basato su immagini, non è necessaria una snapshot inattiva. Se la macchina virtuale è una macchina virtuale SQL che esegue backup coerenti con l'app MSVMAPPAGENT, è necessario eseguire una snapshot inattiva.

Se la snapshot viene creata correttamente, consolidarla ed eliminarla da VMware vSphere. Se si verifica lo stesso errore nelle istantanee eseguite all'esterno di NetWorker, contattare il supporto VMware per individuare la root cause del problema. Anche se le istantanee manuali sono in fase di completamento, altri fattori potrebbero contribuire a questo; Per esempio: VMware vCenter e I/O del datastore/storage quando è stata scattata l'istantanea rispetto a quando è stato eseguito il backup. 

Per una panoramica delle snapshot delle VM, consultare VMware KB: https://kb.vmware.com/s/article/1015180

Snapshot Error Specific KBs:

• NVP vProxy: Il backup della VM ha esito negativo con il messaggio "snapshot has already been deleted or has not been completely created".
• NVP vProxy: Il backup della VM ha esito negativo con errore "Failed to get status of create snapshot task: Operazione scaduta"
• NVP-vProxy: La macchina virtuale non riesce a eseguire il backup con errore "Unable to create snapshot of virtual machine: Impossibile creare una snapshot."
• Il backup di NetWorker vProxy ha esito negativo con errore "Unable to create nonquiesced snapshot after a retry attempt: Rilevata una configurazione istantanea non valida"
• NVP-vProxy: La macchina virtuale perde la connessione durante il processo di rimozione della snapshot.
• NVP-vProxy Triage Guide: NetWorker VMware Protection vProxy Triage and Log Collection Guide

VMware:

• La rimozione di un'istantanea arresta una macchina virtuale per un lungo periodo di tempo (1002836)  
• Stima del tempo necessario per consolidare le istantanee delle macchine virtuali (2053758)