[Users] ovirt VM start fails - Perm Denied error

12 Feb 2012

      This is a multi-part message in MIME format.
--------------050508020207010801060506
Content-Type: text/plain; charset=ISO-8859-1; format=flowed
Content-Transfer-Encoding: 7bit

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

--------------050508020207010801060506
Content-Type: text/html; charset=ISO-8859-1
Content-Transfer-Encoding: 7bit

<!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 4.01 Transitional//EN">
<html>
  <head>

    <meta http-equiv="content-type" content="text/html; charset=ISO-8859-1">
  </head>
  <body text="#000000" bgcolor="#ffffff">
    <tt>Hi,<br>
         I have tried this multiple times and i hit the same error.<br>
      <br>
      I have 3 storage domains  created (iso, data and export) all
      connected to the DC with DC status as Up and <br>
      1 host with status as Up and the same (only) host acting as SPM.<br>
      <br>
      I used the engine-iso-uploader utility to upload my .iso to the
      iso domain.<br>
      Created a new VM and attached a vdisk of type sparse (thin-prov)
      and click on "Run Once",<br>
      where i select "Attach CD" and select my .iso, and change boot
      order to boot from CD, then disk.<br>
      <br>
      But i get this error...<br>
      <br>
    </tt>
    <div title="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
      ." style="outline: medium none;" tabindex="0">
      <div id="gwt-uid-824_col2_row2"><tt>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
          .<br>
          <br>
          I am unable to figure out why.. bcos the user.group perms for
          the .iso are fine. <br>
          In fact i logged into the system serving the nfs share and
          added 0777 perms<br>
          still i get the same error. Here is the snip of how the perms
          for .iso look like...<br>
          <br>
          ll
/tmp/iso1-domain/35f880f8-bd0c-4063-b171-2ddaa59e1212/images/11111111-1111-1111-1111-111111111111/Fedora-16-x86_64-DVD.iso<br>
          -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<br>
          <br>
          Here are some of the imp. logs from the engine.log... note
          that i tried multiple times, so there are multiple<br>
          entries of ERRORs for each of those tries...<br>
          <br>
          2012-02-13 05:10:04,563 INFO 
          [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand]
          (pool-5-thread-47) FINISH, UpdateVdsDynamicDataVDSCommand, log
          id: 549dfc2f<br>
          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<br>
          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.<br>
          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.<br>
          2012-02-13 05:11:25,735 INFO 
          [org.ovirt.engine.core.bll.DbUserCacheManager]
          (QuartzScheduler_Worker-10)
          DbUserCacheManager::refreshAllUserData() - entered<br>
          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<br>
          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<br>
          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<br>
          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<br>
          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<br>
          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<br>
          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<br>
          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<br>
          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<br>
          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<br>
          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<br>
          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<br>
          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<br>
          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<br>
          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={}<br>
          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<br>
          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<br>
          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<br>
          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<br>
          2012-02-13 05:12:26,707 INFO 
          [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand]
          (QuartzScheduler_Worker-52) FINISH, DestroyVDSCommand, log id:
          1091a515<br>
          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<br>
          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<br>
          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<br>
          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<br>
          2012-02-13 05:12:26,748 INFO 
          [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand]
          (pool-5-thread-47) FINISH, UpdateVdsDynamicDataVDSCommand, log
          id: 137ee32a<br>
          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<br>
          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.<br>
          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.<br>
          <br>
          <br>
          Appreciate any help provided.<br>
          <br>
          thanx,<br>
          deepak<br>
          <br>
        </tt></div>
    </div>
  </body>
</html>

--------------050508020207010801060506--

[Users] ovirt VM start fails - Perm Denied error

Deepak C Shetty