Re: [Users] ovirt VM start fails - Perm Denied error

From: "Jason Brooks" <jbrooks(a)redhat.com&gt; To: users(a)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.

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@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@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@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(a)ovirt.org > http://lists.ovirt.org/mailman/listinfo/users _______________________________________________ Users mailing list Users(a)ovirt.org http://lists.ovirt.org/mailman/listinfo/users