Bug 841863

Summary: 3.1 beta1 - While trying to scan ISO domain - Import error append to log - scanning ends before it started
Product: Red Hat Enterprise Linux 6 Reporter: Tomas Dosek <tdosek>
Component: vdsmAssignee: Saggi Mizrahi <smizrahi>
Status: CLOSED ERRATA QA Contact: Dafna Ron <dron>
Severity: urgent Docs Contact:
Priority: unspecified    
Version: 6.3CC: abaron, bazulay, cpelland, danken, iheim, ilvovsky, jbiddle, lpeer, lvroyce, ykaul
Target Milestone: betaKeywords: Regression
Target Release: ---   
Hardware: Unspecified   
OS: Unspecified   
Whiteboard: infra
Fixed In Version: vdsm-4.9.6-27.0 Doc Type: Bug Fix
Doc Text:
Previously, when trying to scan for ISOs on an ISO domain, the VDSM logged an error and the scan failed. This patch corrects the import error and now ISO domains are scanned correctly and meaningful results are logged by the VDSM.
Story Points: ---
Clone Of: Environment:
Last Closed: 2012-12-04 19:03:06 UTC Type: Bug
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Embargoed:
Bug Depends On:    
Bug Blocks: 836638, 838545    
Attachments:
Description Flags
vdsm.log none

Description Tomas Dosek 2012-07-20 12:42:57 UTC
Created attachment 599381 [details]
vdsm.log

Description of problem:
When we try to scan for ISOs on ISO domain vdsm append following error into log and scan for ISOs neve happens.

Thread-6551::ERROR::2012-07-20 14:25:49,598::dispatcher::69::Storage.Dispatcher.Protect::(run) No module named storage_exception
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
ImportError: No module named storage_exception


Version-Release number of selected component (if applicable):
vdsm-4.9.6-23.0.el6_3.x86_64
sanlock-2.3-2.1.el6.x86_64
libvirt-0.9.10-21.el6.x86_64
RHEV-M si11
How reproducible:
100 %

Steps to Reproduce:
1. Install rhev-m and have an NFS DC with some data and iso domain in it
2. Go to Storage - click on your iso domains and click on images subtab
  
Actual results:
Reprosteps trigger ISO list to be refreshed - when the refresh starts vdsm fails to scan ISO domain with import error (which you can see above)

Expected results:
ISO scan should complete and vdsm should report all ISOs on ISO storage domain

Additional info:
Attaching full vdsm log

Comment 2 Ayal Baron 2012-07-20 21:22:15 UTC
Looks like the wrong log.

