Re: [Users] Failed to attach Storage Domain export to Data Center

9 Apr 2012

      This is a multi-part message in MIME format.
--------------070000070304040100060303
Content-Type: text/plain; charset=UTF-8; format=flowed
Content-Transfer-Encoding: 8bit

All commands in this both logs you've attached (see example line below) 
indicate that the sdUUID is still e3e565d6-8104-11e1-afd2-001cc43ceea0 
which is the same as the spUUID. Are you sure your metadata file 
contains the "SDUUID=b23c7ab6-b1d4-4888-8d4a-adc78e61db38" line and not 
the wrong "SDUUID=3e565d6-8104-11e1-afd2-001cc43ceea0"line?

Thread-79075::INFO::2012-04-09 
06:28:05,328::dispatcher::94::Storage.Dispatcher.Protect::(run) Run and 
protect: attachStorageDomain, args: ( 
sdUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0 
spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0)

On 04/09/2012 01:37 PM, зоррыч wrote:
...
Vdsm log (spm):
Thread-79073::DEBUG::2012-04-09 
06:28:05,189::clientIF::239::Storage.Dispatcher.Protect::(wrapper) 
[10.1.20.2]
Thread-79073::INFO::2012-04-09 
06:28:05,190::dispatcher::94::Storage.Dispatcher.Protect::(run) Run 
and protect: validateStorageServerConnection, args: (domType=1, 
spUUID=00000000-0000-0000-0000-000000000000, conList=[{'connection': 
'10.1.20.2:/home/nfs4', 'iqn': '', 'portal': '', 'user': '', 
'password': '******', 'id': 'aef7de23-f271-45f3-853b-9e7b7be6607f', 
'port': ''}])
Thread-79073::DEBUG::2012-04-09 
06:28:05,190::task::495::TaskManager.Task::(_debug) Task 
3634fc46-5104-4ebd-8ed6-1d4428beed92: moving from state init -> state 
preparing
Thread-79073::INFO::2012-04-09 
06:28:05,190::storage_connection::127::Storage.ServerConnection::(validate) 
Request to validate NFS storage server
Thread-79073::INFO::2012-04-09 
06:28:05,191::storage_connection::53::Storage.ServerConnection::(__validateConnectionParams) 
conList=[{'connection': '10.1.20.2:/home/nfs4', 'iqn': '', 'portal': 
'', 'user': '', 'password': '******', 'id': 
'aef7de23-f271-45f3-853b-9e7b7be6607f', 'port': ''}]
Thread-79073::DEBUG::2012-04-09 
06:28:05,192::fileUtils::83::Storage.Misc.excCmd::(mount) 
'/usr/bin/sudo -n /bin/mount -o 
soft,timeo=600,retrans=6,nosharecache,vers=3 -t nfs 
10.1.20.2:/home/nfs4 /tmp/tmp2rKh29' (cwd None)
Thread-79073::DEBUG::2012-04-09 
06:28:05,228::fileUtils::83::Storage.Misc.excCmd::(mount) SUCCESS: 
<err> = ''; <rc> = 0
Thread-79073::DEBUG::2012-04-09 
06:28:05,235::fileUtils::112::Storage.Misc.excCmd::(umount) 
'/usr/bin/sudo -n /bin/umount -t nfs -f /tmp/tmp2rKh29' (cwd None)
Thread-79073::DEBUG::2012-04-09 
06:28:05,270::fileUtils::112::Storage.Misc.excCmd::(umount) SUCCESS: 
<err> = ''; <rc> = 0
Thread-79073::DEBUG::2012-04-09 
06:28:05,271::task::495::TaskManager.Task::(_debug) Task 
3634fc46-5104-4ebd-8ed6-1d4428beed92: finished: {'statuslist': 
[{'status': 0, 'id': 'aef7de23-f271-45f3-853b-9e7b7be6607f'}]}
Thread-79073::DEBUG::2012-04-09 
06:28:05,272::task::495::TaskManager.Task::(_debug) Task 
3634fc46-5104-4ebd-8ed6-1d4428beed92: moving from state preparing -> 
state finished
Thread-79073::DEBUG::2012-04-09 
06:28:05,272::resourceManager::786::ResourceManager.Owner::(releaseAll) Owner.releaseAll 
requests {} resources {}
Thread-79073::DEBUG::2012-04-09 
06:28:05,272::resourceManager::821::ResourceManager.Owner::(cancelAll) 
Owner.cancelAll requests {}
Thread-79073::DEBUG::2012-04-09 
06:28:05,273::task::495::TaskManager.Task::(_debug) Task 
3634fc46-5104-4ebd-8ed6-1d4428beed92: ref 0 aborting False
Thread-79073::INFO::2012-04-09 
06:28:05,273::dispatcher::100::Storage.Dispatcher.Protect::(run) Run 
and protect: validateStorageServerConnection, Return response: 
{'status': {'message': 'OK', 'code': 0}, 'statuslist': [{'status': 0, 
'id': 'aef7de23-f271-45f3-853b-9e7b7be6607f'}]}
Thread-79074::DEBUG::2012-04-09 
06:28:05,297::clientIF::239::Storage.Dispatcher.Protect::(wrapper) 
[10.1.20.2]
Thread-79074::INFO::2012-04-09 
06:28:05,298::dispatcher::94::Storage.Dispatcher.Protect::(run) Run 
and protect: connectStorageServer, args: (domType=1, 
spUUID=00000000-0000-0000-0000-000000000000, conList=[{'connection': 
'10.1.20.2:/home/nfs4', 'iqn': '', 'portal': '', 'user': '', 
'password': '******', 'id': 'aef7de23-f271-45f3-853b-9e7b7be6607f', 
'port': ''}])
Thread-79074::DEBUG::2012-04-09 
06:28:05,298::task::495::TaskManager.Task::(_debug) Task 
4cf78074-142b-418d-ae9d-aa1a3afbd0b8: moving from state init -> state 
preparing
Thread-79074::INFO::2012-04-09 
06:28:05,298::storage_connection::95::Storage.ServerConnection::(connect) 
Request to connect NFS storage server
Thread-79074::INFO::2012-04-09 
06:28:05,298::storage_connection::53::Storage.ServerConnection::(__validateConnectionParams) 
conList=[{'connection': '10.1.20.2:/home/nfs4', 'iqn': '', 'portal': 
'', 'user': '', 'password': '******', 'id': 
'aef7de23-f271-45f3-853b-9e7b7be6607f', 'port': ''}]
Thread-79074::DEBUG::2012-04-09 
06:28:05,308::lvm::547::OperationMutex::(_invalidateAllPvs) Operation 
'lvm invalidate operation' got the operation mutex
Thread-79074::DEBUG::2012-04-09 
06:28:05,308::lvm::549::OperationMutex::(_invalidateAllPvs) Operation 
'lvm invalidate operation' released the operation mutex
Thread-79074::DEBUG::2012-04-09 
06:28:05,309::lvm::559::OperationMutex::(_invalidateAllVgs) Operation 
'lvm invalidate operation' got the operation mutex
Thread-79074::DEBUG::2012-04-09 
06:28:05,309::lvm::561::OperationMutex::(_invalidateAllVgs) Operation 
'lvm invalidate operation' released the operation mutex
Thread-79074::DEBUG::2012-04-09 
06:28:05,310::lvm::580::OperationMutex::(_invalidateAllLvs) Operation 
'lvm invalidate operation' got the operation mutex
Thread-79074::DEBUG::2012-04-09 
06:28:05,310::lvm::582::OperationMutex::(_invalidateAllLvs) Operation 
'lvm invalidate operation' released the operation mutex
Thread-79074::DEBUG::2012-04-09 
06:28:05,310::task::495::TaskManager.Task::(_debug) Task 
4cf78074-142b-418d-ae9d-aa1a3afbd0b8: finished: {'statuslist': 
[{'status': 0, 'id': 'aef7de23-f271-45f3-853b-9e7b7be6607f'}]}
Thread-79074::DEBUG::2012-04-09 
06:28:05,311::task::495::TaskManager.Task::(_debug) Task 
4cf78074-142b-418d-ae9d-aa1a3afbd0b8: moving from state preparing -> 
state finished
Thread-79074::DEBUG::2012-04-09 
06:28:05,311::resourceManager::786::ResourceManager.Owner::(releaseAll) Owner.releaseAll 
requests {} resources {}
Thread-79074::DEBUG::2012-04-09 
06:28:05,311::resourceManager::821::ResourceManager.Owner::(cancelAll) 
Owner.cancelAll requests {}
Thread-79074::DEBUG::2012-04-09 
06:28:05,311::task::495::TaskManager.Task::(_debug) Task 
4cf78074-142b-418d-ae9d-aa1a3afbd0b8: ref 0 aborting False
Thread-79074::INFO::2012-04-09 
06:28:05,312::dispatcher::100::Storage.Dispatcher.Protect::(run) Run 
and protect: connectStorageServer, Return response: {'status': 
{'message': 'OK', 'code': 0}, 'statuslist': [{'status': 0, 'id': 
'aef7de23-f271-45f3-853b-9e7b7be6607f'}]}
Thread-79075::DEBUG::2012-04-09 
06:28:05,328::clientIF::239::Storage.Dispatcher.Protect::(wrapper) 
[10.1.20.2]
Thread-79075::INFO::2012-04-09 
06:28:05,328::dispatcher::94::Storage.Dispatcher.Protect::(run) Run 
and protect: attachStorageDomain, args: ( 
sdUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0 
spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0)
Thread-79075::DEBUG::2012-04-09 
06:28:05,329::task::495::TaskManager.Task::(_debug) Task 
b0ef8b95-fabe-43e0-944c-eb959cf9930a: moving from state init -> state 
preparing
Thread-79075::DEBUG::2012-04-09 
06:28:05,329::resourceManager::155::ResourceManager.Request::(__init__) ResName=`Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`af876ee5-24f4-43f1-a161-d495903c82b5`::Request 
was made in '/usr/share/vdsm/storage/spm.py' line '1072' at 
'public_attachStorageDomain'
Thread-79075::DEBUG::2012-04-09 
06:28:05,330::resourceManager::463::ResourceManager::(registerResource) Trying 
to register resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' 
for lock type 'exclusive'
Thread-79075::DEBUG::2012-04-09 
06:28:05,330::resourceManager::505::ResourceManager::(registerResource) Resource 
'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' is free. Now locking as 
'exclusive' (1 active user)
Thread-79075::DEBUG::2012-04-09 
06:28:05,330::resourceManager::192::ResourceManager.Request::(grant) 
ResName=`Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`af876ee5-24f4-43f1-a161-d495903c82b5`::Granted 
request
Thread-79075::DEBUG::2012-04-09 
06:28:05,331::task::495::TaskManager.Task::(_debug) Task 
b0ef8b95-fabe-43e0-944c-eb959cf9930a: _resourcesAcquired: 
Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 (exclusive)
Thread-79075::DEBUG::2012-04-09 
06:28:05,331::task::495::TaskManager.Task::(_debug) Task 
b0ef8b95-fabe-43e0-944c-eb959cf9930a: ref 1 aborting False
Thread-79075::ERROR::2012-04-09 
06:28:05,331::task::868::TaskManager.Task::(_setError) Unexpected error
Traceback (most recent call last):
File "/usr/share/vdsm/storage/task.py", line 876, in _run
return fn(*args, **kargs)
File "/usr/share/vdsm/storage/spm.py", line 115, in run
return self.func(*args, **kwargs)
File "/usr/share/vdsm/storage/spm.py", line 1073, in 
public_attachStorageDomain
vars.task.getExclusiveLock(STORAGE, sdUUID)
File "/usr/share/vdsm/storage/task.py", line 1311, in getExclusiveLock
self.resOwner.acquire(namespace, resName, 
resourceManager.LockType.exclusive, timeout)
File "/usr/share/vdsm/storage/resourceManager.py", line 673, in acquire
raise ValueError("Owner %s: acquire: resource %s is already 
acquired" % (str(self), fullName))
ValueError: Owner b0ef8b95-fabe-43e0-944c-eb959cf9930a: acquire: 
resource Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 is already acquired
Thread-79075::DEBUG::2012-04-09 
06:28:05,332::task::495::TaskManager.Task::(_debug) Task 
b0ef8b95-fabe-43e0-944c-eb959cf9930a: Task._run: 
b0ef8b95-fabe-43e0-944c-eb959cf9930a 
('e3e565d6-8104-11e1-afd2-001cc43ceea0', 
'e3e565d6-8104-11e1-afd2-001cc43ceea0') {} failed - stopping task
Thread-79075::DEBUG::2012-04-09 
06:28:05,332::task::495::TaskManager.Task::(_debug) Task 
b0ef8b95-fabe-43e0-944c-eb959cf9930a: stopping in state preparing 
(force False)
Thread-79075::DEBUG::2012-04-09 
06:28:05,333::task::495::TaskManager.Task::(_debug) Task 
b0ef8b95-fabe-43e0-944c-eb959cf9930a: ref 1 aborting True
Thread-79075::INFO::2012-04-09 
06:28:05,333::task::1171::TaskManager.Task::(prepare) aborting: Task 
is aborted: 'Owner b0ef8b95-fabe-43e0-944c-eb959cf9930a: acquire: 
resource Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 is already 
acquired' - code 100
Thread-79075::DEBUG::2012-04-09 
06:28:05,333::task::495::TaskManager.Task::(_debug) Task 
b0ef8b95-fabe-43e0-944c-eb959cf9930a: Prepare: aborted: Owner 
b0ef8b95-fabe-43e0-944c-eb959cf9930a: acquire: resource 
Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 is already acquired
Thread-79075::DEBUG::2012-04-09 
06:28:05,333::task::495::TaskManager.Task::(_debug) Task 
b0ef8b95-fabe-43e0-944c-eb959cf9930a: ref 0 aborting True
Thread-79075::DEBUG::2012-04-09 
06:28:05,334::task::495::TaskManager.Task::(_debug) Task 
b0ef8b95-fabe-43e0-944c-eb959cf9930a: Task._doAbort: force False
Thread-79075::DEBUG::2012-04-09 
06:28:05,334::resourceManager::821::ResourceManager.Owner::(cancelAll) 
Owner.cancelAll requests {}
Thread-79075::DEBUG::2012-04-09 
06:28:05,334::task::495::TaskManager.Task::(_debug) Task 
b0ef8b95-fabe-43e0-944c-eb959cf9930a: moving from state preparing -> 
state aborting
Thread-79075::DEBUG::2012-04-09 
06:28:05,335::task::495::TaskManager.Task::(_debug) Task 
b0ef8b95-fabe-43e0-944c-eb959cf9930a: _aborting: recover policy none
Thread-79075::DEBUG::2012-04-09 
06:28:05,335::task::495::TaskManager.Task::(_debug) Task 
b0ef8b95-fabe-43e0-944c-eb959cf9930a: moving from state aborting -> 
state failed
Thread-79075::DEBUG::2012-04-09 
06:28:05,335::resourceManager::786::ResourceManager.Owner::(releaseAll) Owner.releaseAll 
requests {} resources {'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0': 
< ResourceRef 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0', isValid: 
'True' obj: 'None'>}
Thread-79075::DEBUG::2012-04-09 
06:28:05,335::resourceManager::821::ResourceManager.Owner::(cancelAll) 
Owner.cancelAll requests {}
Thread-79075::DEBUG::2012-04-09 
06:28:05,336::resourceManager::515::ResourceManager::(releaseResource) 
Trying to release resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0'
Thread-79075::DEBUG::2012-04-09 
06:28:05,336::resourceManager::530::ResourceManager::(releaseResource) 
Released resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' (0 
active users)
Thread-79075::DEBUG::2012-04-09 
06:28:05,336::resourceManager::535::ResourceManager::(releaseResource) 
Resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' is free, 
finding out if anyone is waiting for it.
Thread-79075::DEBUG::2012-04-09 
06:28:05,337::resourceManager::542::ResourceManager::(releaseResource) 
No one is waiting for resource 
'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0', Clearing records.
Thread-79075::ERROR::2012-04-09 
06:28:05,337::dispatcher::106::Storage.Dispatcher.Protect::(run) Owner 
b0ef8b95-fabe-43e0-944c-eb959cf9930a: acquire: resource 
Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 is already acquired
Thread-79075::ERROR::2012-04-09 
06:28:05,337::dispatcher::107::Storage.Dispatcher.Protect::(run) 
Traceback (most recent call last):
File "/usr/share/vdsm/storage/dispatcher.py", line 96, in run
result = ctask.prepare(self.func, *args, **kwargs)
File "/usr/share/vdsm/storage/task.py", line 1178, in prepare
raise self.error
ValueError: Owner b0ef8b95-fabe-43e0-944c-eb959cf9930a: acquire: 
resource Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 is already acquired
Thread-79076::DEBUG::2012-04-09 
06:28:05,349::clientIF::239::Storage.Dispatcher.Protect::(wrapper) 
[10.1.20.2]
Thread-79076::INFO::2012-04-09 
06:28:05,349::dispatcher::94::Storage.Dispatcher.Protect::(run) Run 
and protect: getSpmStatus, args: ( 
spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0)
Thread-79076::DEBUG::2012-04-09 
06:28:05,350::task::495::TaskManager.Task::(_debug) Task 
13ba6f56-88a9-4bab-a677-11b84d1170dd: moving from state init -> state 
preparing
Thread-79076::DEBUG::2012-04-09 
06:28:05,350::spm::688::Storage.SPM::(public_getSpmStatus) 
spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0: spmStatus=SPM spmLver=18 
spmId=2
Thread-79076::DEBUG::2012-04-09 
06:28:05,350::task::495::TaskManager.Task::(_debug) Task 
13ba6f56-88a9-4bab-a677-11b84d1170dd: finished: {'spm_st': {'spmId': 
2, 'spmStatus': 'SPM', 'spmLver': 18}}
Thread-79076::DEBUG::2012-04-09 
06:28:05,350::task::495::TaskManager.Task::(_debug) Task 
13ba6f56-88a9-4bab-a677-11b84d1170dd: moving from state preparing -> 
state finished
Thread-79076::DEBUG::2012-04-09 
06:28:05,351::resourceManager::786::ResourceManager.Owner::(releaseAll) Owner.releaseAll 
requests {} resources {}
Thread-79076::DEBUG::2012-04-09 
06:28:05,351::resourceManager::821::ResourceManager.Owner::(cancelAll) 
Owner.cancelAll requests {}
Thread-79076::DEBUG::2012-04-09 
06:28:05,351::task::495::TaskManager.Task::(_debug) Task 
13ba6f56-88a9-4bab-a677-11b84d1170dd: ref 0 aborting False
Thread-79076::INFO::2012-04-09 
06:28:05,352::dispatcher::100::Storage.Dispatcher.Protect::(run) Run 
and protect: getSpmStatus, Return response: {'status': {'message': 
'OK', 'code': 0}, 'spm_st': {'spmId': 2, 'spmStatus': 'SPM', 
'spmLver': 18}}
Thread-79077::DEBUG::2012-04-09 
06:28:05,362::clientIF::239::Storage.Dispatcher.Protect::(wrapper) 
[10.1.20.2]
Thread-79077::INFO::2012-04-09 
06:28:05,363::dispatcher::94::Storage.Dispatcher.Protect::(run) Run 
and protect: getAllTasksStatuses, args: ()
Thread-79077::DEBUG::2012-04-09 
06:28:05,363::task::495::TaskManager.Task::(_debug) Task 
d87eafd3-24e9-4aaf-ba81-9c52dbfb5a1c: moving from state init -> state 
preparing
Thread-79077::DEBUG::2012-04-09 
06:28:05,363::taskManager::92::TaskManager::(getAllTasksStatuses) Entry.
Thread-79077::DEBUG::2012-04-09 
06:28:05,363::taskManager::97::TaskManager::(getAllTasksStatuses) 
Return: {}
Thread-79077::DEBUG::2012-04-09 
06:28:05,364::task::495::TaskManager.Task::(_debug) Task 
d87eafd3-24e9-4aaf-ba81-9c52dbfb5a1c: finished: {'allTasksStatus': {}}
Thread-79077::DEBUG::2012-04-09 
06:28:05,364::task::495::TaskManager.Task::(_debug) Task 
d87eafd3-24e9-4aaf-ba81-9c52dbfb5a1c: moving from state preparing -> 
state finished
Thread-79077::DEBUG::2012-04-09 
06:28:05,364::resourceManager::786::ResourceManager.Owner::(releaseAll) Owner.releaseAll 
requests {} resources {}
Thread-79077::DEBUG::2012-04-09 
06:28:05,365::resourceManager::821::ResourceManager.Owner::(cancelAll) 
Owner.cancelAll requests {}
Thread-79077::DEBUG::2012-04-09 
06:28:05,365::task::495::TaskManager.Task::(_debug) Task 
d87eafd3-24e9-4aaf-ba81-9c52dbfb5a1c: ref 0 aborting False
Thread-79077::INFO::2012-04-09 
06:28:05,365::dispatcher::100::Storage.Dispatcher.Protect::(run) Run 
and protect: getAllTasksStatuses, Return response: {'status': 
{'message': 'OK', 'code': 0}, 'allTasksStatus': {}}
Thread-79078::DEBUG::2012-04-09 
06:28:05,373::clientIF::239::Storage.Dispatcher.Protect::(wrapper) 
[10.1.20.2]
Thread-79078::INFO::2012-04-09 
06:28:05,373::dispatcher::94::Storage.Dispatcher.Protect::(run) Run 
and protect: spmStop, args: ( spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0)
Thread-79078::DEBUG::2012-04-09 
06:28:05,374::task::495::TaskManager.Task::(_debug) Task 
0b1d8a06-7441-4614-ab6a-e5ae6b7f1891: moving from state init -> state 
preparing
Thread-79078::DEBUG::2012-04-09 
06:28:05,374::spm::547::Storage.SPM::(public_spmStop) 
spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0
Thread-79078::DEBUG::2012-04-09 
06:28:05,374::taskManager::105::TaskManager::(getAllTasks) Entry.
Thread-79078::DEBUG::2012-04-09 
06:28:05,375::taskManager::110::TaskManager::(getAllTasks) Return: {}
Thread-79078::DEBUG::2012-04-09 
06:28:05,375::resourceManager::155::ResourceManager.Request::(__init__) ResName=`Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`0f0e6998-87a6-40e8-8ded-9f600cb0ca2d`::Request 
was made in '/usr/share/vdsm/storage/spm.py' line '573' at 
'public_spmStop'
Thread-79078::DEBUG::2012-04-09 
06:28:05,375::resourceManager::463::ResourceManager::(registerResource) Trying 
to register resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' 
for lock type 'exclusive'
Thread-79078::DEBUG::2012-04-09 
06:28:05,376::resourceManager::505::ResourceManager::(registerResource) Resource 
'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' is free. Now locking as 
'exclusive' (1 active user)
Thread-79078::DEBUG::2012-04-09 
06:28:05,376::resourceManager::192::ResourceManager.Request::(grant) 
ResName=`Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`0f0e6998-87a6-40e8-8ded-9f600cb0ca2d`::Granted 
request
Thread-79078::DEBUG::2012-04-09 
06:28:05,376::task::495::TaskManager.Task::(_debug) Task 
0b1d8a06-7441-4614-ab6a-e5ae6b7f1891: _resourcesAcquired: 
Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 (exclusive)
Thread-79078::DEBUG::2012-04-09 
06:28:05,377::task::495::TaskManager.Task::(_debug) Task 
0b1d8a06-7441-4614-ab6a-e5ae6b7f1891: ref 1 aborting False
Thread-79078::DEBUG::2012-04-09 
06:28:05,377::resourceManager::155::ResourceManager.Request::(__init__) ResName=`Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`eb290e92-2fbb-4e12-bde2-83292aade8e4`::Request 
was made in '/usr/share/vdsm/storage/spm.py' line '584' at '_stop'
Thread-79078::DEBUG::2012-04-09 
06:28:05,378::resourceManager::463::ResourceManager::(registerResource) Trying 
to register resource 
'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0' for lock type 
'exclusive'
Thread-79078::DEBUG::2012-04-09 
06:28:05,378::resourceManager::505::ResourceManager::(registerResource) Resource 
'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0' is free. Now 
locking as 'exclusive' (1 active user)
Thread-79078::DEBUG::2012-04-09 
06:28:05,378::resourceManager::192::ResourceManager.Request::(grant) 
ResName=`Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`eb290e92-2fbb-4e12-bde2-83292aade8e4`::Granted 
request
Thread-79078::DEBUG::2012-04-09 
06:28:05,379::resourceManager::515::ResourceManager::(releaseResource) 
Trying to release resource 
'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0'
Thread-79078::DEBUG::2012-04-09 
06:28:05,379::resourceManager::530::ResourceManager::(releaseResource) 
Released resource 
'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0' (0 active users)
Thread-79078::DEBUG::2012-04-09 
06:28:05,379::resourceManager::535::ResourceManager::(releaseResource) 
Resource 'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0' is 
free, finding out if anyone is waiting for it.
Thread-79078::DEBUG::2012-04-09 
06:28:05,380::resourceManager::542::ResourceManager::(releaseResource) 
No one is waiting for resource 
'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0', Clearing records.
Thread-79078::DEBUG::2012-04-09 
06:28:05,380::taskManager::118::TaskManager::(unloadTasks) Entry.
Thread-79078::DEBUG::2012-04-09 
06:28:05,380::taskManager::122::TaskManager::(unloadTasks) Return
Thread-79078::DEBUG::2012-04-09 
06:28:05,381::spm::201::Storage.SPM::(__cleanupMasterMount) master 
`/rhev/data-center/mnt/blockSD/e5a63624-716e-4bb4-ae60-cd4d7aae9ed2/master` 
is not mounted, skipping
Thread-79078::DEBUG::2012-04-09 
06:28:05,381::spm::290::Storage.SPM::(__cleanupSPM) cleaning up SPM: 
e3e565d6-8104-11e1-afd2-001cc43ceea0
Thread-79078::DEBUG::2012-04-09 
06:28:05,381::persistentDict::140::Storage.PersistentDict::(transaction) 
Starting transaction
Thread-79078::DEBUG::2012-04-09 
06:28:05,382::persistentDict::146::Storage.PersistentDict::(transaction) 
Flushing changes
Thread-79078::DEBUG::2012-04-09 
06:28:05,382::persistentDict::265::Storage.PersistentDict::(flush) 
about to write lines (FileMetadataRW)=['CLASS=Data', 
'DESCRIPTION=main', 'IOOPTIMEOUTSEC=10', 'LEASERETRIES=3', 
'LEASETIMESEC=60', 'LOCKPOLICY=', 'LOCKRENEWALINTERVALSEC=5', 
'MASTER_VERSION=1', 'POOL_DESCRIPTION=Default', 
'POOL_DOMAINS=4f1199ec-9cdb-4efe-91eb-587faeec2099:Active', 
'POOL_SPM_ID=-1', 'POOL_SPM_LVER=18', 
'POOL_UUID=e3e565d6-8104-11e1-afd2-001cc43ceea0', 
'REMOTE_PATH=nfs.corp:/share', 'ROLE=Master', 
'SDUUID=4f1199ec-9cdb-4efe-91eb-587faeec2099', 'TYPE=NFS', 
'VERSION=0', '_SHA_CKSUM=5a3d228ad230c9d61a754c22abaa6b5f11b6c721']
Thread-79078::DEBUG::2012-04-09 
06:28:05,397::persistentDict::148::Storage.PersistentDict::(transaction) 
Finished transaction
Thread-79078::INFO::2012-04-09 
06:28:05,398::safelease::83::ClusterLock::(release) Releasing cluster 
lock for domain 4f1199ec-9cdb-4efe-91eb-587faeec2099
Thread-79078::DEBUG::2012-04-09 
06:28:05,398::safelease::84::Storage.Misc.excCmd::(release) 
'/usr/libexec/vdsm/spmstop.sh 4f1199ec-9cdb-4efe-91eb-587faeec2099' 
(cwd /usr/libexec/vdsm/)
Thread-79078::DEBUG::2012-04-09 
06:28:06,433::safelease::84::Storage.Misc.excCmd::(release) SUCCESS: 
<err> = ''; <rc> = 0
Thread-79078::DEBUG::2012-04-09 
06:28:06,434::safelease::87::ClusterLock::(release) Cluster lock 
released successfully
Thread-79078::DEBUG::2012-04-09 
06:28:06,434::task::495::TaskManager.Task::(_debug) Task 
0b1d8a06-7441-4614-ab6a-e5ae6b7f1891: finished: None
Thread-79078::DEBUG::2012-04-09 
06:28:06,434::task::495::TaskManager.Task::(_debug) Task 
0b1d8a06-7441-4614-ab6a-e5ae6b7f1891: moving from state preparing -> 
state finished
Thread-79078::DEBUG::2012-04-09 
06:28:06,435::resourceManager::786::ResourceManager.Owner::(releaseAll) Owner.releaseAll 
requests {} resources {'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0': 
< ResourceRef 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0', isValid: 
'True' obj: 'None'>}
Thread-79078::DEBUG::2012-04-09 
06:28:06,435::resourceManager::821::ResourceManager.Owner::(cancelAll) 
Owner.cancelAll requests {}
Thread-79078::DEBUG::2012-04-09 
06:28:06,435::resourceManager::515::ResourceManager::(releaseResource) 
Trying to release resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0'
Thread-79078::DEBUG::2012-04-09 
06:28:06,436::resourceManager::530::ResourceManager::(releaseResource) 
Released resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' (0 
active users)
Thread-79078::DEBUG::2012-04-09 
06:28:06,436::resourceManager::535::ResourceManager::(releaseResource) 
Resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' is free, 
finding out if anyone is waiting for it.
Thread-79078::DEBUG::2012-04-09 
06:28:06,436::resourceManager::542::ResourceManager::(releaseResource) 
No one is waiting for resource 
'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0', Clearing records.
Thread-79078::DEBUG::2012-04-09 
06:28:06,437::task::495::TaskManager.Task::(_debug) Task 
0b1d8a06-7441-4614-ab6a-e5ae6b7f1891: ref 0 aborting False
Thread-79078::INFO::2012-04-09 
06:28:06,437::dispatcher::100::Storage.Dispatcher.Protect::(run) Run 
and protect: spmStop, Return response: {'status': {'message': 'OK', 
'code': 0}}
Thread-79080::INFO::2012-04-09 
06:28:08,375::dispatcher::94::Storage.Dispatcher.Protect::(run) Run 
and protect: repoStats, args: ()
Thread-79080::DEBUG::2012-04-09 
06:28:08,375::task::495::TaskManager.Task::(_debug) Task 
6ef7ea52-d656-4f05-a80d-10026a9cf6a7: moving from state init -> state 
preparing
Thread-79080::DEBUG::2012-04-09 
06:28:08,376::task::495::TaskManager.Task::(_debug) Task 
6ef7ea52-d656-4f05-a80d-10026a9cf6a7: finished: 
{'4f1199ec-9cdb-4efe-91eb-587faeec2099': {'delay': '0.00202488899231', 
'lastCheck': 1333967286.1603661, 'valid': True, 'code': 0}}
Thread-79080::DEBUG::2012-04-09 
06:28:08,376::task::495::TaskManager.Task::(_debug) Task 
6ef7ea52-d656-4f05-a80d-10026a9cf6a7: moving from state preparing -> 
state finished
Thread-79080::DEBUG::2012-04-09 
06:28:08,376::resourceManager::786::ResourceManager.Owner::(releaseAll) Owner.releaseAll 
requests {} resources {}
Thread-79080::DEBUG::2012-04-09 
06:28:08,377::resourceManager::821::ResourceManager.Owner::(cancelAll) 
Owner.cancelAll requests {}
Thread-79080::DEBUG::2012-04-09 
06:28:08,377::task::495::TaskManager.Task::(_debug) Task 
6ef7ea52-d656-4f05-a80d-10026a9cf6a7: ref 0 aborting False
Thread-79080::INFO::2012-04-09 
06:28:08,377::dispatcher::100::Storage.Dispatcher.Protect::(run) Run 
and protect: repoStats, Return response: {'status': {'message': 'OK', 
'code': 0}, '4f1199ec-9cdb-4efe-91eb-587faeec2099': {'delay': 
'0.00202488899231', 'lastCheck': 1333967286.1603661, 'valid': True, 
'code': 0}}
^C
*From:*users-bounces@ovirt.org [mailto:users-bounces@ovirt.org] *On 
Behalf Of *зоррыч
*Sent:* Monday, April 09, 2012 2:20 PM
*To:* rvaknin@redhat.com
*Cc:* users@ovirt.org
*Subject:* Re: [Users] Failed to attach Storage Domain export to Data 
Center
In an attempt to connect ovirt storage domain fail
[root@noc-2-synt nfs4]# cat 
b23c7ab6-b1d4-4888-8d4a-adc78e61db38/dom_md/metadata
CLASS=Backup
DESCRIPTION=export
IOOPTIMEOUTSEC=1
LEASERETRIES=3
LEASETIMESEC=5
LOCKPOLICY=
LOCKRENEWALINTERVALSEC=5
MASTER_VERSION=0
POOL_UUID=e3e565d6-8104-11e1-afd2-001cc43ceea0
REMOTE_PATH=10.1.20.2:/home/nfs4
ROLE=Regular
SDUUID=b23c7ab6-b1d4-4888-8d4a-adc78e61db38
TYPE=NFS
VERSION=0
_SHA_CKSUM=d91770b5a0f1956cbfb79a6a59b5291dc5bd2b59
logs:
2012-04-09 06:11:38,628 INFO  
[org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand] 
(pool-5-thread-50) Running command: AttachStorageDomainToPoolCommand 
internal: false. Entities affected :  ID: 
e3e565d6-8104-11e1-afd2-001cc43ceea0 Type: Storage
2012-04-09 06:11:38,658 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] 
(pool-5-thread-49) START, 
ValidateStorageServerConnectionVDSCommand(vdsId = 
270e3720-8105-11e1-a3ed-001cc43ceea0, storagePoolId = 
00000000-0000-0000-0000-000000000000, storageType = NFS, 
connectionList = [{ id: aef7de23-f271-45f3-853b-9e7b7be6607f, 
connection: 10.1.20.2:/home/nfs4 };]), log id: 651832d2
2012-04-09 06:11:38,658 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] 
(pool-5-thread-48) START, 
ValidateStorageServerConnectionVDSCommand(vdsId = 
1bb3653a-8105-11e1-9eda-001cc43ceea0, storagePoolId = 
00000000-0000-0000-0000-000000000000, storageType = NFS, 
connectionList = [{ id: aef7de23-f271-45f3-853b-9e7b7be6607f, 
connection: 10.1.20.2:/home/nfs4 };]), log id: 69324bf2
2012-04-09 06:11:38,747 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] 
(pool-5-thread-48) FINISH, ValidateStorageServerConnectionVDSCommand, 
return: {aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id: 69324bf2
2012-04-09 06:11:38,748 INFO  
[org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] 
(pool-5-thread-48) Running command: ConnectStorageToVdsCommand 
internal: true. Entities affected :  ID: 
aaa00000-0000-0000-0000-123456789aaa Type: System
2012-04-09 06:11:38,750 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] 
(pool-5-thread-48) START, ConnectStorageServerVDSCommand(vdsId = 
1bb3653a-8105-11e1-9eda-001cc43ceea0, storagePoolId = 
00000000-0000-0000-0000-000000000000, storageType = NFS, 
connectionList = [{ id: aef7de23-f271-45f3-853b-9e7b7be6607f, 
connection: 10.1.20.2:/home/nfs4 };]), log id: 1b5bf8d2
2012-04-09 06:11:38,762 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] 
(pool-5-thread-49) FINISH, ValidateStorageServerConnectionVDSCommand, 
return: {aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id: 651832d2
2012-04-09 06:11:38,763 INFO  
[org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] 
(pool-5-thread-49) Running command: ConnectStorageToVdsCommand 
internal: true. Entities affected :  ID: 
aaa00000-0000-0000-0000-123456789aaa Type: System
2012-04-09 06:11:38,765 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] 
(pool-5-thread-49) START, ConnectStorageServerVDSCommand(vdsId = 
270e3720-8105-11e1-a3ed-001cc43ceea0, storagePoolId = 
00000000-0000-0000-0000-000000000000, storageType = NFS, 
connectionList = [{ id: aef7de23-f271-45f3-853b-9e7b7be6607f, 
connection: 10.1.20.2:/home/nfs4 };]), log id: 59f6a186
2012-04-09 06:11:38,775 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] 
(pool-5-thread-48) FINISH, ConnectStorageServerVDSCommand, return: 
{aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id: 1b5bf8d2
2012-04-09 06:11:38,791 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] 
(pool-5-thread-49) FINISH, ConnectStorageServerVDSCommand, return: 
{aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id: 59f6a186
2012-04-09 06:11:38,792 INFO  
[org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand] 
(pool-5-thread-50) START, AttachStorageDomainVDSCommand(storagePoolId 
= e3e565d6-8104-11e1-afd2-001cc43ceea0, ignoreFailoverLimit = false, 
compatabilityVersion = null, storageDomainId = 
e3e565d6-8104-11e1-afd2-001cc43ceea0), log id: 58a14aa
2012-04-09 06:11:38,816 ERROR 
[org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] 
(pool-5-thread-50) Failed in AttachStorageDomainVDS method
2012-04-09 06:11:38,817 ERROR 
[org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] 
(pool-5-thread-50) Error code StorageDomainActionError and error 
message IRSGenericException: IRSErrorException: Failed to 
AttachStorageDomainVDS, error = Error in storage domain action: 
('sdUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0, 
spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0',)
2012-04-09 06:11:38,817 ERROR 
[org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] 
(pool-5-thread-50) IrsBroker::Failed::AttachStorageDomainVDS due to: 
IRSErrorException: IRSGenericException: IRSErrorException: Failed to 
AttachStorageDomainVDS, error = Error in storage domain action: 
('sdUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0, 
spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0',)
2012-04-09 06:11:38,836 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStopVDSCommand] 
(pool-5-thread-50) START, SpmStopVDSCommand(vdsId = 
270e3720-8105-11e1-a3ed-001cc43ceea0, storagePoolId = 
e3e565d6-8104-11e1-afd2-001cc43ceea0), log id: fbb0af9
2012-04-09 06:11:38,848 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStopVDSCommand] 
(pool-5-thread-50) SpmStopVDSCommand::Stopping SPM on vds 10.2.20.8, 
pool id e3e565d6-8104-11e1-afd2-001cc43ceea0
2012-04-09 06:11:39,915 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStopVDSCommand] 
(pool-5-thread-50) FINISH, SpmStopVDSCommand, log id: fbb0af9
2012-04-09 06:11:39,915 INFO  
[org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] 
(pool-5-thread-50) Irs placed on server null failed. Proceed Failover
2012-04-09 06:11:39,923 INFO  
[org.ovirt.engine.core.bll.storage.SetStoragePoolStatusCommand] 
(pool-5-thread-50) Running command: SetStoragePoolStatusCommand 
internal: true. Entities affected :  ID: 
e3e565d6-8104-11e1-afd2-001cc43ceea0 Type: StoragePool
2012-04-09 06:11:39,948 INFO  
[org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] 
(pool-5-thread-50) hostFromVds::selectedVds - 10.1.20.7, spmStatus 
Free, storage pool Default
2012-04-09 06:11:39,953 INFO  
[org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] 
(pool-5-thread-50) starting spm on vds 10.1.20.7, storage pool 
Default, prevId -1, LVER 13
2012-04-09 06:11:39,955 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] 
(pool-5-thread-50) START, SpmStartVDSCommand(vdsId = 
1bb3653a-8105-11e1-9eda-001cc43ceea0, storagePoolId = 
e3e565d6-8104-11e1-afd2-001cc43ceea0, prevId=-1, prevLVER=13, 
storagePoolFormatType=V1, recoveryMode=Manual, SCSIFencing=false), log 
id: 3eff326c
2012-04-09 06:11:39,971 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] 
(pool-5-thread-50) spmStart polling started: taskId = 
e6db13d3-1c55-4d11-9fbc-8abf50c635ae
^C
*From:*Rami Vaknin [mailto:rvaknin@redhat.com]
*Sent:* Monday, April 09, 2012 1:27 PM
*To:* зоррыч
*Cc:* users@ovirt.org <mailto:users@ovirt.org>
*Subject:* Re: [Users] Failed to attach Storage Domain export to Data 
Center
On 04/08/2012 07:05 PM, зоррыч wrote:
I found the metadata in the file ./dom_md/metadata
Changed “b23c7ab6-b1d4-4888-8d4a-adc78e61db38” to 
“e3e565d6-8104-11e1-afd2-001cc43ceea0”  (SDUUID)
It's wrong, you should change that in POOL_UUID and not in SDUUID (my 
fault, by spuuid I meant to the POOL_UUID).
The data I gave the command:
[root@noc-3-synt ~]# vdsClient -s 0 getConnectedStoragePoolsList
e3e565d6-8104-11e1-afd2-001cc43ceea0
Found in checksumvdsm.log:
Thread-36836::WARNING::2012-04-08 
11:19:47,945::persistentDict::242::Storage.PersistentDict::(refresh) 
data seal is broken metadata declares 
`4df87518878fd0d74932917b83aba9672f92dff2` should be 
`d91770b5a0f1956cbfb79a6a59b5291dc5bd2b59` (lines={'VERSION': '0', 
'LEASETIMESEC': '5', 'DESCRIPTION': 'export', 'LOCKPOLICY': '', 
'LEASERETRIES': '3', 'SDUUID': 'e3e565d6-8104-11e1-afd2-001cc43ceea0', 
'REMOTE_PATH': '10.1.20.2:/home/nfs4', 'MASTER_VERSION': '0', 
'IOOPTIMEOUTSEC': '1', 'ROLE': 'Regular', 'LOCKRENEWALINTERVALSEC': 
'5', 'POOL_UUID': '801e35d4-7405-11e1-b152-001cc43ceea0', 'TYPE': 
'NFS', 'CLASS': 'Backup'})
MetaDataSealIsBroken: Meta Data seal is broken (checksum mismatch): 
'cksum = 4df87518878fd0d74932917b83aba9672f92dff2, computed_cksum = 
d91770b5a0f1956cbfb79a6a59b5291dc5bd2b59'
Changed it.
As a result, the metadata file:
[root@noc-2-synt dom_md]# cat metadata
CLASS=Backup
DESCRIPTION=export
IOOPTIMEOUTSEC=1
LEASERETRIES=3
LEASETIMESEC=5
LOCKPOLICY=
LOCKRENEWALINTERVALSEC=5
MASTER_VERSION=0
POOL_UUID=801e35d4-7405-11e1-b152-001cc43ceea0
REMOTE_PATH=10.1.20.2:/home/nfs4
ROLE=Regular
SDUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0
TYPE=NFS
VERSION=0
_SHA_CKSUM=d91770b5a0f1956cbfb79a6a59b5291dc5bd2b59
attachment to the ovirt fails:
Thread-35311::INFO::2012-04-08 
11:25:54,668::fileSD::244::Storage.StorageDomain::(getStats) 
sdUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0 [Errno 2] No such file or 
directory: 
'/rhev/data-center/mnt/10.1.20.2:_home_nfs4/e3e565d6-8104-11e1-afd2-001cc43ceea0'
Thread-35311::ERROR::2012-04-08 
11:25:54,669::task::868::TaskManager.Task::(_setError) Unexpected error
I renamed the folder that contains the data to 
e3e565d6-8104-11e1-afd2-001cc43ceea0 for 
b23c7ab6-b1d4-4888-8d4a-adc78e61db38 (SDUUID)
attachment to the ovirt fails  =(
messages:
Error while executing action AttachStorageDomainToPool: Error in 
storage domain action
engine.log:
2012-04-08 11:53:10,557 INFO  
[org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand] 
(pool-5-thread-49) Running command: AttachStorageDomainToPoolCommand 
internal: false. Entities affected :  ID: 
e3e565d6-8104-11e1-afd2-001cc43ceea0 Type: Storage
2012-04-08 11:53:10,583 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] 
(pool-5-thread-46) START, 
ValidateStorageServerConnectionVDSCommand(vdsId = 
1bb3653a-8105-11e1-9eda-001cc43ceea0, storagePoolId = 
00000000-0000-0000-0000-000000000000, storageType = NFS, 
connectionList = [{ id: aef7de23-f271-45f3-853b-9e7b7be6607f, 
connection: 10.1.20.2:/home/nfs4 };]), log id: 3dbf104d
2012-04-08 11:53:10,583 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] 
(pool-5-thread-50) START, 
ValidateStorageServerConnectionVDSCommand(vdsId = 
270e3720-8105-11e1-a3ed-001cc43ceea0, storagePoolId = 
00000000-0000-0000-0000-000000000000, storageType = NFS, 
connectionList = [{ id: aef7de23-f271-45f3-853b-9e7b7be6607f, 
connection: 10.1.20.2:/home/nfs4 };]), log id: 4ac74ee6
2012-04-08 11:53:10,685 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] 
(pool-5-thread-50) FINISH, ValidateStorageServerConnectionVDSCommand, 
return: {aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id: 4ac74ee6
2012-04-08 11:53:10,686 INFO  
[org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] 
(pool-5-thread-50) Running command: ConnectStorageToVdsCommand 
internal: true. Entities affected :  ID: 
aaa00000-0000-0000-0000-123456789aaa Type: System
2012-04-08 11:53:10,689 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] 
(pool-5-thread-50) START, ConnectStorageServerVDSCommand(vdsId = 
270e3720-8105-11e1-a3ed-001cc43ceea0, storagePoolId = 
00000000-0000-0000-0000-000000000000, storageType = NFS, 
connectionList = [{ id: aef7de23-f271-45f3-853b-9e7b7be6607f, 
connection: 10.1.20.2:/home/nfs4 };]), log id: 53bd874a
2012-04-08 11:53:10,690 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] 
(pool-5-thread-46) FINISH, ValidateStorageServerConnectionVDSCommand, 
return: {aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id: 3dbf104d
2012-04-08 11:53:10,691 INFO  
[org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] 
(pool-5-thread-46) Running command: ConnectStorageToVdsCommand 
internal: true. Entities affected :  ID: 
aaa00000-0000-0000-0000-123456789aaa Type: System
2012-04-08 11:53:10,693 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] 
(pool-5-thread-46) START, ConnectStorageServerVDSCommand(vdsId = 
1bb3653a-8105-11e1-9eda-001cc43ceea0, storagePoolId = 
00000000-0000-0000-0000-000000000000, storageType = NFS, 
connectionList = [{ id: aef7de23-f271-45f3-853b-9e7b7be6607f, 
connection: 10.1.20.2:/home/nfs4 };]), log id: 3129fd68
2012-04-08 11:53:10,714 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] 
(pool-5-thread-50) FINISH, ConnectStorageServerVDSCommand, return: 
{aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id: 53bd874a
2012-04-08 11:53:10,716 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] 
(pool-5-thread-46) FINISH, ConnectStorageServerVDSCommand, return: 
{aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id: 3129fd68
2012-04-08 11:53:10,718 INFO  
[org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand] 
(pool-5-thread-49) START, AttachStorageDomainVDSCommand(storagePoolId 
= e3e565d6-8104-11e1-afd2-001cc43ceea0, ignoreFailoverLimit = false, 
compatabilityVersion = null, storageDomainId = 
e3e565d6-8104-11e1-afd2-001cc43ceea0), log id: 2b559a22
2012-04-08 11:53:10,737 ERROR 
[org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] 
(pool-5-thread-49) Failed in AttachStorageDomainVDS method
2012-04-08 11:53:10,737 ERROR 
[org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] 
(pool-5-thread-49) Error code StorageDomainActionError and error 
message IRSGenericException: IRSErrorException: Failed to 
AttachStorageDomainVDS, error = Error in storage domain action: 
('sdUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0, 
spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0',)
2012-04-08 11:53:10,738 ERROR 
[org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] 
(pool-5-thread-49) IrsBroker::Failed::AttachStorageDomainVDS due to: 
IRSErrorException: IRSGenericException: IRSErrorException: Failed to 
AttachStorageDomainVDS, error = Error in storage domain action: 
('sdUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0, 
spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0',)
2012-04-08 11:53:10,752 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStopVDSCommand] 
(pool-5-thread-49) START, SpmStopVDSCommand(vdsId = 
1bb3653a-8105-11e1-9eda-001cc43ceea0, storagePoolId = 
e3e565d6-8104-11e1-afd2-001cc43ceea0), log id: 6b2f2dd2
2012-04-08 11:53:10,764 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStopVDSCommand] 
(pool-5-thread-49) SpmStopVDSCommand::Stopping SPM on vds 10.1.20.7, 
pool id e3e565d6-8104-11e1-afd2-001cc43ceea0
2012-04-08 11:53:11,843 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStopVDSCommand] 
(pool-5-thread-49) FINISH, SpmStopVDSCommand, log id: 6b2f2dd2
2012-04-08 11:53:11,843 INFO  
[org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] 
(pool-5-thread-49) Irs placed on server null failed. Proceed Failover
2012-04-08 11:53:11,850 INFO  
[org.ovirt.engine.core.bll.storage.SetStoragePoolStatusCommand] 
(pool-5-thread-49) Running command: SetStoragePoolStatusCommand 
internal: true. Entities affected :  ID: 
e3e565d6-8104-11e1-afd2-001cc43ceea0 Type: StoragePool
2012-04-08 11:53:11,881 INFO  
[org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] 
(pool-5-thread-49) hostFromVds::selectedVds - 10.2.20.8, spmStatus 
Free, storage pool Default
2012-04-08 11:53:11,886 INFO  
[org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] 
(pool-5-thread-49) starting spm on vds 10.2.20.8, storage pool 
Default, prevId -1, LVER 8
2012-04-08 11:53:11,888 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] 
(pool-5-thread-49) START, SpmStartVDSCommand(vdsId = 
270e3720-8105-11e1-a3ed-001cc43ceea0, storagePoolId = 
e3e565d6-8104-11e1-afd2-001cc43ceea0, prevId=-1, prevLVER=8, 
storagePoolFormatType=V1, recoveryMode=Manual, SCSIFencing=false), log 
id: 382e702
2012-04-08 11:53:11,905 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] 
(pool-5-thread-49) spmStart polling started: taskId = 
f65c40e4-956f-4c01-ab3f-5c43f2853806
Vdsm.log:
Thread-38311::INFO::2012-04-08 
11:54:32,427::dispatcher::100::Storage.Dispatcher.Protect::(run) Run 
and protect: getStoragePoolInfo, Return response: {'status': 
{'message': 'OK', 'code': 0}, 'info': {'spm_id': 2, 'master_uuid': 
'4f1199ec-9cdb-4efe-91eb-587faeec2099', 'name': 'Default', 'version': 
'0', 'domains': '4f1199ec-9cdb-4efe-91eb-587faeec2099:Active', 
'pool_status': 'connected', 'isoprefix': '', 'type': 'NFS', 
'master_ver': 1, 'lver': 10}, 'dominfo': 
{'4f1199ec-9cdb-4efe-91eb-587faeec2099': {'status': 'Active', 
'diskfree': '490079256576', 'alerts': [], 'disktotal': '516524343296'}}}
Thread-38312::DEBUG::2012-04-08 
11:54:32,952::clientIF::239::Storage.Dispatcher.Protect::(wrapper) 
[10.1.20.2]
Thread-38312::INFO::2012-04-08 
11:54:32,952::dispatcher::94::Storage.Dispatcher.Protect::(run) Run 
and protect: validateStorageServerConnection, args: (domType=1, 
spUUID=00000000-0000-0000-0000-000000000000, conList=[{'connection': 
'10.1.20.2:/home/nfs4', 'iqn': '', 'portal': '', 'user': '', 
'password': '******', 'id': 'aef7de23-f271-45f3-853b-9e7b7be6607f', 
'port': ''}])
Thread-38312::DEBUG::2012-04-08 
11:54:32,953::task::495::TaskManager.Task::(_debug) Task 
7899228e-1fc3-4fe0-bbcd-84cb4aa1ef11: moving from state init -> state 
preparing
Thread-38312::INFO::2012-04-08 
11:54:32,953::storage_connection::127::Storage.ServerConnection::(validate) 
Request to validate NFS storage server
Thread-38312::INFO::2012-04-08 
11:54:32,953::storage_connection::53::Storage.ServerConnection::(__validateConnectionParams) 
conList=[{'connection': '10.1.20.2:/home/nfs4', 'iqn': '', 'portal': 
'', 'user': '', 'password': '******', 'id': 
'aef7de23-f271-45f3-853b-9e7b7be6607f', 'port': ''}]
Thread-38312::DEBUG::2012-04-08 
11:54:32,955::fileUtils::83::Storage.Misc.excCmd::(mount) 
'/usr/bin/sudo -n /bin/mount -o 
soft,timeo=600,retrans=6,nosharecache,vers=3 -t nfs 
10.1.20.2:/home/nfs4 /tmp/tmplHltd0' (cwd None)
Thread-38312::DEBUG::2012-04-08 
11:54:32,987::fileUtils::83::Storage.Misc.excCmd::(mount) SUCCESS: 
<err> = ''; <rc> = 0
Thread-38312::DEBUG::2012-04-08 
11:54:32,994::fileUtils::112::Storage.Misc.excCmd::(umount) 
'/usr/bin/sudo -n /bin/umount -t nfs -f /tmp/tmplHltd0' (cwd None)
Thread-38312::DEBUG::2012-04-08 
11:54:33,021::fileUtils::112::Storage.Misc.excCmd::(umount) SUCCESS: 
<err> = ''; <rc> = 0
Thread-38312::DEBUG::2012-04-08 
11:54:33,022::task::495::TaskManager.Task::(_debug) Task 
7899228e-1fc3-4fe0-bbcd-84cb4aa1ef11: finished: {'statuslist': 
[{'status': 0, 'id': 'aef7de23-f271-45f3-853b-9e7b7be6607f'}]}
Thread-38312::DEBUG::2012-04-08 
11:54:33,023::task::495::TaskManager.Task::(_debug) Task 
7899228e-1fc3-4fe0-bbcd-84cb4aa1ef11: moving from state preparing -> 
state finished
Thread-38312::DEBUG::2012-04-08 
11:54:33,023::resourceManager::786::ResourceManager.Owner::(releaseAll) Owner.releaseAll 
requests {} resources {}
Thread-38312::DEBUG::2012-04-08 
11:54:33,024::resourceManager::821::ResourceManager.Owner::(cancelAll) 
Owner.cancelAll requests {}
Thread-38312::DEBUG::2012-04-08 
11:54:33,024::task::495::TaskManager.Task::(_debug) Task 
7899228e-1fc3-4fe0-bbcd-84cb4aa1ef11: ref 0 aborting False
Thread-38312::INFO::2012-04-08 
11:54:33,024::dispatcher::100::Storage.Dispatcher.Protect::(run) Run 
and protect: validateStorageServerConnection, Return response: 
{'status': {'message': 'OK', 'code': 0}, 'statuslist': [{'status': 0, 
'id': 'aef7de23-f271-45f3-853b-9e7b7be6607f'}]}
Thread-38313::DEBUG::2012-04-08 
11:54:33,037::clientIF::239::Storage.Dispatcher.Protect::(wrapper) 
[10.1.20.2]
Thread-38313::INFO::2012-04-08 
11:54:33,037::dispatcher::94::Storage.Dispatcher.Protect::(run) Run 
and protect: connectStorageServer, args: (domType=1, 
spUUID=00000000-0000-0000-0000-000000000000, conList=[{'connection': 
'10.1.20.2:/home/nfs4', 'iqn': '', 'portal': '', 'user': '', 
'password': '******', 'id': 'aef7de23-f271-45f3-853b-9e7b7be6607f', 
'port': ''}])
Thread-38313::DEBUG::2012-04-08 
11:54:33,038::task::495::TaskManager.Task::(_debug) Task 
83b36131-7378-4f69-841d-b82637362295: moving from state init -> state 
preparing
Thread-38313::INFO::2012-04-08 
11:54:33,038::storage_connection::95::Storage.ServerConnection::(connect) 
Request to connect NFS storage server
Thread-38313::INFO::2012-04-08 
11:54:33,038::storage_connection::53::Storage.ServerConnection::(__validateConnectionParams) 
conList=[{'connection': '10.1.20.2:/home/nfs4', 'iqn': '', 'portal': 
'', 'user': '', 'password': '******', 'id': 
'aef7de23-f271-45f3-853b-9e7b7be6607f', 'port': ''}]
Thread-38313::DEBUG::2012-04-08 
11:54:33,048::lvm::547::OperationMutex::(_invalidateAllPvs) Operation 
'lvm invalidate operation' got the operation mutex
Thread-38313::DEBUG::2012-04-08 
11:54:33,048::lvm::549::OperationMutex::(_invalidateAllPvs) Operation 
'lvm invalidate operation' released the operation mutex
Thread-38313::DEBUG::2012-04-08 
11:54:33,049::lvm::559::OperationMutex::(_invalidateAllVgs) Operation 
'lvm invalidate operation' got the operation mutex
Thread-38313::DEBUG::2012-04-08 
11:54:33,049::lvm::561::OperationMutex::(_invalidateAllVgs) Operation 
'lvm invalidate operation' released the operation mutex
Thread-38313::DEBUG::2012-04-08 
11:54:33,049::lvm::580::OperationMutex::(_invalidateAllLvs) Operation 
'lvm invalidate operation' got the operation mutex
Thread-38313::DEBUG::2012-04-08 
11:54:33,050::lvm::582::OperationMutex::(_invalidateAllLvs) Operation 
'lvm invalidate operation' released the operation mutex
Thread-38313::DEBUG::2012-04-08 
11:54:33,050::task::495::TaskManager.Task::(_debug) Task 
83b36131-7378-4f69-841d-b82637362295: finished: {'statuslist': 
[{'status': 0, 'id': 'aef7de23-f271-45f3-853b-9e7b7be6607f'}]}
Thread-38313::DEBUG::2012-04-08 
11:54:33,050::task::495::TaskManager.Task::(_debug) Task 
83b36131-7378-4f69-841d-b82637362295: moving from state preparing -> 
state finished
Thread-38313::DEBUG::2012-04-08 
11:54:33,051::resourceManager::786::ResourceManager.Owner::(releaseAll) Owner.releaseAll 
requests {} resources {}
Thread-38313::DEBUG::2012-04-08 
11:54:33,051::resourceManager::821::ResourceManager.Owner::(cancelAll) 
Owner.cancelAll requests {}
Thread-38313::DEBUG::2012-04-08 
11:54:33,051::task::495::TaskManager.Task::(_debug) Task 
83b36131-7378-4f69-841d-b82637362295: ref 0 aborting False
Thread-38313::INFO::2012-04-08 
11:54:33,051::dispatcher::100::Storage.Dispatcher.Protect::(run) Run 
and protect: connectStorageServer, Return response: {'status': 
{'message': 'OK', 'code': 0}, 'statuslist': [{'status': 0, 'id': 
'aef7de23-f271-45f3-853b-9e7b7be6607f'}]}
Thread-38314::DEBUG::2012-04-08 
11:54:33,062::clientIF::239::Storage.Dispatcher.Protect::(wrapper) 
[10.1.20.2]
Thread-38314::INFO::2012-04-08 
11:54:33,062::dispatcher::94::Storage.Dispatcher.Protect::(run) Run 
and protect: attachStorageDomain, args: ( 
sdUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0 
spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0)
Thread-38314::DEBUG::2012-04-08 
11:54:33,063::task::495::TaskManager.Task::(_debug) Task 
2b306544-6fcc-443b-9ea6-23ae4b71d422: moving from state init -> state 
preparing
Thread-38314::DEBUG::2012-04-08 
11:54:33,063::resourceManager::155::ResourceManager.Request::(__init__) ResName=`Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`a97b6096-7b1a-4f93-b0c0-322399c2ac3a`::Request 
was made in '/usr/share/vdsm/storage/spm.py' line '1072' at 
'public_attachStorageDomain'
Thread-38314::DEBUG::2012-04-08 
11:54:33,064::resourceManager::463::ResourceManager::(registerResource) Trying 
to register resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' 
for lock type 'exclusive'
Thread-38314::DEBUG::2012-04-08 
11:54:33,064::resourceManager::505::ResourceManager::(registerResource) Resource 
'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' is free. Now locking as 
'exclusive' (1 active user)
Thread-38314::DEBUG::2012-04-08 
11:54:33,064::resourceManager::192::ResourceManager.Request::(grant) 
ResName=`Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`a97b6096-7b1a-4f93-b0c0-322399c2ac3a`::Granted 
request
Thread-38314::DEBUG::2012-04-08 
11:54:33,065::task::495::TaskManager.Task::(_debug) Task 
2b306544-6fcc-443b-9ea6-23ae4b71d422: _resourcesAcquired: 
Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 (exclusive)
Thread-38314::DEBUG::2012-04-08 
11:54:33,065::task::495::TaskManager.Task::(_debug) Task 
2b306544-6fcc-443b-9ea6-23ae4b71d422: ref 1 aborting False
Thread-38314::ERROR::2012-04-08 
11:54:33,065::task::868::TaskManager.Task::(_setError) Unexpected error
Traceback (most recent call last):
File "/usr/share/vdsm/storage/task.py", line 876, in _run
return fn(*args, **kargs)
File "/usr/share/vdsm/storage/spm.py", line 115, in run
return self.func(*args, **kwargs)
File "/usr/share/vdsm/storage/spm.py", line 1073, in 
public_attachStorageDomain
vars.task.getExclusiveLock(STORAGE, sdUUID)
File "/usr/share/vdsm/storage/task.py", line 1311, in getExclusiveLock
self.resOwner.acquire(namespace, resName, 
resourceManager.LockType.exclusive, timeout)
File "/usr/share/vdsm/storage/resourceManager.py", line 673, in acquire
raise ValueError("Owner %s: acquire: resource %s is already 
acquired" % (str(self), fullName))
ValueError: Owner 2b306544-6fcc-443b-9ea6-23ae4b71d422: acquire: 
resource Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 is already acquired
Thread-38314::DEBUG::2012-04-08 
11:54:33,066::task::495::TaskManager.Task::(_debug) Task 
2b306544-6fcc-443b-9ea6-23ae4b71d422: Task._run: 
2b306544-6fcc-443b-9ea6-23ae4b71d422 
('e3e565d6-8104-11e1-afd2-001cc43ceea0', 
'e3e565d6-8104-11e1-afd2-001cc43ceea0') {} failed - stopping task
Thread-38314::DEBUG::2012-04-08 
11:54:33,066::task::495::TaskManager.Task::(_debug) Task 
2b306544-6fcc-443b-9ea6-23ae4b71d422: stopping in state preparing 
(force False)
Thread-38314::DEBUG::2012-04-08 
11:54:33,067::task::495::TaskManager.Task::(_debug) Task 
2b306544-6fcc-443b-9ea6-23ae4b71d422: ref 1 aborting True
Thread-38314::INFO::2012-04-08 
11:54:33,067::task::1171::TaskManager.Task::(prepare) aborting: Task 
is aborted: 'Owner 2b306544-6fcc-443b-9ea6-23ae4b71d422: acquire: 
resource Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 is already 
acquired' - code 100
Thread-38314::DEBUG::2012-04-08 
11:54:33,067::task::495::TaskManager.Task::(_debug) Task 
2b306544-6fcc-443b-9ea6-23ae4b71d422: Prepare: aborted: Owner 
2b306544-6fcc-443b-9ea6-23ae4b71d422: acquire: resource 
Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 is already acquired
Thread-38314::DEBUG::2012-04-08 
11:54:33,067::task::495::TaskManager.Task::(_debug) Task 
2b306544-6fcc-443b-9ea6-23ae4b71d422: ref 0 aborting True
Thread-38314::DEBUG::2012-04-08 
11:54:33,068::task::495::TaskManager.Task::(_debug) Task 
2b306544-6fcc-443b-9ea6-23ae4b71d422: Task._doAbort: force False
Thread-38314::DEBUG::2012-04-08 
11:54:33,068::resourceManager::821::ResourceManager.Owner::(cancelAll) 
Owner.cancelAll requests {}
Thread-38314::DEBUG::2012-04-08 
11:54:33,068::task::495::TaskManager.Task::(_debug) Task 
2b306544-6fcc-443b-9ea6-23ae4b71d422: moving from state preparing -> 
state aborting
Thread-38314::DEBUG::2012-04-08 
11:54:33,069::task::495::TaskManager.Task::(_debug) Task 
2b306544-6fcc-443b-9ea6-23ae4b71d422: _aborting: recover policy none
Thread-38314::DEBUG::2012-04-08 
11:54:33,069::task::495::TaskManager.Task::(_debug) Task 
2b306544-6fcc-443b-9ea6-23ae4b71d422: moving from state aborting -> 
state failed
Thread-38314::DEBUG::2012-04-08 
11:54:33,069::resourceManager::786::ResourceManager.Owner::(releaseAll) Owner.releaseAll 
requests {} resources {'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0': 
< ResourceRef 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0', isValid: 
'True' obj: 'None'>}
Thread-38314::DEBUG::2012-04-08 
11:54:33,069::resourceManager::821::ResourceManager.Owner::(cancelAll) 
Owner.cancelAll requests {}
Thread-38314::DEBUG::2012-04-08 
11:54:33,070::resourceManager::515::ResourceManager::(releaseResource) 
Trying to release resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0'
Thread-38314::DEBUG::2012-04-08 
11:54:33,070::resourceManager::530::ResourceManager::(releaseResource) 
Released resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' (0 
active users)
Thread-38314::DEBUG::2012-04-08 
11:54:33,070::resourceManager::535::ResourceManager::(releaseResource) 
Resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' is free, 
finding out if anyone is waiting for it.
Thread-38314::DEBUG::2012-04-08 
11:54:33,071::resourceManager::542::ResourceManager::(releaseResource) 
No one is waiting for resource 
'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0', Clearing records.
Thread-38314::ERROR::2012-04-08 
11:54:33,071::dispatcher::106::Storage.Dispatcher.Protect::(run) Owner 
2b306544-6fcc-443b-9ea6-23ae4b71d422: acquire: resource 
Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 is already acquired
Thread-38314::ERROR::2012-04-08 
11:54:33,071::dispatcher::107::Storage.Dispatcher.Protect::(run) 
Traceback (most recent call last):
File "/usr/share/vdsm/storage/dispatcher.py", line 96, in run
result = ctask.prepare(self.func, *args, **kwargs)
File "/usr/share/vdsm/storage/task.py", line 1178, in prepare
raise self.error
ValueError: Owner 2b306544-6fcc-443b-9ea6-23ae4b71d422: acquire: 
resource Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 is already acquired
Thread-38315::DEBUG::2012-04-08 
11:54:33,086::clientIF::239::Storage.Dispatcher.Protect::(wrapper) 
[10.1.20.2]
Thread-38315::INFO::2012-04-08 
11:54:33,086::dispatcher::94::Storage.Dispatcher.Protect::(run) Run 
and protect: getSpmStatus, args: ( 
spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0)
Thread-38315::DEBUG::2012-04-08 
11:54:33,086::task::495::TaskManager.Task::(_debug) Task 
04c6f0a0-6010-4c40-a888-8c59b1e69de3: moving from state init -> state 
preparing
Thread-38315::DEBUG::2012-04-08 
11:54:33,087::spm::688::Storage.SPM::(public_getSpmStatus) 
spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0: spmStatus=SPM spmLver=10 
spmId=2
Thread-38315::DEBUG::2012-04-08 
11:54:33,087::task::495::TaskManager.Task::(_debug) Task 
04c6f0a0-6010-4c40-a888-8c59b1e69de3: finished: {'spm_st': {'spmId': 
2, 'spmStatus': 'SPM', 'spmLver': 10}}
Thread-38315::DEBUG::2012-04-08 
11:54:33,087::task::495::TaskManager.Task::(_debug) Task 
04c6f0a0-6010-4c40-a888-8c59b1e69de3: moving from state preparing -> 
state finished
Thread-38315::DEBUG::2012-04-08 
11:54:33,088::resourceManager::786::ResourceManager.Owner::(releaseAll) Owner.releaseAll 
requests {} resources {}
Thread-38315::DEBUG::2012-04-08 
11:54:33,088::resourceManager::821::ResourceManager.Owner::(cancelAll) 
Owner.cancelAll requests {}
Thread-38315::DEBUG::2012-04-08 
11:54:33,088::task::495::TaskManager.Task::(_debug) Task 
04c6f0a0-6010-4c40-a888-8c59b1e69de3: ref 0 aborting False
Thread-38315::INFO::2012-04-08 
11:54:33,089::dispatcher::100::Storage.Dispatcher.Protect::(run) Run 
and protect: getSpmStatus, Return response: {'status': {'message': 
'OK', 'code': 0}, 'spm_st': {'spmId': 2, 'spmStatus': 'SPM', 
'spmLver': 10}}
Thread-38316::DEBUG::2012-04-08 
11:54:33,101::clientIF::239::Storage.Dispatcher.Protect::(wrapper) 
[10.1.20.2]
Thread-38316::INFO::2012-04-08 
11:54:33,102::dispatcher::94::Storage.Dispatcher.Protect::(run) Run 
and protect: getAllTasksStatuses, args: ()
Thread-38316::DEBUG::2012-04-08 
11:54:33,102::task::495::TaskManager.Task::(_debug) Task 
6714a525-c7c6-4132-90a3-da21b312d7fd: moving from state init -> state 
preparing
Thread-38316::DEBUG::2012-04-08 
11:54:33,102::taskManager::92::TaskManager::(getAllTasksStatuses) Entry.
Thread-38316::DEBUG::2012-04-08 
11:54:33,102::taskManager::97::TaskManager::(getAllTasksStatuses) 
Return: {}
Thread-38316::DEBUG::2012-04-08 
11:54:33,103::task::495::TaskManager.Task::(_debug) Task 
6714a525-c7c6-4132-90a3-da21b312d7fd: finished: {'allTasksStatus': {}}
Thread-38316::DEBUG::2012-04-08 
11:54:33,103::task::495::TaskManager.Task::(_debug) Task 
6714a525-c7c6-4132-90a3-da21b312d7fd: moving from state preparing -> 
state finished
Thread-38316::DEBUG::2012-04-08 
11:54:33,103::resourceManager::786::ResourceManager.Owner::(releaseAll) Owner.releaseAll 
requests {} resources {}
Thread-38316::DEBUG::2012-04-08 
11:54:33,104::resourceManager::821::ResourceManager.Owner::(cancelAll) 
Owner.cancelAll requests {}
Thread-38316::DEBUG::2012-04-08 
11:54:33,104::task::495::TaskManager.Task::(_debug) Task 
6714a525-c7c6-4132-90a3-da21b312d7fd: ref 0 aborting False
Thread-38316::INFO::2012-04-08 
11:54:33,104::dispatcher::100::Storage.Dispatcher.Protect::(run) Run 
and protect: getAllTasksStatuses, Return response: {'status': 
{'message': 'OK', 'code': 0}, 'allTasksStatus': {}}
Thread-38317::DEBUG::2012-04-08 
11:54:33,113::clientIF::239::Storage.Dispatcher.Protect::(wrapper) 
[10.1.20.2]
Thread-38317::INFO::2012-04-08 
11:54:33,113::dispatcher::94::Storage.Dispatcher.Protect::(run) Run 
and protect: spmStop, args: ( spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0)
Thread-38317::DEBUG::2012-04-08 
11:54:33,114::task::495::TaskManager.Task::(_debug) Task 
6bec12b5-5e5b-49d8-a6d3-e8f2a5ef06bf: moving from state init -> state 
preparing
Thread-38317::DEBUG::2012-04-08 
11:54:33,114::spm::547::Storage.SPM::(public_spmStop) 
spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0
Thread-38317::DEBUG::2012-04-08 
11:54:33,114::taskManager::105::TaskManager::(getAllTasks) Entry.
Thread-38317::DEBUG::2012-04-08 
11:54:33,114::taskManager::110::TaskManager::(getAllTasks) Return: {}
Thread-38317::DEBUG::2012-04-08 
11:54:33,115::resourceManager::155::ResourceManager.Request::(__init__) ResName=`Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`417fa7c5-958c-4001-8944-d64df2b44f43`::Request 
was made in '/usr/share/vdsm/storage/spm.py' line '573' at 
'public_spmStop'
Thread-38317::DEBUG::2012-04-08 
11:54:33,115::resourceManager::463::ResourceManager::(registerResource) Trying 
to register resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' 
for lock type 'exclusive'
Thread-38317::DEBUG::2012-04-08 
11:54:33,116::resourceManager::505::ResourceManager::(registerResource) Resource 
'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' is free. Now locking as 
'exclusive' (1 active user)
Thread-38317::DEBUG::2012-04-08 
11:54:33,116::resourceManager::192::ResourceManager.Request::(grant) 
ResName=`Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`417fa7c5-958c-4001-8944-d64df2b44f43`::Granted 
request
Thread-38317::DEBUG::2012-04-08 
11:54:33,116::task::495::TaskManager.Task::(_debug) Task 
6bec12b5-5e5b-49d8-a6d3-e8f2a5ef06bf: _resourcesAcquired: 
Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 (exclusive)
Thread-38317::DEBUG::2012-04-08 
11:54:33,117::task::495::TaskManager.Task::(_debug) Task 
6bec12b5-5e5b-49d8-a6d3-e8f2a5ef06bf: ref 1 aborting False
Thread-38317::DEBUG::2012-04-08 
11:54:33,117::resourceManager::155::ResourceManager.Request::(__init__) ResName=`Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`b1954479-bdb7-47d3-a42c-b672fe788ca4`::Request 
was made in '/usr/share/vdsm/storage/spm.py' line '584' at '_stop'
Thread-38317::DEBUG::2012-04-08 
11:54:33,117::resourceManager::463::ResourceManager::(registerResource) Trying 
to register resource 
'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0' for lock type 
'exclusive'
Thread-38317::DEBUG::2012-04-08 
11:54:33,118::resourceManager::505::ResourceManager::(registerResource) Resource 
'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0' is free. Now 
locking as 'exclusive' (1 active user)
Thread-38317::DEBUG::2012-04-08 
11:54:33,118::resourceManager::192::ResourceManager.Request::(grant) 
ResName=`Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`b1954479-bdb7-47d3-a42c-b672fe788ca4`::Granted 
request
Thread-38317::DEBUG::2012-04-08 
11:54:33,119::resourceManager::515::ResourceManager::(releaseResource) 
Trying to release resource 
'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0'
Thread-38317::DEBUG::2012-04-08 
11:54:33,119::resourceManager::530::ResourceManager::(releaseResource) 
Released resource 
'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0' (0 active users)
Thread-38317::DEBUG::2012-04-08 
11:54:33,119::resourceManager::535::ResourceManager::(releaseResource) 
Resource 'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0' is 
free, finding out if anyone is waiting for it.
Thread-38317::DEBUG::2012-04-08 
11:54:33,119::resourceManager::542::ResourceManager::(releaseResource) 
No one is waiting for resource 
'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0', Clearing records.
Thread-38317::DEBUG::2012-04-08 
11:54:33,120::taskManager::118::TaskManager::(unloadTasks) Entry.
Thread-38317::DEBUG::2012-04-08 
11:54:33,120::taskManager::122::TaskManager::(unloadTasks) Return
Thread-38317::DEBUG::2012-04-08 
11:54:33,121::spm::201::Storage.SPM::(__cleanupMasterMount) master 
`/rhev/data-center/mnt/blockSD/e5a63624-716e-4bb4-ae60-cd4d7aae9ed2/master` 
is not mounted, skipping
Thread-38317::DEBUG::2012-04-08 
11:54:33,121::spm::290::Storage.SPM::(__cleanupSPM) cleaning up SPM: 
e3e565d6-8104-11e1-afd2-001cc43ceea0
Thread-38317::DEBUG::2012-04-08 
11:54:33,121::persistentDict::140::Storage.PersistentDict::(transaction) 
Starting transaction
Thread-38317::DEBUG::2012-04-08 
11:54:33,122::persistentDict::146::Storage.PersistentDict::(transaction) 
Flushing changes
Thread-38317::DEBUG::2012-04-08 
11:54:33,122::persistentDict::265::Storage.PersistentDict::(flush) 
about to write lines (FileMetadataRW)=['CLASS=Data', 
'DESCRIPTION=main', 'IOOPTIMEOUTSEC=10', 'LEASERETRIES=3', 
'LEASETIMESEC=60', 'LOCKPOLICY=', 'LOCKRENEWALINTERVALSEC=5', 
'MASTER_VERSION=1', 'POOL_DESCRIPTION=Default', 
'POOL_DOMAINS=4f1199ec-9cdb-4efe-91eb-587faeec2099:Active', 
'POOL_SPM_ID=-1', 'POOL_SPM_LVER=10', 
'POOL_UUID=e3e565d6-8104-11e1-afd2-001cc43ceea0', 
'REMOTE_PATH=nfs.corp:/share', 'ROLE=Master', 
'SDUUID=4f1199ec-9cdb-4efe-91eb-587faeec2099', 'TYPE=NFS', 
'VERSION=0', '_SHA_CKSUM=1d1cd722cad65ebb2edfa75641c30a5b97302f84']
Thread-38317::DEBUG::2012-04-08 
11:54:33,128::persistentDict::148::Storage.PersistentDict::(transaction) 
Finished transaction
Thread-38317::INFO::2012-04-08 
11:54:33,129::safelease::83::ClusterLock::(release) Releasing cluster 
lock for domain 4f1199ec-9cdb-4efe-91eb-587faeec2099
Thread-38317::DEBUG::2012-04-08 
11:54:33,129::safelease::84::Storage.Misc.excCmd::(release) 
'/usr/libexec/vdsm/spmstop.sh 4f1199ec-9cdb-4efe-91eb-587faeec2099' 
(cwd /usr/libexec/vdsm/)
Thread-38317::DEBUG::2012-04-08 
11:54:34,164::safelease::84::Storage.Misc.excCmd::(release) SUCCESS: 
<err> = ''; <rc> = 0
Thread-38317::DEBUG::2012-04-08 
11:54:34,165::safelease::87::ClusterLock::(release) Cluster lock 
released successfully
Thread-38317::DEBUG::2012-04-08 
11:54:34,165::task::495::TaskManager.Task::(_debug) Task 
6bec12b5-5e5b-49d8-a6d3-e8f2a5ef06bf: finished: None
Thread-38317::DEBUG::2012-04-08 
11:54:34,165::task::495::TaskManager.Task::(_debug) Task 
6bec12b5-5e5b-49d8-a6d3-e8f2a5ef06bf: moving from state preparing -> 
state finished
Thread-38317::DEBUG::2012-04-08 
11:54:34,166::resourceManager::786::ResourceManager.Owner::(releaseAll) Owner.releaseAll 
requests {} resources {'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0': 
< ResourceRef 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0', isValid: 
'True' obj: 'None'>}
Thread-38317::DEBUG::2012-04-08 
11:54:34,166::resourceManager::821::ResourceManager.Owner::(cancelAll) 
Owner.cancelAll requests {}
Thread-38317::DEBUG::2012-04-08 
11:54:34,166::resourceManager::515::ResourceManager::(releaseResource) 
Trying to release resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0'
Thread-38317::DEBUG::2012-04-08 
11:54:34,167::resourceManager::530::ResourceManager::(releaseResource) 
Released resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' (0 
active users)
Thread-38317::DEBUG::2012-04-08 
11:54:34,167::resourceManager::535::ResourceManager::(releaseResource) 
Resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' is free, 
finding out if anyone is waiting for it.
Thread-38317::DEBUG::2012-04-08 
11:54:34,167::resourceManager::542::ResourceManager::(releaseResource) 
No one is waiting for resource 
'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0', Clearing records.
Thread-38317::DEBUG::2012-04-08 
11:54:34,168::task::495::TaskManager.Task::(_debug) Task 
6bec12b5-5e5b-49d8-a6d3-e8f2a5ef06bf: ref 0 aborting False
Thread-38317::INFO::2012-04-08 
11:54:34,168::dispatcher::100::Storage.Dispatcher.Protect::(run) Run 
and protect: spmStop, Return response: {'status': {'message': 'OK', 
'code': 0}}
Thread-38318::INFO::2012-04-08 
11:54:34,395::dispatcher::94::Storage.Dispatcher.Protect::(run) Run 
and protect: repoStats, args: ()
Thread-38318::DEBUG::2012-04-08 
11:54:34,395::task::495::TaskManager.Task::(_debug) Task 
612d6da8-5aa1-4f72-89b4-14c3101b0cec: moving from state init -> state 
preparing
Thread-38318::DEBUG::2012-04-08 
11:54:34,395::task::495::TaskManager.Task::(_debug) Task 
612d6da8-5aa1-4f72-89b4-14c3101b0cec: finished: 
{'4f1199ec-9cdb-4efe-91eb-587faeec2099': {'delay': '0.00149178504944', 
'lastCheck': 1333900465.46615, 'valid': True, 'code': 0}}
Thread-38318::DEBUG::2012-04-08 
11:54:34,396::task::495::TaskManager.Task::(_debug) Task 
612d6da8-5aa1-4f72-89b4-14c3101b0cec: moving from state preparing -> 
state finished
Thread-38318::DEBUG::2012-04-08 
11:54:34,396::resourceManager::786::ResourceManager.Owner::(releaseAll) Owner.releaseAll 
requests {} resources {}
Thread-38318::DEBUG::2012-04-08 
11:54:34,396::resourceManager::821::ResourceManager.Owner::(cancelAll) 
Owner.cancelAll requests {}
Thread-38318::DEBUG::2012-04-08 
11:54:34,397::task::495::TaskManager.Task::(_debug) Task 
612d6da8-5aa1-4f72-89b4-14c3101b0cec: ref 0 aborting False
*From:*Rami Vaknin [mailto:rvaknin@redhat.com]
*Sent:* Sunday, April 08, 2012 7:02 PM
*To:* зоррыч
*Subject:* Re: [Users] Failed to attach Storage Domain export to Data 
Center
On 04/08/2012 03:48 PM, зоррыч wrote:
1. Change the spuuid in the domain's metadata file to be the spuuid of 
the new datacenter (the metadata can be found in dom_md/ dir somewhere 
inside the stoarge domain's directories tree), you can take the new 
spuuid from any host running on the new data center by running:
vdsClient -s 0 getConnectedStoragePoolsList
2. Try to attach the storage domain again, this time it will fail due 
to broken checksum on the metadata file, go to vdsm.log of the spm and 
look for "checksum", it will tell you what the right checksum should 
be like, copy it to the metadata file.
This time the attachment should work.
Hi.
Thank you for the help you provided me in the mailing list.
I dropped the settings using the ovirt - engine-cleanup
Ovirt reinstalled and tried to restore the virtual machine from the 
export of storage domain.
If you try an attachment domain ovirt error:
Error while executing action AttachStorageDomainToPool: The Domain is 
already used by another Data Center.<br/>In order to use it, detach it 
from the Data Center it is currently attached to.
In the logs:
2012-04-08 08:39:49,898 INFO  
[org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand] 
(pool-5-thread-50) Running command: AttachStorageDomainToPoolCommand 
internal: false. Entities affected :  ID: 
b23c7ab6-b1d4-4888-8d4a-adc78e61db38 Type: Storage
2012-04-08 08:39:49,925 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] 
(pool-5-thread-48) START, 
ValidateStorageServerConnectionVDSCommand(vdsId = 
1bb3653a-8105-11e1-9eda-001cc43ceea0, storagePoolId = 
00000000-0000-0000-0000-000000000000, storageType = NFS, 
connectionList = [{ id: aef7de23-f271-45f3-853b-9e7b7be6607f, 
connection: 10.1.20.2:/home/nfs4 };]), log id: 1985db9c
2012-04-08 08:39:49,925 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] 
(pool-5-thread-47) START, 
ValidateStorageServerConnectionVDSCommand(vdsId = 
270e3720-8105-11e1-a3ed-001cc43ceea0, storagePoolId = 
00000000-0000-0000-0000-000000000000, storageType = NFS, 
connectionList = [{ id: aef7de23-f271-45f3-853b-9e7b7be6607f, 
connection: 10.1.20.2:/home/nfs4 };]), log id: 17f02ea3
2012-04-08 08:39:50,004 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] 
(pool-5-thread-47) FINISH, ValidateStorageServerConnectionVDSCommand, 
return: {aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id: 17f02ea3
2012-04-08 08:39:50,005 INFO  
[org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] 
(pool-5-thread-47) Running command: ConnectStorageToVdsCommand 
internal: true. Entities affected :  ID: 
aaa00000-0000-0000-0000-123456789aaa Type: System
2012-04-08 08:39:50,008 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] 
(pool-5-thread-47) START, ConnectStorageServerVDSCommand(vdsId = 
270e3720-8105-11e1-a3ed-001cc43ceea0, storagePoolId = 
00000000-0000-0000-0000-000000000000, storageType = NFS, 
connectionList = [{ id: aef7de23-f271-45f3-853b-9e7b7be6607f, 
connection: 10.1.20.2:/home/nfs4 };]), log id: 6f4db784
2012-04-08 08:39:50,009 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] 
(pool-5-thread-48) FINISH, ValidateStorageServerConnectionVDSCommand, 
return: {aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id: 1985db9c
2012-04-08 08:39:50,010 INFO  
[org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] 
(pool-5-thread-48) Running command: ConnectStorageToVdsCommand 
internal: true. Entities affected :  ID: 
aaa00000-0000-0000-0000-123456789aaa Type: System
2012-04-08 08:39:50,013 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] 
(pool-5-thread-48) START, ConnectStorageServerVDSCommand(vdsId = 
1bb3653a-8105-11e1-9eda-001cc43ceea0, storagePoolId = 
00000000-0000-0000-0000-000000000000, storageType = NFS, 
connectionList = [{ id: aef7de23-f271-45f3-853b-9e7b7be6607f, 
connection: 10.1.20.2:/home/nfs4 };]), log id: 42baf149
2012-04-08 08:39:50,031 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] 
(pool-5-thread-47) FINISH, ConnectStorageServerVDSCommand, return: 
{aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id: 6f4db784
2012-04-08 08:39:50,035 INFO  
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] 
(pool-5-thread-48) FINISH, ConnectStorageServerVDSCommand, return: 
{aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id: 42baf149
2012-04-08 08:39:50,037 INFO  
[org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand] 
(pool-5-thread-50) START, AttachStorageDomainVDSCommand(storagePoolId 
= e3e565d6-8104-11e1-afd2-001cc43ceea0, ignoreFailoverLimit = false, 
compatabilityVersion = null, storageDomainId = 
b23c7ab6-b1d4-4888-8d4a-adc78e61db38), log id: 6f22bcac
2012-04-08 08:39:53,186 ERROR 
[org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] 
(pool-5-thread-50) IrsBroker::Failed::AttachStorageDomainVDS due to: 
IrsOperationFailedNoFailoverException: IRSGenericException: 
IRSErrorException: Storage domain already attached to pool: 
'domain=b23c7ab6-b1d4-4888-8d4a-adc78e61db38, 
pool=801e35d4-7405-11e1-b152-001cc43ceea0'
2012-04-08 08:39:53,186 INFO  
[org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand] 
(pool-5-thread-50) FINISH, AttachStorageDomainVDSCommand, log id: 6f22bcac
2012-04-08 08:39:53,186 ERROR 
[org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand] 
(pool-5-thread-50) Command 
org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand 
throw Vdc Bll exception. With error message VdcBLLException: 
org.ovirt.engine.core.vdsbroker.irsbroker.IrsOperationFailedNoFailoverException: 
IRSGenericException: IRSErrorException: Storage domain already 
attached to pool: 'domain=b23c7ab6-b1d4-4888-8d4a-adc78e61db38, 
pool=801e35d4-7405-11e1-b152-001cc43ceea0'
2012-04-08 08:39:53,190 INFO  
[org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand] 
(pool-5-thread-50) Command [id=b717db6a-a582-49ba-92c1-6bb09340789a]: 
Compensating NEW_ENTITY_ID of 
org.ovirt.engine.core.common.businessentities.storage_pool_iso_map; 
snapshot: storagePoolId = e3e565d6-8104-11e1-afd2-001cc43ceea0, 
storageId = b23c7ab6-b1d4-4888-8d4a-adc78e61db38.
I did not deactivate the export domain before clearing the 
configuration. How can I be in this case? I can manually remove the 
flag of the domain?
_______________________________________________
Users mailing list
Users@ovirt.org  <mailto:Users@ovirt.org>
http://lists.ovirt.org/mailman/listinfo/users
--
Thanks,
Rami Vaknin, QE @ Red Hat, TLV, IL.
--
Thanks,
Rami Vaknin, QE @ Red Hat, TLV, IL.
-- 

Thanks,

Rami Vaknin, QE @ Red Hat, TLV, IL.

--------------070000070304040100060303
Content-Type: text/html; charset=UTF-8
Content-Transfer-Encoding: 8bit

<html>
  <head>
    <meta content="text/html; charset=UTF-8" http-equiv="Content-Type">
  </head>
  <body bgcolor="#FFFFFF" text="#000000">
    <font color="#000000"><span style="color: rgb(31, 73, 125);"
        lang="EN-US">All commands in this both logs you've attached (see
        example line below) indicate that the sdUUID is still </span><span
        style="color:#1F497D" lang="EN-US">e3e565d6-8104-11e1-afd2-001cc43ceea0
        which is the same as the spUUID</span>. Are you sure your
      metadata file contains the
      "SDUUID=b23c7ab6-b1d4-4888-8d4a-adc78e61db38" line and not </font><font
      color="#000000">the wrong "SDUUID=</font><font color="#000000"><span
        style="color:#1F497D" lang="EN-US">3e565d6-8104-11e1-afd2-001cc43ceea0"</span></font><font
      color="#000000"> line?<br>
      <span style="color:#1F497D" lang="EN-US"><br>
        Thread-79075::INFO::2012-04-09
        06:28:05,328::dispatcher::94::Storage.Dispatcher.Protect::(run)
        Run and protect: attachStorageDomain, args: (
        sdUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0
        spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0)</span></font><br>
    <br>
    On 04/09/2012 01:37 PM, зоррыч wrote:
    <blockquote cite="mid:012901cd163c$b633a860$229af920$@ru"
      type="cite">
      <meta http-equiv="Content-Type" content="text/html; charset=UTF-8">
      <meta name="Generator" content="Microsoft Word 12 (filtered
        medium)">
      <style><!--
