5:09 a.m.
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>mortalk...
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>mortalk...
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)