[ovirt-users] cannot start vm after upgrade to ovirt-4.3

[Moritz Baumann]

After upgrading from 4.2 -> 4.3 I cannot start a vm anymore.
I try to start the vm with run once on a specific node (ovirt-node04) 
and this is the output of /var/log/vdsm/vdsm.log

2019-02-19 12:08:33,626+0100 INFO  (jsonrpc/6) [api.host] START 
getAllVmStats() from=::ffff:129.132.17.194,33924 (api:48)
2019-02-19 12:08:33,631+0100 INFO  (jsonrpc/6) [api.host] FINISH 
getAllVmStats return={'status': {'message': 'Done', 'code': 0}, 
'statsList': (suppressed)} from=::ffff:129.132.17.194,33924 (api:54)
2019-02-19 12:08:33,633+0100 INFO  (jsonrpc/6) [jsonrpc.JsonRpcServer] 
RPC call Host.getAllVmStats succeeded in 0.00 seconds (__init__:312)
2019-02-19 12:08:34,218+0100 INFO  (jsonrpc/4) [api.virt] START 
create(vmParams={u'xml': u'<?xml version="1.0" encoding="UTF-8"?><domain 
type="kvm" xmlns:ovirt-tune="http://ovirt.org/vm/tune/1.0"; 
xmlns:ovirt-vm="http://ovirt.org/vm/1.0";><name>mortalkombat</name><uuid>abee17b9-079e-452c-a97d-99eff951dc39</uuid><memory>2097152</memory><currentMemory>2097152</currentMemory><maxMemory 
slots="16">8388608</maxMemory><vcpu current="1">16</vcpu><sysinfo 
type="smbios"><system><entry name="manufacturer">oVirt</entry><entry 
name="product">OS-NAME:</entry><entry 
name="version">OS-VERSION:</entry><entry 
name="serial">HOST-SERIAL:</entry><entry 
name="uuid">abee17b9-079e-452c-a97d-99eff951dc39</entry></system></sysinfo><clock 
offset="variable" adjustment="0"><timer name="rtc" 
tickpolicy="catchup"></timer><timer name="pit" 
tickpolicy="delay"></timer><timer name="hpet" 
present="no"></timer></clock><features><acpi></acpi></features><cpu 
match="exact"><model>SandyBridge</model><feature name="pcid" 
policy="require"></feature><feature name="spec-ctrl" 
policy="require"></feature><feature name="ssbd" 
policy="require"></feature><topology cores="1" threads="1" 
sockets="16"></topology><numa><cell id="0" cpus="0" 
memory="2097152"></cell></numa></cpu><cputune></cputune><devices><input 
type="mouse" bus="ps2"></input><channel type="unix"><target 
type="virtio" name="ovirt-guest-agent.0"></target><source mode="bind" 
path="/var/lib/libvirt/qemu/channels/abee17b9-079e-452c-a97d-99eff951dc39.ovirt-guest-agent.0"></source></channel><channel 
type="unix"><target type="virtio" 
name="org.qemu.guest_agent.0"></target><source mode="bind" 
path="/var/lib/libvirt/qemu/channels/abee17b9-079e-452c-a97d-99eff951dc39.org.qemu.guest_agent.0"></source></channel><rng 
model="virtio"><backend model="random">/dev/urandom</backend><alias 
name="ua-39b1e011-518c-4ca6-91c8-6ae63cea0824"></alias></rng><video><model 
type="qxl" vram="32768" heads="1" ram="65536" 
vgamem="16384"></model><alias 
name="ua-5de2d61a-2e0e-4194-b023-c1bfcb8f9f4e"></alias><address 
bus="0x00" domain="0x0000" function="0x0" slot="0x02" 
type="pci"></address></video><controller type="virtio-serial" index="0" 
ports="16"><alias 
name="ua-78c78899-4f5b-4217-95eb-d11ebf3b882e"></alias><address 
bus="0x00" domain="0x0000" function="0x0" slot="0x04" 
type="pci"></address></controller><controller type="usb" 
model="piix3-uhci" index="0"><address bus="0x00" domain="0x0000" 
function="0x2" slot="0x01" type="pci"></address></controller><memballoon 
model="virtio"><stats period="5"></stats><alias 
name="ua-94e7e4cb-9b07-4560-bb25-b02ef2b0cdbe"></alias><address 
bus="0x00" domain="0x0000" function="0x0" slot="0x06" 
type="pci"></address></memballoon><graphics type="spice" port="-1" 
autoport="yes" passwd="*****" passwdValidTo="1970-01-01T00:00:01" 
tlsPort="-1"><filetransfer enable="no"></filetransfer><channel 
name="main" mode="secure"></channel><channel name="inputs" 
mode="secure"></channel><channel name="cursor" 
mode="secure"></channel><channel name="playback" 
mode="secure"></channel><channel name="record" 
mode="secure"></channel><channel name="display" 
mode="secure"></channel><channel name="smartcard" 
mode="secure"></channel><channel name="usbredir" 
mode="secure"></channel><listen type="network" 
network="vdsm-ovirtmgmt"></listen></graphics><channel 
type="spicevmc"><target type="virtio" 
name="com.redhat.spice.0"></target></channel><interface 
type="bridge"><model type="virtio"></model><link 
state="up"></link><source bridge="external"></source><alias 
name="ua-b5ce5e56-0a45-4917-b60b-a83edae1c649"></alias><address 
bus="0x00" domain="0x0000" function="0x0" slot="0x03" 
type="pci"></address><boot order="2"></boot><mac 
address="00:1a:4a:1b:a4:01"></mac><mtu size="1500"></mtu><filterref 
filter="vdsm-no-mac-spoofing"></filterref><bandwidth></bandwidth></interface><disk 
type="file" device="cdrom" snapshot="no"><driver name="qemu" type="raw" 
error_policy="report"></driver><source file="" 
startupPolicy="optional"></source><target dev="hdc" 
bus="ide"></target><readonly></readonly><alias 
name="ua-70717d83-9339-426b-b954-0ed01f8d60db"></alias><address bus="1" 
controller="0" unit="0" type="drive" target="0"></address></disk><disk 
snapshot="no" type="file" device="disk"><target dev="vda" 
bus="virtio"></target><source 
file="/rhev/data-center/00000002-0002-0002-0002-0000000003c4/c17d9d7f-e578-4626-a5d9-94ea555d7115/images/ef5893bc-cbe0-4971-8884-a4547a65fcc8/21f73e32-2bd1-46db-a4cd-b0e01416954c"></source><driver 
name="qemu" io="threads" type="raw" error_policy="stop" 
cache="none"></driver><alias 
name="ua-ef5893bc-cbe0-4971-8884-a4547a65fcc8"></alias><boot 
order="1"></boot><serial>ef5893bc-cbe0-4971-8884-a4547a65fcc8</serial></disk><disk 
snapshot="no" type="file" device="disk"><target dev="vdb" 
bus="virtio"></target><source 
file="/rhev/data-center/00000002-0002-0002-0002-0000000003c4/c17d9d7f-e578-4626-a5d9-94ea555d7115/images/9b913d5d-5103-44c8-9053-6a02f5dd3562/482698c2-b1bd-4715-9bc5-e222405260df"></source><driver 
name="qemu" io="threads" type="raw" error_policy="stop" 
cache="none"></driver><alias 
name="ua-9b913d5d-5103-44c8-9053-6a02f5dd3562"></alias><serial>9b913d5d-5103-44c8-9053-6a02f5dd3562</serial></disk></devices><pm><suspend-to-disk 
enabled="no"></suspend-to-disk><suspend-to-mem 
enabled="no"></suspend-to-mem></pm><os><type arch="x86_64" 
machine="pc-i440fx-rhel7.6.0">hvm</type><smbios 
mode="sysinfo"></smbios><bootmenu enable="yes" 
timeout="30000"></bootmenu></os><metadata><ovirt-tune:qos></ovirt-tune:qos><ovirt-vm:vm><ovirt-vm:minGuaranteedMemoryMb 
type="int">2048</ovirt-vm:minGuaranteedMemoryMb><ovirt-vm:clusterVersion>4.3</ovirt-vm:clusterVersion><ovirt-vm:custom></ovirt-vm:custom><ovirt-vm:device 
mac_address="00:1a:4a:1b:a4:01"><ovirt-vm:custom></ovirt-vm:custom></ovirt-vm:device><ovirt-vm:device 
devtype="disk" 
name="vda"><ovirt-vm:poolID>00000002-0002-0002-0002-0000000003c4</ovirt-vm:poolID><ovirt-vm:volumeID>21f73e32-2bd1-46db-a4cd-b0e01416954c</ovirt-vm:volumeID><ovirt-vm:imageID>ef5893bc-cbe0-4971-8884-a4547a65fcc8</ovirt-vm:imageID><ovirt-vm:domainID>c17d9d7f-e578-4626-a5d9-94ea555d7115</ovirt-vm:domainID></ovirt-vm:device><ovirt-vm:device 
devtype="disk" 
name="vdb"><ovirt-vm:poolID>00000002-0002-0002-0002-0000000003c4</ovirt-vm:poolID><ovirt-vm:volumeID>482698c2-b1bd-4715-9bc5-e222405260df</ovirt-vm:volumeID><ovirt-vm:imageID>9b913d5d-5103-44c8-9053-6a02f5dd3562</ovirt-vm:imageID><ovirt-vm:domainID>c17d9d7f-e578-4626-a5d9-94ea555d7115</ovirt-vm:domainID></ovirt-vm:device><ovirt-vm:launchPaused>false</ovirt-vm:launchPaused><ovirt-vm:resumeBehavior>auto_resume</ovirt-vm:resumeBehavior></ovirt-vm:vm></metadata></domain>'}) 
from=::ffff:129.132.17.194,33924, 
flow_id=978c7200-b51e-42f8-962d-e8e11383ee53, vmId= (api:48)
2019-02-19 12:08:34,255+0100 INFO  (jsonrpc/4) [api.virt] FINISH create 
return={'status': {'message': 'Done', 'code': 0}, 'vmList': {'status': 
'WaitForLaunch', 'maxMemSize': 8192, 'acpiEnable': 'true', 
'emulatedMachine': 'pc-i440fx-rhel7.6.0', 'vmId': 
'abee17b9-079e-452c-a97d-99eff951dc39', 'memGuaranteedSize': 2048, 
'timeOffset': '0', 'smpThreadsPerCore': '1', 'cpuType': 'SandyBridge', 
'guestDiskMapping': {}, 'arch': 'x86_64', 'smp': '1', 'guestNumaNodes': 
[{'nodeIndex': 0, 'cpus': '0', 'memory': '2048'}], u'xml': u'<?xml 
version="1.0" encoding="UTF-8"?><domain type="kvm" 
xmlns:ovirt-tune="http://ovirt.org/vm/tune/1.0"; 
xmlns:ovirt-vm="http://ovirt.org/vm/1.0";><name>mortalkombat</name><uuid>abee17b9-079e-452c-a97d-99eff951dc39</uuid><memory>2097152</memory><currentMemory>2097152</currentMemory><maxMemory 
slots="16">8388608</maxMemory><vcpu current="1">16</vcpu><sysinfo 
type="smbios"><system><entry name="manufacturer">oVirt</entry><entry 
name="product">OS-NAME:</entry><entry 
name="version">OS-VERSION:</entry><entry 
name="serial">HOST-SERIAL:</entry><entry 
name="uuid">abee17b9-079e-452c-a97d-99eff951dc39</entry></system></sysinfo><clock 
offset="variable" adjustment="0"><timer name="rtc" 
tickpolicy="catchup"></timer><timer name="pit" 
tickpolicy="delay"></timer><timer name="hpet" 
present="no"></timer></clock><features><acpi></acpi></features><cpu 
match="exact"><model>SandyBridge</model><feature name="pcid" 
policy="require"></feature><feature name="spec-ctrl" 
policy="require"></feature><feature name="ssbd" 
policy="require"></feature><topology cores="1" threads="1" 
sockets="16"></topology><numa><cell id="0" cpus="0" 
memory="2097152"></cell></numa></cpu><cputune></cputune><devices><input 
type="mouse" bus="ps2"></input><channel type="unix"><target 
type="virtio" name="ovirt-guest-agent.0"></target><source mode="bind" 
path="/var/lib/libvirt/qemu/channels/abee17b9-079e-452c-a97d-99eff951dc39.ovirt-guest-agent.0"></source></channel><channel 
type="unix"><target type="virtio" 
name="org.qemu.guest_agent.0"></target><source mode="bind" 
path="/var/lib/libvirt/qemu/channels/abee17b9-079e-452c-a97d-99eff951dc39.org.qemu.guest_agent.0"></source></channel><rng 
model="virtio"><backend model="random">/dev/urandom</backend><alias 
name="ua-39b1e011-518c-4ca6-91c8-6ae63cea0824"></alias></rng><video><model 
type="qxl" vram="32768" heads="1" ram="65536" 
vgamem="16384"></model><alias 
name="ua-5de2d61a-2e0e-4194-b023-c1bfcb8f9f4e"></alias><address 
bus="0x00" domain="0x0000" function="0x0" slot="0x02" 
type="pci"></address></video><controller type="virtio-serial" index="0" 
ports="16"><alias 
name="ua-78c78899-4f5b-4217-95eb-d11ebf3b882e"></alias><address 
bus="0x00" domain="0x0000" function="0x0" slot="0x04" 
type="pci"></address></controller><controller type="usb" 
model="piix3-uhci" index="0"><address bus="0x00" domain="0x0000" 
function="0x2" slot="0x01" type="pci"></address></controller><memballoon 
model="virtio"><stats period="5"></stats><alias 
name="ua-94e7e4cb-9b07-4560-bb25-b02ef2b0cdbe"></alias><address 
bus="0x00" domain="0x0000" function="0x0" slot="0x06" 
type="pci"></address></memballoon><graphics type="spice" port="-1" 
autoport="yes" passwd="*****" passwdValidTo="1970-01-01T00:00:01" 
tlsPort="-1"><filetransfer enable="no"></filetransfer><channel 
name="main" mode="secure"></channel><channel name="inputs" 
mode="secure"></channel><channel name="cursor" 
mode="secure"></channel><channel name="playback" 
mode="secure"></channel><channel name="record" 
mode="secure"></channel><channel name="display" 
mode="secure"></channel><channel name="smartcard" 
mode="secure"></channel><channel name="usbredir" 
mode="secure"></channel><listen type="network" 
network="vdsm-ovirtmgmt"></listen></graphics><channel 
type="spicevmc"><target type="virtio" 
name="com.redhat.spice.0"></target></channel><interface 
type="bridge"><model type="virtio"></model><link 
state="up"></link><source bridge="external"></source><alias 
name="ua-b5ce5e56-0a45-4917-b60b-a83edae1c649"></alias><address 
bus="0x00" domain="0x0000" function="0x0" slot="0x03" 
type="pci"></address><boot order="2"></boot><mac 
address="00:1a:4a:1b:a4:01"></mac><mtu size="1500"></mtu><filterref 
filter="vdsm-no-mac-spoofing"></filterref><bandwidth></bandwidth></interface><disk 
type="file" device="cdrom" snapshot="no"><driver name="qemu" type="raw" 
error_policy="report"></driver><source file="" 
startupPolicy="optional"></source><target dev="hdc" 
bus="ide"></target><readonly></readonly><alias 
name="ua-70717d83-9339-426b-b954-0ed01f8d60db"></alias><address bus="1" 
controller="0" unit="0" type="drive" target="0"></address></disk><disk 
snapshot="no" type="file" device="disk"><target dev="vda" 
bus="virtio"></target><source 
file="/rhev/data-center/00000002-0002-0002-0002-0000000003c4/c17d9d7f-e578-4626-a5d9-94ea555d7115/images/ef5893bc-cbe0-4971-8884-a4547a65fcc8/21f73e32-2bd1-46db-a4cd-b0e01416954c"></source><driver 
name="qemu" io="threads" type="raw" error_policy="stop" 
cache="none"></driver><alias 
name="ua-ef5893bc-cbe0-4971-8884-a4547a65fcc8"></alias><boot 
order="1"></boot><serial>ef5893bc-cbe0-4971-8884-a4547a65fcc8</serial></disk><disk 
snapshot="no" type="file" device="disk"><target dev="vdb" 
bus="virtio"></target><source 
file="/rhev/data-center/00000002-0002-0002-0002-0000000003c4/c17d9d7f-e578-4626-a5d9-94ea555d7115/images/9b913d5d-5103-44c8-9053-6a02f5dd3562/482698c2-b1bd-4715-9bc5-e222405260df"></source><driver 
name="qemu" io="threads" type="raw" error_policy="stop" 
cache="none"></driver><alias 
name="ua-9b913d5d-5103-44c8-9053-6a02f5dd3562"></alias><serial>9b913d5d-5103-44c8-9053-6a02f5dd3562</serial></disk></devices><pm><suspend-to-disk 
enabled="no"></suspend-to-disk><suspend-to-mem 
enabled="no"></suspend-to-mem></pm><os><type arch="x86_64" 
machine="pc-i440fx-rhel7.6.0">hvm</type><smbios 
mode="sysinfo"></smbios><bootmenu enable="yes" 
timeout="30000"></bootmenu></os><metadata><ovirt-tune:qos></ovirt-tune:qos><ovirt-vm:vm><ovirt-vm:minGuaranteedMemoryMb 
type="int">2048</ovirt-vm:minGuaranteedMemoryMb><ovirt-vm:clusterVersion>4.3</ovirt-vm:clusterVersion><ovirt-vm:custom></ovirt-vm:custom><ovirt-vm:device 
mac_address="00:1a:4a:1b:a4:01"><ovirt-vm:custom></ovirt-vm:custom></ovirt-vm:device><ovirt-vm:device 
devtype="disk" 
name="vda"><ovirt-vm:poolID>00000002-0002-0002-0002-0000000003c4</ovirt-vm:poolID><ovirt-vm:volumeID>21f73e32-2bd1-46db-a4cd-b0e01416954c</ovirt-vm:volumeID><ovirt-vm:imageID>ef5893bc-cbe0-4971-8884-a4547a65fcc8</ovirt-vm:imageID><ovirt-vm:domainID>c17d9d7f-e578-4626-a5d9-94ea555d7115</ovirt-vm:domainID></ovirt-vm:device><ovirt-vm:device 
devtype="disk" 
name="vdb"><ovirt-vm:poolID>00000002-0002-0002-0002-0000000003c4</ovirt-vm:poolID><ovirt-vm:volumeID>482698c2-b1bd-4715-9bc5-e222405260df</ovirt-vm:volumeID><ovirt-vm:imageID>9b913d5d-5103-44c8-9053-6a02f5dd3562</ovirt-vm:imageID><ovirt-vm:domainID>c17d9d7f-e578-4626-a5d9-94ea555d7115</ovirt-vm:domainID></ovirt-vm:device><ovirt-vm:launchPaused>false</ovirt-vm:launchPaused><ovirt-vm:resumeBehavior>auto_resume</ovirt-vm:resumeBehavior></ovirt-vm:vm></metadata></domain>', 
'smpCoresPerSocket': '1', 'kvmEnable': 'true', 'bootMenuEnable': 'true', 
'devices': [], 'custom': {}, 'maxVCpus': '16', 'statusTime': 
'4357298760', 'vmName': 'mortalkombat', 'maxMemSlots': 16}} 
from=::ffff:129.132.17.194,33924, 
flow_id=978c7200-b51e-42f8-962d-e8e11383ee53, vmId= (api:54)
2019-02-19 12:08:34,256+0100 INFO  (jsonrpc/4) [jsonrpc.JsonRpcServer] 
RPC call VM.create succeeded in 0.04 seconds (__init__:312)
2019-02-19 12:08:34,257+0100 INFO  (vm/abee17b9) [virt.vm] 
(vmId='abee17b9-079e-452c-a97d-99eff951dc39') VM wrapper has started 
(vm:2736)
2019-02-19 12:08:34,273+0100 INFO  (vm/abee17b9) [vdsm.api] START 
getVolumeSize(sdUUID='c17d9d7f-e578-4626-a5d9-94ea555d7115', 
spUUID='00000002-0002-0002-0002-0000000003c4', 
imgUUID='ef5893bc-cbe0-4971-8884-a4547a65fcc8', 
volUUID='21f73e32-2bd1-46db-a4cd-b0e01416954c', options=None) 
from=internal, task_id=e0a3dd79-a36a-4ce2-97ac-d9b3f32cc81f (api:48)
2019-02-19 12:08:34,275+0100 INFO  (vm/abee17b9) [vdsm.api] FINISH 
getVolumeSize return={'truesize': '10631581696', 'apparentsize': 
'21474836480'} from=internal, 
task_id=e0a3dd79-a36a-4ce2-97ac-d9b3f32cc81f (api:54)
2019-02-19 12:08:34,275+0100 INFO  (vm/abee17b9) [vdsm.api] START 
getVolumeSize(sdUUID='c17d9d7f-e578-4626-a5d9-94ea555d7115', 
spUUID='00000002-0002-0002-0002-0000000003c4', 
imgUUID='9b913d5d-5103-44c8-9053-6a02f5dd3562', 
volUUID='482698c2-b1bd-4715-9bc5-e222405260df', options=None) 
from=internal, task_id=7337d2b1-0c2e-48c6-8e12-82d3d7313652 (api:48)
2019-02-19 12:08:34,277+0100 INFO  (vm/abee17b9) [vdsm.api] FINISH 
getVolumeSize return={'truesize': '102303330304', 'apparentsize': 
'107374182400'} from=internal, 
task_id=7337d2b1-0c2e-48c6-8e12-82d3d7313652 (api:54)
2019-02-19 12:08:34,277+0100 INFO  (vm/abee17b9) [vds] prepared volume 
path:  (clientIF:499)
2019-02-19 12:08:34,277+0100 INFO  (vm/abee17b9) [vdsm.api] START 
prepareImage(sdUUID='c17d9d7f-e578-4626-a5d9-94ea555d7115', 
spUUID='00000002-0002-0002-0002-0000000003c4', 
imgUUID='ef5893bc-cbe0-4971-8884-a4547a65fcc8', 
leafUUID='21f73e32-2bd1-46db-a4cd-b0e01416954c', allowIllegal=False) 
from=internal, task_id=e8780fff-1def-439c-854c-62478ccf6176 (api:48)
2019-02-19 12:08:34,302+0100 INFO  (vm/abee17b9) [storage.StorageDomain] 
Fixing permissions on 
/rhev/data-center/mnt/scratch-inf.inf.ethz.ch:_export_scratch_ovirt_data/c17d9d7f-e578-4626-a5d9-94ea555d7115/images/ef5893bc-cbe0-4971-8884-a4547a65fcc8/21f73e32-2bd1-46db-a4cd-b0e01416954c 
(fileSD:622)
2019-02-19 12:08:34,302+0100 INFO  (vm/abee17b9) [storage.StorageDomain] 
Creating domain run directory 
u'/var/run/vdsm/storage/c17d9d7f-e578-4626-a5d9-94ea555d7115' (fileSD:576)
2019-02-19 12:08:34,302+0100 INFO  (vm/abee17b9) [storage.fileUtils] 
Creating directory: 
/var/run/vdsm/storage/c17d9d7f-e578-4626-a5d9-94ea555d7115 mode: None 
(fileUtils:199)
2019-02-19 12:08:34,303+0100 INFO  (vm/abee17b9) [storage.StorageDomain] 
Creating symlink from 
/rhev/data-center/mnt/scratch-inf.inf.ethz.ch:_export_scratch_ovirt_data/c17d9d7f-e578-4626-a5d9-94ea555d7115/images/ef5893bc-cbe0-4971-8884-a4547a65fcc8 
to 
/var/run/vdsm/storage/c17d9d7f-e578-4626-a5d9-94ea555d7115/ef5893bc-cbe0-4971-8884-a4547a65fcc8 
(fileSD:579)
2019-02-19 12:08:34,306+0100 INFO  (vm/abee17b9) [vdsm.api] FINISH 
prepareImage return={'info': {'path': 
u'/rhev/data-center/mnt/scratch-inf.inf.ethz.ch:_export_scratch_ovirt_data/c17d9d7f-e578-4626-a5d9-94ea555d7115/images/ef5893bc-cbe0-4971-8884-a4547a65fcc8/21f73e32-2bd1-46db-a4cd-b0e01416954c', 
'type': 'file'}, 'path': 
u'/rhev/data-center/mnt/scratch-inf.inf.ethz.ch:_export_scratch_ovirt_data/c17d9d7f-e578-4626-a5d9-94ea555d7115/images/ef5893bc-cbe0-4971-8884-a4547a65fcc8/21f73e32-2bd1-46db-a4cd-b0e01416954c', 
'imgVolumesInfo': [{'domainID': 'c17d9d7f-e578-4626-a5d9-94ea555d7115', 
'leaseOffset': 0, 'path': 
u'/rhev/data-center/mnt/scratch-inf.inf.ethz.ch:_export_scratch_ovirt_data/c17d9d7f-e578-4626-a5d9-94ea555d7115/images/ef5893bc-cbe0-4971-8884-a4547a65fcc8/21f73e32-2bd1-46db-a4cd-b0e01416954c', 
'volumeID': u'21f73e32-2bd1-46db-a4cd-b0e01416954c', 'leasePath': 
u'/rhev/data-center/mnt/scratch-inf.inf.ethz.ch:_export_scratch_ovirt_data/c17d9d7f-e578-4626-a5d9-94ea555d7115/images/ef5893bc-cbe0-4971-8884-a4547a65fcc8/21f73e32-2bd1-46db-a4cd-b0e01416954c.lease', 
'imageID': 'ef5893bc-cbe0-4971-8884-a4547a65fcc8'}]} from=internal, 
task_id=e8780fff-1def-439c-854c-62478ccf6176 (api:54)
2019-02-19 12:08:34,306+0100 INFO  (vm/abee17b9) [vds] prepared volume 
path: 
/rhev/data-center/mnt/scratch-inf.inf.ethz.ch:_export_scratch_ovirt_data/c17d9d7f-e578-4626-a5d9-94ea555d7115/images/ef5893bc-cbe0-4971-8884-a4547a65fcc8/21f73e32-2bd1-46db-a4cd-b0e01416954c 
(clientIF:499)
2019-02-19 12:08:34,307+0100 INFO  (vm/abee17b9) [vdsm.api] START 
prepareImage(sdUUID='c17d9d7f-e578-4626-a5d9-94ea555d7115', 
spUUID='00000002-0002-0002-0002-0000000003c4', 
imgUUID='9b913d5d-5103-44c8-9053-6a02f5dd3562', 
leafUUID='482698c2-b1bd-4715-9bc5-e222405260df', allowIllegal=False) 
from=internal, task_id=d04f3abb-f3d3-4e2f-902f-d3c5e4fabc36 (api:48)
2019-02-19 12:08:34,322+0100 INFO  (vm/abee17b9) [vdsm.api] FINISH 
prepareImage error=Volume does not exist: 
(u'482698c2-b1bd-4715-9bc5-e222405260df',) from=internal, 
task_id=d04f3abb-f3d3-4e2f-902f-d3c5e4fabc36 (api:52)
2019-02-19 12:08:34,322+0100 ERROR (vm/abee17b9) 
[storage.TaskManager.Task] (Task='d04f3abb-f3d3-4e2f-902f-d3c5e4fabc36') 
Unexpected error (task:875)
Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/vdsm/storage/task.py", line 
882, in _run
    return fn(*args, **kargs)
  File "<string>", line 2, in prepareImage
  File "/usr/lib/python2.7/site-packages/vdsm/common/api.py", line 50, 
in method
    ret = func(*args, **kwargs)
  File "/usr/lib/python2.7/site-packages/vdsm/storage/hsm.py", line 
3198, in prepareImage
    legality = dom.produceVolume(imgUUID, volUUID).getLegality()
  File "/usr/lib/python2.7/site-packages/vdsm/storage/sd.py", line 818, 
in produceVolume
    volUUID)
  File "/usr/lib/python2.7/site-packages/vdsm/storage/volume.py", line 
800, in __init__
    self._manifest = self.manifestClass(repoPath, sdUUID, imgUUID, volUUID)
  File "/usr/lib/python2.7/site-packages/vdsm/storage/fileVolume.py", 
line 71, in __init__
    volUUID)
  File "/usr/lib/python2.7/site-packages/vdsm/storage/volume.py", line 
