Hi all,
Trying to start a virtual server from DVD fails, the (spice) console
gives:
"Could not read from CDROM (code 0003)"
2012-06-14 18:53:08,630 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.IsoPrefixVDSCommand]
(ajp--0.0.0.0-8009-3) [193bc72b] START,
IsoPrefixVDSCommand(storagePoolId =
b2456911-a25e-4c33-8e46-bae35cc015e7, ignoreFailoverLimit = false,
compatabilityVersion = null), log id: 59f0cc1c
2012-06-14 18:53:08,632 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.IsoPrefixVDSCommand]
(ajp--0.0.0.0-8009-3) [193bc72b] FINISH, IsoPrefixVDSCommand,
return:
/rhev/data-center/b2456911-a25e-4c33-8e46-bae35cc015e7/bfb1da46-c417-472a-8905-09e687648b16/images/11111111-1111-1111-1111-111111111111,
log id: 59f0cc1c
2012-06-14 18:53:08,752 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand]
(ajp--0.0.0.0-8009-3) [193bc72b] START,
IsValidVDSCommand(storagePoolId =
b2456911-a25e-4c33-8e46-bae35cc015e7, ignoreFailoverLimit = false,
compatabilityVersion = null), log id: 2ba80f77
2012-06-14 18:53:08,757 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand]
(ajp--0.0.0.0-8009-3) [193bc72b] FINISH, IsValidVDSCommand, return:
true, log id: 2ba80f77
2012-06-14 18:53:08,864 INFO
[org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]
(ajp--0.0.0.0-8009-3) [193bc72b] START,
IsVmDuringInitiatingVDSCommand(vmId =
d3a1600e-9fb9-4d44-9c5e-b6278226ae57), log id: 7bafb10a
2012-06-14 18:53:08,867 INFO
[org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]
(ajp--0.0.0.0-8009-3) [193bc72b] FINISH,
IsVmDuringInitiatingVDSCommand, return: false, log id: 7bafb10a
2012-06-14 18:53:08,896 INFO
[org.ovirt.engine.core.bll.RunVmOnceCommand] (ajp--0.0.0.0-8009-3)
[193bc72b] Running command: RunVmOnceCommand internal: false.
Entities affected : ID: d3a1600e-9fb9-4d44-9c5e-b6278226ae57 Type:
VM
2012-06-14 18:53:08,922 INFO
[org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand]
(ajp--0.0.0.0-8009-3) [193bc72b] START, CreateVmVDSCommand(vdsId =
c781df7a-b5f5-11e1-91f8-e791895bd007,
vmId=d3a1600e-9fb9-4d44-9c5e-b6278226ae57,
vm=org.ovirt.engine.core.common.businessentities.VM@4209547b), log
id: 184fd2c
2012-06-14 18:53:08,931 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand]
(ajp--0.0.0.0-8009-3) [193bc72b] START, CreateVDSCommand(vdsId =
c781df7a-b5f5-11e1-91f8-e791895bd007,
vmId=d3a1600e-9fb9-4d44-9c5e-b6278226ae57,
vm=org.ovirt.engine.core.common.businessentities.VM@4209547b), log
id: ba59fb6
2012-06-14 18:53:08,997 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand]
(ajp--0.0.0.0-8009-3) [193bc72b]
org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand
spiceSslCipherSuite=DEFAULT,memSize=1024,kvmEnable=true,smp=1,emulatedMachine=pc-0.14,vmType=kvm,keyboardLayout=en-us,pitReinjection=false,nice=0,display=qxl,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,timeOffset=0,transparentHugePages=true,vmId=d3a1600e-9fb9-4d44-9c5e-b6278226ae57,devices=[Ljava.util.Map;@7525661e,acpiEnable=true,vmName=CENTOS6,cpuType=Conroe,custom={device_c27ccfac-aa9c-4b66-977a-4a12e6088533device_26088fe5-ef48-492e-bdad-a7b6e434f715device_d567a5eb-c03a-41a7-8978-3fc4c19743c8device_42361439-dce5-4349-9542-8f2200fc12cc=VmId=d3a1600e-9fb9-4d44-9c5e-b6278226ae57,DeviceId=42361439-dce5-4349-9542-8f2200fc12cc,Device=spicevmc,Type=channel,BootOrder=0,SpecParams={},Address={port=2,
bus=0, controller=0,
type=virtio-serial},IsManaged=false,IsPlugged=true,IsReadOnly=false,alias=channel1,
device_c27ccfac-aa9c-4b66-977a-4a12e6088533device_26088fe5-ef48-492e-bdad-a7b6e434f715device_d567a5eb-c03a-41a7-8978-3fc4c19743c8=VmId=d3a1600e-9fb9-4d44-9c5e-b6278226ae57,DeviceId=d567a5eb-c03a-41a7-8978-3fc4c19743c8,Device=unix,Type=channel,BootOrder=0,SpecParams={},Address={port=1,
bus=0, controller=0,
type=virtio-serial},IsManaged=false,IsPlugged=true,IsReadOnly=false,alias=channel0,
device_c27ccfac-aa9c-4b66-977a-4a12e6088533device_26088fe5-ef48-492e-bdad-a7b6e434f715=VmId=d3a1600e-9fb9-4d44-9c5e-b6278226ae57,DeviceId=26088fe5-ef48-492e-bdad-a7b6e434f715,Device=virtio-serial,Type=controller,BootOrder=0,SpecParams={},Address={bus=0x00,
domain=0x0000, type=pci, slot=0x04,
function=0x0},IsManaged=false,IsPlugged=true,IsReadOnly=false,alias=virtio-serial0,
device_c27ccfac-aa9c-4b66-977a-4a12e6088533=VmId=d3a1600e-9fb9-4d44-9c5e-b6278226ae57,DeviceId=c27ccfac-aa9c-4b66-977a-4a12e6088533,Device=usb,Type=controller,BootOrder=0,SpecParams={},Address={bus=0x00,
domain=0x0000, type=pci, slot=0x01,
function=0x2},IsManaged=false,IsPlugged=true,IsReadOnly=false,alias=usb0}
2012-06-14 18:53:09,019 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand]
(ajp--0.0.0.0-8009-3) [193bc72b] FINISH, CreateVDSCommand, log id:
ba59fb6
2012-06-14 18:53:09,022 INFO
[org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand]
(ajp--0.0.0.0-8009-3) [193bc72b]
IncreasePendingVms::CreateVmIncreasing vds kvm pending vcpu count,
now 1. Vm: CENTOS6
2012-06-14 18:53:09,024 INFO
[org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand]
(ajp--0.0.0.0-8009-3) [193bc72b] FINISH, CreateVmVDSCommand, return:
WaitForLaunch, log id: 184fd2c
2012-06-14 18:53:10,394 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand]
(QuartzScheduler_Worker-86) START, DestroyVDSCommand(vdsId =
c781df7a-b5f5-11e1-91f8-e791895bd007,
vmId=d3a1600e-9fb9-4d44-9c5e-b6278226ae57, force=false,
secondsToWait=0, gracefully=false), log id: 65045e39
2012-06-14 18:53:10,478 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand]
(QuartzScheduler_Worker-86) FINISH, DestroyVDSCommand, log id:
65045e39
2012-06-14 18:53:10,495 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(QuartzScheduler_Worker-86) Running on vds during rerun failed
vm: null
2012-06-14 18:53:10,499 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(QuartzScheduler_Worker-86) vm CENTOS6 running in db and not
running in vds - add to rerun treatment. vds kvm
2012-06-14 18:53:10,500 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand]
(QuartzScheduler_Worker-86) START, FullListVdsCommand(vdsId =
c781df7a-b5f5-11e1-91f8-e791895bd007, vds=null,
vmIds=[d3a1600e-9fb9-4d44-9c5e-b6278226ae57]), log id: 792606bb
2012-06-14 18:53:10,527 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand]
(QuartzScheduler_Worker-86) FINISH, FullListVdsCommand, return:
[Lorg.ovirt.engine.core.vdsbroker.xmlrpc.XmlRpcStruct;@2f841d20, log
id: 792606bb
2012-06-14 18:53:10,545 ERROR
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(QuartzScheduler_Worker-86) Rerun vm
d3a1600e-9fb9-4d44-9c5e-b6278226ae57. Called from vds kvm
2012-06-14 18:53:10,549 INFO
[org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand]
(pool-3-thread-48) START, UpdateVdsDynamicDataVDSCommand(vdsId =
c781df7a-b5f5-11e1-91f8-e791895bd007,
vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@8df86a4b),
log id: 101af892
2012-06-14 18:53:10,562 INFO
[org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand]
(pool-3-thread-48) FINISH, UpdateVdsDynamicDataVDSCommand, log id:
101af892
2012-06-14 18:53:10,605 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand]
(pool-3-thread-48) START, IsValidVDSCommand(storagePoolId =
b2456911-a25e-4c33-8e46-bae35cc015e7, ignoreFailoverLimit = false,
compatabilityVersion = null), log id: 1d9f68f5
2012-06-14 18:53:10,609 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand]
(pool-3-thread-48) FINISH, IsValidVDSCommand, return: true, log id:
1d9f68f5
2012-06-14 18:53:10,664 INFO
[org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]
(pool-3-thread-48) START, IsVmDuringInitiatingVDSCommand(vmId =
d3a1600e-9fb9-4d44-9c5e-b6278226ae57), log id: 329a94da
2012-06-14 18:53:10,666 INFO
[org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]
(pool-3-thread-48) FINISH, IsVmDuringInitiatingVDSCommand, return:
false, log id: 329a94da
2012-06-14 18:53:10,672 WARN
[org.ovirt.engine.core.bll.RunVmOnceCommand] (pool-3-thread-48)
CanDoAction of action RunVmOnce failed.
Reasons:VAR__ACTION__RUN,VAR__TYPE__VM,VAR__ACTION__RUN,VAR__TYPE__VM,ACTION_TYPE_FAILED_VDS_VM_CLUSTER
The machine doesn't start since the DVD is not there. On the
hypervisor host; /var/log/vdsm/vdsm.log:
<source
file="/rhev/data-center/b2456911-a25e-4c33-8e46-bae35cc015e7/bfb1da46-c417-472a-8905-09e687648b16/images/11111111-1111-1111-1111-111111111111/.CentOS-6.2-x86_64-bin-DVD1.iso"
startupPolicy="optional"/>
Aha, there is an extra <dot> in the filename Centos-62....,
which should not be there! And yeah, creating a symlink with an
extra <dot> does not work....