/* Font Definitions */
@font-face
	{font-family:"Cambria Math";
	panose-1:2 4 5 3 5 4 6 3 2 4;}
@font-face
	{font-family:Calibri;
	panose-1:2 15 5 2 2 2 4 3 2 4;}
@font-face
	{font-family:Tahoma;
	panose-1:2 11 6 4 3 5 4 4 2 4;}
@font-face
	{font-family:Consolas;
	panose-1:2 11 6 9 2 2 4 3 2 4;}
/* Style Definitions */
p.MsoNormal, li.MsoNormal, div.MsoNormal
	{margin:0cm;
	margin-bottom:.0001pt;
	font-size:11.0pt;
	font-family:"Calibri","sans-serif";
	color:black;}
a:link, span.MsoHyperlink
	{mso-style-priority:99;
	color:blue;
	text-decoration:underline;}
a:visited, span.MsoHyperlinkFollowed
	{mso-style-priority:99;
	color:purple;
	text-decoration:underline;}
pre
	{mso-style-priority:99;
	mso-style-link:"Стандартный HTML Знак";
	margin:0cm;
	margin-bottom:.0001pt;
	font-size:10.0pt;
	font-family:"Courier New";
	color:black;}
p.MsoAcetate, li.MsoAcetate, div.MsoAcetate
	{mso-style-priority:99;
	mso-style-link:"Текст выноски Знак";
	margin:0cm;
	margin-bottom:.0001pt;
	font-size:8.0pt;
	font-family:"Tahoma","sans-serif";
	color:black;}
