Re: [Users] Error importing export storage

Again reproduce this problem. algorithm: 1. Create export storage, using ovirt (successfully) 2. Mounting it in a data center ovirt (successfully)

3. umount export storage in ovirt (without deleting the files)

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

-----Original Message----- From: users-bounces(a)ovirt.org [mailto:users-bounces@ovirt.org] On Behalf Of зоррыч Sent: Tuesday, August 28, 2012 7:10 PM To: 'Haim' Cc: users(a)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@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@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@noc-3-synt ~]# mkdir /rhev/data-center/mnt/10.1.20.2:_home_nfs4 [root@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@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@noc-3-synt ~]# vdsClient -s 0 getStorageDomainsList c1a00353-e7e0-4ff6-822a-faa0e7243137 b0a0e76b-f983-405b-a0af-d0314a1c381a [root@noc-3-synt ~]# vdsClient -s 0 getStorageDomainInfo b23c7ab6-b1d4-4888-8d4a-adc78e61db38 Storage domain does not exist: ('b23c7ab6-b1d4-4888-8d4a-adc78e61db38',) [root@noc-3-synt ~]# -----Original Message----- From: Haim [mailto:hateya@redhat.com] Sent: Tuesday, August 28, 2012 6:44 PM To: зоррыч Cc: users(a)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@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@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@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@noc-2-synt ~]# > > > > _______________________________________________ > Users mailing list > Users(a)ovirt.org > http://lists.ovirt.org/mailman/listinfo/users _______________________________________________ Users mailing list Users(a)ovirt.org http://lists.ovirt.org/mailman/listinfo/users