<span style='font-family:Verdana'><span style='font-size:12px'>Hello,<br /><br />I'm running in troubles.....<br />After migrating the storage of an powered off VM to a different iSCSI storage, my CentOS 4 VM does not start any more (well, it's not the reality, but for the system the VM stays in Wait for launch for hours). In reality, the VM starts on the node.<br /><br />Any hints?<br /><br />My Config:<br />CentOS 6.4<br />oVirt 3.3.1-2<br />VDSM 4.12.1-4<br /><br />engine.log<br /><br />2013-11-26 14:58:50,171 INFO&nbsp; [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-46) Setting new tasks map. The map contains now 0 tasks<br />2013-11-26 14:58:50,172 INFO&nbsp; [org.ovirt.engine.core.bll.AsyncTaskManager] (DefaultQuartzScheduler_Worker-46) Cleared all tasks of pool 5849b030-626e-47cb-ad90-3ce782d831b3.<br />2013-11-26 14:58:52,106 INFO&nbsp; [org.ovirt.engine.core.bll.RunVmCommand] (ajp--127.0.0.1-8702-2) [574ec4d2] Lock Acquired to object EngineLock [exclusiveLocks= key: 8798d0da-a82c-4bcf-b41a-7e3fe1c613ef value: VM<br />, sharedLocks= ]<br />2013-11-26 14:58:52,124 INFO&nbsp; [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-2) [574ec4d2] START, IsVmDuringInitiatingVDSCommand( vmId = 8798d0da-a82c-4bcf-b41a-7e3fe1c613ef), log id: 13e7aadf<br />2013-11-26 14:58:52,125 INFO&nbsp; [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp--127.0.0.1-8702-2) [574ec4d2] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 13e7aadf<br />2013-11-26 14:58:52,251 INFO&nbsp; [org.ovirt.engine.core.bll.RunVmCommand] (pool-6-thread-50) [574ec4d2] Running command: RunVmCommand internal: false. Entities affected :&nbsp; ID: 8798d0da-a82c-4bcf-b41a-7e3fe1c613ef Type: VM<br />2013-11-26 14:58:52,406 INFO&nbsp; [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (pool-6-thread-50) [574ec4d2] START, IsoPrefixVDSCommand(HostName = HOST01, HostId = b34d1e33-bdda-4ba8-9c23-0d136f372ad8, storagePoolId=5849b030-626e-47cb-ad90-3ce782d831b3), log id: 1a6a3b22<br />2013-11-26 14:58:52,408 INFO&nbsp; [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (pool-6-thread-50) [574ec4d2] FINISH, IsoPrefixVDSCommand, return: /rhev/data-center/mnt/engine.private.com:_var_lib_exports_iso/53a22027-fd2d-451b-8cd7-0e604fb8e460/images/11111111-1111-1111-1111-111111111111, log id: 1a6a3b22<br />2013-11-26 14:58:52,441 INFO&nbsp; [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-6-thread-50) [574ec4d2] START, CreateVmVDSCommand(HostName = HOST01, HostId = b34d1e33-bdda-4ba8-9c23-0d136f372ad8, vmId=8798d0da-a82c-4bcf-b41a-7e3fe1c613ef, vm=VM [vm01]), log id: 2da012df<br />2013-11-26 14:58:52,476 INFO&nbsp; [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-6-thread-50) [574ec4d2] START, CreateVDSCommand(HostName = HOST01, HostId = b34d1e33-bdda-4ba8-9c23-0d136f372ad8, vmId=8798d0da-a82c-4bcf-b41a-7e3fe1c613ef, vm=VM [vm01]), log id: 727a7bf7<br />2013-11-26 14:58:52,539 INFO&nbsp; [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-6-thread-50) [574ec4d2] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand spiceSslCipherSuite=DEFAULT,memSize=1024,kvmEnable=true,smp=1,vmType=kvm,emulatedMachine=rhel6.4.0,keyboardLayout=de,memGuaranteedSize=512,pitReinjection=false,nice=0,display=vnc,smartcardEnable=false,tabletEnable=true,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,timeOffset=1,transparentHugePages=true,vmId=8798d0da-a82c-4bcf-b41a-7e3fe1c613ef,devices=[Ljava.util.HashMap;@1030ba06,acpiEnable=true,vmName=vm01,cpuType=Nehalem,custom={device_99b583e9-9030-40af-bb41-55d62a4a6b6adevice_5b94ca77-fab8-41b4-b286-311dee724813=VmDevice {vmId=8798d0da-a82c-4bcf-b41a-7e3fe1c613ef, deviceId=5b94ca77-fab8-41b4-b286-311dee724813, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=2, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel1, customProperties={}}, device_99b583e9-9030-40af-bb41-55d62a4a6b6a=VmDevice {vmId=8798d0da-a82c-4bcf-b41a-7e3fe1c613ef, deviceId=99b583e9-9030-40af-bb41-55d62a4a6b6a, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=1, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel0, customProperties={}}, device_99b583e9-9030-40af-bb41-55d62a4a6b6adevice_5b94ca77-fab8-41b4-b286-311dee724813device_c3d8dcbf-d977-4d4d-8240-35b973ee00b9device_839371b0-1967-4435-910e-0d8e1c980850=VmDevice {vmId=8798d0da-a82c-4bcf-b41a-7e3fe1c613ef, deviceId=839371b0-1967-4435-910e-0d8e1c980850, device=ide, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x01, function=0x1}, managed=false, plugged=true, readOnly=false, deviceAlias=ide0, customProperties={}}, device_99b583e9-9030-40af-bb41-55d62a4a6b6adevice_5b94ca77-fab8-41b4-b286-311dee724813device_c3d8dcbf-d977-4d4d-8240-35b973ee00b9=VmDevice {vmId=8798d0da-a82c-4bcf-b41a-7e3fe1c613ef, deviceId=c3d8dcbf-d977-4d4d-8240-35b973ee00b9, device=scsi, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x04, function=0x0}, managed=false, plugged=true, readOnly=false, deviceAlias=scsi0, customProperties={}}, device_99b583e9-9030-40af-bb41-55d62a4a6b6adevice_5b94ca77-fab8-41b4-b286-311dee724813device_c3d8dcbf-d977-4d4d-8240-35b973ee00b9device_839371b0-1967-4435-910e-0d8e1c980850device_920282ca-79c2-4abf-b1f2-7425f16f7820=VmDevice {vmId=8798d0da-a82c-4bcf-b41a-7e3fe1c613ef, deviceId=920282ca-79c2-4abf-b1f2-7425f16f7820, device=virtio-serial, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x06, function=0x0}, managed=false, plugged=true, readOnly=false, deviceAlias=virtio-serial0, customProperties={}}}<br />2013-11-26 14:58:52,552 INFO&nbsp; [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-6-thread-50) [574ec4d2] FINISH, CreateVDSCommand, log id: 727a7bf7<br />2013-11-26 14:58:52,567 INFO&nbsp; [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-6-thread-50) [574ec4d2] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 2da012df<br />2013-11-26 14:58:52,568 INFO&nbsp; [org.ovirt.engine.core.bll.RunVmCommand] (pool-6-thread-50) [574ec4d2] Lock freed to object EngineLock [exclusiveLocks= key: 8798d0da-a82c-4bcf-b41a-7e3fe1c613ef value: VM<br />, sharedLocks= ]<br />2013-11-26 14:58:52,576 INFO&nbsp; [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (pool-6-thread-50) [574ec4d2] Correlation ID: 574ec4d2, Job ID: d3d270e1-607b-4599-983a-1ef884e81a43, Call Stack: null, Custom Event ID: -1, Message: VM vm01 was started by User01 (Host: HOST01).<br />2013-11-26 14:58:55,177 WARN&nbsp; [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-33) Failed to refresh VDS , vds = b34d1e33-bdda-4ba8-9c23-0d136f372ad8 : HOST01, error = java.lang.NullPointerException, continuing.: java.lang.NullPointerException<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; at org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo.proceedBalloonCheck(VdsUpdateRunTimeInfo.java:1312) [vdsbroker.jar:]<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; at org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo.refreshVmStats(VdsUpdateRunTimeInfo.java:926) [vdsbroker.jar:]<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; at org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo.refreshVdsRunTimeInfo(VdsUpdateRunTimeInfo.java:502) [vdsbroker.jar:]<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; at org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo.Refresh(VdsUpdateRunTimeInfo.java:343) [vdsbroker.jar:]<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; at org.ovirt.engine.core.vdsbroker.VdsManager.OnTimer(VdsManager.java:237) [vdsbroker.jar:]<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; at sun.reflect.GeneratedMethodAccessor9.invoke(Unknown Source) [:1.7.0_45]<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_45]<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; at java.lang.reflect.Method.invoke(Method.java:606) [rt.jar:1.7.0_45]<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; at org.ovirt.engine.core.utils.timer.JobWrapper.execute(JobWrapper.java:60) [scheduler.jar:]<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; at org.quartz.core.JobRunShell.run(JobRunShell.java:213) [quartz.jar:]<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:557) [quartz.jar:]<br /><br />vdsm.log<br />Thread-867485::DEBUG::2013-11-26 14:58:52,504::BindingXMLRPC::979::vds::(wrapper) client [192.168.1.29]::call vmCreate with ({'acpiEnable': 'true', 'emulatedMachine': 'rhel6.4.0', 'tabletEnable': 'true', 'vmId': '8798d0da-a82c-4bcf-b41a-7e3fe1c613ef', 'memGuaranteedSize': 512, 'spiceSslCipherSuite': 'DEFAULT', 'timeOffset': '1', 'cpuType': 'Nehalem', 'custom': {'device_99b583e9-9030-40af-bb41-55d62a4a6b6adevice_5b94ca77-fab8-41b4-b286-311dee724813device_c3d8dcbf-d977-4d4d-8240-35b973ee00b9': 'VmDevice {vmId=8798d0da-a82c-4bcf-b41a-7e3fe1c613ef, deviceId=c3d8dcbf-d977-4d4d-8240-35b973ee00b9, device=scsi, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x04, function=0x0}, managed=false, plugged=true, readOnly=false, deviceAlias=scsi0, customProperties={}}', 'device_99b583e9-9030-40af-bb41-55d62a4a6b6a': 'VmDevice {vmId=8798d0da-a82c-4bcf-b41a-7e3fe1c613ef, deviceId=99b583e9-9030-40af-bb41-55d62a4a6b6a, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=1, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel0, customProperties={}}', 'device_99b583e9-9030-40af-bb41-55d62a4a6b6adevice_5b94ca77-fab8-41b4-b286-311dee724813device_c3d8dcbf-d977-4d4d-8240-35b973ee00b9device_839371b0-1967-4435-910e-0d8e1c980850device_920282ca-79c2-4abf-b1f2-7425f16f7820': 'VmDevice {vmId=8798d0da-a82c-4bcf-b41a-7e3fe1c613ef, deviceId=920282ca-79c2-4abf-b1f2-7425f16f7820, device=virtio-serial, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x06, function=0x0}, managed=false, plugged=true, readOnly=false, deviceAlias=virtio-serial0, customProperties={}}', 'device_99b583e9-9030-40af-bb41-55d62a4a6b6adevice_5b94ca77-fab8-41b4-b286-311dee724813device_c3d8dcbf-d977-4d4d-8240-35b973ee00b9device_839371b0-1967-4435-910e-0d8e1c980850': 'VmDevice {vmId=8798d0da-a82c-4bcf-b41a-7e3fe1c613ef, deviceId=839371b0-1967-4435-910e-0d8e1c980850, device=ide, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x01, function=0x1}, managed=false, plugged=true, readOnly=false, deviceAlias=ide0, customProperties={}}', 'device_99b583e9-9030-40af-bb41-55d62a4a6b6adevice_5b94ca77-fab8-41b4-b286-311dee724813': 'VmDevice {vmId=8798d0da-a82c-4bcf-b41a-7e3fe1c613ef, deviceId=5b94ca77-fab8-41b4-b286-311dee724813, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=2, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel1, customProperties={}}'}, 'smp': '1', 'vmType': 'kvm', 'memSize': 1024, 'smpCoresPerSocket': '1', 'vmName':<br />'vm01', 'nice': '0', 'smartcardEnable': 'false', 'keyboardLayout': 'de', 'kvmEnable': 'true', 'pitReinjection': 'false', 'transparentHugePages': 'true', 'devices': [{'device': 'cirrus', 'specParams': {'vram': '65536'}, 'type': 'video', 'deviceId': 'bf71f555-dc86-417b-9649-b06083b863b8', 'address': {'bus': '0x00', ' slot': '0x02', ' domain': '0x0000', ' type': 'pci', ' function': '0x0'}}, {'index': '2', 'iface': 'ide', 'address': {' controller': '0', ' target': '0', 'unit': '0', ' bus': '1', ' type': 'drive'}, 'specParams': {'path': ''}, 'readonly': 'true', 'deviceId': '865f42a1-f64a-49cd-a706-76bc1bea8517', 'path': '', 'device': 'cdrom', 'shared': 'false', 'type': 'disk'}, {'index': 0, 'iface': 'virtio', 'format': 'raw', 'bootOrder': '1', 'poolID': '5849b030-626e-47cb-ad90-3ce782d831b3', 'volumeID': 'd3011aef-3297-401c-88d7-65079e05dd4d', 'imageID': '272b7bb5-38e6-41b3-9da4-4cc278873fbf', 'specParams': {}, 'readonly': 'false', 'domainID': '8c8ad4f8-f187-410c-82a7-2beb79a51a30', 'optional': 'false', 'deviceId': '272b7bb5-38e6-41b3-9da4-4cc278873fbf', 'address': {'bus': '0x00', ' slot': '0x05', ' domain': '0x0000', ' type': 'pci', ' function': '0x0'}, 'device': 'disk', 'shared': 'false', 'propagateErrors': 'off', 'type': 'disk'}, {'nicModel': 'pv', 'macAddr': '52:54:00:6d:ce:c8', 'linkActive': 'true', 'network': 'VM', 'custom': {}, 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '78c714a7-9bc5-464b-85ae-328d29ba0b50', 'address': {'bus': '0x00', ' slot': '0x03', ' domain': '0x0000', ' type': 'pci', ' function': '0x0'}, 'device': 'bridge', 'type': 'interface'}], 'spiceSecureChannels': 'smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard', 'display': 'vnc'},) {} flowID [574ec4d2]<br />Thread-867485::INFO::2013-11-26 14:58:52,509::API::641::vds::(_getNetworkIp) network None: using 0<br />Thread-867485::INFO::2013-11-26 14:58:52,509::clientIF::366::vds::(createVm) vmContainerLock acquired by vm 8798d0da-a82c-4bcf-b41a-7e3fe1c613ef<br />Thread-867485::DEBUG::2013-11-26 14:58:52,515::clientIF::380::vds::(createVm) Total desktops after creation of 8798d0da-a82c-4bcf-b41a-7e3fe1c613ef is 5<br />Thread-867486::DEBUG::2013-11-26 14:58:52,516::vm::2015::vm.Vm::(_startUnderlyingVm) vmId=`8798d0da-a82c-4bcf-b41a-7e3fe1c613ef`::Start<br />Thread-867485::DEBUG::2013-11-26 14:58:52,516::BindingXMLRPC::986::vds::(wrapper) return vmCreate with {'status': {'message': 'Done', 'code': 0}, 'vmList': {'status': 'WaitForLaunch', 'acpiEnable': 'true', 'emulatedMachine': 'rhel6.4.0', 'tabletEnable': 'true', 'pid': '0', 'memGuaranteedSize': 512, 'timeOffset': '1', 'keyboardLayout': 'de', 'displayPort': '-1', 'displaySecurePort': '-1', 'spiceSslCipherSuite': 'DEFAULT', 'cpuType': 'Nehalem', 'smp': '1', 'clientIp': '', 'nicModel': 'rtl8139,pv', 'smartcardEnable': 'false', 'kvmEnable': 'true', 'pitReinjection': 'false', 'vmId': '8798d0da-a82c-4bcf-b41a-7e3fe1c613ef', 'transparentHugePages': 'true', 'devices': [{'device': 'cirrus', 'specParams': {'vram': '65536'}, 'type': 'video', 'deviceId': 'bf71f555-dc86-417b-9649-b06083b863b8', 'address': {'bus': '0x00', ' slot': '0x02', ' domain': '0x0000', ' type': 'pci', ' function': '0x0'}}, {'index': '2', 'iface': 'ide', 'address': {' controller': '0', ' target': '0', 'unit': '0', ' bus': '1', ' type': 'drive'}, 'specParams': {'path': ''}, 'readonly': 'true', 'deviceId': '865f42a1-f64a-49cd-a706-76bc1bea8517', 'path': '', 'device': 'cdrom', 'shared': 'false', 'type': 'disk'}, {'index': 0, 'iface': 'virtio', 'format': 'raw', 'bootOrder': '1', 'poolID': '5849b030-626e-47cb-ad90-3ce782d831b3', 'volumeID': 'd3011aef-3297-401c-88d7-65079e05dd4d', 'imageID': '272b7bb5-38e6-41b3-9da4-4cc278873fbf', 'specParams': {}, 'readonly': 'false', 'domainID': '8c8ad4f8-f187-410c-82a7-2beb79a51a30', 'optional': 'false', 'deviceId': '272b7bb5-38e6-41b3-9da4-4cc278873fbf', 'address': {'bus': '0x00', ' slot': '0x05', ' domain': '0x0000', ' type': 'pci', ' function': '0x0'}, 'device': 'disk', 'shared': 'false', 'propagateErrors': 'off', 'type': 'disk'}, {'nicModel': 'pv', 'macAddr': '52:54:00:6d:ce:c8', 'linkActive': 'true', 'network': 'VM', 'custom': {}, 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '78c714a7-9bc5-464b-85ae-328d29ba0b50', 'address': {'bus': '0x00', ' slot': '0x03', ' domain': '0x0000', ' type': 'pci', ' function': '0x0'}, 'device': 'bridge', 'type': 'interface'}], 'custom': {'device_99b583e9-9030-40af-bb41-55d62a4a6b6adevice_5b94ca77-fab8-41b4-b286-311dee724813device_c3d8dcbf-d977-4d4d-8240-35b973ee00b9': 'VmDevice {vmId=8798d0da-a82c-4bcf-b41a-7e3fe1c613ef, deviceId=c3d8dcbf-d977-4d4d-8240-35b973ee00b9, device=scsi, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x04, function=0x0}, managed=false, plugged=true, readOnly=false, deviceAlias=scsi0, customProperties={}}', 'device_99b583e9-9030-40af-bb41-55d62a4a6b6a': 'VmDevice {vmId=8798d0da-a82c-4bcf-b41a-7e3fe1c613ef, deviceId=99b583e9-9030-40af-bb41-55d62a4a6b6a, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=1, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel0, customProperties={}}', 'device_99b583e9-9030-40af-bb41-55d62a4a6b6adevice_5b94ca77-fab8-41b4-b286-311dee724813device_c3d8dcbf-d977-4d4d-8240-35b973ee00b9device_839371b0-1967-4435-910e-0d8e1c980850device_920282ca-79c2-4abf-b1f2-7425f16f7820': 'VmDevice {vmId=8798d0da-a82c-4bcf-b41a-7e3fe1c613ef, deviceId=920282ca-79c2-4abf-b1f2-7425f16f7820, device=virtio-serial, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x06, function=0x0}, managed=false, plugged=true, readOnly=false, deviceAlias=virtio-serial0, customProperties={}}', 'device_99b583e9-9030-40af-bb41-55d62a4a6b6adevice_5b94ca77-fab8-41b4-b286-311dee724813device_c3d8dcbf-d977-4d4d-8240-35b973ee00b9device_839371b0-1967-4435-910e-0d8e1c980850': 'VmDevice {vmId=8798d0da-a82c-4bcf-b41a-7e3fe1c613ef, deviceId=839371b0-1967-4435-910e-0d8e1c980850, device=ide, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x01, function=0x1}, managed=false, plugged=true, readOnly=false, deviceAlias=ide0, customProperties={}}', 'device_99b583e9-9030-40af-bb41-55d62a4a6b6adevice_5b94ca77-fab8-41b4-b286-311dee724813': 'VmDevice {vmId=8798d0da-a82c-4bcf-b41a-7e3fe1c613ef, deviceId=5b94ca77-fab8-41b4-b286-311dee724813, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=2, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel1, customProperties={}}'}, 'vmType': 'kvm', 'memSize': 1024, 'displayIp': '0', 'spiceSecureChannels': 'smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard', 'smpCoresPerSocket': '1', 'vmName':<br />'vm01', 'display': 'vnc', 'nice': '0'}}<br />Thread-867486::DEBUG::2013-11-26 14:58:52,519::vm::2019::vm.Vm::(_startUnderlyingVm) vmId=`8798d0da-a82c-4bcf-b41a-7e3fe1c613ef`::_ongoingCreations acquired<br />Thread-867486::INFO::2013-11-26 14:58:52,522::vm::2815::vm.Vm::(_run) vmId=`8798d0da-a82c-4bcf-b41a-7e3fe1c613ef`::VM wrapper has started<br />Thread-867486::DEBUG::2013-11-26 14:58:52,527::task::579::TaskManager.Task::(_updateState) Task=`e64eb271-e389-4057-81f7-1148059464d7`::moving from state init -&gt; state preparing<br />Thread-867486::INFO::2013-11-26 14:58:52,530::logUtils::44::dispatcher::(wrapper) Run and protect: getVolumeSize(sdUUID='8c8ad4f8-f187-410c-82a7-2beb79a51a30', spUUID='5849b030-626e-47cb-ad90-3ce782d831b3', imgUUID='272b7bb5-38e6-41b3-9da4-4cc278873fbf', volUUID='d3011aef-3297-401c-88d7-65079e05dd4d', options=None)<br />Thread-867486::DEBUG::2013-11-26 14:58:52,534::lvm::415::OperationMutex::(_reloadlvs) Operation 'lvm reload operation' got the operation mutex<br />Thread-867486::DEBUG::2013-11-26 14:58:52,539::lvm::311::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm lvs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 filter = [ \'a|/dev/mapper/360014051fd69751de087d46f1da5aadb|/dev/mapper/360014056ce3d140de6a5d4fffd9218da|/dev/mapper/360014056db0fc0fdcda7d4a27da043d5|/dev/mapper/36001405917dfc0dd0809d41cfd86c9d4|/dev/mapper/3600508b1001cd6f84303d050b8fb5a80|\', \'r|.*|\' ] }&nbsp; global {&nbsp; locking_type=1&nbsp; prioritise_write_locks=1&nbsp; wait_for_locks=1 }&nbsp; backup {&nbsp; retain_min = 50&nbsp; retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,vg_name,attr,size,seg_start_pe,devices,tags 8c8ad4f8-f187-410c-82a7-2beb79a51a30' (cwd None)<br />Thread-867486::DEBUG::2013-11-26 14:58:52,588::lvm::311::Storage.Misc.excCmd::(cmd) SUCCESS: &lt;err&gt; = ''; &lt;rc&gt; = 0<br />Thread-867486::DEBUG::2013-11-26 14:58:52,623::lvm::448::OperationMutex::(_reloadlvs) Operation 'lvm reload operation' released the operation mutex<br />Thread-867486::INFO::2013-11-26 14:58:52,623::logUtils::47::dispatcher::(wrapper) Run and protect: getVolumeSize, Return response: {'truesize': '42949672960', 'apparentsize': '42949672960'}<br />Thread-867486::DEBUG::2013-11-26 14:58:52,624::task::1168::TaskManager.Task::(prepare) Task=`e64eb271-e389-4057-81f7-1148059464d7`::finished: {'truesize': '42949672960', 'apparentsize': '42949672960'}<br />Thread-867486::DEBUG::2013-11-26 14:58:52,624::task::579::TaskManager.Task::(_updateState) Task=`e64eb271-e389-4057-81f7-1148059464d7`::moving from state preparing -&gt; state finished<br />Thread-867486::DEBUG::2013-11-26 14:58:52,624::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}<br />Thread-867486::DEBUG::2013-11-26 14:58:52,625::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}<br />Thread-867486::DEBUG::2013-11-26 14:58:52,625::task::974::TaskManager.Task::(_decref) Task=`e64eb271-e389-4057-81f7-1148059464d7`::ref 0 aborting False<br />Thread-867486::INFO::2013-11-26 14:58:52,625::clientIF::325::vds::(prepareVolumePath) prepared volume path:<br />Thread-867486::DEBUG::2013-11-26 14:58:52,626::task::579::TaskManager.Task::(_updateState) Task=`e0d9071f-a343-400e-865d-81656dd8862b`::moving from state init -&gt; state preparing<br />Thread-867486::INFO::2013-11-26 14:58:52,626::logUtils::44::dispatcher::(wrapper) Run and protect: prepareImage(sdUUID='8c8ad4f8-f187-410c-82a7-2beb79a51a30', spUUID='5849b030-626e-47cb-ad90-3ce782d831b3', imgUUID='272b7bb5-38e6-41b3-9da4-4cc278873fbf', volUUID='d3011aef-3297-401c-88d7-65079e05dd4d')<br />Thread-867486::DEBUG::2013-11-26 14:58:52,627::resourceManager::197::ResourceManager.Request::(__init__) ResName=`Storage.8c8ad4f8-f187-410c-82a7-2beb79a51a30`ReqID=`664dc122-58c1-49d1-a03e-faa2182f42da`::Request was made in '/usr/share/vdsm/storage/hsm.py' line '3240' at 'prepareImage'<br />Thread-867486::DEBUG::2013-11-26 14:58:52,627::resourceManager::541::ResourceManager::(registerResource) Trying to register resource 'Storage.8c8ad4f8-f187-410c-82a7-2beb79a51a30' for lock type 'shared'<br />Thread-867486::DEBUG::2013-11-26 14:58:52,628::resourceManager::600::ResourceManager::(registerResource) Resource 'Storage.8c8ad4f8-f187-410c-82a7-2beb79a51a30' is free. Now locking as 'shared' (1 active user)<br />Thread-867486::DEBUG::2013-11-26 14:58:52,628::resourceManager::237::ResourceManager.Request::(grant) ResName=`Storage.8c8ad4f8-f187-410c-82a7-2beb79a51a30`ReqID=`664dc122-58c1-49d1-a03e-faa2182f42da`::Granted request<br />Thread-867486::DEBUG::2013-11-26 14:58:52,629::task::811::TaskManager.Task::(resourceAcquired) Task=`e0d9071f-a343-400e-865d-81656dd8862b`::_resourcesAcquired: Storage.8c8ad4f8-f187-410c-82a7-2beb79a51a30 (shared)<br />Thread-867486::DEBUG::2013-11-26 14:58:52,629::task::974::TaskManager.Task::(_decref) Task=`e0d9071f-a343-400e-865d-81656dd8862b`::ref 1 aborting False<br />Thread-867486::DEBUG::2013-11-26 14:58:52,635::blockVolume::588::Storage.Misc.excCmd::(getMetadata) '/bin/dd iflag=direct skip=23 bs=512 if=/dev/8c8ad4f8-f187-410c-82a7-2beb79a51a30/metadata count=1' (cwd None)<br />Thread-867486::DEBUG::2013-11-26 14:58:52,644::blockVolume::588::Storage.Misc.excCmd::(getMetadata) SUCCESS: &lt;err&gt; = '1+0 records in\n1+0 records out\n512 bytes (512 B) copied, 0.000281928 s, 1.8 MB/s\n'; &lt;rc&gt; = 0<br />Thread-867486::DEBUG::2013-11-26 14:58:52,644::misc::288::Storage.Misc::(validateDDBytes) err: ['1+0 records in', '1+0 records out', '512 bytes (512 B) copied, 0.000281928 s, 1.8 MB/s'], size: 512<br />Thread-867486::INFO::2013-11-26 14:58:52,645::image::215::Storage.Image::(getChain) sdUUID=8c8ad4f8-f187-410c-82a7-2beb79a51a30 imgUUID=272b7bb5-38e6-41b3-9da4-4cc278873fbf chain=[&lt;storage.blockVolume.BlockVolume object at 0x14f6ad0&gt;]<br />Thread-867486::DEBUG::2013-11-26 14:58:52,646::lvm::311::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm lvchange --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 filter = [ \'a|/dev/mapper/360014051fd69751de087d46f1da5aadb|/dev/mapper/360014056ce3d140de6a5d4fffd9218da|/dev/mapper/360014056db0fc0fdcda7d4a27da043d5|/dev/mapper/36001405917dfc0dd0809d41cfd86c9d4|/dev/mapper/3600508b1001cd6f84303d050b8fb5a80|\', \'r|.*|\' ] }&nbsp; global {&nbsp; locking_type=1&nbsp; prioritise_write_locks=1&nbsp; wait_for_locks=1 }&nbsp; backup {&nbsp; retain_min = 50&nbsp; retain_days = 0 } " --autobackup n --available y 8c8ad4f8-f187-410c-82a7-2beb79a51a30/d3011aef-3297-401c-88d7-65079e05dd4d' (cwd None)<br />Thread-867486::DEBUG::2013-11-26 14:58:52,942::lvm::311::Storage.Misc.excCmd::(cmd) SUCCESS: &lt;err&gt; = ''; &lt;rc&gt; = 0<br />Thread-867486::DEBUG::2013-11-26 14:58:52,943::lvm::499::OperationMutex::(_invalidatelvs) Operation 'lvm invalidate operation' got the operation mutex<br />Thread-867486::DEBUG::2013-11-26 14:58:52,943::lvm::511::OperationMutex::(_invalidatelvs) Operation 'lvm invalidate operation' released the operation mutex<br />Thread-867486::DEBUG::2013-11-26 14:58:52,943::lvm::415::OperationMutex::(_reloadlvs) Operation 'lvm reload operation' got the operation mutex<br />Thread-867486::DEBUG::2013-11-26 14:58:52,944::lvm::311::Storage.Misc.excCmd::(cmd) '/usr/bin/sudo -n /sbin/lvm lvs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 filter = [ \'a|/dev/mapper/360014051fd69751de087d46f1da5aadb|/dev/mapper/360014056ce3d140de6a5d4fffd9218da|/dev/mapper/360014056db0fc0fdcda7d4a27da043d5|/dev/mapper/36001405917dfc0dd0809d41cfd86c9d4|/dev/mapper/3600508b1001cd6f84303d050b8fb5a80|\', \'r|.*|\' ] }&nbsp; global {&nbsp; locking_type=1&nbsp; prioritise_write_locks=1&nbsp; wait_for_locks=1 }&nbsp; backup {&nbsp; retain_min = 50&nbsp; retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,vg_name,attr,size,seg_start_pe,devices,tags 8c8ad4f8-f187-410c-82a7-2beb79a51a30' (cwd None)<br />Thread-867486::DEBUG::2013-11-26 14:58:53,242::lvm::311::Storage.Misc.excCmd::(cmd) SUCCESS: &lt;err&gt; = ''; &lt;rc&gt; = 0<br />Thread-867486::DEBUG::2013-11-26 14:58:53,277::lvm::448::OperationMutex::(_reloadlvs) Operation 'lvm reload operation' released the operation mutex<br />Thread-867486::DEBUG::2013-11-26 14:58:53,283::blockVolume::588::Storage.Misc.excCmd::(getMetadata) '/bin/dd iflag=direct skip=23 bs=512 if=/dev/8c8ad4f8-f187-410c-82a7-2beb79a51a30/metadata count=1' (cwd None)<br />Thread-867486::DEBUG::2013-11-26 14:58:53,292::blockVolume::588::Storage.Misc.excCmd::(getMetadata) SUCCESS: &lt;err&gt; = '1+0 records in\n1+0 records out\n512 bytes (512 B) copied, 0.000298328 s, 1.7 MB/s\n'; &lt;rc&gt; = 0<br />Thread-867486::DEBUG::2013-11-26 14:58:53,292::misc::288::Storage.Misc::(validateDDBytes) err: ['1+0 records in', '1+0 records out', '512 bytes (512 B) copied, 0.000298328 s, 1.7 MB/s'], size: 512<br />Thread-867486::INFO::2013-11-26 14:58:53,293::logUtils::47::dispatcher::(wrapper) Run and protect: prepareImage, Return response: {'info': {'path': '/rhev/data-center/5849b030-626e-47cb-ad90-3ce782d831b3/8c8ad4f8-f187-410c-82a7-2beb79a51a30/images/272b7bb5-38e6-41b3-9da4-4cc278873fbf/d3011aef-3297-401c-88d7-65079e05dd4d', 'volType': 'path'}, 'path': '/rhev/data-center/5849b030-626e-47cb-ad90-3ce782d831b3/8c8ad4f8-f187-410c-82a7-2beb79a51a30/images/272b7bb5-38e6-41b3-9da4-4cc278873fbf/d3011aef-3297-401c-88d7-65079e05dd4d', 'chain': [{'path': '/rhev/data-center/5849b030-626e-47cb-ad90-3ce782d831b3/8c8ad4f8-f187-410c-82a7-2beb79a51a30/images/272b7bb5-38e6-41b3-9da4-4cc278873fbf/d3011aef-3297-401c-88d7-65079e05dd4d', 'domainID': '8c8ad4f8-f187-410c-82a7-2beb79a51a30', 'vmVolInfo': {'path': '/rhev/data-center/5849b030-626e-47cb-ad90-3ce782d831b3/8c8ad4f8-f187-410c-82a7-2beb79a51a30/images/272b7bb5-38e6-41b3-9da4-4cc278873fbf/d3011aef-3297-401c-88d7-65079e05dd4d', 'volType': 'path'}, 'volumeID': 'd3011aef-3297-401c-88d7-65079e05dd4d', 'imageID': '272b7bb5-38e6-41b3-9da4-4cc278873fbf'}]}<br />Thread-867486::DEBUG::2013-11-26 14:58:53,293::task::1168::TaskManager.Task::(prepare) Task=`e0d9071f-a343-400e-865d-81656dd8862b`::finished: {'info': {'path': '/rhev/data-center/5849b030-626e-47cb-ad90-3ce782d831b3/8c8ad4f8-f187-410c-82a7-2beb79a51a30/images/272b7bb5-38e6-41b3-9da4-4cc278873fbf/d3011aef-3297-401c-88d7-65079e05dd4d', 'volType': 'path'}, 'path': '/rhev/data-center/5849b030-626e-47cb-ad90-3ce782d831b3/8c8ad4f8-f187-410c-82a7-2beb79a51a30/images/272b7bb5-38e6-41b3-9da4-4cc278873fbf/d3011aef-3297-401c-88d7-65079e05dd4d', 'chain': [{'path': '/rhev/data-center/5849b030-626e-47cb-ad90-3ce782d831b3/8c8ad4f8-f187-410c-82a7-2beb79a51a30/images/272b7bb5-38e6-41b3-9da4-4cc278873fbf/d3011aef-3297-401c-88d7-65079e05dd4d', 'domainID': '8c8ad4f8-f187-410c-82a7-2beb79a51a30', 'vmVolInfo': {'path': '/rhev/data-center/5849b030-626e-47cb-ad90-3ce782d831b3/8c8ad4f8-f187-410c-82a7-2beb79a51a30/images/272b7bb5-38e6-41b3-9da4-4cc278873fbf/d3011aef-3297-401c-88d7-65079e05dd4d', 'volType': 'path'}, 'volumeID': 'd3011aef-3297-401c-88d7-65079e05dd4d', 'imageID': '272b7bb5-38e6-41b3-9da4-4cc278873fbf'}]}<br />Thread-867486::DEBUG::2013-11-26 14:58:53,293::task::579::TaskManager.Task::(_updateState) Task=`e0d9071f-a343-400e-865d-81656dd8862b`::moving from state preparing -&gt; state finished<br />Thread-867486::DEBUG::2013-11-26 14:58:53,294::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {'Storage.8c8ad4f8-f187-410c-82a7-2beb79a51a30': &lt; ResourceRef 'Storage.8c8ad4f8-f187-410c-82a7-2beb79a51a30', isValid: 'True' obj: 'None'&gt;}<br />Thread-867486::DEBUG::2013-11-26 14:58:53,294::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}<br />Thread-867486::DEBUG::2013-11-26 14:58:53,294::resourceManager::615::ResourceManager::(releaseResource) Trying to release resource 'Storage.8c8ad4f8-f187-410c-82a7-2beb79a51a30'<br />Thread-867486::DEBUG::2013-11-26 14:58:53,295::resourceManager::634::ResourceManager::(releaseResource) Released resource 'Storage.8c8ad4f8-f187-410c-82a7-2beb79a51a30' (0 active users)<br />Thread-867486::DEBUG::2013-11-26 14:58:53,295::resourceManager::640::ResourceManager::(releaseResource) Resource 'Storage.8c8ad4f8-f187-410c-82a7-2beb79a51a30' is free, finding out if anyone is waiting for it.<br />Thread-867486::DEBUG::2013-11-26 14:58:53,295::resourceManager::648::ResourceManager::(releaseResource) No one is waiting for resource 'Storage.8c8ad4f8-f187-410c-82a7-2beb79a51a30', Clearing records.<br />Thread-867486::DEBUG::2013-11-26 14:58:53,296::task::974::TaskManager.Task::(_decref) Task=`e0d9071f-a343-400e-865d-81656dd8862b`::ref 0 aborting False<br />Thread-867486::INFO::2013-11-26 14:58:53,296::clientIF::325::vds::(prepareVolumePath) prepared volume path: /rhev/data-center/5849b030-626e-47cb-ad90-3ce782d831b3/8c8ad4f8-f187-410c-82a7-2beb79a51a30/images/272b7bb5-38e6-41b3-9da4-4cc278873fbf/d3011aef-3297-401c-88d7-65079e05dd4d<br />Thread-867486::DEBUG::2013-11-26 14:58:53,318::utils::486::root::(execCmd) '/usr/libexec/vdsm/hooks/before_vm_start/50_smbios' (cwd None)<br />Thread-867486::DEBUG::2013-11-26 14:58:53,400::utils::505::root::(execCmd) SUCCESS: &lt;err&gt; = ''; &lt;rc&gt; = 0<br />Thread-867486::INFO::2013-11-26 14:58:53,401::hooks::88::root::(_runHooksDir)<br />Thread-867486::DEBUG::2013-11-26 14:58:53,401::vm::2872::vm.Vm::(_run) vmId=`8798d0da-a82c-4bcf-b41a-7e3fe1c613ef`::&lt;?xml version="1.0" encoding="utf-8"?&gt;<br />&lt;domain type="kvm"&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;name&gt;vm01&lt;/name&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;uuid&gt;8798d0da-a82c-4bcf-b41a-7e3fe1c613ef&lt;/uuid&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;memory&gt;1048576&lt;/memory&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;currentMemory&gt;1048576&lt;/currentMemory&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;vcpu&gt;1&lt;/vcpu&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;memtune&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;min_guarantee&gt;524288&lt;/min_guarantee&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;/memtune&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;devices&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;channel type="unix"&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;target name="com.redhat.rhevm.vdsm" type="virtio"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;source mode="bind" path="/var/lib/libvirt/qemu/channels/8798d0da-a82c-4bcf-b41a-7e3fe1c613ef.com.redhat.rhevm.vdsm"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;/channel&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;channel type="unix"&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;target name="org.qemu.guest_agent.0" type="virtio"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;source mode="bind" path="/var/lib/libvirt/qemu/channels/8798d0da-a82c-4bcf-b41a-7e3fe1c613ef.org.qemu.guest_agent.0"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;/channel&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;input bus="usb" type="tablet"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;graphics autoport="yes" keymap="de" listen="0" passwd="*****" passwdValidTo="1970-01-01T00:00:01" port="-1" type="vnc"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;video&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;address bus="0x00" domain="0x0000" function="0x0" slot="0x02" type="pci"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;model heads="1" type="cirrus" vram="65536"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;/video&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;interface type="bridge"&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;address bus="0x00" domain="0x0000" function="0x0" slot="0x03" type="pci"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;mac address="52:54:00:6d:ce:c8"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;model type="virtio"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;source bridge="VM"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;filterref filter="vdsm-no-mac-spoofing"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;link state="up"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;/interface&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;disk device="cdrom" snapshot="no" type="file"&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;address bus="1" controller="0" target="0" type="drive" unit="0"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;source file="" startupPolicy="optional"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;target bus="ide" dev="hdc"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;readonly/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;serial/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;/disk&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;disk device="disk" snapshot="no" type="block"&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;address bus="0x00" domain="0x0000" function="0x0" slot="0x05" type="pci"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;source dev="/rhev/data-center/5849b030-626e-47cb-ad90-3ce782d831b3/8c8ad4f8-f187-410c-82a7-2beb79a51a30/images/272b7bb5-38e6-41b3-9da4-4cc278873fbf/d3011aef-3297-401c-88d7-65079e05dd4d"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;target bus="virtio" dev="vda"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;serial&gt;272b7bb5-38e6-41b3-9da4-4cc278873fbf&lt;/serial&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;boot order="1"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;driver cache="none" error_policy="stop" io="native" name="qemu" type="raw"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;/disk&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;memballoon model="none"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;/devices&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;os&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;type arch="x86_64" machine="rhel6.4.0"&gt;hvm&lt;/type&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;smbios mode="sysinfo"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;/os&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;sysinfo type="smbios"&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;system&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;entry name="manufacturer"&gt;oVirt&lt;/entry&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;entry name="product"&gt;oVirt Node&lt;/entry&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;entry name="version"&gt;6-4.el6.centos.10&lt;/entry&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;entry name="serial"&gt;32393735-3933-5A43-4A32-34333046564B&lt;/entry&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;entry name="uuid"&gt;8798d0da-a82c-4bcf-b41a-7e3fe1c613ef&lt;/entry&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;/system&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;/sysinfo&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;clock adjustment="1" offset="variable"&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;timer name="rtc" tickpolicy="catchup"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;/clock&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;features&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;acpi/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;/features&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;cpu match="exact"&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;model&gt;Nehalem&lt;/model&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;topology cores="1" sockets="1" threads="1"/&gt;<br />&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; &lt;/cpu&gt;<br />&lt;/domain&gt;<br /><br />Thread-166407::DEBUG::2013-11-26 14:58:53,588::blockSD::594::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct if=/dev/8c8ad4f8-f187-410c-82a7-2beb79a51a30/metadata bs=4096 count=1' (cwd None)<br />Thread-166407::DEBUG::2013-11-26 14:58:53,599::blockSD::594::Storage.Misc.excCmd::(getReadDelay) SUCCESS: &lt;err&gt; = '1+0 records in\n1+0 records out\n4096 bytes (4.1 kB) copied, 0.000345562 s, 11.9 MB/s\n'; &lt;rc&gt; = 0<br />Thread-24::DEBUG::2013-11-26 14:58:53,791::fileSD::238::Storage.Misc.excCmd::(getReadDelay) '/bin/dd iflag=direct<br />if=/rhev/data-center/mnt/engine.privat.com:_export_VMs/48055e27-f1ca-466a-8a2c-e191c34f0226/dom_md/metadata bs=4096 count=1' (cwd None)<br />Thread-24::DEBUG::2013-11-26 14:58:53,801::fileSD::238::Storage.Misc.excCmd::(getReadDelay) SUCCESS: &lt;err&gt; = '0+1 records in\n0+1 records out\n349 bytes (349 B) copied, 0.000683955 s, 510 kB/s\n'; &lt;rc&gt; = 0<br />libvirtEventLoop::DEBUG::2013-11-26 14:58:54,094::vm::4724::vm.Vm::(_onLibvirtLifecycleEvent) vmId=`8798d0da-a82c-4bcf-b41a-7e3fe1c613ef`::event Started detail 0 opaque None<br />Thread-867486::DEBUG::2013-11-26 14:58:54,248::sampling::285::vm.Vm::(start) vmId=`8798d0da-a82c-4bcf-b41a-7e3fe1c613ef`::Start statistics collection<br />Thread-867488::DEBUG::2013-11-26 14:58:54,249::sampling::314::vm.Vm::(run) vmId=`8798d0da-a82c-4bcf-b41a-7e3fe1c613ef`::Stats thread started<br />Thread-867486::DEBUG::2013-11-26 14:58:54,251::vmChannels::194::vds::(register) Add fileno 51 to listener's channels.<br />Thread-867488::DEBUG::2013-11-26 14:58:54,253::task::579::TaskManager.Task::(_updateState) Task=`c5c6d816-7de8-4ab5-a5fa-a3fba225d797`::moving from state init -&gt; state preparing<br />Thread-867488::INFO::2013-11-26 14:58:54,253::logUtils::44::dispatcher::(wrapper) Run and protect: getVolumeSize(sdUUID='8c8ad4f8-f187-410c-82a7-2beb79a51a30', spUUID='5849b030-626e-47cb-ad90-3ce782d831b3', imgUUID='272b7bb5-38e6-41b3-9da4-4cc278873fbf', volUUID='d3011aef-3297-401c-88d7-65079e05dd4d', options=None)<br />Thread-867486::WARNING::2013-11-26 14:58:54,253::vm::3326::vm.Vm::(_readPauseCode) vmId=`8798d0da-a82c-4bcf-b41a-7e3fe1c613ef`::_readPauseCode unsupported by libvirt vm<br />Thread-867488::INFO::2013-11-26 14:58:54,255::logUtils::47::dispatcher::(wrapper) Run and protect: getVolumeSize, Return response: {'truesize': '42949672960', 'apparentsize': '42949672960'}<br />Thread-867488::DEBUG::2013-11-26 14:58:54,256::task::1168::TaskManager.Task::(prepare) Task=`c5c6d816-7de8-4ab5-a5fa-a3fba225d797`::finished: {'truesize': '42949672960', 'apparentsize': '42949672960'}<br />Thread-867488::DEBUG::2013-11-26 14:58:54,256::task::579::TaskManager.Task::(_updateState) Task=`c5c6d816-7de8-4ab5-a5fa-a3fba225d797`::moving from state preparing -&gt; state finished<br />Thread-867488::DEBUG::2013-11-26 14:58:54,256::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}<br />Thread-867488::DEBUG::2013-11-26 14:58:54,257::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}<br />Thread-867488::DEBUG::2013-11-26 14:58:54,257::task::974::TaskManager.Task::(_decref) Task=`c5c6d816-7de8-4ab5-a5fa-a3fba225d797`::ref 0 aborting False<br />Thread-867486::DEBUG::2013-11-26 14:58:54,263::vm::2036::vm.Vm::(_startUnderlyingVm) vmId=`8798d0da-a82c-4bcf-b41a-7e3fe1c613ef`::_ongoingCreations released<br />VM Channels Listener::DEBUG::2013-11-26 14:58:54,694::vmChannels::102::vds::(_do_add_channels) fileno 51 was added to unconnected channels.<br />VM Channels Listener::DEBUG::2013-11-26 14:58:54,694::vmChannels::128::vds::(_handle_unconnected) Trying to connect fileno 51.<br />VM Channels Listener::DEBUG::2013-11-26 14:58:54,695::guestIF::147::vm.Vm::(_connect) vmId=`8798d0da-a82c-4bcf-b41a-7e3fe1c613ef`::Attempting connection to /var/lib/libvirt/qemu/channels/8798d0da-a82c-4bcf-b41a-7e3fe1c613ef.com.redhat.rhevm.vdsm<br />VM Channels Listener::DEBUG::2013-11-26 14:58:54,695::guestIF::150::vm.Vm::(_connect) vmId=`8798d0da-a82c-4bcf-b41a-7e3fe1c613ef`::Connected to /var/lib/libvirt/qemu/channels/8798d0da-a82c-4bcf-b41a-7e3fe1c613ef.com.redhat.rhevm.vdsm<br />VM Channels Listener::DEBUG::2013-11-26 14:58:54,695::vmChannels::144::vds::(_handle_unconnected) Connecting to fileno 51 succeeded.<br />Thread-867490::DEBUG::2013-11-26 14:58:55,170::BindingXMLRPC::979::vds::(wrapper) client [192.168.1.29]::call vmGetStats with ('8798d0da-a82c-4bcf-b41a-7e3fe1c613ef',) {}<br />Thread-867490::DEBUG::2013-11-26 14:58:55,170::vm::579::vm.Vm::(_getCpuStats) vmId=`8798d0da-a82c-4bcf-b41a-7e3fe1c613ef`::CPU stats not available: 'NoneType' object is unsubscriptable<br />Thread-867490::DEBUG::2013-11-26 14:58:55,170::vm::613::vm.Vm::(_getNetworkStats) vmId=`8798d0da-a82c-4bcf-b41a-7e3fe1c613ef`::Network stats not available</span></span>