[Users] Virtual disk "locked" and I can't start/alter the VM

[Ian Levesque]

On Aug 8, 2012, at 1:43 AM, Mike Kolesnik wrote:

>>> you can edit it directly in the db to remove the lock from the
>>> vm_dynamic table.
>> 
>> Any pointers on this?
> 
> If this is the only locked disk, please try to run this query on the DB:
> update images
> set imagestatus = 1
> where imagestatus = 2;
> 
> And see if it helped.

Thanks, Mike - that did the trick. I checked the table first, and it was indeed the only disk that was "locked". I was able to restart the VM thanks to this.


> If you can please attach engine.log & vdsm.log from the time of when this happened, that could help us try to identify what went wrong.


Here's what I could find in engine.log:

2012-08-06 17:00:25,511 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-40) SPMAsyncTask::PollTask: Polling task 4435f6be-80cb-46c4-96d4-80c186a4cc12 (Parent Command ExportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'.
2012-08-06 17:00:25,544 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-40) BaseAsyncTask::OnTaskEndSuccess: Task 4435f6be-80cb-46c4-96d4-80c186a4cc12 (Parent Command ExportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully.
2012-08-06 17:00:25,544 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (QuartzScheduler_Worker-40) EntityAsyncTask::EndActionIfNecessary: All tasks of entity 719bd920-fcaf-4112-b2b7-5f38d1cf7938 has ended -> executing EndAction
2012-08-06 17:00:25,545 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (QuartzScheduler_Worker-40) EntityAsyncTask::EndAction: Ending action for 1 tasks (entity ID: 719bd920-fcaf-4112-b2b7-5f38d1cf7938): calling EndAction for action type ExportVm.
2012-08-06 17:00:25,546 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-3-thread-48) EntityAsyncTask::EndCommandAction [within thread]context: Attempting to EndAction ExportVm
2012-08-06 17:00:25,557 INFO  [org.ovirt.engine.core.bll.ExportVmCommand] (pool-3-thread-48) Ending command successfully: org.ovirt.engine.core.bll.ExportVmCommand
2012-08-06 17:00:25,573 INFO  [org.ovirt.engine.core.bll.MoveOrCopyImageGroupCommand] (pool-3-thread-48) [aee205e] Ending command successfully: org.ovirt.engine.core.bll.MoveOrCopyImageGroupCommand
2012-08-06 17:00:25,576 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-3-thread-48) [aee205e] START, GetImageInfoVDSCommand(storagePoolId = ec49c68a-cf98-11e1-89fd-003048348194, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 5e49ae74-6aa2-4d4a-9358-ecbce0631f5e, imageGroupId = 8ea62697-02a5-4485-a801-67c059169c46, imageId = 35343bd0-e08b-4ce1-a7a1-4ef4a9bcf688), log id: 7c7d8f20
2012-08-06 17:00:25,613 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-3-thread-48) [aee205e] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage at 2c4498d1, log id: 7c7d8f20
2012-08-06 17:00:25,627 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (pool-3-thread-48) START, SetVmStatusVDSCommand(vmId = 719bd920-fcaf-4112-b2b7-5f38d1cf7938, status = Down), log id: 7dada4a8
2012-08-06 17:00:25,631 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (pool-3-thread-48) FINISH, SetVmStatusVDSCommand, log id: 7dada4a8
2012-08-06 17:00:25,653 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (pool-3-thread-48) START, UpdateVMVDSCommand(storagePoolId = ec49c68a-cf98-11e1-89fd-003048348194, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = f2e3408f-960b-4543-8cdc-d41ddc58253e, infoDictionary.size = 1), log id: ec95ed4
2012-08-06 17:00:25,682 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (pool-3-thread-48) FINISH, UpdateVMVDSCommand, log id: ec95ed4
2012-08-06 17:00:25,718 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-3-thread-48) EntityAsyncTask::HandleEndActionResult [within thread]: EndAction for action type ExportVm completed, handling the result.
2012-08-06 17:00:25,719 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-3-thread-48) EntityAsyncTask::HandleEndActionResult [within thread]: EndAction for action type ExportVm succeeded, clearing tasks.
2012-08-06 17:00:25,721 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-3-thread-48) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 4435f6be-80cb-46c4-96d4-80c186a4cc12
2012-08-06 17:00:25,722 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-3-thread-48) START, SPMClearTaskVDSCommand(storagePoolId = ec49c68a-cf98-11e1-89fd-003048348194, ignoreFailoverLimit = false, compatabilityVersion = null, taskId = 4435f6be-80cb-46c4-96d4-80c186a4cc12), log id: 5d365898
2012-08-06 17:00:25,724 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-3-thread-48) START, HSMClearTaskVDSCommand(vdsId = ab1e31a6-d055-11e1-bb62-003048348194, taskId=4435f6be-80cb-46c4-96d4-80c186a4cc12), log id: 154a20ca
2012-08-06 17:00:25,746 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-3-thread-48) FINISH, HSMClearTaskVDSCommand, log id: 154a20ca
2012-08-06 17:00:25,746 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-3-thread-48) FINISH, SPMClearTaskVDSCommand, log id: 5d365898
2012-08-06 17:00:25,758 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-3-thread-48) BaseAsyncTask::RemoveTaskFromDB: Removed task 4435f6be-80cb-46c4-96d4-80c186a4cc12 from DataBase
2012-08-06 17:00:25,759 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-3-thread-48) EntityAsyncTask::HandleEndActionResult [within thread]: Removing EntityMultiAsyncTasks object for entity 719bd920-fcaf-4112-b2b7-5f38d1cf7938

Unfortunately, I wasn't able to dig anything up that looked relevant in the vdsm.log. 

~irl