[ovirt-users] oVirt 3.5.6 / VM Import Fails
Michal Skrivanek
michal.skrivanek at redhat.com
Wed Aug 30 09:37:09 UTC 2017
> On 30 Aug 2017, at 04:19, Devin Acosta <devin at pabstatencio.com> wrote:
>
>
> I have a VM that was exported successfully to an export domain, but when I tried to re-import into the Data Center it fails with the following logs. Hoping someone can shed the light on why it's failing to import the VM into the Cluster. It seems concerning when it says: "VDSGenericException: VDSErrorException: Failed to HSMGetAllTasksStatusesVDS, error = low level Image copy failed, code = 261”.
you may want to take a look at the host to see if there are any storage access issues…
it should be the one with HostId = 4c396d88-fe8d-43f3-8fbb-291e88c5c815
Thanks,
michal
>
> Help anyone?
>
>
> 2017-08-30 02:14:03,617 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp--127.0.0.1-8702-4) START, GetVmsInfoVDSCommand( storagePoolId = ff545917-da81-43a2-a5e0-b43b185ee8e8, ignoreFailoverLimit = false, storageDomainId = 4cde55bd-328e-40a7-9b0e-b4a5d576d896, vmIdList = null), log id: 74730ee
> 2017-08-30 02:14:03,639 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp--127.0.0.1-8702-4) FINISH, GetVmsInfoVDSCommand, log id: 74730ee
> 2017-08-30 02:14:06,103 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-32) VM job 07fd15a8-1e41-4cfa-bddd-c2ecef6568a8: In progress (no change)
> 2017-08-30 02:14:06,313 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-34) VM job 80378e33-1efd-4f25-8550-81d4922c5440: In progress (no change)
> 2017-08-30 02:14:06,319 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-36) VM job 1d9fca66-c709-4c2b-a0c3-bf1b1048149c: In progress (no change)
> 2017-08-30 02:14:06,320 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-36) VM job d063300d-7d0f-4bfe-856e-4b0fa450726d: In progress (no change)
> 2017-08-30 02:14:06,321 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-34) VM job ebf6911c-ae9f-4fb1-93ae-1c1cbe242e5e: In progress (no change)
> 2017-08-30 02:14:06,324 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-34) VM job a6c48586-5c02-4b81-a54d-a199d7c4befe: In progress (no change)
> 2017-08-30 02:14:06,506 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-35) VM job 2b1f11c9-4852-4cf3-8a6d-1ac242d05420: In progress (no change)
> 2017-08-30 02:14:20,291 INFO [org.ovirt.engine.core.bll.ImportVmCommand] (ajp--127.0.0.1-8702-5) [338f07a0] Lock Acquired to object EngineLock [exclusiveLocks= key: las01-622-003__2017-08-29__13.01.30 value: VM_NAME
> , sharedLocks= key: 02137593-5f11-4f07-afc0-5d3992a26b38 value: REMOTE_VM
> ]
> 2017-08-30 02:14:20,302 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp--127.0.0.1-8702-5) [338f07a0] START, GetVmsInfoVDSCommand( storagePoolId = ff545917-da81-43a2-a5e0-b43b185ee8e8, ignoreFailoverLimit = false, storageDomainId = 4cde55bd-328e-40a7-9b0e-b4a5d576d896, vmIdList = null), log id: 3672c4a9
> 2017-08-30 02:14:20,320 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp--127.0.0.1-8702-5) [338f07a0] FINISH, GetVmsInfoVDSCommand, log id: 3672c4a9
> 2017-08-30 02:14:20,887 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImagesListVDSCommand] (ajp--127.0.0.1-8702-5) [338f07a0] START, GetImagesListVDSCommand( storagePoolId = ff545917-da81-43a2-a5e0-b43b185ee8e8, ignoreFailoverLimit = false, sdUUID = 39b260fb-0e4e-414d-972f-8ebd87ce7643), log id: 692a8a42
> 2017-08-30 02:14:20,904 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImagesListVDSCommand] (ajp--127.0.0.1-8702-5) [338f07a0] FINISH, GetImagesListVDSCommand, return: [d91aa8be-7929-43e3-8891-e5b35389150f, 420f239a-177b-407e-bb22-c93f984c8735, 7cc223ed-d7cd-4d0e-85fd-05a9a82d3315, e7879ce5-5af3-48ab-ab1c-26540cf785b5], log id: 692a8a42
> 2017-08-30 02:14:20,911 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DoesImageExistVDSCommand] (ajp--127.0.0.1-8702-5) [338f07a0] START, DoesImageExistVDSCommand( storagePoolId = ff545917-da81-43a2-a5e0-b43b185ee8e8, ignoreFailoverLimit = false, storageDomainId = 4cde55bd-328e-40a7-9b0e-b4a5d576d896, imageGroupId = f0b7e806-64d9-49ea-a52c-7fe70be2138b, imageId = 53b161c8-1751-41cf-b0bb-444bd603d136), log id: 707f904c
> 2017-08-30 02:14:20,929 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DoesImageExistVDSCommand] (ajp--127.0.0.1-8702-5) [338f07a0] FINISH, DoesImageExistVDSCommand, return: true, log id: 707f904c
> 2017-08-30 02:14:21,058 INFO [org.ovirt.engine.core.bll.ImportVmCommand] (org.ovirt.thread.pool-8-thread-26) [338f07a0] Running command: ImportVmCommand internal: false. Entities affected : ID: 39b260fb-0e4e-414d-972f-8ebd87ce7643 Type: StorageAction group IMPORT_EXPORT_VM with role type ADMIN
> 2017-08-30 02:14:21,274 INFO [org.ovirt.engine.core.bll.CopyImageGroupCommand] (org.ovirt.thread.pool-8-thread-26) [5637f152] Running command: CopyImageGroupCommand internal: true. Entities affected : ID: 39b260fb-0e4e-414d-972f-8ebd87ce7643 Type: Storage
> 2017-08-30 02:14:21,417 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-99) VM job 07fd15a8-1e41-4cfa-bddd-c2ecef6568a8: In progress (no change)
> 2017-08-30 02:14:21,426 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (org.ovirt.thread.pool-8-thread-26) [5637f152] START, CopyImageVDSCommand( storagePoolId = ff545917-da81-43a2-a5e0-b43b185ee8e8, ignoreFailoverLimit = false, storageDomainId = 4cde55bd-328e-40a7-9b0e-b4a5d576d896, imageGroupId = f0b7e806-64d9-49ea-a52c-7fe70be2138b, imageId = 53b161c8-1751-41cf-b0bb-444bd603d136, dstImageGroupId = f0b7e806-64d9-49ea-a52c-7fe70be2138b, vmId = 02137593-5f11-4f07-afc0-5d3992a26b38, dstImageId = 53b161c8-1751-41cf-b0bb-444bd603d136, imageDescription = , dstStorageDomainId = 39b260fb-0e4e-414d-972f-8ebd87ce7643, copyVolumeType = LeafVol, volumeFormat = RAW, preallocate = Sparse, postZero = false, force = true), log id: 7bd35902
> 2017-08-30 02:14:21,428 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (org.ovirt.thread.pool-8-thread-26) [5637f152] -- executeIrsBrokerCommand: calling 'copyImage' with two new parameters: description and UUID
> 2017-08-30 02:14:21,429 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (org.ovirt.thread.pool-8-thread-26) [5637f152] -- copyImage parameters:
> sdUUID=4cde55bd-328e-40a7-9b0e-b4a5d576d896
> spUUID=ff545917-da81-43a2-a5e0-b43b185ee8e8
> vmGUID=02137593-5f11-4f07-afc0-5d3992a26b38
> srcImageGUID=f0b7e806-64d9-49ea-a52c-7fe70be2138b
> srcVolUUID=53b161c8-1751-41cf-b0bb-444bd603d136
> dstImageGUID=f0b7e806-64d9-49ea-a52c-7fe70be2138b
> dstVolUUID=53b161c8-1751-41cf-b0bb-444bd603d136
> descr=
> dstSdUUID=39b260fb-0e4e-414d-972f-8ebd87ce7643
> 2017-08-30 02:14:21,528 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (org.ovirt.thread.pool-8-thread-26) [5637f152] FINISH, CopyImageVDSCommand, return: 00000000-0000-0000-0000-000000000000, log id: 7bd35902
> 2017-08-30 02:14:21,542 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-26) [5637f152] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 14530608-260e-4988-8fb4-1fab452ae215
> 2017-08-30 02:14:21,544 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (org.ovirt.thread.pool-8-thread-26) [5637f152] CommandMultiAsyncTasks::AttachTask: Attaching task f7762dfe-a9f6-4a09-aea3-7f42ddfcfa6d to command 14530608-260e-4988-8fb4-1fab452ae215.
> 2017-08-30 02:14:21,569 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (org.ovirt.thread.pool-8-thread-26) [5637f152] Adding task f7762dfe-a9f6-4a09-aea3-7f42ddfcfa6d (Parent Command ImportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
> 2017-08-30 02:14:21,742 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (org.ovirt.thread.pool-8-thread-26) [5637f152] START, SetVmStatusVDSCommand( vmId = 02137593-5f11-4f07-afc0-5d3992a26b38, status = ImageLocked, exit status = Normal), log id: 67bbeffa
> 2017-08-30 02:14:21,749 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (org.ovirt.thread.pool-8-thread-26) [5637f152] FINISH, SetVmStatusVDSCommand, log id: 67bbeffa
> 2017-08-30 02:14:21,774 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-98) VM job 80378e33-1efd-4f25-8550-81d4922c5440: In progress (no change)
> 2017-08-30 02:14:21,777 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-98) VM job ebf6911c-ae9f-4fb1-93ae-1c1cbe242e5e: In progress (no change)
> 2017-08-30 02:14:21,779 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-98) VM job a6c48586-5c02-4b81-a54d-a199d7c4befe: In progress (no change)
> 2017-08-30 02:14:21,797 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-26) [5637f152] Correlation ID: 338f07a0, Job ID: 51514883-1f74-4d1e-80e3-24c94b9a75c8, Call Stack: null, Custom Event ID: -1, Message: Starting to import Vm las01-622-003__2017-08-29__13.01.30 to Data Center las01, Cluster las01-switch
> 2017-08-30 02:14:21,799 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-26) [5637f152] BaseAsyncTask::startPollingTask: Starting to poll task f7762dfe-a9f6-4a09-aea3-7f42ddfcfa6d.
> 2017-08-30 02:14:21,799 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-97) VM job 1d9fca66-c709-4c2b-a0c3-bf1b1048149c: In progress (no change)
> 2017-08-30 02:14:21,803 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-97) VM job d063300d-7d0f-4bfe-856e-4b0fa450726d: In progress (no change)
> 2017-08-30 02:14:21,934 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (DefaultQuartzScheduler_Worker-6) VM job 2b1f11c9-4852-4cf3-8a6d-1ac242d05420: In progress (no change)
> 2017-08-30 02:14:23,927 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler_Worker-5) Polling and updating Async Tasks: 1 tasks, 1 tasks to poll now
> 2017-08-30 02:14:23,941 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksStatusesVDSCommand] (DefaultQuartzScheduler_Worker-5) Failed in HSMGetAllTasksStatusesVDS method
> 2017-08-30 02:14:23,943 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-5) SPMAsyncTask::PollTask: Polling task f7762dfe-a9f6-4a09-aea3-7f42ddfcfa6d (Parent Command ImportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'cleanSuccess'.
> 2017-08-30 02:14:23,959 ERROR [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler_Worker-5) BaseAsyncTask::logEndTaskFailure: Task f7762dfe-a9f6-4a09-aea3-7f42ddfcfa6d (Parent Command ImportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended with failure:
> -- Result: cleanSuccess
> -- Message: VDSGenericException: VDSErrorException: Failed to HSMGetAllTasksStatusesVDS, error = low level Image copy failed, code = 261,
> -- Exception: VDSGenericException: VDSErrorException: Failed to HSMGetAllTasksStatusesVDS, error = low level Image copy failed, code = 261
> 2017-08-30 02:14:23,964 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-5) CommandAsyncTask::endActionIfNecessary: All tasks of command 14530608-260e-4988-8fb4-1fab452ae215 has ended -> executing endAction
> 2017-08-30 02:14:23,966 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler_Worker-5) CommandAsyncTask::endAction: Ending action for 1 tasks (command ID: 14530608-260e-4988-8fb4-1fab452ae215): calling endAction .
> 2017-08-30 02:14:23,969 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-43) CommandAsyncTask::endCommandAction [within thread] context: Attempting to endAction ImportVm, executionIndex: 0
> 2017-08-30 02:14:24,008 ERROR [org.ovirt.engine.core.bll.ImportVmCommand] (org.ovirt.thread.pool-8-thread-43) Ending command with failure: org.ovirt.engine.core.bll.ImportVmCommand
> 2017-08-30 02:14:24,100 ERROR [org.ovirt.engine.core.bll.CopyImageGroupCommand] (org.ovirt.thread.pool-8-thread-43) [5637f152] Ending command with failure: org.ovirt.engine.core.bll.CopyImageGroupCommand
> 2017-08-30 02:14:24,113 INFO [org.ovirt.engine.core.bll.RemoveImageCommand] (org.ovirt.thread.pool-8-thread-43) [20e1b3d3] Running command: RemoveImageCommand internal: true. Entities affected : ID: 39b260fb-0e4e-414d-972f-8ebd87ce7643 Type: Storage
> 2017-08-30 02:14:24,193 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (org.ovirt.thread.pool-8-thread-43) [20e1b3d3] START, DeleteImageGroupVDSCommand( storagePoolId = ff545917-da81-43a2-a5e0-b43b185ee8e8, ignoreFailoverLimit = false, storageDomainId = 39b260fb-0e4e-414d-972f-8ebd87ce7643, imageGroupId = f0b7e806-64d9-49ea-a52c-7fe70be2138b, postZeros = false, forceDelete = false), log id: 29fac779
> 2017-08-30 02:14:24,218 ERROR [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (org.ovirt.thread.pool-8-thread-43) [20e1b3d3] Command DeleteImageGroupVDSCommand( storagePoolId = ff545917-da81-43a2-a5e0-b43b185ee8e8, ignoreFailoverLimit = false, storageDomainId = 39b260fb-0e4e-414d-972f-8ebd87ce7643, imageGroupId = f0b7e806-64d9-49ea-a52c-7fe70be2138b, postZeros = false, forceDelete = false) execution failed. Exception: IrsOperationFailedNoFailoverException: IRSGenericException: IRSErrorException: Image does not exist in domain: u'image=f0b7e806-64d9-49ea-a52c-7fe70be2138b, domain=39b260fb-0e4e-414d-972f-8ebd87ce7643'
> 2017-08-30 02:14:24,220 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (org.ovirt.thread.pool-8-thread-43) [20e1b3d3] FINISH, DeleteImageGroupVDSCommand, log id: 29fac779
> 2017-08-30 02:14:24,221 INFO [org.ovirt.engine.core.bll.RemoveImageCommand] (org.ovirt.thread.pool-8-thread-43) [20e1b3d3] Disk f0b7e806-64d9-49ea-a52c-7fe70be2138b doesnt exist on storage domain 39b260fb-0e4e-414d-972f-8ebd87ce7643, rolling forward
> 2017-08-30 02:14:24,360 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (org.ovirt.thread.pool-8-thread-43) [20e1b3d3] Removed task 86cc1a36-6c76-47f7-8c3e-d2d7dc02fcdc from DataBase
> 2017-08-30 02:14:24,411 INFO [org.ovirt.engine.core.bll.ImportVmCommand] (org.ovirt.thread.pool-8-thread-43) Lock freed to object EngineLock [exclusiveLocks= key: las01-622-003__2017-08-29__13.01.30 value: VM_NAME
> , sharedLocks= key: 02137593-5f11-4f07-afc0-5d3992a26b38 value: REMOTE_VM
> ]
> 2017-08-30 02:14:24,429 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-43) Correlation ID: 338f07a0, Job ID: 51514883-1f74-4d1e-80e3-24c94b9a75c8, Call Stack: null, Custom Event ID: -1, Message: Failed to import Vm las01-622-003__2017-08-29__13.01.30 to Data Center las01, Cluster las01-switch
> 2017-08-30 02:14:24,430 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-43) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ImportVm completed, handling the result.
> 2017-08-30 02:14:24,431 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-43) CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type ImportVm succeeded, clearing tasks.
> 2017-08-30 02:14:24,440 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-43) SPMAsyncTask::ClearAsyncTask: Attempting to clear task f7762dfe-a9f6-4a09-aea3-7f42ddfcfa6d
> 2017-08-30 02:14:24,443 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-43) START, SPMClearTaskVDSCommand( storagePoolId = ff545917-da81-43a2-a5e0-b43b185ee8e8, ignoreFailoverLimit = false, taskId = f7762dfe-a9f6-4a09-aea3-7f42ddfcfa6d), log id: 483bc9a3
> 2017-08-30 02:14:24,446 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-43) START, HSMClearTaskVDSCommand(HostName = las01-002-003, HostId = 4c396d88-fe8d-43f3-8fbb-291e88c5c815, taskId=f7762dfe-a9f6-4a09-aea3-7f42ddfcfa6d), log id: 5ee245c
> 2017-08-30 02:14:24,458 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-43) FINISH, HSMClearTaskVDSCommand, log id: 5ee245c
> 2017-08-30 02:14:24,459 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-8-thread-43) FINISH, SPMClearTaskVDSCommand, log id: 483bc9a3
> 2017-08-30 02:14:24,471 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-8-thread-43) BaseAsyncTask::removeTaskFromDB: Removed task f7762dfe-a9f6-4a09-aea3-7f42ddfcfa6d from DataBase
> 2017-08-30 02:14:24,472 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-8-thread-43) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 14530608-260e-4988-8fb4-1fab452ae215
>
> --
>
> Devin Acosta
> Red Hat Certified Architect, LinuxStack
>
> _______________________________________________
> Users mailing list
> Users at ovirt.org
> http://lists.ovirt.org/mailman/listinfo/users
More information about the Users
mailing list