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