NVP-vProxy: os backups falham com o recurso não encontrado código -404 "Aging out inactive session"

A integração do NetWorker VMware Protection (NVP) usa um equipamento vProxy para realizar backups de VM. Os backups de VM falham intermitentemente com uma mensagem de recurso não encontrado no NetWorker Management Console (NMC). Uma nova tentativa subsequente de backup para a mesma VM pode ser bem-sucedida, mas a falha pode afetar outras VMs na mesma janela de backup. O NMC exibe o seguinte erro (o ID da sessão varia dependendo da ocorrência):

{"Text":"Resource not found: 44ab916f-7519-40f9-9d2e-e90394782ed9","Code":-404}

O log de ação de backup mostra as tentativas malsucedidas do servidor do NetWorker de recuperar o status do backup do vProxy, seguidas pela falha de backup:

MM/DD/YY HH:MM:SS [VM_NAME]: Unable to obtain backup status from vProxy after 5 attempts, failing the backup: Received an HTTP code: 404, libCURL message: "", vProxy message: "Error received from vProxy ="-404: Resource not found: 44ab916f-7519-40f9-9d2e-e90394782ed9". ", url: "https://[VPROXY_NAME]:9090/api/v1/BackupVmSessions/44ab916f-7519-40f9-9d2e-e90394782ed9", body: " ".
MM/DD/YY HH:MM:SS [VM_NAME]: Unable to download vProxy Session log into file '/nsr/logs/policy/VMWare Protection/[WORKFLOW]/461300-[VM_NAME]-YYYY-MM-DD-HH-MM-SS.log': Possible wrong vProxy hostname or port, resource path not found. Received an HTTP code: 404, libCURL message: "", url: "https://[VPROXY_NAME]:9090/api/v1/BackupVmSessions/44ab916f-7519-40f9-9d2e-e90394782ed9/log".
MM/DD/YY HH:MM:SS [VM_NAME]: Backup failed.
MM/DD/YY HH:MM:SS The size of proxied data written has not been set.
MM/DD/YY HH:MM:SS Save-set ID '849786629' (client '[VCENTER_NAME]': save-set 'vm:50308677-bf8b-c786-0140-6a9c4409b90f:[VCENTER_NAME]') is aborted.

O vProxy vbackupd-engine.log mostra o ciclo de vida completo da sessão — o backup é concluído com sucesso no vProxy, mas a sessão é posteriormente expirada e excluída antes que o servidor NetWorker recupere o status:

YYYY/MM/DD HH:MM:SS TRACE:  Ending go routine of main session loop for *resources.BackupVmSessions (vm-29424) [VM_NAME].
YYYY/MM/DD HH:MM:SS NOTICE: 44ab916f-7519-40f9-9d2e-e90394782ed9:vm-29424:[VM_NAME]: Backup session is exiting
YYYY/MM/DD HH:MM:SS TRACE:  Resource Update 44ab916f-7519-40f9-9d2e-e90394782ed9:externalId=[VCENTER_NAME]:vm-29424:state=Success:deletable=true.
YYYY/MM/DD HH:MM:SS INFO:   Saved resource map to "/opt/emc/vproxy/runtime/state/vbackupd/BackupVmSessions.gob".
YYYY/MM/DD HH:MM:SS NOTICE: Aging out inactive session BackupVmSessions - 44ab916f-7519-40f9-9d2e-e90394782ed9 ([VCENTER_NAME]:vm-29424) last accessed YYYY-MM-DD HH:MM:SS -0500 CDT.
YYYY/MM/DD HH:MM:SS TRACE:  Deleting logfiles of session '44ab916f-7519-40f9-9d2e-e90394782ed9'...
YYYY/MM/DD HH:MM:SS TRACE:  Deleting log file "/opt/emc/vproxy/runtime/logs/vbackupd/44ab916f-7519-40f9-9d2e-e90394782ed9.log" while deleting session "44ab916f-7519-40f9-9d2e-e90394782ed9"...
YYYY/MM/DD HH:MM:SS INFO:   File '/opt/emc/vproxy/runtime/logs/vbackupd/44ab916f-7519-40f9-9d2e-e90394782ed9.log' is moved to '/opt/emc/vproxy/runtime/logs/recycle/vbackupd/44ab916f-7519-40f9-9d2e-e90394782ed9.log'.
YYYY/MM/DD HH:MM:SS INFO:   Deleted log file "/opt/emc/vproxy/runtime/logs/vbackupd/44ab916f-7519-40f9-9d2e-e90394782ed9.log" while deleting session "44ab916f-7519-40f9-9d2e-e90394782ed9".
YYYY/MM/DD HH:MM:SS TRACE:  Deleting resource: [VCENTER_NAME]:vm-29424 - 44ab916f-7519-40f9-9d2e-e90394782ed9 ...
YYYY/MM/DD HH:MM:SS INFO:   Deleted resource: [VCENTER_NAME]:vm-29424 - 44ab916f-7519-40f9-9d2e-e90394782ed9.
YYYY/MM/DD HH:MM:SS INFO:   Saved resource map to "/opt/emc/vproxy/runtime/state/vbackupd/BackupVmSessions.gob".
YYYY/MM/DD HH:MM:SS INFO:   Aged out 1 'BackupVmSessions' sessions, 0 remaining
YYYY/MM/DD HH:MM:SS ERROR:  Resource not found: 44ab916f-7519-40f9-9d2e-e90394782ed9
YYYY/MM/DD HH:MM:SS ERROR:  Resource not found: 44ab916f-7519-40f9-9d2e-e90394782ed9
YYYY/MM/DD HH:MM:SS ERROR:  Resource not found: 44ab916f-7519-40f9-9d2e-e90394782ed9
YYYY/MM/DD HH:MM:SS ERROR:  Resource not found: 44ab916f-7519-40f9-9d2e-e90394782ed9
YYYY/MM/DD HH:MM:SS ERROR:  Resource not found: 44ab916f-7519-40f9-9d2e-e90394782ed9
YYYY/MM/DD HH:MM:SS ERROR:  Resource not found: 44ab916f-7519-40f9-9d2e-e90394782ed9
YYYY/MM/DD HH:MM:SS TRACE:  Handling Action GetLog for 44ab916f-7519-40f9-9d2e-e90394782ed9 ...
YYYY/MM/DD HH:MM:SS ERROR:  Resource not found: 44ab916f-7519-40f9-9d2e-e90394782ed9
YYYY/MM/DD HH:MM:SS TRACE:  Reply sent successfully for "GetLog".

