9:27 p.m.
Hi,
1)
What do you need exactly? Shutdown VM, edit disk, "extend size by" --->
error
2)
storage domain is up and running, but with lvm command, vg exists, lv does
not exist
Powering up a machine, I also saw these lines in vdsm log:
Thread-22::DEBUG::2017-05-07 18:59:54,115::blockSD::596::
Storage.Misc.excCmd::(getReadDelay) SUCCESS: <err> = '1+0 records in\n1+0
records out\n4096 bytes (4.1 kB) copied, 0.000411491 s, 10.0 MB/s\n'; <rc>
= 0
Thread-300::DEBUG::2017-05-07
18:59:55,539::libvirtconnection::151::root::(wrapper)
Unknown libvirterror: ecode: 80 edom: 20 level: 2 message: metadata not
found: Requested metadata element is not present
Thread-1101::DEBUG::2017-05-07
18:59:55,539::libvirtconnection::151::root::(wrapper)
Unknown libvirterror: ecode: 80 edom: 20 level: 2 message: metadata not
found: Requested metadata element is not present
VM Channels Listener::INFO::2017-05-07
18:59:55,560::guestagent::180::vm.Vm::(_handleAPIVersion)
vmId=`79cd29dc-bb6c-4f1f-ae38-54d6cac05489`::Guest API version changed from
3 to 1
VM Channels Listener::INFO::2017-05-07
18:59:55,865::guestagent::180::vm.Vm::(_handleAPIVersion)
vmId=`d37b37e9-1dd7-4a90-8d8c-755743c714ad`::Guest API version changed from
2 to 1
VM Channels Listener::INFO::2017-05-07
18:59:57,414::guestagent::180::vm.Vm::(_handleAPIVersion)
vmId=`8a034ac2-2646-4fe7-8fda-7c33affa8009`::Guest API version changed from
2 to 1
VM Channels Listener::INFO::2017-05-07
18:59:58,178::guestagent::180::vm.Vm::(_handleAPIVersion)
vmId=`4d1dad66-4ada-445a-b5f6-c695220d6b19`::Guest API version changed from
3 to 1
Thread-272::DEBUG::2017-05-07
18:59:58,187::libvirtconnection::151::root::(wrapper)
Unknown libvirterror: ecode: 80 edom: 20 level: 2 message: metadata not
found: Requested metadata element is not present
Thread-166::DEBUG::2017-05-07
18:59:58,539::libvirtconnection::151::root::(wrapper)
Unknown libvirterror: ecode: 80 edom: 20 level: 2 message: metadata not
found: Requested metadata element is not present
Thread-18::DEBUG::2017-05-07 19:00:33,805::persistentDict::
234::Storage.PersistentDict::(refresh) read lines (LvMetadataRW)=[]
Thread-18::DEBUG::2017-05-07 19:00:33,805::persistentDict::
252::Storage.PersistentDict::(refresh) Empty metadata
Thread-18::DEBUG::2017-05-07 19:00:33,805::persistentDict::
192::Storage.PersistentDict::(__init__) Created a persistent dict with
VGTagMetadataRW backend
Thread-18::DEBUG::2017-05-07 19:00:33,805::lvm::504::
Storage.OperationMutex::(_invalidatevgs) Operation 'lvm invalidate
operation' got the operation mutex
Thread-18::DEBUG::2017-05-07 19:00:33,806::lvm::506::
Storage.OperationMutex::(_invalidatevgs) Operation 'lvm invalidate
operation' released the operation mutex
Thread-18::DEBUG::2017-05-07 19:00:33,806::lvm::514::
Storage.OperationMutex::(_invalidatelvs) Operation 'lvm invalidate
operation' got the operation mutex
Thread-18::DEBUG::2017-05-07 19:00:33,806::lvm::526::
Storage.OperationMutex::(_invalidatelvs) Operation 'lvm invalidate
operation' released the operation mutex
Thread-18::DEBUG::2017-05-07 19:00:33,806::lvm::371::
Storage.OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got
the operation mutex
Thread-18::DEBUG::2017-05-07 19:00:33,806::lvm::291::Storage.Misc.excCmd::(cmd)
/usr/bin/sudo -n /sbin/lvm vgs --config ' devices { preferred_names =
["^/dev/mapper/"] ignore_suspended_devices=1 write_cache_state=0
disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [
'\''a|/dev/mapper/360060160a62134002818778f949de411|/dev/mapper/
360060160a621340040652b7582f5e511|/dev/mapper/360060160a621340064b1034cbbfce
511|/dev/mapper/360060160a6213400c4b39e80949de411|/dev/mapper/
360060160a6213400cce46e40949de411|/dev/mapper/360060160a6213400e622de69949de
411|/dev/mapper/360060160a6213400fa2d31acbbfce511|'\'',
'\''r|.*|'\'' ] }
global { locking_type=1 prioritise_write_locks=1 wait_for_locks=1
use_lvmetad=0 } backup { retain_min = 50 retain_days = 0 } '
--noheadings --units b --nosuffix --separator '|' --ignoreskippedcluster -o
uuid,name,attr,size,free,extent_size,extent_count,free_
count,tags,vg_mda_size,vg_mda_free,lv_count,pv_count,pv_name
2c501858-bf8d-49a5-a42b-bca341b47827 (cwd None)
Thread-18::DEBUG::2017-05-07 19:00:33,962::lvm::291::Storage.Misc.excCmd::(cmd)
SUCCESS: <err> = ' WARNING: lvmetad is running but disabled. Restart
lvmetad before enabling it!\n'; <rc> = 0
Thread-18::DEBUG::2017-05-07 19:00:33,962::lvm::416::
Storage.OperationMutex::(_reloadvgs) Operation 'lvm reload operation'
released the operation mutex
Thread-18::DEBUG::2017-05-07 19:00:33,963::persistentDict::
234::Storage.PersistentDict::(refresh) read lines
(VGTagMetadataRW)=['CLASS=Data',
'DESCRIPTION=BKP01', 'IOOPTIMEOUTSEC=10', 'LEASERETRIES=3',
'LEASETIMESEC=60', 'LOCKPOLICY=', 'LOCKRENEWALINTERVALSEC=5',
'LOGBLKSIZE=512', 'PHYBLKSIZE=512',
'POOL_UUID=715d1ba2-eabe-48db-9aea-c28c30359808',
u'PV0=pv:360060160a6213400c4b39e80949de411,uuid:Epqopf-zKvd-Um2q-
lHO9-6jmE-7IyB-qvib1I,pestart:0,pecount:3997,mapoffset:0', u'PV1=pv:
360060160a6213400cce46e40949de411,uuid:GD9AnB-2pin-Jbof-
kxHI-Gsa2-308p-zeoZ3B,pestart:0,pecount:4477,mapoffset:3997',
'ROLE=Regular', 'SDUUID=2c501858-bf8d-49a5-a42b-bca341b47827',
'TYPE=FCP',
'VERSION=3', 'VGUUID=p9xQ2y-gOuF-nUQU-fKJP-skMF-9xaY-H7W2ym',
'_SHA_CKSUM=
237ce5cf1ccddf89565560f570afb6622c58c90e']
Thread-18::INFO::2017-05-07
19:00:33,963::sd::385::Storage.StorageDomain::(_registerResourceNamespaces)
Resource namespace 2c501858-bf8d-49a5-a42b-bca341b47827_imageNS already
registered
Thread-18::INFO::2017-05-07
19:00:33,963::sd::393::Storage.StorageDomain::(_registerResourceNamespaces)
Resource namespace 2c501858-bf8d-49a5-a42b-bca341b47827_volumeNS already
registered
Thread-18::INFO::2017-05-07 19:00:33,964::blockSD::448::
Storage.StorageDomain::(_registerResourceNamespaces) Resource namespace
2c501858-bf8d-49a5-a42b-bca341b47827_lvmActivationNS already registered
Thread-18::DEBUG::2017-05-07 19:00:33,964::lvm::291::Storage.Misc.excCmd::(cmd)
/usr/bin/sudo -n /sbin/lvm vgck --config ' devices { preferred_names =
["^/dev/mapper/"] ignore_suspended_devices=1 write_cache_state=0
disable_after_error_count=3 obtain_device_list_from_udev=0 filter = [
'\''a|/dev/mapper/360060160a6213400c4b39e80949de411|/dev/mapper/
360060160a6213400cce46e40949de411|'\'', '\''r|.*|'\''
] } global {
locking_type=1 prioritise_write_locks=1 wait_for_locks=1 use_lvmetad=0
} backup { retain_min = 50 retain_days = 0 } '
2c501858-bf8d-49a5-a42b-bca341b47827
(cwd None)
2017-05-07 14:36 GMT+02:00 Kevin Goldblatt <kgoldbla(a)redhat.com>:
Hi Stephano,
From the log I see that the image cannot be found. This would indicate
that you have an issue on your host connected to the storage provider:
OneImageInfoReturnForXmlRpc [mStatus=StatusForXmlRpc [mCode=254,
mMessage=Image path does not exist or cannot be accessed/created:
(u'/rhev/data-center/mnt/blockSD/6a386652-629d-4045-835b-21d
2f5c104aa/images/c5fb9190-d059-4d9b-af23-07618ff660ce',)]]
Please provide the following:
1. Provide the exact scenario you performed in the Update of the disk
2. Can you please check to see that the storage domain on which the disk
resides is up and running,
3. If not then check whether the storage on the host is visible through
the host
4. If all the above is working then please submit a bug through bugzilla
and provide the engine, server and vdsm logs.
Regards,
Kevin
On Tue, May 2, 2017 at 11:10 PM, Stefano Bovina <bovy89(a)gmail.com> wrote:
> Hi, while trying to update a VM disk, a failure was returned (forcing me
> to add a new disk)
>
> Any advice on how to resolve this error?
>
> Thanks
>
>
> Installation info:
>
> ovirt-release35-006-1.noarch
> libgovirt-0.3.3-1.el7_2.1.x86_64
> vdsm-4.16.30-0.el7.centos.x86_64
> vdsm-xmlrpc-4.16.30-0.el7.centos.noarch
> vdsm-yajsonrpc-4.16.30-0.el7.centos.noarch
> vdsm-jsonrpc-4.16.30-0.el7.centos.noarch
> vdsm-python-zombiereaper-4.16.30-0.el7.centos.noarch
> vdsm-python-4.16.30-0.el7.centos.noarch
> vdsm-cli-4.16.30-0.el7.centos.noarch
> qemu-kvm-ev-2.3.0-29.1.el7.x86_64
> qemu-kvm-common-ev-2.3.0-29.1.el7.x86_64
> qemu-kvm-tools-ev-2.3.0-29.1.el7.x86_64
> libvirt-client-1.2.17-13.el7_2.3.x86_64
> libvirt-daemon-driver-storage-1.2.17-13.el7_2.3.x86_64
> libvirt-python-1.2.17-2.el7.x86_64
> libvirt-daemon-driver-nwfilter-1.2.17-13.el7_2.3.x86_64
> libvirt-daemon-driver-nodedev-1.2.17-13.el7_2.3.x86_64
> libvirt-lock-sanlock-1.2.17-13.el7_2.3.x86_64
> libvirt-glib-0.1.9-1.el7.x86_64
> libvirt-daemon-driver-network-1.2.17-13.el7_2.3.x86_64
> libvirt-daemon-driver-lxc-1.2.17-13.el7_2.3.x86_64
> libvirt-daemon-driver-interface-1.2.17-13.el7_2.3.x86_64
> libvirt-1.2.17-13.el7_2.3.x86_64
> libvirt-daemon-1.2.17-13.el7_2.3.x86_64
> libvirt-daemon-config-network-1.2.17-13.el7_2.3.x86_64
> libvirt-daemon-driver-secret-1.2.17-13.el7_2.3.x86_64
> libvirt-daemon-config-nwfilter-1.2.17-13.el7_2.3.x86_64
> libvirt-daemon-kvm-1.2.17-13.el7_2.3.x86_64
> libvirt-daemon-driver-qemu-1.2.17-13.el7_2.3.x86_64
>
>
> -------- engine.log
>
> 2017-05-02 09:48:26,505 INFO [org.ovirt.engine.core.bll.UpdateVmDiskCommand]
> (ajp--127.0.0.1-8702-6) [c3d7125] Lock Acquired to object EngineLock
> [exclusiveLocks= key: 25c0bcc0-0d3d-4ddc-b103-24ed2ac5aa05 value:
> VM_DISK_BOOT
> key: c5fb9190-d059-4d9b-af23-07618ff660ce value: DISK
> , sharedLocks= key: 25c0bcc0-0d3d-4ddc-b103-24ed2ac5aa05 value: VM
> ]
> 2017-05-02 09:48:26,515 INFO [org.ovirt.engine.core.bll.UpdateVmDiskCommand]
> (ajp--127.0.0.1-8702-6) [c3d7125] Running command: UpdateVmDiskCommand
> internal: false. Entities affected : ID: c5fb9190-d059-4d9b-af23-07618ff660ce
> Type: DiskAction group EDIT_DISK_PROPERTIES with role type USER
> 2017-05-02 09:48:26,562 INFO [org.ovirt.engine.core.bll.ExtendImageSizeCommand]
> (ajp--127.0.0.1-8702-6) [ae718d8] Running command: ExtendImageSizeCommand
> internal: true. Entities affected : ID: c5fb9190-d059-4d9b-af23-07618ff660ce
> Type: DiskAction group EDIT_DISK_PROPERTIES with role type USER
> 2017-05-02 09:48:26,565 INFO [org.ovirt.engine.core.vdsbro
> ker.irsbroker.ExtendImageSizeVDSCommand] (ajp--127.0.0.1-8702-6)
> [ae718d8] START, ExtendImageSizeVDSCommand( storagePoolId =
> 715d1ba2-eabe-48db-9aea-c28c30359808, ignoreFailoverLimit = false), log
> id: 52aac743
> 2017-05-02 09:48:26,604 INFO [org.ovirt.engine.core.vdsbro
> ker.irsbroker.ExtendImageSizeVDSCommand] (ajp--127.0.0.1-8702-6)
> [ae718d8] FINISH, ExtendImageSizeVDSCommand, log id: 52aac743
> 2017-05-02 09:48:26,650 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
> (ajp--127.0.0.1-8702-6) [ae718d8] CommandAsyncTask::Adding
> CommandMultiAsyncTasks object for command cb7958d9-6eae-44a9-891a-7fe088
> a79df8
> 2017-05-02 09:48:26,651 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks]
> (ajp--127.0.0.1-8702-6) [ae718d8] CommandMultiAsyncTasks::AttachTask:
> Attaching task 769a4b18-182b-4048-bb34-a276a55ccbff to command
> cb7958d9-6eae-44a9-891a-7fe088a79df8.
> 2017-05-02 09:48:26,661 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
> (ajp--127.0.0.1-8702-6) [ae718d8] Adding task 769a4b18-182b-4048-bb34-a276a55ccbff
> (Parent Command UpdateVmDisk, Parameters Type org.ovirt.engine.core.common.a
> synctasks.AsyncTaskParameters), polling hasn't started yet..
> 2017-05-02 09:48:26,673 INFO [org.ovirt.engine.core.dal.db
> broker.auditloghandling.AuditLogDirector] (ajp--127.0.0.1-8702-6)
> [ae718d8] Correlation ID: c3d7125, Call Stack: null, Custom Event ID: -1,
> Message: VM sysinfo-73 sysinfo-73_Disk3 disk was updated by admin@internal.
> 2017-05-02 09:48:26,674 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
> (ajp--127.0.0.1-8702-6) [ae718d8] BaseAsyncTask::startPollingTask:
> Starting to poll task 769a4b18-182b-4048-bb34-a276a55ccbff.
> 2017-05-02 09:48:28,430 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
> (DefaultQuartzScheduler_Worker-48) [36cd2f7] Polling and updating Async
> Tasks: 1 tasks, 1 tasks to poll now
> 2017-05-02 09:48:28,435 ERROR [org.ovirt.engine.core.vdsbrok
> er.vdsbroker.HSMGetAllTasksStatusesVDSCommand]
> (DefaultQuartzScheduler_Worker-48) [36cd2f7] Failed in
> HSMGetAllTasksStatusesVDS method
> 2017-05-02 09:48:28,436 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
> (DefaultQuartzScheduler_Worker-48) [36cd2f7] SPMAsyncTask::PollTask:
> Polling task 769a4b18-182b-4048-bb34-a276a55ccbff (Parent Command
> UpdateVmDisk, Parameters Type
org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters)
> returned status finished, result 'cleanSuccess'.
> 2017-05-02 09:48:28,446 ERROR [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
> (DefaultQuartzScheduler_Worker-48) [36cd2f7]
> BaseAsyncTask::logEndTaskFailure: Task 769a4b18-182b-4048-bb34-a276a55ccbff
> (Parent Command UpdateVmDisk, Parameters Type
org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters)
> ended with failure:
> -- Result: cleanSuccess
> -- Message: VDSGenericException: VDSErrorException: Failed to
> HSMGetAllTasksStatusesVDS, error = Could not acquire resource. Probably
> resource factory threw an exception.: (), code = 100,
> -- Exception: VDSGenericException: VDSErrorException: Failed to
> HSMGetAllTasksStatusesVDS, error = Could not acquire resource. Probably
> resource factory threw an exception.: (), code = 100
> 2017-05-02 09:48:28,448 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
> (DefaultQuartzScheduler_Worker-48) [36cd2f7]
> CommandAsyncTask::endActionIfNecessary: Al
> l tasks of command cb7958d9-6eae-44a9-891a-7fe088a79df8 has ended ->
> executing endAction
> 2017-05-02 09:48:28,449 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
> (DefaultQuartzScheduler_Worker-48) [36cd2f7]
> CommandAsyncTask::endAction: Ending action
> for 1 tasks (command ID: cb7958d9-6eae-44a9-891a-7fe088a79df8): calling
> endAction .
> 2017-05-02 09:48:28,450 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
> (org.ovirt.thread.pool-8-thread-6) [36cd2f7]
> CommandAsyncTask::endCommandAction [within
> thread] context: Attempting to endAction UpdateVmDisk, executionIndex: 0
> 2017-05-02 09:48:28,501 ERROR [org.ovirt.engine.core.bll.UpdateVmDiskCommand]
> (org.ovirt.thread.pool-8-thread-6) [c3d7125] Ending command with
> failure: org.ovirt.engine
> .core.bll.UpdateVmDiskCommand
> 2017-05-02 09:48:28,502 ERROR [org.ovirt.engine.core.bll.ExtendImageSizeCommand]
> (org.ovirt.thread.pool-8-thread-6) Ending command with failure:
> org.ovirt.engine.core.b
> ll.ExtendImageSizeCommand
> 2017-05-02 09:48:28,504 INFO [org.ovirt.engine.core.vdsbro
> ker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-8-thread-6)
> START, GetImageInfoVDSCommand( stora
> gePoolId = 715d1ba2-eabe-48db-9aea-c28c30359808, ignoreFailoverLimit =
> false, storageDomainId = 6a386652-629d-4045-835b-21d2f5c104aa,
> imageGroupId = c5fb9190-d059-4d9b-
> af23-07618ff660ce, imageId = 562f5b50-1d1a-457c-8b5f-cd8020611550), log
> id: 674a2581
> 2017-05-02 09:48:28,511 ERROR [org.ovirt.engine.core.vdsbrok
> er.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-8-thread-6)
> IrsBroker::getImageInfo::Failed gett
> ing image info imageId = 562f5b50-1d1a-457c-8b5f-cd8020611550 does not
> exist on domainName = VM01 , domainId = 6a386652-629d-4045-835b-21d2f5c104aa,
> error code: ImagePathError, message: Image path does not exist or cannot
> be accessed/created: (u'/rhev/data-center/mnt/block
> SD/6a386652-629d-4045-835b-21d2f5c104aa/images/c5fb9190-d059
> -4d9b-af23-07618ff660ce',)
> 2017-05-02 09:48:28,512 INFO [org.ovirt.engine.core.vdsbro
> ker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-8-thread-6)
> Command org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand
> return value
>
> OneImageInfoReturnForXmlRpc [mStatus=StatusForXmlRpc [mCode=254,
> mMessage=Image path does not exist or cannot be accessed/created:
> (u'/rhev/data-center/mnt/blockSD/6a386652-629d-4045-835b-21d
> 2f5c104aa/images/c5fb9190-d059-4d9b-af23-07618ff660ce',)]]
>
> 2017-05-02 09:48:28,512 INFO [org.ovirt.engine.core.vdsbro
> ker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-8-thread-6)
> FINISH, GetImageInfoVDSCommand, log id: 674a2581
> 2017-05-02 09:48:28,533 ERROR [org.ovirt.engine.core.dal.dbb
> roker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-6)
> Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message:
> Failed to extend size of the disk 'sysinfo-73_Disk3' to 20 GB, User:
> admin@internal.
> 2017-05-02 09:48:28,534 INFO [org.ovirt.engine.core.bll.UpdateVmDiskCommand]
> (org.ovirt.thread.pool-8-thread-6) Lock freed to object EngineLock
> [exclusiveLocks= key: 25c0bcc0-0d3d-4ddc-b103-24ed2ac5aa05 value:
> VM_DISK_BOOT
> key: c5fb9190-d059-4d9b-af23-07618ff660ce value: DISK
> , sharedLocks= key: 25c0bcc0-0d3d-4ddc-b103-24ed2ac5aa05 value: VM
> ]
> 2017-05-02 09:48:28,541 INFO [org.ovirt.engine.core.dal.db
> broker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-8-thread-6)
> Correlation ID: c3d7125, Call Stack: null, Custom Event ID: -1, Message: VM
> sysinfo-73 sysinfo-73_Disk3 disk was updated by admin@internal.
> 2017-05-02 09:48:28,541 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
> (org.ovirt.thread.pool-8-thread-6) CommandAsyncTask::HandleEndActionResult
> [within thread]: endAction for action type UpdateVmDisk completed, handling
> the result.
> 2017-05-02 09:48:28,541 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
> (org.ovirt.thread.pool-8-thread-6) CommandAsyncTask::HandleEndActionResult
> [within thread]: endAction for action type UpdateVmDisk succeeded, clearing
> tasks.
>
> _______________________________________________
> Users mailing list
> Users(a)ovirt.org
> http://lists.ovirt.org/mailman/listinfo/users
>
>