Attachment 597038 Details for Bug 838521 – vdsm-log

vdsm-log

mount.log (text/x-log), 21.08 KB, created by Royce Lv on 2012-07-09 10:51:52 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Royce Lv

Created: 2012-07-09 10:51:52 UTC

Size: 21.08 KB

patch

obsolete

>Thread-349::INFO::2012-07-09 17:01:27,851::logUtils::37::dispatcher::(wrapper) Run and protect: validateStorageServerConnection(domType=1, spUUID='00000000-0000-0000-0000-000000000000', conList=[{'connection': '9.115.122.149:/home/lvroyce/nfs-domain-dir', 'iqn': '', 'portal': '', 'user': '', 'password': '******', 'id': '75260f7d-0da4-4155-a89c-548a5f15fa9e', 'port': ''}], options=None)
>Thread-349::INFO::2012-07-09 17:01:27,852::logUtils::39::dispatcher::(wrapper) Run and protect: validateStorageServerConnection, Return response: {'statuslist': [{'status': 0, 'id': '75260f7d-0da4-4155-a89c-548a5f15fa9e'}]}
>Thread-349::DEBUG::2012-07-09 17:01:27,852::task::1172::TaskManager.Task::(prepare) Task=`8318ec90-849c-4195-9584-d44a005f0306`::finished: {'statuslist': [{'status': 0, 'id': '75260f7d-0da4-4155-a89c-548a5f15fa9e'}]}
>Thread-349::DEBUG::2012-07-09 17:01:27,853::task::588::TaskManager.Task::(_updateState) Task=`8318ec90-849c-4195-9584-d44a005f0306`::moving from state preparing -> state finished
>Thread-349::DEBUG::2012-07-09 17:01:27,853::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-349::DEBUG::2012-07-09 17:01:27,853::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-349::DEBUG::2012-07-09 17:01:27,854::task::978::TaskManager.Task::(_decref) Task=`8318ec90-849c-4195-9584-d44a005f0306`::ref 0 aborting False
>Thread-350::DEBUG::2012-07-09 17:01:27,902::BindingXMLRPC::160::vds::(wrapper) [9.115.122.149]
>Thread-350::DEBUG::2012-07-09 17:01:27,903::task::588::TaskManager.Task::(_updateState) Task=`b59b83fe-7f13-4fe8-ae72-7960e883f4fb`::moving from state init -> state preparing
>Thread-350::INFO::2012-07-09 17:01:27,904::logUtils::37::dispatcher::(wrapper) Run and protect: connectStorageServer(domType=1, spUUID='00000000-0000-0000-0000-000000000000', conList=[{'connection': '9.115.122.149:/home/lvroyce/nfs-domain-dir', 'iqn': '', 'portal': '', 'user': '', 'password': '******', 'id': '75260f7d-0da4-4155-a89c-548a5f15fa9e', 'port': ''}], options=None)
>Thread-350::DEBUG::2012-07-09 17:01:27,910::__init__::1249::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /usr/bin/mount -t nfs -o soft,nosharecache,timeo=600,retrans=6 9.115.122.149:/home/lvroyce/nfs-domain-dir /rhev/data-center/mnt/9.115.122.149:_home_lvroyce_nfs-domain-dir' (cwd None)
>Thread-350::DEBUG::2012-07-09 17:01:28,273::lvm::460::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operation mutex
>Thread-350::DEBUG::2012-07-09 17:01:28,274::lvm::462::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the operation mutex
>Thread-350::DEBUG::2012-07-09 17:01:28,275::lvm::472::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operation mutex
>Thread-350::DEBUG::2012-07-09 17:01:28,275::lvm::474::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the operation mutex
>Thread-350::DEBUG::2012-07-09 17:01:28,276::lvm::493::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operation mutex
>Thread-350::DEBUG::2012-07-09 17:01:28,278::lvm::495::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the operation mutex
>Thread-350::INFO::2012-07-09 17:01:28,278::logUtils::39::dispatcher::(wrapper) Run and protect: connectStorageServer, Return response: {'statuslist': [{'status': 0, 'id': '75260f7d-0da4-4155-a89c-548a5f15fa9e'}]}
>Thread-350::DEBUG::2012-07-09 17:01:28,279::task::1172::TaskManager.Task::(prepare) Task=`b59b83fe-7f13-4fe8-ae72-7960e883f4fb`::finished: {'statuslist': [{'status': 0, 'id': '75260f7d-0da4-4155-a89c-548a5f15fa9e'}]}
>Thread-350::DEBUG::2012-07-09 17:01:28,279::task::588::TaskManager.Task::(_updateState) Task=`b59b83fe-7f13-4fe8-ae72-7960e883f4fb`::moving from state preparing -> state finished
>Thread-350::DEBUG::2012-07-09 17:01:28,279::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-350::DEBUG::2012-07-09 17:01:28,279::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-350::DEBUG::2012-07-09 17:01:28,279::task::978::TaskManager.Task::(_decref) Task=`b59b83fe-7f13-4fe8-ae72-7960e883f4fb`::ref 0 aborting False
>Thread-351::DEBUG::2012-07-09 17:01:28,430::BindingXMLRPC::160::vds::(wrapper) [9.115.122.149]
>Thread-351::DEBUG::2012-07-09 17:01:28,431::task::588::TaskManager.Task::(_updateState) Task=`94fd68cd-4a24-48bf-bcb0-eff22e40af10`::moving from state init -> state preparing
>Thread-351::INFO::2012-07-09 17:01:28,432::logUtils::37::dispatcher::(wrapper) Run and protect: createStorageDomain(storageType=1, sdUUID='53616734-f14e-447a-b6fa-53b3ad08bf08', domainName='lvroyce-nfs-domain', typeSpecificArg='9.115.122.149:/home/lvroyce/nfs-domain-dir', domClass=2, domVersion='0', options=None)
>Thread-351::DEBUG::2012-07-09 17:01:28,432::misc::1053::SamplingMethod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage)
>Thread-351::DEBUG::2012-07-09 17:01:28,433::misc::1055::SamplingMethod::(__call__) Got in to sampling method
>Thread-351::DEBUG::2012-07-09 17:01:28,433::misc::1053::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.rescan)
>Thread-351::DEBUG::2012-07-09 17:01:28,433::misc::1055::SamplingMethod::(__call__) Got in to sampling method
>Thread-351::DEBUG::2012-07-09 17:01:28,434::__init__::1249::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /usr/sbin/iscsiadm -m session -R' (cwd None)
>Thread-351::DEBUG::2012-07-09 17:01:28,467::__init__::1249::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-351::DEBUG::2012-07-09 17:01:28,467::misc::1063::SamplingMethod::(__call__) Returning last result
>Dummy-171::DEBUG::2012-07-09 17:01:29,631::__init__::1249::Storage.Misc.excCmd::(_log) 'dd if=/rhev/data-center/f65b0042-0067-4fd0-8a5f-52d8ad344e91/mastersd/dom_md/inbox iflag=direct,fullblock count=1 bs=1024000' (cwd None)
>Thread-351::DEBUG::2012-07-09 17:01:29,741::__init__::1249::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /usr/sbin/multipath' (cwd None)
>Dummy-171::DEBUG::2012-07-09 17:01:29,805::__init__::1249::Storage.Misc.excCmd::(_log) SUCCESS: <err> = '1+0 records in\n1+0 records out\n1024000 bytes (1.0 MB) copied, 0.143856 s, 7.1 MB/s\n'; <rc> = 0
>Thread-351::DEBUG::2012-07-09 17:01:29,863::__init__::1249::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-351::DEBUG::2012-07-09 17:01:29,863::lvm::460::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operation mutex
>Thread-351::DEBUG::2012-07-09 17:01:29,864::lvm::462::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the operation mutex
>Thread-351::DEBUG::2012-07-09 17:01:29,864::lvm::472::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operation mutex
>Thread-351::DEBUG::2012-07-09 17:01:29,864::lvm::474::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the operation mutex
>Thread-351::DEBUG::2012-07-09 17:01:29,864::lvm::493::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operation mutex
>Thread-351::DEBUG::2012-07-09 17:01:29,864::lvm::495::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the operation mutex
>Thread-351::DEBUG::2012-07-09 17:01:29,865::misc::1063::SamplingMethod::(__call__) Returning last result
>Thread-351::DEBUG::2012-07-09 17:01:29,865::lvm::352::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex
>Thread-351::DEBUG::2012-07-09 17:01:29,866::__init__::1249::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /usr/sbin/lvm vgs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 filter = [ \\"a%1IET_00010001|1IET_00010002%\\", \\"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 53616734-f14e-447a-b6fa-53b3ad08bf08' (cwd None)
>Thread-351::DEBUG::2012-07-09 17:01:29,973::__init__::1249::Storage.Misc.excCmd::(_log) FAILED: <err> = '  /dev/mapper/1IET_00010001: read failed after 0 of 4096 at 10485694464: Input/output error\n  /dev/mapper/1IET_00010001: read failed after 0 of 4096 at 10485751808: Input/output error\n  /dev/mapper/1IET_00010001: read failed after 0 of 4096 at 0: Input/output error\n  WARNING: Error counts reached a limit of 3. Device /dev/mapper/1IET_00010001 was disabled\n  Volume group "53616734-f14e-447a-b6fa-53b3ad08bf08" not found\n'; <rc> = 5
>Thread-351::WARNING::2012-07-09 17:01:29,974::lvm::356::Storage.LVM::(_reloadvgs) lvm vgs failed: 5 [] ['  /dev/mapper/1IET_00010001: read failed after 0 of 4096 at 10485694464: Input/output error', '  /dev/mapper/1IET_00010001: read failed after 0 of 4096 at 10485751808: Input/output error', '  /dev/mapper/1IET_00010001: read failed after 0 of 4096 at 0: Input/output error', '  WARNING: Error counts reached a limit of 3. Device /dev/mapper/1IET_00010001 was disabled', '  Volume group "53616734-f14e-447a-b6fa-53b3ad08bf08" not found']
>Thread-351::DEBUG::2012-07-09 17:01:29,974::lvm::379::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex
>Thread-351::INFO::2012-07-09 17:01:29,978::nfsSD::64::Storage.StorageDomain::(create) sdUUID=53616734-f14e-447a-b6fa-53b3ad08bf08 domainName=lvroyce-nfs-domain remotePath=9.115.122.149:/home/lvroyce/nfs-domain-dir domClass=2
>Thread-351::DEBUG::2012-07-09 17:01:30,241::persistentDict::192::Storage.PersistentDict::(__init__) Created a persistent dict with FileMetadataRW backend
>Thread-351::DEBUG::2012-07-09 17:01:30,243::persistentDict::234::Storage.PersistentDict::(refresh) read lines (FileMetadataRW)=[]
>Thread-351::WARNING::2012-07-09 17:01:30,243::persistentDict::256::Storage.PersistentDict::(refresh) data has no embedded checksum - trust it as it is
>Thread-351::DEBUG::2012-07-09 17:01:30,244::persistentDict::167::Storage.PersistentDict::(transaction) Starting transaction
>Thread-351::DEBUG::2012-07-09 17:01:30,245::persistentDict::173::Storage.PersistentDict::(transaction) Flushing changes
>Thread-351::DEBUG::2012-07-09 17:01:30,245::persistentDict::299::Storage.PersistentDict::(flush) about to write lines (FileMetadataRW)=['CLASS=Iso', 'DESCRIPTION=lvroyce-nfs-domain', 'IOOPTIMEOUTSEC=1', 'LEASERETRIES=3', 'LEASETIMESEC=5', 'LOCKPOLICY=', 'LOCKRENEWALINTERVALSEC=5', 'POOL_UUID=', 'REMOTE_PATH=9.115.122.149:/home/lvroyce/nfs-domain-dir', 'ROLE=Regular', 'SDUUID=53616734-f14e-447a-b6fa-53b3ad08bf08', 'TYPE=NFS', 'VERSION=0', '_SHA_CKSUM=1ea8a50e503d4e70297d0da6197939e8ca18ce1d']
>Thread-351::DEBUG::2012-07-09 17:01:30,302::persistentDict::175::Storage.PersistentDict::(transaction) Finished transaction
>Thread-351::DEBUG::2012-07-09 17:01:30,324::fileSD::107::Storage.StorageDomain::(__init__) Reading domain in path /rhev/data-center/mnt/9.115.122.149:_home_lvroyce_nfs-domain-dir/53616734-f14e-447a-b6fa-53b3ad08bf08
>Thread-351::DEBUG::2012-07-09 17:01:30,324::persistentDict::192::Storage.PersistentDict::(__init__) Created a persistent dict with FileMetadataRW backend
>Thread-351::DEBUG::2012-07-09 17:01:30,331::persistentDict::234::Storage.PersistentDict::(refresh) read lines (FileMetadataRW)=['CLASS=Iso', 'DESCRIPTION=lvroyce-nfs-domain', 'IOOPTIMEOUTSEC=1', 'LEASERETRIES=3', 'LEASETIMESEC=5', 'LOCKPOLICY=', 'LOCKRENEWALINTERVALSEC=5', 'POOL_UUID=', 'REMOTE_PATH=9.115.122.149:/home/lvroyce/nfs-domain-dir', 'ROLE=Regular', 'SDUUID=53616734-f14e-447a-b6fa-53b3ad08bf08', 'TYPE=NFS', 'VERSION=0', '_SHA_CKSUM=1ea8a50e503d4e70297d0da6197939e8ca18ce1d']
>Thread-351::DEBUG::2012-07-09 17:01:30,338::fileSD::420::Storage.StorageDomain::(imageGarbageCollector) Removing remnants of deleted images []
>Thread-351::DEBUG::2012-07-09 17:01:30,339::resourceManager::379::ResourceManager::(registerNamespace) Registering namespace '53616734-f14e-447a-b6fa-53b3ad08bf08_imageNS'
>Thread-351::DEBUG::2012-07-09 17:01:30,339::resourceManager::379::ResourceManager::(registerNamespace) Registering namespace '53616734-f14e-447a-b6fa-53b3ad08bf08_volumeNS'
>Thread-351::DEBUG::2012-07-09 17:01:30,340::__init__::1249::Storage.Misc.excCmd::(_log) '/usr/libexec/vdsm/safelease release -f /rhev/data-center/mnt/9.115.122.149:_home_lvroyce_nfs-domain-dir/53616734-f14e-447a-b6fa-53b3ad08bf08/dom_md/leases 0' (cwd /usr/libexec/vdsm)
>Thread-351::DEBUG::2012-07-09 17:01:30,392::__init__::1249::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-351::DEBUG::2012-07-09 17:01:30,393::sd::389::Storage.StorageDomain::(initSPMlease) lease initialized successfully
>Thread-351::INFO::2012-07-09 17:01:30,393::logUtils::39::dispatcher::(wrapper) Run and protect: createStorageDomain, Return response: None
>Thread-351::DEBUG::2012-07-09 17:01:30,394::task::1172::TaskManager.Task::(prepare) Task=`94fd68cd-4a24-48bf-bcb0-eff22e40af10`::finished: None
>Thread-351::DEBUG::2012-07-09 17:01:30,394::task::588::TaskManager.Task::(_updateState) Task=`94fd68cd-4a24-48bf-bcb0-eff22e40af10`::moving from state preparing -> state finished
>Thread-351::DEBUG::2012-07-09 17:01:30,395::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-351::DEBUG::2012-07-09 17:01:30,395::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-351::DEBUG::2012-07-09 17:01:30,395::task::978::TaskManager.Task::(_decref) Task=`94fd68cd-4a24-48bf-bcb0-eff22e40af10`::ref 0 aborting False
>Thread-353::DEBUG::2012-07-09 17:01:30,427::BindingXMLRPC::160::vds::(wrapper) [9.115.122.149]
>Thread-353::DEBUG::2012-07-09 17:01:30,428::task::588::TaskManager.Task::(_updateState) Task=`e2b959f0-2cc5-4a4b-8d17-330fdfc86508`::moving from state init -> state preparing
>Thread-353::INFO::2012-07-09 17:01:30,428::logUtils::37::dispatcher::(wrapper) Run and protect: getStorageDomainStats(sdUUID='53616734-f14e-447a-b6fa-53b3ad08bf08', options=None)
>Thread-353::DEBUG::2012-07-09 17:01:30,429::resourceManager::175::ResourceManager.Request::(__init__) ResName=`Storage.53616734-f14e-447a-b6fa-53b3ad08bf08`ReqID=`5b3d4a71-d20f-472d-b116-7187f1b192d9`::Request was made in '/usr/share/vdsm/storage/resourceManager.py' line '485' at 'registerResource'
>Thread-353::DEBUG::2012-07-09 17:01:30,429::resourceManager::486::ResourceManager::(registerResource) Trying to register resource 'Storage.53616734-f14e-447a-b6fa-53b3ad08bf08' for lock type 'shared'
>Thread-353::DEBUG::2012-07-09 17:01:30,429::resourceManager::528::ResourceManager::(registerResource) Resource 'Storage.53616734-f14e-447a-b6fa-53b3ad08bf08' is free. Now locking as 'shared' (1 active user)
>Thread-353::DEBUG::2012-07-09 17:01:30,430::resourceManager::212::ResourceManager.Request::(grant) ResName=`Storage.53616734-f14e-447a-b6fa-53b3ad08bf08`ReqID=`5b3d4a71-d20f-472d-b116-7187f1b192d9`::Granted request
>Thread-353::DEBUG::2012-07-09 17:01:30,431::task::817::TaskManager.Task::(resourceAcquired) Task=`e2b959f0-2cc5-4a4b-8d17-330fdfc86508`::_resourcesAcquired: Storage.53616734-f14e-447a-b6fa-53b3ad08bf08 (shared)
>Thread-353::DEBUG::2012-07-09 17:01:30,431::task::978::TaskManager.Task::(_decref) Task=`e2b959f0-2cc5-4a4b-8d17-330fdfc86508`::ref 1 aborting False
>Thread-353::DEBUG::2012-07-09 17:01:30,432::lvm::352::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex
>Thread-353::DEBUG::2012-07-09 17:01:30,433::__init__::1249::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /usr/sbin/lvm vgs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 filter = [ \\"a%1IET_00010001|1IET_00010002%\\", \\"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 53616734-f14e-447a-b6fa-53b3ad08bf08' (cwd None)
>Thread-353::DEBUG::2012-07-09 17:01:30,535::__init__::1249::Storage.Misc.excCmd::(_log) FAILED: <err> = '  /dev/mapper/1IET_00010001: read failed after 0 of 4096 at 10485694464: Input/output error\n  /dev/mapper/1IET_00010001: read failed after 0 of 4096 at 10485751808: Input/output error\n  /dev/mapper/1IET_00010001: read failed after 0 of 4096 at 0: Input/output error\n  WARNING: Error counts reached a limit of 3. Device /dev/mapper/1IET_00010001 was disabled\n  Volume group "53616734-f14e-447a-b6fa-53b3ad08bf08" not found\n'; <rc> = 5
>Thread-353::WARNING::2012-07-09 17:01:30,536::lvm::356::Storage.LVM::(_reloadvgs) lvm vgs failed: 5 [] ['  /dev/mapper/1IET_00010001: read failed after 0 of 4096 at 10485694464: Input/output error', '  /dev/mapper/1IET_00010001: read failed after 0 of 4096 at 10485751808: Input/output error', '  /dev/mapper/1IET_00010001: read failed after 0 of 4096 at 0: Input/output error', '  WARNING: Error counts reached a limit of 3. Device /dev/mapper/1IET_00010001 was disabled', '  Volume group "53616734-f14e-447a-b6fa-53b3ad08bf08" not found']
>Thread-353::DEBUG::2012-07-09 17:01:30,537::lvm::379::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex
>Thread-353::DEBUG::2012-07-09 17:01:30,544::fileSD::107::Storage.StorageDomain::(__init__) Reading domain in path /rhev/data-center/mnt/9.115.122.149:_home_lvroyce_nfs-domain-dir/53616734-f14e-447a-b6fa-53b3ad08bf08
>Thread-353::DEBUG::2012-07-09 17:01:30,545::persistentDict::192::Storage.PersistentDict::(__init__) Created a persistent dict with FileMetadataRW backend
>Thread-353::DEBUG::2012-07-09 17:01:30,547::persistentDict::234::Storage.PersistentDict::(refresh) read lines (FileMetadataRW)=['CLASS=Iso', 'DESCRIPTION=lvroyce-nfs-domain', 'IOOPTIMEOUTSEC=1', 'LEASERETRIES=3', 'LEASETIMESEC=5', 'LOCKPOLICY=', 'LOCKRENEWALINTERVALSEC=5', 'POOL_UUID=', 'REMOTE_PATH=9.115.122.149:/home/lvroyce/nfs-domain-dir', 'ROLE=Regular', 'SDUUID=53616734-f14e-447a-b6fa-53b3ad08bf08', 'TYPE=NFS', 'VERSION=0', '_SHA_CKSUM=1ea8a50e503d4e70297d0da6197939e8ca18ce1d']
>Thread-353::DEBUG::2012-07-09 17:01:30,549::fileSD::420::Storage.StorageDomain::(imageGarbageCollector) Removing remnants of deleted images []
>Thread-353::WARNING::2012-07-09 17:01:30,549::sd::348::Storage.StorageDomain::(_registerResourceNamespaces) Resource namespace 53616734-f14e-447a-b6fa-53b3ad08bf08_imageNS already registered
>Thread-353::WARNING::2012-07-09 17:01:30,550::sd::356::Storage.StorageDomain::(_registerResourceNamespaces) Resource namespace 53616734-f14e-447a-b6fa-53b3ad08bf08_volumeNS already registered
>Thread-353::INFO::2012-07-09 17:01:30,553::logUtils::39::dispatcher::(wrapper) Run and protect: getStorageDomainStats, Return response: {'stats': {'mdasize': 0, 'mdathreshold': True, 'mdavalid': True, 'diskfree': '115434586112', 'disktotal': '190271979520', 'mdafree': 0}}
>Thread-353::DEBUG::2012-07-09 17:01:30,554::task::1172::TaskManager.Task::(prepare) Task=`e2b959f0-2cc5-4a4b-8d17-330fdfc86508`::finished: {'stats': {'mdasize': 0, 'mdathreshold': True, 'mdavalid': True, 'diskfree': '115434586112', 'disktotal': '190271979520', 'mdafree': 0}}
>Thread-353::DEBUG::2012-07-09 17:01:30,554::task::588::TaskManager.Task::(_updateState) Task=`e2b959f0-2cc5-4a4b-8d17-330fdfc86508`::moving from state preparing -> state finished
>Thread-353::DEBUG::2012-07-09 17:01:30,554::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {'Storage.53616734-f14e-447a-b6fa-53b3ad08bf08': < ResourceRef 'Storage.53616734-f14e-447a-b6fa-53b3ad08bf08', isValid: 'True' obj: 'None'>}
>Thread-353::DEBUG::2012-07-09 17:01:30,555::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-353::DEBUG::2012-07-09 17:01:30,555::resourceManager::538::ResourceManager::(releaseResource) Trying to release resource 'Storage.53616734-f14e-447a-b6fa-53b3ad08bf08'
>Thread-353::DEBUG::2012-07-09 17:01:30,556::resourceManager::553::ResourceManager::(releaseResource) Released resource 'Storage.53616734-f14e-447a-b6fa-53b3ad08bf08' (0 active users)
>Thread-353::DEBUG::2012-07-09 17:01:30,556::resourceManager::558::ResourceManager::(releaseResource) Resource 'Storage.53616734-f14e-447a-b6fa-53b3ad08bf08' is free, finding out if anyone is waiting for it.
>Thread-353::DEBUG::2012-07-09 17:01:30,557::resourceManager::565::ResourceManager::(releaseResource) No one is waiting for resource 'Storage.53616734-f14e-447a-b6fa-53b3ad08bf08', Clearing records.
>Thread-353::DEBUG::2012-07-09 17:01:30,557::task::978::TaskManager.Task::(_decref) Task=`e2b959f0-2cc5-4a4b-8d17-330fdfc86508`::ref 0 aborting False
>Thread-355::DEBUG::2012-07-09 17:01:30,719::BindingXMLRPC::160::vds::(wrapper) [9.115.122.149]
>Thread-355::DEBUG::2012-07-09 17:01:30,720::task::588::TaskManager.Task::(_updateState) Task=`54038136-bf12-433c-8a1d-3f68b53c01cf`::moving from state init -> state preparing
>Thread-355::INFO::2012-07-09 17:01:30,720::logUtils::37::dispatcher::(wrapper) Run and protect: disconnectStorageServer(domType=1, spUUID='00000000-0000-0000-0000-000000000000', conList=[{'connection': '9.115.122.149:/home/lvroyce/nfs-domain-dir', 'iqn': '', 'portal': '', 'user': '', 'password': '******', 'id': '00000000-0000-0000-0000-000000000000', 'port': ''}], options=None)

Actions: View

Attachments on bug 838521: 597037 | 597038