[Users] oVirt 3.1 VM is not responding

José Ferradeira jf at logicworks.pt
Wed Jan 30 16:44:00 UTC 2013


Hi, 

I'm testing oVirt 3.1, with one node 2.5.5-0.1fc17 and a NAS built with openfiler 2.99, working on a Gigabit net. 
I configured a NFS share on openfiler, and everything looks nice. 
I'm installing a Windows 2008 R2 as a VM, time to time i get a warning message: ! VM is not responding, but the windows installation keeps going, a bit slow. 

Any idea what is happening? 

Thanks 

Jose 

the vdsm.log : 

Thread-2405::DEBUG::2013-01-30 16:42:21,532::resourceManager::212::ResourceManager.Request::(grant) ResName=`Storage.7b44684c-5f34-11e2-beba-00138fbe3093`ReqID=`e9aefcf3-b4ed-4d7c-9d95-723f824c30ee`::Granted request 
Thread-2405::DEBUG::2013-01-30 16:42:21,532::task::817::TaskManager.Task::(resourceAcquired) Task=`c581aa06-4e50-41dd-a836-756827865cc6`::_resourcesAcquired: Storage.7b44684c-5f34-11e2-beba-00138fbe3093 (shared) 
Thread-2405::DEBUG::2013-01-30 16:42:21,532::task::978::TaskManager.Task::(_decref) Task=`c581aa06-4e50-41dd-a836-756827865cc6`::ref 1 aborting False 
Thread-2405::INFO::2013-01-30 16:42:21,664::logUtils::39::dispatcher::(wrapper) Run and protect: getStoragePoolInfo, Return response: {'info': {'spm_id': 1, 'master_uuid': 'cc060953-baf5-4b86-a66c-2f135b8fbb30', 'name': 'acloudDC', 'version': '0', 'domains': '39484795-666f-44b3-9cf5-21bbb02531ad:Active,0b6a87e8-53d9-46f3-bc81-20aab92f08aa:Active,cc060953-baf5-4b86-a66c-2f135b8fbb30:Active', 'pool_status': 'connected', 'isoprefix': '/rhev/data-center/7b44684c-5f34-11e2-beba-00138fbe3093/0b6a87e8-53d9-46f3-bc81-20aab92f08aa/images/11111111-1111-1111-1111-111111111111', 'type': 'NFS', 'master_ver': 10575, 'lver': 4}, 'dominfo': {'0b6a87e8-53d9-46f3-bc81-20aab92f08aa': {'status': 'Active', 'diskfree': '17986224128', 'alerts': [], 'disktotal': '23648534528'}, '39484795-666f-44b3-9cf5-21bbb02531ad': {'status': 'Active', 'diskfree': '467744063488', 'alerts': [], 'disktotal': '476676358144'}, 'cc060953-baf5-4b86-a66c-2f135b8fbb30': {'status': 'Active', 'diskfree': '313802031104', 'alerts': [], 'disktotal': '982927736832'}}} 
Thread-2405::DEBUG::2013-01-30 16:42:21,664::task::1172::TaskManager.Task::(prepare) Task=`c581aa06-4e50-41dd-a836-756827865cc6`::finished: {'info': {'spm_id': 1, 'master_uuid': 'cc060953-baf5-4b86-a66c-2f135b8fbb30', 'name': 'acloudDC', 'version': '0', 'domains': '39484795-666f-44b3-9cf5-21bbb02531ad:Active,0b6a87e8-53d9-46f3-bc81-20aab92f08aa:Active,cc060953-baf5-4b86-a66c-2f135b8fbb30:Active', 'pool_status': 'connected', 'isoprefix': '/rhev/data-center/7b44684c-5f34-11e2-beba-00138fbe3093/0b6a87e8-53d9-46f3-bc81-20aab92f08aa/images/11111111-1111-1111-1111-111111111111', 'type': 'NFS', 'master_ver': 10575, 'lver': 4}, 'dominfo': {'0b6a87e8-53d9-46f3-bc81-20aab92f08aa': {'status': 'Active', 'diskfree': '17986224128', 'alerts': [], 'disktotal': '23648534528'}, '39484795-666f-44b3-9cf5-21bbb02531ad': {'status': 'Active', 'diskfree': '467744063488', 'alerts': [], 'disktotal': '476676358144'}, 'cc060953-baf5-4b86-a66c-2f135b8fbb30': {'status': 'Active', 'diskfree': '313802031104', 'alerts': [], 'disktotal': '982927736832'}}} 
Thread-2405::DEBUG::2013-01-30 16:42:21,664::task::588::TaskManager.Task::(_updateState) Task=`c581aa06-4e50-41dd-a836-756827865cc6`::moving from state preparing -> state finished 
Thread-2405::DEBUG::2013-01-30 16:42:21,664::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {'Storage.7b44684c-5f34-11e2-beba-00138fbe3093': < ResourceRef 'Storage.7b44684c-5f34-11e2-beba-00138fbe3093', isValid: 'True' obj: 'None'>} 
Thread-2405::DEBUG::2013-01-30 16:42:21,664::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} 
Thread-2405::DEBUG::2013-01-30 16:42:21,665::resourceManager::538::ResourceManager::(releaseResource) Trying to release resource 'Storage.7b44684c-5f34-11e2-beba-00138fbe3093' 
Thread-2405::DEBUG::2013-01-30 16:42:21,665::resourceManager::553::ResourceManager::(releaseResource) Released resource 'Storage.7b44684c-5f34-11e2-beba-00138fbe3093' (0 active users) 
Thread-2405::DEBUG::2013-01-30 16:42:21,665::resourceManager::558::ResourceManager::(releaseResource) Resource 'Storage.7b44684c-5f34-11e2-beba-00138fbe3093' is free, finding out if anyone is waiting for it. 
Thread-2405::DEBUG::2013-01-30 16:42:21,665::resourceManager::565::ResourceManager::(releaseResource) No one is waiting for resource 'Storage.7b44684c-5f34-11e2-beba-00138fbe3093', Clearing records. 
Thread-2405::DEBUG::2013-01-30 16:42:21,665::task::978::TaskManager.Task::(_decref) Task=`c581aa06-4e50-41dd-a836-756827865cc6`::ref 0 aborting False 
Thread-2406::DEBUG::2013-01-30 16:42:23,304::task::588::TaskManager.Task::(_updateState) Task=`8926a671-3076-42e5-8521-f8ec6ba67748`::moving from state init -> state preparing 
Thread-2406::INFO::2013-01-30 16:42:23,304::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None) 
Thread-2406::INFO::2013-01-30 16:42:23,304::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {'39484795-666f-44b3-9cf5-21bbb02531ad': {'delay': '0.00179004669189', 'lastCheck': 1359564135.40165, 'code': 0, 'valid': True}, '0b6a87e8-53d9-46f3-bc81-20aab92f08aa': {'delay': '0.00332403182983', 'lastCheck': 1359564141.220177, 'code': 0, 'valid': True}, 'cc060953-baf5-4b86-a66c-2f135b8fbb30': {'delay': '0.00157809257507', 'lastCheck': 1359564143.248153, 'code': 0, 'valid': True}} 
Thread-2406::DEBUG::2013-01-30 16:42:23,305::task::1172::TaskManager.Task::(prepare) Task=`8926a671-3076-42e5-8521-f8ec6ba67748`::finished: {'39484795-666f-44b3-9cf5-21bbb02531ad': {'delay': '0.00179004669189', 'lastCheck': 1359564135.40165, 'code': 0, 'valid': True}, '0b6a87e8-53d9-46f3-bc81-20aab92f08aa': {'delay': '0.00332403182983', 'lastCheck': 1359564141.220177, 'code': 0, 'valid': True}, 'cc060953-baf5-4b86-a66c-2f135b8fbb30': {'delay': '0.00157809257507', 'lastCheck': 1359564143.248153, 'code': 0, 'valid': True}} 
Thread-2406::DEBUG::2013-01-30 16:42:23,305::task::588::TaskManager.Task::(_updateState) Task=`8926a671-3076-42e5-8521-f8ec6ba67748`::moving from state preparing -> state finished 
Thread-2406::DEBUG::2013-01-30 16:42:23,305::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} 
Thread-2406::DEBUG::2013-01-30 16:42:23,305::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} 
Thread-2406::DEBUG::2013-01-30 16:42:23,305::task::978::TaskManager.Task::(_decref) Task=`8926a671-3076-42e5-8521-f8ec6ba67748`::ref 0 aborting False 
Thread-2407::DEBUG::2013-01-30 16:42:23,368::libvirtvm::240::vm.Vm::(_getDiskStats) vmId=`35fb5c70-3ac8-4b5e-8e48-8cffe9fdcf53`::Disk hdc stats not available 
Thread-2407::DEBUG::2013-01-30 16:42:23,368::libvirtvm::240::vm.Vm::(_getDiskStats) vmId=`35fb5c70-3ac8-4b5e-8e48-8cffe9fdcf53`::Disk fda stats not available 
VM Channels Listener::DEBUG::2013-01-30 16:42:27,492::vmChannels::60::vds::(_handle_timeouts) Timeout on fileno 18. 
Thread-788::DEBUG::2013-01-30 16:42:28,907::task::588::TaskManager.Task::(_updateState) Task=`aec127b3-b02b-4bc3-b0e9-542c90b4c91e`::moving from state init -> state preparing 
Thread-788::INFO::2013-01-30 16:42:28,907::logUtils::37::dispatcher::(wrapper) Run and protect: getVolumeSize(sdUUID='39484795-666f-44b3-9cf5-21bbb02531ad', spUUID='7b44684c-5f34-11e2-beba-00138fbe3093', imgUUID='2ff4c743-6e5a-42af-8b99-131c8849bd3f', volUUID='7304b24f-5c4d-41b3-b12f-b916bc9f4299', options=None) 
Thread-788::DEBUG::2013-01-30 16:42:28,908::resourceManager::175::ResourceManager.Request::(__init__) ResName=`Storage.39484795-666f-44b3-9cf5-21bbb02531ad`ReqID=`9ba33a25-cdd2-4fc0-9f50-55a82178ad48`::Request was made in '/usr/share/vdsm/storage/resourceManager.py' line '485' at 'registerResource' 
Thread-788::DEBUG::2013-01-30 16:42:28,908::resourceManager::486::ResourceManager::(registerResource) Trying to register resource 'Storage.39484795-666f-44b3-9cf5-21bbb02531ad' for lock type 'shared' 
Thread-788::DEBUG::2013-01-30 16:42:28,908::resourceManager::528::ResourceManager::(registerResource) Resource 'Storage.39484795-666f-44b3-9cf5-21bbb02531ad' is free. Now locking as 'shared' (1 active user) 
Thread-788::DEBUG::2013-01-30 16:42:28,908::resourceManager::212::ResourceManager.Request::(grant) ResName=`Storage.39484795-666f-44b3-9cf5-21bbb02531ad`ReqID=`9ba33a25-cdd2-4fc0-9f50-55a82178ad48`::Granted request 
Thread-788::DEBUG::2013-01-30 16:42:28,908::task::817::TaskManager.Task::(resourceAcquired) Task=`aec127b3-b02b-4bc3-b0e9-542c90b4c91e`::_resourcesAcquired: Storage.39484795-666f-44b3-9cf5-21bbb02531ad (shared) 
Thread-788::DEBUG::2013-01-30 16:42:28,909::task::978::TaskManager.Task::(_decref) Task=`aec127b3-b02b-4bc3-b0e9-542c90b4c91e`::ref 1 aborting False 
Thread-788::DEBUG::2013-01-30 16:42:28,911::fileVolume::535::Storage.Volume::(validateVolumePath) validate path for 7304b24f-5c4d-41b3-b12f-b916bc9f4299 
Thread-788::DEBUG::2013-01-30 16:42:28,913::fileVolume::535::Storage.Volume::(validateVolumePath) validate path for 7304b24f-5c4d-41b3-b12f-b916bc9f4299 
Thread-788::INFO::2013-01-30 16:42:28,914::logUtils::39::dispatcher::(wrapper) Run and protect: getVolumeSize, Return response: {'truesize': '3256225792', 'apparentsize': '107374182400'} 
Thread-788::DEBUG::2013-01-30 16:42:28,914::task::1172::TaskManager.Task::(prepare) Task=`aec127b3-b02b-4bc3-b0e9-542c90b4c91e`::finished: {'truesize': '3256225792', 'apparentsize': '107374182400'} 
Thread-788::DEBUG::2013-01-30 16:42:28,915::task::588::TaskManager.Task::(_updateState) Task=`aec127b3-b02b-4bc3-b0e9-542c90b4c91e`::moving from state preparing -> state finished 
Thread-788::DEBUG::2013-01-30 16:42:28,915::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {'Storage.39484795-666f-44b3-9cf5-21bbb02531ad': < ResourceRef 'Storage.39484795-666f-44b3-9cf5-21bbb02531ad', isValid: 'True' obj: 'None'>} 
Thread-788::DEBUG::2013-01-30 16:42:28,915::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} 
Thread-788::DEBUG::2013-01-30 16:42:28,915::resourceManager::538::ResourceManager::(releaseResource) Trying to release resource 'Storage.39484795-666f-44b3-9cf5-21bbb02531ad' 
Thread-788::DEBUG::2013-01-30 16:42:28,915::resourceManager::553::ResourceManager::(releaseResource) Released resource 'Storage.39484795-666f-44b3-9cf5-21bbb02531ad' (0 active users) 
Thread-788::DEBUG::2013-01-30 16:42:28,915::resourceManager::558::ResourceManager::(releaseResource) Resource 'Storage.39484795-666f-44b3-9cf5-21bbb02531ad' is free, finding out if anyone is waiting for it. 
Thread-788::DEBUG::2013-01-30 16:42:28,915::resourceManager::565::ResourceManager::(releaseResource) No one is waiting for resource 'Storage.39484795-666f-44b3-9cf5-21bbb02531ad', Clearing records. 
Thread-788::DEBUG::2013-01-30 16:42:28,915::task::978::TaskManager.Task::(_decref) Task=`aec127b3-b02b-4bc3-b0e9-542c90b4c91e`::ref 0 aborting False 
VM Channels Listener::DEBUG::2013-01-30 16:42:29,494::vmChannels::60::vds::(_handle_timeouts) Timeout on fileno 322. 
Thread-2412::DEBUG::2013-01-30 16:42:31,843::BindingXMLRPC::156::vds::(wrapper) [192.168.5.180] 
Thread-2412::DEBUG::2013-01-30 16:42:31,843::task::588::TaskManager.Task::(_updateState) Task=`4095451f-5992-4276-8bbc-3cbd5c192905`::moving from state init -> state preparing 
Thread-2412::INFO::2013-01-30 16:42:31,844::logUtils::37::dispatcher::(wrapper) Run and protect: getSpmStatus(spUUID='7b44684c-5f34-11e2-beba-00138fbe3093', options=None) 
Thread-2412::INFO::2013-01-30 16:42:31,844::logUtils::39::dispatcher::(wrapper) Run and protect: getSpmStatus, Return response: {'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4}} 
Thread-2412::DEBUG::2013-01-30 16:42:31,844::task::1172::TaskManager.Task::(prepare) Task=`4095451f-5992-4276-8bbc-3cbd5c192905`::finished: {'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 4}} 
Thread-2412::DEBUG::2013-01-30 16:42:31,844::task::588::TaskManager.Task::(_updateState) Task=`4095451f-5992-4276-8bbc-3cbd5c192905`::moving from state preparing -> state finished 
Thread-2412::DEBUG::2013-01-30 16:42:31,844::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} 
Thread-2412::DEBUG::2013-01-30 16:42:31,844::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} 
Thread-2412::DEBUG::2013-01-30 16:42:31,844::task::978::TaskManager.Task::(_decref) Task=`4095451f-5992-4276-8bbc-3cbd5c192905`::ref 0 aborting False 
Thread-2413::DEBUG::2013-01-30 16:42:31,936::BindingXMLRPC::156::vds::(wrapper) [192.168.5.180] 
Thread-2413::DEBUG::2013-01-30 16:42:31,937::task::588::TaskManager.Task::(_updateState) Task=`42cacf2e-311a-4ca4-8158-6b3dd8f00a94`::moving from state init -> state preparing 
Thread-2413::INFO::2013-01-30 16:42:31,937::logUtils::37::dispatcher::(wrapper) Run and protect: getStoragePoolInfo(spUUID='7b44684c-5f34-11e2-beba-00138fbe3093', options=None) 
Thread-2413::DEBUG::2013-01-30 16:42:31,937::resourceManager::175::ResourceManager.Request::(__init__) ResName=`Storage.7b44684c-5f34-11e2-beba-00138fbe3093`ReqID=`e72c6606-db58-41d0-8072-c4fff9e0e417`::Request was made in '/usr/share/vdsm/storage/resourceManager.py' line '485' at 'registerResource' 
Thread-2413::DEBUG::2013-01-30 16:42:31,937::resourceManager::486::ResourceManager::(registerResource) Trying to register resource 'Storage.7b44684c-5f34-11e2-beba-00138fbe3093' for lock type 'shared' 
Thread-2413::DEBUG::2013-01-30 16:42:31,937::resourceManager::528::ResourceManager::(registerResource) Resource 'Storage.7b44684c-5f34-11e2-beba-00138fbe3093' is free. Now locking as 'shared' (1 active user) 
Thread-2413::DEBUG::2013-01-30 16:42:31,938::resourceManager::212::ResourceManager.Request::(grant) ResName=`Storage.7b44684c-5f34-11e2-beba-00138fbe3093`ReqID=`e72c6606-db58-41d0-8072-c4fff9e0e417`::Granted request 
Thread-2413::DEBUG::2013-01-30 16:42:31,939::task::817::TaskManager.Task::(resourceAcquired) Task=`42cacf2e-311a-4ca4-8158-6b3dd8f00a94`::_resourcesAcquired: Storage.7b44684c-5f34-11e2-beba-00138fbe3093 (shared) 
Thread-2413::DEBUG::2013-01-30 16:42:31,939::task::978::TaskManager.Task::(_decref) Task=`42cacf2e-311a-4ca4-8158-6b3dd8f00a94`::ref 1 aborting False 
Thread-2413::INFO::2013-01-30 16:42:32,073::logUtils::39::dispatcher::(wrapper) Run and protect: getStoragePoolInfo, Return response: {'info': {'spm_id': 1, 'master_uuid': 'cc060953-baf5-4b86-a66c-2f135b8fbb30', 'name': 'acloudDC', 'version': '0', 'domains': '39484795-666f-44b3-9cf5-21bbb02531ad:Active,0b6a87e8-53d9-46f3-bc81-20aab92f08aa:Active,cc060953-baf5-4b86-a66c-2f135b8fbb30:Active', 'pool_status': 'connected', 'isoprefix': '/rhev/data-center/7b44684c-5f34-11e2-beba-00138fbe3093/0b6a87e8-53d9-46f3-bc81-20aab92f08aa/images/11111111-1111-1111-1111-111111111111', 'type': 'NFS', 'master_ver': 10575, 'lver': 4}, 'dominfo': {'0b6a87e8-53d9-46f3-bc81-20aab92f08aa': {'status': 'Active', 'diskfree': '17986224128', 'alerts': [], 'disktotal': '23648534528'}, '39484795-666f-44b3-9cf5-21bbb02531ad': {'status': 'Active', 'diskfree': '467744063488', 'alerts': [], 'disktotal': '476676358144'}, 'cc060953-baf5-4b86-a66c-2f135b8fbb30': {'status': 'Active', 'diskfree': '313801244672', 'alerts': [], 'disktotal': '982927736832'}}} 
Thread-2413::DEBUG::2013-01-30 16:42:32,073::task::1172::TaskManager.Task::(prepare) Task=`42cacf2e-311a-4ca4-8158-6b3dd8f00a94`::finished: {'info': {'spm_id': 1, 'master_uuid': 'cc060953-baf5-4b86-a66c-2f135b8fbb30', 'name': 'acloudDC', 'version': '0', 'domains': '39484795-666f-44b3-9cf5-21bbb02531ad:Active,0b6a87e8-53d9-46f3-bc81-20aab92f08aa:Active,cc060953-baf5-4b86-a66c-2f135b8fbb30:Active', 'pool_status': 'connected', 'isoprefix': '/rhev/data-center/7b44684c-5f34-11e2-beba-00138fbe3093/0b6a87e8-53d9-46f3-bc81-20aab92f08aa/images/11111111-1111-1111-1111-111111111111', 'type': 'NFS', 'master_ver': 10575, 'lver': 4}, 'dominfo': {'0b6a87e8-53d9-46f3-bc81-20aab92f08aa': {'status': 'Active', 'diskfree': '17986224128', 'alerts': [], 'disktotal': '23648534528'}, '39484795-666f-44b3-9cf5-21bbb02531ad': {'status': 'Active', 'diskfree': '467744063488', 'alerts': [], 'disktotal': '476676358144'}, 'cc060953-baf5-4b86-a66c-2f135b8fbb30': {'status': 'Active', 'diskfree': '313801244672', 'alerts': [], 'disktotal': '982927736832'}}} 
Thread-2413::DEBUG::2013-01-30 16:42:32,073::task::588::TaskManager.Task::(_updateState) Task=`42cacf2e-311a-4ca4-8158-6b3dd8f00a94`::moving from state preparing -> state finished 
Thread-2413::DEBUG::2013-01-30 16:42:32,073::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {'Storage.7b44684c-5f34-11e2-beba-00138fbe3093': < ResourceRef 'Storage.7b44684c-5f34-11e2-beba-00138fbe3093', isValid: 'True' obj: 'None'>} 
Thread-2413::DEBUG::2013-01-30 16:42:32,073::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} 
Thread-2413::DEBUG::2013-01-30 16:42:32,074::resourceManager::538::ResourceManager::(releaseResource) Trying to release resource 'Storage.7b44684c-5f34-11e2-beba-00138fbe3093' 
Thread-2413::DEBUG::2013-01-30 16:42:32,074::resourceManager::553::ResourceManager::(releaseResource) Released resource 'Storage.7b44684c-5f34-11e2-beba-00138fbe3093' (0 active users) 
Thread-2413::DEBUG::2013-01-30 16:42:32,074::resourceManager::558::ResourceManager::(releaseResource) Resource 'Storage.7b44684c-5f34-11e2-beba-00138fbe3093' is free, finding out if anyone is waiting for it. 
Thread-2413::DEBUG::2013-01-30 16:42:32,074::resourceManager::565::ResourceManager::(releaseResource) No one is waiting for resource 'Storage.7b44684c-5f34-11e2-beba-00138fbe3093', Clearing records. 
Thread-2413::DEBUG::2013-01-30 16:42:32,075::task::978::TaskManager.Task::(_decref) Task=`42cacf2e-311a-4ca4-8158-6b3dd8f00a94`::ref 0 aborting False 
Thread-2414::DEBUG::2013-01-30 16:42:33,894::task::588::TaskManager.Task::(_updateState) Task=`e5af4772-7be5-43c0-a907-8a3f3fe0cebd`::moving from state init -> state preparing 
Thread-2414::INFO::2013-01-30 16:42:33,894::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None) 
Thread-2414::INFO::2013-01-30 16:42:33,894::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {'39484795-666f-44b3-9cf5-21bbb02531ad': {'delay': '0.00196099281311', 'lastCheck': 1359564145.53994, 'code': 0, 'valid': True}, '0b6a87e8-53d9-46f3-bc81-20aab92f08aa': {'delay': '0.00284719467163', 'lastCheck': 1359564151.359623, 'code': 0, 'valid': True}, 'cc060953-baf5-4b86-a66c-2f135b8fbb30': {'delay': '0.0015709400177', 'lastCheck': 1359564153.38349, 'code': 0, 'valid': True}} 
Thread-2414::DEBUG::2013-01-30 16:42:33,895::task::1172::TaskManager.Task::(prepare) Task=`e5af4772-7be5-43c0-a907-8a3f3fe0cebd`::finished: {'39484795-666f-44b3-9cf5-21bbb02531ad': {'delay': '0.00196099281311', 'lastCheck': 1359564145.53994, 'code': 0, 'valid': True}, '0b6a87e8-53d9-46f3-bc81-20aab92f08aa': {'delay': '0.00284719467163', 'lastCheck': 1359564151.359623, 'code': 0, 'valid': True}, 'cc060953-baf5-4b86-a66c-2f135b8fbb30': {'delay': '0.0015709400177', 'lastCheck': 1359564153.38349, 'code': 0, 'valid': True}} 
Thread-2414::DEBUG::2013-01-30 16:42:33,895::task::588::TaskManager.Task::(_updateState) Task=`e5af4772-7be5-43c0-a907-8a3f3fe0cebd`::moving from state preparing -> state finished 
Thread-2414::DEBUG::2013-01-30 16:42:33,895::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {} 
Thread-2414::DEBUG::2013-01-30 16:42:33,895::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {} 
Thread-2414::DEBUG::2013-01-30 16:42:33,895::task::978::TaskManager.Task::(_decref) Task=`e5af4772-7be5-43c0-a907-8a3f3fe0cebd`::ref 0 aborting False 
Thread-2415::DEBUG::2013-01-30 16:42:33,996::libvirtvm::240::vm.Vm::(_getDiskStats) vmId=`35fb5c70-3ac8-4b5e-8e48-8cffe9fdcf53`::Disk hdc stats not available 
Thread-2415::DEBUG::2013-01-30 16:42:33,997::libvirtvm::240::vm.Vm::(_getDiskStats) vmId=`35fb5c70-3ac8-4b5e-8e48-8cffe9fdcf53`::Disk fda stats not available 
Thread-424::DEBUG::2013-01-30 16:42:34,508::task::588::TaskManager.Task::(_updateState) Task=`413cbd4e-2ddc-4a21-9526-39e970dc48d3`::moving from state init -> state preparing 
Thread-424::INFO::2013-01-30 16:42:34,509::logUtils::37::dispatcher::(wrapper) Run and protect: getVolumeSize(sdUUID='39484795-666f-44b3-9cf5-21bbb02531ad', spUUID='7b44684c-5f34-11e2-beba-00138fbe3093', imgUUID='c7524cbd-5f92-41ba-a9e9-8724dd2c4c11', volUUID='5904f9d7-09c3-404d-8887-71a32ff96735', options=None) 
Thread-424::DEBUG::2013-01-30 16:42:34,509::resourceManager::175::ResourceManager.Request::(__init__) ResName=`Storage.39484795-666f-44b3-9cf5-21bbb02531ad`ReqID=`e4e2d280-6941-469f-be31-ac06bfb73f99`::Request was made in '/usr/share/vdsm/storage/resourceManager.py' line '485' at 'registerResource' 
Thread-424::DEBUG::2013-01-30 16:42:34,509::resourceManager::486::ResourceManager::(registerResource) Trying to register resource 'Storage.39484795-666f-44b3-9cf5-21bbb02531ad' for lock type 'shared' 
Thread-424::DEBUG::2013-01-30 16:42:34,509::resourceManager::528::ResourceManager::(registerResource) Resource 'Storage.39484795-666f-44b3-9cf5-21bbb02531ad' is free. Now locking as 'shared' (1 active user) 
Thread-424::DEBUG::2013-01-30 16:42:34,509::resourceManager::212::ResourceManager.Request::(grant) ResName=`Storage.39484795-666f-44b3-9cf5-21bbb02531ad`ReqID=`e4e2d280-6941-469f-be31-ac06bfb73f99`::Granted request 
Thread-424::DEBUG::2013-01-30 16:42:34,509::task::817::TaskManager.Task::(resourceAcquired) Task=`413cbd4e-2ddc-4a21-9526-39e970dc48d3`::_resourcesAcquired: Storage.39484795-666f-44b3-9cf5-21bbb02531ad (shared) 
Thread-424::DEBUG::2013-01-30 16:42:34,510::task::978::TaskManager.Task::(_decref) Task=`413cbd4e-2ddc-4a21-9526-39e970dc48d3`::ref 1 aborting False 
Thread-424::DEBUG::2013-01-30 16:42:34,511::fileVolume::535::Storage.Volume::(validateVolumePath) validate path for 5904f9d7-09c3-404d-8887-71a32ff96735 



--------------------------------------------- 
Logicworks Tecnologias de Informática 
http://www.logicworks.pt 
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.ovirt.org/pipermail/users/attachments/20130130/501ccdaf/attachment-0001.html>


More information about the Users mailing list