Comment 4 Haim 2012-07-22 13:31:42 UTC
(In reply to comment #2)
> Looks like the wrong log.

Thread-1686::DEBUG::2012-07-22 15:41:13,076::task::588::TaskManager.Task::(_updateState) Task=`a10a5ffd-4af4-4224-b477-9fc844792928`::moving from state init -> state preparing
Thread-1686::INFO::2012-07-22 15:41:13,077::logUtils::37::dispatcher::(wrapper) Run and protect: getFloppyList(spUUID='829fa006-456b-4a74-96f4-aebc48bf6d00', options=None)
Thread-1686::INFO::2012-07-22 15:41:13,077::logUtils::37::dispatcher::(wrapper) Run and protect: getIsoList(spUUID='829fa006-456b-4a74-96f4-aebc48bf6d00', extension='vfd', options=None)
Thread-1686::DEBUG::2012-07-22 15:41:13,077::resourceManager::175::ResourceManager.Request::(__init__) ResName=`Storage.829fa006-456b-4a74-96f4-aebc48bf6d00`ReqID=`e55d5ef2-910c-4a7d-876e-b3f78c4272dc`::Request was made in '/usr/share/vdsm/storage/resourceManager.py' line '485' at 'registerResource'
Thread-1686::DEBUG::2012-07-22 15:41:13,078::resourceManager::486::ResourceManager::(registerResource) Trying to register resource 'Storage.829fa006-456b-4a74-96f4-aebc48bf6d00' for lock type 'shared'
Thread-1686::DEBUG::2012-07-22 15:41:13,078::resourceManager::528::ResourceManager::(registerResource) Resource 'Storage.829fa006-456b-4a74-96f4-aebc48bf6d00' is free. Now locking as 'shared' (1 active user)
Thread-1686::DEBUG::2012-07-22 15:41:13,078::resourceManager::212::ResourceManager.Request::(grant) ResName=`Storage.829fa006-456b-4a74-96f4-aebc48bf6d00`ReqID=`e55d5ef2-910c-4a7d-876e-b3f78c4272dc`::Granted request
Thread-1686::DEBUG::2012-07-22 15:41:13,079::task::817::TaskManager.Task::(resourceAcquired) Task=`a10a5ffd-4af4-4224-b477-9fc844792928`::_resourcesAcquired: Storage.829fa006-456b-4a74-96f4-aebc48bf6d00 (shared)
Thread-1686::DEBUG::2012-07-22 15:41:13,079::task::978::TaskManager.Task::(_decref) Task=`a10a5ffd-4af4-4224-b477-9fc844792928`::ref 1 aborting False
Thread-1686::ERROR::2012-07-22 15:41:13,091::task::853::TaskManager.Task::(_setError) Task=`a10a5ffd-4af4-4224-b477-9fc844792928`::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 1905, in getFloppyList
    return self.getIsoList(spUUID=spUUID, extension='vfd')
  File "/usr/share/vdsm/logUtils.py", line 38, in wrapper
    res = f(*args, **kwargs)
  File "/usr/share/vdsm/storage/hsm.py", line 1886, in getIsoList
    isodict = isoDom.getFileList(pattern='*.' + extension, caseSensitive=False)
  File "/usr/share/vdsm/storage/nfsSD.py", line 117, in getFileList
    self.oop.fileUtils.validateQemuReadable(entry)
  File "/usr/share/vdsm/storage/remoteFileHandler.py", line 270, in callCrabRPCFunction
    *args, **kwargs)
  File "/usr/share/vdsm/storage/remoteFileHandler.py", line 186, in callCrabRPCFunction
    res, err = pickle.loads(rawResponse)
  File "/usr/lib64/python2.6/pickle.py", line 1374, in loads
    return Unpickler(file).load()
  File "/usr/lib64/python2.6/pickle.py", line 858, in load
    dispatch[key](self)
  File "/usr/lib64/python2.6/pickle.py", line 1090, in load_global
    klass = self.find_class(module, name)
  File "/usr/lib64/python2.6/pickle.py", line 1124, in find_class
    __import__(module)
ImportError: No module named storage_exception

Comment 5 Dan Kenigsberg 2012-07-22 15:15:04 UTC
smells related to http://gerrit.ovirt.org/6463

Comment 8 Mark Wu 2012-08-06 02:00:04 UTC
*** Bug 839919 has been marked as a duplicate of this bug. ***

Comment 9 Ayal Baron 2012-08-06 13:05:43 UTC
http://gerrit.ovirt.org/#/c/6867/

Comment 10 Ayal Baron 2012-08-06 14:23:38 UTC
(In reply to comment #9)
> http://gerrit.ovirt.org/#/c/6867/

wrong comment. please ignore.

Comment 12 Dafna Ron 2012-08-14 15:29:06 UTC
verified on vdsm-4.9.6-27.0.el6_3.x86_64

Comment 15 errata-xmlrpc 2012-12-04 19:03:06 UTC
Since the problem described in this bug report should be
resolved in a recent advisory, it has been closed with a
resolution of ERRATA.

For information on the advisory, and where to find the updated
files, follow the link below.

If the solution does not work for you, open a new bug report.

http://rhn.redhat.com/errata/RHSA-2012-1508.html