O servidor do NetWorker consulta continuamente o vProxy para obter o status da sessão de backup e solicita os registros da sessão depois que o backup é concluído. O vProxy conclui o backup e envelhece a sessão depois que o servidor NetWorker não solicita uma atualização por alguns minutos. Com a idade da sessão, o vProxy não consegue mais fornecer ao servidor NetWorker uma atualização de status ou registros e falha com o comando resource not found Erro. Os atrasos na solicitação de status do servidor do NetWorker geralmente ocorrem devido à alta latência entre o nó de armazenamento do NetWorker e a comunicação do Data Domain.

1. Meça a latência do nó de armazenamento para o Data Domain:
   • Execute um ping contínuo com carimbo de data/hora do nó de armazenamento do NetWorker para o Data Domain e envie os resultados para um arquivo. Certifique-se de que os fusos horários em ambos os sistemas correspondam.
     • Windows: 

       ping -t [DATA_DOMAIN_IP] > ping_output.txt

     • Linux:

       ping [DATA_DOMAIN_IP] | while read line; do echo "$(date): $line"; done > ping_output.txt

   • Deixe o ping em execução em segundo plano durante uma janela de backup.
   • Quando a falha de backup ocorrer novamente, analise o arquivo de ping_output.txt e verifique o intervalo de tempo correspondente à falha para picos de latência.
2. Reduza a latência para menos de 50 ms:
   • Identificar e resolver a origem da alta latência entre o nó de armazenamento do NetWorker e o Data Domain. As causas comuns incluem congestionamento de rede, links de WAN, ineficiências de roteamento ou sobrecarga do equipamento de rede.
   • Certifique-se de que a latência de ida e volta entre o nó de armazenamento do NetWorker e o Data Domain permaneça abaixo de 50 ms durante a execução do backup. Esse limite está documentado na seção "Tamanho da janela de TCP e considerações de latência de rede" do Guia de planejamento de otimização de desempenho do Dell NetWorker.
3. Implemente um nó de armazenamento remoto (se a latência não puder ser reduzida):
   • Se a latência do nó de armazenamento para o Data Domain não puder ser reduzida para menos de 50 ms (por exemplo, devido à separação geográfica), configure um nó de armazenamento do NetWorker no local remoto em que o Data Domain reside. Esse nó de armazenamento gerencia os dispositivos Data Domain localmente, eliminando o caminho de alta latência.
4. Verifique a resolução:
   • Depois de reduzir a latência ou implementar um nó de armazenamento remoto, acione os trabalhos de backup para as VMs afetadas anteriormente.
   • Confirme se os backups foram concluídos com sucesso sem erros de recurso não encontrado (código -404) no NMC, no log de ação de backup ou no vbackupd-engine.log do vProxy.
   • Monitore os trabalhos de backup em três janelas de backup consecutivas para confirmar se as falhas intermitentes não ocorrem mais.
   • Se as falhas de envelhecimento da sessão persistirem após a redução da latência para um nível abaixo de 50 ms, entre em contato com o Suporte Dell para obter mais investigações e consulte este artigo da base de conhecimento.

O seguinte artigo detalha como realizar a solução de problemas de rede: NetWorker: Como solucionar problemas relacionados à rede.