[Users] SD Again

Dafna Ron dron at redhat.com
Mon Jun 10 09:54:33 UTC 2013


I did see issues with your storage in the logs previously to the detach 
issue but they seem to have been resolved so I do not think that they 
are related.

what I can see in the logs is that the detach fails because the 
connectStorageServer command, which suppose to mount the storage, fails:

Mount of `172.16.0.5:/home/external/migration` at 
`/rhev/data-center/mnt/172.16.0.5:_home_external_migration` does not exist

so unless you had a momentary problem in your storage it actually looks 
like a vdsm bug to me.

adding Eduardo, perhaps he will see something I missed, but I think that 
its possibly related to this issue: 
https://bugzilla.redhat.com/show_bug.cgi?id=971291



On 06/10/2013 09:51 AM, Alessandro Bianchi wrote:
>
>
> Il 09/06/2013 08:38, Dafna Ron ha scritto:
>> please attach the full logs :)
>>
>>
>> On 06/06/2013 05:47 PM, Alessandro Bianchi wrote:
>>>
>>>
>>> Il 06/06/2013 15:55, users-request at ovirt.org ha scritto:
>>>> Message: 5
>>>> Date: Thu, 06 Jun 2013 15:38:48 +0300
>>>> From: Dafna Ron<dron at redhat.com>
>>>> To:users at ovirt.org
>>>> Subject: Re: [Users] SD Again
>>>> Message-ID:<51B082D8.5040700 at redhat.com>
>>>> Content-Type: text/plain; charset=ISO-8859-1; format=flowed
>>>>
>>>> You can attach full engine and vdsm logs and I'll try to debug it (I
>>>> need to see the first umount).
>>>>
>>>>
>>>>
>>>> On 06/06/2013 02:25 PM, Alessandro Bianchi wrote:
>>>>> >Hi guys
>>>>> >
>>>>> >I solved manually remounting the Expord SD after restarting the NFS
>>>>> >server and then detaching the export domain
>>>>> >
>>>>> >I wonder if someone knows why this rhings happen ...
>>>>> >
>>>>> >Best regards
>>>>> >
>>>>> >
>>> Hope I'm providing the right stuff
>>>
>>> I may send you all the file but these are the relevat infos I suppose
>>>
>>> vdsm.log
>>>
>>> Thread-281877::DEBUG::2013-06-06 
>>> 12:36:49,932::BindingXMLRPC::161::vds::(wrapper) [172.16.0.5]
>>> Thread-281877::DEBUG::2013-06-06 
>>> 12:36:49,932::task::568::TaskManager.Task::(_updateState) 
>>> Task=`c0d1c115-cb3d-4f39-8c9b-448401097921`::moving from state init 
>>> -> state preparing
>>> Thread-281877::INFO::2013-06-06 
>>> 12:36:49,933::logUtils::41::dispatcher::(wrapper) Run and protect: 
>>> disconnectStorageServer(domType=1, 
>>> spUUID='00000000-0000-0000-0000-000000000000', 
>>> conList=[{'connection': '172.16.0.5:/home/external/migration', 
>>> 'iqn': '', 'portal': '', 'user': '', 'password': '******', 'id': 
>>> '45085cbf-da10-4852-9d85-754707d20a92', 'port': ''}], options=None)
>>> *Thread-281877::DEBUG::2013-06-06 
>>> 12:36:49,933::misc::84::Storage.Misc.excCmd::(<lambda>) 
>>> '/usr/bin/sudo -n /usr/bin/umount -f -l 
>>> /rhev/data-center/mnt/172.16.0.5:_home_external_migration' (cwd None)*
>>> Thread-281877::DEBUG::2013-06-06 
>>> 12:36:50,012::misc::1054::SamplingMethod::(__call__) Trying to enter 
>>> sampling method (storage.sdc.refreshStorage)
>>> Thread-281877::DEBUG::2013-06-06 
>>> 12:36:50,012::misc::1056::SamplingMethod::(__call__) Got in to 
>>> sampling method
>>> Thread-281877::DEBUG::2013-06-06 
>>> 12:36:50,012::misc::1054::SamplingMethod::(__call__) Trying to enter 
>>> sampling method (storage.iscsi.rescan)
>>> Thread-281877::DEBUG::2013-06-06 
>>> 12:36:50,013::misc::1056::SamplingMethod::(__call__) Got in to 
>>> sampling method
>>> Thread-281877::DEBUG::2013-06-06 
>>> 12:36:50,013::misc::84::Storage.Misc.excCmd::(<lambda>) 
>>> '/usr/bin/sudo -n /sbin/iscsiadm -m session -R' (cwd None)
>>> Thread-281877::DEBUG::2013-06-06 
>>> 12:36:50,021::misc::84::Storage.Misc.excCmd::(<lambda>) FAILED: 
>>> <err> = 'iscsiadm: No session found.\n'; <rc> = 21
>>> Thread-281877::DEBUG::2013-06-06 
>>> 12:36:50,021::misc::1064::SamplingMethod::(__call__) Returning last 
>>> result
>>> Thread-281877::DEBUG::2013-06-06 
>>> 12:36:52,029::misc::84::Storage.Misc.excCmd::(<lambda>) 
>>> '/usr/bin/sudo -n /sbin/multipath' (cwd None)
>>> Thread-281877::DEBUG::2013-06-06 
>>> 12:36:52,188::misc::84::Storage.Misc.excCmd::(<lambda>) SUCCESS: 
>>> <err> = ''; <rc> = 0
>>> Thread-281877::DEBUG::2013-06-06 
>>> 12:36:52,188::lvm::477::OperationMutex::(_invalidateAllPvs) 
>>> Operation 'lvm invalidate operation' got the operation mutex
>>> Thread-281877::DEBUG::2013-06-06 
>>> 12:36:52,189::lvm::479::OperationMutex::(_invalidateAllPvs) 
>>> Operation 'lvm invalidate operation' released the operation mutex
>>> Thread-281877::DEBUG::2013-06-06 
>>> 12:36:52,189::lvm::488::OperationMutex::(_invalidateAllVgs) 
>>> Operation 'lvm invalidate operation' got the operation mutex
>>> Thread-281877::DEBUG::2013-06-06 
>>> 12:36:52,189::lvm::490::OperationMutex::(_invalidateAllVgs) 
>>> Operation 'lvm invalidate operation' released the operation mutex
>>> Thread-281877::DEBUG::2013-06-06 
>>> 12:36:52,189::lvm::508::OperationMutex::(_invalidateAllLvs) 
>>> Operation 'lvm invalidate operation' got the operation mutex
>>> Thread-281877::DEBUG::2013-06-06 
>>> 12:36:52,189::lvm::510::OperationMutex::(_invalidateAllLvs) 
>>> Operation 'lvm invalidate operation' released the operation mutex
>>> Thread-281877::DEBUG::2013-06-06 
>>> 12:36:52,189::misc::1064::SamplingMethod::(__call__) Returning last 
>>> result
>>> Thread-281877::INFO::2013-06-06 
>>> 12:36:52,189::logUtils::44::dispatcher::(wrapper) Run and protect: 
>>> disconnectStorageServer, Return response: {'statuslist': [{'status': 
>>> 0, 'id': '45085cbf-da10-4852-9d85-754707d20a92'}]}
>>> Thread-281877::DEBUG::2013-06-06 
>>> 12:36:52,190::task::1151::TaskManager.Task::(prepare) 
>>> Task=`c0d1c115-cb3d-4f39-8c9b-448401097921`::finished: 
>>> {'statuslist': [{'status': 0, 'id': 
>>> '45085cbf-da10-4852-9d85-754707d20a92'}]}
>>> Thread-281877::DEBUG::2013-06-06 
>>> 12:36:52,190::task::568::TaskManager.Task::(_updateState) 
>>> Task=`c0d1c115-cb3d-4f39-8c9b-448401097921`::moving from state 
>>> preparing -> state finished
>>> Thread-281877::DEBUG::2013-06-06 
>>> 12:36:52,190::resourceManager::830::ResourceManager.Owner::(releaseAll) 
>>> Owner.releaseAll requests {} resources {}
>>> Thread-281877::DEBUG::2013-06-06 
>>> 12:36:52,190::resourceManager::864::ResourceManager.Owner::(cancelAll) 
>>> Owner.cancelAll requests {}
>>> Thread-281877::DEBUG::2013-06-06 
>>> 12:36:52,190::task::957::TaskManager.Task::(_decref) 
>>> Task=`c0d1c115-cb3d-4f39-8c9b-448401097921`::ref 0 aborting False
>>> VM Channels Listener::DEBUG::2013-06-06 
>>> 12:36:55,355::vmChannels::61::vds::(_handle_timeouts) Timeout on 
>>> fileno 31.
>>> Thread-281881::DEBUG::2013-06-06 
>>> 12:36:55,705::BindingXMLRPC::161::vds::(wrapper) [172.16.0.5]
>>> Thread-281881::DEBUG::2013-06-06 
>>> 12:36:55,706::task::568::TaskManager.Task::(_updateState) 
>>> Task=`da3a8b8e-60ea-4c84-a72c-8feea05c7178`::moving from state init 
>>> -> state preparing
>>> Thread-281881::INFO::2013-06-06 
>>> 12:36:55,706::logUtils::41::dispatcher::(wrapper) Run and protect: 
>>> getSpmStatus(spUUID='d76c9edf-34cb-48eb-b53b-32d27bedc26a', 
>>> options=None)
>>> Thread-281881::INFO::2013-06-06 
>>> 12:36:55,706::logUtils::44::dispatcher::(wrapper) Run and protect: 
>>> getSpmStatus, Return response: {'spm_st': {'spmId': 1, 'spmStatus': 
>>> 'SPM', 'spmLver': 3}}
>>> Thread-281881::DEBUG::2013-06-06 
>>> 12:36:55,706::task::1151::TaskManager.Task::(prepare) 
>>> Task=`da3a8b8e-60ea-4c84-a72c-8feea05c7178`::finished: {'spm_st': 
>>> {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 3}}
>>> Thread-281881::DEBUG::2013-06-06 
>>> 12:36:55,706::task::568::TaskManager.Task::(_updateState) 
>>> Task=`da3a8b8e-60ea-4c84-a72c-8feea05c7178`::moving from state 
>>> preparing -> state finished
>>> Thread-281881::DEBUG::2013-06-06 
>>> 12:36:55,706::resourceManager::830::ResourceManager.Owner::(releaseAll) 
>>> Owner.releaseAll requests {} resources {}
>>> Thread-281881::DEBUG::2013-06-06 
>>> 12:36:55,706::resourceManager::864::ResourceManager.Owner::(cancelAll) 
>>> Owner.cancelAll requests {}
>>> Thread-281881::DEBUG::2013-06-06 
>>> 12:36:55,707::task::957::TaskManager.Task::(_decref) 
>>> Task=`da3a8b8e-60ea-4c84-a72c-8feea05c7178`::ref 0 aborting False
>>> Thread-281882::DEBUG::2013-06-06 
>>> 12:36:55,709::BindingXMLRPC::161::vds::(wrapper) [172.16.0.5]
>>> Thread-281882::DEBUG::2013-06-06 
>>> 12:36:55,709::task::568::TaskManager.Task::(_updateState) 
>>> Task=`efc1142e-49bd-4649-8b3d-57e0a408460a`::moving from state init 
>>> -> state preparing
>>> Thread-281882::INFO::2013-06-06 
>>> 12:36:55,709::logUtils::41::dispatcher::(wrapper) Run and protect: 
>>> getStoragePoolInfo(spUUID='d76c9edf-34cb-48eb-b53b-32d27bedc26a', 
>>> options=None)
>>> Thread-281882::DEBUG::2013-06-06 
>>> 12:36:55,709::resourceManager::190::ResourceManager.Request::(__init__) 
>>> ResName=`Storage.d76c9edf-34cb-48eb-b53b-32d27bedc26a`ReqID=`9b522d87-e9b5-46ae-932c-c3102d25ef28`::Request 
>>> was made in '/usr/share/vdsm/storage/resourceManager.py' line '189' 
>>> at '__init__'
>>> Thread-281882::DEBUG::2013-06-06 
>>> 12:36:55,709::resourceManager::504::ResourceManager::(registerResource) 
>>> Trying to register resource 
>>> 'Storage.d76c9edf-34cb-48eb-b53b-32d27bedc26a' for lock type 'shared'
>>> Thread-281882::DEBUG::2013-06-06 
>>> 12:36:55,710::resourceManager::547::ResourceManager::(registerResource) 
>>> Resource 'Storage.d76c9edf-34cb-48eb-b53b-32d27bedc26a' is free. Now 
>>> locking as 'shared' (1 active user)
>>> Thread-281882::DEBUG::2013-06-06 
>>> 12:36:55,710::resourceManager::227::ResourceManager.Request::(grant) 
>>> ResName=`Storage.d76c9edf-34cb-48eb-b53b-32d27bedc26a`ReqID=`9b522d87-e9b5-46ae-932c-c3102d25ef28`::Granted 
>>> request
>>> Thread-281882::DEBUG::2013-06-06 
>>> 12:36:55,710::task::794::TaskManager.Task::(resourceAcquired) 
>>> Task=`efc1142e-49bd-4649-8b3d-57e0a408460a`::_resourcesAcquired: 
>>> Storage.d76c9edf-34cb-48eb-b53b-32d27bedc26a (shared)
>>> Thread-281882::DEBUG::2013-06-06 
>>> 12:36:55,710::task::957::TaskManager.Task::(_decref) 
>>> Task=`efc1142e-49bd-4649-8b3d-57e0a408460a`::ref 1 aborting False
>>> Thread-281882::INFO::2013-06-06 
>>> 12:36:55,711::logUtils::44::dispatcher::(wrapper) Run and protect: 
>>> getStoragePoolInfo, Return response: {'info': {'spm_id': 1, 
>>> 'master_uuid': 'fb8feb05-b605-4014-ac05-14b0fb1e37e9', 'name': 
>>> 'nodo1-Local', 'version': '3', 'domains': 
>>> 'fb8feb05-b605-4014-ac05-14b0fb1e37e9:Active,e79cd423-ae17-4f8b-9f53-28d851cc9822:Attached', 
>>> 'pool_status': 'connected', 'isoprefix': '', 'type': 'LOCALFS', 
>>> 'master_ver': 1, 'lver': 3}, 'dominfo': 
>>> {'fb8feb05-b605-4014-ac05-14b0fb1e37e9': {'status': 'Active', 
>>> 'diskfree': '348445036544', 'alerts': [], 'disktotal': 
>>> '492120522752'}, 'e79cd423-ae17-4f8b-9f53-28d851cc9822': {'status': 
>>> 'Attached'}}}
>>> Thread-281882::DEBUG::2013-06-06 
>>> 12:36:55,711::task::1151::TaskManager.Task::(prepare) 
>>> Task=`efc1142e-49bd-4649-8b3d-57e0a408460a`::finished: {'info': 
>>> {'spm_id': 1, 'master_uuid': 'fb8feb05-b605-4014-ac05-14b0fb1e37e9', 
>>> 'name': 'nodo1-Local', 'version': '3', 'domains': 
>>> 'fb8feb05-b605-4014-ac05-14b0fb1e37e9:Active,e79cd423-ae17-4f8b-9f53-28d851cc9822:Attached', 
>>> 'pool_status': 'connected', 'isoprefix': '', 'type': 'LOCALFS', 
>>> 'master_ver': 1, 'lver': 3}, 'dominfo': 
>>> {'fb8feb05-b605-4014-ac05-14b0fb1e37e9': {'status': 'Active', 
>>> 'diskfree': '348445036544', 'alerts': [], 'disktotal': 
>>> '492120522752'}, 'e79cd423-ae17-4f8b-9f53-28d851cc9822': {'status': 
>>> 'Attached'}}}
>>> Thread-281882::DEBUG::2013-06-06 
>>> 12:36:55,711::task::568::TaskManager.Task::(_updateState) 
>>> Task=`efc1142e-49bd-4649-8b3d-57e0a408460a`::moving from state 
>>> preparing -> state finished
>>> Thread-281882::DEBUG::2013-06-06 
>>> 12:36:55,711::resourceManager::830::ResourceManager.Owner::(releaseAll) 
>>> Owner.releaseAll requests {} resources 
>>> {'Storage.d76c9edf-34cb-48eb-b53b-32d27bedc26a': < ResourceRef 
>>> 'Storage.d76c9edf-34cb-48eb-b53b-32d27bedc26a', isValid: 'True' obj: 
>>> 'None'>}
>>> Thread-281882::DEBUG::2013-06-06 
>>> 12:36:55,711::resourceManager::864::ResourceManager.Owner::(cancelAll) 
>>> Owner.cancelAll requests {}
>>> Thread-281882::DEBUG::2013-06-06 
>>> 12:36:55,711::resourceManager::557::ResourceManager::(releaseResource) 
>>> Trying to release resource 
>>> 'Storage.d76c9edf-34cb-48eb-b53b-32d27bedc26a'
>>> Thread-281882::DEBUG::2013-06-06 
>>> 12:36:55,711::resourceManager::573::ResourceManager::(releaseResource) 
>>> Released resource 'Storage.d76c9edf-34cb-48eb-b53b-32d27bedc26a' (0 
>>> active users)
>>> Thread-281882::DEBUG::2013-06-06 
>>> 12:36:55,711::resourceManager::578::ResourceManager::(releaseResource) 
>>> Resource 'Storage.d76c9edf-34cb-48eb-b53b-32d27bedc26a' is free, 
>>> finding out if anyone is waiting for it.
>>> Thread-281882::DEBUG::2013-06-06 
>>> 12:36:55,712::resourceManager::585::ResourceManager::(releaseResource) 
>>> No one is waiting for resource 
>>> 'Storage.d76c9edf-34cb-48eb-b53b-32d27bedc26a', Clearing records.
>>> Thread-281882::DEBUG::2013-06-06 
>>> 12:36:55,712::task::957::TaskManager.Task::(_decref) 
>>> Task=`efc1142e-49bd-4649-8b3d-57e0a408460a`::ref 0 aborting False
>>> Thread-281883::DEBUG::2013-06-06 
>>> 12:36:57,645::task::568::TaskManager.Task::(_updateState) 
>>> Task=`9f7eee6f-a85f-4922-a229-300f57133718`::moving from state init 
>>> -> state preparing
>>> Thread-281883::INFO::2013-06-06 
>>> 12:36:57,645::logUtils::41::dispatcher::(wrapper) Run and protect: 
>>> repoStats(options=None)
>>> Thread-281883::INFO::2013-06-06 
>>> 12:36:57,645::logUtils::44::dispatcher::(wrapper) Run and protect: 
>>> repoStats, Return response: {'fb8feb05-b605-4014-ac05-14b0fb1e37e9': 
>>> {'delay': '0.0230140686035', 'lastCheck': '2.8', 'code': 0, 'valid': 
>>> True}}
>>> Thread-281883::DEBUG::2013-06-06 
>>> 12:36:57,645::task::1151::TaskManager.Task::(prepare) 
>>> Task=`9f7eee6f-a85f-4922-a229-300f57133718`::finished: 
>>> {'fb8feb05-b605-4014-ac05-14b0fb1e37e9': {'delay': 
>>> '0.0230140686035', 'lastCheck': '2.8', 'code': 0, 'valid': True}}
>>> Thread-281883::DEBUG::2013-06-06 
>>> 12:36:57,645::task::568::TaskManager.Task::(_updateState) 
>>> Task=`9f7eee6f-a85f-4922-a229-300f57133718`::moving from state 
>>> preparing -> state finished
>>> Thread-281883::DEBUG::2013-06-06 
>>> 12:36:57,646::resourceManager::830::ResourceManager.Owner::(releaseAll) 
>>> Owner.releaseAll requests {} resources {}
>>> Thread-281883::DEBUG::2013-06-06 
>>> 12:36:57,646::resourceManager::864::ResourceManager.Owner::(cancelAll) 
>>> Owner.cancelAll requests {}
>>> Thread-281883::DEBUG::2013-06-06 
>>> 12:36:57,646::task::957::TaskManager.Task::(_decref) 
>>> Task=`9f7eee6f-a85f-4922-a229-300f57133718`::ref 0 aborting False
>>> VM Channels Listener::DEBUG::2013-06-06 
>>> 12:36:58,358::vmChannels::61::vds::(_handle_timeouts) Timeout on 
>>> fileno 85.
>>> Thread-281885::DEBUG::2013-06-06 
>>> 12:36:58,994::BindingXMLRPC::161::vds::(wrapper) [172.16.0.5]
>>> Thread-281885::DEBUG::2013-06-06 
>>> 12:36:58,994::task::568::TaskManager.Task::(_updateState) 
>>> Task=`5b718b7c-415a-4995-b0be-4773d5b2dbad`::moving from state init 
>>> -> state preparing
>>> Thread-281885::INFO::2013-06-06 
>>> 12:36:58,994::logUtils::41::dispatcher::(wrapper) Run and protect: 
>>> validateStorageServerConnection(domType=1, 
>>> spUUID='00000000-0000-0000-0000-000000000000', 
>>> conList=[{'connection': '172.16.0.5:/home/external/migration', 
>>> 'iqn': '', 'portal': '', 'user': '', 'password': '******', 'id': 
>>> '45085cbf-da10-4852-9d85-754707d20a92', 'port': ''}], options=None)
>>> Thread-281885::INFO::2013-06-06 
>>> 12:36:58,994::logUtils::44::dispatcher::(wrapper) Run and protect: 
>>> validateStorageServerConnection, Return response: {'statuslist': 
>>> [{'status': 0, 'id': '45085cbf-da10-4852-9d85-754707d20a92'}]}
>>> Thread-281885::DEBUG::2013-06-06 
>>> 12:36:58,994::task::1151::TaskManager.Task::(prepare) 
>>> Task=`5b718b7c-415a-4995-b0be-4773d5b2dbad`::finished: 
>>> {'statuslist': [{'status': 0, 'id': 
>>> '45085cbf-da10-4852-9d85-754707d20a92'}]}
>>> Thread-281885::DEBUG::2013-06-06 
>>> 12:36:58,995::task::568::TaskManager.Task::(_updateState) 
>>> Task=`5b718b7c-415a-4995-b0be-4773d5b2dbad`::moving from state 
>>> preparing -> state finished
>>>
>>> And here is the engine part:
>>>
>>> 2013-06-06 12:35:45,693 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] 
>>> (pool-3-thread-50) START, HSMClearTaskVDSCommand(HostName = nodo1, 
>>> HostId = 3156bdac-ebfb-44cf-bea6-53d668b74a10, 
>>> taskId=6592e0f7-4df3-404c-b1c4-6e95715453ef), log id: 4356a978
>>> 2013-06-06 12:35:45,696 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] 
>>> (pool-3-thread-50) FINISH, HSMClearTaskVDSCommand, log id: 4356a978
>>> 2013-06-06 12:35:45,697 INFO 
>>> [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] 
>>> (pool-3-thread-50) FINISH, SPMClearTaskVDSCommand, log id: 121e765
>>> 2013-06-06 12:35:45,708 INFO 
>>> [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-3-thread-50) 
>>> BaseAsyncTask::RemoveTaskFromDB: Removed task 
>>> 6592e0f7-4df3-404c-b1c4-6e95715453ef from DataBase
>>> 2013-06-06 12:35:45,708 INFO 
>>> [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-3-thread-50) 
>>> EntityAsyncTask::HandleEndActionResult [within thread]: Removing 
>>> EntityMultiAsyncTasks object for entity 
>>> 2518c791-17fc-413e-a353-8437b6fcf478
>>> 2013-06-06 12:36:03,193 INFO 
>>> [org.ovirt.engine.core.bll.LoginUserCommand] (ajp--127.0.0.1-8702-5) 
>>> Running command: LoginUserCommand internal: false.
>>> 2013-06-06 12:36:03,225 WARN 
>>> [org.ovirt.engine.core.bll.GetConfigurationValueQuery] 
>>> (ajp--127.0.0.1-8702-5) calling GetConfigurationValueQuery 
>>> (ApplicationMode) with null version, using default general for version
>>> 2013-06-06 12:36:03,225 WARN 
>>> [org.ovirt.engine.core.bll.GetConfigurationValueQuery] 
>>> (ajp--127.0.0.1-8702-5) calling GetConfigurationValueQuery 
>>> (VdcVersion) with null version, using default general for version
>>> 2013-06-06 12:36:47,337 INFO 
>>> [org.ovirt.engine.core.bll.storage.DeactivateStorageDomainCommand] 
>>> (ajp--127.0.0.1-8702-5) Lock Acquired to object EngineLock 
>>> [exclusiveLocks= key: e79cd423-ae17-4f8b-9f53-28d851cc9822 value: 
>>> STORAGE
>>> , sharedLocks= ]
>>> 2013-06-06 12:36:47,386 INFO 
>>> [org.ovirt.engine.core.bll.storage.DeactivateStorageDomainCommand] 
>>> (pool-3-thread-50) [787573d0] Running command: 
>>> DeactivateStorageDomainCommand internal: false. Entities affected :  
>>> ID: e79cd423-ae17-4f8b-9f53-28d851cc9822 Type: Storage
>>> 2013-06-06 12:36:47,394 INFO 
>>> [org.ovirt.engine.core.vdsbroker.irsbroker.DeactivateStorageDomainVDSCommand] 
>>> (pool-3-thread-50) [787573d0] START, 
>>> DeactivateStorageDomainVDSCommand( storagePoolId = 
>>> d76c9edf-34cb-48eb-b53b-32d27bedc26a, ignoreFailoverLimit = false, 
>>> compatabilityVersion = null, storageDomainId = 
>>> e79cd423-ae17-4f8b-9f53-28d851cc9822, masterDomainId = 
>>> 00000000-0000-0000-0000-000000000000, masterVersion = 1), log id: 
>>> 94abeb9
>>> 2013-06-06 12:36:47,406 INFO 
>>> [org.ovirt.engine.core.vdsbroker.irsbroker.DeactivateStorageDomainVDSCommand] 
>>> (pool-3-thread-50) [787573d0] FINISH, 
>>> DeactivateStorageDomainVDSCommand, log id: 94abeb9
>>> 2013-06-06 12:36:47,406 INFO 
>>> [org.ovirt.engine.core.bll.storage.DeactivateStorageDomainCommand] 
>>> (pool-3-thread-50) [787573d0] Lock freed to object EngineLock 
>>> [exclusiveLocks= key: e79cd423-ae17-4f8b-9f53-28d851cc9822 value: 
>>> STORAGE
>>> , sharedLocks= ]
>>> 2013-06-06 12:36:47,409 INFO 
>>> [org.ovirt.engine.core.bll.storage.AfterDeactivateSingleAsyncOperation] 
>>> (pool-3-thread-47) [787573d0] After deactivate treatment vds: 
>>> nodo1,pool nodo1-Local
>>> 2013-06-06 12:36:47,412 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.RefreshStoragePoolVDSCommand] 
>>> (pool-3-thread-47) [787573d0] START, 
>>> RefreshStoragePoolVDSCommand(HostName = nodo1, HostId = 
>>> 3156bdac-ebfb-44cf-bea6-53d668b74a10, storagePoolId = 
>>> d76c9edf-34cb-48eb-b53b-32d27bedc26a, 
>>> masterStorageDomainId=fb8feb05-b605-4014-ac05-14b0fb1e37e9, 
>>> msterVersion=1), log id: 2ca9a709
>>> 2013-06-06 12:36:49,927 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.RefreshStoragePoolVDSCommand] 
>>> (pool-3-thread-47) [787573d0] FINISH, RefreshStoragePoolVDSCommand, 
>>> log id: 2ca9a709
>>> 2013-06-06 12:36:49,929 INFO 
>>> [org.ovirt.engine.core.bll.storage.RemoveStorageServerConnectionCommand] 
>>> (pool-3-thread-50) [3bf3ce09] Running command: 
>>> RemoveStorageServerConnectionCommand internal: true. Entities 
>>> affected :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System
>>> 2013-06-06 12:36:49,931 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] 
>>> (pool-3-thread-50) [3bf3ce09] START, 
>>> DisconnectStorageServerVDSCommand(HostName = nodo1, HostId = 
>>> 3156bdac-ebfb-44cf-bea6-53d668b74a10, storagePoolId = 
>>> 00000000-0000-0000-0000-000000000000, storageType = NFS, 
>>> connectionList = [{ id: 45085cbf-da10-4852-9d85-754707d20a92, 
>>> connection: 172.16.0.5:/home/external/migration, iqn: null, vfsType: 
>>> null, mountOptions: null, nfsVersion: null, nfsRetrans: null, 
>>> nfsTimeo: null };]), log id: ae4d339
>>> 2013-06-06 12:36:50,809 INFO 
>>> [org.ovirt.engine.core.bll.AsyncTaskManager] 
>>> (DefaultQuartzScheduler_Worker-9) Setting new tasks map. The map 
>>> contains now 0 tasks
>>> 2013-06-06 12:36:50,809 INFO 
>>> [org.ovirt.engine.core.bll.AsyncTaskManager] 
>>> (DefaultQuartzScheduler_Worker-9) Cleared all tasks of pool 
>>> d76c9edf-34cb-48eb-b53b-32d27bedc26a.
>>> 2013-06-06 12:36:50,811 INFO 
>>> [org.ovirt.engine.core.bll.AsyncTaskManager] 
>>> (DefaultQuartzScheduler_Worker-9) Could not find vds that is spm and 
>>> non-operational.
>>> 2013-06-06 12:36:52,193 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] 
>>> (pool-3-thread-50) [3bf3ce09] FINISH, 
>>> DisconnectStorageServerVDSCommand, return: 
>>> {45085cbf-da10-4852-9d85-754707d20a92=0}, log id: ae4d339
>>> 2013-06-06 12:36:58,978 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-50) [65c04a25] Running command: 
>>> DetachStorageDomainFromPoolCommand internal: false. Entities 
>>> affected :  ID: e79cd423-ae17-4f8b-9f53-28d851cc9822 Type: Storage
>>> 2013-06-06 12:36:58,978 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-50) [65c04a25] Start detach storage domain
>>> 2013-06-06 12:36:58,986 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-50) [65c04a25]  Detach storage domain: before connect
>>> 2013-06-06 12:36:58,992 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] 
>>> (pool-3-thread-47) [7345dde6] START, 
>>> ValidateStorageServerConnectionVDSCommand(HostName = nodo1, HostId = 
>>> 3156bdac-ebfb-44cf-bea6-53d668b74a10, storagePoolId = 
>>> 00000000-0000-0000-0000-000000000000, storageType = NFS, 
>>> connectionList = [{ id: 45085cbf-da10-4852-9d85-754707d20a92, 
>>> connection: 172.16.0.5:/home/external/migration, iqn: null, vfsType: 
>>> null, mountOptions: null, nfsVersion: null, nfsRetrans: null, 
>>> nfsTimeo: null };]), log id: 313568f0
>>> 2013-06-06 12:36:58,997 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] 
>>> (pool-3-thread-47) [7345dde6] FINISH, 
>>> ValidateStorageServerConnectionVDSCommand, return: 
>>> {45085cbf-da10-4852-9d85-754707d20a92=0}, log id: 313568f0
>>> 2013-06-06 12:36:58,998 INFO 
>>> [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] 
>>> (pool-3-thread-47) [7345dde6] Running command: 
>>> ConnectStorageToVdsCommand internal: true. Entities affected : ID: 
>>> aaa00000-0000-0000-0000-123456789aaa Type: System
>>> 2013-06-06 12:36:58,999 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] 
>>> (pool-3-thread-47) [7345dde6] START, 
>>> ConnectStorageServerVDSCommand(HostName = nodo1, HostId = 
>>> 3156bdac-ebfb-44cf-bea6-53d668b74a10, storagePoolId = 
>>> 00000000-0000-0000-0000-000000000000, storageType = NFS, 
>>> connectionList = [{ id: 45085cbf-da10-4852-9d85-754707d20a92, 
>>> connection: 172.16.0.5:/home/external/migration, iqn: null, vfsType: 
>>> null, mountOptions: null, nfsVersion: null, nfsRetrans: null, 
>>> nfsTimeo: null };]), log id: 54464e6b
>>> 2013-06-06 12:36:59,149 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] 
>>> (pool-3-thread-47) [7345dde6] FINISH, 
>>> ConnectStorageServerVDSCommand, return: 
>>> {45085cbf-da10-4852-9d85-754707d20a92=100}, log id: 54464e6b
>>> 2013-06-06 12:36:59,150 ERROR 
>>> [org.ovirt.engine.core.bll.storage.NFSStorageHelper] 
>>> (pool-3-thread-47) [7345dde6] The connection with details 
>>> 172.16.0.5:/home/external/migration failed because of error code 100 
>>> and error message is: generalexception
>>> 2013-06-06 12:36:59,175 ERROR 
>>> [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] 
>>> (pool-3-thread-47) [7345dde6] Transaction rolled-back for command: 
>>> org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand.
>>> 2013-06-06 12:36:59,175 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-50) [65c04a25]  Detach storage domain: after connect
>>> 2013-06-06 12:36:59,176 INFO 
>>> [org.ovirt.engine.core.vdsbroker.irsbroker.DetachStorageDomainVDSCommand] 
>>> (pool-3-thread-50) [65c04a25] START, DetachStorageDomainVDSCommand( 
>>> storagePoolId = d76c9edf-34cb-48eb-b53b-32d27bedc26a, 
>>> ignoreFailoverLimit = false, compatabilityVersion = null, 
>>> storageDomainId = e79cd423-ae17-4f8b-9f53-28d851cc9822, 
>>> masterDomainId = 00000000-0000-0000-0000-000000000000, masterVersion 
>>> = 1, force = false), log id: 51ea7302
>>> 2013-06-06 12:37:01,354 ERROR 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] 
>>> (pool-3-thread-50) [65c04a25] Failed in DetachStorageDomainVDS method
>>> 2013-06-06 12:37:01,354 ERROR 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] 
>>> (pool-3-thread-50) [65c04a25] Error code StorageDomainDoesNotExist 
>>> and error message IRSGenericException: IRSErrorException: Failed to 
>>> DetachStorageDomainVDS, error = Storage domain does not exist: 
>>> ('e79cd423-ae17-4f8b-9f53-28d851cc9822',)
>>> 2013-06-06 12:37:01,355 ERROR 
>>> [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] 
>>> (pool-3-thread-50) [65c04a25] 
>>> IrsBroker::Failed::DetachStorageDomainVDS due to: IRSErrorException: 
>>> IRSGenericException: IRSErrorException: Failed to 
>>> DetachStorageDomainVDS, error = Storage domain does not exist: 
>>> ('e79cd423-ae17-4f8b-9f53-28d851cc9822',)
>>> 2013-06-06 12:37:01,356 INFO 
>>> [org.ovirt.engine.core.vdsbroker.irsbroker.DetachStorageDomainVDSCommand] 
>>> (pool-3-thread-50) [65c04a25] FINISH, DetachStorageDomainVDSCommand, 
>>> log id: 51ea7302
>>> 2013-06-06 12:37:01,356 ERROR 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-50) [65c04a25] Command 
>>> org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand 
>>> throw Vdc Bll exception. With error message VdcBLLException: 
>>> org.ovirt.engine.core.vdsbroker.irsbroker.IRSErrorException: 
>>> IRSGenericException: IRSErrorException: Failed to 
>>> DetachStorageDomainVDS, error = Storage domain does not exist: 
>>> ('e79cd423-ae17-4f8b-9f53-28d851cc9822',)
>>> 2013-06-06 12:37:01,404 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-50) [65c04a25] Command 
>>> [id=9f02402d-dca7-4631-877b-5a4e6880e410]: Compensating 
>>> CHANGED_STATUS_ONLY of 
>>> org.ovirt.engine.core.common.businessentities.StoragePoolIsoMap; 
>>> snapshot: EntityStatusSnapshot [id=storagePoolId = 
>>> d76c9edf-34cb-48eb-b53b-32d27bedc26a, storageId = 
>>> e79cd423-ae17-4f8b-9f53-28d851cc9822, status=Maintenance].
>>> 2013-06-06 12:37:03,211 INFO 
>>> [org.ovirt.engine.core.bll.LoginUserCommand] 
>>> (ajp--127.0.0.1-8702-14) Running command: LoginUserCommand internal: 
>>> false.
>>> 2013-06-06 12:37:03,236 WARN 
>>> [org.ovirt.engine.core.bll.GetConfigurationValueQuery] 
>>> (ajp--127.0.0.1-8702-14) calling GetConfigurationValueQuery 
>>> (ApplicationMode) with null version, using default general for version
>>> 2013-06-06 12:37:03,237 WARN 
>>> [org.ovirt.engine.core.bll.GetConfigurationValueQuery] 
>>> (ajp--127.0.0.1-8702-14) calling GetConfigurationValueQuery 
>>> (VdcVersion) with null version, using default general for version
>>> 2013-06-06 12:37:13,261 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-50) [12b7aaad] Running command: 
>>> DetachStorageDomainFromPoolCommand internal: false. Entities 
>>> affected :  ID: e79cd423-ae17-4f8b-9f53-28d851cc9822 Type: Storage
>>> 2013-06-06 12:37:13,261 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-50) [12b7aaad] Start detach storage domain
>>> 2013-06-06 12:37:13,269 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-50) [12b7aaad]  Detach storage domain: before connect
>>> 2013-06-06 12:37:13,275 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] 
>>> (pool-3-thread-47) [66b8de5c] START, 
>>> ValidateStorageServerConnectionVDSCommand(HostName = nodo1, HostId = 
>>> 3156bdac-ebfb-44cf-bea6-53d668b74a10, storagePoolId = 
>>> 00000000-0000-0000-0000-000000000000, storageType = NFS, 
>>> connectionList = [{ id: 45085cbf-da10-4852-9d85-754707d20a92, 
>>> connection: 172.16.0.5:/home/external/migration, iqn: null, vfsType: 
>>> null, mountOptions: null, nfsVersion: null, nfsRetrans: null, 
>>> nfsTimeo: null };]), log id: 6183dba8
>>> 2013-06-06 12:37:13,279 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] 
>>> (pool-3-thread-47) [66b8de5c] FINISH, 
>>> ValidateStorageServerConnectionVDSCommand, return: 
>>> {45085cbf-da10-4852-9d85-754707d20a92=0}, log id: 6183dba8
>>> 2013-06-06 12:37:13,280 INFO 
>>> [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] 
>>> (pool-3-thread-47) [66b8de5c] Running command: 
>>> ConnectStorageToVdsCommand internal: true. Entities affected : ID: 
>>> aaa00000-0000-0000-0000-123456789aaa Type: System
>>> 2013-06-06 12:37:13,281 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] 
>>> (pool-3-thread-47) [66b8de5c] START, 
>>> ConnectStorageServerVDSCommand(HostName = nodo1, HostId = 
>>> 3156bdac-ebfb-44cf-bea6-53d668b74a10, storagePoolId = 
>>> 00000000-0000-0000-0000-000000000000, storageType = NFS, 
>>> connectionList = [{ id: 45085cbf-da10-4852-9d85-754707d20a92, 
>>> connection: 172.16.0.5:/home/external/migration, iqn: null, vfsType: 
>>> null, mountOptions: null, nfsVersion: null, nfsRetrans: null, 
>>> nfsTimeo: null };]), log id: 29f6f005
>>> 2013-06-06 12:37:13,317 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] 
>>> (pool-3-thread-47) [66b8de5c] FINISH, 
>>> ConnectStorageServerVDSCommand, return: 
>>> {45085cbf-da10-4852-9d85-754707d20a92=100}, log id: 29f6f005
>>> 2013-06-06 12:37:13,318 ERROR 
>>> [org.ovirt.engine.core.bll.storage.NFSStorageHelper] 
>>> (pool-3-thread-47) [66b8de5c] The connection with details 
>>> 172.16.0.5:/home/external/migration failed because of error code 100 
>>> and error message is: generalexception
>>> 2013-06-06 12:37:13,320 ERROR 
>>> [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] 
>>> (pool-3-thread-47) [66b8de5c] Transaction rolled-back for command: 
>>> org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand.
>>> 2013-06-06 12:37:13,320 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-50) [12b7aaad]  Detach storage domain: after connect
>>> 2013-06-06 12:37:13,321 INFO 
>>> [org.ovirt.engine.core.vdsbroker.irsbroker.DetachStorageDomainVDSCommand] 
>>> (pool-3-thread-50) [12b7aaad] START, DetachStorageDomainVDSCommand( 
>>> storagePoolId = d76c9edf-34cb-48eb-b53b-32d27bedc26a, 
>>> ignoreFailoverLimit = false, compatabilityVersion = null, 
>>> storageDomainId = e79cd423-ae17-4f8b-9f53-28d851cc9822, 
>>> masterDomainId = 00000000-0000-0000-0000-000000000000, masterVersion 
>>> = 1, force = false), log id: 78d0cd25
>>> 2013-06-06 12:37:15,559 ERROR 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] 
>>> (pool-3-thread-50) [12b7aaad] Failed in DetachStorageDomainVDS method
>>> 2013-06-06 12:37:15,559 ERROR 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] 
>>> (pool-3-thread-50) [12b7aaad] Error code StorageDomainDoesNotExist 
>>> and error message IRSGenericException: IRSErrorException: Failed to 
>>> DetachStorageDomainVDS, error = Storage domain does not exist: 
>>> ('e79cd423-ae17-4f8b-9f53-28d851cc9822',)
>>> 2013-06-06 12:37:15,559 ERROR 
>>> [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] 
>>> (pool-3-thread-50) [12b7aaad] 
>>> IrsBroker::Failed::DetachStorageDomainVDS due to: IRSErrorException: 
>>> IRSGenericException: IRSErrorException: Failed to 
>>> DetachStorageDomainVDS, error = Storage domain does not exist: 
>>> ('e79cd423-ae17-4f8b-9f53-28d851cc9822',)
>>> 2013-06-06 12:37:15,561 INFO 
>>> [org.ovirt.engine.core.vdsbroker.irsbroker.DetachStorageDomainVDSCommand] 
>>> (pool-3-thread-50) [12b7aaad] FINISH, DetachStorageDomainVDSCommand, 
>>> log id: 78d0cd25
>>> 2013-06-06 12:37:15,561 ERROR 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-50) [12b7aaad] Command 
>>> org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand 
>>> throw Vdc Bll exception. With error message VdcBLLException: 
>>> org.ovirt.engine.core.vdsbroker.irsbroker.IRSErrorException: 
>>> IRSGenericException: IRSErrorException: Failed to 
>>> DetachStorageDomainVDS, error = Storage domain does not exist: 
>>> ('e79cd423-ae17-4f8b-9f53-28d851cc9822',)
>>> 2013-06-06 12:37:15,563 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-50) [12b7aaad] Command 
>>> [id=4c15220c-b7a6-4b9d-85f7-b37d49bd51ee]: Compensating 
>>> CHANGED_STATUS_ONLY of 
>>> org.ovirt.engine.core.common.businessentities.StoragePoolIsoMap; 
>>> snapshot: EntityStatusSnapshot [id=storagePoolId = 
>>> d76c9edf-34cb-48eb-b53b-32d27bedc26a, storageId = 
>>> e79cd423-ae17-4f8b-9f53-28d851cc9822, status=Maintenance].
>>> 2013-06-06 12:37:34,712 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-50) [ec7839b] Running command: 
>>> DetachStorageDomainFromPoolCommand internal: false. Entities 
>>> affected :  ID: e79cd423-ae17-4f8b-9f53-28d851cc9822 Type: Storage
>>> 2013-06-06 12:37:34,712 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-50) [ec7839b] Start detach storage domain
>>> 2013-06-06 12:37:34,720 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-50) [ec7839b]  Detach storage domain: before connect
>>> 2013-06-06 12:37:34,725 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] 
>>> (pool-3-thread-47) [7085ae59] START, 
>>> ValidateStorageServerConnectionVDSCommand(HostName = nodo1, HostId = 
>>> 3156bdac-ebfb-44cf-bea6-53d668b74a10, storagePoolId = 
>>> 00000000-0000-0000-0000-000000000000, storageType = NFS, 
>>> connectionList = [{ id: 45085cbf-da10-4852-9d85-754707d20a92, 
>>> connection: 172.16.0.5:/home/external/migration, iqn: null, vfsType: 
>>> null, mountOptions: null, nfsVersion: null, nfsRetrans: null, 
>>> nfsTimeo: null };]), log id: 5eb166b4
>>> 2013-06-06 12:37:34,731 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] 
>>> (pool-3-thread-47) [7085ae59] FINISH, 
>>> ValidateStorageServerConnectionVDSCommand, return: 
>>> {45085cbf-da10-4852-9d85-754707d20a92=0}, log id: 5eb166b4
>>> 2013-06-06 12:37:34,732 INFO 
>>> [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] 
>>> (pool-3-thread-47) [7085ae59] Running command: 
>>> ConnectStorageToVdsCommand internal: true. Entities affected : ID: 
>>> aaa00000-0000-0000-0000-123456789aaa Type: System
>>> 2013-06-06 12:37:34,733 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] 
>>> (pool-3-thread-47) [7085ae59] START, 
>>> ConnectStorageServerVDSCommand(HostName = nodo1, HostId = 
>>> 3156bdac-ebfb-44cf-bea6-53d668b74a10, storagePoolId = 
>>> 00000000-0000-0000-0000-000000000000, storageType = NFS, 
>>> connectionList = [{ id: 45085cbf-da10-4852-9d85-754707d20a92, 
>>> connection: 172.16.0.5:/home/external/migration, iqn: null, vfsType: 
>>> null, mountOptions: null, nfsVersion: null, nfsRetrans: null, 
>>> nfsTimeo: null };]), log id: 47ecdc5f
>>> 2013-06-06 12:37:34,772 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] 
>>> (pool-3-thread-47) [7085ae59] FINISH, 
>>> ConnectStorageServerVDSCommand, return: 
>>> {45085cbf-da10-4852-9d85-754707d20a92=100}, log id: 47ecdc5f
>>> 2013-06-06 12:37:34,773 ERROR 
>>> [org.ovirt.engine.core.bll.storage.NFSStorageHelper] 
>>> (pool-3-thread-47) [7085ae59] The connection with details 
>>> 172.16.0.5:/home/external/migration failed because of error code 100 
>>> and error message is: generalexception
>>> 2013-06-06 12:37:34,775 ERROR 
>>> [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] 
>>> (pool-3-thread-47) [7085ae59] Transaction rolled-back for command: 
>>> org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand.
>>> 2013-06-06 12:37:34,776 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-50) [ec7839b]  Detach storage domain: after connect
>>> 2013-06-06 12:37:34,776 INFO 
>>> [org.ovirt.engine.core.vdsbroker.irsbroker.DetachStorageDomainVDSCommand] 
>>> (pool-3-thread-50) [ec7839b] START, DetachStorageDomainVDSCommand( 
>>> storagePoolId = d76c9edf-34cb-48eb-b53b-32d27bedc26a, 
>>> ignoreFailoverLimit = false, compatabilityVersion = null, 
>>> storageDomainId = e79cd423-ae17-4f8b-9f53-28d851cc9822, 
>>> masterDomainId = 00000000-0000-0000-0000-000000000000, masterVersion 
>>> = 1, force = false), log id: 25dc2568
>>> 2013-06-06 12:37:36,947 ERROR 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] 
>>> (pool-3-thread-50) [ec7839b] Failed in DetachStorageDomainVDS method
>>> 2013-06-06 12:37:36,947 ERROR 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] 
>>> (pool-3-thread-50) [ec7839b] Error code StorageDomainDoesNotExist 
>>> and error message IRSGenericException: IRSErrorException: Failed to 
>>> DetachStorageDomainVDS, error = Storage domain does not exist: 
>>> ('e79cd423-ae17-4f8b-9f53-28d851cc9822',)
>>> 2013-06-06 12:37:36,947 ERROR 
>>> [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] 
>>> (pool-3-thread-50) [ec7839b] 
>>> IrsBroker::Failed::DetachStorageDomainVDS due to: IRSErrorException: 
>>> IRSGenericException: IRSErrorException: Failed to 
>>> DetachStorageDomainVDS, error = Storage domain does not exist: 
>>> ('e79cd423-ae17-4f8b-9f53-28d851cc9822',)
>>> 2013-06-06 12:37:36,950 INFO 
>>> [org.ovirt.engine.core.vdsbroker.irsbroker.DetachStorageDomainVDSCommand] 
>>> (pool-3-thread-50) [ec7839b] FINISH, DetachStorageDomainVDSCommand, 
>>> log id: 25dc2568
>>> 2013-06-06 12:37:36,950 ERROR 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-50) [ec7839b] Command 
>>> org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand 
>>> throw Vdc Bll exception. With error message VdcBLLException: 
>>> org.ovirt.engine.core.vdsbroker.irsbroker.IRSErrorException: 
>>> IRSGenericException: IRSErrorException: Failed to 
>>> DetachStorageDomainVDS, error = Storage domain does not exist: 
>>> ('e79cd423-ae17-4f8b-9f53-28d851cc9822',)
>>> 2013-06-06 12:37:36,953 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-50) [ec7839b] Command 
>>> [id=f31c6dda-866d-4d33-ab23-1878e2273c2f]: Compensating 
>>> CHANGED_STATUS_ONLY of 
>>> org.ovirt.engine.core.common.businessentities.StoragePoolIsoMap; 
>>> snapshot: EntityStatusSnapshot [id=storagePoolId = 
>>> d76c9edf-34cb-48eb-b53b-32d27bedc26a, storageId = 
>>> e79cd423-ae17-4f8b-9f53-28d851cc9822, status=Maintenance].
>>> 2013-06-06 12:38:03,209 INFO 
>>> [org.ovirt.engine.core.bll.LoginUserCommand] 
>>> (ajp--127.0.0.1-8702-14) Running command: LoginUserCommand internal: 
>>> false.
>>> 2013-06-06 12:38:03,236 WARN 
>>> [org.ovirt.engine.core.bll.GetConfigurationValueQuery] 
>>> (ajp--127.0.0.1-8702-14) calling GetConfigurationValueQuery 
>>> (ApplicationMode) with null version, using default general for version
>>> 2013-06-06 12:38:03,237 WARN 
>>> [org.ovirt.engine.core.bll.GetConfigurationValueQuery] 
>>> (ajp--127.0.0.1-8702-14) calling GetConfigurationValueQuery 
>>> (VdcVersion) with null version, using default general for version
>>> 2013-06-06 12:38:29,328 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-47) [60bf447d] Running command: 
>>> DetachStorageDomainFromPoolCommand internal: false. Entities 
>>> affected :  ID: e79cd423-ae17-4f8b-9f53-28d851cc9822 Type: Storage
>>> 2013-06-06 12:38:29,328 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-47) [60bf447d] Start detach storage domain
>>> 2013-06-06 12:38:29,336 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-47) [60bf447d]  Detach storage domain: before connect
>>> 2013-06-06 12:38:29,342 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] 
>>> (pool-3-thread-50) [54faa6d5] START, 
>>> ValidateStorageServerConnectionVDSCommand(HostName = nodo1, HostId = 
>>> 3156bdac-ebfb-44cf-bea6-53d668b74a10, storagePoolId = 
>>> 00000000-0000-0000-0000-000000000000, storageType = NFS, 
>>> connectionList = [{ id: 45085cbf-da10-4852-9d85-754707d20a92, 
>>> connection: 172.16.0.5:/home/external/migration, iqn: null, vfsType: 
>>> null, mountOptions: null, nfsVersion: null, nfsRetrans: null, 
>>> nfsTimeo: null };]), log id: 3c67ab9
>>> 2013-06-06 12:38:29,347 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] 
>>> (pool-3-thread-50) [54faa6d5] FINISH, 
>>> ValidateStorageServerConnectionVDSCommand, return: 
>>> {45085cbf-da10-4852-9d85-754707d20a92=0}, log id: 3c67ab9
>>> 2013-06-06 12:38:29,347 INFO 
>>> [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] 
>>> (pool-3-thread-50) [54faa6d5] Running command: 
>>> ConnectStorageToVdsCommand internal: true. Entities affected : ID: 
>>> aaa00000-0000-0000-0000-123456789aaa Type: System
>>> 2013-06-06 12:38:29,349 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] 
>>> (pool-3-thread-50) [54faa6d5] START, 
>>> ConnectStorageServerVDSCommand(HostName = nodo1, HostId = 
>>> 3156bdac-ebfb-44cf-bea6-53d668b74a10, storagePoolId = 
>>> 00000000-0000-0000-0000-000000000000, storageType = NFS, 
>>> connectionList = [{ id: 45085cbf-da10-4852-9d85-754707d20a92, 
>>> connection: 172.16.0.5:/home/external/migration, iqn: null, vfsType: 
>>> null, mountOptions: null, nfsVersion: null, nfsRetrans: null, 
>>> nfsTimeo: null };]), log id: 354a1d0
>>> 2013-06-06 12:38:29,391 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] 
>>> (pool-3-thread-50) [54faa6d5] FINISH, 
>>> ConnectStorageServerVDSCommand, return: 
>>> {45085cbf-da10-4852-9d85-754707d20a92=100}, log id: 354a1d0
>>> 2013-06-06 12:38:29,392 ERROR 
>>> [org.ovirt.engine.core.bll.storage.NFSStorageHelper] 
>>> (pool-3-thread-50) [54faa6d5] The connection with details 
>>> 172.16.0.5:/home/external/migration failed because of error code 100 
>>> and error message is: generalexception
>>> 2013-06-06 12:38:29,394 ERROR 
>>> [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] 
>>> (pool-3-thread-50) [54faa6d5] Transaction rolled-back for command: 
>>> org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand.
>>> 2013-06-06 12:38:29,395 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-47) [60bf447d]  Detach storage domain: after connect
>>> 2013-06-06 12:38:29,395 INFO 
>>> [org.ovirt.engine.core.vdsbroker.irsbroker.DetachStorageDomainVDSCommand] 
>>> (pool-3-thread-47) [60bf447d] START, DetachStorageDomainVDSCommand( 
>>> storagePoolId = d76c9edf-34cb-48eb-b53b-32d27bedc26a, 
>>> ignoreFailoverLimit = false, compatabilityVersion = null, 
>>> storageDomainId = e79cd423-ae17-4f8b-9f53-28d851cc9822, 
>>> masterDomainId = 00000000-0000-0000-0000-000000000000, masterVersion 
>>> = 1, force = false), log id: 5eb3b792
>>> 2013-06-06 12:38:31,565 ERROR 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] 
>>> (pool-3-thread-47) [60bf447d] Failed in DetachStorageDomainVDS method
>>> 2013-06-06 12:38:31,566 ERROR 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] 
>>> (pool-3-thread-47) [60bf447d] Error code StorageDomainDoesNotExist 
>>> and error message IRSGenericException: IRSErrorException: Failed to 
>>> DetachStorageDomainVDS, error = Storage domain does not exist: 
>>> ('e79cd423-ae17-4f8b-9f53-28d851cc9822',)
>>> 2013-06-06 12:38:31,566 ERROR 
>>> [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] 
>>> (pool-3-thread-47) [60bf447d] 
>>> IrsBroker::Failed::DetachStorageDomainVDS due to: IRSErrorException: 
>>> IRSGenericException: IRSErrorException: Failed to 
>>> DetachStorageDomainVDS, error = Storage domain does not exist: 
>>> ('e79cd423-ae17-4f8b-9f53-28d851cc9822',)
>>> 2013-06-06 12:38:31,567 INFO 
>>> [org.ovirt.engine.core.vdsbroker.irsbroker.DetachStorageDomainVDSCommand] 
>>> (pool-3-thread-47) [60bf447d] FINISH, DetachStorageDomainVDSCommand, 
>>> log id: 5eb3b792
>>> 2013-06-06 12:38:31,568 ERROR 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-47) [60bf447d] Command 
>>> org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand 
>>> throw Vdc Bll exception. With error message VdcBLLException: 
>>> org.ovirt.engine.core.vdsbroker.irsbroker.IRSErrorException: 
>>> IRSGenericException: IRSErrorException: Failed to 
>>> DetachStorageDomainVDS, error = Storage domain does not exist: 
>>> ('e79cd423-ae17-4f8b-9f53-28d851cc9822',)
>>> 2013-06-06 12:38:31,570 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-47) [60bf447d] Command 
>>> [id=b0c96566-93f4-4259-ab5a-cca123d660ac]: Compensating 
>>> CHANGED_STATUS_ONLY of 
>>> org.ovirt.engine.core.common.businessentities.StoragePoolIsoMap; 
>>> snapshot: EntityStatusSnapshot [id=storagePoolId = 
>>> d76c9edf-34cb-48eb-b53b-32d27bedc26a, storageId = 
>>> e79cd423-ae17-4f8b-9f53-28d851cc9822, status=Maintenance].
>>> 2013-06-06 12:38:51,136 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-47) [7edf94ee] Running command: 
>>> DetachStorageDomainFromPoolCommand internal: false. Entities 
>>> affected :  ID: e79cd423-ae17-4f8b-9f53-28d851cc9822 Type: Storage
>>> 2013-06-06 12:38:51,136 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-47) [7edf94ee] Start detach storage domain
>>> 2013-06-06 12:38:51,144 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-47) [7edf94ee]  Detach storage domain: before connect
>>> 2013-06-06 12:38:51,149 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] 
>>> (pool-3-thread-50) [3e06482] START, 
>>> ValidateStorageServerConnectionVDSCommand(HostName = nodo1, HostId = 
>>> 3156bdac-ebfb-44cf-bea6-53d668b74a10, storagePoolId = 
>>> 00000000-0000-0000-0000-000000000000, storageType = NFS, 
>>> connectionList = [{ id: 45085cbf-da10-4852-9d85-754707d20a92, 
>>> connection: 172.16.0.5:/home/external/migration, iqn: null, vfsType: 
>>> null, mountOptions: null, nfsVersion: null, nfsRetrans: null, 
>>> nfsTimeo: null };]), log id: f9172da
>>> 2013-06-06 12:38:51,154 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] 
>>> (pool-3-thread-50) [3e06482] FINISH, 
>>> ValidateStorageServerConnectionVDSCommand, return: 
>>> {45085cbf-da10-4852-9d85-754707d20a92=0}, log id: f9172da
>>> 2013-06-06 12:38:51,155 INFO 
>>> [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] 
>>> (pool-3-thread-50) [3e06482] Running command: 
>>> ConnectStorageToVdsCommand internal: true. Entities affected : ID: 
>>> aaa00000-0000-0000-0000-123456789aaa Type: System
>>> 2013-06-06 12:38:51,156 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] 
>>> (pool-3-thread-50) [3e06482] START, 
>>> ConnectStorageServerVDSCommand(HostName = nodo1, HostId = 
>>> 3156bdac-ebfb-44cf-bea6-53d668b74a10, storagePoolId = 
>>> 00000000-0000-0000-0000-000000000000, storageType = NFS, 
>>> connectionList = [{ id: 45085cbf-da10-4852-9d85-754707d20a92, 
>>> connection: 172.16.0.5:/home/external/migration, iqn: null, vfsType: 
>>> null, mountOptions: null, nfsVersion: null, nfsRetrans: null, 
>>> nfsTimeo: null };]), log id: 2b6b5260
>>> 2013-06-06 12:38:51,193 INFO 
>>> [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] 
>>> (pool-3-thread-50) [3e06482] FINISH, ConnectStorageServerVDSCommand, 
>>> return: {45085cbf-da10-4852-9d85-754707d20a92=100}, log id: 2b6b5260
>>> 2013-06-06 12:38:51,194 ERROR 
>>> [org.ovirt.engine.core.bll.storage.NFSStorageHelper] 
>>> (pool-3-thread-50) [3e06482] The connection with details 
>>> 172.16.0.5:/home/external/migration failed because of error code 100 
>>> and error message is: generalexception
>>> 2013-06-06 12:38:51,196 ERROR 
>>> [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] 
>>> (pool-3-thread-50) [3e06482] Transaction rolled-back for command: 
>>> org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand.
>>> 2013-06-06 12:38:51,196 INFO 
>>> [org.ovirt.engine.core.bll.storage.DetachStorageDomainFromPoolCommand] 
>>> (pool-3-thread-47) [7edf94ee]  Detach storage domain: after connect
>>> 2013-06-06 12:38:51,197 INFO 
>>> [org.ovirt.engine.core.vdsbroker.irsbroker.DetachStorageDomainVDSCommand] 
>>> (pool-3-thread-47) [7edf94ee] START, DetachStorageDomainVDSCommand( 
>>> storagePoolId = d76c9edf-34cb-48eb-b53b-32d27bedc26a, 
>>> ignoreFailoverLimit = false, compatabilityVersion = null, 
>>> storageDomainId = e79cd423-ae17-4f8b-9f53-28d851cc9822, 
>>> masterDomainId = 00000000-0000-0000-0000-000000000000, masterVersion 
>>> = 1, force = false), log id: 2038d5bd
>>>
>>> Thank you for your help
>>>
>>> Best regards
>>>
>>> Alessandro Bianchi
>>> -- 
>>>
>>> SkyNet SRL
>>>
>>> Via Maggiate 67/a - 28021 Borgomanero (NO) - tel. +39 
>>> 0322-836487/834765 - fax +39 0322-836608
>>>
>>> http://www.skynet.it <http://www.skynet.it/>
>>>
>>> Autorizzazione Ministeriale n.197
>>>
>>> Le informazioni contenute in questo messaggio sono riservate e 
>>> confidenziali ed è vietata la diffusione in qualunque modo eseguita.
>>> Qualora Lei non fosse la persona a cui il presente messaggio è 
>>> destinato, La invitiamo ad eliminarlo ed a distruggerlo non 
>>> divulgandolo, dandocene gentilmente comunicazione.
>>> Per qualsiasi informazione si prega di contattare info at skynet.it 
>>> (e-mail dell'azienda). Rif. D.L. 196/2003
>>>
>>>
>>
>>
> Hi
>
> Here it is
>
> I've zipped the two files
>
> Thank you
>
> Best regards
>
> Alessandro Bianchi
>
> -- 
>
> SkyNet SRL
>
> Via Maggiate 67/a - 28021 Borgomanero (NO) - tel. +39 
> 0322-836487/834765 - fax +39 0322-836608
>
> http://www.skynet.it <http://www.skynet.it/>
>
> Autorizzazione Ministeriale n.197
>
> Le informazioni contenute in questo messaggio sono riservate e 
> confidenziali ed è vietata la diffusione in qualunque modo eseguita.
> Qualora Lei non fosse la persona a cui il presente messaggio è 
> destinato, La invitiamo ad eliminarlo ed a distruggerlo non 
> divulgandolo, dandocene gentilmente comunicazione.
> Per qualsiasi informazione si prega di contattare info at skynet.it 
> (e-mail dell'azienda). Rif. D.L. 196/2003
>


-- 
Dafna Ron



More information about the Users mailing list