[Users] ovirt VM start fails - Perm Denied error

Mon Feb 13 16:35:36 UTC 2012

----- Original Message -----
> From: "Jason Brooks" <jbrooks at redhat.com>
> To: users at ovirt.org
> Sent: Monday, February 13, 2012 11:21:00 AM
> Subject: Re: [Users] ovirt VM start fails - Perm Denied error
> 
> On 02/12/2012 10:28 AM, Deepak C Shetty wrote:
> > Hi,
> >    I have tried this multiple times and i hit the same error.
> > 
> > I have 3 storage domains  created (iso, data and export) all
> > connected
> > to the DC with DC status as Up and
> > 1 host with status as Up and the same (only) host acting as SPM.
> > 
> > I used the engine-iso-uploader utility to upload my .iso to the iso
> > domain.
> > Created a new VM and attached a vdisk of type sparse (thin-prov)
> > and
> > click on "Run Once",
> > where i select "Attach CD" and select my .iso, and change boot
> > order to
> > boot from CD, then disk.
> > 
> > But i get this error...
> > 
> > VM first-ovirt-vm is down. Exit message internal error process
> > exited
> > while connecting to monitor: qemu-kvm: -drive
> > file=/rhev/data-center/4087fea7-b54a-4318-8d5c-828eff8846f4/35f880f8-bd0c-4063-b171-2ddaa59e1212/images/11111111-1111-1111-1111-111111111111/Fedora-16-x86_64-DVD.iso,if=none,media=cdrom,id=drive-ide0-1-0,readonly=on,format=raw:
> > could not open disk image
> > /rhev/data-center/4087fea7-b54a-4318-8d5c-828eff8846f4/35f880f8-bd0c-4063-b171-2ddaa59e1212/images/11111111-1111-1111-1111-111111111111/Fedora-16-x86_64-DVD.iso:
> > Permission denied .
> 
> I hit this same issue last week, on an ovirt-engine that I'd updated
> from nightly to stable. In order to get around the issue, I
> completely
> uninstalled ovirt and removed the /rhev/datacenter directory, which
> wasn't removed automatically when I uninstalled ovirt. Once I did
> that,
> I was able to proceed normally.
> 

