Attachment 649136 Details for Bug 878784 – vdsm-log

vdsm-log

vdsm.log (text/plain), 9.21 KB, created by yuping zhang on 2012-11-21 10:27:16 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: yuping zhang

Created: 2012-11-21 10:27:16 UTC

Size: 9.21 KB

patch

obsolete

>Thread-12173::DEBUG::2012-11-21 09:49:10,649::task::588::TaskManager.Task::(_updateState) Task=`f485b3a1-a9d5-4738-a4fc-2c696cfb593d`::moving from state init -> state preparing
>Thread-12173::INFO::2012-11-21 09:49:10,650::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-12173::INFO::2012-11-21 09:49:10,650::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {'08552000-7ab1-4a4a-81f7-39fdcf785f68': {'delay': '0.00135898590088', 'lastCheck': '9.4', 'code': 0, 'valid': True}, '6f9431a8-e3fa-4081-af58-a34f532692d7': {'delay': '0.00125098228455', 'lastCheck': '5.8', 'code': 0, 'valid': True}}
>Thread-12173::DEBUG::2012-11-21 09:49:10,650::task::1172::TaskManager.Task::(prepare) Task=`f485b3a1-a9d5-4738-a4fc-2c696cfb593d`::finished: {'08552000-7ab1-4a4a-81f7-39fdcf785f68': {'delay': '0.00135898590088', 'lastCheck': '9.4', 'code': 0, 'valid': True}, '6f9431a8-e3fa-4081-af58-a34f532692d7': {'delay': '0.00125098228455', 'lastCheck': '5.8', 'code': 0, 'valid': True}}
>Thread-12173::DEBUG::2012-11-21 09:49:10,651::task::588::TaskManager.Task::(_updateState) Task=`f485b3a1-a9d5-4738-a4fc-2c696cfb593d`::moving from state preparing -> state finished
>Thread-12173::DEBUG::2012-11-21 09:49:10,651::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-12173::DEBUG::2012-11-21 09:49:10,651::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-12173::DEBUG::2012-11-21 09:49:10,651::task::978::TaskManager.Task::(_decref) Task=`f485b3a1-a9d5-4738-a4fc-2c696cfb593d`::ref 0 aborting False
>Thread-12178::DEBUG::2012-11-21 09:49:17,692::BindingXMLRPC::171::vds::(wrapper) [10.66.72.38]
>Thread-12178::DEBUG::2012-11-21 09:49:17,693::task::588::TaskManager.Task::(_updateState) Task=`23838477-e087-4922-96f9-3ebbbf7fe909`::moving from state init -> state preparing
>Thread-12178::INFO::2012-11-21 09:49:17,693::logUtils::37::dispatcher::(wrapper) Run and protect: getSpmStatus(spUUID='794be62d-1be7-4881-a8bb-a95d77853247', options=None)
>Thread-12178::INFO::2012-11-21 09:49:17,694::logUtils::39::dispatcher::(wrapper) Run and protect: getSpmStatus, Return response: {'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 0}}
>Thread-12178::DEBUG::2012-11-21 09:49:17,694::task::1172::TaskManager.Task::(prepare) Task=`23838477-e087-4922-96f9-3ebbbf7fe909`::finished: {'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 0}}
>Thread-12178::DEBUG::2012-11-21 09:49:17,694::task::588::TaskManager.Task::(_updateState) Task=`23838477-e087-4922-96f9-3ebbbf7fe909`::moving from state preparing -> state finished
>Thread-12178::DEBUG::2012-11-21 09:49:17,694::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-12178::DEBUG::2012-11-21 09:49:17,695::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-12178::DEBUG::2012-11-21 09:49:17,695::task::978::TaskManager.Task::(_decref) Task=`23838477-e087-4922-96f9-3ebbbf7fe909`::ref 0 aborting False
>Thread-12179::DEBUG::2012-11-21 09:49:17,710::BindingXMLRPC::171::vds::(wrapper) [10.66.72.38]
>Thread-12179::DEBUG::2012-11-21 09:49:17,710::task::588::TaskManager.Task::(_updateState) Task=`2e172936-8b46-4a6f-84e1-06b121cc88eb`::moving from state init -> state preparing
>Thread-12179::INFO::2012-11-21 09:49:17,711::logUtils::37::dispatcher::(wrapper) Run and protect: getStoragePoolInfo(spUUID='794be62d-1be7-4881-a8bb-a95d77853247', options=None)
>Thread-12179::DEBUG::2012-11-21 09:49:17,711::resourceManager::175::ResourceManager.Request::(__init__) ResName=`Storage.794be62d-1be7-4881-a8bb-a95d77853247`ReqID=`64fcaf24-0306-4844-b64f-9dfd241d7fd1`::Request was made in '/usr/share/vdsm/storage/resourceManager.py' line '485' at 'registerResource'
>Thread-12179::DEBUG::2012-11-21 09:49:17,711::resourceManager::486::ResourceManager::(registerResource) Trying to register resource 'Storage.794be62d-1be7-4881-a8bb-a95d77853247' for lock type 'shared'
>Thread-12179::DEBUG::2012-11-21 09:49:17,712::resourceManager::528::ResourceManager::(registerResource) Resource 'Storage.794be62d-1be7-4881-a8bb-a95d77853247' is free. Now locking as 'shared' (1 active user)
>Thread-12179::DEBUG::2012-11-21 09:49:17,712::resourceManager::212::ResourceManager.Request::(grant) ResName=`Storage.794be62d-1be7-4881-a8bb-a95d77853247`ReqID=`64fcaf24-0306-4844-b64f-9dfd241d7fd1`::Granted request
>Thread-12179::DEBUG::2012-11-21 09:49:17,712::task::817::TaskManager.Task::(resourceAcquired) Task=`2e172936-8b46-4a6f-84e1-06b121cc88eb`::_resourcesAcquired: Storage.794be62d-1be7-4881-a8bb-a95d77853247 (shared)
>Thread-12179::DEBUG::2012-11-21 09:49:17,713::task::978::TaskManager.Task::(_decref) Task=`2e172936-8b46-4a6f-84e1-06b121cc88eb`::ref 1 aborting False
>Thread-12179::INFO::2012-11-21 09:49:17,727::logUtils::39::dispatcher::(wrapper) Run and protect: getStoragePoolInfo, Return response: {'info': {'spm_id': 1, 'master_uuid': '6f9431a8-e3fa-4081-af58-a34f532692d7', 'name': 'NFS', 'version': '0', 'domains': '08552000-7ab1-4a4a-81f7-39fdcf785f68:Active,6f9431a8-e3fa-4081-af58-a34f532692d7:Active', 'pool_status': 'connected', 'isoprefix': '', 'type': 'NFS', 'master_ver': 1, 'lver': 0}, 'dominfo': {'08552000-7ab1-4a4a-81f7-39fdcf785f68': {'status': 'Active', 'diskfree': '758666297344', 'alerts': [], 'disktotal': '2308544921600'}, '6f9431a8-e3fa-4081-af58-a34f532692d7': {'status': 'Active', 'diskfree': '758666297344', 'alerts': [], 'disktotal': '2308544921600'}}}
>Thread-12179::DEBUG::2012-11-21 09:49:17,727::task::1172::TaskManager.Task::(prepare) Task=`2e172936-8b46-4a6f-84e1-06b121cc88eb`::finished: {'info': {'spm_id': 1, 'master_uuid': '6f9431a8-e3fa-4081-af58-a34f532692d7', 'name': 'NFS', 'version': '0', 'domains': '08552000-7ab1-4a4a-81f7-39fdcf785f68:Active,6f9431a8-e3fa-4081-af58-a34f532692d7:Active', 'pool_status': 'connected', 'isoprefix': '', 'type': 'NFS', 'master_ver': 1, 'lver': 0}, 'dominfo': {'08552000-7ab1-4a4a-81f7-39fdcf785f68': {'status': 'Active', 'diskfree': '758666297344', 'alerts': [], 'disktotal': '2308544921600'}, '6f9431a8-e3fa-4081-af58-a34f532692d7': {'status': 'Active', 'diskfree': '758666297344', 'alerts': [], 'disktotal': '2308544921600'}}}
>Thread-12179::DEBUG::2012-11-21 09:49:17,728::task::588::TaskManager.Task::(_updateState) Task=`2e172936-8b46-4a6f-84e1-06b121cc88eb`::moving from state preparing -> state finished
>Thread-12179::DEBUG::2012-11-21 09:49:17,728::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {'Storage.794be62d-1be7-4881-a8bb-a95d77853247': < ResourceRef 'Storage.794be62d-1be7-4881-a8bb-a95d77853247', isValid: 'True' obj: 'None'>}
>Thread-12179::DEBUG::2012-11-21 09:49:17,728::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-12179::DEBUG::2012-11-21 09:49:17,728::resourceManager::538::ResourceManager::(releaseResource) Trying to release resource 'Storage.794be62d-1be7-4881-a8bb-a95d77853247'
>Thread-12179::DEBUG::2012-11-21 09:49:17,729::resourceManager::553::ResourceManager::(releaseResource) Released resource 'Storage.794be62d-1be7-4881-a8bb-a95d77853247' (0 active users)
>Thread-12179::DEBUG::2012-11-21 09:49:17,729::resourceManager::558::ResourceManager::(releaseResource) Resource 'Storage.794be62d-1be7-4881-a8bb-a95d77853247' is free, finding out if anyone is waiting for it.
>Thread-12179::DEBUG::2012-11-21 09:49:17,729::resourceManager::565::ResourceManager::(releaseResource) No one is waiting for resource 'Storage.794be62d-1be7-4881-a8bb-a95d77853247', Clearing records.
>Thread-12179::DEBUG::2012-11-21 09:49:17,729::task::978::TaskManager.Task::(_decref) Task=`2e172936-8b46-4a6f-84e1-06b121cc88eb`::ref 0 aborting False
>Thread-12181::DEBUG::2012-11-21 09:49:20,812::task::588::TaskManager.Task::(_updateState) Task=`a3d29b6b-5d08-4dfc-890a-b3137a21594d`::moving from state init -> state preparing
>Thread-12181::INFO::2012-11-21 09:49:20,812::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-12181::INFO::2012-11-21 09:49:20,812::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {'08552000-7ab1-4a4a-81f7-39fdcf785f68': {'delay': '0.00100493431091', 'lastCheck': '9.6', 'code': 0, 'valid': True}, '6f9431a8-e3fa-4081-af58-a34f532692d7': {'delay': '0.00124907493591', 'lastCheck': '6.0', 'code': 0, 'valid': True}}
>Thread-12181::DEBUG::2012-11-21 09:49:20,813::task::1172::TaskManager.Task::(prepare) Task=`a3d29b6b-5d08-4dfc-890a-b3137a21594d`::finished: {'08552000-7ab1-4a4a-81f7-39fdcf785f68': {'delay': '0.00100493431091', 'lastCheck': '9.6', 'code': 0, 'valid': True}, '6f9431a8-e3fa-4081-af58-a34f532692d7': {'delay': '0.00124907493591', 'lastCheck': '6.0', 'code': 0, 'valid': True}}
>Thread-12181::DEBUG::2012-11-21 09:49:20,813::task::588::TaskManager.Task::(_updateState) Task=`a3d29b6b-5d08-4dfc-890a-b3137a21594d`::moving from state preparing -> state finished
>Thread-12181::DEBUG::2012-11-21 09:49:20,813::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-12181::DEBUG::2012-11-21 09:49:20,813::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-12181::DEBUG::2012-11-21 09:49:20,813::task::978::TaskManager.Task::(_decref) Task=`a3d29b6b-5d08-4dfc-890a-b3137a21594d`::ref 0 aborting False
>

Actions: View

Attachments on bug 878784: 649136