[ovirt-users] Don't start vm

[Nir Soffer]

----- Original Message -----
> From: "Roman Nikolayevich Drovalev" <drovalev at kaluga-gov.ru>
> To: Users at ovirt.org
> Sent: Saturday, December 6, 2014 8:28:08 PM
> Subject: [ovirt-users]  Don't start vm
> 
> Hi,
> My config: vdsm host - CentOS 7, oVirt 3.5
> 
> > Could you please share from hypervisor the /var/log/vdsm/vdsm.log too?
> 
> my /var/log/vdsm/vdsm.log

We need the full log - please attach here or open a bug and
attach the full log.

> 
> Thread-283375::DEBUG::2014-12-06
> 21:20:40,219::stompReactor::163::yajsonrpc.StompServer::(send) Sending
> response

You are using jsonrpc - please check if switching to xmlrpc solve
your issue.

> Thread-283376::DEBUG::2014-12-06
> 21:20:40,252::lvm::288::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = '
> WARNING: lvmetad is running but disabled. Restart lvmetad before enabling
> it!\n'; <rc> = 0
> Thread-283376::DEBUG::2014-12-06
> 21:20:40,253::lvm::454::Storage.LVM::(_reloadlvs) lvs reloaded
> Thread-283376::DEBUG::2014-12-06
> 21:20:40,254::lvm::454::Storage.OperationMutex::(_reloadlvs) Operation 'lvm
> reload operation' released the operation mutex
> Thread-283376::WARNING::2014-12-06
> 21:20:40,254::lvm::600::Storage.LVM::(getLv) lv:
> fb8466c9-0867-4e73-8362-2c95eea89a83 not found in lvs vg:
> 9d53ecef-8bfc-470b-8867-836bfa7df137 response
> Thread-283376::ERROR::2014-12-06
> 21:20:40,254::task::866::Storage.TaskManager.Task::(_setError)
> Task=`cb86d3c3-77f7-46c8-aec0-4c848f1eb2cd`::Unexpected error
> Traceback (most recent call last):
> File "/usr/share/vdsm/storage/task.py", line 873, in _run
> return fn(*args, **kargs)
> File "/usr/share/vdsm/logUtils.py", line 45, in wrapper
> res = f(*args, **kwargs)
> File "/usr/share/vdsm/storage/hsm.py", line 3099, in getVolumeSize
> apparentsize = str(dom.getVSize(imgUUID, volUUID))
> File "/usr/share/vdsm/storage/blockSD.py", line 622, in getVSize
> size = lvm.getLV(self.sdUUID, volUUID).size
> File "/usr/share/vdsm/storage/lvm.py", line 893, in getLV
> raise se.LogicalVolumeDoesNotExistError("%s/%s" % (vgName, lvName))
> LogicalVolumeDoesNotExistError: Logical volume does not exist:
> (u'9d53ecef-8bfc-470b-8867-836bfa7df137/fb8466c9-0867-4e73-8362-2c95eea89a83',)
> Thread-283376::DEBUG::2014-12-06
> 21:20:40,255::task::885::Storage.TaskManager.Task::(_run)
> Task=`cb86d3c3-77f7-46c8-aec0-4c848f1eb2cd`::Task._run:
> cb86d3c3-77f7-46c8-aec0-4c848f1eb2cd
> (u'9d53ecef-8bfc-470b-8867-836bfa7df137',
> u'00000002-0002-0002-0002-00000000010b',
> u'7deace0a-0c83-41c8-9122-84079ad949c2',
> u'fb8466c9-0867-4e73-8362-2c95eea89a83') {} failed - stopping task
> Thread-283376::DEBUG::2014-12-06
> 21:20:40,255::task::1217::Storage.TaskManager.Task::(stop)
> Task=`cb86d3c3-77f7-46c8-aec0-4c848f1eb2cd`::stopping in state preparing
> (force False)
> Thread-283376::DEBUG::2014-12-06
> 21:20:40,255::task::993::Storage.TaskManager.Task::(_decref)
> Task=`cb86d3c3-77f7-46c8-aec0-4c848f1eb2cd`::ref 1 aborting True
> Thread-283376::INFO::2014-12-06
> 21:20:40,255::task::1171::Storage.TaskManager.Task::(prepare)
> Task=`cb86d3c3-77f7-46c8-aec0-4c848f1eb2cd`::aborting: Task is aborted:
> 'Logical volume does not exist' - code 610
> Thread-283376::DEBUG::2014-12-06
> 21:20:40,255::task::1176::Storage.TaskManager.Task::(prepare)
> Task=`cb86d3c3-77f7-46c8-aec0-4c848f1eb2cd`::Prepare: aborted: Logical
> volume does not exist
> Thread-283376::DEBUG::2014-12-06
> 21:20:40,256::task::993::Storage.TaskManager.Task::(_decref)
> Task=`cb86d3c3-77f7-46c8-aec0-4c848f1eb2cd`::ref 0 aborting True
> Thread-283376::DEBUG::2014-12-06
> 21:20:40,256::task::928::Storage.TaskManager.Task::(_doAbort)
> Task=`cb86d3c3-77f7-46c8-aec0-4c848f1eb2cd`::Task._doAbort: force False
> Thread-283376::DEBUG::2014-12-06
> 21:20:40,256::resourceManager::977::Storage.ResourceManager.Owner::(cancelAll)
> Owner.cancelAll requests {}
> Thread-283376::DEBUG::2014-12-06
> 21:20:40,256::task::595::Storage.TaskManager.Task::(_updateState)
> Task=`cb86d3c3-77f7-46c8-aec0-4c848f1eb2cd`::moving from state preparing ->
> state aborting
> Thread-283376::DEBUG::2014-12-06
> 21:20:40,256::task::550::Storage.TaskManager.Task::(__state_aborting)
> Task=`cb86d3c3-77f7-46c8-aec0-4c848f1eb2cd`::_aborting: recover policy none
> Thread-283376::DEBUG::2014-12-06
> 21:20:40,256::task::595::Storage.TaskManager.Task::(_updateState)
> Task=`cb86d3c3-77f7-46c8-aec0-4c848f1eb2cd`::moving from state aborting ->
> state failed
> Thread-283376::DEBUG::2014-12-06
> 21:20:40,257::resourceManager::940::Storage.ResourceManager.Owner::(releaseAll)
> Owner.releaseAll requests {} resources {}
> Thread-283376::DEBUG::2014-12-06
> 21:20:40,257::resourceManager::977::Storage.ResourceManager.Owner::(cancelAll)
> Owner.cancelAll requests {}
> Thread-283376::ERROR::2014-12-06
> 21:20:40,257::dispatcher::76::Storage.Dispatcher::(wrapper) {'status':
> {'message': "Logical volume does not exist:
> (u'9d53ecef-8bfc-470b-8867-836bfa7df137/fb8466c9-0867-4e73-8362-2c95eea89a83',)",
> 'code': 610}}
> # My comment:" Realy, this is volume is present! I mount it in the vdsm host!
> But, mount /dev/block/253:20 , no present in
> /dev/9d53ecef-8bfc-470b-8867-836bfa7df137/"

Please share with us the output of:

lsblk
multipath -ll
pvscan --cache
pvs
vgs
lvs

When a host is up.

Thanks,
Nir