span.HTML
	{mso-style-name:"Стандартный HTML Знак";
	mso-style-priority:99;
	mso-style-link:"Стандартный HTML";
	font-family:Consolas;
	color:black;}
span.a
	{mso-style-name:"Текст выноски Знак";
	mso-style-priority:99;
	mso-style-link:"Текст выноски";
	font-family:"Tahoma","sans-serif";
	color:black;}
span.EmailStyle21
	{mso-style-type:personal;
	font-family:"Calibri","sans-serif";
	color:windowtext;}
span.EmailStyle22
	{mso-style-type:personal;
	font-family:"Calibri","sans-serif";
	color:#1F497D;}
span.EmailStyle23
	{mso-style-type:personal;
	font-family:"Calibri","sans-serif";
	color:#1F497D;}
span.EmailStyle24
	{mso-style-type:personal-reply;
	font-family:"Calibri","sans-serif";
	color:#1F497D;}
.MsoChpDefault
	{mso-style-type:export-only;
	font-size:10.0pt;}
@page WordSection1
	{size:612.0pt 792.0pt;
	margin:2.0cm 42.5pt 2.0cm 3.0cm;}
div.WordSection1
	{page:WordSection1;}
--></style><!--[if gte mso 9]><xml>
<o:shapedefaults v:ext="edit" spidmax="1026" />
</xml><![endif]--><!--[if gte mso 9]><xml>
<o:shapelayout v:ext="edit">
<o:idmap v:ext="edit" data="1" />
</o:shapelayout></xml><![endif]-->
      <div class="WordSection1">
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Vdsm
            log (spm):<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"><o:p> </o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"><o:p> </o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79073::DEBUG::2012-04-09
            06:28:05,189::clientIF::239::Storage.Dispatcher.Protect::(wrapper)
            [10.1.20.2]<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79073::INFO::2012-04-09
            06:28:05,190::dispatcher::94::Storage.Dispatcher.Protect::(run)
            Run and protect: validateStorageServerConnection, args:
            (domType=1, spUUID=00000000-0000-0000-0000-000000000000,
            conList=[{'connection': '10.1.20.2:/home/nfs4', 'iqn': '',
            'portal': '', 'user': '', 'password': '******', 'id':
            'aef7de23-f271-45f3-853b-9e7b7be6607f', 'port': ''}])<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79073::DEBUG::2012-04-09
            06:28:05,190::task::495::TaskManager.Task::(_debug) Task
            3634fc46-5104-4ebd-8ed6-1d4428beed92: moving from state init
            -> state preparing<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79073::INFO::2012-04-09
            06:28:05,190::storage_connection::127::Storage.ServerConnection::(validate)
            Request to validate NFS storage server<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79073::INFO::2012-04-09
            06:28:05,191::storage_connection::53::Storage.ServerConnection::(__validateConnectionParams)
            conList=[{'connection': '10.1.20.2:/home/nfs4', 'iqn': '',
            'portal': '', 'user': '', 'password': '******', 'id':
            'aef7de23-f271-45f3-853b-9e7b7be6607f', 'port': ''}]<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79073::DEBUG::2012-04-09
            06:28:05,192::fileUtils::83::Storage.Misc.excCmd::(mount)
            '/usr/bin/sudo -n /bin/mount -o
            soft,timeo=600,retrans=6,nosharecache,vers=3 -t nfs
            10.1.20.2:/home/nfs4 /tmp/tmp2rKh29' (cwd None)<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79073::DEBUG::2012-04-09
            06:28:05,228::fileUtils::83::Storage.Misc.excCmd::(mount)
            SUCCESS: <err> = ''; <rc> = 0<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79073::DEBUG::2012-04-09
            06:28:05,235::fileUtils::112::Storage.Misc.excCmd::(umount)
            '/usr/bin/sudo -n /bin/umount -t nfs -f /tmp/tmp2rKh29' (cwd
            None)<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79073::DEBUG::2012-04-09
            06:28:05,270::fileUtils::112::Storage.Misc.excCmd::(umount)
            SUCCESS: <err> = ''; <rc> = 0<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79073::DEBUG::2012-04-09
            06:28:05,271::task::495::TaskManager.Task::(_debug) Task
            3634fc46-5104-4ebd-8ed6-1d4428beed92: finished:
            {'statuslist': [{'status': 0, 'id':
            'aef7de23-f271-45f3-853b-9e7b7be6607f'}]}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79073::DEBUG::2012-04-09
            06:28:05,272::task::495::TaskManager.Task::(_debug) Task
            3634fc46-5104-4ebd-8ed6-1d4428beed92: moving from state
            preparing -> state finished<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79073::DEBUG::2012-04-09
            06:28:05,272::resourceManager::786::ResourceManager.Owner::(releaseAll)
            Owner.releaseAll requests {} resources {}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79073::DEBUG::2012-04-09
            06:28:05,272::resourceManager::821::ResourceManager.Owner::(cancelAll)
            Owner.cancelAll requests {}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79073::DEBUG::2012-04-09
            06:28:05,273::task::495::TaskManager.Task::(_debug) Task
            3634fc46-5104-4ebd-8ed6-1d4428beed92: ref 0 aborting False<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79073::INFO::2012-04-09
            06:28:05,273::dispatcher::100::Storage.Dispatcher.Protect::(run)
            Run and protect: validateStorageServerConnection, Return
            response: {'status': {'message': 'OK', 'code': 0},
            'statuslist': [{'status': 0, 'id':
            'aef7de23-f271-45f3-853b-9e7b7be6607f'}]}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79074::DEBUG::2012-04-09
            06:28:05,297::clientIF::239::Storage.Dispatcher.Protect::(wrapper)
            [10.1.20.2]<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79074::INFO::2012-04-09
            06:28:05,298::dispatcher::94::Storage.Dispatcher.Protect::(run)
            Run and protect: connectStorageServer, args: (domType=1,
            spUUID=00000000-0000-0000-0000-000000000000,
            conList=[{'connection': '10.1.20.2:/home/nfs4', 'iqn': '',
            'portal': '', 'user': '', 'password': '******', 'id':
            'aef7de23-f271-45f3-853b-9e7b7be6607f', 'port': ''}])<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79074::DEBUG::2012-04-09
            06:28:05,298::task::495::TaskManager.Task::(_debug) Task
            4cf78074-142b-418d-ae9d-aa1a3afbd0b8: moving from state init
            -> state preparing<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79074::INFO::2012-04-09
            06:28:05,298::storage_connection::95::Storage.ServerConnection::(connect)
            Request to connect NFS storage server<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79074::INFO::2012-04-09
            06:28:05,298::storage_connection::53::Storage.ServerConnection::(__validateConnectionParams)
            conList=[{'connection': '10.1.20.2:/home/nfs4', 'iqn': '',
            'portal': '', 'user': '', 'password': '******', 'id':
            'aef7de23-f271-45f3-853b-9e7b7be6607f', 'port': ''}]<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79074::DEBUG::2012-04-09
            06:28:05,308::lvm::547::OperationMutex::(_invalidateAllPvs)
            Operation 'lvm invalidate operation' got the operation mutex<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79074::DEBUG::2012-04-09
            06:28:05,308::lvm::549::OperationMutex::(_invalidateAllPvs)
            Operation 'lvm invalidate operation' released the operation
            mutex<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79074::DEBUG::2012-04-09
            06:28:05,309::lvm::559::OperationMutex::(_invalidateAllVgs)
            Operation 'lvm invalidate operation' got the operation mutex<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79074::DEBUG::2012-04-09
            06:28:05,309::lvm::561::OperationMutex::(_invalidateAllVgs)
            Operation 'lvm invalidate operation' released the operation
            mutex<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79074::DEBUG::2012-04-09
            06:28:05,310::lvm::580::OperationMutex::(_invalidateAllLvs)
            Operation 'lvm invalidate operation' got the operation mutex<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79074::DEBUG::2012-04-09
            06:28:05,310::lvm::582::OperationMutex::(_invalidateAllLvs)
            Operation 'lvm invalidate operation' released the operation
            mutex<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79074::DEBUG::2012-04-09
            06:28:05,310::task::495::TaskManager.Task::(_debug) Task
            4cf78074-142b-418d-ae9d-aa1a3afbd0b8: finished:
            {'statuslist': [{'status': 0, 'id':
            'aef7de23-f271-45f3-853b-9e7b7be6607f'}]}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79074::DEBUG::2012-04-09
            06:28:05,311::task::495::TaskManager.Task::(_debug) Task
            4cf78074-142b-418d-ae9d-aa1a3afbd0b8: moving from state
            preparing -> state finished<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79074::DEBUG::2012-04-09
            06:28:05,311::resourceManager::786::ResourceManager.Owner::(releaseAll)
            Owner.releaseAll requests {} resources {}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79074::DEBUG::2012-04-09
            06:28:05,311::resourceManager::821::ResourceManager.Owner::(cancelAll)
            Owner.cancelAll requests {}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79074::DEBUG::2012-04-09
            06:28:05,311::task::495::TaskManager.Task::(_debug) Task
            4cf78074-142b-418d-ae9d-aa1a3afbd0b8: ref 0 aborting False<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79074::INFO::2012-04-09
            06:28:05,312::dispatcher::100::Storage.Dispatcher.Protect::(run)
            Run and protect: connectStorageServer, Return response:
            {'status': {'message': 'OK', 'code': 0}, 'statuslist':
            [{'status': 0, 'id':
            'aef7de23-f271-45f3-853b-9e7b7be6607f'}]}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,328::clientIF::239::Storage.Dispatcher.Protect::(wrapper)
            [10.1.20.2]<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::INFO::2012-04-09
            06:28:05,328::dispatcher::94::Storage.Dispatcher.Protect::(run)
            Run and protect: attachStorageDomain, args: (
            sdUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0
            spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0)<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,329::task::495::TaskManager.Task::(_debug) Task
            b0ef8b95-fabe-43e0-944c-eb959cf9930a: moving from state init
            -> state preparing<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,329::resourceManager::155::ResourceManager.Request::(__init__)
            ResName=`Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`af876ee5-24f4-43f1-a161-d495903c82b5`::Request
            was made in '/usr/share/vdsm/storage/spm.py' line '1072' at
            'public_attachStorageDomain'<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,330::resourceManager::463::ResourceManager::(registerResource)
            Trying to register resource
            'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' for lock type
            'exclusive'<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,330::resourceManager::505::ResourceManager::(registerResource)
            Resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' is
            free. Now locking as 'exclusive' (1 active user)<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,330::resourceManager::192::ResourceManager.Request::(grant)
            ResName=`Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`af876ee5-24f4-43f1-a161-d495903c82b5`::Granted
            request<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,331::task::495::TaskManager.Task::(_debug) Task
            b0ef8b95-fabe-43e0-944c-eb959cf9930a: _resourcesAcquired:
            Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 (exclusive)<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,331::task::495::TaskManager.Task::(_debug) Task
            b0ef8b95-fabe-43e0-944c-eb959cf9930a: ref 1 aborting False<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::ERROR::2012-04-09
            06:28:05,331::task::868::TaskManager.Task::(_setError)
            Unexpected error<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Traceback
            (most recent call last):<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> 
            File "/usr/share/vdsm/storage/task.py", line 876, in _run<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">   
            return fn(*args, **kargs)<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> 
            File "/usr/share/vdsm/storage/spm.py", line 115, in run<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">   
            return self.func(*args, **kwargs)<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> 
            File "/usr/share/vdsm/storage/spm.py", line 1073, in
            public_attachStorageDomain<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">   
            vars.task.getExclusiveLock(STORAGE, sdUUID)<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> 
            File "/usr/share/vdsm/storage/task.py", line 1311, in
            getExclusiveLock<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">   
            self.resOwner.acquire(namespace, resName,
            resourceManager.LockType.exclusive, timeout)<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> 
            File "/usr/share/vdsm/storage/resourceManager.py", line 673,
            in acquire<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">   
            raise ValueError("Owner %s: acquire: resource %s is already
            acquired" % (str(self), fullName))<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">ValueError:
            Owner b0ef8b95-fabe-43e0-944c-eb959cf9930a: acquire:
            resource Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 is
            already acquired<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,332::task::495::TaskManager.Task::(_debug) Task
            b0ef8b95-fabe-43e0-944c-eb959cf9930a: Task._run:
            b0ef8b95-fabe-43e0-944c-eb959cf9930a
            ('e3e565d6-8104-11e1-afd2-001cc43ceea0',
            'e3e565d6-8104-11e1-afd2-001cc43ceea0') {} failed - stopping
            task<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,332::task::495::TaskManager.Task::(_debug) Task
            b0ef8b95-fabe-43e0-944c-eb959cf9930a: stopping in state
            preparing (force False)<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,333::task::495::TaskManager.Task::(_debug) Task
            b0ef8b95-fabe-43e0-944c-eb959cf9930a: ref 1 aborting True<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::INFO::2012-04-09
            06:28:05,333::task::1171::TaskManager.Task::(prepare)
            aborting: Task is aborted: 'Owner
            b0ef8b95-fabe-43e0-944c-eb959cf9930a: acquire: resource
            Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 is already
            acquired' - code 100<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,333::task::495::TaskManager.Task::(_debug) Task
            b0ef8b95-fabe-43e0-944c-eb959cf9930a: Prepare: aborted:
            Owner b0ef8b95-fabe-43e0-944c-eb959cf9930a: acquire:
            resource Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 is
            already acquired<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,333::task::495::TaskManager.Task::(_debug) Task
            b0ef8b95-fabe-43e0-944c-eb959cf9930a: ref 0 aborting True<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,334::task::495::TaskManager.Task::(_debug) Task
            b0ef8b95-fabe-43e0-944c-eb959cf9930a: Task._doAbort: force
            False<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,334::resourceManager::821::ResourceManager.Owner::(cancelAll)
            Owner.cancelAll requests {}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,334::task::495::TaskManager.Task::(_debug) Task
            b0ef8b95-fabe-43e0-944c-eb959cf9930a: moving from state
            preparing -> state aborting<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,335::task::495::TaskManager.Task::(_debug) Task
            b0ef8b95-fabe-43e0-944c-eb959cf9930a: _aborting: recover
            policy none<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,335::task::495::TaskManager.Task::(_debug) Task
            b0ef8b95-fabe-43e0-944c-eb959cf9930a: moving from state
            aborting -> state failed<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,335::resourceManager::786::ResourceManager.Owner::(releaseAll)
            Owner.releaseAll requests {} resources
            {'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0': <
            ResourceRef 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0',
            isValid: 'True' obj: 'None'>}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,335::resourceManager::821::ResourceManager.Owner::(cancelAll)
            Owner.cancelAll requests {}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,336::resourceManager::515::ResourceManager::(releaseResource)
            Trying to release resource
            'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0'<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,336::resourceManager::530::ResourceManager::(releaseResource)
            Released resource
            'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' (0 active
            users)<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,336::resourceManager::535::ResourceManager::(releaseResource)
            Resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' is
            free, finding out if anyone is waiting for it.<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::DEBUG::2012-04-09
            06:28:05,337::resourceManager::542::ResourceManager::(releaseResource)
            No one is waiting for resource
            'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0', Clearing
            records.<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::ERROR::2012-04-09
            06:28:05,337::dispatcher::106::Storage.Dispatcher.Protect::(run)
            Owner b0ef8b95-fabe-43e0-944c-eb959cf9930a: acquire:
            resource Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 is
            already acquired<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79075::ERROR::2012-04-09
            06:28:05,337::dispatcher::107::Storage.Dispatcher.Protect::(run)
            Traceback (most recent call last):<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> 
            File "/usr/share/vdsm/storage/dispatcher.py", line 96, in
            run<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">   
            result = ctask.prepare(self.func, *args, **kwargs)<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> 
            File "/usr/share/vdsm/storage/task.py", line 1178, in
            prepare<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">   
            raise self.error<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">ValueError:
            Owner b0ef8b95-fabe-43e0-944c-eb959cf9930a: acquire:
            resource Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 is
            already acquired<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"><o:p> </o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79076::DEBUG::2012-04-09
            06:28:05,349::clientIF::239::Storage.Dispatcher.Protect::(wrapper)
            [10.1.20.2]<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79076::INFO::2012-04-09
            06:28:05,349::dispatcher::94::Storage.Dispatcher.Protect::(run)
            Run and protect: getSpmStatus, args: (
            spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0)<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79076::DEBUG::2012-04-09
            06:28:05,350::task::495::TaskManager.Task::(_debug) Task
            13ba6f56-88a9-4bab-a677-11b84d1170dd: moving from state init
            -> state preparing<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79076::DEBUG::2012-04-09
            06:28:05,350::spm::688::Storage.SPM::(public_getSpmStatus)
            spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0: spmStatus=SPM
            spmLver=18 spmId=2<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79076::DEBUG::2012-04-09
            06:28:05,350::task::495::TaskManager.Task::(_debug) Task
            13ba6f56-88a9-4bab-a677-11b84d1170dd: finished: {'spm_st':
            {'spmId': 2, 'spmStatus': 'SPM', 'spmLver': 18}}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79076::DEBUG::2012-04-09
            06:28:05,350::task::495::TaskManager.Task::(_debug) Task
            13ba6f56-88a9-4bab-a677-11b84d1170dd: moving from state
            preparing -> state finished<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79076::DEBUG::2012-04-09
            06:28:05,351::resourceManager::786::ResourceManager.Owner::(releaseAll)
            Owner.releaseAll requests {} resources {}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79076::DEBUG::2012-04-09
            06:28:05,351::resourceManager::821::ResourceManager.Owner::(cancelAll)
            Owner.cancelAll requests {}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79076::DEBUG::2012-04-09
            06:28:05,351::task::495::TaskManager.Task::(_debug) Task
            13ba6f56-88a9-4bab-a677-11b84d1170dd: ref 0 aborting False<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79076::INFO::2012-04-09
            06:28:05,352::dispatcher::100::Storage.Dispatcher.Protect::(run)
            Run and protect: getSpmStatus, Return response: {'status':
            {'message': 'OK', 'code': 0}, 'spm_st': {'spmId': 2,
            'spmStatus': 'SPM', 'spmLver': 18}}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79077::DEBUG::2012-04-09
            06:28:05,362::clientIF::239::Storage.Dispatcher.Protect::(wrapper)
            [10.1.20.2]<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79077::INFO::2012-04-09
            06:28:05,363::dispatcher::94::Storage.Dispatcher.Protect::(run)
            Run and protect: getAllTasksStatuses, args: ()<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79077::DEBUG::2012-04-09
            06:28:05,363::task::495::TaskManager.Task::(_debug) Task
            d87eafd3-24e9-4aaf-ba81-9c52dbfb5a1c: moving from state init
            -> state preparing<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79077::DEBUG::2012-04-09
            06:28:05,363::taskManager::92::TaskManager::(getAllTasksStatuses)
            Entry.<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79077::DEBUG::2012-04-09
            06:28:05,363::taskManager::97::TaskManager::(getAllTasksStatuses)
            Return: {}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79077::DEBUG::2012-04-09
            06:28:05,364::task::495::TaskManager.Task::(_debug) Task
            d87eafd3-24e9-4aaf-ba81-9c52dbfb5a1c: finished:
            {'allTasksStatus': {}}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79077::DEBUG::2012-04-09
            06:28:05,364::task::495::TaskManager.Task::(_debug) Task
            d87eafd3-24e9-4aaf-ba81-9c52dbfb5a1c: moving from state
            preparing -> state finished<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79077::DEBUG::2012-04-09
            06:28:05,364::resourceManager::786::ResourceManager.Owner::(releaseAll)
            Owner.releaseAll requests {} resources {}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79077::DEBUG::2012-04-09
            06:28:05,365::resourceManager::821::ResourceManager.Owner::(cancelAll)
            Owner.cancelAll requests {}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79077::DEBUG::2012-04-09
            06:28:05,365::task::495::TaskManager.Task::(_debug) Task
            d87eafd3-24e9-4aaf-ba81-9c52dbfb5a1c: ref 0 aborting False<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79077::INFO::2012-04-09
            06:28:05,365::dispatcher::100::Storage.Dispatcher.Protect::(run)
            Run and protect: getAllTasksStatuses, Return response:
            {'status': {'message': 'OK', 'code': 0}, 'allTasksStatus':
            {}}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,373::clientIF::239::Storage.Dispatcher.Protect::(wrapper)
            [10.1.20.2]<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::INFO::2012-04-09
            06:28:05,373::dispatcher::94::Storage.Dispatcher.Protect::(run)
            Run and protect: spmStop, args: (
            spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0)<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,374::task::495::TaskManager.Task::(_debug) Task
            0b1d8a06-7441-4614-ab6a-e5ae6b7f1891: moving from state init
            -> state preparing<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,374::spm::547::Storage.SPM::(public_spmStop)
            spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,374::taskManager::105::TaskManager::(getAllTasks)
            Entry.<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,375::taskManager::110::TaskManager::(getAllTasks)
            Return: {}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,375::resourceManager::155::ResourceManager.Request::(__init__)
            ResName=`Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`0f0e6998-87a6-40e8-8ded-9f600cb0ca2d`::Request
            was made in '/usr/share/vdsm/storage/spm.py' line '573' at
            'public_spmStop'<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,375::resourceManager::463::ResourceManager::(registerResource)
            Trying to register resource
            'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' for lock type
            'exclusive'<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,376::resourceManager::505::ResourceManager::(registerResource)
            Resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' is
            free. Now locking as 'exclusive' (1 active user)<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,376::resourceManager::192::ResourceManager.Request::(grant)
            ResName=`Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`0f0e6998-87a6-40e8-8ded-9f600cb0ca2d`::Granted
            request<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,376::task::495::TaskManager.Task::(_debug) Task
            0b1d8a06-7441-4614-ab6a-e5ae6b7f1891: _resourcesAcquired:
            Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 (exclusive)<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,377::task::495::TaskManager.Task::(_debug) Task
            0b1d8a06-7441-4614-ab6a-e5ae6b7f1891: ref 1 aborting False<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,377::resourceManager::155::ResourceManager.Request::(__init__)
            ResName=`Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`eb290e92-2fbb-4e12-bde2-83292aade8e4`::Request
            was made in '/usr/share/vdsm/storage/spm.py' line '584' at
            '_stop'<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,378::resourceManager::463::ResourceManager::(registerResource)
            Trying to register resource
            'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0' for
            lock type 'exclusive'<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,378::resourceManager::505::ResourceManager::(registerResource)
            Resource
            'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0' is
            free. Now locking as 'exclusive' (1 active user)<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,378::resourceManager::192::ResourceManager.Request::(grant)
            ResName=`Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`eb290e92-2fbb-4e12-bde2-83292aade8e4`::Granted
            request<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,379::resourceManager::515::ResourceManager::(releaseResource)
            Trying to release resource
            'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0'<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,379::resourceManager::530::ResourceManager::(releaseResource)
            Released resource
            'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0' (0
            active users)<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,379::resourceManager::535::ResourceManager::(releaseResource)
            Resource
            'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0' is
            free, finding out if anyone is waiting for it.<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,380::resourceManager::542::ResourceManager::(releaseResource)
            No one is waiting for resource
            'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0',
            Clearing records.<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,380::taskManager::118::TaskManager::(unloadTasks)
            Entry.<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,380::taskManager::122::TaskManager::(unloadTasks)
            Return<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,381::spm::201::Storage.SPM::(__cleanupMasterMount)
            master
            `/rhev/data-center/mnt/blockSD/e5a63624-716e-4bb4-ae60-cd4d7aae9ed2/master`
            is not mounted, skipping<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,381::spm::290::Storage.SPM::(__cleanupSPM) cleaning
            up SPM: e3e565d6-8104-11e1-afd2-001cc43ceea0<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,381::persistentDict::140::Storage.PersistentDict::(transaction)
            Starting transaction<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,382::persistentDict::146::Storage.PersistentDict::(transaction)
            Flushing changes<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,382::persistentDict::265::Storage.PersistentDict::(flush)
            about to write lines (FileMetadataRW)=['CLASS=Data',
            'DESCRIPTION=main', 'IOOPTIMEOUTSEC=10', 'LEASERETRIES=3',
            'LEASETIMESEC=60', 'LOCKPOLICY=',
            'LOCKRENEWALINTERVALSEC=5', 'MASTER_VERSION=1',
            'POOL_DESCRIPTION=Default',
            'POOL_DOMAINS=4f1199ec-9cdb-4efe-91eb-587faeec2099:Active',
            'POOL_SPM_ID=-1', 'POOL_SPM_LVER=18',
            'POOL_UUID=e3e565d6-8104-11e1-afd2-001cc43ceea0',
            'REMOTE_PATH=nfs.corp:/share', 'ROLE=Master',
            'SDUUID=4f1199ec-9cdb-4efe-91eb-587faeec2099', 'TYPE=NFS',
            'VERSION=0',
            '_SHA_CKSUM=5a3d228ad230c9d61a754c22abaa6b5f11b6c721']<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,397::persistentDict::148::Storage.PersistentDict::(transaction)
            Finished transaction<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::INFO::2012-04-09
            06:28:05,398::safelease::83::ClusterLock::(release)
            Releasing cluster lock for domain
            4f1199ec-9cdb-4efe-91eb-587faeec2099<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:05,398::safelease::84::Storage.Misc.excCmd::(release)
            '/usr/libexec/vdsm/spmstop.sh
            4f1199ec-9cdb-4efe-91eb-587faeec2099' (cwd
            /usr/libexec/vdsm/)<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:06,433::safelease::84::Storage.Misc.excCmd::(release)
            SUCCESS: <err> = ''; <rc> = 0<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:06,434::safelease::87::ClusterLock::(release) Cluster
            lock released successfully<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:06,434::task::495::TaskManager.Task::(_debug) Task
            0b1d8a06-7441-4614-ab6a-e5ae6b7f1891: finished: None<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:06,434::task::495::TaskManager.Task::(_debug) Task
            0b1d8a06-7441-4614-ab6a-e5ae6b7f1891: moving from state
            preparing -> state finished<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:06,435::resourceManager::786::ResourceManager.Owner::(releaseAll)
            Owner.releaseAll requests {} resources
            {'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0': <
            ResourceRef 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0',
            isValid: 'True' obj: 'None'>}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:06,435::resourceManager::821::ResourceManager.Owner::(cancelAll)
            Owner.cancelAll requests {}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:06,435::resourceManager::515::ResourceManager::(releaseResource)
            Trying to release resource
            'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0'<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:06,436::resourceManager::530::ResourceManager::(releaseResource)
            Released resource
            'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' (0 active
            users)<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:06,436::resourceManager::535::ResourceManager::(releaseResource)
            Resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' is
            free, finding out if anyone is waiting for it.<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:06,436::resourceManager::542::ResourceManager::(releaseResource)
            No one is waiting for resource
            'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0', Clearing
            records.<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::DEBUG::2012-04-09
            06:28:06,437::task::495::TaskManager.Task::(_debug) Task
            0b1d8a06-7441-4614-ab6a-e5ae6b7f1891: ref 0 aborting False<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79078::INFO::2012-04-09
            06:28:06,437::dispatcher::100::Storage.Dispatcher.Protect::(run)
            Run and protect: spmStop, Return response: {'status':
            {'message': 'OK', 'code': 0}}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79080::INFO::2012-04-09
            06:28:08,375::dispatcher::94::Storage.Dispatcher.Protect::(run)
            Run and protect: repoStats, args: ()<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79080::DEBUG::2012-04-09
            06:28:08,375::task::495::TaskManager.Task::(_debug) Task
            6ef7ea52-d656-4f05-a80d-10026a9cf6a7: moving from state init
            -> state preparing<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79080::DEBUG::2012-04-09
            06:28:08,376::task::495::TaskManager.Task::(_debug) Task
            6ef7ea52-d656-4f05-a80d-10026a9cf6a7: finished:
            {'4f1199ec-9cdb-4efe-91eb-587faeec2099': {'delay':
            '0.00202488899231', 'lastCheck': 1333967286.1603661,
            'valid': True, 'code': 0}}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79080::DEBUG::2012-04-09
            06:28:08,376::task::495::TaskManager.Task::(_debug) Task
            6ef7ea52-d656-4f05-a80d-10026a9cf6a7: moving from state
            preparing -> state finished<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79080::DEBUG::2012-04-09
            06:28:08,376::resourceManager::786::ResourceManager.Owner::(releaseAll)
            Owner.releaseAll requests {} resources {}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79080::DEBUG::2012-04-09
            06:28:08,377::resourceManager::821::ResourceManager.Owner::(cancelAll)
            Owner.cancelAll requests {}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79080::DEBUG::2012-04-09
            06:28:08,377::task::495::TaskManager.Task::(_debug) Task
            6ef7ea52-d656-4f05-a80d-10026a9cf6a7: ref 0 aborting False<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-79080::INFO::2012-04-09
            06:28:08,377::dispatcher::100::Storage.Dispatcher.Protect::(run)
            Run and protect: repoStats, Return response: {'status':
            {'message': 'OK', 'code': 0},
            '4f1199ec-9cdb-4efe-91eb-587faeec2099': {'delay':
            '0.00202488899231', 'lastCheck': 1333967286.1603661,
            'valid': True, 'code': 0}}<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">^C<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D"><o:p> </o:p></span></p>
        <div>
          <div style="border:none;border-top:solid #B5C4DF
            1.0pt;padding:3.0pt 0cm 0cm 0cm">
            <p class="MsoNormal"><b><span
style="font-size:10.0pt;font-family:"Tahoma","sans-serif";color:windowtext">From:</span></b><span
style="font-size:10.0pt;font-family:"Tahoma","sans-serif";color:windowtext">
                <a class="moz-txt-link-abbreviated" href="mailto:users-bounces@ovirt.org">users-bounces@ovirt.org</a> [<a class="moz-txt-link-freetext" href="mailto:users-bounces@ovirt.org">mailto:users-bounces@ovirt.org</a>]
                <b>On Behalf Of </b>зоррыч<br>
                <b>Sent:</b> Monday, April 09, 2012 2:20 PM<br>
                <b>To:</b> <a class="moz-txt-link-abbreviated" href="mailto:rvaknin@redhat.com">rvaknin@redhat.com</a><br>
                <b>Cc:</b> <a class="moz-txt-link-abbreviated" href="mailto:users@ovirt.org">users@ovirt.org</a><br>
                <b>Subject:</b> Re: [Users] Failed to attach Storage
                Domain export to Data Center<o:p></o:p></span></p>
          </div>
        </div>
        <p class="MsoNormal"><o:p> </o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">In
            an attempt to connect ovirt storage domain fail<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">[root@noc-2-synt
            nfs4]# cat
            b23c7ab6-b1d4-4888-8d4a-adc78e61db38/dom_md/metadata<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">CLASS=Backup<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">DESCRIPTION=export<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">IOOPTIMEOUTSEC=1<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">LEASERETRIES=3<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">LEASETIMESEC=5<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">LOCKPOLICY=<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">LOCKRENEWALINTERVALSEC=5<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">MASTER_VERSION=0<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">POOL_UUID=e3e565d6-8104-11e1-afd2-001cc43ceea0<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">REMOTE_PATH=10.1.20.2:/home/nfs4<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">ROLE=Regular<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">SDUUID=b23c7ab6-b1d4-4888-8d4a-adc78e61db38<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">TYPE=NFS<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">VERSION=0<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">_SHA_CKSUM=d91770b5a0f1956cbfb79a6a59b5291dc5bd2b59<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"><o:p> </o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"><o:p> </o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D">logs:</span><span
            style="color:#1F497D" lang="EN-US"><o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"><o:p> </o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:38,628 INFO 
            [org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand]
            (pool-5-thread-50) Running command:
            AttachStorageDomainToPoolCommand internal: false. Entities
            affected :  ID: e3e565d6-8104-11e1-afd2-001cc43ceea0 Type:
            Storage<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:38,658 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand]
            (pool-5-thread-49) START,
            ValidateStorageServerConnectionVDSCommand(vdsId =
            270e3720-8105-11e1-a3ed-001cc43ceea0, storagePoolId =
            00000000-0000-0000-0000-000000000000, storageType = NFS,
            connectionList = [{ id:
            aef7de23-f271-45f3-853b-9e7b7be6607f, connection:
            10.1.20.2:/home/nfs4 };]), log id: 651832d2<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:38,658 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand]
            (pool-5-thread-48) START,
            ValidateStorageServerConnectionVDSCommand(vdsId =
            1bb3653a-8105-11e1-9eda-001cc43ceea0, storagePoolId =
            00000000-0000-0000-0000-000000000000, storageType = NFS,
            connectionList = [{ id:
            aef7de23-f271-45f3-853b-9e7b7be6607f, connection:
            10.1.20.2:/home/nfs4 };]), log id: 69324bf2<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:38,747 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand]
            (pool-5-thread-48) FINISH,
            ValidateStorageServerConnectionVDSCommand, return:
            {aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id: 69324bf2<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:38,748 INFO 
            [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand]
            (pool-5-thread-48) Running command:
            ConnectStorageToVdsCommand internal: true. Entities affected
            :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:38,750 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
            (pool-5-thread-48) START,
            ConnectStorageServerVDSCommand(vdsId =
            1bb3653a-8105-11e1-9eda-001cc43ceea0, storagePoolId =
            00000000-0000-0000-0000-000000000000, storageType = NFS,
            connectionList = [{ id:
            aef7de23-f271-45f3-853b-9e7b7be6607f, connection:
            10.1.20.2:/home/nfs4 };]), log id: 1b5bf8d2<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:38,762 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand]
            (pool-5-thread-49) FINISH,
            ValidateStorageServerConnectionVDSCommand, return:
            {aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id: 651832d2<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:38,763 INFO 
            [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand]
            (pool-5-thread-49) Running command:
            ConnectStorageToVdsCommand internal: true. Entities affected
            :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:38,765 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
            (pool-5-thread-49) START,
            ConnectStorageServerVDSCommand(vdsId =
            270e3720-8105-11e1-a3ed-001cc43ceea0, storagePoolId =
            00000000-0000-0000-0000-000000000000, storageType = NFS,
            connectionList = [{ id:
            aef7de23-f271-45f3-853b-9e7b7be6607f, connection:
            10.1.20.2:/home/nfs4 };]), log id: 59f6a186<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:38,775 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
            (pool-5-thread-48) FINISH, ConnectStorageServerVDSCommand,
            return: {aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id:
            1b5bf8d2<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:38,791 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
            (pool-5-thread-49) FINISH, ConnectStorageServerVDSCommand,
            return: {aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id:
            59f6a186<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:38,792 INFO 
            [org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand]
            (pool-5-thread-50) START,
            AttachStorageDomainVDSCommand(storagePoolId =
            e3e565d6-8104-11e1-afd2-001cc43ceea0, ignoreFailoverLimit =
            false, compatabilityVersion = null, storageDomainId =
            e3e565d6-8104-11e1-afd2-001cc43ceea0), log id: 58a14aa<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:38,816 ERROR
            [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase]
            (pool-5-thread-50) Failed in AttachStorageDomainVDS method<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:38,817 ERROR
            [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase]
            (pool-5-thread-50) Error code StorageDomainActionError and
            error message IRSGenericException: IRSErrorException: Failed
            to AttachStorageDomainVDS, error = Error in storage domain
            action: ('sdUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0,
            spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0',)<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:38,817 ERROR
            [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand]
            (pool-5-thread-50) IrsBroker::Failed::AttachStorageDomainVDS
            due to: IRSErrorException: IRSGenericException:
            IRSErrorException: Failed to AttachStorageDomainVDS, error =
            Error in storage domain action:
            ('sdUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0,
            spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0',)<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:38,836 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStopVDSCommand]
            (pool-5-thread-50) START, SpmStopVDSCommand(vdsId =
            270e3720-8105-11e1-a3ed-001cc43ceea0, storagePoolId =
            e3e565d6-8104-11e1-afd2-001cc43ceea0), log id: fbb0af9<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:38,848 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStopVDSCommand]
            (pool-5-thread-50) SpmStopVDSCommand::Stopping SPM on vds
            10.2.20.8, pool id e3e565d6-8104-11e1-afd2-001cc43ceea0<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:39,915 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStopVDSCommand]
            (pool-5-thread-50) FINISH, SpmStopVDSCommand, log id:
            fbb0af9<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:39,915 INFO 
            [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand]
            (pool-5-thread-50) Irs placed on server null failed. Proceed
            Failover<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:39,923 INFO 
            [org.ovirt.engine.core.bll.storage.SetStoragePoolStatusCommand]
            (pool-5-thread-50) Running command:
            SetStoragePoolStatusCommand internal: true. Entities
            affected :  ID: e3e565d6-8104-11e1-afd2-001cc43ceea0 Type:
            StoragePool<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:39,948 INFO 
            [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand]
            (pool-5-thread-50) hostFromVds::selectedVds - 10.1.20.7,
            spmStatus Free, storage pool Default<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:39,953 INFO 
            [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand]
            (pool-5-thread-50) starting spm on vds 10.1.20.7, storage
            pool Default, prevId -1, LVER 13<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:39,955 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand]
            (pool-5-thread-50) START, SpmStartVDSCommand(vdsId =
            1bb3653a-8105-11e1-9eda-001cc43ceea0, storagePoolId =
            e3e565d6-8104-11e1-afd2-001cc43ceea0, prevId=-1,
            prevLVER=13, storagePoolFormatType=V1, recoveryMode=Manual,
            SCSIFencing=false), log id: 3eff326c<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-09
            06:11:39,971 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand]
            (pool-5-thread-50) spmStart polling started: taskId =
            e6db13d3-1c55-4d11-9fbc-8abf50c635ae<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">^C<o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D"><o:p> </o:p></span></p>
        <div>
          <div style="border:none;border-top:solid #B5C4DF
            1.0pt;padding:3.0pt 0cm 0cm 0cm">
            <p class="MsoNormal"><b><span
style="font-size:10.0pt;font-family:"Tahoma","sans-serif";color:windowtext">From:</span></b><span
style="font-size:10.0pt;font-family:"Tahoma","sans-serif";color:windowtext">
                Rami Vaknin [<a moz-do-not-send="true"
                  href="mailto:rvaknin@redhat.com">mailto:rvaknin@redhat.com</a>]
                <br>
                <b>Sent:</b> Monday, April 09, 2012 1:27 PM<br>
                <b>To:</b> зоррыч<br>
                <b>Cc:</b> <a moz-do-not-send="true"
                  href="mailto:users@ovirt.org">users@ovirt.org</a><br>
                <b>Subject:</b> Re: [Users] Failed to attach Storage
                Domain export to Data Center<o:p></o:p></span></p>
          </div>
        </div>
        <p class="MsoNormal"><o:p> </o:p></p>
        <p class="MsoNormal">On 04/08/2012 07:05 PM, зоррыч wrote: <o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">I
            found the metadata in the file ./dom_md/metadata</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Changed
            “b23c7ab6-b1d4-4888-8d4a-adc78e61db38” to
            “e3e565d6-8104-11e1-afd2-001cc43ceea0”  (SDUUID)</span><o:p></o:p></p>
        <p class="MsoNormal" style="margin-bottom:12.0pt"><span
            style="font-size:12.0pt;font-family:"Times New
            Roman","serif""><br>
            It's wrong, you should change that in POOL_UUID and not in
            SDUUID (my fault, by spuuid I meant to the POOL_UUID).<br>
            <br>
            <o:p></o:p></span></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">The
            data I gave the command: </span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">[root@noc-3-synt
            ~]# vdsClient -s 0 getConnectedStoragePoolsList</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">e3e565d6-8104-11e1-afd2-001cc43ceea0</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> </span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Found
            in </span><span lang="EN-US">checksum</span><span
            style="color:#1F497D" lang="EN-US"> vdsm.log:</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-36836::WARNING::2012-04-08
            11:19:47,945::persistentDict::242::Storage.PersistentDict::(refresh)
            data seal is broken metadata declares
            `4df87518878fd0d74932917b83aba9672f92dff2` should be
            `d91770b5a0f1956cbfb79a6a59b5291dc5bd2b59`
            (lines={'VERSION': '0', 'LEASETIMESEC': '5', 'DESCRIPTION':
            'export', 'LOCKPOLICY': '', 'LEASERETRIES': '3', 'SDUUID':
            'e3e565d6-8104-11e1-afd2-001cc43ceea0', 'REMOTE_PATH':
            '10.1.20.2:/home/nfs4', 'MASTER_VERSION': '0',
            'IOOPTIMEOUTSEC': '1', 'ROLE': 'Regular',
            'LOCKRENEWALINTERVALSEC': '5', 'POOL_UUID':
            '801e35d4-7405-11e1-b152-001cc43ceea0', 'TYPE': 'NFS',
            'CLASS': 'Backup'})</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">MetaDataSealIsBroken:
            Meta Data seal is broken (checksum mismatch): 'cksum =
            4df87518878fd0d74932917b83aba9672f92dff2, computed_cksum =
            d91770b5a0f1956cbfb79a6a59b5291dc5bd2b59'</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> </span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Changed
            it.</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">As
            a result, the metadata file:</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> </span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">[root@noc-2-synt
            dom_md]# cat metadata</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">CLASS=Backup</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">DESCRIPTION=export</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">IOOPTIMEOUTSEC=1</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">LEASERETRIES=3</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">LEASETIMESEC=5</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">LOCKPOLICY=</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">LOCKRENEWALINTERVALSEC=5</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">MASTER_VERSION=0</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">POOL_UUID=801e35d4-7405-11e1-b152-001cc43ceea0</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">REMOTE_PATH=10.1.20.2:/home/nfs4</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">ROLE=Regular</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">SDUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">TYPE=NFS</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">VERSION=0</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">_SHA_CKSUM=d91770b5a0f1956cbfb79a6a59b5291dc5bd2b59</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> </span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> </span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">attachment
            to the ovirt fails:</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-35311::INFO::2012-04-08
            11:25:54,668::fileSD::244::Storage.StorageDomain::(getStats)
            sdUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0 [Errno 2] No
            such file or directory:
