Re: [Users] Import VMs from abandoned Storage Domain

after some help from itamar, got the list of the VMs and Templates, however, the VMs wont import erroring with the following on the engine : 2013-01-15 22:34:18,891 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp--0.0.0.0-8009-19) FINISH, GetVmsInfoVDSCommand, log id: 19ab9695 2013-01-15 22:34:19,953 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageDomainsListVDSCommand] (ajp--0.0.0.0-8009-19) START, GetImageDomainsListVDSCommand(storagePoolId = eb01e934-6413-4ef6-8736-7e9e56af8ed2, ignoreFailoverLimit = false, compatabilityVersion = null, imageGroupId = 081433f7-f630-4d90-bd48-dd4300b33cce), log id: 5a777985 2013-01-15 22:34:19,972 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageDomainsListVDSCommand] (ajp--0.0.0.0-8009-19) FINISH, GetImageDomainsListVDSCommand, return: [], log id: 5a777985 2013-01-15 22:34:19,973 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DoesImageExistVDSCommand] (ajp--0.0.0.0-8009-19) START, DoesImageExistVDSCommand(storagePoolId = eb01e934-6413-4ef6-8736-7e9e56af8ed2, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 84e33e25-f4af-4b3c-9780-8a116a737236, imageGroupId = 081433f7-f630-4d90-bd48-dd4300b33cce, imageId = f4493a50-a0aa-4ea5-9c0d-641381be905a), log id: 59cb3929 2013-01-15 22:34:20,012 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.DoesImageExistVDSCommand] (ajp--0.0.0.0-8009-19) FINISH, DoesImageExistVDSCommand, return: true, log id: 59cb3929 2013-01-15 22:34:20,051 INFO [org.ovirt.engine.core.bll.ImportVmCommand] (pool-3-thread-47) [6b456986] Running command: ImportVmCommand internal: false. Entities affected : ID: 9bd4735c-a02b-403a-8e66-c5679b70e137 Type: Storage 2013-01-15 22:34:20,053 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (pool-3-thread-47) [6b456986] Try to add duplicate values with same name. Type: UNASSIGNED. Value: vmname 2013-01-15 22:34:20,060 INFO [org.ovirt.engine.core.utils.transaction.TransactionSupport] (pool-3-thread-47) [6b456986] transaction rolled back 2013-01-15 22:34:20,064 ERROR [org.ovirt.engine.core.bll.ImportVmCommand] (pool-3-thread-47) [6b456986] Command org.ovirt.engine.core.bll.ImportVmCommand throw exception: java.lang.StringIndexOutOfBoundsException: String index out of range: 6 at java.lang.AbstractStringBuilder.deleteCharAt(AbstractStringBuilder.java:766) [rt.jar:1.6.0_24] at java.lang.StringBuilder.deleteCharAt(StringBuilder.java:280) [rt.jar:1.6.0_24] at org.ovirt.engine.core.bll.ImportVmCommand.auditInvalidInterfaces(ImportVmCommand.java:933) [engine-bll.jar:] at org.ovirt.engine.core.bll.ImportVmCommand.AddVmNetwork(ImportVmCommand.java:801) [engine-bll.jar:] at org.ovirt.engine.core.bll.ImportVmCommand$3.runInTransaction(ImportVmCommand.java:488) [engine-bll.jar:] at org.ovirt.engine.core.bll.ImportVmCommand$3.runInTransaction(ImportVmCommand.java:482) [engine-bll.jar:] at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInNewTransaction(TransactionSupport.java:204) [engine-utils.jar:] at org.ovirt.engine.core.bll.ImportVmCommand.addVmToDb(ImportVmCommand.java:482) [engine-bll.jar:] at org.ovirt.engine.core.bll.ImportVmCommand.executeCommand(ImportVmCommand.java:476) [engine-bll.jar:] at org.ovirt.engine.core.bll.CommandBase.ExecuteWithoutTransaction(CommandBase.java:804) [engine-bll.jar:] at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:896) [engine-bll.jar:] at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1203) [engine-bll.jar:] at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInSuppressed(TransactionSupport.java:168) [engine-utils.jar:] at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:107) [engine-utils.jar:] at org.ovirt.engine.core.bll.CommandBase.Execute(CommandBase.java:911) [engine-bll.jar:] at org.ovirt.engine.core.bll.CommandBase.ExecuteAction(CommandBase.java:268) [engine-bll.jar:] at org.ovirt.engine.core.bll.MultipleActionsRunner.executeValidatedCommands(MultipleActionsRunner.java:182) [engine-bll.jar:] at org.ovirt.engine.core.bll.MultipleActionsRunner.RunCommands(MultipleActionsRunner.java:162) [engine-bll.jar:] at org.ovirt.engine.core.bll.MultipleActionsRunner$1.run(MultipleActionsRunner.java:84) [engine-bll.jar:] at org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil$InternalWrapperRunnable.run(ThreadPoolUtil.java:64) [engine-utils.jar:] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [rt.jar:1.6.0_24] at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) [rt.jar:1.6.0_24] at java.util.concurrent.FutureTask.run(FutureTask.java:166) [rt.jar:1.6.0_24] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) [rt.jar:1.6.0_24] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) [rt.jar:1.6.0_24] at java.lang.Thread.run(Thread.java:679) [rt.jar:1.6.0_24] 2013-01-15 22:34:20,136 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp--0.0.0.0-8009-24) START, GetVmsInfoVDSCommand(storagePoolId = eb01e934-6413-4ef6-8736-7e9e56af8ed2, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 84e33e25-f4af-4b3c-9780-8a116a737236, vmIdList = null), log id: 487cd2b0 2013-01-15 22:34:20,192 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp--0.0.0.0-8009-24) FINISH, GetVmsInfoVDSCommand, log id: 487cd2b0 2013-01-15 22:34:25,092 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetVmsInfoVDSCommand] (ajp--0.0.0.0-8009-18) START, GetVmsInfoVDSCommand(storagePoolId = eb01e934-6413-4ef6-8736-7e9e56af8ed2, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 84e33e25-f4af-4b3c-9780-8a116a737236, vmIdList = null), log id: 259edb64 ## i couldnt see any specific errors in the VDSM log at the same time ... but ... ## Thread-3349::DEBUG::2013-01-15 22:34:21,274::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-3349::DEBUG::2013-01-15 22:34:21,274::resourceManager::538::ResourceManager::(releaseResource) Trying to release resource 'Storage.84e33e25-f4af-4b3c-9780-8a116a737236' Thread-3349::DEBUG::2013-01-15 22:34:21,274::resourceManager::553::ResourceManager::(releaseResource) Released resource 'Storage.84e33e25-f4af-4b3c-9780-8a116a737236' (0 active users) Thread-3349::DEBUG::2013-01-15 22:34:21,275::resourceManager::558::ResourceManager::(releaseResource) Resource 'Storage.84e33e25-f4af-4b3c-9780-8a116a737236' is free, finding out if anyone is waiting for it. Thread-3349::DEBUG::2013-01-15 22:34:21,275::resourceManager::565::ResourceManager::(releaseResource) No one is waiting for resource 'Storage.84e33e25-f4af-4b3c-9780-8a116a737236', Clearing records. Thread-3349::DEBUG::2013-01-15 22:34:21,275::task::978::TaskManager.Task::(_decref) Task=`c67134e1-8d15-4a44-a850-19fe265b0138`::ref 0 aborting False Thread-3350::DEBUG::2013-01-15 22:34:21,591::task::588::TaskManager.Task::(_updateState) Task=`41136614-6ca2-44f6-8fbb-9332b246634f`::moving from state init -> state preparing Thread-3350::INFO::2013-01-15 22:34:21,591::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None) Thread-3350::INFO::2013-01-15 22:34:21,591::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {'84e33e25-f4af-4b3c-9780-8a116a737236': {'delay': '0.00113701820374', 'lastCheck': 1358289253.850596, 'code': 0, 'valid': True}, '9bd4735c-a02b-403a-8e66-c5679b70e137': {'delay': '0.00147080421448', 'lastCheck': 1358289253.3592761, 'code': 0, 'valid': True}} Thread-3350::DEBUG::2013-01-15 22:34:21,591::task::1172::TaskManager.Task::(prepare) Task=`41136614-6ca2-44f6-8fbb-9332b246634f`::finished: {'84e33e25-f4af-4b3c-9780-8a116a737236': {'delay': '0.00113701820374', 'lastCheck': 1358289253.850596, 'code': 0, 'valid': True}, '9bd4735c-a02b-403a-8e66-c5679b70e137': {'delay': '0.00147080421448', 'lastCheck': 1358289253.3592761, 'code': 0, 'valid': True}} Thread-3350::DEBUG::2013-01-15 22:34:21,591::task::588::TaskManager.Task::(_updateState) Task=`41136614-6ca2-44f6-8fbb-9332b246634f`::moving from state preparing -> state finished Thread-3350::DEBUG::2013-01-15 22:34:21,592::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} Thread-3350::DEBUG::2013-01-15 22:34:21,592::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-3350::DEBUG::2013-01-15 22:34:21,592::task::978::TaskManager.Task::(_decref) Task=`41136614-6ca2-44f6-8fbb-9332b246634f`::ref 0 aborting False Thread-3354::DEBUG::2013-01-15 22:34:26,218::BindingXMLRPC::156::vds::(wrapper) [X.X.X.X] Thread-3354::DEBUG::2013-01-15 22:34:26,218::task::588::TaskManager.Task::(_updateState) Task=`5736a0db-3455-4bc5-855e-8823ced25fd5`::moving from state init -> state preparing Thread-3354::INFO::2013-01-15 22:34:26,218::logUtils::37::dispatcher::(wrapper) Run and protect: getVmsInfo(spUUID='eb01e934-6413-4ef6-8736-7e9e56af8ed2', sdUUID='84e33e25-f4af-4b3c-9780-8a116a737236', vmList=[], options=None) Thread-3354::DEBUG::2013-01-15 22:34:26,219::resourceManager::175::ResourceManager.Request::(__init__) ResName=`Storage.84e33e25-f4af-4b3c-9780-8a116a737236`ReqID=`7b3e2e78-36ff-4c8a-a58d-3eddc46583ac`::Request was made in '/usr/share/vdsm/storage/resourceManager.py' line '485' at 'registerResource' Thread-3354::DEBUG::2013-01-15 22:34:26,219::resourceManager::486::ResourceManager::(registerResource) Trying to register resource 'Storage.84e33e25-f4af-4b3c-9780-8a116a737236' for lock type 'shared' Thread-3354::DEBUG::2013-01-15 22:34:26,219::resourceManager::528::ResourceManager::(registerResource) Resource 'Storage.84e33e25-f4af-4b3c-9780-8a116a737236' is free. Now locking as 'shared' (1 active user) Thread-3354::DEBUG::2013-01-15 22:34:26,219::resourceManager::212::ResourceManager.Request::(grant) ResName=`Storage.84e33e25-f4af-4b3c-9780-8a116a737236`ReqID=`7b3e2e78-36ff-4c8a-a58d-3eddc46583ac`::Granted request Thread-3354::DEBUG::2013-01-15 22:34:26,219::task::817::TaskManager.Task::(resourceAcquired) Task=`5736a0db-3455-4bc5-855e-8823ced25fd5`::_resourcesAcquired: Storage.84e33e25-f4af-4b3c-9780-8a116a737236 (shared) Thread-3354::DEBUG::2013-01-15 22:34:26,220::task::978::TaskManager.Task::(_decref) Task=`5736a0db-3455-4bc5-855e-8823ced25fd5`::ref 1 aborting False Thread-3354::INFO::2013-01-15 22:34:26,220::sd::523::Storage.StorageDomain::(getVMsList) vmList=['e820d540-cf1a-4e04-b844-e135440a7a59', '5daf90f1-5c5f-4ee7-9e9b-92f3499d22bd', 'da22fa88-d980-4899-9465-62f031ea3628', '3e5cae8b-c159-4287-b8e9-2330b03ad15c', 'd206228a-4443-4585-a7e5-06cd765933fd', '90684cf5-9f6e-42c6-9e40-0c660fbe13e7', '7fd2d757-38ae-426a-99c5-ca6ef1ac6894', '23946099-3efc-4233-95d5-600ec4148fb3', 'e4bc369f-f032-47b1-881b-d87b3db710c4', '97359c5d-b842-41b2-9d10-a4f57dd737b0', '637f66ee-e25e-4adb-8df9-d386e821a603', '8e2a82e2-3f5b-4ecf-b627-ccf5f2c46e9c', '89e6afd4-ece8-4050-b5c1-0ef164e68c09', 'f646f0b9-1908-4553-b2ed-e95f50065bea', '4ed22988-f432-4462-b618-e99808235d2a', 'e88e7867-9784-4fe7-a4f1-38a90c554360', 'fa9173dd-784f-4556-af30-026bedb681b0', '9bc99ace-ae79-4bb4-b987-5d3dfce9ddb6', '0734c6b3-747d-464f-b72c-598a8ab88703', '8cd1ac79-493f-4ed3-b6d6-8f8637662fb9', '2742510a-6ad5-4a31-b716-7a16b91f24a6', '9eb3a6e4-3bdc-4315-8aa1-82e45cb00fe1', 'ac1bdab3-ec1f-4356-9e7c-e77cd40ce3d7', '2c59d7cf-1eff-4bad-acc1-bcd6d21fbd35', '04f7daeb-aef6-448b-b375-403b311b9da5', '6351905c-6325-4785-be05-61de0524e167', 'bdf112a3-25e5-43f7-a400-e36ee2dadf8e', '7ff432eb-6d1f-4478-89a7-7f22e35811e9', 'ea3e1adc-2d20-47c3-8831-ce7bd8468aa2', '289d28f3-1c99-40d4-968a-c663b125f7c9', 'cac61e84-839c-4630-88a6-4b6c29eb2b6d', '480f1923-f2c3-476c-b03f-ed84cc12c24a', 'ece663d9-4602-453c-be5f-c6de7b252d9f', '54a557ee-eee6-4697-aa06-8491a8654eaf', '047bf8b9-9329-4d32-a349-d3f389036a33', 'a196b7ef-665d-4493-b2f7-b436814af4ef', 'a7bba898-93ab-4b3d-b528-31229285fdef', '3c75a0a5-d19f-46ca-ac5c-e463eabb7c5c', 'be0db44a-0962-48e5-8f13-1df01d31ac53', '859efb65-9b27-460a-92eb-19be6ca57017', 'f05494ba-9638-4e01-87eb-934dc9841350', 'd9c66276-0298-46ed-b522-8a8ebbc66934', '162845b6-0569-4430-b7c7-e6eb668f9430', '9c26e9dc-4812-4749-8761-b85690beb0f3', '160acae8-801f-4503-a5fd-4c1f22cc5b16'] and a looong list of vm details and then ... Thread-3500::DEBUG::2013-01-15 22:37:26,475::task::588::TaskManager.Task::(_updateState) Task=`2383d97a-c121-4f29-b6ab-e588a45b69c7`::moving from state preparing -> state finished Thread-3500::DEBUG::2013-01-15 22:37:26,476::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {'Storage.84e33e25-f4af-4b3c-9780-8a116a737236': < ResourceRef 'Storage.84e33e25-f4af-4b3c-9780-8a116a737236', isValid: 'True' obj: 'None'>} Thread-3500::DEBUG::2013-01-15 22:37:26,476::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-3500::DEBUG::2013-01-15 22:37:26,476::resourceManager::538::ResourceManager::(releaseResource) Trying to release resource 'Storage.84e33e25-f4af-4b3c-9780-8a116a737236' Thread-3500::DEBUG::2013-01-15 22:37:26,476::resourceManager::553::ResourceManager::(releaseResource) Released resource 'Storage.84e33e25-f4af-4b3c-9780-8a116a737236' (0 active users) Thread-3500::DEBUG::2013-01-15 22:37:26,476::resourceManager::558::ResourceManager::(releaseResource) Resource 'Storage.84e33e25-f4af-4b3c-9780-8a116a737236' is free, finding out if anyone is waiting for it. Thread-3500::DEBUG::2013-01-15 22:37:26,476::resourceManager::565::ResourceManager::(releaseResource) No one is waiting for resource 'Storage.84e33e25-f4af-4b3c-9780-8a116a737236', Clearing records. Thread-3500::DEBUG::2013-01-15 22:37:26,477::task::978::TaskManager.Task::(_decref) Task=`2383d97a-c121-4f29-b6ab-e588a45b69c7`::ref 0 aborting False Thread-3504::DEBUG::2013-01-15 22:37:32,088::BindingXMLRPC::156::vds::(wrapper) [X.X.X.X] Thread-3504::DEBUG::2013-01-15 22:37:32,088::task::588::TaskManager.Task::(_updateState) Task=`c9d3cc56-fe66-4466-a020-56cb1f8d1e35`::moving from state init -> state preparing Thread-3504::INFO::2013-01-15 22:37:32,088::logUtils::37::dispatcher::(wrapper) Run and protect: getSpmStatus(spUUID='eb01e934-6413-4ef6-8736-7e9e56af8ed2', options=None) Thread-3504::INFO::2013-01-15 22:37:32,089::logUtils::39::dispatcher::(wrapper) Run and protect: getSpmStatus, Return response: {'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 2}} Thread-3504::DEBUG::2013-01-15 22:37:32,089::task::1172::TaskManager.Task::(prepare) Task=`c9d3cc56-fe66-4466-a020-56cb1f8d1e35`::finished: {'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 2}} Thread-3504::DEBUG::2013-01-15 22:37:32,089::task::588::TaskManager.Task::(_updateState) Task=`c9d3cc56-fe66-4466-a020-56cb1f8d1e35`::moving from state preparing -> state finished Thread-3504::DEBUG::2013-01-15 22:37:32,089::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} Thread-3504::DEBUG::2013-01-15 22:37:32,089::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-3504::DEBUG::2013-01-15 22:37:32,089::task::978::TaskManager.Task::(_decref) Task=`c9d3cc56-fe66-4466-a020-56cb1f8d1e35`::ref 0 aborting False Thread-3505::DEBUG::2013-01-15 22:37:32,096::BindingXMLRPC::156::vds::(wrapper) [X.X.X.X] Alex On 15 January 2013 20:24, Alex Leonhardt <alex.tuxx(a)gmail.com&gt; wrote: -- | RHCE | Senior Systems Engineer | www.vcore.co | www.vsearchcloud.com |