Bug 1007674

Summary:	Imported VM disappears after engine reboot
Product:	[Retired] oVirt	Reporter:	Markus Stockhausen <mst>
Component:	ovirt-engine-core	Assignee:	Nobody's working on this, feel free to take it <nobody>
Status:	CLOSED DUPLICATE	QA Contact:
Severity:	high	Docs Contact:
Priority:	unspecified
Version:	3.3	CC:	acathrow, iheim, jbw, yeylon, yzaslavs
Target Milestone:	---
Target Release:	---
Hardware:	Unspecified
OS:	Unspecified
Whiteboard:
Fixed In Version:		Doc Type:	Bug Fix
Doc Text:		Story Points:	---
Clone Of:		Environment:
Last Closed:	2013-09-13 10:47:46 UTC	Type:	Bug
Regression:	---	Mount Type:	---
Documentation:	---	CRM:
Verified Versions:		Category:	---
oVirt Team:	---	RHEL 7.3 requirements from Atomic Host:
Cloudforms Team:	---	Target Upstream Version:
Embargoed:

Description Markus Stockhausen 2013-09-13 05:52:29 UTC

Description of problem:

Rebooting the ovirt-engine after a successful import of a VM from an export domain makes the VM disappear.


Version-Release number of selected component (if applicable):

ovirt-engine 3.3


How reproducible:

100%

Steps to Reproduce:
1. Import a VM from an export domain into a cluster using web admin
2. Wait for the "successful imported" message in the web admin
3. restart ovirt-engine service
4. Relogin to webadmin interface
5. machine is no longer visible
6. webadmin logs of engine after restart show that a second import was tried and failed.

Actual results:

vm is lost


Expected results:

vm is available

Additional info:

discussion on users mailing list:

http://lists.ovirt.org/pipermail/users/2013-September/016281.html

Comment 1 James Wilson 2013-09-13 10:37:48 UTC

As indicated in the mailing list, I'm experiencing the same issue.

Interestingly, I imported a VM late last nigh, which competed successfully @ 22:26

The following morning @11:17, I restarted ovirt-engine to see it complain that the task was never finished.  The VM is then removed from the UI.

Initial successful import:

2013-09-12 22:26:29,381 INFO  [org.ovirt.engine.core.bll.ImportVmCommand] (pool-6-thread-50) Lock freed to object EngineLock [exclusiveLocks= key: lb.test.lan value: VM_NAME
, sharedLocks= key: 18f3477b-0b65-4255-a56c-379f2c1b326a value: REMOTE_VM
]
2013-09-12 22:26:29,428 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (pool-6-thread-50) Correlation ID: 32298b0a, Job ID: cddb5fa0-2e99-4abd-af50-c933e9a9e762, Call Stack: null, Custom Event ID: -1, Message: Vm lb.test.lan was imported successfully to Data Center local_datacenter, Cluster local_cluster
2013-09-12 22:26:29,429 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (pool-6-thread-50) CommandAsyncTask::HandleEndActionResult [within thread]: EndAction for action type ImportVm completed, handling the result.
2013-09-12 22:26:29,429 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (pool-6-thread-50) CommandAsyncTask::HandleEndActionResult [within thread]: EndAction for action type ImportVm succeeded, clearing tasks.
2013-09-12 22:26:29,440 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-6-thread-50) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 7e8e96c7-e09e-4086-a9ec-f71f93d7ab1f
2013-09-12 22:26:29,440 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-6-thread-50) START, SPMClearTaskVDSCommand( storagePoolId = 534b0cae-ecf2-4500-b71d-6ea72f241b07, ignoreFailoverLimit = false, taskId = 7e8e96c7-e09e-4086-a9ec-f71f93d7ab1f), log id: 3afcedfb
2013-09-12 22:26:29,444 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-6-thread-50) START, HSMClearTaskVDSCommand(HostName = local_host, HostId = 8fd4a450-f420-4c5a-9c44-c23638a0f068, taskId=7e8e96c7-e09e-4086-a9ec-f71f93d7ab1f), log id: 7d9b3f11
2013-09-12 22:26:29,453 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-6-thread-50) FINISH, HSMClearTaskVDSCommand, log id: 7d9b3f11
2013-09-12 22:26:29,453 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-6-thread-50) FINISH, SPMClearTaskVDSCommand, log id: 3afcedfb
2013-09-12 22:26:29,456 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-6-thread-50) BaseAsyncTask::RemoveTaskFromDB: Removed task 7e8e96c7-e09e-4086-a9ec-f71f93d7ab1f from DataBase
2013-09-12 22:26:29,457 INFO  [org.ovirt.engine.core.bll.CommandAsyncTask] (pool-6-thread-50) CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 24175ba4-2ea0-40c9-b4ff-dcb97677ef2c


On ovirt-engine restart:

2013-09-13 11:17:49,682 ERROR [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-6-thread-1) BaseAsyncTask::LogEndTaskFailure: Task 00000000-0000-0000-0000-000000000000 (Parent Command ImportVm, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended with failure:
-- Result: failure
-- Message: Engine was restarted before all tasks of the command could be submitted to vdsm.,
-- Exception: [null]



This also appears to have been reported in:

https://bugzilla.redhat.com/show_bug.cgi?id=1007427

Comment 2 Yair Zaslavsky 2013-09-13 10:47:46 UTC


*** This bug has been marked as a duplicate of bug 1007427 ***