[Users] Error importing export storage
Haim Ateya
hateya at redhat.com
Tue Aug 28 19:41:55 UTC 2012
On Aug 28, 2012, at 21:34, зоррыч <zorro at megatrone.ru> wrote:
> Again reproduce this problem.
> algorithm:
> 1. Create export storage, using ovirt (successfully)
> 2. Mounting it in a data center ovirt (successfully)
Manually or using oVirt?
> 3. umount export storage in ovirt (without deleting the files)
Same question.
> 4. Attempting to import a previously created back in storage ovirt (error)
> Error:
> Thread-114745 :: DEBUG :: 2012-08-28 14:14:05,781 :: __init__ :: 1164 :: Storage.Misc.excCmd :: (_log) SUCCESS: <err> = "Couldn't find device with uuid sySrjj-b79L-jZ9D-8ZUe-UAwz-r08M-l7YWxe. \ n "; <rc> = 0
You have to detach the storage domain using oVirt in order to be able to import it again.
From the log below, it appears that vdsm doesn't find any existing domain under this path, which might indicate a permission problem or storage domain was not detached properly using our system, or storage domain was indeed detached but also removed and formatted.
p.s - the above failure (4) not related to the import failure, engine executed getExistingStorageDomainsList query and revived an empty list.
>
> -----Original Message-----
> From: users-bounces at ovirt.org [mailto:users-bounces at ovirt.org] On Behalf Of зоррыч
> Sent: Tuesday, August 28, 2012 7:10 PM
> To: 'Haim'
> Cc: users at ovirt.org
> Subject: Re: [Users] Error importing export storage
>
> Engine.log:
> 2012-08-28 10:48:59,857 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp--0.0.0.0-8009-36) [173db25f] START, ValidateStorageServerConnectionVDSCommand(vdsId = 098ee856-ef8d-11e1-b08d-001cc43ceea0, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: null, connection: 10.1.20.7:/home/nfs4 };]), log id: 56625f10
> 2012-08-28 10:48:59,871 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp--0.0.0.0-8009-36) [173db25f] FINISH, ValidateStorageServerConnectionVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 56625f10
> 2012-08-28 10:48:59,879 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (ajp--0.0.0.0-8009-36) [173db25f] Running command: ConnectStorageToVdsCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System
> 2012-08-28 10:48:59,882 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--0.0.0.0-8009-36) [173db25f] START, ConnectStorageServerVDSCommand(vdsId = 098ee856-ef8d-11e1-b08d-001cc43ceea0, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: null, connection: 10.1.20.7:/home/nfs4 };]), log id: 7820a07e
> 2012-08-28 10:49:37,318 INFO [org.ovirt.engine.core.bll.storage.RemoveStorageServerConnectionCommand] (ajp--0.0.0.0-8009-72) [7d5acac5] Running command: RemoveStorageServerConnectionCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System
> 2012-08-28 10:49:37,321 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (ajp--0.0.0.0-8009-72) [7d5acac5] START, DisconnectStorageServerVDSCommand(vdsId = 098ee856-ef8d-11e1-b08d-001cc43ceea0, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: null, connection: 10.1.20.7:/home/nfs4 };]), log id: 20c475a6
> 2012-08-28 10:49:37,619 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (ajp--0.0.0.0-8009-72) [7d5acac5] FINISH, DisconnectStorageServerVDSCommand, return: {00000000-0000-0000-0000-000000000000=477}, log id: 20c475a6
> 2012-08-28 10:49:37,716 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp--0.0.0.0-8009-62) [d90d0ce] START, ValidateStorageServerConnectionVDSCommand(vdsId = 098ee856-ef8d-11e1-b08d-001cc43ceea0, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: null, connection: 10.1.20.2:/home/nfs4 };]), log id: 58d63f5b
> 2012-08-28 10:49:37,728 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp--0.0.0.0-8009-62) [d90d0ce] FINISH, ValidateStorageServerConnectionVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 58d63f5b
> 2012-08-28 10:49:37,738 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (ajp--0.0.0.0-8009-62) [d90d0ce] Running command: ConnectStorageToVdsCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System
> 2012-08-28 10:49:37,741 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--0.0.0.0-8009-62) [d90d0ce] START, ConnectStorageServerVDSCommand(vdsId = 098ee856-ef8d-11e1-b08d-001cc43ceea0, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: null, connection: 10.1.20.2:/home/nfs4 };]), log id: 3248aad6
> 2012-08-28 10:49:37,810 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp--0.0.0.0-8009-62) [d90d0ce] FINISH, ConnectStorageServerVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 3248aad6
> 2012-08-28 10:49:37,843 INFO [org.ovirt.engine.core.bll.storage.GetExistingStorageDomainListQuery] (ajp--0.0.0.0-8009-41) START, GetExistingStorageDomainListQuery(org.ovirt.engine.core.common.queries.GetExistingStorageDomainListParameters at 7cde8097), log id: 2f278e3
> 2012-08-28 10:49:37,846 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetStorageDomainsListVDSCommand] (ajp--0.0.0.0-8009-41) START, HSMGetStorageDomainsListVDSCommand(vdsId = 098ee856-ef8d-11e1-b08d-001cc43ceea0, storagePoolId=00000000-0000-0000-0000-000000000000, storageType=NFS, storageDomainType=ImportExport, path=10.1.20.2:/home/nfs4), log id: 44909d8a
> 2012-08-28 10:49:38,281 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetStorageDomainsListVDSCommand] (ajp--0.0.0.0-8009-41) FINISH, HSMGetStorageDomainsListVDSCommand, return: [], log id: 44909d8a
> 2012-08-28 10:49:38,282 INFO [org.ovirt.engine.core.bll.storage.GetExistingStorageDomainListQuery] (ajp--0.0.0.0-8009-41) FINISH, GetExistingStorageDomainListQuery, log id: 2f278e3
> 2012-08-28 10:49:38,339 INFO [org.ovirt.engine.core.bll.storage.RemoveStorageServerConnectionCommand] (ajp--0.0.0.0-8009-26) [32374c6d] Running command: RemoveStorageServerConnectionCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System
> 2012-08-28 10:49:38,342 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (ajp--0.0.0.0-8009-26) [32374c6d] START, DisconnectStorageServerVDSCommand(vdsId = 098ee856-ef8d-11e1-b08d-001cc43ceea0, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: null, connection: 10.1.20.2:/home/nfs4 };]), log id: 307c0926
> 2012-08-28 10:49:38,625 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (ajp--0.0.0.0-8009-26) [32374c6d] FINISH, DisconnectStorageServerVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 307c0926
>
>
>
>
> Vdsm.log:
>
>
> Thread-104470::DEBUG::2012-08-28 10:51:24,934::BindingXMLRPC::160::vds::(wrapper) [10.1.20.2]
> Thread-104470::DEBUG::2012-08-28 10:51:24,935::task::568::TaskManager.Task::(_updateState) Task=`66d3e9f1-dbff-491b-8d05-e32bc46d3bbe`::moving from state init -> state preparing
> Thread-104470::INFO::2012-08-28 10:51:24,935::logUtils::37::dispatcher::(wrapper) Run and protect: validateStorageServerConnection(domType=1, spUUID='00000000-0000-0000-0000-000000000000', conList=[{'connection': '10.1.20.2:/home/nfs4', 'iqn': '', 'portal': '', 'user': '', 'password': '******', 'id': '00000000-0000-0000-0000-000000000000', 'port': ''}], options=None)
> Thread-104470::INFO::2012-08-28 10:51:24,936::logUtils::39::dispatcher::(wrapper) Run and protect: validateStorageServerConnection, Return response: {'statuslist': [{'status': 0, 'id': '00000000-0000-0000-0000-000000000000'}]}
> Thread-104470::DEBUG::2012-08-28 10:51:24,936::task::1151::TaskManager.Task::(prepare) Task=`66d3e9f1-dbff-491b-8d05-e32bc46d3bbe`::finished: {'statuslist': [{'status': 0, 'id': '00000000-0000-0000-0000-000000000000'}]}
> Thread-104470::DEBUG::2012-08-28 10:51:24,936::task::568::TaskManager.Task::(_updateState) Task=`66d3e9f1-dbff-491b-8d05-e32bc46d3bbe`::moving from state preparing -> state finished
> Thread-104470::DEBUG::2012-08-28 10:51:24,936::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
> Thread-104470::DEBUG::2012-08-28 10:51:24,936::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
> Thread-104470::DEBUG::2012-08-28 10:51:24,936::task::957::TaskManager.Task::(_decref) Task=`66d3e9f1-dbff-491b-8d05-e32bc46d3bbe`::ref 0 aborting False
> Thread-104471::DEBUG::2012-08-28 10:51:24,958::BindingXMLRPC::160::vds::(wrapper) [10.1.20.2]
> Thread-104471::DEBUG::2012-08-28 10:51:24,958::task::568::TaskManager.Task::(_updateState) Task=`c8b2efab-c3ea-4c45-80f3-91b0702d5caa`::moving from state init -> state preparing
> Thread-104471::INFO::2012-08-28 10:51:24,959::logUtils::37::dispatcher::(wrapper) Run and protect: connectStorageServer(domType=1, spUUID='00000000-0000-0000-0000-000000000000', conList=[{'connection': '10.1.20.2:/home/nfs4', 'iqn': '', 'portal': '', 'user': '', 'password': '******', 'id': '00000000-0000-0000-0000-000000000000', 'port': ''}], options=None)
> Thread-104471::DEBUG::2012-08-28 10:51:24,962::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /bin/mount -t nfs -o soft,nosharecache,timeo=600,retrans=6 10.1.20.2:/home/nfs4 /rhev/data-center/mnt/10.1.20.2:_home_nfs4' (cwd None)
> Thread-104471::DEBUG::2012-08-28 10:51:25,023::lvm::477::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operation mutex
> Thread-104471::DEBUG::2012-08-28 10:51:25,023::lvm::479::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the operation mutex
> Thread-104471::DEBUG::2012-08-28 10:51:25,023::lvm::488::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operation mutex
> Thread-104471::DEBUG::2012-08-28 10:51:25,024::lvm::490::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the operation mutex
> Thread-104471::DEBUG::2012-08-28 10:51:25,024::lvm::508::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operation mutex
> Thread-104471::DEBUG::2012-08-28 10:51:25,024::lvm::510::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the operation mutex
> Thread-104471::INFO::2012-08-28 10:51:25,024::logUtils::39::dispatcher::(wrapper) Run and protect: connectStorageServer, Return response: {'statuslist': [{'status': 0, 'id': '00000000-0000-0000-0000-000000000000'}]}
> Thread-104471::DEBUG::2012-08-28 10:51:25,025::task::1151::TaskManager.Task::(prepare) Task=`c8b2efab-c3ea-4c45-80f3-91b0702d5caa`::finished: {'statuslist': [{'status': 0, 'id': '00000000-0000-0000-0000-000000000000'}]}
> Thread-104471::DEBUG::2012-08-28 10:51:25,025::task::568::TaskManager.Task::(_updateState) Task=`c8b2efab-c3ea-4c45-80f3-91b0702d5caa`::moving from state preparing -> state finished
> Thread-104471::DEBUG::2012-08-28 10:51:25,025::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
> Thread-104471::DEBUG::2012-08-28 10:51:25,025::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
> Thread-104471::DEBUG::2012-08-28 10:51:25,025::task::957::TaskManager.Task::(_decref) Task=`c8b2efab-c3ea-4c45-80f3-91b0702d5caa`::ref 0 aborting False
> Thread-104472::DEBUG::2012-08-28 10:51:25,063::BindingXMLRPC::160::vds::(wrapper) [10.1.20.2]
> Thread-104472::DEBUG::2012-08-28 10:51:25,063::task::568::TaskManager.Task::(_updateState) Task=`38840ddf-7948-40b3-ae71-8d9ee71205b7`::moving from state init -> state preparing
> Thread-104472::INFO::2012-08-28 10:51:25,064::logUtils::37::dispatcher::(wrapper) Run and protect: getStorageDomainsList(spUUID='00000000-0000-0000-0000-000000000000', domainClass=3, storageType=1, remotePath='10.1.20.2:/home/nfs4', options=None)
> Thread-104472::DEBUG::2012-08-28 10:51:25,064::misc::1061::SamplingMethod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage)
> Thread-104472::DEBUG::2012-08-28 10:51:25,064::misc::1063::SamplingMethod::(__call__) Got in to sampling method
> Thread-104472::DEBUG::2012-08-28 10:51:25,064::misc::1061::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.rescan)
> Thread-104472::DEBUG::2012-08-28 10:51:25,064::misc::1063::SamplingMethod::(__call__) Got in to sampling method
> Thread-104472::DEBUG::2012-08-28 10:51:25,065::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /sbin/iscsiadm -m session -R' (cwd None)
> Thread-104472::DEBUG::2012-08-28 10:51:25,082::__init__::1164::Storage.Misc.excCmd::(_log) FAILED: <err> = 'iscsiadm: No session found.\n'; <rc> = 21
> Thread-104472::DEBUG::2012-08-28 10:51:25,082::misc::1071::SamplingMethod::(__call__) Returning last result
> Thread-104472::DEBUG::2012-08-28 10:51:25,247::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /sbin/multipath' (cwd None)
> Thread-104472::DEBUG::2012-08-28 10:51:25,304::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
> Thread-104472::DEBUG::2012-08-28 10:51:25,305::lvm::477::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operation mutex
> Thread-104472::DEBUG::2012-08-28 10:51:25,305::lvm::479::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the operation mutex
> Thread-104472::DEBUG::2012-08-28 10:51:25,305::lvm::488::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operation mutex
> Thread-104472::DEBUG::2012-08-28 10:51:25,306::lvm::490::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the operation mutex
> Thread-104472::DEBUG::2012-08-28 10:51:25,306::lvm::508::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operation mutex
> Thread-104472::DEBUG::2012-08-28 10:51:25,306::lvm::510::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the operation mutex
> Thread-104472::DEBUG::2012-08-28 10:51:25,306::misc::1071::SamplingMethod::(__call__) Returning last result
> Thread-104472::DEBUG::2012-08-28 10:51:25,307::lvm::368::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex
> Thread-104472::DEBUG::2012-08-28 10:51:25,308::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /sbin/lvm vgs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 filter = [ \\"a%35000c50001770ea3%\\", \\"r%.*%\\" ] } global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_size,vg_mda_free' (cwd None)
> Thread-104472::DEBUG::2012-08-28 10:51:25,492::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = " Couldn't find device with uuid sySrjj-b79L-jZ9D-8ZUe-UAwz-r08M-l7YWxe.\n"; <rc> = 0
> Thread-104472::DEBUG::2012-08-28 10:51:25,494::lvm::397::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex
> Thread-104472::INFO::2012-08-28 10:51:25,507::logUtils::39::dispatcher::(wrapper) Run and protect: getStorageDomainsList, Return response: {'domlist': []}
> Thread-104472::DEBUG::2012-08-28 10:51:25,507::task::1151::TaskManager.Task::(prepare) Task=`38840ddf-7948-40b3-ae71-8d9ee71205b7`::finished: {'domlist': []}
> Thread-104472::DEBUG::2012-08-28 10:51:25,507::task::568::TaskManager.Task::(_updateState) Task=`38840ddf-7948-40b3-ae71-8d9ee71205b7`::moving from state preparing -> state finished
> Thread-104472::DEBUG::2012-08-28 10:51:25,507::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
> Thread-104472::DEBUG::2012-08-28 10:51:25,507::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
> Thread-104472::DEBUG::2012-08-28 10:51:25,508::task::957::TaskManager.Task::(_decref) Task=`38840ddf-7948-40b3-ae71-8d9ee71205b7`::ref 0 aborting False
> Thread-104482::DEBUG::2012-08-28 10:51:25,566::BindingXMLRPC::160::vds::(wrapper) [10.1.20.2]
> Thread-104482::DEBUG::2012-08-28 10:51:25,566::task::568::TaskManager.Task::(_updateState) Task=`646a0221-f17b-4d13-8f3d-69985bb5f185`::moving from state init -> state preparing
> Thread-104482::INFO::2012-08-28 10:51:25,567::logUtils::37::dispatcher::(wrapper) Run and protect: disconnectStorageServer(domType=1, spUUID='00000000-0000-0000-0000-000000000000', conList=[{'connection': '10.1.20.2:/home/nfs4', 'iqn': '', 'portal': '', 'user': '', 'password': '******', 'id': '00000000-0000-0000-0000-000000000000', 'port': ''}], options=None)
> Thread-104482::DEBUG::2012-08-28 10:51:25,567::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /bin/umount -f -l /rhev/data-center/mnt/10.1.20.2:_home_nfs4' (cwd None)
> Thread-104482::DEBUG::2012-08-28 10:51:25,597::misc::1061::SamplingMethod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage)
> Thread-104482::DEBUG::2012-08-28 10:51:25,597::misc::1063::SamplingMethod::(__call__) Got in to sampling method
> Thread-104482::DEBUG::2012-08-28 10:51:25,597::misc::1061::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.rescan)
> Thread-104482::DEBUG::2012-08-28 10:51:25,597::misc::1063::SamplingMethod::(__call__) Got in to sampling method
> Thread-104482::DEBUG::2012-08-28 10:51:25,598::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /sbin/iscsiadm -m session -R' (cwd None)
> Thread-104482::DEBUG::2012-08-28 10:51:25,615::__init__::1164::Storage.Misc.excCmd::(_log) FAILED: <err> = 'iscsiadm: No session found.\n'; <rc> = 21
> Thread-104482::DEBUG::2012-08-28 10:51:25,616::misc::1071::SamplingMethod::(__call__) Returning last result
> Thread-104482::DEBUG::2012-08-28 10:51:25,780::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /sbin/multipath' (cwd None)
> Thread-104482::DEBUG::2012-08-28 10:51:25,853::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
> Thread-104482::DEBUG::2012-08-28 10:51:25,854::lvm::477::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operation mutex
> Thread-104482::DEBUG::2012-08-28 10:51:25,854::lvm::479::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the operation mutex
> Thread-104482::DEBUG::2012-08-28 10:51:25,854::lvm::488::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operation mutex
> Thread-104482::DEBUG::2012-08-28 10:51:25,855::lvm::490::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the operation mutex
> Thread-104482::DEBUG::2012-08-28 10:51:25,855::lvm::508::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operation mutex
> Thread-104482::DEBUG::2012-08-28 10:51:25,855::lvm::510::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the operation mutex
> Thread-104482::DEBUG::2012-08-28 10:51:25,855::misc::1071::SamplingMethod::(__call__) Returning last result
> Thread-104482::INFO::2012-08-28 10:51:25,855::logUtils::39::dispatcher::(wrapper) Run and protect: disconnectStorageServer, Return response: {'statuslist': [{'status': 0, 'id': '00000000-0000-0000-0000-000000000000'}]}
> Thread-104482::DEBUG::2012-08-28 10:51:25,856::task::1151::TaskManager.Task::(prepare) Task=`646a0221-f17b-4d13-8f3d-69985bb5f185`::finished: {'statuslist': [{'status': 0, 'id': '00000000-0000-0000-0000-000000000000'}]}
> Thread-104482::DEBUG::2012-08-28 10:51:25,856::task::568::TaskManager.Task::(_updateState) Task=`646a0221-f17b-4d13-8f3d-69985bb5f185`::moving from state preparing -> state finished
> Thread-104482::DEBUG::2012-08-28 10:51:25,856::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
> Thread-104482::DEBUG::2012-08-28 10:51:25,856::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
> Thread-104482::DEBUG::2012-08-28 10:51:25,856::task::957::TaskManager.Task::(_decref) Task=`646a0221-f17b-4d13-8f3d-69985bb5f185`::ref 0 aborting False
> Thread-104483::DEBUG::2012-08-28 10:51:26,240::BindingXMLRPC::160::vds::(wrapper) [10.1.20.2]
> Thread-104483::DEBUG::2012-08-28 10:51:26,241::task::568::TaskManager.Task::(_updateState) Task=`841cf3ff-174b-4821-b0d4-5b690138ada7`::moving from state init -> state preparing
> Thread-104483::INFO::2012-08-28 10:51:26,241::logUtils::37::dispatcher::(wrapper) Run and protect: getSpmStatus(spUUID='dd424005-9444-4c73-8653-3b5192ec39ae', options=None)
> Thread-104483::INFO::2012-08-28 10:51:26,242::logUtils::39::dispatcher::(wrapper) Run and protect: getSpmStatus, Return response: {'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 1}}
> Thread-104483::DEBUG::2012-08-28 10:51:26,242::task::1151::TaskManager.Task::(prepare) Task=`841cf3ff-174b-4821-b0d4-5b690138ada7`::finished: {'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 1}}
> Thread-104483::DEBUG::2012-08-28 10:51:26,242::task::568::TaskManager.Task::(_updateState) Task=`841cf3ff-174b-4821-b0d4-5b690138ada7`::moving from state preparing -> state finished
> Thread-104483::DEBUG::2012-08-28 10:51:26,242::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
> Thread-104483::DEBUG::2012-08-28 10:51:26,243::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
> Thread-104483::DEBUG::2012-08-28 10:51:26,243::task::957::TaskManager.Task::(_decref) Task=`841cf3ff-174b-4821-b0d4-5b690138ada7`::ref 0 aborting False
> Thread-104484::DEBUG::2012-08-28 10:51:26,250::BindingXMLRPC::160::vds::(wrapper) [10.1.20.2]
> Thread-104484::DEBUG::2012-08-28 10:51:26,251::task::568::TaskManager.Task::(_updateState) Task=`723785f0-9574-4ad1-b1e2-673b193d9860`::moving from state init -> state preparing
> Thread-104484::INFO::2012-08-28 10:51:26,251::logUtils::37::dispatcher::(wrapper) Run and protect: getStoragePoolInfo(spUUID='dd424005-9444-4c73-8653-3b5192ec39ae', options=None)
> Thread-104484::DEBUG::2012-08-28 10:51:26,251::resourceManager::175::ResourceManager.Request::(__init__) ResName=`Storage.dd424005-9444-4c73-8653-3b5192ec39ae`ReqID=`76f206f7-7359-4ee2-9f5a-2d90704fb28a`::Request was made in '/usr/share/vdsm/storage/resourceManager.py' line '485' at 'registerResource'
> Thread-104484::DEBUG::2012-08-28 10:51:26,251::resourceManager::486::ResourceManager::(registerResource) Trying to register resource 'Storage.dd424005-9444-4c73-8653-3b5192ec39ae' for lock type 'shared'
> Thread-104484::DEBUG::2012-08-28 10:51:26,252::resourceManager::528::ResourceManager::(registerResource) Resource 'Storage.dd424005-9444-4c73-8653-3b5192ec39ae' is free. Now locking as 'shared' (1 active user)
> Thread-104484::DEBUG::2012-08-28 10:51:26,252::resourceManager::212::ResourceManager.Request::(grant) ResName=`Storage.dd424005-9444-4c73-8653-3b5192ec39ae`ReqID=`76f206f7-7359-4ee2-9f5a-2d90704fb28a`::Granted request
> Thread-104484::DEBUG::2012-08-28 10:51:26,252::task::794::TaskManager.Task::(resourceAcquired) Task=`723785f0-9574-4ad1-b1e2-673b193d9860`::_resourcesAcquired: Storage.dd424005-9444-4c73-8653-3b5192ec39ae (shared)
> Thread-104484::DEBUG::2012-08-28 10:51:26,252::task::957::TaskManager.Task::(_decref) Task=`723785f0-9574-4ad1-b1e2-673b193d9860`::ref 1 aborting False
> Thread-104484::INFO::2012-08-28 10:51:26,256::logUtils::39::dispatcher::(wrapper) Run and protect: getStoragePoolInfo, Return response: {'info': {'spm_id': 1, 'master_uuid': 'b0a0e76b-f983-405b-a0af-d0314a1c381a', 'name': 'cv', 'version': '0', 'domains': 'b0a0e76b-f983-405b-a0af-d0314a1c381a:Active', 'pool_status': 'connected', 'isoprefix': '', 'type': 'POSIXFS', 'master_ver': 1, 'lver': 1}, 'dominfo': {'b0a0e76b-f983-405b-a0af-d0314a1c381a': {'status': 'Active', 'diskfree': '489236856832', 'alerts': [], 'disktotal': '526951907328'}}}
> Thread-104484::DEBUG::2012-08-28 10:51:26,256::task::1151::TaskManager.Task::(prepare) Task=`723785f0-9574-4ad1-b1e2-673b193d9860`::finished: {'info': {'spm_id': 1, 'master_uuid': 'b0a0e76b-f983-405b-a0af-d0314a1c381a', 'name': 'cv', 'version': '0', 'domains': 'b0a0e76b-f983-405b-a0af-d0314a1c381a:Active', 'pool_status': 'connected', 'isoprefix': '', 'type': 'POSIXFS', 'master_ver': 1, 'lver': 1}, 'dominfo': {'b0a0e76b-f983-405b-a0af-d0314a1c381a': {'status': 'Active', 'diskfree': '489236856832', 'alerts': [], 'disktotal': '526951907328'}}}
> Thread-104484::DEBUG::2012-08-28 10:51:26,256::task::568::TaskManager.Task::(_updateState) Task=`723785f0-9574-4ad1-b1e2-673b193d9860`::moving from state preparing -> state finished
> Thread-104484::DEBUG::2012-08-28 10:51:26,256::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {'Storage.dd424005-9444-4c73-8653-3b5192ec39ae': < ResourceRef 'Storage.dd424005-9444-4c73-8653-3b5192ec39ae', isValid: 'True' obj: 'None'>}
> Thread-104484::DEBUG::2012-08-28 10:51:26,256::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
> Thread-104484::DEBUG::2012-08-28 10:51:26,257::resourceManager::538::ResourceManager::(releaseResource) Trying to release resource 'Storage.dd424005-9444-4c73-8653-3b5192ec39ae'
> Thread-104484::DEBUG::2012-08-28 10:51:26,257::resourceManager::553::ResourceManager::(releaseResource) Released resource 'Storage.dd424005-9444-4c73-8653-3b5192ec39ae' (0 active users)
> Thread-104484::DEBUG::2012-08-28 10:51:26,257::resourceManager::558::ResourceManager::(releaseResource) Resource 'Storage.dd424005-9444-4c73-8653-3b5192ec39ae' is free, finding out if anyone is waiting for it.
> Thread-104484::DEBUG::2012-08-28 10:51:26,257::resourceManager::565::ResourceManager::(releaseResource) No one is waiting for resource 'Storage.dd424005-9444-4c73-8653-3b5192ec39ae', Clearing records.
> Thread-104484::DEBUG::2012-08-28 10:51:26,258::task::957::TaskManager.Task::(_decref) Task=`723785f0-9574-4ad1-b1e2-673b193d9860`::ref 0 aborting False
> Thread-104487::DEBUG::2012-08-28 10:51:30,541::task::568::TaskManager.Task::(_updateState) Task=`2cedb6be-6152-47ce-9f00-7ce3a38256e0`::moving from state init -> state preparing
> Thread-104487::INFO::2012-08-28 10:51:30,542::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
> Thread-104487::INFO::2012-08-28 10:51:30,542::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {'b0a0e76b-f983-405b-a0af-d0314a1c381a': {'delay': '0.00299310684204', 'lastCheck': 1346165490.092454, 'code': 0, 'valid': True}}
> Thread-104487::DEBUG::2012-08-28 10:51:30,542::task::1151::TaskManager.Task::(prepare) Task=`2cedb6be-6152-47ce-9f00-7ce3a38256e0`::finished: {'b0a0e76b-f983-405b-a0af-d0314a1c381a': {'delay': '0.00299310684204', 'lastCheck': 1346165490.092454, 'code': 0, 'valid': True}}
> Thread-104487::DEBUG::2012-08-28 10:51:30,542::task::568::TaskManager.Task::(_updateState) Task=`2cedb6be-6152-47ce-9f00-7ce3a38256e0`::moving from state preparing -> state finished
> Thread-104487::DEBUG::2012-08-28 10:51:30,543::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
> Thread-104487::DEBUG::2012-08-28 10:51:30,543::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
> Thread-104487::DEBUG::2012-08-28 10:51:30,543::task::957::TaskManager.Task::(_decref) Task=`2cedb6be-6152-47ce-9f00-7ce3a38256e0`::ref 0 aborting False
>
> Please pay attention to is the:
> Thread-104472::DEBUG::2012-08-28 10:51:25,492::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = " Couldn't find device with uuid sySrjj-b79L-jZ9D-8ZUe-UAwz-r08M-l7YWxe.\n"; <rc> = 0
>
> Command:
> Node:
> [root at noc-3-synt ~]# /usr/bin/sudo -n /bin/mount -t nfs -o soft,nosharecache,timeo=600,retrans=6 10.1.20.2:/home/nfs4 /rhev/data-center/mnt/10.1.20.2:_home_nfs4
> mount.nfs: mount point /rhev/data-center/mnt/10.1.20.2:_home_nfs4 does not exist [root at noc-3-synt ~]# mkdir /rhev/data-center/mnt/10.1.20.2:_home_nfs4
> [root at noc-3-synt ~]# /usr/bin/sudo -n /bin/mount -t nfs -o soft,nosharecache,timeo=600,retrans=6 10.1.20.2:/home/nfs4 /rhev/data-center/mnt/10.1.20.2:_home_nfs4
> [root at noc-3-synt ~]# mount
> /dev/mapper/vg_noc3synt-lv_root on / type ext4 (rw) proc on /proc type proc (rw) sysfs on /sys type sysfs (rw) devpts on /dev/pts type devpts (rw,gid=5,mode=620) tmpfs on /dev/shm type tmpfs (rw,rootcontext="system_u:object_r:tmpfs_t:s0")
> /dev/sda1 on /boot type ext4 (rw)
> /dev/mapper/vg_noc3synt-lv_home on /mht type ext4 (rw) none on /proc/sys/fs/binfmt_misc type binfmt_misc (rw) sunrpc on /var/lib/nfs/rpc_pipefs type rpc_pipefs (rw) 127.0.0.1:/gluster on /rhev/data-center/mnt/127.0.0.1:_gluster type fuse.glusterfs (rw,default_permissions,allow_other,max_read=131072)
> 10.1.20.2:/home/nfs4 on /tmp/foo type nfs (rw,soft,nosharecache,timeo=600,retrans=6,addr=10.1.20.2)
> 10.1.20.2:/home/nfs4 on /rhev/data-center/mnt/10.1.20.2:_home_nfs4 type nfs (rw,soft,nosharecache,timeo=600,retrans=6,addr=10.1.20.2)
> [root at noc-3-synt ~]# vdsClient -s 0 getStorageDomainsList
> c1a00353-e7e0-4ff6-822a-faa0e7243137
> b0a0e76b-f983-405b-a0af-d0314a1c381a
>
> [root at noc-3-synt ~]# vdsClient -s 0 getStorageDomainInfo b23c7ab6-b1d4-4888-8d4a-adc78e61db38
> Storage domain does not exist: ('b23c7ab6-b1d4-4888-8d4a-adc78e61db38',)
> [root at noc-3-synt ~]#
>
>
>
> -----Original Message-----
> From: Haim [mailto:hateya at redhat.com]
> Sent: Tuesday, August 28, 2012 6:44 PM
> To: зоррыч
> Cc: users at ovirt.org
> Subject: Re: [Users] Error importing export storage
>
> On 08/28/2012 04:34 PM, зоррыч wrote:
>>
>> Hi
>>
>> Trying to import export stotage created earlier.
>>
>> But get this error:
>>
> hi,
>
> please provide full vdsm and engine logs (compressed).
> also, run the following commands on host (vds):
>
> - /usr/bin/sudo -n /bin/mount -t nfs -o
> soft,nosharecache,timeo=600,retrans=6 10.1.20.2:/home/nfs4
> /rhev/data-center/mnt/10.1.20.2:_home_nfs4
> - mount
> - vdsClient -s 0 getStorageDomainsList (if you are working in a non-secure mode, don't use the -s).
> - vdsClient -s 0 getStorageDomainInfo b23c7ab6-b1d4-4888-8d4a-adc78e61db38
> - check permission on storage server (NFS) - ls -l /home/nfs4 (should be vdsm:kvm).
>
> another thing - it appears that domain is already attached to storage pool (as I understand from domain metadata), is it possible that you haven't detach this domain from its previous domain ?
>
>> There is no storage domain under the specified path. Please check path.
>>
>> Vdsm.logs:
>>
>> Thread-99790::DEBUG::2012-08-28
>> 09:17:26,010::task::568::TaskManager.Task::(_updateState)
>> Task=`ac99ba99-f55d-4562-822e-0286ab30566e`::moving from state init ->
>> state preparing
>>
>> Thread-99790::INFO::2012-08-28
>> 09:17:26,010::logUtils::37::dispatcher::(wrapper) Run and protect:
>> repoStats(options=None)
>>
>> Thread-99790::INFO::2012-08-28
>> 09:17:26,010::logUtils::39::dispatcher::(wrapper) Run and protect:
>> repoStats, Return response: {'b0a0e76b-f983-405b-a0af-d0314a1c381a':
>> {'delay': '0.00292301177979', 'lastCheck': 1346159839.788852, 'code':
>> 0, 'valid': True}}
>>
>> Thread-99790::DEBUG::2012-08-28
>> 09:17:26,011::task::1151::TaskManager.Task::(prepare)
>> Task=`ac99ba99-f55d-4562-822e-0286ab30566e`::finished:
>> {'b0a0e76b-f983-405b-a0af-d0314a1c381a': {'delay': '0.00292301177979',
>> 'lastCheck': 1346159839.788852, 'code': 0, 'valid': True}}
>>
>> Thread-99790::DEBUG::2012-08-28
>> 09:17:26,011::task::568::TaskManager.Task::(_updateState)
>> Task=`ac99ba99-f55d-4562-822e-0286ab30566e`::moving from state
>> preparing -> state finished
>>
>> Thread-99790::DEBUG::2012-08-28
>> 09:17:26,011::resourceManager::809::ResourceManager.Owner::(releaseAll
>> ) Owner.releaseAll requests {} resources {}
>>
>> Thread-99790::DEBUG::2012-08-28
>> 09:17:26,011::resourceManager::844::ResourceManager.Owner::(cancelAll)
>> Owner.cancelAll requests {}
>>
>> Thread-99790::DEBUG::2012-08-28
>> 09:17:26,011::task::957::TaskManager.Task::(_decref)
>> Task=`ac99ba99-f55d-4562-822e-0286ab30566e`::ref 0 aborting False
>>
>> Thread-99792::DEBUG::2012-08-28
>> 09:17:26,473::BindingXMLRPC::160::vds::(wrapper) [10.1.20.2]
>>
>> Thread-99792::DEBUG::2012-08-28
>> 09:17:26,474::task::568::TaskManager.Task::(_updateState)
>> Task=`e55145ac-1052-454b-92ec-a9eb981c1b04`::moving from state init ->
>> state preparing
>>
>> Thread-99792::INFO::2012-08-28
>> 09:17:26,474::logUtils::37::dispatcher::(wrapper) Run and protect:
>> validateStorageServerConnection(domType=1,
>> spUUID='00000000-0000-0000-0000-000000000000', conList=[{'connection':
>> '10.1.20.2:/home/nfs4', 'iqn': '', 'portal': '', 'user': '',
>> 'password': '******', 'id': '00000000-0000-0000-0000-000000000000',
>> 'port': ''}], options=None)
>>
>> Thread-99792::INFO::2012-08-28
>> 09:17:26,474::logUtils::39::dispatcher::(wrapper) Run and protect:
>> validateStorageServerConnection, Return response: {'statuslist':
>> [{'status': 0, 'id': '00000000-0000-0000-0000-000000000000'}]}
>>
>> Thread-99792::DEBUG::2012-08-28
>> 09:17:26,474::task::1151::TaskManager.Task::(prepare)
>> Task=`e55145ac-1052-454b-92ec-a9eb981c1b04`::finished: {'statuslist':
>> [{'status': 0, 'id': '00000000-0000-0000-0000-000000000000'}]}
>>
>> Thread-99792::DEBUG::2012-08-28
>> 09:17:26,474::task::568::TaskManager.Task::(_updateState)
>> Task=`e55145ac-1052-454b-92ec-a9eb981c1b04`::moving from state
>> preparing -> state finished
>>
>> Thread-99792::DEBUG::2012-08-28
>> 09:17:26,475::resourceManager::809::ResourceManager.Owner::(releaseAll
>> ) Owner.releaseAll requests {} resources {}
>>
>> Thread-99792::DEBUG::2012-08-28
>> 09:17:26,475::resourceManager::844::ResourceManager.Owner::(cancelAll)
>> Owner.cancelAll requests {}
>>
>> Thread-99792::DEBUG::2012-08-28
>> 09:17:26,475::task::957::TaskManager.Task::(_decref)
>> Task=`e55145ac-1052-454b-92ec-a9eb981c1b04`::ref 0 aborting False
>>
>> Thread-99793::DEBUG::2012-08-28
>> 09:17:26,494::BindingXMLRPC::160::vds::(wrapper) [10.1.20.2]
>>
>> Thread-99793::DEBUG::2012-08-28
>> 09:17:26,495::task::568::TaskManager.Task::(_updateState)
>> Task=`700181ad-b9d4-411b-bfbc-25a28aa288e2`::moving from state init ->
>> state preparing
>>
>> Thread-99793::INFO::2012-08-28
>> 09:17:26,503::logUtils::37::dispatcher::(wrapper) Run and protect:
>> connectStorageServer(domType=1,
>> spUUID='00000000-0000-0000-0000-000000000000', conList=[{'connection':
>> '10.1.20.2:/home/nfs4', 'iqn': '', 'portal': '', 'user': '',
>> 'password': '******', 'id': '00000000-0000-0000-0000-000000000000',
>> 'port': ''}], options=None)
>>
>> Thread-99793::DEBUG::2012-08-28
>> 09:17:26,505::__init__::1164::Storage.Misc.excCmd::(_log)
>> '/usr/bin/sudo -n /bin/mount -t nfs -o
>> soft,nosharecache,timeo=600,retrans=6 10.1.20.2:/home/nfs4
>> /rhev/data-center/mnt/10.1.20.2:_home_nfs4' (cwd None)
>>
>> Thread-99793::DEBUG::2012-08-28
>> 09:17:26,609::lvm::477::OperationMutex::(_invalidateAllPvs) Operation
>> 'lvm invalidate operation' got the operation mutex
>>
>> Thread-99793::DEBUG::2012-08-28
>> 09:17:26,609::lvm::479::OperationMutex::(_invalidateAllPvs) Operation
>> 'lvm invalidate operation' released the operation mutex
>>
>> Thread-99793::DEBUG::2012-08-28
>> 09:17:26,609::lvm::488::OperationMutex::(_invalidateAllVgs) Operation
>> 'lvm invalidate operation' got the operation mutex
>>
>> Thread-99793::DEBUG::2012-08-28
>> 09:17:26,610::lvm::490::OperationMutex::(_invalidateAllVgs) Operation
>> 'lvm invalidate operation' released the operation mutex
>>
>> Thread-99793::DEBUG::2012-08-28
>> 09:17:26,610::lvm::508::OperationMutex::(_invalidateAllLvs) Operation
>> 'lvm invalidate operation' got the operation mutex
>>
>> Thread-99793::DEBUG::2012-08-28
>> 09:17:26,610::lvm::510::OperationMutex::(_invalidateAllLvs) Operation
>> 'lvm invalidate operation' released the operation mutex
>>
>> Thread-99793::INFO::2012-08-28
>> 09:17:26,610::logUtils::39::dispatcher::(wrapper) Run and protect:
>> connectStorageServer, Return response: {'statuslist': [{'status': 0,
>> 'id': '00000000-0000-0000-0000-000000000000'}]}
>>
>> Thread-99793::DEBUG::2012-08-28
>> 09:17:26,610::task::1151::TaskManager.Task::(prepare)
>> Task=`700181ad-b9d4-411b-bfbc-25a28aa288e2`::finished: {'statuslist':
>> [{'status': 0, 'id': '00000000-0000-0000-0000-000000000000'}]}
>>
>> Thread-99793::DEBUG::2012-08-28
>> 09:17:26,611::task::568::TaskManager.Task::(_updateState)
>> Task=`700181ad-b9d4-411b-bfbc-25a28aa288e2`::moving from state
>> preparing -> state finished
>>
>> Thread-99793::DEBUG::2012-08-28
>> 09:17:26,611::resourceManager::809::ResourceManager.Owner::(releaseAll
>> ) Owner.releaseAll requests {} resources {}
>>
>> Thread-99793::DEBUG::2012-08-28
>> 09:17:26,611::resourceManager::844::ResourceManager.Owner::(cancelAll)
>> Owner.cancelAll requests {}
>>
>> Thread-99793::DEBUG::2012-08-28
>> 09:17:26,611::task::957::TaskManager.Task::(_decref)
>> Task=`700181ad-b9d4-411b-bfbc-25a28aa288e2`::ref 0 aborting False
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:26,653::BindingXMLRPC::160::vds::(wrapper) [10.1.20.2]
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:26,654::task::568::TaskManager.Task::(_updateState)
>> Task=`8a3e33d5-4cfe-4ad6-87e9-55082b67394e`::moving from state init ->
>> state preparing
>>
>> Thread-99794::INFO::2012-08-28
>> 09:17:26,654::logUtils::37::dispatcher::(wrapper) Run and protect:
>> getStorageDomainsList(spUUID='00000000-0000-0000-0000-000000000000',
>> domainClass=3, storageType=1, remotePath='10.1.20.2:/home/nfs4',
>> options=None)
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:26,654::misc::1061::SamplingMethod::(__call__) Trying to enter
>> sampling method (storage.sdc.refreshStorage)
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:26,654::misc::1063::SamplingMethod::(__call__) Got in to
>> sampling method
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:26,655::misc::1061::SamplingMethod::(__call__) Trying to enter
>> sampling method (storage.iscsi.rescan)
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:26,655::misc::1063::SamplingMethod::(__call__) Got in to
>> sampling method
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:26,655::__init__::1164::Storage.Misc.excCmd::(_log)
>> '/usr/bin/sudo -n /sbin/iscsiadm -m session -R' (cwd None)
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:26,677::__init__::1164::Storage.Misc.excCmd::(_log) FAILED:
>> <err> = 'iscsiadm: No session found.\n'; <rc> = 21
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:26,678::misc::1071::SamplingMethod::(__call__) Returning last
>> result
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:26,843::__init__::1164::Storage.Misc.excCmd::(_log)
>> '/usr/bin/sudo -n /sbin/multipath' (cwd None)
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:26,935::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS:
>> <err> = ''; <rc> = 0
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:26,936::lvm::477::OperationMutex::(_invalidateAllPvs) Operation
>> 'lvm invalidate operation' got the operation mutex
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:26,941::lvm::479::OperationMutex::(_invalidateAllPvs) Operation
>> 'lvm invalidate operation' released the operation mutex
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:26,941::lvm::488::OperationMutex::(_invalidateAllVgs) Operation
>> 'lvm invalidate operation' got the operation mutex
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:26,941::lvm::490::OperationMutex::(_invalidateAllVgs) Operation
>> 'lvm invalidate operation' released the operation mutex
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:26,942::lvm::508::OperationMutex::(_invalidateAllLvs) Operation
>> 'lvm invalidate operation' got the operation mutex
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:26,942::lvm::510::OperationMutex::(_invalidateAllLvs) Operation
>> 'lvm invalidate operation' released the operation mutex
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:26,942::misc::1071::SamplingMethod::(__call__) Returning last
>> result
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:26,942::lvm::368::OperationMutex::(_reloadvgs) Operation 'lvm
>> reload operation' got the operation mutex
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:26,944::__init__::1164::Storage.Misc.excCmd::(_log)
>> '/usr/bin/sudo -n /sbin/lvm vgs --config " devices { preferred_names =
>> [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0
>> disable_after_error_count=3 filter = [ \\"a%35000c50001770ea3%\\",
>> \\"r%.*%\\" ] } global { locking_type=1 prioritise_write_locks=1
>> wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } "
>> --noheadings --units b --nosuffix --separator | -o
>> uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_size,vg_mda_free'
>> (cwd None)
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:27,146::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS:
>> <err> = " Couldn't find device with uuid
>> sySrjj-b79L-jZ9D-8ZUe-UAwz-r08M-l7YWxe.\n"; <rc> = 0
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:27,148::lvm::397::OperationMutex::(_reloadvgs) Operation 'lvm
>> reload operation' released the operation mutex
>>
>> Thread-99794::INFO::2012-08-28
>> 09:17:27,156::logUtils::39::dispatcher::(wrapper) Run and protect:
>> getStorageDomainsList, Return response: {'domlist': []}
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:27,157::task::1151::TaskManager.Task::(prepare)
>> Task=`8a3e33d5-4cfe-4ad6-87e9-55082b67394e`::finished: {'domlist': []}
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:27,157::task::568::TaskManager.Task::(_updateState)
>> Task=`8a3e33d5-4cfe-4ad6-87e9-55082b67394e`::moving from state
>> preparing -> state finished
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:27,157::resourceManager::809::ResourceManager.Owner::(releaseAll
>> ) Owner.releaseAll requests {} resources {}
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:27,157::resourceManager::844::ResourceManager.Owner::(cancelAll)
>> Owner.cancelAll requests {}
>>
>> Thread-99794::DEBUG::2012-08-28
>> 09:17:27,158::task::957::TaskManager.Task::(_decref)
>> Task=`8a3e33d5-4cfe-4ad6-87e9-55082b67394e`::ref 0 aborting False
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,232::BindingXMLRPC::160::vds::(wrapper) [10.1.20.2]
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,232::task::568::TaskManager.Task::(_updateState)
>> Task=`bfd73de7-79b7-4c24-b87b-7ecd234ce505`::moving from state init ->
>> state preparing
>>
>> Thread-99803::INFO::2012-08-28
>> 09:17:27,233::logUtils::37::dispatcher::(wrapper) Run and protect:
>> disconnectStorageServer(domType=1,
>> spUUID='00000000-0000-0000-0000-000000000000', conList=[{'connection':
>> '10.1.20.2:/home/nfs4', 'iqn': '', 'portal': '', 'user': '',
>> 'password': '******', 'id': '00000000-0000-0000-0000-000000000000',
>> 'port': ''}], options=None)
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,233::__init__::1164::Storage.Misc.excCmd::(_log)
>> '/usr/bin/sudo -n /bin/umount -f -l
>> /rhev/data-center/mnt/10.1.20.2:_home_nfs4' (cwd None)
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,265::misc::1061::SamplingMethod::(__call__) Trying to enter
>> sampling method (storage.sdc.refreshStorage)
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,266::misc::1063::SamplingMethod::(__call__) Got in to
>> sampling method
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,266::misc::1061::SamplingMethod::(__call__) Trying to enter
>> sampling method (storage.iscsi.rescan)
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,266::misc::1063::SamplingMethod::(__call__) Got in to
>> sampling method
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,267::__init__::1164::Storage.Misc.excCmd::(_log)
>> '/usr/bin/sudo -n /sbin/iscsiadm -m session -R' (cwd None)
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,284::__init__::1164::Storage.Misc.excCmd::(_log) FAILED:
>> <err> = 'iscsiadm: No session found.\n'; <rc> = 21
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,284::misc::1071::SamplingMethod::(__call__) Returning last
>> result
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,449::__init__::1164::Storage.Misc.excCmd::(_log)
>> '/usr/bin/sudo -n /sbin/multipath' (cwd None)
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,514::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS:
>> <err> = ''; <rc> = 0
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,515::lvm::477::OperationMutex::(_invalidateAllPvs) Operation
>> 'lvm invalidate operation' got the operation mutex
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,515::lvm::479::OperationMutex::(_invalidateAllPvs) Operation
>> 'lvm invalidate operation' released the operation mutex
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,515::lvm::488::OperationMutex::(_invalidateAllVgs) Operation
>> 'lvm invalidate operation' got the operation mutex
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,515::lvm::490::OperationMutex::(_invalidateAllVgs) Operation
>> 'lvm invalidate operation' released the operation mutex
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,516::lvm::508::OperationMutex::(_invalidateAllLvs) Operation
>> 'lvm invalidate operation' got the operation mutex
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,516::lvm::510::OperationMutex::(_invalidateAllLvs) Operation
>> 'lvm invalidate operation' released the operation mutex
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,516::misc::1071::SamplingMethod::(__call__) Returning last
>> result
>>
>> Thread-99803::INFO::2012-08-28
>> 09:17:27,516::logUtils::39::dispatcher::(wrapper) Run and protect:
>> disconnectStorageServer, Return response: {'statuslist': [{'status':
>> 0, 'id': '00000000-0000-0000-0000-000000000000'}]}
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,517::task::1151::TaskManager.Task::(prepare)
>> Task=`bfd73de7-79b7-4c24-b87b-7ecd234ce505`::finished: {'statuslist':
>> [{'status': 0, 'id': '00000000-0000-0000-0000-000000000000'}]}
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,517::task::568::TaskManager.Task::(_updateState)
>> Task=`bfd73de7-79b7-4c24-b87b-7ecd234ce505`::moving from state
>> preparing -> state finished
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,517::resourceManager::809::ResourceManager.Owner::(releaseAll
>> ) Owner.releaseAll requests {} resources {}
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,517::resourceManager::844::ResourceManager.Owner::(cancelAll)
>> Owner.cancelAll requests {}
>>
>> Thread-99803::DEBUG::2012-08-28
>> 09:17:27,517::task::957::TaskManager.Task::(_decref)
>> Task=`bfd73de7-79b7-4c24-b87b-7ecd234ce505`::ref 0 aborting False
>>
>> Version:
>>
>> [root at noc-3-synt ~]# rpm -qa | grep lvm
>>
>> lvm2-2.02.95-10.el6.x86_64
>>
>> lvm2-libs-2.02.95-10.el6.x86_64
>>
>> [root at noc-3-synt ~]# rpm -qa | grep vdsm
>>
>> vdsm-xmlrpc-4.10.0-0.283.13.el6.noarch
>>
>> vdsm-python-4.10.0-0.283.13.el6.x86_64
>>
>> vdsm-gluster-4.10.0-0.283.13.el6.noarch
>>
>> vdsm-4.10.0-0.283.13.el6.x86_64
>>
>> vdsm-cli-4.10.0-0.283.13.el6.noarch
>>
>> [root at noc-2-synt ~]# cat
>> /home/nfs4/b23c7ab6-b1d4-4888-8d4a-adc78e61db38/dom_md/metadata
>>
>> CLASS=Backup
>>
>> DESCRIPTION=export
>>
>> IOOPTIMEOUTSEC=1
>>
>> LEASERETRIES=3
>>
>> LEASETIMESEC=5
>>
>> LOCKPOLICY=
>>
>> LOCKRENEWALINTERVALSEC=5
>>
>> MASTER_VERSION=0
>>
>> POOL_UUID=dd424005-9444-4c73-8653-3b5192ec39ae
>>
>> REMOTE_PATH=10.1.20.2:/home/nfs4
>>
>> ROLE=Regular
>>
>> SDUUID=b23c7ab6-b1d4-4888-8d4a-adc78e61db38
>>
>> TYPE=NFS
>>
>> VERSION=0
>>
>> _SHA_CKSUM=e59674cf8d506af569a6540b9d075147da3b490d
>>
>> [root at noc-2-synt ~]#
>>
>>
>>
>> _______________________________________________
>> Users mailing list
>> Users at ovirt.org
>> http://lists.ovirt.org/mailman/listinfo/users
>
>
>
> _______________________________________________
> Users mailing list
> Users at ovirt.org
> http://lists.ovirt.org/mailman/listinfo/users
>
More information about the Users
mailing list