[Users] ovirt VM start fails - Perm Denied error

Deepak C Shetty deepakcs at linux.vnet.ibm.com
Sun Feb 12 18:28:05 UTC 2012


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 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

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.ovirt.org/pipermail/users/attachments/20120212/9a12dd7b/attachment-0001.html>


More information about the Users mailing list