
UNSOLVED
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.
Responses (0)
Solutions (0)
