Attachment 639764 Details for Bug 869956 – VDSM client host /vra/log/vdsm/vdsm.log using vdsm-41

VDSM client host /vra/log/vdsm/vdsm.log using vdsm-41

vdsm.log (text/plain), 137.77 KB, created by EricLee on 2012-11-07 02:31:57 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: EricLee

Created: 2012-11-07 02:31:57 UTC

Size: 137.77 KB

patch

obsolete

>Thread-65::DEBUG::2012-11-07 10:13:02,038::task::588::TaskManager.Task::(_updateState) Task=`90286507-73e3-4e80-bced-a8637e90a792`::moving from state init -> state preparing
>Thread-65::INFO::2012-11-07 10:13:02,038::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-65::INFO::2012-11-07 10:13:02,039::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-65::DEBUG::2012-11-07 10:13:02,039::task::1172::TaskManager.Task::(prepare) Task=`90286507-73e3-4e80-bced-a8637e90a792`::finished: {}
>Thread-65::DEBUG::2012-11-07 10:13:02,039::task::588::TaskManager.Task::(_updateState) Task=`90286507-73e3-4e80-bced-a8637e90a792`::moving from state preparing -> state finished
>Thread-65::DEBUG::2012-11-07 10:13:02,039::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-65::DEBUG::2012-11-07 10:13:02,039::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-65::DEBUG::2012-11-07 10:13:02,040::task::978::TaskManager.Task::(_decref) Task=`90286507-73e3-4e80-bced-a8637e90a792`::ref 0 aborting False
>Thread-71::DEBUG::2012-11-07 10:13:12,204::task::588::TaskManager.Task::(_updateState) Task=`eba4dc80-0cd9-4a89-b067-c7587f2c8629`::moving from state init -> state preparing
>Thread-71::INFO::2012-11-07 10:13:12,204::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-71::INFO::2012-11-07 10:13:12,204::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-71::DEBUG::2012-11-07 10:13:12,205::task::1172::TaskManager.Task::(prepare) Task=`eba4dc80-0cd9-4a89-b067-c7587f2c8629`::finished: {}
>Thread-71::DEBUG::2012-11-07 10:13:12,205::task::588::TaskManager.Task::(_updateState) Task=`eba4dc80-0cd9-4a89-b067-c7587f2c8629`::moving from state preparing -> state finished
>Thread-71::DEBUG::2012-11-07 10:13:12,205::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-71::DEBUG::2012-11-07 10:13:12,205::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-71::DEBUG::2012-11-07 10:13:12,206::task::978::TaskManager.Task::(_decref) Task=`eba4dc80-0cd9-4a89-b067-c7587f2c8629`::ref 0 aborting False
>Thread-77::DEBUG::2012-11-07 10:13:22,366::task::588::TaskManager.Task::(_updateState) Task=`c569af31-5a62-4aa1-ae24-87fd30115767`::moving from state init -> state preparing
>Thread-77::INFO::2012-11-07 10:13:22,366::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-77::INFO::2012-11-07 10:13:22,367::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-77::DEBUG::2012-11-07 10:13:22,367::task::1172::TaskManager.Task::(prepare) Task=`c569af31-5a62-4aa1-ae24-87fd30115767`::finished: {}
>Thread-77::DEBUG::2012-11-07 10:13:22,367::task::588::TaskManager.Task::(_updateState) Task=`c569af31-5a62-4aa1-ae24-87fd30115767`::moving from state preparing -> state finished
>Thread-77::DEBUG::2012-11-07 10:13:22,367::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-77::DEBUG::2012-11-07 10:13:22,367::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-77::DEBUG::2012-11-07 10:13:22,368::task::978::TaskManager.Task::(_decref) Task=`c569af31-5a62-4aa1-ae24-87fd30115767`::ref 0 aborting False
>Thread-83::DEBUG::2012-11-07 10:13:32,562::task::588::TaskManager.Task::(_updateState) Task=`e1107f6c-7ca4-4e62-8767-5324cc6d299c`::moving from state init -> state preparing
>Thread-83::INFO::2012-11-07 10:13:32,562::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-83::INFO::2012-11-07 10:13:32,562::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-83::DEBUG::2012-11-07 10:13:32,562::task::1172::TaskManager.Task::(prepare) Task=`e1107f6c-7ca4-4e62-8767-5324cc6d299c`::finished: {}
>Thread-83::DEBUG::2012-11-07 10:13:32,563::task::588::TaskManager.Task::(_updateState) Task=`e1107f6c-7ca4-4e62-8767-5324cc6d299c`::moving from state preparing -> state finished
>Thread-83::DEBUG::2012-11-07 10:13:32,563::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-83::DEBUG::2012-11-07 10:13:32,563::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-83::DEBUG::2012-11-07 10:13:32,563::task::978::TaskManager.Task::(_decref) Task=`e1107f6c-7ca4-4e62-8767-5324cc6d299c`::ref 0 aborting False
>Thread-89::DEBUG::2012-11-07 10:13:42,776::task::588::TaskManager.Task::(_updateState) Task=`f32e203a-c901-49ac-89fc-ab02411cb466`::moving from state init -> state preparing
>Thread-89::INFO::2012-11-07 10:13:42,776::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-89::INFO::2012-11-07 10:13:42,776::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-89::DEBUG::2012-11-07 10:13:42,776::task::1172::TaskManager.Task::(prepare) Task=`f32e203a-c901-49ac-89fc-ab02411cb466`::finished: {}
>Thread-89::DEBUG::2012-11-07 10:13:42,777::task::588::TaskManager.Task::(_updateState) Task=`f32e203a-c901-49ac-89fc-ab02411cb466`::moving from state preparing -> state finished
>Thread-89::DEBUG::2012-11-07 10:13:42,777::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-89::DEBUG::2012-11-07 10:13:42,777::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-89::DEBUG::2012-11-07 10:13:42,777::task::978::TaskManager.Task::(_decref) Task=`f32e203a-c901-49ac-89fc-ab02411cb466`::ref 0 aborting False
>Thread-95::DEBUG::2012-11-07 10:13:52,939::task::588::TaskManager.Task::(_updateState) Task=`88c1b37a-8b59-437e-8c7b-fde1a803874e`::moving from state init -> state preparing
>Thread-95::INFO::2012-11-07 10:13:52,939::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-95::INFO::2012-11-07 10:13:52,939::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-95::DEBUG::2012-11-07 10:13:52,939::task::1172::TaskManager.Task::(prepare) Task=`88c1b37a-8b59-437e-8c7b-fde1a803874e`::finished: {}
>Thread-95::DEBUG::2012-11-07 10:13:52,939::task::588::TaskManager.Task::(_updateState) Task=`88c1b37a-8b59-437e-8c7b-fde1a803874e`::moving from state preparing -> state finished
>Thread-95::DEBUG::2012-11-07 10:13:52,940::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-95::DEBUG::2012-11-07 10:13:52,940::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-95::DEBUG::2012-11-07 10:13:52,940::task::978::TaskManager.Task::(_decref) Task=`88c1b37a-8b59-437e-8c7b-fde1a803874e`::ref 0 aborting False
>Thread-101::DEBUG::2012-11-07 10:14:03,093::task::588::TaskManager.Task::(_updateState) Task=`60876866-afdd-4fe3-af23-af90b206a23c`::moving from state init -> state preparing
>Thread-101::INFO::2012-11-07 10:14:03,094::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-101::INFO::2012-11-07 10:14:03,094::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-101::DEBUG::2012-11-07 10:14:03,094::task::1172::TaskManager.Task::(prepare) Task=`60876866-afdd-4fe3-af23-af90b206a23c`::finished: {}
>Thread-101::DEBUG::2012-11-07 10:14:03,094::task::588::TaskManager.Task::(_updateState) Task=`60876866-afdd-4fe3-af23-af90b206a23c`::moving from state preparing -> state finished
>Thread-101::DEBUG::2012-11-07 10:14:03,094::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-101::DEBUG::2012-11-07 10:14:03,095::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-101::DEBUG::2012-11-07 10:14:03,095::task::978::TaskManager.Task::(_decref) Task=`60876866-afdd-4fe3-af23-af90b206a23c`::ref 0 aborting False
>Thread-107::DEBUG::2012-11-07 10:14:13,270::task::588::TaskManager.Task::(_updateState) Task=`b6834a50-f85e-42fd-b29b-6c1293500e2c`::moving from state init -> state preparing
>Thread-107::INFO::2012-11-07 10:14:13,270::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-107::INFO::2012-11-07 10:14:13,270::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-107::DEBUG::2012-11-07 10:14:13,270::task::1172::TaskManager.Task::(prepare) Task=`b6834a50-f85e-42fd-b29b-6c1293500e2c`::finished: {}
>Thread-107::DEBUG::2012-11-07 10:14:13,270::task::588::TaskManager.Task::(_updateState) Task=`b6834a50-f85e-42fd-b29b-6c1293500e2c`::moving from state preparing -> state finished
>Thread-107::DEBUG::2012-11-07 10:14:13,271::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-107::DEBUG::2012-11-07 10:14:13,271::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-107::DEBUG::2012-11-07 10:14:13,271::task::978::TaskManager.Task::(_decref) Task=`b6834a50-f85e-42fd-b29b-6c1293500e2c`::ref 0 aborting False
>Thread-112::DEBUG::2012-11-07 10:14:21,382::BindingXMLRPC::171::vds::(wrapper) [10.66.6.38]
>Thread-112::DEBUG::2012-11-07 10:14:21,383::task::588::TaskManager.Task::(_updateState) Task=`a2de8f53-a536-4cda-ad88-2f31203369e3`::moving from state init -> state preparing
>Thread-112::INFO::2012-11-07 10:14:21,383::logUtils::37::dispatcher::(wrapper) Run and protect: validateStorageServerConnection(domType=1, spUUID='00000000-0000-0000-0000-000000000000', conList=[{'connection': '10.66.6.38:/mnt/data/images', 'iqn': '', 'portal': '', 'user': '', 'password': '******', 'id': '00000000-0000-0000-0000-000000000000', 'port': ''}], options=None)
>Thread-112::INFO::2012-11-07 10:14:21,383::logUtils::39::dispatcher::(wrapper) Run and protect: validateStorageServerConnection, Return response: {'statuslist': [{'status': 0, 'id': '00000000-0000-0000-0000-000000000000'}]}
>Thread-112::DEBUG::2012-11-07 10:14:21,383::task::1172::TaskManager.Task::(prepare) Task=`a2de8f53-a536-4cda-ad88-2f31203369e3`::finished: {'statuslist': [{'status': 0, 'id': '00000000-0000-0000-0000-000000000000'}]}
>Thread-112::DEBUG::2012-11-07 10:14:21,383::task::588::TaskManager.Task::(_updateState) Task=`a2de8f53-a536-4cda-ad88-2f31203369e3`::moving from state preparing -> state finished
>Thread-112::DEBUG::2012-11-07 10:14:21,384::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-112::DEBUG::2012-11-07 10:14:21,384::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-112::DEBUG::2012-11-07 10:14:21,384::task::978::TaskManager.Task::(_decref) Task=`a2de8f53-a536-4cda-ad88-2f31203369e3`::ref 0 aborting False
>Thread-114::DEBUG::2012-11-07 10:14:21,438::BindingXMLRPC::171::vds::(wrapper) [10.66.6.38]
>Thread-114::DEBUG::2012-11-07 10:14:21,438::task::588::TaskManager.Task::(_updateState) Task=`3d1a1bda-aadd-43cb-8990-fcf8906a1255`::moving from state init -> state preparing
>Thread-114::INFO::2012-11-07 10:14:21,439::logUtils::37::dispatcher::(wrapper) Run and protect: connectStorageServer(domType=1, spUUID='00000000-0000-0000-0000-000000000000', conList=[{'port': '', 'connection': '10.66.6.38:/mnt/data/images', 'iqn': '', 'portal': '', 'user': '', 'protocol_version': '3', 'password': '******', 'id': '2ceb6ca1-8c20-4bb1-9822-d23f48012eb2'}], options=None)
>Thread-114::DEBUG::2012-11-07 10:14:21,453::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /bin/mount -t nfs -o soft,nosharecache,timeo=600,retrans=6,nfsvers=3 10.66.6.38:/mnt/data/images /rhev/data-center/mnt/10.66.6.38:_mnt_data_images' (cwd None)
>Thread-114::INFO::2012-11-07 10:14:21,667::logUtils::39::dispatcher::(wrapper) Run and protect: connectStorageServer, Return response: {'statuslist': [{'status': 0, 'id': '2ceb6ca1-8c20-4bb1-9822-d23f48012eb2'}]}
>Thread-114::DEBUG::2012-11-07 10:14:21,668::task::1172::TaskManager.Task::(prepare) Task=`3d1a1bda-aadd-43cb-8990-fcf8906a1255`::finished: {'statuslist': [{'status': 0, 'id': '2ceb6ca1-8c20-4bb1-9822-d23f48012eb2'}]}
>Thread-114::DEBUG::2012-11-07 10:14:21,668::task::588::TaskManager.Task::(_updateState) Task=`3d1a1bda-aadd-43cb-8990-fcf8906a1255`::moving from state preparing -> state finished
>Thread-114::DEBUG::2012-11-07 10:14:21,668::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-114::DEBUG::2012-11-07 10:14:21,668::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-114::DEBUG::2012-11-07 10:14:21,669::task::978::TaskManager.Task::(_decref) Task=`3d1a1bda-aadd-43cb-8990-fcf8906a1255`::ref 0 aborting False
>Thread-115::DEBUG::2012-11-07 10:14:22,012::BindingXMLRPC::171::vds::(wrapper) [10.66.6.38]
>Thread-115::DEBUG::2012-11-07 10:14:22,012::task::588::TaskManager.Task::(_updateState) Task=`1e10e743-1e13-417b-9eac-29cf6a72ae72`::moving from state init -> state preparing
>Thread-115::INFO::2012-11-07 10:14:22,012::logUtils::37::dispatcher::(wrapper) Run and protect: createStorageDomain(storageType=1, sdUUID='f6ec4069-d8d2-4c23-aa0e-9a6758177e1f', domainName='638', typeSpecificArg='10.66.6.38:/mnt/data/images', domClass=1, domVersion='3', options=None)
>Thread-115::DEBUG::2012-11-07 10:14:22,013::misc::1044::SamplingMethod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage)
>Thread-115::DEBUG::2012-11-07 10:14:22,013::misc::1046::SamplingMethod::(__call__) Got in to sampling method
>Thread-115::DEBUG::2012-11-07 10:14:22,013::misc::1044::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.rescan)
>Thread-115::DEBUG::2012-11-07 10:14:22,013::misc::1046::SamplingMethod::(__call__) Got in to sampling method
>Thread-115::DEBUG::2012-11-07 10:14:22,014::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /sbin/iscsiadm -m session -R' (cwd None)
>Thread-115::DEBUG::2012-11-07 10:14:22,027::__init__::1164::Storage.Misc.excCmd::(_log) FAILED: <err> = 'iscsiadm: No session found.\n'; <rc> = 21
>Thread-115::DEBUG::2012-11-07 10:14:22,027::misc::1054::SamplingMethod::(__call__) Returning last result
>MainProcess|Thread-115::DEBUG::2012-11-07 10:14:22,029::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host0/scan' (cwd None)
>MainProcess|Thread-115::DEBUG::2012-11-07 10:14:22,031::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host1/scan' (cwd None)
>MainProcess|Thread-115::DEBUG::2012-11-07 10:14:22,032::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host2/scan' (cwd None)
>MainProcess|Thread-115::DEBUG::2012-11-07 10:14:22,034::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host3/scan' (cwd None)
>MainProcess|Thread-115::DEBUG::2012-11-07 10:14:22,036::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host4/scan' (cwd None)
>MainProcess|Thread-115::DEBUG::2012-11-07 10:14:22,037::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host5/scan' (cwd None)
>MainProcess|Thread-115::DEBUG::2012-11-07 10:14:22,039::iscsi::388::Storage.ISCSI::(forceIScsiScan) Performing SCSI scan, this will take up to 30 seconds
>Thread-116::DEBUG::2012-11-07 10:14:23,443::task::588::TaskManager.Task::(_updateState) Task=`e416406e-5a58-46ca-8c6d-de2497d94a5c`::moving from state init -> state preparing
>Thread-116::INFO::2012-11-07 10:14:23,443::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-116::INFO::2012-11-07 10:14:23,443::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-116::DEBUG::2012-11-07 10:14:23,443::task::1172::TaskManager.Task::(prepare) Task=`e416406e-5a58-46ca-8c6d-de2497d94a5c`::finished: {}
>Thread-116::DEBUG::2012-11-07 10:14:23,444::task::588::TaskManager.Task::(_updateState) Task=`e416406e-5a58-46ca-8c6d-de2497d94a5c`::moving from state preparing -> state finished
>Thread-116::DEBUG::2012-11-07 10:14:23,444::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-116::DEBUG::2012-11-07 10:14:23,444::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-116::DEBUG::2012-11-07 10:14:23,444::task::978::TaskManager.Task::(_decref) Task=`e416406e-5a58-46ca-8c6d-de2497d94a5c`::ref 0 aborting False
>Thread-115::DEBUG::2012-11-07 10:14:24,042::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /sbin/multipath' (cwd None)
>Thread-115::DEBUG::2012-11-07 10:14:24,115::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-115::DEBUG::2012-11-07 10:14:24,115::lvm::460::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operation mutex
>Thread-115::DEBUG::2012-11-07 10:14:24,116::lvm::462::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the operation mutex
>Thread-115::DEBUG::2012-11-07 10:14:24,116::lvm::472::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operation mutex
>Thread-115::DEBUG::2012-11-07 10:14:24,116::lvm::474::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the operation mutex
>Thread-115::DEBUG::2012-11-07 10:14:24,117::lvm::493::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operation mutex
>Thread-115::DEBUG::2012-11-07 10:14:24,117::lvm::495::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the operation mutex
>Thread-115::DEBUG::2012-11-07 10:14:24,117::misc::1054::SamplingMethod::(__call__) Returning last result
>Thread-115::DEBUG::2012-11-07 10:14:24,117::lvm::352::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex
>Thread-115::DEBUG::2012-11-07 10:14:24,118::__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 = [ \\"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 f6ec4069-d8d2-4c23-aa0e-9a6758177e1f' (cwd None)
>Thread-115::DEBUG::2012-11-07 10:14:24,243::__init__::1164::Storage.Misc.excCmd::(_log) FAILED: <err> = '  Volume group "f6ec4069-d8d2-4c23-aa0e-9a6758177e1f" not found\n'; <rc> = 5
>Thread-115::WARNING::2012-11-07 10:14:24,244::lvm::356::Storage.LVM::(_reloadvgs) lvm vgs failed: 5 [] ['  Volume group "f6ec4069-d8d2-4c23-aa0e-9a6758177e1f" not found']
>Thread-115::DEBUG::2012-11-07 10:14:24,244::lvm::379::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex
>Thread-115::INFO::2012-11-07 10:14:24,349::nfsSD::66::Storage.StorageDomain::(create) sdUUID=f6ec4069-d8d2-4c23-aa0e-9a6758177e1f domainName=638 remotePath=10.66.6.38:/mnt/data/images domClass=1
>Thread-115::DEBUG::2012-11-07 10:14:24,418::persistentDict::185::Storage.PersistentDict::(__init__) Created a persistant dict with FileMetadataRW backend
>Thread-115::DEBUG::2012-11-07 10:14:24,419::persistentDict::226::Storage.PersistentDict::(refresh) read lines (FileMetadataRW)=[]
>Thread-115::WARNING::2012-11-07 10:14:24,419::persistentDict::248::Storage.PersistentDict::(refresh) data has no embedded checksum - trust it as it is
>Thread-115::DEBUG::2012-11-07 10:14:24,420::persistentDict::162::Storage.PersistentDict::(transaction) Starting transaction
>Thread-115::DEBUG::2012-11-07 10:14:24,420::persistentDict::168::Storage.PersistentDict::(transaction) Flushing changes
>Thread-115::DEBUG::2012-11-07 10:14:24,420::persistentDict::287::Storage.PersistentDict::(flush) about to write lines (FileMetadataRW)=['CLASS=Data', 'DESCRIPTION=638', 'IOOPTIMEOUTSEC=1', 'LEASERETRIES=3', 'LEASETIMESEC=5', 'LOCKPOLICY=', 'LOCKRENEWALINTERVALSEC=5', 'POOL_UUID=', 'REMOTE_PATH=10.66.6.38:/mnt/data/images', 'ROLE=Regular', 'SDUUID=f6ec4069-d8d2-4c23-aa0e-9a6758177e1f', 'TYPE=NFS', 'VERSION=3', '_SHA_CKSUM=3ac0b45b2ce9aa60625c28b93314f1543d1259f4']
>Thread-115::DEBUG::2012-11-07 10:14:24,422::persistentDict::170::Storage.PersistentDict::(transaction) Finished transaction
>Thread-115::DEBUG::2012-11-07 10:14:24,423::fileSD::107::Storage.StorageDomain::(__init__) Reading domain in path /rhev/data-center/mnt/10.66.6.38:_mnt_data_images/f6ec4069-d8d2-4c23-aa0e-9a6758177e1f
>Thread-115::DEBUG::2012-11-07 10:14:24,423::persistentDict::185::Storage.PersistentDict::(__init__) Created a persistant dict with FileMetadataRW backend
>Thread-115::WARNING::2012-11-07 10:14:24,446::remoteFileHandler::185::Storage.CrabRPCProxy::(callCrabRPCFunction) Problem with handler, treating as timeout
>Traceback (most recent call last):
>  File "/usr/share/vdsm/storage/remoteFileHandler.py", line 177, in callCrabRPCFunction
>    rawLength = self._recvAll(LENGTH_STRUCT_LENGTH, timeout)
>  File "/usr/share/vdsm/storage/remoteFileHandler.py", line 146, in _recvAll
>    timeLeft):
>  File "/usr/lib64/python2.6/contextlib.py", line 83, in helper
>    return GeneratorContextManager(func(*args, **kwds))
>  File "/usr/share/vdsm/storage/remoteFileHandler.py", line 133, in _poll
>    raise Timeout()
>Timeout
>Thread-115::ERROR::2012-11-07 10:14:24,447::task::853::TaskManager.Task::(_setError) Task=`1e10e743-1e13-417b-9eac-29cf6a72ae72`::Unexpected error
>Traceback (most recent call last):
>  File "/usr/share/vdsm/storage/task.py", line 861, in _run
>    return fn(*args, **kargs)
>  File "/usr/share/vdsm/logUtils.py", line 38, in wrapper
>    res = f(*args, **kwargs)
>  File "/usr/share/vdsm/storage/hsm.py", line 2263, in createStorageDomain
>    typeSpecificArg, storageType, domVersion)
>  File "/usr/share/vdsm/storage/nfsSD.py", line 93, in create
>    fsd = cls(os.path.join(mntPoint, sdUUID))
>  File "/usr/share/vdsm/storage/fileSD.py", line 113, in __init__
>    sdUUID = metadata[sd.DMDK_SDUUID]
>  File "/usr/share/vdsm/storage/persistentDict.py", line 85, in __getitem__
>    return dec(self._dict[key])
>  File "/usr/share/vdsm/storage/persistentDict.py", line 193, in __getitem__
>    with self._accessWrapper():
>  File "/usr/lib64/python2.6/contextlib.py", line 16, in __enter__
>    return self.gen.next()
>  File "/usr/share/vdsm/storage/persistentDict.py", line 147, in _accessWrapper
>    self.refresh()
>  File "/usr/share/vdsm/storage/persistentDict.py", line 224, in refresh
>    lines = self._metaRW.readlines()
>  File "/usr/share/vdsm/storage/fileSD.py", line 82, in readlines
>    return misc.stripNewLines(self._oop.directReadLines(self._metafile))
>  File "/usr/share/vdsm/storage/remoteFileHandler.py", line 286, in callCrabRPCFunction
>    raise Timeout("Operation stuck on remote handler")
>Timeout: Operation stuck on remote handler
>Thread-115::DEBUG::2012-11-07 10:14:24,448::task::872::TaskManager.Task::(_run) Task=`1e10e743-1e13-417b-9eac-29cf6a72ae72`::Task._run: 1e10e743-1e13-417b-9eac-29cf6a72ae72 (1, 'f6ec4069-d8d2-4c23-aa0e-9a6758177e1f', '638', '10.66.6.38:/mnt/data/images', 1, '3') {} failed - stopping task
>Thread-115::DEBUG::2012-11-07 10:14:24,449::task::1199::TaskManager.Task::(stop) Task=`1e10e743-1e13-417b-9eac-29cf6a72ae72`::stopping in state preparing (force False)
>Thread-115::DEBUG::2012-11-07 10:14:24,449::task::978::TaskManager.Task::(_decref) Task=`1e10e743-1e13-417b-9eac-29cf6a72ae72`::ref 1 aborting True
>Thread-115::INFO::2012-11-07 10:14:24,449::task::1157::TaskManager.Task::(prepare) Task=`1e10e743-1e13-417b-9eac-29cf6a72ae72`::aborting: Task is aborted: u'Operation stuck on remote handler' - code 100
>Thread-115::DEBUG::2012-11-07 10:14:24,449::task::1162::TaskManager.Task::(prepare) Task=`1e10e743-1e13-417b-9eac-29cf6a72ae72`::Prepare: aborted: Operation stuck on remote handler
>Thread-115::DEBUG::2012-11-07 10:14:24,450::task::978::TaskManager.Task::(_decref) Task=`1e10e743-1e13-417b-9eac-29cf6a72ae72`::ref 0 aborting True
>Thread-115::DEBUG::2012-11-07 10:14:24,450::task::913::TaskManager.Task::(_doAbort) Task=`1e10e743-1e13-417b-9eac-29cf6a72ae72`::Task._doAbort: force False
>Thread-115::DEBUG::2012-11-07 10:14:24,450::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-115::DEBUG::2012-11-07 10:14:24,450::task::588::TaskManager.Task::(_updateState) Task=`1e10e743-1e13-417b-9eac-29cf6a72ae72`::moving from state preparing -> state aborting
>Thread-115::DEBUG::2012-11-07 10:14:24,450::task::537::TaskManager.Task::(__state_aborting) Task=`1e10e743-1e13-417b-9eac-29cf6a72ae72`::_aborting: recover policy none
>Thread-115::DEBUG::2012-11-07 10:14:24,451::task::588::TaskManager.Task::(_updateState) Task=`1e10e743-1e13-417b-9eac-29cf6a72ae72`::moving from state aborting -> state failed
>Thread-115::DEBUG::2012-11-07 10:14:24,451::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-115::DEBUG::2012-11-07 10:14:24,451::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-115::ERROR::2012-11-07 10:14:24,451::dispatcher::69::Storage.Dispatcher.Protect::(run) Operation stuck on remote handler
>Traceback (most recent call last):
>  File "/usr/share/vdsm/storage/dispatcher.py", line 61, in run
>    result = ctask.prepare(self.func, *args, **kwargs)
>  File "/usr/share/vdsm/storage/task.py", line 1164, in prepare
>    raise self.error
>Timeout: Operation stuck on remote handler
>Thread-128::DEBUG::2012-11-07 10:14:24,670::BindingXMLRPC::171::vds::(wrapper) [10.66.6.38]
>Thread-128::DEBUG::2012-11-07 10:14:24,671::task::588::TaskManager.Task::(_updateState) Task=`78692474-0132-4eb4-aaf4-47d4d1e353d3`::moving from state init -> state preparing
>Thread-128::INFO::2012-11-07 10:14:24,671::logUtils::37::dispatcher::(wrapper) Run and protect: disconnectStorageServer(domType=1, spUUID='00000000-0000-0000-0000-000000000000', conList=[{'connection': '10.66.6.38:/mnt/data/images', 'iqn': '', 'portal': '', 'user': '', 'password': '******', 'id': '00000000-0000-0000-0000-000000000000', 'port': ''}], options=None)
>Thread-128::DEBUG::2012-11-07 10:14:24,671::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /bin/umount -f -l /rhev/data-center/mnt/10.66.6.38:_mnt_data_images' (cwd None)
>Thread-128::DEBUG::2012-11-07 10:14:24,693::misc::1044::SamplingMethod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage)
>Thread-128::DEBUG::2012-11-07 10:14:24,694::misc::1046::SamplingMethod::(__call__) Got in to sampling method
>Thread-128::DEBUG::2012-11-07 10:14:24,694::misc::1044::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.rescan)
>Thread-128::DEBUG::2012-11-07 10:14:24,694::misc::1046::SamplingMethod::(__call__) Got in to sampling method
>Thread-128::DEBUG::2012-11-07 10:14:24,695::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /sbin/iscsiadm -m session -R' (cwd None)
>Thread-128::DEBUG::2012-11-07 10:14:24,707::__init__::1164::Storage.Misc.excCmd::(_log) FAILED: <err> = 'iscsiadm: No session found.\n'; <rc> = 21
>Thread-128::DEBUG::2012-11-07 10:14:24,707::misc::1054::SamplingMethod::(__call__) Returning last result
>MainProcess|Thread-128::DEBUG::2012-11-07 10:14:24,709::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host0/scan' (cwd None)
>MainProcess|Thread-128::DEBUG::2012-11-07 10:14:24,711::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host1/scan' (cwd None)
>MainProcess|Thread-128::DEBUG::2012-11-07 10:14:24,712::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host2/scan' (cwd None)
>MainProcess|Thread-128::DEBUG::2012-11-07 10:14:24,714::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host3/scan' (cwd None)
>MainProcess|Thread-128::DEBUG::2012-11-07 10:14:24,716::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host4/scan' (cwd None)
>MainProcess|Thread-128::DEBUG::2012-11-07 10:14:24,717::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host5/scan' (cwd None)
>MainProcess|Thread-128::DEBUG::2012-11-07 10:14:24,719::iscsi::388::Storage.ISCSI::(forceIScsiScan) Performing SCSI scan, this will take up to 30 seconds
>Thread-128::DEBUG::2012-11-07 10:14:26,722::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /sbin/multipath' (cwd None)
>Thread-128::DEBUG::2012-11-07 10:14:26,781::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-128::DEBUG::2012-11-07 10:14:26,781::lvm::460::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operation mutex
>Thread-128::DEBUG::2012-11-07 10:14:26,782::lvm::462::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the operation mutex
>Thread-128::DEBUG::2012-11-07 10:14:26,782::lvm::472::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operation mutex
>Thread-128::DEBUG::2012-11-07 10:14:26,782::lvm::474::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the operation mutex
>Thread-128::DEBUG::2012-11-07 10:14:26,782::lvm::493::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operation mutex
>Thread-128::DEBUG::2012-11-07 10:14:26,783::lvm::495::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the operation mutex
>Thread-128::DEBUG::2012-11-07 10:14:26,783::misc::1054::SamplingMethod::(__call__) Returning last result
>Thread-128::INFO::2012-11-07 10:14:26,783::logUtils::39::dispatcher::(wrapper) Run and protect: disconnectStorageServer, Return response: {'statuslist': [{'status': 0, 'id': '00000000-0000-0000-0000-000000000000'}]}
>Thread-128::DEBUG::2012-11-07 10:14:26,783::task::1172::TaskManager.Task::(prepare) Task=`78692474-0132-4eb4-aaf4-47d4d1e353d3`::finished: {'statuslist': [{'status': 0, 'id': '00000000-0000-0000-0000-000000000000'}]}
>Thread-128::DEBUG::2012-11-07 10:14:26,784::task::588::TaskManager.Task::(_updateState) Task=`78692474-0132-4eb4-aaf4-47d4d1e353d3`::moving from state preparing -> state finished
>Thread-128::DEBUG::2012-11-07 10:14:26,784::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-128::DEBUG::2012-11-07 10:14:26,784::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-128::DEBUG::2012-11-07 10:14:26,784::task::978::TaskManager.Task::(_decref) Task=`78692474-0132-4eb4-aaf4-47d4d1e353d3`::ref 0 aborting False
>Thread-133::DEBUG::2012-11-07 10:14:33,690::task::588::TaskManager.Task::(_updateState) Task=`02649c94-c2af-4ee1-a1d2-319879d28f73`::moving from state init -> state preparing
>Thread-133::INFO::2012-11-07 10:14:33,693::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-133::INFO::2012-11-07 10:14:33,693::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-133::DEBUG::2012-11-07 10:14:33,693::task::1172::TaskManager.Task::(prepare) Task=`02649c94-c2af-4ee1-a1d2-319879d28f73`::finished: {}
>Thread-133::DEBUG::2012-11-07 10:14:33,694::task::588::TaskManager.Task::(_updateState) Task=`02649c94-c2af-4ee1-a1d2-319879d28f73`::moving from state preparing -> state finished
>Thread-133::DEBUG::2012-11-07 10:14:33,694::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-133::DEBUG::2012-11-07 10:14:33,694::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-133::DEBUG::2012-11-07 10:14:33,694::task::978::TaskManager.Task::(_decref) Task=`02649c94-c2af-4ee1-a1d2-319879d28f73`::ref 0 aborting False
>Thread-139::DEBUG::2012-11-07 10:14:43,856::task::588::TaskManager.Task::(_updateState) Task=`83675839-a517-40a9-b4a0-bf6e1f3f4c45`::moving from state init -> state preparing
>Thread-139::INFO::2012-11-07 10:14:43,857::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-139::INFO::2012-11-07 10:14:43,857::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-139::DEBUG::2012-11-07 10:14:43,857::task::1172::TaskManager.Task::(prepare) Task=`83675839-a517-40a9-b4a0-bf6e1f3f4c45`::finished: {}
>Thread-139::DEBUG::2012-11-07 10:14:43,857::task::588::TaskManager.Task::(_updateState) Task=`83675839-a517-40a9-b4a0-bf6e1f3f4c45`::moving from state preparing -> state finished
>Thread-139::DEBUG::2012-11-07 10:14:43,857::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-139::DEBUG::2012-11-07 10:14:43,858::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-139::DEBUG::2012-11-07 10:14:43,858::task::978::TaskManager.Task::(_decref) Task=`83675839-a517-40a9-b4a0-bf6e1f3f4c45`::ref 0 aborting False
>Thread-145::DEBUG::2012-11-07 10:14:54,016::task::588::TaskManager.Task::(_updateState) Task=`f98a3b6c-0ae4-4a5f-ac90-cb00a64df4ec`::moving from state init -> state preparing
>Thread-145::INFO::2012-11-07 10:14:54,016::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-145::INFO::2012-11-07 10:14:54,016::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-145::DEBUG::2012-11-07 10:14:54,016::task::1172::TaskManager.Task::(prepare) Task=`f98a3b6c-0ae4-4a5f-ac90-cb00a64df4ec`::finished: {}
>Thread-145::DEBUG::2012-11-07 10:14:54,017::task::588::TaskManager.Task::(_updateState) Task=`f98a3b6c-0ae4-4a5f-ac90-cb00a64df4ec`::moving from state preparing -> state finished
>Thread-145::DEBUG::2012-11-07 10:14:54,017::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-145::DEBUG::2012-11-07 10:14:54,017::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-145::DEBUG::2012-11-07 10:14:54,017::task::978::TaskManager.Task::(_decref) Task=`f98a3b6c-0ae4-4a5f-ac90-cb00a64df4ec`::ref 0 aborting False
>Thread-151::DEBUG::2012-11-07 10:15:04,180::task::588::TaskManager.Task::(_updateState) Task=`a82ac55f-8193-4a28-bbee-fe466e11097c`::moving from state init -> state preparing
>Thread-151::INFO::2012-11-07 10:15:04,181::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-151::INFO::2012-11-07 10:15:04,181::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-151::DEBUG::2012-11-07 10:15:04,181::task::1172::TaskManager.Task::(prepare) Task=`a82ac55f-8193-4a28-bbee-fe466e11097c`::finished: {}
>Thread-151::DEBUG::2012-11-07 10:15:04,181::task::588::TaskManager.Task::(_updateState) Task=`a82ac55f-8193-4a28-bbee-fe466e11097c`::moving from state preparing -> state finished
>Thread-151::DEBUG::2012-11-07 10:15:04,182::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-151::DEBUG::2012-11-07 10:15:04,182::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-151::DEBUG::2012-11-07 10:15:04,182::task::978::TaskManager.Task::(_decref) Task=`a82ac55f-8193-4a28-bbee-fe466e11097c`::ref 0 aborting False
>Thread-157::DEBUG::2012-11-07 10:15:14,348::task::588::TaskManager.Task::(_updateState) Task=`2fc035e4-4153-40ae-a808-561328d8cd29`::moving from state init -> state preparing
>Thread-157::INFO::2012-11-07 10:15:14,349::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-157::INFO::2012-11-07 10:15:14,349::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-157::DEBUG::2012-11-07 10:15:14,349::task::1172::TaskManager.Task::(prepare) Task=`2fc035e4-4153-40ae-a808-561328d8cd29`::finished: {}
>Thread-157::DEBUG::2012-11-07 10:15:14,349::task::588::TaskManager.Task::(_updateState) Task=`2fc035e4-4153-40ae-a808-561328d8cd29`::moving from state preparing -> state finished
>Thread-157::DEBUG::2012-11-07 10:15:14,349::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-157::DEBUG::2012-11-07 10:15:14,350::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-157::DEBUG::2012-11-07 10:15:14,350::task::978::TaskManager.Task::(_decref) Task=`2fc035e4-4153-40ae-a808-561328d8cd29`::ref 0 aborting False
>Thread-163::DEBUG::2012-11-07 10:15:24,510::task::588::TaskManager.Task::(_updateState) Task=`cea90caa-0a1e-4e7f-b34c-f50e7f01bb48`::moving from state init -> state preparing
>Thread-163::INFO::2012-11-07 10:15:24,511::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-163::INFO::2012-11-07 10:15:24,511::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-163::DEBUG::2012-11-07 10:15:24,511::task::1172::TaskManager.Task::(prepare) Task=`cea90caa-0a1e-4e7f-b34c-f50e7f01bb48`::finished: {}
>Thread-163::DEBUG::2012-11-07 10:15:24,511::task::588::TaskManager.Task::(_updateState) Task=`cea90caa-0a1e-4e7f-b34c-f50e7f01bb48`::moving from state preparing -> state finished
>Thread-163::DEBUG::2012-11-07 10:15:24,511::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-163::DEBUG::2012-11-07 10:15:24,512::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-163::DEBUG::2012-11-07 10:15:24,512::task::978::TaskManager.Task::(_decref) Task=`cea90caa-0a1e-4e7f-b34c-f50e7f01bb48`::ref 0 aborting False
>Thread-169::DEBUG::2012-11-07 10:15:34,672::task::588::TaskManager.Task::(_updateState) Task=`33b25440-bb9c-4ba8-b942-062d1ed96f2f`::moving from state init -> state preparing
>Thread-169::INFO::2012-11-07 10:15:34,672::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-169::INFO::2012-11-07 10:15:34,673::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-169::DEBUG::2012-11-07 10:15:34,673::task::1172::TaskManager.Task::(prepare) Task=`33b25440-bb9c-4ba8-b942-062d1ed96f2f`::finished: {}
>Thread-169::DEBUG::2012-11-07 10:15:34,673::task::588::TaskManager.Task::(_updateState) Task=`33b25440-bb9c-4ba8-b942-062d1ed96f2f`::moving from state preparing -> state finished
>Thread-169::DEBUG::2012-11-07 10:15:34,673::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-169::DEBUG::2012-11-07 10:15:34,674::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-169::DEBUG::2012-11-07 10:15:34,674::task::978::TaskManager.Task::(_decref) Task=`33b25440-bb9c-4ba8-b942-062d1ed96f2f`::ref 0 aborting False
>Thread-175::DEBUG::2012-11-07 10:15:44,864::task::588::TaskManager.Task::(_updateState) Task=`978568bf-72f2-4d08-83ac-8dfed39491fa`::moving from state init -> state preparing
>Thread-175::INFO::2012-11-07 10:15:44,864::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-175::INFO::2012-11-07 10:15:44,864::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-175::DEBUG::2012-11-07 10:15:44,864::task::1172::TaskManager.Task::(prepare) Task=`978568bf-72f2-4d08-83ac-8dfed39491fa`::finished: {}
>Thread-175::DEBUG::2012-11-07 10:15:44,865::task::588::TaskManager.Task::(_updateState) Task=`978568bf-72f2-4d08-83ac-8dfed39491fa`::moving from state preparing -> state finished
>Thread-175::DEBUG::2012-11-07 10:15:44,865::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-175::DEBUG::2012-11-07 10:15:44,865::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-175::DEBUG::2012-11-07 10:15:44,865::task::978::TaskManager.Task::(_decref) Task=`978568bf-72f2-4d08-83ac-8dfed39491fa`::ref 0 aborting False
>Thread-181::DEBUG::2012-11-07 10:15:55,805::task::588::TaskManager.Task::(_updateState) Task=`519e3039-882f-47ab-825c-412bc71f7205`::moving from state init -> state preparing
>Thread-181::INFO::2012-11-07 10:15:55,805::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-181::INFO::2012-11-07 10:15:55,805::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-181::DEBUG::2012-11-07 10:15:55,805::task::1172::TaskManager.Task::(prepare) Task=`519e3039-882f-47ab-825c-412bc71f7205`::finished: {}
>Thread-181::DEBUG::2012-11-07 10:15:55,806::task::588::TaskManager.Task::(_updateState) Task=`519e3039-882f-47ab-825c-412bc71f7205`::moving from state preparing -> state finished
>Thread-181::DEBUG::2012-11-07 10:15:55,806::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-181::DEBUG::2012-11-07 10:15:55,806::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-181::DEBUG::2012-11-07 10:15:55,806::task::978::TaskManager.Task::(_decref) Task=`519e3039-882f-47ab-825c-412bc71f7205`::ref 0 aborting False
>Thread-187::DEBUG::2012-11-07 10:16:05,968::task::588::TaskManager.Task::(_updateState) Task=`693dd718-0036-4e89-b459-8e7453f8e036`::moving from state init -> state preparing
>Thread-187::INFO::2012-11-07 10:16:05,968::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-187::INFO::2012-11-07 10:16:05,969::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-187::DEBUG::2012-11-07 10:16:05,969::task::1172::TaskManager.Task::(prepare) Task=`693dd718-0036-4e89-b459-8e7453f8e036`::finished: {}
>Thread-187::DEBUG::2012-11-07 10:16:05,969::task::588::TaskManager.Task::(_updateState) Task=`693dd718-0036-4e89-b459-8e7453f8e036`::moving from state preparing -> state finished
>Thread-187::DEBUG::2012-11-07 10:16:05,969::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-187::DEBUG::2012-11-07 10:16:05,970::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-187::DEBUG::2012-11-07 10:16:05,970::task::978::TaskManager.Task::(_decref) Task=`693dd718-0036-4e89-b459-8e7453f8e036`::ref 0 aborting False
>Thread-190::DEBUG::2012-11-07 10:16:09,008::BindingXMLRPC::171::vds::(wrapper) [10.66.6.38]
>Thread-190::DEBUG::2012-11-07 10:16:09,009::task::588::TaskManager.Task::(_updateState) Task=`1e605541-f537-4724-a916-4c38869a3a78`::moving from state init -> state preparing
>Thread-190::INFO::2012-11-07 10:16:09,009::logUtils::37::dispatcher::(wrapper) Run and protect: validateStorageServerConnection(domType=1, spUUID='00000000-0000-0000-0000-000000000000', conList=[{'port': '', 'connection': '10.66.6.38:/mnt/data/images', 'iqn': '', 'portal': '', 'user': '', 'protocol_version': '3', 'password': '******', 'id': '00000000-0000-0000-0000-000000000000'}], options=None)
>Thread-190::INFO::2012-11-07 10:16:09,009::logUtils::39::dispatcher::(wrapper) Run and protect: validateStorageServerConnection, Return response: {'statuslist': [{'status': 0, 'id': '00000000-0000-0000-0000-000000000000'}]}
>Thread-190::DEBUG::2012-11-07 10:16:09,009::task::1172::TaskManager.Task::(prepare) Task=`1e605541-f537-4724-a916-4c38869a3a78`::finished: {'statuslist': [{'status': 0, 'id': '00000000-0000-0000-0000-000000000000'}]}
>Thread-190::DEBUG::2012-11-07 10:16:09,009::task::588::TaskManager.Task::(_updateState) Task=`1e605541-f537-4724-a916-4c38869a3a78`::moving from state preparing -> state finished
>Thread-190::DEBUG::2012-11-07 10:16:09,010::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-190::DEBUG::2012-11-07 10:16:09,010::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-190::DEBUG::2012-11-07 10:16:09,010::task::978::TaskManager.Task::(_decref) Task=`1e605541-f537-4724-a916-4c38869a3a78`::ref 0 aborting False
>Thread-191::DEBUG::2012-11-07 10:16:09,052::BindingXMLRPC::171::vds::(wrapper) [10.66.6.38]
>Thread-191::DEBUG::2012-11-07 10:16:09,052::task::588::TaskManager.Task::(_updateState) Task=`de10145a-856c-40a3-8b3f-f42af2bdd685`::moving from state init -> state preparing
>Thread-191::INFO::2012-11-07 10:16:09,053::logUtils::37::dispatcher::(wrapper) Run and protect: connectStorageServer(domType=1, spUUID='00000000-0000-0000-0000-000000000000', conList=[{'port': '', 'connection': '10.66.6.38:/mnt/data/images', 'iqn': '', 'portal': '', 'user': '', 'protocol_version': '3', 'password': '******', 'id': '2ceb6ca1-8c20-4bb1-9822-d23f48012eb2'}], options=None)
>Thread-191::DEBUG::2012-11-07 10:16:09,055::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /bin/mount -t nfs -o soft,nosharecache,timeo=600,retrans=6,nfsvers=3 10.66.6.38:/mnt/data/images /rhev/data-center/mnt/10.66.6.38:_mnt_data_images' (cwd None)
>Thread-191::INFO::2012-11-07 10:16:09,084::logUtils::39::dispatcher::(wrapper) Run and protect: connectStorageServer, Return response: {'statuslist': [{'status': 0, 'id': '2ceb6ca1-8c20-4bb1-9822-d23f48012eb2'}]}
>Thread-191::DEBUG::2012-11-07 10:16:09,084::task::1172::TaskManager.Task::(prepare) Task=`de10145a-856c-40a3-8b3f-f42af2bdd685`::finished: {'statuslist': [{'status': 0, 'id': '2ceb6ca1-8c20-4bb1-9822-d23f48012eb2'}]}
>Thread-191::DEBUG::2012-11-07 10:16:09,085::task::588::TaskManager.Task::(_updateState) Task=`de10145a-856c-40a3-8b3f-f42af2bdd685`::moving from state preparing -> state finished
>Thread-191::DEBUG::2012-11-07 10:16:09,085::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-191::DEBUG::2012-11-07 10:16:09,085::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-191::DEBUG::2012-11-07 10:16:09,085::task::978::TaskManager.Task::(_decref) Task=`de10145a-856c-40a3-8b3f-f42af2bdd685`::ref 0 aborting False
>Thread-192::DEBUG::2012-11-07 10:16:09,192::BindingXMLRPC::171::vds::(wrapper) [10.66.6.38]
>Thread-192::DEBUG::2012-11-07 10:16:09,192::task::588::TaskManager.Task::(_updateState) Task=`94e31ea3-8fc7-44e0-92eb-2c5198196493`::moving from state init -> state preparing
>Thread-192::INFO::2012-11-07 10:16:09,193::logUtils::37::dispatcher::(wrapper) Run and protect: createStorageDomain(storageType=1, sdUUID='32f38401-670a-4c5f-90bb-5ea96c375df3', domainName='638', typeSpecificArg='10.66.6.38:/mnt/data/images', domClass=1, domVersion='3', options=None)
>Thread-192::DEBUG::2012-11-07 10:16:09,193::misc::1044::SamplingMethod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage)
>Thread-192::DEBUG::2012-11-07 10:16:09,193::misc::1046::SamplingMethod::(__call__) Got in to sampling method
>Thread-192::DEBUG::2012-11-07 10:16:09,193::misc::1044::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.rescan)
>Thread-192::DEBUG::2012-11-07 10:16:09,194::misc::1046::SamplingMethod::(__call__) Got in to sampling method
>Thread-192::DEBUG::2012-11-07 10:16:09,194::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /sbin/iscsiadm -m session -R' (cwd None)
>Thread-192::DEBUG::2012-11-07 10:16:09,206::__init__::1164::Storage.Misc.excCmd::(_log) FAILED: <err> = 'iscsiadm: No session found.\n'; <rc> = 21
>Thread-192::DEBUG::2012-11-07 10:16:09,206::misc::1054::SamplingMethod::(__call__) Returning last result
>MainProcess|Thread-192::DEBUG::2012-11-07 10:16:09,208::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host0/scan' (cwd None)
>MainProcess|Thread-192::DEBUG::2012-11-07 10:16:09,209::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host1/scan' (cwd None)
>MainProcess|Thread-192::DEBUG::2012-11-07 10:16:09,211::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host2/scan' (cwd None)
>MainProcess|Thread-192::DEBUG::2012-11-07 10:16:09,213::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host3/scan' (cwd None)
>MainProcess|Thread-192::DEBUG::2012-11-07 10:16:09,214::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host4/scan' (cwd None)
>MainProcess|Thread-192::DEBUG::2012-11-07 10:16:09,216::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host5/scan' (cwd None)
>MainProcess|Thread-192::DEBUG::2012-11-07 10:16:09,217::iscsi::388::Storage.ISCSI::(forceIScsiScan) Performing SCSI scan, this will take up to 30 seconds
>Thread-192::DEBUG::2012-11-07 10:16:11,220::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /sbin/multipath' (cwd None)
>Thread-192::DEBUG::2012-11-07 10:16:11,273::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-192::DEBUG::2012-11-07 10:16:11,274::lvm::460::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operation mutex
>Thread-192::DEBUG::2012-11-07 10:16:11,274::lvm::462::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the operation mutex
>Thread-192::DEBUG::2012-11-07 10:16:11,275::lvm::472::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operation mutex
>Thread-192::DEBUG::2012-11-07 10:16:11,275::lvm::474::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the operation mutex
>Thread-192::DEBUG::2012-11-07 10:16:11,275::lvm::493::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operation mutex
>Thread-192::DEBUG::2012-11-07 10:16:11,275::lvm::495::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the operation mutex
>Thread-192::DEBUG::2012-11-07 10:16:11,276::misc::1054::SamplingMethod::(__call__) Returning last result
>Thread-192::DEBUG::2012-11-07 10:16:11,276::lvm::352::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex
>Thread-192::DEBUG::2012-11-07 10:16:11,277::__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 = [ \\"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 32f38401-670a-4c5f-90bb-5ea96c375df3' (cwd None)
>Thread-192::DEBUG::2012-11-07 10:16:11,388::__init__::1164::Storage.Misc.excCmd::(_log) FAILED: <err> = '  Volume group "32f38401-670a-4c5f-90bb-5ea96c375df3" not found\n'; <rc> = 5
>Thread-192::WARNING::2012-11-07 10:16:11,389::lvm::356::Storage.LVM::(_reloadvgs) lvm vgs failed: 5 [] ['  Volume group "32f38401-670a-4c5f-90bb-5ea96c375df3" not found']
>Thread-192::DEBUG::2012-11-07 10:16:11,389::lvm::379::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex
>Thread-192::INFO::2012-11-07 10:16:11,394::nfsSD::66::Storage.StorageDomain::(create) sdUUID=32f38401-670a-4c5f-90bb-5ea96c375df3 domainName=638 remotePath=10.66.6.38:/mnt/data/images domClass=1
>Thread-192::DEBUG::2012-11-07 10:16:11,445::persistentDict::185::Storage.PersistentDict::(__init__) Created a persistant dict with FileMetadataRW backend
>Thread-192::DEBUG::2012-11-07 10:16:11,446::persistentDict::226::Storage.PersistentDict::(refresh) read lines (FileMetadataRW)=[]
>Thread-192::WARNING::2012-11-07 10:16:11,446::persistentDict::248::Storage.PersistentDict::(refresh) data has no embedded checksum - trust it as it is
>Thread-192::DEBUG::2012-11-07 10:16:11,447::persistentDict::162::Storage.PersistentDict::(transaction) Starting transaction
>Thread-192::DEBUG::2012-11-07 10:16:11,447::persistentDict::168::Storage.PersistentDict::(transaction) Flushing changes
>Thread-192::DEBUG::2012-11-07 10:16:11,447::persistentDict::287::Storage.PersistentDict::(flush) about to write lines (FileMetadataRW)=['CLASS=Data', 'DESCRIPTION=638', 'IOOPTIMEOUTSEC=1', 'LEASERETRIES=3', 'LEASETIMESEC=5', 'LOCKPOLICY=', 'LOCKRENEWALINTERVALSEC=5', 'POOL_UUID=', 'REMOTE_PATH=10.66.6.38:/mnt/data/images', 'ROLE=Regular', 'SDUUID=32f38401-670a-4c5f-90bb-5ea96c375df3', 'TYPE=NFS', 'VERSION=3', '_SHA_CKSUM=04bf891c1b9b4223b8d3558493e8c09f1a5e919f']
>Thread-192::DEBUG::2012-11-07 10:16:11,449::persistentDict::170::Storage.PersistentDict::(transaction) Finished transaction
>Thread-192::DEBUG::2012-11-07 10:16:11,450::fileSD::107::Storage.StorageDomain::(__init__) Reading domain in path /rhev/data-center/mnt/10.66.6.38:_mnt_data_images/32f38401-670a-4c5f-90bb-5ea96c375df3
>Thread-192::DEBUG::2012-11-07 10:16:11,450::persistentDict::185::Storage.PersistentDict::(__init__) Created a persistant dict with FileMetadataRW backend
>Thread-192::WARNING::2012-11-07 10:16:11,459::remoteFileHandler::185::Storage.CrabRPCProxy::(callCrabRPCFunction) Problem with handler, treating as timeout
>Traceback (most recent call last):
>  File "/usr/share/vdsm/storage/remoteFileHandler.py", line 177, in callCrabRPCFunction
>    rawLength = self._recvAll(LENGTH_STRUCT_LENGTH, timeout)
>  File "/usr/share/vdsm/storage/remoteFileHandler.py", line 146, in _recvAll
>    timeLeft):
>  File "/usr/lib64/python2.6/contextlib.py", line 83, in helper
>    return GeneratorContextManager(func(*args, **kwds))
>  File "/usr/share/vdsm/storage/remoteFileHandler.py", line 133, in _poll
>    raise Timeout()
>Timeout
>Thread-192::ERROR::2012-11-07 10:16:11,460::task::853::TaskManager.Task::(_setError) Task=`94e31ea3-8fc7-44e0-92eb-2c5198196493`::Unexpected error
>Traceback (most recent call last):
>  File "/usr/share/vdsm/storage/task.py", line 861, in _run
>    return fn(*args, **kargs)
>  File "/usr/share/vdsm/logUtils.py", line 38, in wrapper
>    res = f(*args, **kwargs)
>  File "/usr/share/vdsm/storage/hsm.py", line 2263, in createStorageDomain
>    typeSpecificArg, storageType, domVersion)
>  File "/usr/share/vdsm/storage/nfsSD.py", line 93, in create
>    fsd = cls(os.path.join(mntPoint, sdUUID))
>  File "/usr/share/vdsm/storage/fileSD.py", line 113, in __init__
>    sdUUID = metadata[sd.DMDK_SDUUID]
>  File "/usr/share/vdsm/storage/persistentDict.py", line 85, in __getitem__
>    return dec(self._dict[key])
>  File "/usr/share/vdsm/storage/persistentDict.py", line 193, in __getitem__
>    with self._accessWrapper():
>  File "/usr/lib64/python2.6/contextlib.py", line 16, in __enter__
>    return self.gen.next()
>  File "/usr/share/vdsm/storage/persistentDict.py", line 147, in _accessWrapper
>    self.refresh()
>  File "/usr/share/vdsm/storage/persistentDict.py", line 224, in refresh
>    lines = self._metaRW.readlines()
>  File "/usr/share/vdsm/storage/fileSD.py", line 82, in readlines
>    return misc.stripNewLines(self._oop.directReadLines(self._metafile))
>  File "/usr/share/vdsm/storage/remoteFileHandler.py", line 286, in callCrabRPCFunction
>    raise Timeout("Operation stuck on remote handler")
>Timeout: Operation stuck on remote handler
>Thread-192::DEBUG::2012-11-07 10:16:11,460::task::872::TaskManager.Task::(_run) Task=`94e31ea3-8fc7-44e0-92eb-2c5198196493`::Task._run: 94e31ea3-8fc7-44e0-92eb-2c5198196493 (1, '32f38401-670a-4c5f-90bb-5ea96c375df3', '638', '10.66.6.38:/mnt/data/images', 1, '3') {} failed - stopping task
>Thread-192::DEBUG::2012-11-07 10:16:11,461::task::1199::TaskManager.Task::(stop) Task=`94e31ea3-8fc7-44e0-92eb-2c5198196493`::stopping in state preparing (force False)
>Thread-192::DEBUG::2012-11-07 10:16:11,461::task::978::TaskManager.Task::(_decref) Task=`94e31ea3-8fc7-44e0-92eb-2c5198196493`::ref 1 aborting True
>Thread-192::INFO::2012-11-07 10:16:11,461::task::1157::TaskManager.Task::(prepare) Task=`94e31ea3-8fc7-44e0-92eb-2c5198196493`::aborting: Task is aborted: u'Operation stuck on remote handler' - code 100
>Thread-192::DEBUG::2012-11-07 10:16:11,461::task::1162::TaskManager.Task::(prepare) Task=`94e31ea3-8fc7-44e0-92eb-2c5198196493`::Prepare: aborted: Operation stuck on remote handler
>Thread-192::DEBUG::2012-11-07 10:16:11,462::task::978::TaskManager.Task::(_decref) Task=`94e31ea3-8fc7-44e0-92eb-2c5198196493`::ref 0 aborting True
>Thread-192::DEBUG::2012-11-07 10:16:11,462::task::913::TaskManager.Task::(_doAbort) Task=`94e31ea3-8fc7-44e0-92eb-2c5198196493`::Task._doAbort: force False
>Thread-192::DEBUG::2012-11-07 10:16:11,462::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-192::DEBUG::2012-11-07 10:16:11,462::task::588::TaskManager.Task::(_updateState) Task=`94e31ea3-8fc7-44e0-92eb-2c5198196493`::moving from state preparing -> state aborting
>Thread-192::DEBUG::2012-11-07 10:16:11,462::task::537::TaskManager.Task::(__state_aborting) Task=`94e31ea3-8fc7-44e0-92eb-2c5198196493`::_aborting: recover policy none
>Thread-192::DEBUG::2012-11-07 10:16:11,463::task::588::TaskManager.Task::(_updateState) Task=`94e31ea3-8fc7-44e0-92eb-2c5198196493`::moving from state aborting -> state failed
>Thread-192::DEBUG::2012-11-07 10:16:11,463::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-192::DEBUG::2012-11-07 10:16:11,463::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-192::ERROR::2012-11-07 10:16:11,463::dispatcher::69::Storage.Dispatcher.Protect::(run) Operation stuck on remote handler
>Traceback (most recent call last):
>  File "/usr/share/vdsm/storage/dispatcher.py", line 61, in run
>    result = ctask.prepare(self.func, *args, **kwargs)
>  File "/usr/share/vdsm/storage/task.py", line 1164, in prepare
>    raise self.error
>Timeout: Operation stuck on remote handler
>Thread-204::DEBUG::2012-11-07 10:16:11,627::BindingXMLRPC::171::vds::(wrapper) [10.66.6.38]
>Thread-204::DEBUG::2012-11-07 10:16:11,627::task::588::TaskManager.Task::(_updateState) Task=`7a7fb13d-b7ee-43cb-a789-523344f39380`::moving from state init -> state preparing
>Thread-204::INFO::2012-11-07 10:16:11,627::logUtils::37::dispatcher::(wrapper) Run and protect: disconnectStorageServer(domType=1, spUUID='00000000-0000-0000-0000-000000000000', conList=[{'port': '', 'connection': '10.66.6.38:/mnt/data/images', 'iqn': '', 'portal': '', 'user': '', 'protocol_version': '3', 'password': '******', 'id': '00000000-0000-0000-0000-000000000000'}], options=None)
>Thread-204::DEBUG::2012-11-07 10:16:11,628::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /bin/umount -f -l /rhev/data-center/mnt/10.66.6.38:_mnt_data_images' (cwd None)
>Thread-204::DEBUG::2012-11-07 10:16:11,648::misc::1044::SamplingMethod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage)
>Thread-204::DEBUG::2012-11-07 10:16:11,648::misc::1046::SamplingMethod::(__call__) Got in to sampling method
>Thread-204::DEBUG::2012-11-07 10:16:11,649::misc::1044::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.rescan)
>Thread-204::DEBUG::2012-11-07 10:16:11,649::misc::1046::SamplingMethod::(__call__) Got in to sampling method
>Thread-204::DEBUG::2012-11-07 10:16:11,649::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /sbin/iscsiadm -m session -R' (cwd None)
>Thread-204::DEBUG::2012-11-07 10:16:11,661::__init__::1164::Storage.Misc.excCmd::(_log) FAILED: <err> = 'iscsiadm: No session found.\n'; <rc> = 21
>Thread-204::DEBUG::2012-11-07 10:16:11,661::misc::1054::SamplingMethod::(__call__) Returning last result
>MainProcess|Thread-204::DEBUG::2012-11-07 10:16:11,663::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host0/scan' (cwd None)
>MainProcess|Thread-204::DEBUG::2012-11-07 10:16:11,665::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host1/scan' (cwd None)
>MainProcess|Thread-204::DEBUG::2012-11-07 10:16:11,666::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host2/scan' (cwd None)
>MainProcess|Thread-204::DEBUG::2012-11-07 10:16:11,668::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host3/scan' (cwd None)
>MainProcess|Thread-204::DEBUG::2012-11-07 10:16:11,669::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host4/scan' (cwd None)
>MainProcess|Thread-204::DEBUG::2012-11-07 10:16:11,671::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/dd of=/sys/class/scsi_host/host5/scan' (cwd None)
>MainProcess|Thread-204::DEBUG::2012-11-07 10:16:11,673::iscsi::388::Storage.ISCSI::(forceIScsiScan) Performing SCSI scan, this will take up to 30 seconds
>Thread-204::DEBUG::2012-11-07 10:16:13,676::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /sbin/multipath' (cwd None)
>Thread-204::DEBUG::2012-11-07 10:16:13,730::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-204::DEBUG::2012-11-07 10:16:13,731::lvm::460::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operation mutex
>Thread-204::DEBUG::2012-11-07 10:16:13,731::lvm::462::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the operation mutex
>Thread-204::DEBUG::2012-11-07 10:16:13,731::lvm::472::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operation mutex
>Thread-204::DEBUG::2012-11-07 10:16:13,732::lvm::474::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the operation mutex
>Thread-204::DEBUG::2012-11-07 10:16:13,732::lvm::493::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operation mutex
>Thread-204::DEBUG::2012-11-07 10:16:13,732::lvm::495::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the operation mutex
>Thread-204::DEBUG::2012-11-07 10:16:13,732::misc::1054::SamplingMethod::(__call__) Returning last result
>Thread-204::INFO::2012-11-07 10:16:13,733::logUtils::39::dispatcher::(wrapper) Run and protect: disconnectStorageServer, Return response: {'statuslist': [{'status': 0, 'id': '00000000-0000-0000-0000-000000000000'}]}
>Thread-204::DEBUG::2012-11-07 10:16:13,733::task::1172::TaskManager.Task::(prepare) Task=`7a7fb13d-b7ee-43cb-a789-523344f39380`::finished: {'statuslist': [{'status': 0, 'id': '00000000-0000-0000-0000-000000000000'}]}
>Thread-204::DEBUG::2012-11-07 10:16:13,733::task::588::TaskManager.Task::(_updateState) Task=`7a7fb13d-b7ee-43cb-a789-523344f39380`::moving from state preparing -> state finished
>Thread-204::DEBUG::2012-11-07 10:16:13,733::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-204::DEBUG::2012-11-07 10:16:13,734::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-204::DEBUG::2012-11-07 10:16:13,734::task::978::TaskManager.Task::(_decref) Task=`7a7fb13d-b7ee-43cb-a789-523344f39380`::ref 0 aborting False
>Thread-207::DEBUG::2012-11-07 10:16:16,132::task::588::TaskManager.Task::(_updateState) Task=`acbdcc47-1458-4659-a6cb-24205512e6e4`::moving from state init -> state preparing
>Thread-207::INFO::2012-11-07 10:16:16,132::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-207::INFO::2012-11-07 10:16:16,133::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-207::DEBUG::2012-11-07 10:16:16,133::task::1172::TaskManager.Task::(prepare) Task=`acbdcc47-1458-4659-a6cb-24205512e6e4`::finished: {}
>Thread-207::DEBUG::2012-11-07 10:16:16,133::task::588::TaskManager.Task::(_updateState) Task=`acbdcc47-1458-4659-a6cb-24205512e6e4`::moving from state preparing -> state finished
>Thread-207::DEBUG::2012-11-07 10:16:16,133::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-207::DEBUG::2012-11-07 10:16:16,133::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-207::DEBUG::2012-11-07 10:16:16,134::task::978::TaskManager.Task::(_decref) Task=`acbdcc47-1458-4659-a6cb-24205512e6e4`::ref 0 aborting False
>Thread-213::DEBUG::2012-11-07 10:16:26,293::task::588::TaskManager.Task::(_updateState) Task=`aadb3d90-fada-43c4-9128-ab14249a3ff5`::moving from state init -> state preparing
>Thread-213::INFO::2012-11-07 10:16:26,294::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-213::INFO::2012-11-07 10:16:26,294::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-213::DEBUG::2012-11-07 10:16:26,294::task::1172::TaskManager.Task::(prepare) Task=`aadb3d90-fada-43c4-9128-ab14249a3ff5`::finished: {}
>Thread-213::DEBUG::2012-11-07 10:16:26,294::task::588::TaskManager.Task::(_updateState) Task=`aadb3d90-fada-43c4-9128-ab14249a3ff5`::moving from state preparing -> state finished
>Thread-213::DEBUG::2012-11-07 10:16:26,294::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-213::DEBUG::2012-11-07 10:16:26,295::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-213::DEBUG::2012-11-07 10:16:26,295::task::978::TaskManager.Task::(_decref) Task=`aadb3d90-fada-43c4-9128-ab14249a3ff5`::ref 0 aborting False
>Thread-219::DEBUG::2012-11-07 10:16:36,457::task::588::TaskManager.Task::(_updateState) Task=`726f295d-72ea-4dcc-9c19-f6b706096088`::moving from state init -> state preparing
>Thread-219::INFO::2012-11-07 10:16:36,457::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-219::INFO::2012-11-07 10:16:36,457::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-219::DEBUG::2012-11-07 10:16:36,458::task::1172::TaskManager.Task::(prepare) Task=`726f295d-72ea-4dcc-9c19-f6b706096088`::finished: {}
>Thread-219::DEBUG::2012-11-07 10:16:36,458::task::588::TaskManager.Task::(_updateState) Task=`726f295d-72ea-4dcc-9c19-f6b706096088`::moving from state preparing -> state finished
>Thread-219::DEBUG::2012-11-07 10:16:36,458::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-219::DEBUG::2012-11-07 10:16:36,458::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-219::DEBUG::2012-11-07 10:16:36,458::task::978::TaskManager.Task::(_decref) Task=`726f295d-72ea-4dcc-9c19-f6b706096088`::ref 0 aborting False
>Thread-225::DEBUG::2012-11-07 10:16:46,610::task::588::TaskManager.Task::(_updateState) Task=`a1344442-cb9f-498b-aca4-62a9f65eaf1b`::moving from state init -> state preparing
>Thread-225::INFO::2012-11-07 10:16:46,610::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-225::INFO::2012-11-07 10:16:46,611::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-225::DEBUG::2012-11-07 10:16:46,611::task::1172::TaskManager.Task::(prepare) Task=`a1344442-cb9f-498b-aca4-62a9f65eaf1b`::finished: {}
>Thread-225::DEBUG::2012-11-07 10:16:46,611::task::588::TaskManager.Task::(_updateState) Task=`a1344442-cb9f-498b-aca4-62a9f65eaf1b`::moving from state preparing -> state finished
>Thread-225::DEBUG::2012-11-07 10:16:46,611::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-225::DEBUG::2012-11-07 10:16:46,611::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-225::DEBUG::2012-11-07 10:16:46,612::task::978::TaskManager.Task::(_decref) Task=`a1344442-cb9f-498b-aca4-62a9f65eaf1b`::ref 0 aborting False
>Thread-231::DEBUG::2012-11-07 10:16:56,761::task::588::TaskManager.Task::(_updateState) Task=`af3336be-13df-4207-9e4d-d467f68e0fb1`::moving from state init -> state preparing
>Thread-231::INFO::2012-11-07 10:16:56,762::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-231::INFO::2012-11-07 10:16:56,762::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-231::DEBUG::2012-11-07 10:16:56,762::task::1172::TaskManager.Task::(prepare) Task=`af3336be-13df-4207-9e4d-d467f68e0fb1`::finished: {}
>Thread-231::DEBUG::2012-11-07 10:16:56,762::task::588::TaskManager.Task::(_updateState) Task=`af3336be-13df-4207-9e4d-d467f68e0fb1`::moving from state preparing -> state finished
>Thread-231::DEBUG::2012-11-07 10:16:56,762::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-231::DEBUG::2012-11-07 10:16:56,763::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-231::DEBUG::2012-11-07 10:16:56,763::task::978::TaskManager.Task::(_decref) Task=`af3336be-13df-4207-9e4d-d467f68e0fb1`::ref 0 aborting False
>Thread-237::DEBUG::2012-11-07 10:17:06,927::task::588::TaskManager.Task::(_updateState) Task=`3e92c51e-b297-476d-93e5-c0df21d63e09`::moving from state init -> state preparing
>Thread-237::INFO::2012-11-07 10:17:06,927::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-237::INFO::2012-11-07 10:17:06,928::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-237::DEBUG::2012-11-07 10:17:06,928::task::1172::TaskManager.Task::(prepare) Task=`3e92c51e-b297-476d-93e5-c0df21d63e09`::finished: {}
>Thread-237::DEBUG::2012-11-07 10:17:06,928::task::588::TaskManager.Task::(_updateState) Task=`3e92c51e-b297-476d-93e5-c0df21d63e09`::moving from state preparing -> state finished
>Thread-237::DEBUG::2012-11-07 10:17:06,928::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-237::DEBUG::2012-11-07 10:17:06,928::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-237::DEBUG::2012-11-07 10:17:06,929::task::978::TaskManager.Task::(_decref) Task=`3e92c51e-b297-476d-93e5-c0df21d63e09`::ref 0 aborting False
>Thread-243::DEBUG::2012-11-07 10:17:17,202::task::588::TaskManager.Task::(_updateState) Task=`56a0623e-4519-4279-865d-628c4ef7519c`::moving from state init -> state preparing
>Thread-243::INFO::2012-11-07 10:17:17,202::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-243::INFO::2012-11-07 10:17:17,202::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-243::DEBUG::2012-11-07 10:17:17,202::task::1172::TaskManager.Task::(prepare) Task=`56a0623e-4519-4279-865d-628c4ef7519c`::finished: {}
>Thread-243::DEBUG::2012-11-07 10:17:17,202::task::588::TaskManager.Task::(_updateState) Task=`56a0623e-4519-4279-865d-628c4ef7519c`::moving from state preparing -> state finished
>Thread-243::DEBUG::2012-11-07 10:17:17,203::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-243::DEBUG::2012-11-07 10:17:17,203::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-243::DEBUG::2012-11-07 10:17:17,203::task::978::TaskManager.Task::(_decref) Task=`56a0623e-4519-4279-865d-628c4ef7519c`::ref 0 aborting False
>Thread-249::DEBUG::2012-11-07 10:17:27,381::task::588::TaskManager.Task::(_updateState) Task=`b85a7585-535a-49cc-ae76-270052989399`::moving from state init -> state preparing
>Thread-249::INFO::2012-11-07 10:17:27,381::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-249::INFO::2012-11-07 10:17:27,382::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-249::DEBUG::2012-11-07 10:17:27,382::task::1172::TaskManager.Task::(prepare) Task=`b85a7585-535a-49cc-ae76-270052989399`::finished: {}
>Thread-249::DEBUG::2012-11-07 10:17:27,382::task::588::TaskManager.Task::(_updateState) Task=`b85a7585-535a-49cc-ae76-270052989399`::moving from state preparing -> state finished
>Thread-249::DEBUG::2012-11-07 10:17:27,382::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-249::DEBUG::2012-11-07 10:17:27,382::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-249::DEBUG::2012-11-07 10:17:27,383::task::978::TaskManager.Task::(_decref) Task=`b85a7585-535a-49cc-ae76-270052989399`::ref 0 aborting False
>Thread-255::DEBUG::2012-11-07 10:17:37,543::task::588::TaskManager.Task::(_updateState) Task=`c56c7268-fd81-40ce-86a6-7045e052b8b7`::moving from state init -> state preparing
>Thread-255::INFO::2012-11-07 10:17:37,544::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-255::INFO::2012-11-07 10:17:37,544::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-255::DEBUG::2012-11-07 10:17:37,544::task::1172::TaskManager.Task::(prepare) Task=`c56c7268-fd81-40ce-86a6-7045e052b8b7`::finished: {}
>Thread-255::DEBUG::2012-11-07 10:17:37,544::task::588::TaskManager.Task::(_updateState) Task=`c56c7268-fd81-40ce-86a6-7045e052b8b7`::moving from state preparing -> state finished
>Thread-255::DEBUG::2012-11-07 10:17:37,545::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-255::DEBUG::2012-11-07 10:17:37,545::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-255::DEBUG::2012-11-07 10:17:37,545::task::978::TaskManager.Task::(_decref) Task=`c56c7268-fd81-40ce-86a6-7045e052b8b7`::ref 0 aborting False
>Thread-261::DEBUG::2012-11-07 10:17:47,701::task::588::TaskManager.Task::(_updateState) Task=`fc84ace8-b016-4913-84a9-61354151b316`::moving from state init -> state preparing
>Thread-261::INFO::2012-11-07 10:17:47,701::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-261::INFO::2012-11-07 10:17:47,702::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-261::DEBUG::2012-11-07 10:17:47,702::task::1172::TaskManager.Task::(prepare) Task=`fc84ace8-b016-4913-84a9-61354151b316`::finished: {}
>Thread-261::DEBUG::2012-11-07 10:17:47,702::task::588::TaskManager.Task::(_updateState) Task=`fc84ace8-b016-4913-84a9-61354151b316`::moving from state preparing -> state finished
>Thread-261::DEBUG::2012-11-07 10:17:47,702::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-261::DEBUG::2012-11-07 10:17:47,702::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-261::DEBUG::2012-11-07 10:17:47,703::task::978::TaskManager.Task::(_decref) Task=`fc84ace8-b016-4913-84a9-61354151b316`::ref 0 aborting False
>Thread-267::DEBUG::2012-11-07 10:17:57,900::task::588::TaskManager.Task::(_updateState) Task=`3885ddf6-8523-4d0e-84f7-d142bb873acb`::moving from state init -> state preparing
>Thread-267::INFO::2012-11-07 10:17:57,900::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-267::INFO::2012-11-07 10:17:57,900::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-267::DEBUG::2012-11-07 10:17:57,900::task::1172::TaskManager.Task::(prepare) Task=`3885ddf6-8523-4d0e-84f7-d142bb873acb`::finished: {}
>Thread-267::DEBUG::2012-11-07 10:17:57,901::task::588::TaskManager.Task::(_updateState) Task=`3885ddf6-8523-4d0e-84f7-d142bb873acb`::moving from state preparing -> state finished
>Thread-267::DEBUG::2012-11-07 10:17:57,901::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-267::DEBUG::2012-11-07 10:17:57,901::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-267::DEBUG::2012-11-07 10:17:57,901::task::978::TaskManager.Task::(_decref) Task=`3885ddf6-8523-4d0e-84f7-d142bb873acb`::ref 0 aborting False
>Thread-273::DEBUG::2012-11-07 10:18:08,055::task::588::TaskManager.Task::(_updateState) Task=`de955f7b-ec60-4c05-8be3-f1429df0aea7`::moving from state init -> state preparing
>Thread-273::INFO::2012-11-07 10:18:08,056::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-273::INFO::2012-11-07 10:18:08,056::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-273::DEBUG::2012-11-07 10:18:08,056::task::1172::TaskManager.Task::(prepare) Task=`de955f7b-ec60-4c05-8be3-f1429df0aea7`::finished: {}
>Thread-273::DEBUG::2012-11-07 10:18:08,056::task::588::TaskManager.Task::(_updateState) Task=`de955f7b-ec60-4c05-8be3-f1429df0aea7`::moving from state preparing -> state finished
>Thread-273::DEBUG::2012-11-07 10:18:08,057::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-273::DEBUG::2012-11-07 10:18:08,057::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-273::DEBUG::2012-11-07 10:18:08,057::task::978::TaskManager.Task::(_decref) Task=`de955f7b-ec60-4c05-8be3-f1429df0aea7`::ref 0 aborting False
>Thread-279::DEBUG::2012-11-07 10:18:18,215::task::588::TaskManager.Task::(_updateState) Task=`21218557-e1a9-4f25-8ecb-b6a17f39bcc9`::moving from state init -> state preparing
>Thread-279::INFO::2012-11-07 10:18:18,215::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-279::INFO::2012-11-07 10:18:18,216::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-279::DEBUG::2012-11-07 10:18:18,216::task::1172::TaskManager.Task::(prepare) Task=`21218557-e1a9-4f25-8ecb-b6a17f39bcc9`::finished: {}
>Thread-279::DEBUG::2012-11-07 10:18:18,216::task::588::TaskManager.Task::(_updateState) Task=`21218557-e1a9-4f25-8ecb-b6a17f39bcc9`::moving from state preparing -> state finished
>Thread-279::DEBUG::2012-11-07 10:18:18,216::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-279::DEBUG::2012-11-07 10:18:18,216::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-279::DEBUG::2012-11-07 10:18:18,217::task::978::TaskManager.Task::(_decref) Task=`21218557-e1a9-4f25-8ecb-b6a17f39bcc9`::ref 0 aborting False
>Thread-285::DEBUG::2012-11-07 10:18:28,378::task::588::TaskManager.Task::(_updateState) Task=`e7601141-981e-4880-8038-432caa203218`::moving from state init -> state preparing
>Thread-285::INFO::2012-11-07 10:18:28,378::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-285::INFO::2012-11-07 10:18:28,378::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-285::DEBUG::2012-11-07 10:18:28,379::task::1172::TaskManager.Task::(prepare) Task=`e7601141-981e-4880-8038-432caa203218`::finished: {}
>Thread-285::DEBUG::2012-11-07 10:18:28,379::task::588::TaskManager.Task::(_updateState) Task=`e7601141-981e-4880-8038-432caa203218`::moving from state preparing -> state finished
>Thread-285::DEBUG::2012-11-07 10:18:28,379::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-285::DEBUG::2012-11-07 10:18:28,379::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-285::DEBUG::2012-11-07 10:18:28,379::task::978::TaskManager.Task::(_decref) Task=`e7601141-981e-4880-8038-432caa203218`::ref 0 aborting False
>Thread-291::DEBUG::2012-11-07 10:18:38,532::task::588::TaskManager.Task::(_updateState) Task=`98f63b3a-6255-48b2-a437-328ec4517a38`::moving from state init -> state preparing
>Thread-291::INFO::2012-11-07 10:18:38,532::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-291::INFO::2012-11-07 10:18:38,533::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-291::DEBUG::2012-11-07 10:18:38,533::task::1172::TaskManager.Task::(prepare) Task=`98f63b3a-6255-48b2-a437-328ec4517a38`::finished: {}
>Thread-291::DEBUG::2012-11-07 10:18:38,533::task::588::TaskManager.Task::(_updateState) Task=`98f63b3a-6255-48b2-a437-328ec4517a38`::moving from state preparing -> state finished
>Thread-291::DEBUG::2012-11-07 10:18:38,533::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-291::DEBUG::2012-11-07 10:18:38,533::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-291::DEBUG::2012-11-07 10:18:38,534::task::978::TaskManager.Task::(_decref) Task=`98f63b3a-6255-48b2-a437-328ec4517a38`::ref 0 aborting False
>Thread-297::DEBUG::2012-11-07 10:18:48,702::task::588::TaskManager.Task::(_updateState) Task=`35da7e97-1cee-4123-b568-8ebc7d911a40`::moving from state init -> state preparing
>Thread-297::INFO::2012-11-07 10:18:48,702::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-297::INFO::2012-11-07 10:18:48,703::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-297::DEBUG::2012-11-07 10:18:48,703::task::1172::TaskManager.Task::(prepare) Task=`35da7e97-1cee-4123-b568-8ebc7d911a40`::finished: {}
>Thread-297::DEBUG::2012-11-07 10:18:48,703::task::588::TaskManager.Task::(_updateState) Task=`35da7e97-1cee-4123-b568-8ebc7d911a40`::moving from state preparing -> state finished
>Thread-297::DEBUG::2012-11-07 10:18:48,703::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-297::DEBUG::2012-11-07 10:18:48,704::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-297::DEBUG::2012-11-07 10:18:48,704::task::978::TaskManager.Task::(_decref) Task=`35da7e97-1cee-4123-b568-8ebc7d911a40`::ref 0 aborting False
>Thread-303::DEBUG::2012-11-07 10:18:58,863::task::588::TaskManager.Task::(_updateState) Task=`0ca2f380-cd57-444e-be2e-d98a6026dcc0`::moving from state init -> state preparing
>Thread-303::INFO::2012-11-07 10:18:58,863::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-303::INFO::2012-11-07 10:18:58,863::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-303::DEBUG::2012-11-07 10:18:58,864::task::1172::TaskManager.Task::(prepare) Task=`0ca2f380-cd57-444e-be2e-d98a6026dcc0`::finished: {}
>Thread-303::DEBUG::2012-11-07 10:18:58,864::task::588::TaskManager.Task::(_updateState) Task=`0ca2f380-cd57-444e-be2e-d98a6026dcc0`::moving from state preparing -> state finished
>Thread-303::DEBUG::2012-11-07 10:18:58,864::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-303::DEBUG::2012-11-07 10:18:58,864::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-303::DEBUG::2012-11-07 10:18:58,865::task::978::TaskManager.Task::(_decref) Task=`0ca2f380-cd57-444e-be2e-d98a6026dcc0`::ref 0 aborting False
>Thread-309::DEBUG::2012-11-07 10:19:09,021::task::588::TaskManager.Task::(_updateState) Task=`11fcc59b-9799-46cb-b1f3-3e4a55dc099d`::moving from state init -> state preparing
>Thread-309::INFO::2012-11-07 10:19:09,021::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-309::INFO::2012-11-07 10:19:09,021::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-309::DEBUG::2012-11-07 10:19:09,022::task::1172::TaskManager.Task::(prepare) Task=`11fcc59b-9799-46cb-b1f3-3e4a55dc099d`::finished: {}
>Thread-309::DEBUG::2012-11-07 10:19:09,022::task::588::TaskManager.Task::(_updateState) Task=`11fcc59b-9799-46cb-b1f3-3e4a55dc099d`::moving from state preparing -> state finished
>Thread-309::DEBUG::2012-11-07 10:19:09,022::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-309::DEBUG::2012-11-07 10:19:09,022::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-309::DEBUG::2012-11-07 10:19:09,022::task::978::TaskManager.Task::(_decref) Task=`11fcc59b-9799-46cb-b1f3-3e4a55dc099d`::ref 0 aborting False
>Thread-315::DEBUG::2012-11-07 10:19:19,183::task::588::TaskManager.Task::(_updateState) Task=`f4d02003-32ff-4d16-8716-ac411a151409`::moving from state init -> state preparing
>Thread-315::INFO::2012-11-07 10:19:19,183::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-315::INFO::2012-11-07 10:19:19,183::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-315::DEBUG::2012-11-07 10:19:19,184::task::1172::TaskManager.Task::(prepare) Task=`f4d02003-32ff-4d16-8716-ac411a151409`::finished: {}
>Thread-315::DEBUG::2012-11-07 10:19:19,184::task::588::TaskManager.Task::(_updateState) Task=`f4d02003-32ff-4d16-8716-ac411a151409`::moving from state preparing -> state finished
>Thread-315::DEBUG::2012-11-07 10:19:19,184::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-315::DEBUG::2012-11-07 10:19:19,184::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-315::DEBUG::2012-11-07 10:19:19,185::task::978::TaskManager.Task::(_decref) Task=`f4d02003-32ff-4d16-8716-ac411a151409`::ref 0 aborting False
>Thread-321::DEBUG::2012-11-07 10:19:29,341::task::588::TaskManager.Task::(_updateState) Task=`7ea345af-ef02-4e4d-9670-87836ad28526`::moving from state init -> state preparing
>Thread-321::INFO::2012-11-07 10:19:29,342::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-321::INFO::2012-11-07 10:19:29,342::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-321::DEBUG::2012-11-07 10:19:29,342::task::1172::TaskManager.Task::(prepare) Task=`7ea345af-ef02-4e4d-9670-87836ad28526`::finished: {}
>Thread-321::DEBUG::2012-11-07 10:19:29,342::task::588::TaskManager.Task::(_updateState) Task=`7ea345af-ef02-4e4d-9670-87836ad28526`::moving from state preparing -> state finished
>Thread-321::DEBUG::2012-11-07 10:19:29,342::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-321::DEBUG::2012-11-07 10:19:29,343::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-321::DEBUG::2012-11-07 10:19:29,343::task::978::TaskManager.Task::(_decref) Task=`7ea345af-ef02-4e4d-9670-87836ad28526`::ref 0 aborting False
>Thread-327::DEBUG::2012-11-07 10:19:39,547::task::588::TaskManager.Task::(_updateState) Task=`dda5f868-59f4-4569-b877-2ebc015c60ea`::moving from state init -> state preparing
>Thread-327::INFO::2012-11-07 10:19:39,548::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-327::INFO::2012-11-07 10:19:39,548::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-327::DEBUG::2012-11-07 10:19:39,548::task::1172::TaskManager.Task::(prepare) Task=`dda5f868-59f4-4569-b877-2ebc015c60ea`::finished: {}
>Thread-327::DEBUG::2012-11-07 10:19:39,548::task::588::TaskManager.Task::(_updateState) Task=`dda5f868-59f4-4569-b877-2ebc015c60ea`::moving from state preparing -> state finished
>Thread-327::DEBUG::2012-11-07 10:19:39,548::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-327::DEBUG::2012-11-07 10:19:39,549::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-327::DEBUG::2012-11-07 10:19:39,549::task::978::TaskManager.Task::(_decref) Task=`dda5f868-59f4-4569-b877-2ebc015c60ea`::ref 0 aborting False
>Thread-333::DEBUG::2012-11-07 10:19:49,704::task::588::TaskManager.Task::(_updateState) Task=`aa3a7af0-01f9-4b4d-be37-73c1bf8c189a`::moving from state init -> state preparing
>Thread-333::INFO::2012-11-07 10:19:49,704::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-333::INFO::2012-11-07 10:19:49,704::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-333::DEBUG::2012-11-07 10:19:49,704::task::1172::TaskManager.Task::(prepare) Task=`aa3a7af0-01f9-4b4d-be37-73c1bf8c189a`::finished: {}
>Thread-333::DEBUG::2012-11-07 10:19:49,705::task::588::TaskManager.Task::(_updateState) Task=`aa3a7af0-01f9-4b4d-be37-73c1bf8c189a`::moving from state preparing -> state finished
>Thread-333::DEBUG::2012-11-07 10:19:49,705::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-333::DEBUG::2012-11-07 10:19:49,705::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-333::DEBUG::2012-11-07 10:19:49,705::task::978::TaskManager.Task::(_decref) Task=`aa3a7af0-01f9-4b4d-be37-73c1bf8c189a`::ref 0 aborting False
>Thread-339::DEBUG::2012-11-07 10:19:59,863::task::588::TaskManager.Task::(_updateState) Task=`04a4625d-ecaa-4435-a8f8-abb1f15f52a7`::moving from state init -> state preparing
>Thread-339::INFO::2012-11-07 10:19:59,863::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-339::INFO::2012-11-07 10:19:59,864::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-339::DEBUG::2012-11-07 10:19:59,864::task::1172::TaskManager.Task::(prepare) Task=`04a4625d-ecaa-4435-a8f8-abb1f15f52a7`::finished: {}
>Thread-339::DEBUG::2012-11-07 10:19:59,864::task::588::TaskManager.Task::(_updateState) Task=`04a4625d-ecaa-4435-a8f8-abb1f15f52a7`::moving from state preparing -> state finished
>Thread-339::DEBUG::2012-11-07 10:19:59,864::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-339::DEBUG::2012-11-07 10:19:59,864::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-339::DEBUG::2012-11-07 10:19:59,865::task::978::TaskManager.Task::(_decref) Task=`04a4625d-ecaa-4435-a8f8-abb1f15f52a7`::ref 0 aborting False
>Thread-345::DEBUG::2012-11-07 10:20:10,023::task::588::TaskManager.Task::(_updateState) Task=`03d6319a-f027-4412-8331-b407bad8f318`::moving from state init -> state preparing
>Thread-345::INFO::2012-11-07 10:20:10,024::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-345::INFO::2012-11-07 10:20:10,024::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-345::DEBUG::2012-11-07 10:20:10,024::task::1172::TaskManager.Task::(prepare) Task=`03d6319a-f027-4412-8331-b407bad8f318`::finished: {}
>Thread-345::DEBUG::2012-11-07 10:20:10,024::task::588::TaskManager.Task::(_updateState) Task=`03d6319a-f027-4412-8331-b407bad8f318`::moving from state preparing -> state finished
>Thread-345::DEBUG::2012-11-07 10:20:10,025::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-345::DEBUG::2012-11-07 10:20:10,025::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-345::DEBUG::2012-11-07 10:20:10,025::task::978::TaskManager.Task::(_decref) Task=`03d6319a-f027-4412-8331-b407bad8f318`::ref 0 aborting False
>Thread-351::DEBUG::2012-11-07 10:20:20,202::task::588::TaskManager.Task::(_updateState) Task=`ef8be873-9ba4-4328-a59e-0ab036b0aae4`::moving from state init -> state preparing
>Thread-351::INFO::2012-11-07 10:20:20,203::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-351::INFO::2012-11-07 10:20:20,203::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-351::DEBUG::2012-11-07 10:20:20,203::task::1172::TaskManager.Task::(prepare) Task=`ef8be873-9ba4-4328-a59e-0ab036b0aae4`::finished: {}
>Thread-351::DEBUG::2012-11-07 10:20:20,203::task::588::TaskManager.Task::(_updateState) Task=`ef8be873-9ba4-4328-a59e-0ab036b0aae4`::moving from state preparing -> state finished
>Thread-351::DEBUG::2012-11-07 10:20:20,203::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-351::DEBUG::2012-11-07 10:20:20,204::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-351::DEBUG::2012-11-07 10:20:20,204::task::978::TaskManager.Task::(_decref) Task=`ef8be873-9ba4-4328-a59e-0ab036b0aae4`::ref 0 aborting False
>Thread-357::DEBUG::2012-11-07 10:20:30,372::task::588::TaskManager.Task::(_updateState) Task=`7db6319f-0158-42b2-bac2-b0b285865415`::moving from state init -> state preparing
>Thread-357::INFO::2012-11-07 10:20:30,372::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-357::INFO::2012-11-07 10:20:30,372::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-357::DEBUG::2012-11-07 10:20:30,373::task::1172::TaskManager.Task::(prepare) Task=`7db6319f-0158-42b2-bac2-b0b285865415`::finished: {}
>Thread-357::DEBUG::2012-11-07 10:20:30,373::task::588::TaskManager.Task::(_updateState) Task=`7db6319f-0158-42b2-bac2-b0b285865415`::moving from state preparing -> state finished
>Thread-357::DEBUG::2012-11-07 10:20:30,373::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-357::DEBUG::2012-11-07 10:20:30,373::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-357::DEBUG::2012-11-07 10:20:30,373::task::978::TaskManager.Task::(_decref) Task=`7db6319f-0158-42b2-bac2-b0b285865415`::ref 0 aborting False
>Thread-363::DEBUG::2012-11-07 10:20:40,531::task::588::TaskManager.Task::(_updateState) Task=`eb887d3c-e414-4942-9810-f01f04b811ab`::moving from state init -> state preparing
>Thread-363::INFO::2012-11-07 10:20:40,532::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-363::INFO::2012-11-07 10:20:40,532::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-363::DEBUG::2012-11-07 10:20:40,532::task::1172::TaskManager.Task::(prepare) Task=`eb887d3c-e414-4942-9810-f01f04b811ab`::finished: {}
>Thread-363::DEBUG::2012-11-07 10:20:40,532::task::588::TaskManager.Task::(_updateState) Task=`eb887d3c-e414-4942-9810-f01f04b811ab`::moving from state preparing -> state finished
>Thread-363::DEBUG::2012-11-07 10:20:40,533::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-363::DEBUG::2012-11-07 10:20:40,533::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-363::DEBUG::2012-11-07 10:20:40,533::task::978::TaskManager.Task::(_decref) Task=`eb887d3c-e414-4942-9810-f01f04b811ab`::ref 0 aborting False
>Thread-369::DEBUG::2012-11-07 10:20:50,688::task::588::TaskManager.Task::(_updateState) Task=`0f82f1e9-ddfe-48f5-9c77-cc740d415b58`::moving from state init -> state preparing
>Thread-369::INFO::2012-11-07 10:20:50,688::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-369::INFO::2012-11-07 10:20:50,688::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-369::DEBUG::2012-11-07 10:20:50,688::task::1172::TaskManager.Task::(prepare) Task=`0f82f1e9-ddfe-48f5-9c77-cc740d415b58`::finished: {}
>Thread-369::DEBUG::2012-11-07 10:20:50,689::task::588::TaskManager.Task::(_updateState) Task=`0f82f1e9-ddfe-48f5-9c77-cc740d415b58`::moving from state preparing -> state finished
>Thread-369::DEBUG::2012-11-07 10:20:50,689::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-369::DEBUG::2012-11-07 10:20:50,689::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-369::DEBUG::2012-11-07 10:20:50,689::task::978::TaskManager.Task::(_decref) Task=`0f82f1e9-ddfe-48f5-9c77-cc740d415b58`::ref 0 aborting False
>Thread-375::DEBUG::2012-11-07 10:21:00,843::task::588::TaskManager.Task::(_updateState) Task=`afdfd71c-ef7d-46fa-b92e-b7a8a85a0a0d`::moving from state init -> state preparing
>Thread-375::INFO::2012-11-07 10:21:00,843::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-375::INFO::2012-11-07 10:21:00,844::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-375::DEBUG::2012-11-07 10:21:00,844::task::1172::TaskManager.Task::(prepare) Task=`afdfd71c-ef7d-46fa-b92e-b7a8a85a0a0d`::finished: {}
>Thread-375::DEBUG::2012-11-07 10:21:00,844::task::588::TaskManager.Task::(_updateState) Task=`afdfd71c-ef7d-46fa-b92e-b7a8a85a0a0d`::moving from state preparing -> state finished
>Thread-375::DEBUG::2012-11-07 10:21:00,844::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-375::DEBUG::2012-11-07 10:21:00,845::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-375::DEBUG::2012-11-07 10:21:00,845::task::978::TaskManager.Task::(_decref) Task=`afdfd71c-ef7d-46fa-b92e-b7a8a85a0a0d`::ref 0 aborting False
>Thread-381::DEBUG::2012-11-07 10:21:11,012::task::588::TaskManager.Task::(_updateState) Task=`6fcd2f14-1b3f-42a0-88fb-bc965e67fb16`::moving from state init -> state preparing
>Thread-381::INFO::2012-11-07 10:21:11,013::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-381::INFO::2012-11-07 10:21:11,013::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-381::DEBUG::2012-11-07 10:21:11,013::task::1172::TaskManager.Task::(prepare) Task=`6fcd2f14-1b3f-42a0-88fb-bc965e67fb16`::finished: {}
>Thread-381::DEBUG::2012-11-07 10:21:11,013::task::588::TaskManager.Task::(_updateState) Task=`6fcd2f14-1b3f-42a0-88fb-bc965e67fb16`::moving from state preparing -> state finished
>Thread-381::DEBUG::2012-11-07 10:21:11,014::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-381::DEBUG::2012-11-07 10:21:11,014::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-381::DEBUG::2012-11-07 10:21:11,014::task::978::TaskManager.Task::(_decref) Task=`6fcd2f14-1b3f-42a0-88fb-bc965e67fb16`::ref 0 aborting False
>Thread-387::DEBUG::2012-11-07 10:21:21,173::task::588::TaskManager.Task::(_updateState) Task=`8cfce468-c801-4561-92b0-3fef2d2cabde`::moving from state init -> state preparing
>Thread-387::INFO::2012-11-07 10:21:21,174::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-387::INFO::2012-11-07 10:21:21,174::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-387::DEBUG::2012-11-07 10:21:21,174::task::1172::TaskManager.Task::(prepare) Task=`8cfce468-c801-4561-92b0-3fef2d2cabde`::finished: {}
>Thread-387::DEBUG::2012-11-07 10:21:21,174::task::588::TaskManager.Task::(_updateState) Task=`8cfce468-c801-4561-92b0-3fef2d2cabde`::moving from state preparing -> state finished
>Thread-387::DEBUG::2012-11-07 10:21:21,175::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-387::DEBUG::2012-11-07 10:21:21,175::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-387::DEBUG::2012-11-07 10:21:21,175::task::978::TaskManager.Task::(_decref) Task=`8cfce468-c801-4561-92b0-3fef2d2cabde`::ref 0 aborting False
>Thread-393::DEBUG::2012-11-07 10:21:31,333::task::588::TaskManager.Task::(_updateState) Task=`e80f0c20-5b9f-4705-9681-36712898b283`::moving from state init -> state preparing
>Thread-393::INFO::2012-11-07 10:21:31,333::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-393::INFO::2012-11-07 10:21:31,334::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-393::DEBUG::2012-11-07 10:21:31,334::task::1172::TaskManager.Task::(prepare) Task=`e80f0c20-5b9f-4705-9681-36712898b283`::finished: {}
>Thread-393::DEBUG::2012-11-07 10:21:31,334::task::588::TaskManager.Task::(_updateState) Task=`e80f0c20-5b9f-4705-9681-36712898b283`::moving from state preparing -> state finished
>Thread-393::DEBUG::2012-11-07 10:21:31,334::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-393::DEBUG::2012-11-07 10:21:31,334::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-393::DEBUG::2012-11-07 10:21:31,335::task::978::TaskManager.Task::(_decref) Task=`e80f0c20-5b9f-4705-9681-36712898b283`::ref 0 aborting False
>Thread-399::DEBUG::2012-11-07 10:21:41,503::task::588::TaskManager.Task::(_updateState) Task=`a2ad3242-544d-44cb-af49-bf2d5dda6c1d`::moving from state init -> state preparing
>Thread-399::INFO::2012-11-07 10:21:41,503::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-399::INFO::2012-11-07 10:21:41,503::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-399::DEBUG::2012-11-07 10:21:41,504::task::1172::TaskManager.Task::(prepare) Task=`a2ad3242-544d-44cb-af49-bf2d5dda6c1d`::finished: {}
>Thread-399::DEBUG::2012-11-07 10:21:41,504::task::588::TaskManager.Task::(_updateState) Task=`a2ad3242-544d-44cb-af49-bf2d5dda6c1d`::moving from state preparing -> state finished
>Thread-399::DEBUG::2012-11-07 10:21:41,504::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-399::DEBUG::2012-11-07 10:21:41,504::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-399::DEBUG::2012-11-07 10:21:41,504::task::978::TaskManager.Task::(_decref) Task=`a2ad3242-544d-44cb-af49-bf2d5dda6c1d`::ref 0 aborting False
>Thread-405::DEBUG::2012-11-07 10:21:51,674::task::588::TaskManager.Task::(_updateState) Task=`5bf571ab-aa4b-4fb9-bb8e-9c52e23269f4`::moving from state init -> state preparing
>Thread-405::INFO::2012-11-07 10:21:51,674::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-405::INFO::2012-11-07 10:21:51,674::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-405::DEBUG::2012-11-07 10:21:51,675::task::1172::TaskManager.Task::(prepare) Task=`5bf571ab-aa4b-4fb9-bb8e-9c52e23269f4`::finished: {}
>Thread-405::DEBUG::2012-11-07 10:21:51,675::task::588::TaskManager.Task::(_updateState) Task=`5bf571ab-aa4b-4fb9-bb8e-9c52e23269f4`::moving from state preparing -> state finished
>Thread-405::DEBUG::2012-11-07 10:21:51,675::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-405::DEBUG::2012-11-07 10:21:51,675::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-405::DEBUG::2012-11-07 10:21:51,675::task::978::TaskManager.Task::(_decref) Task=`5bf571ab-aa4b-4fb9-bb8e-9c52e23269f4`::ref 0 aborting False
>Thread-411::DEBUG::2012-11-07 10:22:01,833::task::588::TaskManager.Task::(_updateState) Task=`e30c2410-a35a-4ea1-aea3-f71cc40b2bfb`::moving from state init -> state preparing
>Thread-411::INFO::2012-11-07 10:22:01,834::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-411::INFO::2012-11-07 10:22:01,834::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-411::DEBUG::2012-11-07 10:22:01,834::task::1172::TaskManager.Task::(prepare) Task=`e30c2410-a35a-4ea1-aea3-f71cc40b2bfb`::finished: {}
>Thread-411::DEBUG::2012-11-07 10:22:01,834::task::588::TaskManager.Task::(_updateState) Task=`e30c2410-a35a-4ea1-aea3-f71cc40b2bfb`::moving from state preparing -> state finished
>Thread-411::DEBUG::2012-11-07 10:22:01,834::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-411::DEBUG::2012-11-07 10:22:01,835::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-411::DEBUG::2012-11-07 10:22:01,835::task::978::TaskManager.Task::(_decref) Task=`e30c2410-a35a-4ea1-aea3-f71cc40b2bfb`::ref 0 aborting False
>Thread-417::DEBUG::2012-11-07 10:22:11,992::task::588::TaskManager.Task::(_updateState) Task=`57e0b579-8cfb-4550-8937-f1d100b42965`::moving from state init -> state preparing
>Thread-417::INFO::2012-11-07 10:22:11,992::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-417::INFO::2012-11-07 10:22:11,992::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-417::DEBUG::2012-11-07 10:22:11,993::task::1172::TaskManager.Task::(prepare) Task=`57e0b579-8cfb-4550-8937-f1d100b42965`::finished: {}
>Thread-417::DEBUG::2012-11-07 10:22:11,993::task::588::TaskManager.Task::(_updateState) Task=`57e0b579-8cfb-4550-8937-f1d100b42965`::moving from state preparing -> state finished
>Thread-417::DEBUG::2012-11-07 10:22:11,993::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-417::DEBUG::2012-11-07 10:22:11,993::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-417::DEBUG::2012-11-07 10:22:11,994::task::978::TaskManager.Task::(_decref) Task=`57e0b579-8cfb-4550-8937-f1d100b42965`::ref 0 aborting False
>Thread-423::DEBUG::2012-11-07 10:22:22,154::task::588::TaskManager.Task::(_updateState) Task=`9a293872-1b87-409e-9d5e-a47ed153e5ad`::moving from state init -> state preparing
>Thread-423::INFO::2012-11-07 10:22:22,154::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-423::INFO::2012-11-07 10:22:22,154::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-423::DEBUG::2012-11-07 10:22:22,155::task::1172::TaskManager.Task::(prepare) Task=`9a293872-1b87-409e-9d5e-a47ed153e5ad`::finished: {}
>Thread-423::DEBUG::2012-11-07 10:22:22,155::task::588::TaskManager.Task::(_updateState) Task=`9a293872-1b87-409e-9d5e-a47ed153e5ad`::moving from state preparing -> state finished
>Thread-423::DEBUG::2012-11-07 10:22:22,155::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-423::DEBUG::2012-11-07 10:22:22,155::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-423::DEBUG::2012-11-07 10:22:22,156::task::978::TaskManager.Task::(_decref) Task=`9a293872-1b87-409e-9d5e-a47ed153e5ad`::ref 0 aborting False
>Thread-429::DEBUG::2012-11-07 10:22:32,318::task::588::TaskManager.Task::(_updateState) Task=`b7574bd3-6de1-4ed7-95fa-40a297acb9c3`::moving from state init -> state preparing
>Thread-429::INFO::2012-11-07 10:22:32,319::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-429::INFO::2012-11-07 10:22:32,319::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-429::DEBUG::2012-11-07 10:22:32,319::task::1172::TaskManager.Task::(prepare) Task=`b7574bd3-6de1-4ed7-95fa-40a297acb9c3`::finished: {}
>Thread-429::DEBUG::2012-11-07 10:22:32,319::task::588::TaskManager.Task::(_updateState) Task=`b7574bd3-6de1-4ed7-95fa-40a297acb9c3`::moving from state preparing -> state finished
>Thread-429::DEBUG::2012-11-07 10:22:32,320::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-429::DEBUG::2012-11-07 10:22:32,320::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-429::DEBUG::2012-11-07 10:22:32,320::task::978::TaskManager.Task::(_decref) Task=`b7574bd3-6de1-4ed7-95fa-40a297acb9c3`::ref 0 aborting False
>Thread-435::DEBUG::2012-11-07 10:22:42,494::task::588::TaskManager.Task::(_updateState) Task=`e78a5323-3dcf-41af-bf31-571616d9dcb0`::moving from state init -> state preparing
>Thread-435::INFO::2012-11-07 10:22:42,494::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-435::INFO::2012-11-07 10:22:42,494::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-435::DEBUG::2012-11-07 10:22:42,494::task::1172::TaskManager.Task::(prepare) Task=`e78a5323-3dcf-41af-bf31-571616d9dcb0`::finished: {}
>Thread-435::DEBUG::2012-11-07 10:22:42,495::task::588::TaskManager.Task::(_updateState) Task=`e78a5323-3dcf-41af-bf31-571616d9dcb0`::moving from state preparing -> state finished
>Thread-435::DEBUG::2012-11-07 10:22:42,495::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-435::DEBUG::2012-11-07 10:22:42,495::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-435::DEBUG::2012-11-07 10:22:42,495::task::978::TaskManager.Task::(_decref) Task=`e78a5323-3dcf-41af-bf31-571616d9dcb0`::ref 0 aborting False
>Thread-441::DEBUG::2012-11-07 10:22:52,665::task::588::TaskManager.Task::(_updateState) Task=`d20834ff-66d9-4e40-b95a-ebc5d17af8f5`::moving from state init -> state preparing
>Thread-441::INFO::2012-11-07 10:22:52,665::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-441::INFO::2012-11-07 10:22:52,665::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-441::DEBUG::2012-11-07 10:22:52,666::task::1172::TaskManager.Task::(prepare) Task=`d20834ff-66d9-4e40-b95a-ebc5d17af8f5`::finished: {}
>Thread-441::DEBUG::2012-11-07 10:22:52,666::task::588::TaskManager.Task::(_updateState) Task=`d20834ff-66d9-4e40-b95a-ebc5d17af8f5`::moving from state preparing -> state finished
>Thread-441::DEBUG::2012-11-07 10:22:52,666::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-441::DEBUG::2012-11-07 10:22:52,666::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-441::DEBUG::2012-11-07 10:22:52,666::task::978::TaskManager.Task::(_decref) Task=`d20834ff-66d9-4e40-b95a-ebc5d17af8f5`::ref 0 aborting False
>Thread-447::DEBUG::2012-11-07 10:23:02,863::task::588::TaskManager.Task::(_updateState) Task=`0691d564-f973-48a6-9b91-3f6f44a3530b`::moving from state init -> state preparing
>Thread-447::INFO::2012-11-07 10:23:02,863::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-447::INFO::2012-11-07 10:23:02,863::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-447::DEBUG::2012-11-07 10:23:02,864::task::1172::TaskManager.Task::(prepare) Task=`0691d564-f973-48a6-9b91-3f6f44a3530b`::finished: {}
>Thread-447::DEBUG::2012-11-07 10:23:02,864::task::588::TaskManager.Task::(_updateState) Task=`0691d564-f973-48a6-9b91-3f6f44a3530b`::moving from state preparing -> state finished
>Thread-447::DEBUG::2012-11-07 10:23:02,864::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-447::DEBUG::2012-11-07 10:23:02,864::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-447::DEBUG::2012-11-07 10:23:02,864::task::978::TaskManager.Task::(_decref) Task=`0691d564-f973-48a6-9b91-3f6f44a3530b`::ref 0 aborting False
>Thread-453::DEBUG::2012-11-07 10:23:13,024::task::588::TaskManager.Task::(_updateState) Task=`7f8f83fa-7286-468d-ae63-1ecba0b30534`::moving from state init -> state preparing
>Thread-453::INFO::2012-11-07 10:23:13,024::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-453::INFO::2012-11-07 10:23:13,025::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-453::DEBUG::2012-11-07 10:23:13,025::task::1172::TaskManager.Task::(prepare) Task=`7f8f83fa-7286-468d-ae63-1ecba0b30534`::finished: {}
>Thread-453::DEBUG::2012-11-07 10:23:13,025::task::588::TaskManager.Task::(_updateState) Task=`7f8f83fa-7286-468d-ae63-1ecba0b30534`::moving from state preparing -> state finished
>Thread-453::DEBUG::2012-11-07 10:23:13,025::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-453::DEBUG::2012-11-07 10:23:13,025::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-453::DEBUG::2012-11-07 10:23:13,026::task::978::TaskManager.Task::(_decref) Task=`7f8f83fa-7286-468d-ae63-1ecba0b30534`::ref 0 aborting False
>Thread-459::DEBUG::2012-11-07 10:23:23,198::task::588::TaskManager.Task::(_updateState) Task=`96c6b116-b0f9-4ee0-8d6c-6e29df20e7b6`::moving from state init -> state preparing
>Thread-459::INFO::2012-11-07 10:23:23,198::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-459::INFO::2012-11-07 10:23:23,199::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-459::DEBUG::2012-11-07 10:23:23,199::task::1172::TaskManager.Task::(prepare) Task=`96c6b116-b0f9-4ee0-8d6c-6e29df20e7b6`::finished: {}
>Thread-459::DEBUG::2012-11-07 10:23:23,199::task::588::TaskManager.Task::(_updateState) Task=`96c6b116-b0f9-4ee0-8d6c-6e29df20e7b6`::moving from state preparing -> state finished
>Thread-459::DEBUG::2012-11-07 10:23:23,199::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-459::DEBUG::2012-11-07 10:23:23,199::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-459::DEBUG::2012-11-07 10:23:23,200::task::978::TaskManager.Task::(_decref) Task=`96c6b116-b0f9-4ee0-8d6c-6e29df20e7b6`::ref 0 aborting False
>Thread-465::DEBUG::2012-11-07 10:23:33,356::task::588::TaskManager.Task::(_updateState) Task=`17d5aed3-a7c0-4f7f-8c0b-0847df3f9e0b`::moving from state init -> state preparing
>Thread-465::INFO::2012-11-07 10:23:33,356::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-465::INFO::2012-11-07 10:23:33,357::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-465::DEBUG::2012-11-07 10:23:33,357::task::1172::TaskManager.Task::(prepare) Task=`17d5aed3-a7c0-4f7f-8c0b-0847df3f9e0b`::finished: {}
>Thread-465::DEBUG::2012-11-07 10:23:33,357::task::588::TaskManager.Task::(_updateState) Task=`17d5aed3-a7c0-4f7f-8c0b-0847df3f9e0b`::moving from state preparing -> state finished
>Thread-465::DEBUG::2012-11-07 10:23:33,357::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-465::DEBUG::2012-11-07 10:23:33,357::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-465::DEBUG::2012-11-07 10:23:33,358::task::978::TaskManager.Task::(_decref) Task=`17d5aed3-a7c0-4f7f-8c0b-0847df3f9e0b`::ref 0 aborting False
>Thread-471::DEBUG::2012-11-07 10:23:43,514::task::588::TaskManager.Task::(_updateState) Task=`8773ed31-05ce-4382-8fc7-0dc3bae05191`::moving from state init -> state preparing
>Thread-471::INFO::2012-11-07 10:23:43,514::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-471::INFO::2012-11-07 10:23:43,515::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-471::DEBUG::2012-11-07 10:23:43,515::task::1172::TaskManager.Task::(prepare) Task=`8773ed31-05ce-4382-8fc7-0dc3bae05191`::finished: {}
>Thread-471::DEBUG::2012-11-07 10:23:43,515::task::588::TaskManager.Task::(_updateState) Task=`8773ed31-05ce-4382-8fc7-0dc3bae05191`::moving from state preparing -> state finished
>Thread-471::DEBUG::2012-11-07 10:23:43,515::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-471::DEBUG::2012-11-07 10:23:43,515::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-471::DEBUG::2012-11-07 10:23:43,516::task::978::TaskManager.Task::(_decref) Task=`8773ed31-05ce-4382-8fc7-0dc3bae05191`::ref 0 aborting False
>Thread-477::DEBUG::2012-11-07 10:23:53,669::task::588::TaskManager.Task::(_updateState) Task=`3e320d8a-d416-4a39-a416-cbaa6c9ad1ce`::moving from state init -> state preparing
>Thread-477::INFO::2012-11-07 10:23:53,669::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-477::INFO::2012-11-07 10:23:53,669::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-477::DEBUG::2012-11-07 10:23:53,669::task::1172::TaskManager.Task::(prepare) Task=`3e320d8a-d416-4a39-a416-cbaa6c9ad1ce`::finished: {}
>Thread-477::DEBUG::2012-11-07 10:23:53,670::task::588::TaskManager.Task::(_updateState) Task=`3e320d8a-d416-4a39-a416-cbaa6c9ad1ce`::moving from state preparing -> state finished
>Thread-477::DEBUG::2012-11-07 10:23:53,670::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-477::DEBUG::2012-11-07 10:23:53,670::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-477::DEBUG::2012-11-07 10:23:53,670::task::978::TaskManager.Task::(_decref) Task=`3e320d8a-d416-4a39-a416-cbaa6c9ad1ce`::ref 0 aborting False
>Thread-483::DEBUG::2012-11-07 10:24:03,834::task::588::TaskManager.Task::(_updateState) Task=`2d3b91a8-8b6b-49b2-aa6e-0ce796ae2587`::moving from state init -> state preparing
>Thread-483::INFO::2012-11-07 10:24:03,835::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-483::INFO::2012-11-07 10:24:03,835::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-483::DEBUG::2012-11-07 10:24:03,835::task::1172::TaskManager.Task::(prepare) Task=`2d3b91a8-8b6b-49b2-aa6e-0ce796ae2587`::finished: {}
>Thread-483::DEBUG::2012-11-07 10:24:03,835::task::588::TaskManager.Task::(_updateState) Task=`2d3b91a8-8b6b-49b2-aa6e-0ce796ae2587`::moving from state preparing -> state finished
>Thread-483::DEBUG::2012-11-07 10:24:03,835::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-483::DEBUG::2012-11-07 10:24:03,836::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-483::DEBUG::2012-11-07 10:24:03,836::task::978::TaskManager.Task::(_decref) Task=`2d3b91a8-8b6b-49b2-aa6e-0ce796ae2587`::ref 0 aborting False
>Thread-489::DEBUG::2012-11-07 10:24:13,994::task::588::TaskManager.Task::(_updateState) Task=`49daecc6-4431-4f9b-ad87-bcdffd7db9c2`::moving from state init -> state preparing
>Thread-489::INFO::2012-11-07 10:24:13,994::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-489::INFO::2012-11-07 10:24:13,995::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-489::DEBUG::2012-11-07 10:24:13,995::task::1172::TaskManager.Task::(prepare) Task=`49daecc6-4431-4f9b-ad87-bcdffd7db9c2`::finished: {}
>Thread-489::DEBUG::2012-11-07 10:24:13,995::task::588::TaskManager.Task::(_updateState) Task=`49daecc6-4431-4f9b-ad87-bcdffd7db9c2`::moving from state preparing -> state finished
>Thread-489::DEBUG::2012-11-07 10:24:13,995::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-489::DEBUG::2012-11-07 10:24:13,995::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-489::DEBUG::2012-11-07 10:24:13,996::task::978::TaskManager.Task::(_decref) Task=`49daecc6-4431-4f9b-ad87-bcdffd7db9c2`::ref 0 aborting False
>Thread-495::DEBUG::2012-11-07 10:24:24,153::task::588::TaskManager.Task::(_updateState) Task=`28bd70c3-b7d3-4205-9156-1d822c3cc844`::moving from state init -> state preparing
>Thread-495::INFO::2012-11-07 10:24:24,153::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-495::INFO::2012-11-07 10:24:24,153::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-495::DEBUG::2012-11-07 10:24:24,154::task::1172::TaskManager.Task::(prepare) Task=`28bd70c3-b7d3-4205-9156-1d822c3cc844`::finished: {}
>Thread-495::DEBUG::2012-11-07 10:24:24,154::task::588::TaskManager.Task::(_updateState) Task=`28bd70c3-b7d3-4205-9156-1d822c3cc844`::moving from state preparing -> state finished
>Thread-495::DEBUG::2012-11-07 10:24:24,154::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-495::DEBUG::2012-11-07 10:24:24,154::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-495::DEBUG::2012-11-07 10:24:24,155::task::978::TaskManager.Task::(_decref) Task=`28bd70c3-b7d3-4205-9156-1d822c3cc844`::ref 0 aborting False
>Thread-501::DEBUG::2012-11-07 10:24:34,314::task::588::TaskManager.Task::(_updateState) Task=`850aae98-cdca-46f3-a753-2634565f8e50`::moving from state init -> state preparing
>Thread-501::INFO::2012-11-07 10:24:34,314::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-501::INFO::2012-11-07 10:24:34,314::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-501::DEBUG::2012-11-07 10:24:34,314::task::1172::TaskManager.Task::(prepare) Task=`850aae98-cdca-46f3-a753-2634565f8e50`::finished: {}
>Thread-501::DEBUG::2012-11-07 10:24:34,315::task::588::TaskManager.Task::(_updateState) Task=`850aae98-cdca-46f3-a753-2634565f8e50`::moving from state preparing -> state finished
>Thread-501::DEBUG::2012-11-07 10:24:34,315::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-501::DEBUG::2012-11-07 10:24:34,315::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-501::DEBUG::2012-11-07 10:24:34,315::task::978::TaskManager.Task::(_decref) Task=`850aae98-cdca-46f3-a753-2634565f8e50`::ref 0 aborting False
>Thread-507::DEBUG::2012-11-07 10:24:44,474::task::588::TaskManager.Task::(_updateState) Task=`bc0a1b4e-8f8f-4c46-ae47-90207c4be81b`::moving from state init -> state preparing
>Thread-507::INFO::2012-11-07 10:24:44,474::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-507::INFO::2012-11-07 10:24:44,474::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-507::DEBUG::2012-11-07 10:24:44,475::task::1172::TaskManager.Task::(prepare) Task=`bc0a1b4e-8f8f-4c46-ae47-90207c4be81b`::finished: {}
>Thread-507::DEBUG::2012-11-07 10:24:44,475::task::588::TaskManager.Task::(_updateState) Task=`bc0a1b4e-8f8f-4c46-ae47-90207c4be81b`::moving from state preparing -> state finished
>Thread-507::DEBUG::2012-11-07 10:24:44,475::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-507::DEBUG::2012-11-07 10:24:44,475::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-507::DEBUG::2012-11-07 10:24:44,475::task::978::TaskManager.Task::(_decref) Task=`bc0a1b4e-8f8f-4c46-ae47-90207c4be81b`::ref 0 aborting False
>Thread-513::DEBUG::2012-11-07 10:24:54,654::task::588::TaskManager.Task::(_updateState) Task=`00dfd6ae-8ce2-46df-b68e-f0317f1a2034`::moving from state init -> state preparing
>Thread-513::INFO::2012-11-07 10:24:54,654::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-513::INFO::2012-11-07 10:24:54,654::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-513::DEBUG::2012-11-07 10:24:54,654::task::1172::TaskManager.Task::(prepare) Task=`00dfd6ae-8ce2-46df-b68e-f0317f1a2034`::finished: {}
>Thread-513::DEBUG::2012-11-07 10:24:54,655::task::588::TaskManager.Task::(_updateState) Task=`00dfd6ae-8ce2-46df-b68e-f0317f1a2034`::moving from state preparing -> state finished
>Thread-513::DEBUG::2012-11-07 10:24:54,655::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-513::DEBUG::2012-11-07 10:24:54,655::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-513::DEBUG::2012-11-07 10:24:54,655::task::978::TaskManager.Task::(_decref) Task=`00dfd6ae-8ce2-46df-b68e-f0317f1a2034`::ref 0 aborting False
>Thread-519::DEBUG::2012-11-07 10:25:04,833::task::588::TaskManager.Task::(_updateState) Task=`949e70d4-4e45-4d95-88ec-b3a81e4b1f1c`::moving from state init -> state preparing
>Thread-519::INFO::2012-11-07 10:25:04,836::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-519::INFO::2012-11-07 10:25:04,837::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-519::DEBUG::2012-11-07 10:25:04,837::task::1172::TaskManager.Task::(prepare) Task=`949e70d4-4e45-4d95-88ec-b3a81e4b1f1c`::finished: {}
>Thread-519::DEBUG::2012-11-07 10:25:04,837::task::588::TaskManager.Task::(_updateState) Task=`949e70d4-4e45-4d95-88ec-b3a81e4b1f1c`::moving from state preparing -> state finished
>Thread-519::DEBUG::2012-11-07 10:25:04,837::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-519::DEBUG::2012-11-07 10:25:04,838::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-519::DEBUG::2012-11-07 10:25:04,838::task::978::TaskManager.Task::(_decref) Task=`949e70d4-4e45-4d95-88ec-b3a81e4b1f1c`::ref 0 aborting False
>Thread-525::DEBUG::2012-11-07 10:25:14,998::task::588::TaskManager.Task::(_updateState) Task=`eebcbdc4-e92a-4147-ad8d-604dfb4589aa`::moving from state init -> state preparing
>Thread-525::INFO::2012-11-07 10:25:14,998::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-525::INFO::2012-11-07 10:25:14,998::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-525::DEBUG::2012-11-07 10:25:14,999::task::1172::TaskManager.Task::(prepare) Task=`eebcbdc4-e92a-4147-ad8d-604dfb4589aa`::finished: {}
>Thread-525::DEBUG::2012-11-07 10:25:14,999::task::588::TaskManager.Task::(_updateState) Task=`eebcbdc4-e92a-4147-ad8d-604dfb4589aa`::moving from state preparing -> state finished
>Thread-525::DEBUG::2012-11-07 10:25:14,999::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-525::DEBUG::2012-11-07 10:25:14,999::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-525::DEBUG::2012-11-07 10:25:14,999::task::978::TaskManager.Task::(_decref) Task=`eebcbdc4-e92a-4147-ad8d-604dfb4589aa`::ref 0 aborting False
>Thread-531::DEBUG::2012-11-07 10:25:25,178::task::588::TaskManager.Task::(_updateState) Task=`7ceb676a-3767-4612-ae24-3da8331f3845`::moving from state init -> state preparing
>Thread-531::INFO::2012-11-07 10:25:25,178::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-531::INFO::2012-11-07 10:25:25,178::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-531::DEBUG::2012-11-07 10:25:25,179::task::1172::TaskManager.Task::(prepare) Task=`7ceb676a-3767-4612-ae24-3da8331f3845`::finished: {}
>Thread-531::DEBUG::2012-11-07 10:25:25,179::task::588::TaskManager.Task::(_updateState) Task=`7ceb676a-3767-4612-ae24-3da8331f3845`::moving from state preparing -> state finished
>Thread-531::DEBUG::2012-11-07 10:25:25,179::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-531::DEBUG::2012-11-07 10:25:25,179::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-531::DEBUG::2012-11-07 10:25:25,179::task::978::TaskManager.Task::(_decref) Task=`7ceb676a-3767-4612-ae24-3da8331f3845`::ref 0 aborting False
>Thread-537::DEBUG::2012-11-07 10:25:35,341::task::588::TaskManager.Task::(_updateState) Task=`18afc4b4-a41a-4455-b683-ec6b544e552f`::moving from state init -> state preparing
>Thread-537::INFO::2012-11-07 10:25:35,341::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-537::INFO::2012-11-07 10:25:35,341::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-537::DEBUG::2012-11-07 10:25:35,341::task::1172::TaskManager.Task::(prepare) Task=`18afc4b4-a41a-4455-b683-ec6b544e552f`::finished: {}
>Thread-537::DEBUG::2012-11-07 10:25:35,342::task::588::TaskManager.Task::(_updateState) Task=`18afc4b4-a41a-4455-b683-ec6b544e552f`::moving from state preparing -> state finished
>Thread-537::DEBUG::2012-11-07 10:25:35,342::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-537::DEBUG::2012-11-07 10:25:35,342::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-537::DEBUG::2012-11-07 10:25:35,342::task::978::TaskManager.Task::(_decref) Task=`18afc4b4-a41a-4455-b683-ec6b544e552f`::ref 0 aborting False
>Thread-543::DEBUG::2012-11-07 10:25:45,502::task::588::TaskManager.Task::(_updateState) Task=`b4ec420b-93e2-43f4-8172-c0d5f6498d32`::moving from state init -> state preparing
>Thread-543::INFO::2012-11-07 10:25:45,503::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-543::INFO::2012-11-07 10:25:45,503::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-543::DEBUG::2012-11-07 10:25:45,503::task::1172::TaskManager.Task::(prepare) Task=`b4ec420b-93e2-43f4-8172-c0d5f6498d32`::finished: {}
>Thread-543::DEBUG::2012-11-07 10:25:45,503::task::588::TaskManager.Task::(_updateState) Task=`b4ec420b-93e2-43f4-8172-c0d5f6498d32`::moving from state preparing -> state finished
>Thread-543::DEBUG::2012-11-07 10:25:45,503::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-543::DEBUG::2012-11-07 10:25:45,504::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-543::DEBUG::2012-11-07 10:25:45,504::task::978::TaskManager.Task::(_decref) Task=`b4ec420b-93e2-43f4-8172-c0d5f6498d32`::ref 0 aborting False
>Thread-549::DEBUG::2012-11-07 10:25:55,664::task::588::TaskManager.Task::(_updateState) Task=`817359fc-8210-48ed-860e-36bf3d6839c1`::moving from state init -> state preparing
>Thread-549::INFO::2012-11-07 10:25:55,664::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-549::INFO::2012-11-07 10:25:55,664::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-549::DEBUG::2012-11-07 10:25:55,664::task::1172::TaskManager.Task::(prepare) Task=`817359fc-8210-48ed-860e-36bf3d6839c1`::finished: {}
>Thread-549::DEBUG::2012-11-07 10:25:55,665::task::588::TaskManager.Task::(_updateState) Task=`817359fc-8210-48ed-860e-36bf3d6839c1`::moving from state preparing -> state finished
>Thread-549::DEBUG::2012-11-07 10:25:55,665::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-549::DEBUG::2012-11-07 10:25:55,665::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-549::DEBUG::2012-11-07 10:25:55,665::task::978::TaskManager.Task::(_decref) Task=`817359fc-8210-48ed-860e-36bf3d6839c1`::ref 0 aborting False
>Thread-555::DEBUG::2012-11-07 10:26:05,831::task::588::TaskManager.Task::(_updateState) Task=`3e1696fa-f872-466b-99d0-cd5c91aea711`::moving from state init -> state preparing
>Thread-555::INFO::2012-11-07 10:26:05,831::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-555::INFO::2012-11-07 10:26:05,831::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-555::DEBUG::2012-11-07 10:26:05,832::task::1172::TaskManager.Task::(prepare) Task=`3e1696fa-f872-466b-99d0-cd5c91aea711`::finished: {}
>Thread-555::DEBUG::2012-11-07 10:26:05,832::task::588::TaskManager.Task::(_updateState) Task=`3e1696fa-f872-466b-99d0-cd5c91aea711`::moving from state preparing -> state finished
>Thread-555::DEBUG::2012-11-07 10:26:05,832::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-555::DEBUG::2012-11-07 10:26:05,832::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-555::DEBUG::2012-11-07 10:26:05,833::task::978::TaskManager.Task::(_decref) Task=`3e1696fa-f872-466b-99d0-cd5c91aea711`::ref 0 aborting False
>Thread-561::DEBUG::2012-11-07 10:26:15,984::task::588::TaskManager.Task::(_updateState) Task=`fa31e1fa-cacc-47b5-8c51-f4d2e50e6567`::moving from state init -> state preparing
>Thread-561::INFO::2012-11-07 10:26:15,985::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-561::INFO::2012-11-07 10:26:15,985::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-561::DEBUG::2012-11-07 10:26:15,985::task::1172::TaskManager.Task::(prepare) Task=`fa31e1fa-cacc-47b5-8c51-f4d2e50e6567`::finished: {}
>Thread-561::DEBUG::2012-11-07 10:26:15,985::task::588::TaskManager.Task::(_updateState) Task=`fa31e1fa-cacc-47b5-8c51-f4d2e50e6567`::moving from state preparing -> state finished
>Thread-561::DEBUG::2012-11-07 10:26:15,986::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-561::DEBUG::2012-11-07 10:26:15,986::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-561::DEBUG::2012-11-07 10:26:15,986::task::978::TaskManager.Task::(_decref) Task=`fa31e1fa-cacc-47b5-8c51-f4d2e50e6567`::ref 0 aborting False
>Thread-567::DEBUG::2012-11-07 10:26:26,157::task::588::TaskManager.Task::(_updateState) Task=`14a0dffb-1722-43b3-9d29-02653cbbd271`::moving from state init -> state preparing
>Thread-567::INFO::2012-11-07 10:26:26,157::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-567::INFO::2012-11-07 10:26:26,157::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-567::DEBUG::2012-11-07 10:26:26,157::task::1172::TaskManager.Task::(prepare) Task=`14a0dffb-1722-43b3-9d29-02653cbbd271`::finished: {}
>Thread-567::DEBUG::2012-11-07 10:26:26,158::task::588::TaskManager.Task::(_updateState) Task=`14a0dffb-1722-43b3-9d29-02653cbbd271`::moving from state preparing -> state finished
>Thread-567::DEBUG::2012-11-07 10:26:26,158::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-567::DEBUG::2012-11-07 10:26:26,158::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-567::DEBUG::2012-11-07 10:26:26,158::task::978::TaskManager.Task::(_decref) Task=`14a0dffb-1722-43b3-9d29-02653cbbd271`::ref 0 aborting False
>Thread-573::DEBUG::2012-11-07 10:26:36,317::task::588::TaskManager.Task::(_updateState) Task=`83b70a0c-ddfb-4bb5-a8dd-ed47eb8d767e`::moving from state init -> state preparing
>Thread-573::INFO::2012-11-07 10:26:36,317::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-573::INFO::2012-11-07 10:26:36,318::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-573::DEBUG::2012-11-07 10:26:36,318::task::1172::TaskManager.Task::(prepare) Task=`83b70a0c-ddfb-4bb5-a8dd-ed47eb8d767e`::finished: {}
>Thread-573::DEBUG::2012-11-07 10:26:36,318::task::588::TaskManager.Task::(_updateState) Task=`83b70a0c-ddfb-4bb5-a8dd-ed47eb8d767e`::moving from state preparing -> state finished
>Thread-573::DEBUG::2012-11-07 10:26:36,318::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-573::DEBUG::2012-11-07 10:26:36,318::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-573::DEBUG::2012-11-07 10:26:36,319::task::978::TaskManager.Task::(_decref) Task=`83b70a0c-ddfb-4bb5-a8dd-ed47eb8d767e`::ref 0 aborting False
>Thread-579::DEBUG::2012-11-07 10:26:46,504::task::588::TaskManager.Task::(_updateState) Task=`aeaeb31d-d9fc-4dc1-a064-544a6554bdbf`::moving from state init -> state preparing
>Thread-579::INFO::2012-11-07 10:26:46,504::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-579::INFO::2012-11-07 10:26:46,504::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-579::DEBUG::2012-11-07 10:26:46,504::task::1172::TaskManager.Task::(prepare) Task=`aeaeb31d-d9fc-4dc1-a064-544a6554bdbf`::finished: {}
>Thread-579::DEBUG::2012-11-07 10:26:46,505::task::588::TaskManager.Task::(_updateState) Task=`aeaeb31d-d9fc-4dc1-a064-544a6554bdbf`::moving from state preparing -> state finished
>Thread-579::DEBUG::2012-11-07 10:26:46,505::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-579::DEBUG::2012-11-07 10:26:46,505::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-579::DEBUG::2012-11-07 10:26:46,505::task::978::TaskManager.Task::(_decref) Task=`aeaeb31d-d9fc-4dc1-a064-544a6554bdbf`::ref 0 aborting False
>Thread-585::DEBUG::2012-11-07 10:26:56,678::task::588::TaskManager.Task::(_updateState) Task=`95866d93-4e37-4c8b-98ee-73bc2a3a1f6e`::moving from state init -> state preparing
>Thread-585::INFO::2012-11-07 10:26:56,678::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-585::INFO::2012-11-07 10:26:56,678::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-585::DEBUG::2012-11-07 10:26:56,678::task::1172::TaskManager.Task::(prepare) Task=`95866d93-4e37-4c8b-98ee-73bc2a3a1f6e`::finished: {}
>Thread-585::DEBUG::2012-11-07 10:26:56,679::task::588::TaskManager.Task::(_updateState) Task=`95866d93-4e37-4c8b-98ee-73bc2a3a1f6e`::moving from state preparing -> state finished
>Thread-585::DEBUG::2012-11-07 10:26:56,679::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-585::DEBUG::2012-11-07 10:26:56,679::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-585::DEBUG::2012-11-07 10:26:56,679::task::978::TaskManager.Task::(_decref) Task=`95866d93-4e37-4c8b-98ee-73bc2a3a1f6e`::ref 0 aborting False
>Thread-591::DEBUG::2012-11-07 10:27:06,834::task::588::TaskManager.Task::(_updateState) Task=`014f3e65-f98b-4bd4-ba92-c43f3464c45a`::moving from state init -> state preparing
>Thread-591::INFO::2012-11-07 10:27:06,834::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-591::INFO::2012-11-07 10:27:06,835::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-591::DEBUG::2012-11-07 10:27:06,835::task::1172::TaskManager.Task::(prepare) Task=`014f3e65-f98b-4bd4-ba92-c43f3464c45a`::finished: {}
>Thread-591::DEBUG::2012-11-07 10:27:06,835::task::588::TaskManager.Task::(_updateState) Task=`014f3e65-f98b-4bd4-ba92-c43f3464c45a`::moving from state preparing -> state finished
>Thread-591::DEBUG::2012-11-07 10:27:06,835::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-591::DEBUG::2012-11-07 10:27:06,835::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-591::DEBUG::2012-11-07 10:27:06,836::task::978::TaskManager.Task::(_decref) Task=`014f3e65-f98b-4bd4-ba92-c43f3464c45a`::ref 0 aborting False
>Thread-597::DEBUG::2012-11-07 10:27:16,995::task::588::TaskManager.Task::(_updateState) Task=`6e7cd7dd-bcc1-43be-9971-a9bfa446d55d`::moving from state init -> state preparing
>Thread-597::INFO::2012-11-07 10:27:16,995::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-597::INFO::2012-11-07 10:27:16,995::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-597::DEBUG::2012-11-07 10:27:16,996::task::1172::TaskManager.Task::(prepare) Task=`6e7cd7dd-bcc1-43be-9971-a9bfa446d55d`::finished: {}
>Thread-597::DEBUG::2012-11-07 10:27:16,996::task::588::TaskManager.Task::(_updateState) Task=`6e7cd7dd-bcc1-43be-9971-a9bfa446d55d`::moving from state preparing -> state finished
>Thread-597::DEBUG::2012-11-07 10:27:16,996::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-597::DEBUG::2012-11-07 10:27:16,996::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-597::DEBUG::2012-11-07 10:27:16,996::task::978::TaskManager.Task::(_decref) Task=`6e7cd7dd-bcc1-43be-9971-a9bfa446d55d`::ref 0 aborting False
>Thread-603::DEBUG::2012-11-07 10:27:27,157::task::588::TaskManager.Task::(_updateState) Task=`579dc832-046f-467e-a7ac-68789fe5fbff`::moving from state init -> state preparing
>Thread-603::INFO::2012-11-07 10:27:27,157::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-603::INFO::2012-11-07 10:27:27,157::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-603::DEBUG::2012-11-07 10:27:27,157::task::1172::TaskManager.Task::(prepare) Task=`579dc832-046f-467e-a7ac-68789fe5fbff`::finished: {}
>Thread-603::DEBUG::2012-11-07 10:27:27,158::task::588::TaskManager.Task::(_updateState) Task=`579dc832-046f-467e-a7ac-68789fe5fbff`::moving from state preparing -> state finished
>Thread-603::DEBUG::2012-11-07 10:27:27,158::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-603::DEBUG::2012-11-07 10:27:27,158::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-603::DEBUG::2012-11-07 10:27:27,158::task::978::TaskManager.Task::(_decref) Task=`579dc832-046f-467e-a7ac-68789fe5fbff`::ref 0 aborting False

Actions: View

Attachments on bug 869956: 633227 | 633228 | 639763 | 639764 | 680207