UNSOLVED

Dsantibanez

updated

7 years ago

D

Dsantibanez

1 Rookie

82 Posts

0

4345

July 24th, 2019 09:00

Error disabling storage migration for virtual machine (migration enabled:true)

Hi Everyone a few weeks ago i have issues to backup somes VM with the error Error disabling storage migration for virtual machine "VM-example" 
I try this solution      but every failed VM  have migration enabled: true 

We have networker  9.1.1.3

Here  is a log example.:

 

2019/07/24 02:29:51 NOTICE: [189] Logging to '/opt/emc/vproxy/runtime/logs/vbackupd/2a3fc39d-5ef3-439e-b8c0-4a02429a64c5.log' on host 'nwprx-paip01'
2019/07/24 02:29:51 NOTICE: [189] Release: '2.0.2-11_1', Build number: '1', Build date: '2017-03-30T18:46:12Z'
2019/07/24 02:29:51 TRACE:  [189] Using locale "en", 67 messages.
2019/07/24 02:29:51 NOTICE: [189] Starting backup of "Fomento7-New" (vm-155) logging at level "TRACE" ...
2019/07/24 02:29:51 NOTICE: [189] BackupVmSessions supplied by client:
2019/07/24 02:29:51 NOTICE: [189] {
2019/07/24 02:29:51 NOTICE: [189]     "Config": {
2019/07/24 02:29:51 NOTICE: [189]         "SessionId": "2a3fc39d-5ef3-439e-b8c0-4a02429a64c5",
2019/07/24 02:29:51 NOTICE: [189]         "LogTag": "189",
2019/07/24 02:29:51 NOTICE: [189]         "LogLevel": "TRACE",
2019/07/24 02:29:51 NOTICE: [189]         "IdleTimeout": 300,
2019/07/24 02:29:51 NOTICE: [189]         "AcceptedLanguage": "en",
2019/07/24 02:29:51 NOTICE: [189]         "TransportModeRequested": "hotadd",
2019/07/24 02:29:51 NOTICE: [189]         "Parallelism": 1,
2019/07/24 02:29:51 NOTICE: [189]         "UseCbt": true,
2019/07/24 02:29:51 NOTICE: [189]         "VimServerRef": {
2019/07/24 02:29:51 NOTICE: [189]             "HostName": "vcenter-paip.xxx.xx",
2019/07/24 02:29:51 NOTICE: [189]             "TcpPort": 0,
2019/07/24 02:29:51 NOTICE: [189]             "UserName": "backup@vsphere.local",
2019/07/24 02:29:51 NOTICE: [189]             "UserPassword": "********",
2019/07/24 02:29:51 NOTICE: [189]             "ThumbPrint": ""
2019/07/24 02:29:51 NOTICE: [189]         },
2019/07/24 02:29:51 NOTICE: [189]         "VmSpec": {
2019/07/24 02:29:51 NOTICE: [189]             "Name": "Fomento7-New",
2019/07/24 02:29:51 NOTICE: [189]             "VmMoref": "vm-155",
2019/07/24 02:29:51 NOTICE: [189]             "VirtualDisks": [
2019/07/24 02:29:51 NOTICE: [189]                 {
2019/07/24 02:29:51 NOTICE: [189]                     "Label": "Hard disk 1",
2019/07/24 02:29:51 NOTICE: [189]                     "Key": 2000
2019/07/24 02:29:51 NOTICE: [189]                 },
2019/07/24 02:29:51 NOTICE: [189]                 {
2019/07/24 02:29:51 NOTICE: [189]                     "Label": "Hard disk 2",
2019/07/24 02:29:51 NOTICE: [189]                     "Key": 2001
2019/07/24 02:29:51 NOTICE: [189]                 },
2019/07/24 02:29:51 NOTICE: [189]                 {
2019/07/24 02:29:51 NOTICE: [189]                     "Label": "Hard disk 3",
2019/07/24 02:29:51 NOTICE: [189]                     "Key": 2002
2019/07/24 02:29:51 NOTICE: [189]                 }
2019/07/24 02:29:51 NOTICE: [189]             ],
2019/07/24 02:29:51 NOTICE: [189]             "CustomFieldName": "Last EMC vProxy Backup",
2019/07/24 02:29:51 NOTICE: [189]             "CustomFieldValue": "Backup Server=paipoterex, Policy=WMWARE FHVL, Workflow=VM_FILESERVERS, Action=backup, JobId=847324"
2019/07/24 02:29:51 NOTICE: [189]         },
2019/07/24 02:29:51 NOTICE: [189]         "SnapshotSpec": {
2019/07/24 02:29:51 NOTICE: [189]             "Name": "NetWorker Backup Snapshot",
2019/07/24 02:29:51 NOTICE: [189]             "Description": "Snapshot is created as part of protecting this VM by EMC NetWorker VProxy.",
2019/07/24 02:29:51 NOTICE: [189]             "Quiesce": false,
2019/07/24 02:29:51 NOTICE: [189]             "DumpMemory": false,
2019/07/24 02:29:51 NOTICE: [189]             "RemoveAll": false,
2019/07/24 02:29:51 NOTICE: [189]             "Consolidate": false
2019/07/24 02:29:51 NOTICE: [189]         },
2019/07/24 02:29:51 NOTICE: [189]         "TargetSpec": {
2019/07/24 02:29:51 NOTICE: [189]             "DeviceType": "DataDomain",
2019/07/24 02:29:51 NOTICE: [189]             "HostName": "paipotedata.xxxxx.cxl",
2019/07/24 02:29:51 NOTICE: [189]             "UserName": "nwboost",
2019/07/24 02:29:51 NOTICE: [189]             "UserPassword": "********",
2019/07/24 02:29:51 NOTICE: [189]             "BackupMode": "VSS",
2019/07/24 02:29:51 NOTICE: [189]             "BackupPath": "paipoteres/VM-01//paipoteres/VM-01/46/16/5909ba16-00000006-0f37c2ae-5d37c2ae-4ff36618-881ca6da",
2019/07/24 02:29:51 NOTICE: [189]             "PreviousBackupPath": "/paipoteres/VM-01/67/68/2cb0faab-00000006-1a0d92ad-5d0d92ad-2fe86618-881ca6da",
2019/07/24 02:29:51 NOTICE: [189]             "CurrentBackupPath": "/paipoteres/VM-01/active/5909ba16-00000006-0f37c2ae-5d37c2ae-4ff36618-881ca6da",
2019/07/24 02:29:51 NOTICE: [189]             "VBABackup": {
2019/07/24 02:29:51 NOTICE: [189]                 "VBABackupPath": "",
2019/07/24 02:29:51 NOTICE: [189]                 "VBATempPath": ""
2019/07/24 02:29:51 NOTICE: [189]             }
2019/07/24 02:29:51 NOTICE: [189]         },
2019/07/24 02:29:51 NOTICE: [189]         "ZeroFillUnusedSectors": false,
2019/07/24 02:29:51 NOTICE: [189]         "AutoEnableCbt": true,
2019/07/24 02:29:51 NOTICE: [189]         "AutoRepairCbt": true,
2019/07/24 02:29:51 NOTICE: [189]         "FallbackCbtThreshold": 0,
2019/07/24 02:29:51 NOTICE: [189]         "FallbackTransportMode": "",
2019/07/24 02:29:51 NOTICE: [189]         "DeprecatedREST": false,
2019/07/24 02:29:51 NOTICE: [189]         "ReadBufferMB": 0,
2019/07/24 02:29:51 NOTICE: [189]         "WriteBufferKB": 0,
2019/07/24 02:29:51 NOTICE: [189]         "BufferCount": 0
2019/07/24 02:29:51 NOTICE: [189]     },
2019/07/24 02:29:51 NOTICE: [189]     "Actions": {
2019/07/24 02:29:51 NOTICE: [189]         "ChangeSession": "",
2019/07/24 02:29:51 NOTICE: [189]         "DeleteSession": "/api/v1/BackupVmSessions/2a3fc39d-5ef3-439e-b8c0-4a02429a64c5",
2019/07/24 02:29:51 NOTICE: [189]         "CancelActivity": "/api/v1/BackupVmSessions/2a3fc39d-5ef3-439e-b8c0-4a02429a64c5/activity",
2019/07/24 02:29:51 NOTICE: [189]         "GetSession": "/api/v1/BackupVmSessions/2a3fc39d-5ef3-439e-b8c0-4a02429a64c5",
2019/07/24 02:29:51 NOTICE: [189]         "GetSessionLog": "/api/v1/BackupVmSessions/2a3fc39d-5ef3-439e-b8c0-4a02429a64c5/log",
2019/07/24 02:29:51 NOTICE: [189]         "GetAllSessions": "/api/v1/BackupVmSessions"
2019/07/24 02:29:51 NOTICE: [189]     },
2019/07/24 02:29:51 NOTICE: [189]     "Status": {
2019/07/24 02:29:51 NOTICE: [189]         "State": "Queued",
2019/07/24 02:29:51 NOTICE: [189]         "Description": "",
2019/07/24 02:29:51 NOTICE: [189]         "Progress": 0,
2019/07/24 02:29:51 NOTICE: [189]         "Error": {
2019/07/24 02:29:51 NOTICE: [189]             "Text": "",
2019/07/24 02:29:51 NOTICE: [189]             "Code": 0
2019/07/24 02:29:51 NOTICE: [189]         },
2019/07/24 02:29:51 NOTICE: [189]         "StartTime": "",
2019/07/24 02:29:51 NOTICE: [189]         "EndTime": "",
2019/07/24 02:29:51 NOTICE: [189]         "BytesToMove": 0,
2019/07/24 02:29:51 NOTICE: [189]         "BytesMoved": 0,
2019/07/24 02:29:51 NOTICE: [189]         "TransportModeUsed": "",
2019/07/24 02:29:51 NOTICE: [189]         "BackupModeUsed": ""
2019/07/24 02:29:51 NOTICE: [189]     }
2019/07/24 02:29:51 NOTICE: [189] }
2019/07/24 02:29:51 TRACE:  [189] Set state to running.
2019/07/24 02:29:51 TRACE:  [189] Sent initial status to vProxy Engine.
2019/07/24 02:29:51 TRACE:  [189] Opening session to target device "paipotedata.xxxxx.cxl" ...
2019/07/24 02:29:51 NOTICE: [189] DD Model = "DD6300", DDOS Version = "Data Domain OS 6.0.1.10-561375", DD Boost Version = "3.3.0.4-550644".
2019/07/24 02:29:51 TRACE:  [189] Opened session to target device "paipotedata.xxxxx.cxl".
2019/07/24 02:29:51 INFO:   [189] DDOS version 6.0.1.10-561375 compatible.
2019/07/24 02:29:51 TRACE:  [189] Verifying that target directory "/paipoteres/VM-01/active/5909ba16-00000006-0f37c2ae-5d37c2ae-4ff36618-881ca6da" on "paipotedata.xxxxx.cxl" is empty.
2019/07/24 02:29:51 TRACE:  [189] Opening target directory "/paipoteres/VM-01/active/5909ba16-00000006-0f37c2ae-5d37c2ae-4ff36618-881ca6da" on DD "paipotedata.xxxxx.cxl".
2019/07/24 02:29:51 INFO:   [189] Opened target directory "/paipoteres/VM-01/active/5909ba16-00000006-0f37c2ae-5d37c2ae-4ff36618-881ca6da" on DD "paipotedata.xxxxx.cxl".
2019/07/24 02:29:51 TRACE:  [189] Closing target directory "/paipoteres/VM-01/active/5909ba16-00000006-0f37c2ae-5d37c2ae-4ff36618-881ca6da" on DD "paipotedata.xxxxx.cxl".
2019/07/24 02:29:51 INFO:   [189] Closed target directory "/paipoteres/VM-01/active/5909ba16-00000006-0f37c2ae-5d37c2ae-4ff36618-881ca6da" on DD "paipotedata.xxxxx.cxl".
2019/07/24 02:29:51 TRACE:  [189] Converting VBA backup from  '' ...
2019/07/24 02:29:51 TRACE:  [189] Getting previous backup descriptor from "/paipoteres/VM-01/67/68/2cb0faab-00000006-1a0d92ad-5d0d92ad-2fe86618-881ca6da" ...
2019/07/24 02:29:51 TRACE:  [189] Opening Backup Descriptor file "/paipoteres/VM-01/67/68/2cb0faab-00000006-1a0d92ad-5d0d92ad-2fe86618-881ca6da/BackupDescriptor.json" ...
2019/07/24 02:29:51 INFO:   [189] Opened Backup Descriptor file "/paipoteres/VM-01/67/68/2cb0faab-00000006-1a0d92ad-5d0d92ad-2fe86618-881ca6da/BackupDescriptor.json" ...
2019/07/24 02:29:51 TRACE:  [189] Starting go routine to listen for cancel from vProxy engine.
2019/07/24 02:29:51 TRACE:  [189] Starting go routine to periodically send status updates...
2019/07/24 02:29:51 INFO:   [189] Loaded Backup Descriptor file "/paipoteres/VM-01/67/68/2cb0faab-00000006-1a0d92ad-5d0d92ad-2fe86618-881ca6da/BackupDescriptor.json".
2019/07/24 02:29:51 TRACE:  [189] Closing Backup Descriptor file "/paipoteres/VM-01/67/68/2cb0faab-00000006-1a0d92ad-5d0d92ad-2fe86618-881ca6da/BackupDescriptor.json" ...
2019/07/24 02:29:51 INFO:   [189] Closed Backup Descriptor file "/paipoteres/VM-01/67/68/2cb0faab-00000006-1a0d92ad-5d0d92ad-2fe86618-881ca6da/BackupDescriptor.json" ...
2019/07/24 02:29:51 INFO:   [189] Connected to session on vCenter 'vcenter-paip.xxxxx.cxl' Version '6.5.0 build-8024368, API:6.5', Uuid:ef4a6db1-7c65-4909-9195-5314c2bc614a.
2019/07/24 02:29:51 TRACE:  [189] Locating virtual machine associated with IP address 192.168.58.51 ...
2019/07/24 02:29:51 INFO:   [189] The vProxy's virtual machine has MORef ID = "vm-1857".
2019/07/24 02:29:51 TRACE:  [189] Searching for virtual machine with MORef "vm-155" ...
2019/07/24 02:29:51 TRACE:  [189] Querying vCenter for definition of virtual machine with MORef of "vm-155" ...
2019/07/24 02:29:52 INFO:   [189] Found virtual machine "Fomento7-New" with MORef "vm-155".
2019/07/24 02:29:52 TRACE:  [189] Check host'nodo-paip-01.xxxxx.cxl' connectionState:'connected',inMaintenanceMode:'false', powerState:poweredOn
2019/07/24 02:29:52 INFO:   [189] Host 'nodo-paip-01.xxxxx.cxl' is running VMware ESXi 6.5.0 build-7967591.
2019/07/24 02:29:52 INFO:   [189] Locking VM.
2019/07/24 02:29:52 TRACE:  [189] Setting custom attribute 'EMC vProxy Session' value for object vm-155 to 'Operation=Backup, StartTime=2019-07-24T02:29:51Z, vProxy=nwprx-paip01, SessionId=2a3fc39d-5ef3-439e-b8c0-4a02429a64c5' ...
2019/07/24 02:29:52 INFO:   [189] Set custom attribute 'EMC vProxy Session' value for object vm-155 to 'Operation=Backup, StartTime=2019-07-24T02:29:51Z, vProxy=nwprx-paip01, SessionId=2a3fc39d-5ef3-439e-b8c0-4a02429a64c5'.
2019/07/24 02:29:53 TRACE:  [189] Checking for old snapshots to cleanup ...
2019/07/24 02:29:53 INFO:   [189] Disabling storage migration of virtual machine ...
2019/07/24 02:29:53 TRACE:  [189] Disabling storage migration of virtual machine "vm-155" ...
2019/07/24 02:29:54 WARN:   [189] Error disabling storage migration, retrying, error: VDDK Error: 20005: The operation has been disabled by the guest operating system.
2019/07/24 02:29:54 ERROR:  [189] Failed to disable storage migration for virtual machine "vm-155": VDDK Error: 20005: The operation has been disabled by the guest operating system.
2019/07/24 02:29:54 ERROR:  [189] Error disabling storage migration for virtual machine "Fomento7-New".
2019/07/24 02:29:54 TRACE:  [189] Unlocking VM ...
2019/07/24 02:29:54 TRACE:  [189] Attemp #1 to clear virtual machine's custom field lock "EMC vProxy Session" ...
2019/07/24 02:29:54 TRACE:  [189] Setting custom attribute 'EMC vProxy Session' value for object vm-155 to '' ...
2019/07/24 02:29:54 INFO:   [189] Set custom attribute 'EMC vProxy Session' value for object vm-155 to ''.
2019/07/24 02:29:54 INFO:   [189] Unlocked virtual machine.
2019/07/24 02:29:54 INFO:   [189] Disconnected from session on vCenter 'vcenter-paip.xxxxx.cxl'.
2019/07/24 02:29:54 TRACE:  [189] Closing session with target device "paipotedata.xxxxx.cxl" ...
2019/07/24 02:29:54 INFO:   [189] Closed session with target device "paipotedata.xxxxx.cxl".
2019/07/24 02:29:54 TRACE:  [189] Checking final status...
2019/07/24 02:29:54 TRACE:  [189] Set state to failed.
2019/07/24 02:29:54 ERROR:  [189] Backup has FAILED.
2019/07/24 02:29:54 TRACE:  [189] Sending final status to vProxy Engine ...
2019/07/24 02:29:54 INFO:   [189] Sent final status to vProxy Engine.
2019/07/24 02:29:54 INFO:   [189] Go routine sending status updates has completed.
2019/07/24 02:29:54 INFO:   [189] Go routine listening for cancel messages from vProxy engine has completed.
2019/07/24 02:29:54 NOTICE: [189] Backup session is exiting

 

Thanks in advance.