'/rhev/data-center/mnt/10.1.20.2:_home_nfs4/e3e565d6-8104-11e1-afd2-001cc43ceea0'</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-35311::ERROR::2012-04-08
            11:25:54,669::task::868::TaskManager.Task::(_setError)
            Unexpected error</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> </span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">I
            renamed the folder that contains the data to
            e3e565d6-8104-11e1-afd2-001cc43ceea0 for
            b23c7ab6-b1d4-4888-8d4a-adc78e61db38 (SDUUID)</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> </span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">attachment
            to the ovirt fails  =(</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">messages:</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="font-size:12.0pt" lang="EN-US">Error
            while executing action AttachStorageDomainToPool: Error in
            storage domain action</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> </span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> 
          </span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> </span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">engine.log:</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:10,557 INFO 
            [org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand]
            (pool-5-thread-49) Running command:
            AttachStorageDomainToPoolCommand internal: false. Entities
            affected :  ID: e3e565d6-8104-11e1-afd2-001cc43ceea0 Type:
            Storage</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:10,583 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand]
            (pool-5-thread-46) START,
            ValidateStorageServerConnectionVDSCommand(vdsId =
            1bb3653a-8105-11e1-9eda-001cc43ceea0, storagePoolId =
            00000000-0000-0000-0000-000000000000, storageType = NFS,
            connectionList = [{ id:
            aef7de23-f271-45f3-853b-9e7b7be6607f, connection:
            10.1.20.2:/home/nfs4 };]), log id: 3dbf104d</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:10,583 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand]
            (pool-5-thread-50) START,
            ValidateStorageServerConnectionVDSCommand(vdsId =
            270e3720-8105-11e1-a3ed-001cc43ceea0, storagePoolId =
            00000000-0000-0000-0000-000000000000, storageType = NFS,
            connectionList = [{ id:
            aef7de23-f271-45f3-853b-9e7b7be6607f, connection:
            10.1.20.2:/home/nfs4 };]), log id: 4ac74ee6</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:10,685 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand]
            (pool-5-thread-50) FINISH,
            ValidateStorageServerConnectionVDSCommand, return:
            {aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id: 4ac74ee6</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:10,686 INFO 
            [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand]
            (pool-5-thread-50) Running command:
            ConnectStorageToVdsCommand internal: true. Entities affected
            :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:10,689 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
            (pool-5-thread-50) START,
            ConnectStorageServerVDSCommand(vdsId =
            270e3720-8105-11e1-a3ed-001cc43ceea0, storagePoolId =
            00000000-0000-0000-0000-000000000000, storageType = NFS,
            connectionList = [{ id:
            aef7de23-f271-45f3-853b-9e7b7be6607f, connection:
            10.1.20.2:/home/nfs4 };]), log id: 53bd874a</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:10,690 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand]
            (pool-5-thread-46) FINISH,
            ValidateStorageServerConnectionVDSCommand, return:
            {aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id: 3dbf104d</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:10,691 INFO 
            [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand]
            (pool-5-thread-46) Running command:
            ConnectStorageToVdsCommand internal: true. Entities affected
            :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:10,693 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
            (pool-5-thread-46) START,
            ConnectStorageServerVDSCommand(vdsId =
            1bb3653a-8105-11e1-9eda-001cc43ceea0, storagePoolId =
            00000000-0000-0000-0000-000000000000, storageType = NFS,
            connectionList = [{ id:
            aef7de23-f271-45f3-853b-9e7b7be6607f, connection:
            10.1.20.2:/home/nfs4 };]), log id: 3129fd68</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:10,714 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
            (pool-5-thread-50) FINISH, ConnectStorageServerVDSCommand,
            return: {aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id:
            53bd874a</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:10,716 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
            (pool-5-thread-46) FINISH, ConnectStorageServerVDSCommand,
            return: {aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id:
            3129fd68</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:10,718 INFO 
            [org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand]
            (pool-5-thread-49) START,
            AttachStorageDomainVDSCommand(storagePoolId =
            e3e565d6-8104-11e1-afd2-001cc43ceea0, ignoreFailoverLimit =
            false, compatabilityVersion = null, storageDomainId =
            e3e565d6-8104-11e1-afd2-001cc43ceea0), log id: 2b559a22</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:10,737 ERROR
            [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase]
            (pool-5-thread-49) Failed in AttachStorageDomainVDS method</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:10,737 ERROR
            [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase]
            (pool-5-thread-49) Error code StorageDomainActionError and
            error message IRSGenericException: IRSErrorException: Failed
            to AttachStorageDomainVDS, error = Error in storage domain
            action: ('sdUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0,
            spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0',)</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:10,738 ERROR
            [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand]
            (pool-5-thread-49) IrsBroker::Failed::AttachStorageDomainVDS
            due to: IRSErrorException: IRSGenericException:
            IRSErrorException: Failed to AttachStorageDomainVDS, error =
            Error in storage domain action:
            ('sdUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0,
            spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0',)</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:10,752 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStopVDSCommand]
            (pool-5-thread-49) START, SpmStopVDSCommand(vdsId =
            1bb3653a-8105-11e1-9eda-001cc43ceea0, storagePoolId =
            e3e565d6-8104-11e1-afd2-001cc43ceea0), log id: 6b2f2dd2</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:10,764 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStopVDSCommand]
            (pool-5-thread-49) SpmStopVDSCommand::Stopping SPM on vds
            10.1.20.7, pool id e3e565d6-8104-11e1-afd2-001cc43ceea0</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:11,843 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStopVDSCommand]
            (pool-5-thread-49) FINISH, SpmStopVDSCommand, log id:
            6b2f2dd2</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:11,843 INFO 
            [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand]
            (pool-5-thread-49) Irs placed on server null failed. Proceed
            Failover</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:11,850 INFO 
            [org.ovirt.engine.core.bll.storage.SetStoragePoolStatusCommand]
            (pool-5-thread-49) Running command:
            SetStoragePoolStatusCommand internal: true. Entities
            affected :  ID: e3e565d6-8104-11e1-afd2-001cc43ceea0 Type:
            StoragePool</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:11,881 INFO 
            [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand]
            (pool-5-thread-49) hostFromVds::selectedVds - 10.2.20.8,
            spmStatus Free, storage pool Default</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:11,886 INFO 
            [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand]
            (pool-5-thread-49) starting spm on vds 10.2.20.8, storage
            pool Default, prevId -1, LVER 8</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:11,888 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand]
            (pool-5-thread-49) START, SpmStartVDSCommand(vdsId =
            270e3720-8105-11e1-a3ed-001cc43ceea0, storagePoolId =
            e3e565d6-8104-11e1-afd2-001cc43ceea0, prevId=-1, prevLVER=8,
            storagePoolFormatType=V1, recoveryMode=Manual,
            SCSIFencing=false), log id: 382e702</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">2012-04-08
            11:53:11,905 INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand]
            (pool-5-thread-49) spmStart polling started: taskId =
            f65c40e4-956f-4c01-ab3f-5c43f2853806</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> </span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> </span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Vdsm.log:</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38311::INFO::2012-04-08
            11:54:32,427::dispatcher::100::Storage.Dispatcher.Protect::(run)
            Run and protect: getStoragePoolInfo, Return response:
            {'status': {'message': 'OK', 'code': 0}, 'info': {'spm_id':
            2, 'master_uuid': '4f1199ec-9cdb-4efe-91eb-587faeec2099',
            'name': 'Default', 'version': '0', 'domains':
            '4f1199ec-9cdb-4efe-91eb-587faeec2099:Active',
            'pool_status': 'connected', 'isoprefix': '', 'type': 'NFS',
            'master_ver': 1, 'lver': 10}, 'dominfo':
            {'4f1199ec-9cdb-4efe-91eb-587faeec2099': {'status':
            'Active', 'diskfree': '490079256576', 'alerts': [],
            'disktotal': '516524343296'}}}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38312::DEBUG::2012-04-08
            11:54:32,952::clientIF::239::Storage.Dispatcher.Protect::(wrapper)
            [10.1.20.2]</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38312::INFO::2012-04-08
            11:54:32,952::dispatcher::94::Storage.Dispatcher.Protect::(run)
            Run and protect: validateStorageServerConnection, args:
            (domType=1, spUUID=00000000-0000-0000-0000-000000000000,
            conList=[{'connection': '10.1.20.2:/home/nfs4', 'iqn': '',
            'portal': '', 'user': '', 'password': '******', 'id':
            'aef7de23-f271-45f3-853b-9e7b7be6607f', 'port': ''}])</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38312::DEBUG::2012-04-08
            11:54:32,953::task::495::TaskManager.Task::(_debug) Task
            7899228e-1fc3-4fe0-bbcd-84cb4aa1ef11: moving from state init
            -> state preparing</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38312::INFO::2012-04-08
            11:54:32,953::storage_connection::127::Storage.ServerConnection::(validate)
            Request to validate NFS storage server</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38312::INFO::2012-04-08
            11:54:32,953::storage_connection::53::Storage.ServerConnection::(__validateConnectionParams)
            conList=[{'connection': '10.1.20.2:/home/nfs4', 'iqn': '',
            'portal': '', 'user': '', 'password': '******', 'id':
            'aef7de23-f271-45f3-853b-9e7b7be6607f', 'port': ''}]</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38312::DEBUG::2012-04-08
            11:54:32,955::fileUtils::83::Storage.Misc.excCmd::(mount)
            '/usr/bin/sudo -n /bin/mount -o
            soft,timeo=600,retrans=6,nosharecache,vers=3 -t nfs
            10.1.20.2:/home/nfs4 /tmp/tmplHltd0' (cwd None)</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38312::DEBUG::2012-04-08
            11:54:32,987::fileUtils::83::Storage.Misc.excCmd::(mount)
            SUCCESS: <err> = ''; <rc> = 0</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38312::DEBUG::2012-04-08
            11:54:32,994::fileUtils::112::Storage.Misc.excCmd::(umount)
            '/usr/bin/sudo -n /bin/umount -t nfs -f /tmp/tmplHltd0' (cwd
            None)</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38312::DEBUG::2012-04-08
            11:54:33,021::fileUtils::112::Storage.Misc.excCmd::(umount)
            SUCCESS: <err> = ''; <rc> = 0</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38312::DEBUG::2012-04-08
            11:54:33,022::task::495::TaskManager.Task::(_debug) Task
            7899228e-1fc3-4fe0-bbcd-84cb4aa1ef11: finished:
            {'statuslist': [{'status': 0, 'id':
            'aef7de23-f271-45f3-853b-9e7b7be6607f'}]}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38312::DEBUG::2012-04-08
            11:54:33,023::task::495::TaskManager.Task::(_debug) Task
            7899228e-1fc3-4fe0-bbcd-84cb4aa1ef11: moving from state
            preparing -> state finished</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38312::DEBUG::2012-04-08
            11:54:33,023::resourceManager::786::ResourceManager.Owner::(releaseAll)
            Owner.releaseAll requests {} resources {}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38312::DEBUG::2012-04-08
            11:54:33,024::resourceManager::821::ResourceManager.Owner::(cancelAll)
            Owner.cancelAll requests {}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38312::DEBUG::2012-04-08
            11:54:33,024::task::495::TaskManager.Task::(_debug) Task
            7899228e-1fc3-4fe0-bbcd-84cb4aa1ef11: ref 0 aborting False</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38312::INFO::2012-04-08
            11:54:33,024::dispatcher::100::Storage.Dispatcher.Protect::(run)
            Run and protect: validateStorageServerConnection, Return
            response: {'status': {'message': 'OK', 'code': 0},
            'statuslist': [{'status': 0, 'id':
            'aef7de23-f271-45f3-853b-9e7b7be6607f'}]}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38313::DEBUG::2012-04-08
            11:54:33,037::clientIF::239::Storage.Dispatcher.Protect::(wrapper)
            [10.1.20.2]</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38313::INFO::2012-04-08
            11:54:33,037::dispatcher::94::Storage.Dispatcher.Protect::(run)
            Run and protect: connectStorageServer, args: (domType=1,
            spUUID=00000000-0000-0000-0000-000000000000,
            conList=[{'connection': '10.1.20.2:/home/nfs4', 'iqn': '',
            'portal': '', 'user': '', 'password': '******', 'id':
            'aef7de23-f271-45f3-853b-9e7b7be6607f', 'port': ''}])</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38313::DEBUG::2012-04-08
            11:54:33,038::task::495::TaskManager.Task::(_debug) Task
            83b36131-7378-4f69-841d-b82637362295: moving from state init
            -> state preparing</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38313::INFO::2012-04-08
            11:54:33,038::storage_connection::95::Storage.ServerConnection::(connect)
            Request to connect NFS storage server</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38313::INFO::2012-04-08
            11:54:33,038::storage_connection::53::Storage.ServerConnection::(__validateConnectionParams)
            conList=[{'connection': '10.1.20.2:/home/nfs4', 'iqn': '',
            'portal': '', 'user': '', 'password': '******', 'id':
            'aef7de23-f271-45f3-853b-9e7b7be6607f', 'port': ''}]</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38313::DEBUG::2012-04-08
            11:54:33,048::lvm::547::OperationMutex::(_invalidateAllPvs)
            Operation 'lvm invalidate operation' got the operation mutex</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38313::DEBUG::2012-04-08
            11:54:33,048::lvm::549::OperationMutex::(_invalidateAllPvs)
            Operation 'lvm invalidate operation' released the operation
            mutex</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38313::DEBUG::2012-04-08
            11:54:33,049::lvm::559::OperationMutex::(_invalidateAllVgs)
            Operation 'lvm invalidate operation' got the operation mutex</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38313::DEBUG::2012-04-08
            11:54:33,049::lvm::561::OperationMutex::(_invalidateAllVgs)
            Operation 'lvm invalidate operation' released the operation
            mutex</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38313::DEBUG::2012-04-08
            11:54:33,049::lvm::580::OperationMutex::(_invalidateAllLvs)
            Operation 'lvm invalidate operation' got the operation mutex</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38313::DEBUG::2012-04-08
            11:54:33,050::lvm::582::OperationMutex::(_invalidateAllLvs)
            Operation 'lvm invalidate operation' released the operation
            mutex</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38313::DEBUG::2012-04-08
            11:54:33,050::task::495::TaskManager.Task::(_debug) Task
            83b36131-7378-4f69-841d-b82637362295: finished:
            {'statuslist': [{'status': 0, 'id':
            'aef7de23-f271-45f3-853b-9e7b7be6607f'}]}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38313::DEBUG::2012-04-08
            11:54:33,050::task::495::TaskManager.Task::(_debug) Task
            83b36131-7378-4f69-841d-b82637362295: moving from state
            preparing -> state finished</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38313::DEBUG::2012-04-08
            11:54:33,051::resourceManager::786::ResourceManager.Owner::(releaseAll)
            Owner.releaseAll requests {} resources {}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38313::DEBUG::2012-04-08
            11:54:33,051::resourceManager::821::ResourceManager.Owner::(cancelAll)
            Owner.cancelAll requests {}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38313::DEBUG::2012-04-08
            11:54:33,051::task::495::TaskManager.Task::(_debug) Task
            83b36131-7378-4f69-841d-b82637362295: ref 0 aborting False</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38313::INFO::2012-04-08
            11:54:33,051::dispatcher::100::Storage.Dispatcher.Protect::(run)
            Run and protect: connectStorageServer, Return response:
            {'status': {'message': 'OK', 'code': 0}, 'statuslist':
            [{'status': 0, 'id':
            'aef7de23-f271-45f3-853b-9e7b7be6607f'}]}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,062::clientIF::239::Storage.Dispatcher.Protect::(wrapper)
            [10.1.20.2]</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::INFO::2012-04-08
            11:54:33,062::dispatcher::94::Storage.Dispatcher.Protect::(run)
            Run and protect: attachStorageDomain, args: (
            sdUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0
            spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0)</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,063::task::495::TaskManager.Task::(_debug) Task
            2b306544-6fcc-443b-9ea6-23ae4b71d422: moving from state init
            -> state preparing</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,063::resourceManager::155::ResourceManager.Request::(__init__)
            ResName=`Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`a97b6096-7b1a-4f93-b0c0-322399c2ac3a`::Request
            was made in '/usr/share/vdsm/storage/spm.py' line '1072' at
            'public_attachStorageDomain'</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,064::resourceManager::463::ResourceManager::(registerResource)
            Trying to register resource
            'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' for lock type
            'exclusive'</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,064::resourceManager::505::ResourceManager::(registerResource)
            Resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' is
            free. Now locking as 'exclusive' (1 active user)</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,064::resourceManager::192::ResourceManager.Request::(grant)
            ResName=`Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`a97b6096-7b1a-4f93-b0c0-322399c2ac3a`::Granted
            request</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,065::task::495::TaskManager.Task::(_debug) Task
            2b306544-6fcc-443b-9ea6-23ae4b71d422: _resourcesAcquired:
            Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 (exclusive)</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,065::task::495::TaskManager.Task::(_debug) Task
            2b306544-6fcc-443b-9ea6-23ae4b71d422: ref 1 aborting False</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::ERROR::2012-04-08
            11:54:33,065::task::868::TaskManager.Task::(_setError)
            Unexpected error</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Traceback
            (most recent call last):</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> 
            File "/usr/share/vdsm/storage/task.py", line 876, in _run</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">   
            return fn(*args, **kargs)</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> 
            File "/usr/share/vdsm/storage/spm.py", line 115, in run</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">   
            return self.func(*args, **kwargs)</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> 
            File "/usr/share/vdsm/storage/spm.py", line 1073, in
            public_attachStorageDomain</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">   
            vars.task.getExclusiveLock(STORAGE, sdUUID)</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> 
            File "/usr/share/vdsm/storage/task.py", line 1311, in
            getExclusiveLock</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">   
            self.resOwner.acquire(namespace, resName,
            resourceManager.LockType.exclusive, timeout)</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> 
            File "/usr/share/vdsm/storage/resourceManager.py", line 673,
            in acquire</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">   
            raise ValueError("Owner %s: acquire: resource %s is already
            acquired" % (str(self), fullName))</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">ValueError:
            Owner 2b306544-6fcc-443b-9ea6-23ae4b71d422: acquire:
            resource Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 is
            already acquired</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,066::task::495::TaskManager.Task::(_debug) Task
            2b306544-6fcc-443b-9ea6-23ae4b71d422: Task._run:
            2b306544-6fcc-443b-9ea6-23ae4b71d422
            ('e3e565d6-8104-11e1-afd2-001cc43ceea0',
            'e3e565d6-8104-11e1-afd2-001cc43ceea0') {} failed - stopping
            task</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,066::task::495::TaskManager.Task::(_debug) Task
            2b306544-6fcc-443b-9ea6-23ae4b71d422: stopping in state
            preparing (force False)</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,067::task::495::TaskManager.Task::(_debug) Task
            2b306544-6fcc-443b-9ea6-23ae4b71d422: ref 1 aborting True</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::INFO::2012-04-08
            11:54:33,067::task::1171::TaskManager.Task::(prepare)
            aborting: Task is aborted: 'Owner
            2b306544-6fcc-443b-9ea6-23ae4b71d422: acquire: resource
            Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 is already
            acquired' - code 100</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,067::task::495::TaskManager.Task::(_debug) Task
            2b306544-6fcc-443b-9ea6-23ae4b71d422: Prepare: aborted:
            Owner 2b306544-6fcc-443b-9ea6-23ae4b71d422: acquire:
            resource Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 is
            already acquired</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,067::task::495::TaskManager.Task::(_debug) Task
            2b306544-6fcc-443b-9ea6-23ae4b71d422: ref 0 aborting True</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,068::task::495::TaskManager.Task::(_debug) Task
            2b306544-6fcc-443b-9ea6-23ae4b71d422: Task._doAbort: force
            False</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,068::resourceManager::821::ResourceManager.Owner::(cancelAll)
            Owner.cancelAll requests {}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,068::task::495::TaskManager.Task::(_debug) Task
            2b306544-6fcc-443b-9ea6-23ae4b71d422: moving from state
            preparing -> state aborting</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,069::task::495::TaskManager.Task::(_debug) Task
            2b306544-6fcc-443b-9ea6-23ae4b71d422: _aborting: recover
            policy none</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,069::task::495::TaskManager.Task::(_debug) Task
            2b306544-6fcc-443b-9ea6-23ae4b71d422: moving from state
            aborting -> state failed</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,069::resourceManager::786::ResourceManager.Owner::(releaseAll)
            Owner.releaseAll requests {} resources
            {'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0': <
            ResourceRef 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0',
            isValid: 'True' obj: 'None'>}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,069::resourceManager::821::ResourceManager.Owner::(cancelAll)
            Owner.cancelAll requests {}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,070::resourceManager::515::ResourceManager::(releaseResource)
            Trying to release resource
            'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0'</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,070::resourceManager::530::ResourceManager::(releaseResource)
            Released resource
            'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' (0 active
            users)</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,070::resourceManager::535::ResourceManager::(releaseResource)
            Resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' is
            free, finding out if anyone is waiting for it.</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::DEBUG::2012-04-08
            11:54:33,071::resourceManager::542::ResourceManager::(releaseResource)
            No one is waiting for resource
            'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0', Clearing
            records.</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::ERROR::2012-04-08
            11:54:33,071::dispatcher::106::Storage.Dispatcher.Protect::(run)
            Owner 2b306544-6fcc-443b-9ea6-23ae4b71d422: acquire:
            resource Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 is
            already acquired</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38314::ERROR::2012-04-08
            11:54:33,071::dispatcher::107::Storage.Dispatcher.Protect::(run)
            Traceback (most recent call last):</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> 
            File "/usr/share/vdsm/storage/dispatcher.py", line 96, in
            run</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">   
            result = ctask.prepare(self.func, *args, **kwargs)</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> 
            File "/usr/share/vdsm/storage/task.py", line 1178, in
            prepare</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">   
            raise self.error</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">ValueError:
            Owner 2b306544-6fcc-443b-9ea6-23ae4b71d422: acquire:
            resource Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 is
            already acquired</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> </span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38315::DEBUG::2012-04-08
            11:54:33,086::clientIF::239::Storage.Dispatcher.Protect::(wrapper)
            [10.1.20.2]</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38315::INFO::2012-04-08
            11:54:33,086::dispatcher::94::Storage.Dispatcher.Protect::(run)
            Run and protect: getSpmStatus, args: (
            spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0)</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38315::DEBUG::2012-04-08
            11:54:33,086::task::495::TaskManager.Task::(_debug) Task
            04c6f0a0-6010-4c40-a888-8c59b1e69de3: moving from state init
            -> state preparing</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38315::DEBUG::2012-04-08
            11:54:33,087::spm::688::Storage.SPM::(public_getSpmStatus)
            spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0: spmStatus=SPM
            spmLver=10 spmId=2</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38315::DEBUG::2012-04-08
            11:54:33,087::task::495::TaskManager.Task::(_debug) Task
            04c6f0a0-6010-4c40-a888-8c59b1e69de3: finished: {'spm_st':
            {'spmId': 2, 'spmStatus': 'SPM', 'spmLver': 10}}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38315::DEBUG::2012-04-08
            11:54:33,087::task::495::TaskManager.Task::(_debug) Task
            04c6f0a0-6010-4c40-a888-8c59b1e69de3: moving from state
            preparing -> state finished</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38315::DEBUG::2012-04-08
            11:54:33,088::resourceManager::786::ResourceManager.Owner::(releaseAll)
            Owner.releaseAll requests {} resources {}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38315::DEBUG::2012-04-08
            11:54:33,088::resourceManager::821::ResourceManager.Owner::(cancelAll)
            Owner.cancelAll requests {}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38315::DEBUG::2012-04-08
            11:54:33,088::task::495::TaskManager.Task::(_debug) Task
            04c6f0a0-6010-4c40-a888-8c59b1e69de3: ref 0 aborting False</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38315::INFO::2012-04-08
            11:54:33,089::dispatcher::100::Storage.Dispatcher.Protect::(run)
            Run and protect: getSpmStatus, Return response: {'status':
            {'message': 'OK', 'code': 0}, 'spm_st': {'spmId': 2,
            'spmStatus': 'SPM', 'spmLver': 10}}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38316::DEBUG::2012-04-08
            11:54:33,101::clientIF::239::Storage.Dispatcher.Protect::(wrapper)
            [10.1.20.2]</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38316::INFO::2012-04-08
            11:54:33,102::dispatcher::94::Storage.Dispatcher.Protect::(run)
            Run and protect: getAllTasksStatuses, args: ()</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38316::DEBUG::2012-04-08
            11:54:33,102::task::495::TaskManager.Task::(_debug) Task
            6714a525-c7c6-4132-90a3-da21b312d7fd: moving from state init
            -> state preparing</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38316::DEBUG::2012-04-08
            11:54:33,102::taskManager::92::TaskManager::(getAllTasksStatuses)
            Entry.</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38316::DEBUG::2012-04-08
            11:54:33,102::taskManager::97::TaskManager::(getAllTasksStatuses)
            Return: {}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38316::DEBUG::2012-04-08
            11:54:33,103::task::495::TaskManager.Task::(_debug) Task
            6714a525-c7c6-4132-90a3-da21b312d7fd: finished:
            {'allTasksStatus': {}}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38316::DEBUG::2012-04-08
            11:54:33,103::task::495::TaskManager.Task::(_debug) Task
            6714a525-c7c6-4132-90a3-da21b312d7fd: moving from state
            preparing -> state finished</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38316::DEBUG::2012-04-08
            11:54:33,103::resourceManager::786::ResourceManager.Owner::(releaseAll)
            Owner.releaseAll requests {} resources {}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38316::DEBUG::2012-04-08
            11:54:33,104::resourceManager::821::ResourceManager.Owner::(cancelAll)
            Owner.cancelAll requests {}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38316::DEBUG::2012-04-08
            11:54:33,104::task::495::TaskManager.Task::(_debug) Task
            6714a525-c7c6-4132-90a3-da21b312d7fd: ref 0 aborting False</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38316::INFO::2012-04-08
            11:54:33,104::dispatcher::100::Storage.Dispatcher.Protect::(run)
            Run and protect: getAllTasksStatuses, Return response:
            {'status': {'message': 'OK', 'code': 0}, 'allTasksStatus':
            {}}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,113::clientIF::239::Storage.Dispatcher.Protect::(wrapper)
            [10.1.20.2]</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::INFO::2012-04-08
            11:54:33,113::dispatcher::94::Storage.Dispatcher.Protect::(run)
            Run and protect: spmStop, args: (
            spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0)</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,114::task::495::TaskManager.Task::(_debug) Task
            6bec12b5-5e5b-49d8-a6d3-e8f2a5ef06bf: moving from state init
            -> state preparing</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,114::spm::547::Storage.SPM::(public_spmStop)
            spUUID=e3e565d6-8104-11e1-afd2-001cc43ceea0</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,114::taskManager::105::TaskManager::(getAllTasks)
            Entry.</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,114::taskManager::110::TaskManager::(getAllTasks)
            Return: {}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,115::resourceManager::155::ResourceManager.Request::(__init__)
            ResName=`Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`417fa7c5-958c-4001-8944-d64df2b44f43`::Request
            was made in '/usr/share/vdsm/storage/spm.py' line '573' at
            'public_spmStop'</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,115::resourceManager::463::ResourceManager::(registerResource)
            Trying to register resource
            'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' for lock type
            'exclusive'</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,116::resourceManager::505::ResourceManager::(registerResource)
            Resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' is
            free. Now locking as 'exclusive' (1 active user)</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,116::resourceManager::192::ResourceManager.Request::(grant)
            ResName=`Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`417fa7c5-958c-4001-8944-d64df2b44f43`::Granted
            request</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,116::task::495::TaskManager.Task::(_debug) Task
            6bec12b5-5e5b-49d8-a6d3-e8f2a5ef06bf: _resourcesAcquired:
            Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0 (exclusive)</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,117::task::495::TaskManager.Task::(_debug) Task
            6bec12b5-5e5b-49d8-a6d3-e8f2a5ef06bf: ref 1 aborting False</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,117::resourceManager::155::ResourceManager.Request::(__init__)
            ResName=`Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`b1954479-bdb7-47d3-a42c-b672fe788ca4`::Request
            was made in '/usr/share/vdsm/storage/spm.py' line '584' at
            '_stop'</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,117::resourceManager::463::ResourceManager::(registerResource)
            Trying to register resource
            'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0' for
            lock type 'exclusive'</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,118::resourceManager::505::ResourceManager::(registerResource)
            Resource
            'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0' is
            free. Now locking as 'exclusive' (1 active user)</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,118::resourceManager::192::ResourceManager.Request::(grant)
            ResName=`Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0`ReqID=`b1954479-bdb7-47d3-a42c-b672fe788ca4`::Granted
            request</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,119::resourceManager::515::ResourceManager::(releaseResource)
            Trying to release resource
            'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0'</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,119::resourceManager::530::ResourceManager::(releaseResource)
            Released resource
            'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0' (0
            active users)</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,119::resourceManager::535::ResourceManager::(releaseResource)
            Resource
            'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0' is
            free, finding out if anyone is waiting for it.</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,119::resourceManager::542::ResourceManager::(releaseResource)
            No one is waiting for resource
            'Storage.upgrade_e3e565d6-8104-11e1-afd2-001cc43ceea0',
            Clearing records.</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,120::taskManager::118::TaskManager::(unloadTasks)
            Entry.</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,120::taskManager::122::TaskManager::(unloadTasks)
            Return</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,121::spm::201::Storage.SPM::(__cleanupMasterMount)
            master
            `/rhev/data-center/mnt/blockSD/e5a63624-716e-4bb4-ae60-cd4d7aae9ed2/master`
            is not mounted, skipping</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,121::spm::290::Storage.SPM::(__cleanupSPM) cleaning
            up SPM: e3e565d6-8104-11e1-afd2-001cc43ceea0</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,121::persistentDict::140::Storage.PersistentDict::(transaction)
            Starting transaction</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,122::persistentDict::146::Storage.PersistentDict::(transaction)
            Flushing changes</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,122::persistentDict::265::Storage.PersistentDict::(flush)
            about to write lines (FileMetadataRW)=['CLASS=Data',
            'DESCRIPTION=main', 'IOOPTIMEOUTSEC=10', 'LEASERETRIES=3',
            'LEASETIMESEC=60', 'LOCKPOLICY=',
            'LOCKRENEWALINTERVALSEC=5', 'MASTER_VERSION=1',
            'POOL_DESCRIPTION=Default',
            'POOL_DOMAINS=4f1199ec-9cdb-4efe-91eb-587faeec2099:Active',
            'POOL_SPM_ID=-1', 'POOL_SPM_LVER=10',
            'POOL_UUID=e3e565d6-8104-11e1-afd2-001cc43ceea0',
            'REMOTE_PATH=nfs.corp:/share', 'ROLE=Master',
            'SDUUID=4f1199ec-9cdb-4efe-91eb-587faeec2099', 'TYPE=NFS',
            'VERSION=0',
            '_SHA_CKSUM=1d1cd722cad65ebb2edfa75641c30a5b97302f84']</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,128::persistentDict::148::Storage.PersistentDict::(transaction)
            Finished transaction</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::INFO::2012-04-08
            11:54:33,129::safelease::83::ClusterLock::(release)
            Releasing cluster lock for domain
            4f1199ec-9cdb-4efe-91eb-587faeec2099</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:33,129::safelease::84::Storage.Misc.excCmd::(release)
            '/usr/libexec/vdsm/spmstop.sh
            4f1199ec-9cdb-4efe-91eb-587faeec2099' (cwd
            /usr/libexec/vdsm/)</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:34,164::safelease::84::Storage.Misc.excCmd::(release)
            SUCCESS: <err> = ''; <rc> = 0</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:34,165::safelease::87::ClusterLock::(release) Cluster
            lock released successfully</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:34,165::task::495::TaskManager.Task::(_debug) Task
            6bec12b5-5e5b-49d8-a6d3-e8f2a5ef06bf: finished: None</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:34,165::task::495::TaskManager.Task::(_debug) Task
            6bec12b5-5e5b-49d8-a6d3-e8f2a5ef06bf: moving from state
            preparing -> state finished</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:34,166::resourceManager::786::ResourceManager.Owner::(releaseAll)
            Owner.releaseAll requests {} resources
            {'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0': <
            ResourceRef 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0',
            isValid: 'True' obj: 'None'>}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:34,166::resourceManager::821::ResourceManager.Owner::(cancelAll)
            Owner.cancelAll requests {}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:34,166::resourceManager::515::ResourceManager::(releaseResource)
            Trying to release resource
            'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0'</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:34,167::resourceManager::530::ResourceManager::(releaseResource)
            Released resource
            'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' (0 active
            users)</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:34,167::resourceManager::535::ResourceManager::(releaseResource)
            Resource 'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0' is
            free, finding out if anyone is waiting for it.</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:34,167::resourceManager::542::ResourceManager::(releaseResource)
            No one is waiting for resource
            'Storage.e3e565d6-8104-11e1-afd2-001cc43ceea0', Clearing
            records.</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::DEBUG::2012-04-08
            11:54:34,168::task::495::TaskManager.Task::(_debug) Task
            6bec12b5-5e5b-49d8-a6d3-e8f2a5ef06bf: ref 0 aborting False</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38317::INFO::2012-04-08
            11:54:34,168::dispatcher::100::Storage.Dispatcher.Protect::(run)
            Run and protect: spmStop, Return response: {'status':
            {'message': 'OK', 'code': 0}}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38318::INFO::2012-04-08
            11:54:34,395::dispatcher::94::Storage.Dispatcher.Protect::(run)
            Run and protect: repoStats, args: ()</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38318::DEBUG::2012-04-08
            11:54:34,395::task::495::TaskManager.Task::(_debug) Task
            612d6da8-5aa1-4f72-89b4-14c3101b0cec: moving from state init
            -> state preparing</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38318::DEBUG::2012-04-08
            11:54:34,395::task::495::TaskManager.Task::(_debug) Task
            612d6da8-5aa1-4f72-89b4-14c3101b0cec: finished:
            {'4f1199ec-9cdb-4efe-91eb-587faeec2099': {'delay':
            '0.00149178504944', 'lastCheck': 1333900465.46615, 'valid':
            True, 'code': 0}}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38318::DEBUG::2012-04-08
            11:54:34,396::task::495::TaskManager.Task::(_debug) Task
            612d6da8-5aa1-4f72-89b4-14c3101b0cec: moving from state
            preparing -> state finished</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38318::DEBUG::2012-04-08
            11:54:34,396::resourceManager::786::ResourceManager.Owner::(releaseAll)
            Owner.releaseAll requests {} resources {}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38318::DEBUG::2012-04-08
            11:54:34,396::resourceManager::821::ResourceManager.Owner::(cancelAll)
            Owner.cancelAll requests {}</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US">Thread-38318::DEBUG::2012-04-08
            11:54:34,397::task::495::TaskManager.Task::(_debug) Task
            612d6da8-5aa1-4f72-89b4-14c3101b0cec: ref 0 aborting False</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> </span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> </span><o:p></o:p></p>
        <p class="MsoNormal"><span style="color:#1F497D" lang="EN-US"> </span><o:p></o:p></p>
        <div>
          <div style="border:none;border-top:solid #B5C4DF
            1.0pt;padding:3.0pt 0cm 0cm 0cm">
            <p class="MsoNormal"><b><span
style="font-size:10.0pt;font-family:"Tahoma","sans-serif";color:windowtext"
                  lang="EN-US">From:</span></b><span
style="font-size:10.0pt;font-family:"Tahoma","sans-serif";color:windowtext"
                lang="EN-US"> Rami Vaknin [<a moz-do-not-send="true"
                  href="mailto:rvaknin@redhat.com">mailto:rvaknin@redhat.com</a>]
                <br>
                <b>Sent:</b> Sunday, April 08, 2012 7:02 PM<br>
                <b>To:</b> </span><span
style="font-size:10.0pt;font-family:"Tahoma","sans-serif";color:windowtext">зоррыч</span><span
style="font-size:10.0pt;font-family:"Tahoma","sans-serif";color:windowtext"
                lang="EN-US"><br>
                <b>Subject:</b> Re: [Users] Failed to attach Storage
                Domain export to Data Center</span><o:p></o:p></p>
          </div>
        </div>
        <p class="MsoNormal"><span lang="EN-US"> </span><o:p></o:p></p>
        <p class="MsoNormal" style="margin-bottom:12.0pt">On 04/08/2012
          03:48 PM, зоррыч wrote:<br>
          <br>
          1. Change the spuuid in the domain's metadata file to be the
          spuuid of the new datacenter (the metadata can be found in
          dom_md/ dir somewhere inside the stoarge domain's directories
          tree), you can take the new spuuid from any host running on
          the new data center by running:<br>
          vdsClient -s 0 getConnectedStoragePoolsList<br>
          <br>
          2. Try to attach the storage domain again, this time it will
          fail due to broken checksum on the metadata file, go to
          vdsm.log of the spm and look for "checksum", it will tell you
          what the right checksum should be like, copy it to the
          metadata file.<br>
          This time the attachment should work.<br>
          <br>
          <o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US">Hi. </span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US">Thank you for the help
            you provided me in the mailing list.</span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US">I dropped the settings
            using the ovirt - engine-cleanup</span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US">Ovirt reinstalled and
            tried to restore the virtual machine from the export of
            storage domain.</span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US">If you try an attachment
            domain ovirt error:</span><o:p></o:p></p>
        <p class="MsoNormal"><span style="font-size:12.0pt" lang="EN-US">Error
            while executing action AttachStorageDomainToPool: The Domain
            is already used by another Data Center.<br/>In order
            to use it, detach it from the Data Center it is currently
            attached to.</span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US"> </span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US"> </span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US">In the logs:</span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US"> </span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US">2012-04-08 08:39:49,898
            INFO 
            [org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand]
            (pool-5-thread-50) Running command:
            AttachStorageDomainToPoolCommand internal: false. Entities
            affected :  ID: b23c7ab6-b1d4-4888-8d4a-adc78e61db38 Type:
            Storage</span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US">2012-04-08 08:39:49,925
            INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand]
            (pool-5-thread-48) START,
            ValidateStorageServerConnectionVDSCommand(vdsId =
            1bb3653a-8105-11e1-9eda-001cc43ceea0, storagePoolId =
            00000000-0000-0000-0000-000000000000, storageType = NFS,
            connectionList = [{ id:
            aef7de23-f271-45f3-853b-9e7b7be6607f, connection:
            10.1.20.2:/home/nfs4 };]), log id: 1985db9c</span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US">2012-04-08 08:39:49,925
            INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand]
            (pool-5-thread-47) START,
            ValidateStorageServerConnectionVDSCommand(vdsId =
            270e3720-8105-11e1-a3ed-001cc43ceea0, storagePoolId =
            00000000-0000-0000-0000-000000000000, storageType = NFS,
            connectionList = [{ id:
            aef7de23-f271-45f3-853b-9e7b7be6607f, connection:
            10.1.20.2:/home/nfs4 };]), log id: 17f02ea3</span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US">2012-04-08 08:39:50,004
            INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand]
            (pool-5-thread-47) FINISH,
            ValidateStorageServerConnectionVDSCommand, return:
            {aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id: 17f02ea3</span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US">2012-04-08 08:39:50,005
            INFO 
            [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand]
            (pool-5-thread-47) Running command:
            ConnectStorageToVdsCommand internal: true. Entities affected
            :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System</span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US">2012-04-08 08:39:50,008
            INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
            (pool-5-thread-47) START,
            ConnectStorageServerVDSCommand(vdsId =
            270e3720-8105-11e1-a3ed-001cc43ceea0, storagePoolId =
            00000000-0000-0000-0000-000000000000, storageType = NFS,
            connectionList = [{ id:
            aef7de23-f271-45f3-853b-9e7b7be6607f, connection:
            10.1.20.2:/home/nfs4 };]), log id: 6f4db784</span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US">2012-04-08 08:39:50,009
            INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand]
            (pool-5-thread-48) FINISH,
            ValidateStorageServerConnectionVDSCommand, return:
            {aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id: 1985db9c</span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US">2012-04-08 08:39:50,010
            INFO 
            [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand]
            (pool-5-thread-48) Running command:
            ConnectStorageToVdsCommand internal: true. Entities affected
            :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System</span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US">2012-04-08 08:39:50,013
            INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
            (pool-5-thread-48) START,
            ConnectStorageServerVDSCommand(vdsId =
            1bb3653a-8105-11e1-9eda-001cc43ceea0, storagePoolId =
            00000000-0000-0000-0000-000000000000, storageType = NFS,
            connectionList = [{ id:
            aef7de23-f271-45f3-853b-9e7b7be6607f, connection:
            10.1.20.2:/home/nfs4 };]), log id: 42baf149</span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US">2012-04-08 08:39:50,031
            INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
            (pool-5-thread-47) FINISH, ConnectStorageServerVDSCommand,
            return: {aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id:
            6f4db784</span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US">2012-04-08 08:39:50,035
            INFO 
            [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand]
            (pool-5-thread-48) FINISH, ConnectStorageServerVDSCommand,
            return: {aef7de23-f271-45f3-853b-9e7b7be6607f=0}, log id:
            42baf149</span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US">2012-04-08 08:39:50,037
            INFO 
            [org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand]
            (pool-5-thread-50) START,
            AttachStorageDomainVDSCommand(storagePoolId =
            e3e565d6-8104-11e1-afd2-001cc43ceea0, ignoreFailoverLimit =
            false, compatabilityVersion = null, storageDomainId =
            b23c7ab6-b1d4-4888-8d4a-adc78e61db38), log id: 6f22bcac</span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US">2012-04-08 08:39:53,186
            ERROR
            [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand]
            (pool-5-thread-50) IrsBroker::Failed::AttachStorageDomainVDS
            due to: IrsOperationFailedNoFailoverException:
            IRSGenericException: IRSErrorException: Storage domain
            already attached to pool:
            'domain=b23c7ab6-b1d4-4888-8d4a-adc78e61db38,
            pool=801e35d4-7405-11e1-b152-001cc43ceea0'</span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US">2012-04-08 08:39:53,186
            INFO 
            [org.ovirt.engine.core.vdsbroker.irsbroker.AttachStorageDomainVDSCommand]
            (pool-5-thread-50) FINISH, AttachStorageDomainVDSCommand,
            log id: 6f22bcac</span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US">2012-04-08 08:39:53,186
            ERROR
            [org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand]
            (pool-5-thread-50) Command
            org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand
            throw Vdc Bll exception. With error message VdcBLLException:
            org.ovirt.engine.core.vdsbroker.irsbroker.IrsOperationFailedNoFailoverException:
            IRSGenericException: IRSErrorException: Storage domain
            already attached to pool:
            'domain=b23c7ab6-b1d4-4888-8d4a-adc78e61db38,
            pool=801e35d4-7405-11e1-b152-001cc43ceea0'</span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US">2012-04-08 08:39:53,190
            INFO 
            [org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand]
            (pool-5-thread-50) Command
            [id=b717db6a-a582-49ba-92c1-6bb09340789a]: Compensating
            NEW_ENTITY_ID of
            org.ovirt.engine.core.common.businessentities.storage_pool_iso_map;
            snapshot: storagePoolId =
            e3e565d6-8104-11e1-afd2-001cc43ceea0, storageId =
            b23c7ab6-b1d4-4888-8d4a-adc78e61db38.</span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US"> </span><o:p></o:p></p>
        <p class="MsoNormal"><span lang="EN-US">I did not deactivate the
            export domain before clearing the configuration. How can I
            be in this case? I can manually remove the flag of the
            domain?</span><o:p></o:p></p>
        <p class="MsoNormal" style="margin-bottom:12.0pt"><span
            style="font-size:12.0pt"><br>
            <br>
            <br>
          </span><o:p></o:p></p>
        <pre>_______________________________________________<o:p></o:p></pre>
        <pre>Users mailing list<o:p></o:p></pre>
        <pre><a moz-do-not-send="true" href="mailto:Users@ovirt.org">Users@ovirt.org</a><o:p></o:p></pre>
        <pre><a moz-do-not-send="true" href="http://lists.ovirt.org/mailman/listinfo/users">http://lists.ovirt.org/mailman/listinfo/users</a><o:p></o:p></pre>
        <p class="MsoNormal" style="margin-bottom:12.0pt"><span
            style="font-size:12.0pt"><br>
            <br>
            <br>
          </span><o:p></o:p></p>
        <pre>-- <o:p></o:p></pre>
        <pre> <o:p></o:p></pre>
        <pre>Thanks,<o:p></o:p></pre>
        <pre> <o:p></o:p></pre>
        <pre>Rami Vaknin, QE @ Red Hat, TLV, IL.<o:p></o:p></pre>
        <p class="MsoNormal" style="margin-bottom:12.0pt"><span
            style="font-size:12.0pt;font-family:"Times New
            Roman","serif""><br>
            <br>
            <o:p></o:p></span></p>
        <pre>-- <o:p></o:p></pre>
        <pre><o:p> </o:p></pre>
        <pre>Thanks,<o:p></o:p></pre>
        <pre><o:p> </o:p></pre>
        <pre>Rami Vaknin, QE @ Red Hat, TLV, IL.<o:p></o:p></pre>
      </div>
    </blockquote>
    <br>
    <br>
    <pre class="moz-signature" cols="72">-- 

Thanks,

Rami Vaknin, QE @ Red Hat, TLV, IL.
</pre>
  </body>
</html>

--------------070000070304040100060303--