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
_______________________________________________
Users mailing list
[email protected]
http://lists.ovirt.org/mailman/listinfo/users