86, in __init__
    self.validate()
  File "/usr/lib/python2.7/site-packages/vdsm/storage/volume.py", line 
112, in validate
    self.validateVolumePath()
  File "/usr/lib/python2.7/site-packages/vdsm/storage/fileVolume.py", 
line 131, in validateVolumePath
    raise se.VolumeDoesNotExist(self.volUUID)
VolumeDoesNotExist: Volume does not exist: 
(u'482698c2-b1bd-4715-9bc5-e222405260df',)
2019-02-19 12:08:34,322+0100 INFO  (vm/abee17b9) 
[storage.TaskManager.Task] (Task='d04f3abb-f3d3-4e2f-902f-d3c5e4fabc36') 
aborting: Task is aborted: "Volume does not exist: 
(u'482698c2-b1bd-4715-9bc5-e222405260df',)" - code 201 (task:1181)
2019-02-19 12:08:34,322+0100 ERROR (vm/abee17b9) [storage.Dispatcher] 
FINISH prepareImage error=Volume does not exist: 
(u'482698c2-b1bd-4715-9bc5-e222405260df',) (dispatcher:81)
2019-02-19 12:08:34,322+0100 ERROR (vm/abee17b9) [virt.vm] 
(vmId='abee17b9-079e-452c-a97d-99eff951dc39') The vm start process 
failed (vm:937)
Traceback (most recent call last):
  File "/usr/lib/python2.7/site-packages/vdsm/virt/vm.py", line 866, in 