/rhev/* 
Is there BZ to change this to ovirt?

> Jason
> 
> 
> > 
> > I am unable to figure out why.. bcos the user.group perms for the
> > .iso
> > are fine.
> > In fact i logged into the system serving the nfs share and added
> > 0777 perms
> > still i get the same error. Here is the snip of how the perms for
> > .iso
> > look like...
> > 
> > ll
> > /tmp/iso1-domain/35f880f8-bd0c-4063-b171-2ddaa59e1212/images/11111111-1111-1111-1111-111111111111/Fedora-16-x86_64-DVD.iso
> > 
> > -rwxr-xr-x. 1 vdsm kvm 3757047808 Feb 13 04:24
> > /tmp/iso1-domain/35f880f8-bd0c-4063-b171-2ddaa59e1212/images/11111111-1111-1111-1111-111111111111/Fedora-16-x86_64-DVD.iso
> > 
> > 
> > Here are some of the imp. logs from the engine.log... note that i
> > tried
> > multiple times, so there are multiple
> > entries of ERRORs for each of those tries...
> > 
> > 2012-02-13 05:10:04,563 INFO
> > [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand]
> > (pool-5-thread-47) FINISH, UpdateVdsDynamicDataVDSCommand, log id:
> > 549dfc2f
> > 2012-02-13 05:10:04,612 INFO
> > [org.ovirt.engine.core.bll.RunVmOnceCommand] (pool-5-thread-47)
> > Running
> > command: RunVmOnceCommand internal: false. Entities affected :  ID:
> > c587bc8e-f800-4ce4-a35e-1dd43480cb58 Type: VM
> > 2012-02-13 05:10:04,617 ERROR
> > [org.ovirt.engine.core.bll.RunVmCommand]
> > (pool-5-thread-47) Cant find VDS to run the VM
> > c587bc8e-f800-4ce4-a35e-1dd43480cb58 on, so this VM will not be
> > run.
> > 2012-02-13 05:10:04,622 ERROR
> > [org.ovirt.engine.core.bll.RunVmOnceCommand] (pool-5-thread-47)
> > Transaction rolled-back for command:
> > org.ovirt.engine.core.bll.RunVmOnceCommand.
> > 2012-02-13 05:11:25,735 INFO
> > [org.ovirt.engine.core.bll.DbUserCacheManager]
> > (QuartzScheduler_Worker-10)
> > DbUserCacheManager::refreshAllUserData() -
> > entered
> > 2012-02-13 05:11:52,648 WARN
> > [org.ovirt.engine.core.bll.GetConfigurationValueQuery]
> > (http--0.0.0.0-8080-1) calling GetConfigurationValueQuery with null
> > version, using default general for version
> > 2012-02-13 05:11:52,791 WARN
> > [org.ovirt.engine.core.bll.GetConfigurationValueQuery]
> > (http--0.0.0.0-8080-2) calling GetConfigurationValueQuery with null
> > version, using default general for version
> > 2012-02-13 05:11:55,828 WARN
> > [org.ovirt.engine.core.bll.GetConfigurationValueQuery]
> > (http--0.0.0.0-8080-8) calling GetConfigurationValueQuery with null
> > version, using default general for version
> > 2012-02-13 05:11:56,917 WARN
> > [org.ovirt.engine.core.bll.GetConfigurationValueQuery]
> > (http--0.0.0.0-8080-2) calling GetConfigurationValueQuery with null
> > version, using default general for version
> > 2012-02-13 05:12:11,611 INFO
> > [org.ovirt.engine.core.bll.UpdateVmCommand] (http--0.0.0.0-8080-10)
> > Running command: UpdateVmCommand internal: false. Entities affected
> > :
> > ID: c587bc8e-f800-4ce4-a35e-1dd43480cb58 Type: VM
> > 2012-02-13 05:12:11,617 INFO
> > [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand]
> > (http--0.0.0.0-8080-10) START, IsValidVDSCommand(storagePoolId =
> > 4087fea7-b54a-4318-8d5c-828eff8846f4, ignoreFailoverLimit = false,
> > compatabilityVersion = null), log id: 247cacfc
> > 2012-02-13 05:12:11,619 INFO
> > [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand]
> > (http--0.0.0.0-8080-10) FINISH, IsValidVDSCommand, return: true,
> > log id:
> > 247cacfc
> > 2012-02-13 05:12:11,632 INFO
> > [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand]
> > (http--0.0.0.0-8080-10) START, UpdateVMVDSCommand(storagePoolId =
> > 4087fea7-b54a-4318-8d5c-828eff8846f4, ignoreFailoverLimit = false,
> > compatabilityVersion = null, storageDomainId =
> > 00000000-0000-0000-0000-000000000000, infoDictionary.size = 1), log
> > id:
> > 1294a3c3
> > 2012-02-13 05:12:11,904 INFO
> > [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand]
> > (http--0.0.0.0-8080-10) FINISH, UpdateVMVDSCommand, log id:
> > 1294a3c3
> > 2012-02-13 05:12:23,502 INFO
> > [org.ovirt.engine.core.vdsbroker.irsbroker.IsoPrefixVDSCommand]
> > (http--0.0.0.0-8080-1) START, IsoPrefixVDSCommand(storagePoolId =
> > 4087fea7-b54a-4318-8d5c-828eff8846f4, ignoreFailoverLimit = false,
> > compatabilityVersion = null), log id: 201b9bfd
> > 2012-02-13 05:12:23,502 INFO
> > [org.ovirt.engine.core.vdsbroker.irsbroker.IsoPrefixVDSCommand]
> > (http--0.0.0.0-8080-1) FINISH, IsoPrefixVDSCommand, return:
> > /rhev/data-center/4087fea7-b54a-4318-8d5c-828eff8846f4/35f880f8-bd0c-4063-b171-2ddaa59e1212/images/11111111-1111-1111-1111-111111111111,
> > log id: 201b9bfd
> > 2012-02-13 05:12:23,567 INFO
> > [org.ovirt.engine.core.bll.RunVmOnceCommand] (http--0.0.0.0-8080-1)
> > Running command: RunVmOnceCommand internal: false. Entities
> > affected :
> > ID: c587bc8e-f800-4ce4-a35e-1dd43480cb58 Type: VM
> > 2012-02-13 05:12:23,586 INFO
> > [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand]
> > (http--0.0.0.0-8080-1) START, CreateVmVDSCommand(vdsId =
> > c6ff0cd0-55b5-11e1-ac57-00145e3f27e8,
> > vmId=c587bc8e-f800-4ce4-a35e-1dd43480cb58,
> > vm=org.ovirt.engine.core.common.businessentities.VM at 2d5e24e9), log
> > id:
> > 25e637df
> > 2012-02-13 05:12:23,594 INFO
> > [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand]
> > (http--0.0.0.0-8080-1) START, CreateVDSCommand(vdsId =
> > c6ff0cd0-55b5-11e1-ac57-00145e3f27e8,
> > vmId=c587bc8e-f800-4ce4-a35e-1dd43480cb58,
> > vm=org.ovirt.engine.core.common.businessentities.VM at 2d5e24e9), log
> > id:
> > 3bee3ef3
> > 2012-02-13 05:12:23,756 INFO
> > [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand]
> > (http--0.0.0.0-8080-1)
> > org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand
> > spiceSslCipherSuite=DEFAULT,memSize=512,kvmEnable=true,boot=dc,smp=1,vmType=kvm,emulatedMachine=pc-0.14,keyboardLayout=en-us,nice=0,display=qxl,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,spiceMonitors=1,cdrom=/rhev/data-center/4087fea7-b54a-4318-8d5c-828eff8846f4/35f880f8-bd0c-4063-b171-2ddaa59e1212/images/11111111-1111-1111-1111-111111111111/Fedora-16-x86_64-DVD.iso,timeOffset=0,transparentHugePages=true,drives=[Ljava.util.Map;@426264c4,vmId=c587bc8e-f800-4ce4-a35e-1dd43480cb58,acpiEnable=true,vmName=first-ovirt-vm,cpuType=Opteron_G1,custom={}
> > 
> > 2012-02-13 05:12:23,756 INFO
> > [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand]
> > (http--0.0.0.0-8080-1) FINISH, CreateVDSCommand, log id: 3bee3ef3
> > 2012-02-13 05:12:23,760 INFO
> > [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand]
> > (http--0.0.0.0-8080-1) IncreasePendingVms::CreateVmIncreasing vds
> > llm56.in.ibm.com pending vcpu count, now 1. Vm: first-ovirt-vm
> > 2012-02-13 05:12:23,764 INFO
> > [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand]
> > (http--0.0.0.0-8080-1) FINISH, CreateVmVDSCommand, return:
> > WaitForLaunch, log id: 25e637df
> > 2012-02-13 05:12:26,522 INFO
> > [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand]
> > (QuartzScheduler_Worker-52) START, DestroyVDSCommand(vdsId =
> > c6ff0cd0-55b5-11e1-ac57-00145e3f27e8,
> > vmId=c587bc8e-f800-4ce4-a35e-1dd43480cb58, force=false,
> > secondsToWait=0,
> > gracefully=false), log id: 1091a515
> > 2012-02-13 05:12:26,707 INFO
> > [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand]
> > (QuartzScheduler_Worker-52) FINISH, DestroyVDSCommand, log id:
> > 1091a515
> > 2012-02-13 05:12:26,724 INFO
> > [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
> > (QuartzScheduler_Worker-52) Running on vds during rerun failed vm:
> > null
> > 2012-02-13 05:12:26,729 INFO
> > [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
> > (QuartzScheduler_Worker-52) vm first-ovirt-vm running in db and not
> > running in vds - add to rerun treatment. vds llm56.in.ibm.com
> > 2012-02-13 05:12:26,741 ERROR
> > [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
> > (QuartzScheduler_Worker-52) Rerun vm
> > c587bc8e-f800-4ce4-a35e-1dd43480cb58. Called from vds
> > llm56.in.ibm.com
> > 2012-02-13 05:12:26,745 INFO
> > [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand]
> > (pool-5-thread-47) START, UpdateVdsDynamicDataVDSCommand(vdsId =
> > c6ff0cd0-55b5-11e1-ac57-00145e3f27e8,
> > vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic at 7ac864a5),
> > log id: 137ee32a
> > 2012-02-13 05:12:26,748 INFO
> > [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand]
> > (pool-5-thread-47) FINISH, UpdateVdsDynamicDataVDSCommand, log id:
> > 137ee32a
> > 2012-02-13 05:12:26,795 INFO
> > [org.ovirt.engine.core.bll.RunVmOnceCommand] (pool-5-thread-47)
> > Running
> > command: RunVmOnceCommand internal: false. Entities affected :  ID:
> > c587bc8e-f800-4ce4-a35e-1dd43480cb58 Type: VM
> > 2012-02-13 05:12:26,800 ERROR
> > [org.ovirt.engine.core.bll.RunVmCommand]
> > (pool-5-thread-47) Cant find VDS to run the VM
> > c587bc8e-f800-4ce4-a35e-1dd43480cb58 on, so this VM will not be
> > run.
> > 2012-02-13 05:12:26,805 ERROR
> > [org.ovirt.engine.core.bll.RunVmOnceCommand] (pool-5-thread-47)
> > Transaction rolled-back for command:
> > org.ovirt.engine.core.bll.RunVmOnceCommand.
> > 
> > 
> > Appreciate any help provided.
> > 
> > thanx,
> > deepak
> > 
> > 
> > 
> > 
> > _______________________________________________
> > Users mailing list
> > Users at ovirt.org
> > http://lists.ovirt.org/mailman/listinfo/users
> 
> _______________________________________________
> Users mailing list
> Users at ovirt.org
> http://lists.ovirt.org/mailman/listinfo/users
> 