_startUnderlyingVm
    self._run()
  File "/usr/lib/python2.7/site-packages/vdsm/virt/vm.py", line 2749, 
in _run
    self._devices = self._make_devices()
  File "/usr/lib/python2.7/site-packages/vdsm/virt/vm.py", line 2589, 
in _make_devices
    disk_objs = self._perform_host_local_adjustment()
  File "/usr/lib/python2.7/site-packages/vdsm/virt/vm.py", line 2662, 
in _perform_host_local_adjustment
    self._preparePathsForDrives(disk_params)
  File "/usr/lib/python2.7/site-packages/vdsm/virt/vm.py", line 1011, 
in _preparePathsForDrives
    drive['path'] = self.cif.prepareVolumePath(drive, self.id)
  File "/usr/lib/python2.7/site-packages/vdsm/clientIF.py", line 415, 
in prepareVolumePath
    raise vm.VolumeError(drive)
VolumeError: Bad volume specification {'index': 1, 'domainID': 
'c17d9d7f-e578-4626-a5d9-94ea555d7115', 'reqsize': '0', 'name': 'vdb', 
'truesize': '102303330304', 'alias': 
'ua-9b913d5d-5103-44c8-9053-6a02f5dd3562', 'format': 'raw', 'discard': 
False, 'volumeID': '482698c2-b1bd-4715-9bc5-e222405260df', 
'apparentsize': '107374182400', 'diskType': 'file', 'specParams': {}, 
'iface': 'virtio', 'cache': 'none', 'propagateErrors': 'off', 'poolID': 
'00000002-0002-0002-0002-0000000003c4', 'device': 'disk', 'path': 
'/rhev/data-center/00000002-0002-0002-0002-0000000003c4/c17d9d7f-e578-4626-a5d9-94ea555d7115/images/9b913d5d-5103-44c8-9053-6a02f5dd3562/482698c2-b1bd-4715-9bc5-e222405260df', 
'serial': '9b913d5d-5103-44c8-9053-6a02f5dd3562', 'imageID': 
'9b913d5d-5103-44c8-9053-6a02f5dd3562', 'type': 'disk'}
2019-02-19 12:08:34,322+0100 INFO  (vm/abee17b9) [virt.vm] 
(vmId='abee17b9-079e-452c-a97d-99eff951dc39') Changed state to Down: Bad 
volume specification {'index': 1, 'domainID': 
'c17d9d7f-e578-4626-a5d9-94ea555d7115', 'reqsize': '0', 'name': 'vdb', 
'truesize': '102303330304', 'alias': 
'ua-9b913d5d-5103-44c8-9053-6a02f5dd3562', 'format': 'raw', 'discard': 
False, 'volumeID': '482698c2-b1bd-4715-9bc5-e222405260df', 
'apparentsize': '107374182400', 'diskType': 'file', 'specParams': {}, 
'iface': 'virtio', 'cache': 'none', 'propagateErrors': 'off', 'poolID': 
'00000002-0002-0002-0002-0000000003c4', 'device': 'disk', 'path': 
'/rhev/data-center/00000002-0002-0002-0002-0000000003c4/c17d9d7f-e578-4626-a5d9-94ea555d7115/images/9b913d5d-5103-44c8-9053-6a02f5dd3562/482698c2-b1bd-4715-9bc5-e222405260df', 
'serial': '9b913d5d-5103-44c8-9053-6a02f5dd3562', 'imageID': 
'9b913d5d-5103-44c8-9053-6a02f5dd3562', 'type': 'disk'} (code=1) (vm:1675)
2019-02-19 12:08:34,326+0100 INFO  (vm/abee17b9) [virt.vm] 
(vmId='abee17b9-079e-452c-a97d-99eff951dc39') Stopping connection 
(guestagent:455)
2019-02-19 12:08:34,330+0100 INFO  (jsonrpc/5) [api.virt] START 
destroy(gracefulAttempts=1) from=::ffff:129.132.17.194,33924, 
vmId=abee17b9-079e-452c-a97d-99eff951dc39 (api:48)
2019-02-19 12:08:34,331+0100 INFO  (jsonrpc/5) [virt.vm] 
(vmId='abee17b9-079e-452c-a97d-99eff951dc39') Release VM resources (vm:5127)
2019-02-19 12:08:34,331+0100 WARN  (jsonrpc/5) [virt.vm] 
(vmId='abee17b9-079e-452c-a97d-99eff951dc39') trying to set state to 
Powering down when already Down (vm:624)
2019-02-19 12:08:34,331+0100 INFO  (jsonrpc/5) [virt.vm] 
(vmId='abee17b9-079e-452c-a97d-99eff951dc39') Stopping connection 
(guestagent:455)
2019-02-19 12:08:34,331+0100 INFO  (jsonrpc/5) [virt.vm] 
(vmId='abee17b9-079e-452c-a97d-99eff951dc39') Stopping connection 
(guestagent:455)
2019-02-19 12:08:34,331+0100 WARN  (jsonrpc/5) [root] File: 
/var/lib/libvirt/qemu/channels/abee17b9-079e-452c-a97d-99eff951dc39.ovirt-guest-agent.0 
already removed (fileutils:54)
2019-02-19 12:08:34,332+0100 WARN  (jsonrpc/5) [root] File: 
/var/lib/libvirt/qemu/channels/abee17b9-079e-452c-a97d-99eff951dc39.org.qemu.guest_agent.0 
already removed (fileutils:54)
2019-02-19 12:08:34,332+0100 WARN  (jsonrpc/5) [virt.vm] 
(vmId='abee17b9-079e-452c-a97d-99eff951dc39') timestamp already removed 
from stats cache (vm:2395)
2019-02-19 12:08:34,332+0100 INFO  (jsonrpc/5) [vdsm.api] START 
inappropriateDevices(thiefId='abee17b9-079e-452c-a97d-99eff951dc39') 
from=::ffff:129.132.17.194,33924, 
task_id=9474f7a3-9938-464e-ad08-ba61c6c528c9 (api:48)
2019-02-19 12:08:34,333+0100 INFO  (jsonrpc/5) [vdsm.api] FINISH 
inappropriateDevices return=None from=::ffff:129.132.17.194,33924, 
task_id=9474f7a3-9938-464e-ad08-ba61c6c528c9 (api:54)
2019-02-19 12:08:34,522+0100 INFO  (jsonrpc/5) [root] 
/usr/libexec/vdsm/hooks/after_vm_destroy/50_vhostmd: rc=0 err= (hooks:114)
2019-02-19 12:08:34,656+0100 INFO  (jsonrpc/5) [root] 
/usr/libexec/vdsm/hooks/after_vm_destroy/delete_vhostuserclient_hook: 
rc=0 err= (hooks:114)
2019-02-19 12:08:34,657+0100 INFO  (jsonrpc/5) [api.virt] FINISH destroy 
return={'status': {'message': 'Machine destroyed', 'code': 0}} 
from=::ffff:129.132.17.194,33924, 
vmId=abee17b9-079e-452c-a97d-99eff951dc39 (api:54)
2019-02-19 12:08:34,657+0100 INFO  (jsonrpc/5) [jsonrpc.JsonRpcServer] 
RPC call VM.destroy succeeded in 0.33 seconds (__init__:312)
_______________________________________________
Users mailing list -- users@ovirt.org
To unsubscribe send an email to users-le...@ovirt.org
Privacy Statement: https://www.ovirt.org/site/privacy-policy/
oVirt Code of Conduct: 
https://www.ovirt.org/community/about/community-guidelines/
List Archives: 
https://lists.ovirt.org/archives/list/users@ovirt.org/message/Q4D3IJ4YZ2HSKNMT4OTBHALQKYVPO2PZ/