[10.3.72.248]::call vmCreate with ({'acpiEnable': 'true', 'emulatedMachine': 'pc-1.0', 'vmId': '147a1deb-3795-4153-a820-ceabd8db15f3', 'memGuaranteedSize': 1024, 'spiceSslCipherSuite': 'DEFAULT', 'timeOffset': '0', 'cpuType': 'Penryn', 'smp': '1', 'custom': {}, 'vmType': 'kvm', 'memSize': 1024, 'smpCoresPerSocket': '1', 'vmName': 'TestVm', 'nice': '0', 'smartcardEnable': 'false', 'keyboardLayout': 'en-us', 'kvmEnable': 'true', 'pitReinjection': 'false', 'transparentHugePages': 'true', 'devices': [{'index': '0', 'specParams': {}, 'deviceId': '0646d163-0a5d-48fd-b0a7-868f597745cf', 'device': 'usb', 'model': 'ich9-ehci1', 'type': 'controller'}, {'device': 'qxl', 'specParams': {'vram': '32768', 'ram': '65536', 'heads': '1'}, 'type': 'video', 'deviceId': '1b78d7e2-0a1f-49ca-a87d-98cbae4b930a'}, {'index': '2', 'iface': 'ide', 'bootOrder': '1', 'specParams': {}, 'readonly': 'true', 'deviceId': '9fa7d215-a5bf-48c4-9ba7-6c96accc383a', 'path': '/rhev/data-center/mnt/ovirt-manager.local:_var_lib_exports_iso/e15927a5-1c3c-4e49-8a19-3790f411ddb8/images/11111111-1111-1111-1111-111111111111/CentOS-6.4-x86_64-bin-DVD1.iso', 'device': 'cdrom', 'shared': 'false', 'type': 'disk'}, {'index': 0, 'iface': 'virtio', 'format': 'raw', 'bootOrder': '2', 'volumeID': 'b0839e46-9f79-49b2-ae34-f7cafb626c75', 'imageID': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'specParams': {}, 'readonly': 'false', 'domainID': '66e0b27c-dcbb-4b6e-9a58-8213e8380c65', 'optional': 'false', 'deviceId': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'poolID': 'b709443b-9933-46aa-86cd-c687bb8953f8', 'device': 'disk', 'shared': 'false', 'propagateErrors': 'off', 'type': 'disk'}, {'nicModel': 'pv', 'macAddr': '00:1a:4a:6f:be:39', 'linkActive': 'true', 'network': 'ovirtmgmt', 'bootOrder': '3', 'custom': {}, 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '2a99a6d5-19fe-48c7-af8b-0f0ecbcc89be', 'device': 'bridge', 'type': 'interface'}, {'index': '0', 'specParams': {}, 'deviceId': '8dc7a6f8-1ce1-46e6-99d8-79546a3fe2db', 'device': 'usb', 'model': 'ich9-uhci1', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '60de3c26-96ef-41bc-98c2-3e3da3f5f3a7', 'device': 'usb', 'model': 'ich9-uhci2', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '8cee35e8-419c-4239-87f7-822ce59536e3', 'device': 'usb', 'model': 'ich9-uhci3', 'type': 'controller'}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '52e20b63-f8c6-4366-a5ec-1dd86c9162cb', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '81e2498f-5cd9-475e-9e54-7ef4dda44ced', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '935b6410-cf72-49d5-8d0c-0412f939bfa2', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '03f2d01b-368d-46f5-b5e2-6a92b07e84e1', 'specParams': {}}, {'device': 'memballoon', 'specParams': {'model': 'virtio'}, 'type': 'balloon', 'deviceId': '3d80d703-8e7b-45bb-b06c-b2f782ce7fdd'}], 'spiceSecureChannels': 'smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard', 'display': 'qxl'},) {}
Thread-621::INFO::2013-12-31 05:06:02,545::API::642::vds::(_getNetworkIp) network None: using 0
Thread-621::INFO::2013-12-31 05:06:02,545::clientIF::391::vds::(createVm) vmContainerLock acquired by vm 147a1deb-3795-4153-a820-ceabd8db15f3
Thread-621::DEBUG::2013-12-31 05:06:02,546::libvirtconnection::131::libvirtconnection::(get) trying to connect libvirt
Thread-622::DEBUG::2013-12-31 05:06:02,561::vm::2085::vm.Vm::(_startUnderlyingVm) vmId=`147a1deb-3795-4153-a820-ceabd8db15f3`::Start
Thread-621::DEBUG::2013-12-31 05:06:02,562::clientIF::404::vds::(createVm) Total desktops after creation of 147a1deb-3795-4153-a820-ceabd8db15f3 is 1
Thread-621::DEBUG::2013-12-31 05:06:02,562::BindingXMLRPC::981::vds::(wrapper) return vmCreate with {'status': {'message': 'Done', 'code': 0}, 'vmList': {'status': 'WaitForLaunch', 'acpiEnable': 'true', 'emulatedMachine': 'pc-1.0', 'vmId': '147a1deb-3795-4153-a820-ceabd8db15f3', 'pid': '0', 'memGuaranteedSize': 1024, 'timeOffset': '0', 'keyboardLayout': 'en-us', 'displayPort': '-1', 'displaySecurePort': '-1', 'spiceSslCipherSuite': 'DEFAULT', 'cpuType': 'Penryn', 'custom': {}, 'clientIp': '', 'nicModel': 'rtl8139,pv', 'smartcardEnable': 'false', 'kvmEnable': 'true', 'pitReinjection': 'false', 'transparentHugePages': 'true', 'devices': [{'index': '0', 'specParams': {}, 'deviceId': '0646d163-0a5d-48fd-b0a7-868f597745cf', 'device': 'usb', 'model': 'ich9-ehci1', 'type': 'controller'}, {'device': 'qxl', 'specParams': {'vram': '32768', 'ram': '65536', 'heads': '1'}, 'type': 'video', 'deviceId': '1b78d7e2-0a1f-49ca-a87d-98cbae4b930a'}, {'index': '2', 'iface': 'ide', 'bootOrder': '1', 'specParams': {}, 'readonly': 'true', 'deviceId': '9fa7d215-a5bf-48c4-9ba7-6c96accc383a', 'path': '/rhev/data-center/mnt/ovirt-manager.local:_var_lib_exports_iso/e15927a5-1c3c-4e49-8a19-3790f411ddb8/images/11111111-1111-1111-1111-111111111111/CentOS-6.4-x86_64-bin-DVD1.iso', 'device': 'cdrom', 'shared': 'false', 'type': 'disk'}, {'index': 0, 'iface': 'virtio', 'format': 'raw', 'bootOrder': '2', 'volumeID': 'b0839e46-9f79-49b2-ae34-f7cafb626c75', 'imageID': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'specParams': {}, 'readonly': 'false', 'domainID': '66e0b27c-dcbb-4b6e-9a58-8213e8380c65', 'optional': 'false', 'deviceId': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'poolID': 'b709443b-9933-46aa-86cd-c687bb8953f8', 'device': 'disk', 'shared': 'false', 'propagateErrors': 'off', 'type': 'disk'}, {'nicModel': 'pv', 'macAddr': '00:1a:4a:6f:be:39', 'linkActive': 'true', 'network': 'ovirtmgmt', 'bootOrder': '3', 'custom': {}, 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '2a99a6d5-19fe-48c7-af8b-0f0ecbcc89be', 'device': 'bridge', 'type': 'interface'}, {'index': '0', 'specParams': {}, 'deviceId': '8dc7a6f8-1ce1-46e6-99d8-79546a3fe2db', 'device': 'usb', 'model': 'ich9-uhci1', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '60de3c26-96ef-41bc-98c2-3e3da3f5f3a7', 'device': 'usb', 'model': 'ich9-uhci2', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '8cee35e8-419c-4239-87f7-822ce59536e3', 'device': 'usb', 'model': 'ich9-uhci3', 'type': 'controller'}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '52e20b63-f8c6-4366-a5ec-1dd86c9162cb', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '81e2498f-5cd9-475e-9e54-7ef4dda44ced', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '935b6410-cf72-49d5-8d0c-0412f939bfa2', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '03f2d01b-368d-46f5-b5e2-6a92b07e84e1', 'specParams': {}}, {'device': 'memballoon', 'specParams': {'model': 'virtio'}, 'type': 'balloon', 'deviceId': '3d80d703-8e7b-45bb-b06c-b2f782ce7fdd'}], 'smp': '1', 'vmType': 'kvm', 'memSize': 1024, 'displayIp': '0', 'spiceSecureChannels': 'smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard', 'smpCoresPerSocket': '1', 'vmName': 'TestVm', 'display': 'qxl', 'nice': '0'}}
Thread-622::DEBUG::2013-12-31 05:06:02,562::vm::2089::vm.Vm::(_startUnderlyingVm) vmId=`147a1deb-3795-4153-a820-ceabd8db15f3`::_ongoingCreations acquired
Thread-622::INFO::2013-12-31 05:06:02,563::vm::2868::vm.Vm::(_run) vmId=`147a1deb-3795-4153-a820-ceabd8db15f3`::VM wrapper has started
Thread-622::DEBUG::2013-12-31 05:06:02,564::task::579::TaskManager.Task::(_updateState) Task=`9d7030ee-298a-495f-a395-36d108018a08`::moving from state init -> state preparing
Thread-622::INFO::2013-12-31 05:06:02,564::logUtils::44::dispatcher::(wrapper) Run and protect: getVolumeSize(sdUUID='66e0b27c-dcbb-4b6e-9a58-8213e8380c65', spUUID='b709443b-9933-46aa-86cd-c687bb8953f8', imgUUID='7c063b30-634a-483b-82aa-7f19d2ae46cd', volUUID='b0839e46-9f79-49b2-ae34-f7cafb626c75', options=None)
Thread-622::INFO::2013-12-31 05:06:02,570::logUtils::47::dispatcher::(wrapper) Run and protect: getVolumeSize, Return response: {'truesize': '0', 'apparentsize': '42949672960'}
Thread-622::DEBUG::2013-12-31 05:06:02,570::task::1168::TaskManager.Task::(prepare) Task=`9d7030ee-298a-495f-a395-36d108018a08`::finished: {'truesize': '0', 'apparentsize': '42949672960'}
Thread-622::DEBUG::2013-12-31 05:06:02,570::task::579::TaskManager.Task::(_updateState) Task=`9d7030ee-298a-495f-a395-36d108018a08`::moving from state preparing -> state finished
Thread-622::DEBUG::2013-12-31 05:06:02,571::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
Thread-622::DEBUG::2013-12-31 05:06:02,571::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
Thread-622::DEBUG::2013-12-31 05:06:02,571::task::974::TaskManager.Task::(_decref) Task=`9d7030ee-298a-495f-a395-36d108018a08`::ref 0 aborting False
Thread-622::INFO::2013-12-31 05:06:02,571::clientIF::350::vds::(prepareVolumePath) prepared volume path: /rhev/data-center/mnt/ovirt-manager.local:_var_lib_exports_iso/e15927a5-1c3c-4e49-8a19-3790f411ddb8/images/11111111-1111-1111-1111-111111111111/CentOS-6.4-x86_64-bin-DVD1.iso
Thread-622::DEBUG::2013-12-31 05:06:02,571::task::579::TaskManager.Task::(_updateState) Task=`8ae0743d-8e3e-4516-9d67-7713fa546759`::moving from state init -> state preparing
Thread-622::INFO::2013-12-31 05:06:02,571::logUtils::44::dispatcher::(wrapper) Run and protect: prepareImage(sdUUID='66e0b27c-dcbb-4b6e-9a58-8213e8380c65', spUUID='b709443b-9933-46aa-86cd-c687bb8953f8', imgUUID='7c063b30-634a-483b-82aa-7f19d2ae46cd', leafUUID='b0839e46-9f79-49b2-ae34-f7cafb626c75')
Thread-622::DEBUG::2013-12-31 05:06:02,572::resourceManager::197::ResourceManager.Request::(__init__) ResName=`Storage.66e0b27c-dcbb-4b6e-9a58-8213e8380c65`ReqID=`29fe830e-439e-446c-89c0-299774de0dda`::Request was made in '/usr/share/vdsm/storage/hsm.py' line '3230' at 'prepareImage'
Thread-622::DEBUG::2013-12-31 05:06:02,572::resourceManager::541::ResourceManager::(registerResource) Trying to register resource 'Storage.66e0b27c-dcbb-4b6e-9a58-8213e8380c65' for lock type 'shared'
Thread-622::DEBUG::2013-12-31 05:06:02,572::resourceManager::600::ResourceManager::(registerResource) Resource 'Storage.66e0b27c-dcbb-4b6e-9a58-8213e8380c65' is free. Now locking as 'shared' (1 active user)
Thread-622::DEBUG::2013-12-31 05:06:02,572::resourceManager::237::ResourceManager.Request::(grant) ResName=`Storage.66e0b27c-dcbb-4b6e-9a58-8213e8380c65`ReqID=`29fe830e-439e-446c-89c0-299774de0dda`::Granted request
Thread-622::DEBUG::2013-12-31 05:06:02,572::task::811::TaskManager.Task::(resourceAcquired) Task=`8ae0743d-8e3e-4516-9d67-7713fa546759`::_resourcesAcquired: Storage.66e0b27c-dcbb-4b6e-9a58-8213e8380c65 (shared)
Thread-622::DEBUG::2013-12-31 05:06:02,572::task::974::TaskManager.Task::(_decref) Task=`8ae0743d-8e3e-4516-9d67-7713fa546759`::ref 1 aborting False
Thread-622::DEBUG::2013-12-31 05:06:02,574::fileVolume::528::Storage.Volume::(validateVolumePath) validate path for b0839e46-9f79-49b2-ae34-f7cafb626c75



----------------------------------------------------------------------


[10.3.72.248]::call vmCreate with ({'acpiEnable': 'true', 'emulatedMachine': 'pc-1.0', 'vmId': '147a1deb-3795-4153-a820-ceabd8db15f3', 'memGuaranteedSize': 1024, 'spiceSslCipherSuite': 'DEFAULT', 'timeOffset': '0', 'cpuType': 'Penryn', 'smp': '1', 'custom': {}, 'vmType': 'kvm', 'memSize': 1024, 'smpCoresPerSocket': '1', 'vmName': 'TestVm', 'nice': '0', 'smartcardEnable': 'false', 'keyboardLayout': 'en-us', 'kvmEnable': 'true', 'pitReinjection': 'false', 'transparentHugePages': 'true', 'devices': [{'index': '0', 'specParams': {}, 'deviceId': '0646d163-0a5d-48fd-b0a7-868f597745cf', 'device': 'usb', 'model': 'ich9-ehci1', 'type': 'controller'}, {'device': 'qxl', 'specParams': {'vram': '32768', 'ram': '65536', 'heads': '1'}, 'type': 'video', 'deviceId': '1b78d7e2-0a1f-49ca-a87d-98cbae4b930a'}, {'index': '2', 'iface': 'ide', 'bootOrder': '1', 'specParams': {}, 'readonly': 'true', 'deviceId': '12cdc33e-b6df-42fc-890f-756fd4fb7957', 'path': '/rhev/data-center/mnt/ovirt-manager.local:_var_lib_exports_iso/e15927a5-1c3c-4e49-8a19-3790f411ddb8/images/11111111-1111-1111-1111-111111111111/CentOS-6.4-x86_64-bin-DVD1.iso', 'device': 'cdrom', 'shared': 'false', 'type': 'disk'}, {'index': 0, 'iface': 'virtio', 'format': 'raw', 'bootOrder': '2', 'volumeID': 'b0839e46-9f79-49b2-ae34-f7cafb626c75', 'imageID': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'specParams': {}, 'readonly': 'false', 'domainID': '66e0b27c-dcbb-4b6e-9a58-8213e8380c65', 'optional': 'false', 'deviceId': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'poolID': 'b709443b-9933-46aa-86cd-c687bb8953f8', 'device': 'disk', 'shared': 'false', 'propagateErrors': 'off', 'type': 'disk'}, {'nicModel': 'pv', 'macAddr': '00:1a:4a:6f:be:39', 'linkActive': 'true', 'network': 'ovirtmgmt', 'bootOrder': '3', 'custom': {}, 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '2a99a6d5-19fe-48c7-af8b-0f0ecbcc89be', 'device': 'bridge', 'type': 'interface'}, {'index': '0', 'specParams': {}, 'deviceId': '8dc7a6f8-1ce1-46e6-99d8-79546a3fe2db', 'device': 'usb', 'model': 'ich9-uhci1', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '60de3c26-96ef-41bc-98c2-3e3da3f5f3a7', 'device': 'usb', 'model': 'ich9-uhci2', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '8cee35e8-419c-4239-87f7-822ce59536e3', 'device': 'usb', 'model': 'ich9-uhci3', 'type': 'controller'}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '52e20b63-f8c6-4366-a5ec-1dd86c9162cb', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '81e2498f-5cd9-475e-9e54-7ef4dda44ced', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '935b6410-cf72-49d5-8d0c-0412f939bfa2', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '03f2d01b-368d-46f5-b5e2-6a92b07e84e1', 'specParams': {}}, {'device': 'memballoon', 'specParams': {'model': 'virtio'}, 'type': 'balloon', 'deviceId': 'b94730de-6edc-4da3-9888-539c9fb7b22e'}], 'spiceSecureChannels': 'smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard', 'display': 'qxl'},) {}
Thread-793::INFO::2013-12-31 06:33:05,874::API::642::vds::(_getNetworkIp) network None: using 0
Thread-793::INFO::2013-12-31 06:33:05,877::clientIF::391::vds::(createVm) vmContainerLock acquired by vm 147a1deb-3795-4153-a820-ceabd8db15f3
Thread-793::DEBUG::2013-12-31 06:33:05,880::libvirtconnection::131::libvirtconnection::(get) trying to connect libvirt
Thread-794::DEBUG::2013-12-31 06:33:05,941::vm::2085::vm.Vm::(_startUnderlyingVm) vmId=`147a1deb-3795-4153-a820-ceabd8db15f3`::Start
Thread-793::DEBUG::2013-12-31 06:33:05,942::clientIF::404::vds::(createVm) Total desktops after creation of 147a1deb-3795-4153-a820-ceabd8db15f3 is 1
Thread-794::DEBUG::2013-12-31 06:33:05,944::vm::2089::vm.Vm::(_startUnderlyingVm) vmId=`147a1deb-3795-4153-a820-ceabd8db15f3`::_ongoingCreations acquired
Thread-793::DEBUG::2013-12-31 06:33:05,944::BindingXMLRPC::981::vds::(wrapper) return vmCreate with {'status': {'message': 'Done', 'code': 0}, 'vmList': {'status': 'WaitForLaunch', 'acpiEnable': 'true', 'emulatedMachine': 'pc-1.0', 'vmId': '147a1deb-3795-4153-a820-ceabd8db15f3', 'pid': '0', 'memGuaranteedSize': 1024, 'timeOffset': '0', 'keyboardLayout': 'en-us', 'displayPort': '-1', 'displaySecurePort': '-1', 'spiceSslCipherSuite': 'DEFAULT', 'cpuType': 'Penryn', 'custom': {}, 'clientIp': '', 'nicModel': 'rtl8139,pv', 'smartcardEnable': 'false', 'kvmEnable': 'true', 'pitReinjection': 'false', 'transparentHugePages': 'true', 'devices': [{'index': '0', 'specParams': {}, 'deviceId': '0646d163-0a5d-48fd-b0a7-868f597745cf', 'device': 'usb', 'model': 'ich9-ehci1', 'type': 'controller'}, {'device': 'qxl', 'specParams': {'vram': '32768', 'ram': '65536', 'heads': '1'}, 'type': 'video', 'deviceId': '1b78d7e2-0a1f-49ca-a87d-98cbae4b930a'}, {'index': '2', 'iface': 'ide', 'bootOrder': '1', 'specParams': {}, 'readonly': 'true', 'deviceId': '12cdc33e-b6df-42fc-890f-756fd4fb7957', 'path': '/rhev/data-center/mnt/ovirt-manager.local:_var_lib_exports_iso/e15927a5-1c3c-4e49-8a19-3790f411ddb8/images/11111111-1111-1111-1111-111111111111/CentOS-6.4-x86_64-bin-DVD1.iso', 'device': 'cdrom', 'shared': 'false', 'type': 'disk'}, {'index': 0, 'iface': 'virtio', 'format': 'raw', 'bootOrder': '2', 'volumeID': 'b0839e46-9f79-49b2-ae34-f7cafb626c75', 'imageID': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'specParams': {}, 'readonly': 'false', 'domainID': '66e0b27c-dcbb-4b6e-9a58-8213e8380c65', 'optional': 'false', 'deviceId': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'poolID': 'b709443b-9933-46aa-86cd-c687bb8953f8', 'device': 'disk', 'shared': 'false', 'propagateErrors': 'off', 'type': 'disk'}, {'nicModel': 'pv', 'macAddr': '00:1a:4a:6f:be:39', 'linkActive': 'true', 'network': 'ovirtmgmt', 'bootOrder': '3', 'custom': {}, 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '2a99a6d5-19fe-48c7-af8b-0f0ecbcc89be', 'device': 'bridge', 'type': 'interface'}, {'index': '0', 'specParams': {}, 'deviceId': '8dc7a6f8-1ce1-46e6-99d8-79546a3fe2db', 'device': 'usb', 'model': 'ich9-uhci1', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '60de3c26-96ef-41bc-98c2-3e3da3f5f3a7', 'device': 'usb', 'model': 'ich9-uhci2', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '8cee35e8-419c-4239-87f7-822ce59536e3', 'device': 'usb', 'model': 'ich9-uhci3', 'type': 'controller'}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '52e20b63-f8c6-4366-a5ec-1dd86c9162cb', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '81e2498f-5cd9-475e-9e54-7ef4dda44ced', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '935b6410-cf72-49d5-8d0c-0412f939bfa2', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '03f2d01b-368d-46f5-b5e2-6a92b07e84e1', 'specParams': {}}, {'device': 'memballoon', 'specParams': {'model': 'virtio'}, 'type': 'balloon', 'deviceId': 'b94730de-6edc-4da3-9888-539c9fb7b22e'}], 'smp': '1', 'vmType': 'kvm', 'memSize': 1024, 'displayIp': '0', 'spiceSecureChannels': 'smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard', 'smpCoresPerSocket': '1', 'vmName': 'TestVm', 'display': 'qxl', 'nice': '0'}}
Thread-794::INFO::2013-12-31 06:33:05,953::vm::2868::vm.Vm::(_run) vmId=`147a1deb-3795-4153-a820-ceabd8db15f3`::VM wrapper has started
Thread-794::DEBUG::2013-12-31 06:33:05,953::task::579::TaskManager.Task::(_updateState) Task=`cf47649e-0b06-47c1-b453-9136168dd84f`::moving from state init -> state preparing
Thread-794::INFO::2013-12-31 06:33:05,954::logUtils::44::dispatcher::(wrapper) Run and protect: getVolumeSize(sdUUID='66e0b27c-dcbb-4b6e-9a58-8213e8380c65', spUUID='b709443b-9933-46aa-86cd-c687bb8953f8', imgUUID='7c063b30-634a-483b-82aa-7f19d2ae46cd', volUUID='b0839e46-9f79-49b2-ae34-f7cafb626c75', options=None)
Thread-794::INFO::2013-12-31 06:33:05,977::logUtils::47::dispatcher::(wrapper) Run and protect: getVolumeSize, Return response: {'truesize': '0', 'apparentsize': '42949672960'}
Thread-794::DEBUG::2013-12-31 06:33:05,981::task::1168::TaskManager.Task::(prepare) Task=`cf47649e-0b06-47c1-b453-9136168dd84f`::finished: {'truesize': '0', 'apparentsize': '42949672960'}
Thread-794::DEBUG::2013-12-31 06:33:05,984::task::579::TaskManager.Task::(_updateState) Task=`cf47649e-0b06-47c1-b453-9136168dd84f`::moving from state preparing -> state finished
Thread-794::DEBUG::2013-12-31 06:33:05,986::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
Thread-794::DEBUG::2013-12-31 06:33:05,986::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
Thread-794::DEBUG::2013-12-31 06:33:05,989::task::974::TaskManager.Task::(_decref) Task=`cf47649e-0b06-47c1-b453-9136168dd84f`::ref 0 aborting False
Thread-794::INFO::2013-12-31 06:33:05,989::clientIF::350::vds::(prepareVolumePath) prepared volume path: /rhev/data-center/mnt/ovirt-manager.local:_var_lib_exports_iso/e15927a5-1c3c-4e49-8a19-3790f411ddb8/images/11111111-1111-1111-1111-111111111111/CentOS-6.4-x86_64-bin-DVD1.iso
Thread-794::DEBUG::2013-12-31 06:33:05,994::task::579::TaskManager.Task::(_updateState) Task=`8789ca2d-455a-4dfb-a188-d13ed7bb5b48`::moving from state init -> state preparing
Thread-794::INFO::2013-12-31 06:33:05,994::logUtils::44::dispatcher::(wrapper) Run and protect: prepareImage(sdUUID='66e0b27c-dcbb-4b6e-9a58-8213e8380c65', spUUID='b709443b-9933-46aa-86cd-c687bb8953f8', imgUUID='7c063b30-634a-483b-82aa-7f19d2ae46cd', leafUUID='b0839e46-9f79-49b2-ae34-f7cafb626c75')
Thread-794::DEBUG::2013-12-31 06:33:05,998::resourceManager::197::ResourceManager.Request::(__init__) ResName=`Storage.66e0b27c-dcbb-4b6e-9a58-8213e8380c65`ReqID=`eabb4923-625b-4ac3-906c-7416aef86a64`::Request was made in '/usr/share/vdsm/storage/hsm.py' line '3230' at 'prepareImage'
Thread-794::DEBUG::2013-12-31 06:33:06,001::resourceManager::541::ResourceManager::(registerResource) Trying to register resource 'Storage.66e0b27c-dcbb-4b6e-9a58-8213e8380c65' for lock type 'shared'
Thread-794::DEBUG::2013-12-31 06:33:06,003::resourceManager::600::ResourceManager::(registerResource) Resource 'Storage.66e0b27c-dcbb-4b6e-9a58-8213e8380c65' is free. Now locking as 'shared' (1 active user)
Thread-794::DEBUG::2013-12-31 06:33:06,003::resourceManager::237::ResourceManager.Request::(grant) ResName=`Storage.66e0b27c-dcbb-4b6e-9a58-8213e8380c65`ReqID=`eabb4923-625b-4ac3-906c-7416aef86a64`::Granted request
Thread-794::DEBUG::2013-12-31 06:33:06,006::task::811::TaskManager.Task::(resourceAcquired) Task=`8789ca2d-455a-4dfb-a188-d13ed7bb5b48`::_resourcesAcquired: Storage.66e0b27c-dcbb-4b6e-9a58-8213e8380c65 (shared)
Thread-794::DEBUG::2013-12-31 06:33:06,010::task::974::TaskManager.Task::(_decref) Task=`8789ca2d-455a-4dfb-a188-d13ed7bb5b48`::ref 1 aborting False
Thread-794::DEBUG::2013-12-31 06:33:06,028::fileVolume::528::Storage.Volume::(validateVolumePath) validate path for b0839e46-9f79-49b2-ae34-f7cafb626c75
Thread-794::INFO::2013-12-31

----------------------------------------------------------------------

[10.3.72.248]::call vmCreate with ({'acpiEnable': 'true', 'emulatedMachine': 'pc-1.0', 'vmId': '147a1deb-3795-4153-a820-ceabd8db15f3', 'memGuaranteedSize': 1024, 'spiceSslCipherSuite': 'DEFAULT', 'timeOffset': '0', 'cpuType': 'Penryn', 'smp': '1', 'custom': {}, 'vmType': 'kvm', 'memSize': 1024, 'smpCoresPerSocket': '1', 'vmName': 'TestVm', 'nice': '0', 'smartcardEnable': 'false', 'keyboardLayout': 'en-us', 'kvmEnable': 'true', 'pitReinjection': 'false', 'transparentHugePages': 'true', 'devices': [{'index': '0', 'specParams': {}, 'deviceId': '0646d163-0a5d-48fd-b0a7-868f597745cf', 'device': 'usb', 'model': 'ich9-ehci1', 'type': 'controller'}, {'device': 'qxl', 'specParams': {'vram': '32768', 'ram': '65536', 'heads': '1'}, 'type': 'video', 'deviceId': '1b78d7e2-0a1f-49ca-a87d-98cbae4b930a'}, {'index': '2', 'iface': 'ide', 'bootOrder': '1', 'specParams': {}, 'readonly': 'true', 'deviceId': 'fc6c202f-0fb1-4195-b5d1-2247e52ba2aa', 'path': '/rhev/data-center/mnt/ovirt-manager.local:_var_lib_exports_iso/e15927a5-1c3c-4e49-8a19-3790f411ddb8/images/11111111-1111-1111-1111-111111111111/CentOS-6.4-x86_64-bin-DVD1.iso', 'device': 'cdrom', 'shared': 'false', 'type': 'disk'}, {'index': 0, 'iface': 'virtio', 'format': 'raw', 'bootOrder': '2', 'volumeID': 'b0839e46-9f79-49b2-ae34-f7cafb626c75', 'imageID': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'specParams': {}, 'readonly': 'false', 'domainID': '66e0b27c-dcbb-4b6e-9a58-8213e8380c65', 'optional': 'false', 'deviceId': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'poolID': 'b709443b-9933-46aa-86cd-c687bb8953f8', 'device': 'disk', 'shared': 'false', 'propagateErrors': 'off', 'type': 'disk'}, {'nicModel': 'pv', 'macAddr': '00:1a:4a:6f:be:39', 'linkActive': 'true', 'network': 'ovirtmgmt', 'bootOrder': '3', 'custom': {}, 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '2a99a6d5-19fe-48c7-af8b-0f0ecbcc89be', 'device': 'bridge', 'type': 'interface'}, {'index': '0', 'specParams': {}, 'deviceId': '8dc7a6f8-1ce1-46e6-99d8-79546a3fe2db', 'device': 'usb', 'model': 'ich9-uhci1', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '60de3c26-96ef-41bc-98c2-3e3da3f5f3a7', 'device': 'usb', 'model': 'ich9-uhci2', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '8cee35e8-419c-4239-87f7-822ce59536e3', 'device': 'usb', 'model': 'ich9-uhci3', 'type': 'controller'}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '52e20b63-f8c6-4366-a5ec-1dd86c9162cb', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '81e2498f-5cd9-475e-9e54-7ef4dda44ced', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '935b6410-cf72-49d5-8d0c-0412f939bfa2', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '03f2d01b-368d-46f5-b5e2-6a92b07e84e1', 'specParams': {}}, {'device': 'memballoon', 'specParams': {'model': 'virtio'}, 'type': 'balloon', 'deviceId': '1f17b364-919d-4e60-a6e1-facca4475e2d'}], 'spiceSecureChannels': 'smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard', 'display': 'qxl'},) {}
Thread-825::INFO::2013-12-31 06:33:54,597::API::642::vds::(_getNetworkIp) network None: using 0
Thread-825::INFO::2013-12-31 06:33:54,600::clientIF::391::vds::(createVm) vmContainerLock acquired by vm 147a1deb-3795-4153-a820-ceabd8db15f3
Thread-826::DEBUG::2013-12-31 06:33:54,668::vm::2085::vm.Vm::(_startUnderlyingVm) vmId=`147a1deb-3795-4153-a820-ceabd8db15f3`::Start
Thread-826::DEBUG::2013-12-31 06:33:54,668::vm::2089::vm.Vm::(_startUnderlyingVm) vmId=`147a1deb-3795-4153-a820-ceabd8db15f3`::_ongoingCreations acquired
Thread-825::DEBUG::2013-12-31 06:33:54,668::clientIF::404::vds::(createVm) Total desktops after creation of 147a1deb-3795-4153-a820-ceabd8db15f3 is 1
Thread-825::DEBUG::2013-12-31 06:33:54,684::BindingXMLRPC::981::vds::(wrapper) return vmCreate with {'status': {'message': 'Done', 'code': 0}, 'vmList': {'status': 'WaitForLaunch', 'acpiEnable': 'true', 'emulatedMachine': 'pc-1.0', 'vmId': '147a1deb-3795-4153-a820-ceabd8db15f3', 'pid': '0', 'memGuaranteedSize': 1024, 'timeOffset': '0', 'keyboardLayout': 'en-us', 'displayPort': '-1', 'displaySecurePort': '-1', 'spiceSslCipherSuite': 'DEFAULT', 'cpuType': 'Penryn', 'custom': {}, 'clientIp': '', 'nicModel': 'rtl8139,pv', 'smartcardEnable': 'false', 'kvmEnable': 'true', 'pitReinjection': 'false', 'transparentHugePages': 'true', 'devices': [{'index': '0', 'specParams': {}, 'deviceId': '0646d163-0a5d-48fd-b0a7-868f597745cf', 'device': 'usb', 'model': 'ich9-ehci1', 'type': 'controller'}, {'device': 'qxl', 'specParams': {'vram': '32768', 'ram': '65536', 'heads': '1'}, 'type': 'video', 'deviceId': '1b78d7e2-0a1f-49ca-a87d-98cbae4b930a'}, {'index': '2', 'iface': 'ide', 'bootOrder': '1', 'specParams': {}, 'readonly': 'true', 'deviceId': 'fc6c202f-0fb1-4195-b5d1-2247e52ba2aa', 'path': '/rhev/data-center/mnt/ovirt-manager.local:_var_lib_exports_iso/e15927a5-1c3c-4e49-8a19-3790f411ddb8/images/11111111-1111-1111-1111-111111111111/CentOS-6.4-x86_64-bin-DVD1.iso', 'device': 'cdrom', 'shared': 'false', 'type': 'disk'}, {'index': 0, 'iface': 'virtio', 'format': 'raw', 'bootOrder': '2', 'volumeID': 'b0839e46-9f79-49b2-ae34-f7cafb626c75', 'imageID': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'specParams': {}, 'readonly': 'false', 'domainID': '66e0b27c-dcbb-4b6e-9a58-8213e8380c65', 'optional': 'false', 'deviceId': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'poolID': 'b709443b-9933-46aa-86cd-c687bb8953f8', 'device': 'disk', 'shared': 'false', 'propagateErrors': 'off', 'type': 'disk'}, {'nicModel': 'pv', 'macAddr': '00:1a:4a:6f:be:39', 'linkActive': 'true', 'network': 'ovirtmgmt', 'bootOrder': '3', 'custom': {}, 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '2a99a6d5-19fe-48c7-af8b-0f0ecbcc89be', 'device': 'bridge', 'type': 'interface'}, {'index': '0', 'specParams': {}, 'deviceId': '8dc7a6f8-1ce1-46e6-99d8-79546a3fe2db', 'device': 'usb', 'model': 'ich9-uhci1', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '60de3c26-96ef-41bc-98c2-3e3da3f5f3a7', 'device': 'usb', 'model': 'ich9-uhci2', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '8cee35e8-419c-4239-87f7-822ce59536e3', 'device': 'usb', 'model': 'ich9-uhci3', 'type': 'controller'}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '52e20b63-f8c6-4366-a5ec-1dd86c9162cb', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '81e2498f-5cd9-475e-9e54-7ef4dda44ced', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '935b6410-cf72-49d5-8d0c-0412f939bfa2', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '03f2d01b-368d-46f5-b5e2-6a92b07e84e1', 'specParams': {}}, {'device': 'memballoon', 'specParams': {'model': 'virtio'}, 'type': 'balloon', 'deviceId': '1f17b364-919d-4e60-a6e1-facca4475e2d'}], 'smp': '1', 'vmType': 'kvm', 'memSize': 1024, 'displayIp': '0', 'spiceSecureChannels': 'smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard', 'smpCoresPerSocket': '1', 'vmName': 'TestVm', 'display': 'qxl', 'nice': '0'}}
Thread-826::INFO::2013-12-31 06:33:54,688::vm::2868::vm.Vm::(_run) vmId=`147a1deb-3795-4153-a820-ceabd8db15f3`::VM wrapper has started
Thread-826::DEBUG::2013-12-31 06:33:54,706::task::579::TaskManager.Task::(_updateState) Task=`0b19ca33-0d79-4cb6-aa74-2b5270208261`::moving from state init -> state preparing
Thread-826::INFO::2013-12-31 06:33:54,710::logUtils::44::dispatcher::(wrapper) Run and protect: getVolumeSize(sdUUID='66e0b27c-dcbb-4b6e-9a58-8213e8380c65', spUUID='b709443b-9933-46aa-86cd-c687bb8953f8', imgUUID='7c063b30-634a-483b-82aa-7f19d2ae46cd', volUUID='b0839e46-9f79-49b2-ae34-f7cafb626c75', options=None)
Thread-826::INFO::2013-12-31 06:33:54,732::logUtils::47::dispatcher::(wrapper) Run and protect: getVolumeSize, Return response: {'truesize': '0', 'apparentsize': '42949672960'}
Thread-826::DEBUG::2013-12-31 06:33:54,740::task::1168::TaskManager.Task::(prepare) Task=`0b19ca33-0d79-4cb6-aa74-2b5270208261`::finished: {'truesize': '0', 'apparentsize': '42949672960'}
Thread-826::DEBUG::2013-12-31 06:33:54,747::task::579::TaskManager.Task::(_updateState) Task=`0b19ca33-0d79-4cb6-aa74-2b5270208261`::moving from state preparing -> state finished
Thread-826::DEBUG::2013-12-31 06:33:54,756::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
Thread-826::DEBUG::2013-12-31 06:33:54,756::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
Thread-826::DEBUG::2013-12-31 06:33:54,762::task::974::TaskManager.Task::(_decref) Task=`0b19ca33-0d79-4cb6-aa74-2b5270208261`::ref 0 aborting False
Thread-826::INFO::2013-12-31 06:33:54,766::clientIF::350::vds::(prepareVolumePath) prepared volume path: /rhev/data-center/mnt/ovirt-manager.local:_var_lib_exports_iso/e15927a5-1c3c-4e49-8a19-3790f411ddb8/images/11111111-1111-1111-1111-111111111111/CentOS-6.4-x86_64-bin-DVD1.iso
Thread-826::DEBUG::2013-12-31 06:33:54,775::task::579::TaskManager.Task::(_updateState) Task=`f8a768c8-a7e1-4f42-b49e-04f33ab41d94`::moving from state init -> state preparing
Thread-826::INFO::2013-12-31 

---------------------------------------------------------------------

Thread-354::DEBUG::2013-12-31 09:41:31,794::BindingXMLRPC::974::vds::(wrapper) client [10.3.72.248]::call vmCreate with ({'acpiEnable': 'true', 'emulatedMachine': 'pc-1.0', 'vmId': '147a1deb-3795-4153-a820-ceabd8db15f3', 'memGuaranteedSize': 1024, 'spiceSslCipherSuite': 'DEFAULT', 'timeOffset': '0', 'cpuType': 'Penryn', 'smp': '1', 'custom': {}, 'vmType': 'kvm', 'memSize': 1024, 'smpCoresPerSocket': '1', 'vmName': 'TestVm', 'nice': '0', 'smartcardEnable': 'false', 'keyboardLayout': 'en-us', 'kvmEnable': 'true', 'pitReinjection': 'false', 'transparentHugePages': 'true', 'devices': [{'index': '0', 'specParams': {}, 'deviceId': '0646d163-0a5d-48fd-b0a7-868f597745cf', 'device': 'usb', 'model': 'ich9-ehci1', 'type': 'controller'}, {'device': 'qxl', 'specParams': {'vram': '32768', 'ram': '65536', 'heads': '1'}, 'type': 'video', 'deviceId': '1b78d7e2-0a1f-49ca-a87d-98cbae4b930a'}, {'index': '2', 'iface': 'ide', 'bootOrder': '1', 'specParams': {}, 'readonly': 'true', 'deviceId': 'd0d47c38-7d18-46ad-aef4-61cbdd4e42e9', 'path': '/rhev/data-center/mnt/ovirt-manager.local:_var_lib_exports_iso/e15927a5-1c3c-4e49-8a19-3790f411ddb8/images/11111111-1111-1111-1111-111111111111/CentOS-6.4-x86_64-bin-DVD1.iso', 'device': 'cdrom', 'shared': 'false', 'type': 'disk'}, {'index': 0, 'iface': 'virtio', 'format': 'raw', 'bootOrder': '2', 'volumeID': 'b0839e46-9f79-49b2-ae34-f7cafb626c75', 'imageID': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'specParams': {}, 'readonly': 'false', 'domainID': '66e0b27c-dcbb-4b6e-9a58-8213e8380c65', 'optional': 'false', 'deviceId': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'poolID': 'b709443b-9933-46aa-86cd-c687bb8953f8', 'device': 'disk', 'shared': 'false', 'propagateErrors': 'off', 'type': 'disk'}, {'nicModel': 'pv', 'macAddr': '00:1a:4a:6f:be:39', 'linkActive': 'true', 'network': 'ovirtmgmt', 'bootOrder': '3', 'custom': {}, 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '2a99a6d5-19fe-48c7-af8b-0f0ecbcc89be', 'device': 'bridge', 'type': 'interface'}, {'index': '0', 'specParams': {}, 'deviceId': '8dc7a6f8-1ce1-46e6-99d8-79546a3fe2db', 'device': 'usb', 'model': 'ich9-uhci1', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '60de3c26-96ef-41bc-98c2-3e3da3f5f3a7', 'device': 'usb', 'model': 'ich9-uhci2', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '8cee35e8-419c-4239-87f7-822ce59536e3', 'device': 'usb', 'model': 'ich9-uhci3', 'type': 'controller'}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '52e20b63-f8c6-4366-a5ec-1dd86c9162cb', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '81e2498f-5cd9-475e-9e54-7ef4dda44ced', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '935b6410-cf72-49d5-8d0c-0412f939bfa2', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '03f2d01b-368d-46f5-b5e2-6a92b07e84e1', 'specParams': {}}, {'device': 'memballoon', 'specParams': {'model': 'virtio'}, 'type': 'balloon', 'deviceId': '15d11553-3d7c-47cc-95cb-fd17986a3822'}], 'spiceSecureChannels': 'smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard', 'display': 'qxl'},) {}
Thread-354::INFO::2013-12-31 09:41:31,796::API::642::vds::(_getNetworkIp) network None: using 0
Thread-354::INFO::2013-12-31 09:41:31,796::clientIF::391::vds::(createVm) vmContainerLock acquired by vm 147a1deb-3795-4153-a820-ceabd8db15f3
Thread-354::DEBUG::2013-12-31 09:41:31,796::libvirtconnection::131::libvirtconnection::(get) trying to connect libvirt
Thread-355::DEBUG::2013-12-31 09:41:31,812::vm::2085::vm.Vm::(_startUnderlyingVm) vmId=`147a1deb-3795-4153-a820-ceabd8db15f3`::Start
Thread-354::DEBUG::2013-12-31 09:41:31,812::clientIF::404::vds::(createVm) Total desktops after creation of 147a1deb-3795-4153-a820-ceabd8db15f3 is 1
Thread-355::DEBUG::2013-12-31 09:41:31,812::vm::2089::vm.Vm::(_startUnderlyingVm) vmId=`147a1deb-3795-4153-a820-ceabd8db15f3`::_ongoingCreations acquired
Thread-354::DEBUG::2013-12-31 09:41:31,812::BindingXMLRPC::981::vds::(wrapper) return vmCreate with {'status': {'message': 'Done', 'code': 0}, 'vmList': {'status': 'WaitForLaunch', 'acpiEnable': 'true', 'emulatedMachine': 'pc-1.0', 'vmId': '147a1deb-3795-4153-a820-ceabd8db15f3', 'pid': '0', 'memGuaranteedSize': 1024, 'timeOffset': '0', 'keyboardLayout': 'en-us', 'displayPort': '-1', 'displaySecurePort': '-1', 'spiceSslCipherSuite': 'DEFAULT', 'cpuType': 'Penryn', 'custom': {}, 'clientIp': '', 'nicModel': 'rtl8139,pv', 'smartcardEnable': 'false', 'kvmEnable': 'true', 'pitReinjection': 'false', 'transparentHugePages': 'true', 'devices': [{'index': '0', 'specParams': {}, 'deviceId': '0646d163-0a5d-48fd-b0a7-868f597745cf', 'device': 'usb', 'model': 'ich9-ehci1', 'type': 'controller'}, {'device': 'qxl', 'specParams': {'vram': '32768', 'ram': '65536', 'heads': '1'}, 'type': 'video', 'deviceId': '1b78d7e2-0a1f-49ca-a87d-98cbae4b930a'}, {'index': '2', 'iface': 'ide', 'bootOrder': '1', 'specParams': {}, 'readonly': 'true', 'deviceId': 'd0d47c38-7d18-46ad-aef4-61cbdd4e42e9', 'path': '/rhev/data-center/mnt/ovirt-manager.local:_var_lib_exports_iso/e15927a5-1c3c-4e49-8a19-3790f411ddb8/images/11111111-1111-1111-1111-111111111111/CentOS-6.4-x86_64-bin-DVD1.iso', 'device': 'cdrom', 'shared': 'false', 'type': 'disk'}, {'index': 0, 'iface': 'virtio', 'format': 'raw', 'bootOrder': '2', 'volumeID': 'b0839e46-9f79-49b2-ae34-f7cafb626c75', 'imageID': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'specParams': {}, 'readonly': 'false', 'domainID': '66e0b27c-dcbb-4b6e-9a58-8213e8380c65', 'optional': 'false', 'deviceId': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'poolID': 'b709443b-9933-46aa-86cd-c687bb8953f8', 'device': 'disk', 'shared': 'false', 'propagateErrors': 'off', 'type': 'disk'}, {'nicModel': 'pv', 'macAddr': '00:1a:4a:6f:be:39', 'linkActive': 'true', 'network': 'ovirtmgmt', 'bootOrder': '3', 'custom': {}, 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '2a99a6d5-19fe-48c7-af8b-0f0ecbcc89be', 'device': 'bridge', 'type': 'interface'}, {'index': '0', 'specParams': {}, 'deviceId': '8dc7a6f8-1ce1-46e6-99d8-79546a3fe2db', 'device': 'usb', 'model': 'ich9-uhci1', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '60de3c26-96ef-41bc-98c2-3e3da3f5f3a7', 'device': 'usb', 'model': 'ich9-uhci2', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '8cee35e8-419c-4239-87f7-822ce59536e3', 'device': 'usb', 'model': 'ich9-uhci3', 'type': 'controller'}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '52e20b63-f8c6-4366-a5ec-1dd86c9162cb', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '81e2498f-5cd9-475e-9e54-7ef4dda44ced', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '935b6410-cf72-49d5-8d0c-0412f939bfa2', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '03f2d01b-368d-46f5-b5e2-6a92b07e84e1', 'specParams': {}}, {'device': 'memballoon', 'specParams': {'model': 'virtio'}, 'type': 'balloon', 'deviceId': '15d11553-3d7c-47cc-95cb-fd17986a3822'}], 'smp': '1', 'vmType': 'kvm', 'memSize': 1024, 'displayIp': '0', 'spiceSecureChannels': 'smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard', 'smpCoresPerSocket': '1', 'vmName': 'TestVm', 'display': 'qxl', 'nice': '0'}}
Thread-355::INFO::2013-12-31 09:41:31,812::vm::2868::vm.Vm::(_run) vmId=`147a1deb-3795-4153-a820-ceabd8db15f3`::VM wrapper has started
Thread-355::DEBUG::2013-12-31 09:41:31,814::task::579::TaskManager.Task::(_updateState) Task=`b7d83b7e-c065-47a7-9b2f-82217d88678b`::moving from state init -> state preparing
Thread-355::INFO::2013-12-31 09:41:31,814::logUtils::44::dispatcher::(wrapper) Run and protect: getVolumeSize(sdUUID='66e0b27c-dcbb-4b6e-9a58-8213e8380c65', spUUID='b709443b-9933-46aa-86cd-c687bb8953f8', imgUUID='7c063b30-634a-483b-82aa-7f19d2ae46cd', volUUID='b0839e46-9f79-49b2-ae34-f7cafb626c75', options=None)

---------------------------------------------------------------------------

Thread-515::DEBUG::2013-12-31 14:46:15,424::BindingXMLRPC::974::vds::(wrapper) client [10.3.72.248]::call vmCreate with ({'acpiEnable': 'true', 'emulatedMachine': 'pc-1.0', 'vmId': '147a1deb-3795-4153-a820-ceabd8db15f3', 'memGuaranteedSize': 1024, 'spiceSslCipherSuite': 'DEFAULT', 'timeOffset': '0', 'cpuType': 'Penryn', 'smp': '1', 'custom': {}, 'vmType': 'kvm', 'memSize': 1024, 'smpCoresPerSocket': '1', 'vmName': 'TestVm', 'nice': '0', 'smartcardEnable': 'false', 'keyboardLayout': 'en-us', 'kvmEnable': 'true', 'pitReinjection': 'false', 'transparentHugePages': 'true', 'devices': [{'index': '0', 'specParams': {}, 'deviceId': '0646d163-0a5d-48fd-b0a7-868f597745cf', 'device': 'usb', 'model': 'ich9-ehci1', 'type': 'controller'}, {'device': 'qxl', 'specParams': {'vram': '32768', 'ram': '65536', 'heads': '1'}, 'type': 'video', 'deviceId': '1b78d7e2-0a1f-49ca-a87d-98cbae4b930a'}, {'index': '2', 'iface': 'ide', 'bootOrder': '1', 'specParams': {}, 'readonly': 'true', 'deviceId': '2f1a0b6b-a663-45db-847e-770c195c2fda', 'path': '/rhev/data-center/mnt/ovirt-manager.local:_var_lib_exports_iso/e15927a5-1c3c-4e49-8a19-3790f411ddb8/images/11111111-1111-1111-1111-111111111111/CentOS-6.4-x86_64-bin-DVD1.iso', 'device': 'cdrom', 'shared': 'false', 'type': 'disk'}, {'index': 0, 'iface': 'virtio', 'format': 'raw', 'bootOrder': '2', 'volumeID': 'b0839e46-9f79-49b2-ae34-f7cafb626c75', 'imageID': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'specParams': {}, 'readonly': 'false', 'domainID': '66e0b27c-dcbb-4b6e-9a58-8213e8380c65', 'optional': 'false', 'deviceId': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'poolID': 'b709443b-9933-46aa-86cd-c687bb8953f8', 'device': 'disk', 'shared': 'false', 'propagateErrors': 'off', 'type': 'disk'}, {'nicModel': 'pv', 'macAddr': '00:1a:4a:6f:be:39', 'linkActive': 'true', 'network': 'ovirtmgmt', 'bootOrder': '3', 'custom': {}, 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '2a99a6d5-19fe-48c7-af8b-0f0ecbcc89be', 'device': 'bridge', 'type': 'interface'}, {'index': '0', 'specParams': {}, 'deviceId': '8dc7a6f8-1ce1-46e6-99d8-79546a3fe2db', 'device': 'usb', 'model': 'ich9-uhci1', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '60de3c26-96ef-41bc-98c2-3e3da3f5f3a7', 'device': 'usb', 'model': 'ich9-uhci2', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '8cee35e8-419c-4239-87f7-822ce59536e3', 'device': 'usb', 'model': 'ich9-uhci3', 'type': 'controller'}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '52e20b63-f8c6-4366-a5ec-1dd86c9162cb', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '81e2498f-5cd9-475e-9e54-7ef4dda44ced', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '935b6410-cf72-49d5-8d0c-0412f939bfa2', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '03f2d01b-368d-46f5-b5e2-6a92b07e84e1', 'specParams': {}}, {'device': 'memballoon', 'specParams': {'model': 'virtio'}, 'type': 'balloon', 'deviceId': '0eb2d043-b7d9-48e0-a6c7-d6957c95f81c'}], 'spiceSecureChannels': 'smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard', 'display': 'qxl'},) {}
Thread-515::INFO::2013-12-31 14:46:15,434::API::642::vds::(_getNetworkIp) network None: using 0
Thread-515::INFO::2013-12-31 14:46:15,435::clientIF::391::vds::(createVm) vmContainerLock acquired by vm 147a1deb-3795-4153-a820-ceabd8db15f3
Thread-516::DEBUG::2013-12-31 14:46:15,467::vm::2085::vm.Vm::(_startUnderlyingVm) vmId=`147a1deb-3795-4153-a820-ceabd8db15f3`::Start
Thread-516::DEBUG::2013-12-31 14:46:15,470::vm::2089::vm.Vm::(_startUnderlyingVm) vmId=`147a1deb-3795-4153-a820-ceabd8db15f3`::_ongoingCreations acquired
Thread-515::DEBUG::2013-12-31 14:46:15,470::clientIF::404::vds::(createVm) Total desktops after creation of 147a1deb-3795-4153-a820-ceabd8db15f3 is 1
Thread-515::DEBUG::2013-12-31 14:46:15,471::BindingXMLRPC::981::vds::(wrapper) return vmCreate with {'status': {'message': 'Done', 'code': 0}, 'vmList': {'status': 'WaitForLaunch', 'acpiEnable': 'true', 'emulatedMachine': 'pc-1.0', 'vmId': '147a1deb-3795-4153-a820-ceabd8db15f3', 'pid': '0', 'memGuaranteedSize': 1024, 'timeOffset': '0', 'keyboardLayout': 'en-us', 'displayPort': '-1', 'displaySecurePort': '-1', 'spiceSslCipherSuite': 'DEFAULT', 'cpuType': 'Penryn', 'custom': {}, 'clientIp': '', 'nicModel': 'rtl8139,pv', 'smartcardEnable': 'false', 'kvmEnable': 'true', 'pitReinjection': 'false', 'transparentHugePages': 'true', 'devices': [{'index': '0', 'specParams': {}, 'deviceId': '0646d163-0a5d-48fd-b0a7-868f597745cf', 'device': 'usb', 'model': 'ich9-ehci1', 'type': 'controller'}, {'device': 'qxl', 'specParams': {'vram': '32768', 'ram': '65536', 'heads': '1'}, 'type': 'video', 'deviceId': '1b78d7e2-0a1f-49ca-a87d-98cbae4b930a'}, {'index': '2', 'iface': 'ide', 'bootOrder': '1', 'specParams': {}, 'readonly': 'true', 'deviceId': '2f1a0b6b-a663-45db-847e-770c195c2fda', 'path': '/rhev/data-center/mnt/ovirt-manager.local:_var_lib_exports_iso/e15927a5-1c3c-4e49-8a19-3790f411ddb8/images/11111111-1111-1111-1111-111111111111/CentOS-6.4-x86_64-bin-DVD1.iso', 'device': 'cdrom', 'shared': 'false', 'type': 'disk'}, {'index': 0, 'iface': 'virtio', 'format': 'raw', 'bootOrder': '2', 'volumeID': 'b0839e46-9f79-49b2-ae34-f7cafb626c75', 'imageID': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'specParams': {}, 'readonly': 'false', 'domainID': '66e0b27c-dcbb-4b6e-9a58-8213e8380c65', 'optional': 'false', 'deviceId': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'poolID': 'b709443b-9933-46aa-86cd-c687bb8953f8', 'device': 'disk', 'shared': 'false', 'propagateErrors': 'off', 'type': 'disk'}, {'nicModel': 'pv', 'macAddr': '00:1a:4a:6f:be:39', 'linkActive': 'true', 'network': 'ovirtmgmt', 'bootOrder': '3', 'custom': {}, 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '2a99a6d5-19fe-48c7-af8b-0f0ecbcc89be', 'device': 'bridge', 'type': 'interface'}, {'index': '0', 'specParams': {}, 'deviceId': '8dc7a6f8-1ce1-46e6-99d8-79546a3fe2db', 'device': 'usb', 'model': 'ich9-uhci1', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '60de3c26-96ef-41bc-98c2-3e3da3f5f3a7', 'device': 'usb', 'model': 'ich9-uhci2', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '8cee35e8-419c-4239-87f7-822ce59536e3', 'device': 'usb', 'model': 'ich9-uhci3', 'type': 'controller'}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '52e20b63-f8c6-4366-a5ec-1dd86c9162cb', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '81e2498f-5cd9-475e-9e54-7ef4dda44ced', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '935b6410-cf72-49d5-8d0c-0412f939bfa2', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '03f2d01b-368d-46f5-b5e2-6a92b07e84e1', 'specParams': {}}, {'device': 'memballoon', 'specParams': {'model': 'virtio'}, 'type': 'balloon', 'deviceId': '0eb2d043-b7d9-48e0-a6c7-d6957c95f81c'}], 'smp': '1', 'vmType': 'kvm', 'memSize': 1024, 'displayIp': '0', 'spiceSecureChannels': 'smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard', 'smpCoresPerSocket': '1', 'vmName': 'TestVm', 'display': 'qxl', 'nice': '0'}}
Thread-516::INFO::2013-12-31 14:46:15,471::vm::2868::vm.Vm::(_run) vmId=`147a1deb-3795-4153-a820-ceabd8db15f3`::VM wrapper has started
Thread-516::DEBUG::2013-12-31 14:46:15,479::task::579::TaskManager.Task::(_updateState) Task=`2047b14a-f30c-4a91-8649-ebb4547629a4`::moving from state init -> state preparing
Thread-516::INFO::2013-12-31 

---------------------------------------------------------


Thread-164::DEBUG::2013-12-31 14:53:17,305::BindingXMLRPC::974::vds::(wrapper) client [10.3.72.248]::call vmCreate with ({'acpiEnable': 'true', 'emulatedMachine': 'pc-1.0', 'vmId': '147a1deb-3795-4153-a820-ceabd8db15f3', 'memGuaranteedSize': 1024, 'spiceSslCipherSuite': 'DEFAULT', 'timeOffset': '0', 'cpuType': 'Penryn', 'smp': '1', 'custom': {}, 'vmType': 'kvm', 'memSize': 1024, 'smpCoresPerSocket': '1', 'vmName': 'TestVm', 'nice': '0', 'smartcardEnable': 'false', 'keyboardLayout': 'en-us', 'kvmEnable': 'true', 'pitReinjection': 'false', 'transparentHugePages': 'true', 'devices': [{'index': '0', 'specParams': {}, 'deviceId': '0646d163-0a5d-48fd-b0a7-868f597745cf', 'device': 'usb', 'model': 'ich9-ehci1', 'type': 'controller'}, {'device': 'qxl', 'specParams': {'vram': '32768', 'ram': '65536', 'heads': '1'}, 'type': 'video', 'deviceId': '1b78d7e2-0a1f-49ca-a87d-98cbae4b930a'}, {'index': '2', 'iface': 'ide', 'bootOrder': '1', 'specParams': {}, 'readonly': 'true', 'deviceId': '07e313b9-fd1f-48ad-a5a1-21b05cfdf676', 'path': '/rhev/data-center/mnt/ovirt-manager.local:_var_lib_exports_iso/e15927a5-1c3c-4e49-8a19-3790f411ddb8/images/11111111-1111-1111-1111-111111111111/CentOS-6.4-x86_64-bin-DVD1.iso', 'device': 'cdrom', 'shared': 'false', 'type': 'disk'}, {'index': 0, 'iface': 'virtio', 'format': 'raw', 'bootOrder': '2', 'volumeID': 'b0839e46-9f79-49b2-ae34-f7cafb626c75', 'imageID': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'specParams': {}, 'readonly': 'false', 'domainID': '66e0b27c-dcbb-4b6e-9a58-8213e8380c65', 'optional': 'false', 'deviceId': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'poolID': 'b709443b-9933-46aa-86cd-c687bb8953f8', 'device': 'disk', 'shared': 'false', 'propagateErrors': 'off', 'type': 'disk'}, {'nicModel': 'pv', 'macAddr': '00:1a:4a:6f:be:39', 'linkActive': 'true', 'network': 'ovirtmgmt', 'bootOrder': '3', 'custom': {}, 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '2a99a6d5-19fe-48c7-af8b-0f0ecbcc89be', 'device': 'bridge', 'type': 'interface'}, {'index': '0', 'specParams': {}, 'deviceId': '8dc7a6f8-1ce1-46e6-99d8-79546a3fe2db', 'device': 'usb', 'model': 'ich9-uhci1', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '60de3c26-96ef-41bc-98c2-3e3da3f5f3a7', 'device': 'usb', 'model': 'ich9-uhci2', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '8cee35e8-419c-4239-87f7-822ce59536e3', 'device': 'usb', 'model': 'ich9-uhci3', 'type': 'controller'}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '52e20b63-f8c6-4366-a5ec-1dd86c9162cb', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '81e2498f-5cd9-475e-9e54-7ef4dda44ced', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '935b6410-cf72-49d5-8d0c-0412f939bfa2', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '03f2d01b-368d-46f5-b5e2-6a92b07e84e1', 'specParams': {}}, {'device': 'memballoon', 'specParams': {'model': 'virtio'}, 'type': 'balloon', 'deviceId': '57d13348-ec19-4963-be38-88e41626423f'}], 'spiceSecureChannels': 'smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard', 'display': 'qxl'},) {}
Thread-164::INFO::2013-12-31 14:53:17,306::API::642::vds::(_getNetworkIp) network None: using 0
Thread-164::INFO::2013-12-31 14:53:17,307::clientIF::391::vds::(createVm) vmContainerLock acquired by vm 147a1deb-3795-4153-a820-ceabd8db15f3
Thread-164::DEBUG::2013-12-31 14:53:17,307::libvirtconnection::131::libvirtconnection::(get) trying to connect libvirt
Thread-165::DEBUG::2013-12-31 14:53:17,322::vm::2085::vm.Vm::(_startUnderlyingVm) vmId=`147a1deb-3795-4153-a820-ceabd8db15f3`::Start
Thread-164::DEBUG::2013-12-31 14:53:17,322::clientIF::404::vds::(createVm) Total desktops after creation of 147a1deb-3795-4153-a820-ceabd8db15f3 is 1
Thread-165::DEBUG::2013-12-31 14:53:17,322::vm::2089::vm.Vm::(_startUnderlyingVm) vmId=`147a1deb-3795-4153-a820-ceabd8db15f3`::_ongoingCreations acquired
Thread-164::DEBUG::2013-12-31 14:53:17,322::BindingXMLRPC::981::vds::(wrapper) return vmCreate with {'status': {'message': 'Done', 'code': 0}, 'vmList': {'status': 'WaitForLaunch', 'acpiEnable': 'true', 'emulatedMachine': 'pc-1.0', 'vmId': '147a1deb-3795-4153-a820-ceabd8db15f3', 'pid': '0', 'memGuaranteedSize': 1024, 'timeOffset': '0', 'keyboardLayout': 'en-us', 'displayPort': '-1', 'displaySecurePort': '-1', 'spiceSslCipherSuite': 'DEFAULT', 'cpuType': 'Penryn', 'custom': {}, 'clientIp': '', 'nicModel': 'rtl8139,pv', 'smartcardEnable': 'false', 'kvmEnable': 'true', 'pitReinjection': 'false', 'transparentHugePages': 'true', 'devices': [{'index': '0', 'specParams': {}, 'deviceId': '0646d163-0a5d-48fd-b0a7-868f597745cf', 'device': 'usb', 'model': 'ich9-ehci1', 'type': 'controller'}, {'device': 'qxl', 'specParams': {'vram': '32768', 'ram': '65536', 'heads': '1'}, 'type': 'video', 'deviceId': '1b78d7e2-0a1f-49ca-a87d-98cbae4b930a'}, {'index': '2', 'iface': 'ide', 'bootOrder': '1', 'specParams': {}, 'readonly': 'true', 'deviceId': '07e313b9-fd1f-48ad-a5a1-21b05cfdf676', 'path': '/rhev/data-center/mnt/ovirt-manager.local:_var_lib_exports_iso/e15927a5-1c3c-4e49-8a19-3790f411ddb8/images/11111111-1111-1111-1111-111111111111/CentOS-6.4-x86_64-bin-DVD1.iso', 'device': 'cdrom', 'shared': 'false', 'type': 'disk'}, {'index': 0, 'iface': 'virtio', 'format': 'raw', 'bootOrder': '2', 'volumeID': 'b0839e46-9f79-49b2-ae34-f7cafb626c75', 'imageID': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'specParams': {}, 'readonly': 'false', 'domainID': '66e0b27c-dcbb-4b6e-9a58-8213e8380c65', 'optional': 'false', 'deviceId': '7c063b30-634a-483b-82aa-7f19d2ae46cd', 'poolID': 'b709443b-9933-46aa-86cd-c687bb8953f8', 'device': 'disk', 'shared': 'false', 'propagateErrors': 'off', 'type': 'disk'}, {'nicModel': 'pv', 'macAddr': '00:1a:4a:6f:be:39', 'linkActive': 'true', 'network': 'ovirtmgmt', 'bootOrder': '3', 'custom': {}, 'filter': 'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': '2a99a6d5-19fe-48c7-af8b-0f0ecbcc89be', 'device': 'bridge', 'type': 'interface'}, {'index': '0', 'specParams': {}, 'deviceId': '8dc7a6f8-1ce1-46e6-99d8-79546a3fe2db', 'device': 'usb', 'model': 'ich9-uhci1', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '60de3c26-96ef-41bc-98c2-3e3da3f5f3a7', 'device': 'usb', 'model': 'ich9-uhci2', 'type': 'controller'}, {'index': '0', 'specParams': {}, 'deviceId': '8cee35e8-419c-4239-87f7-822ce59536e3', 'device': 'usb', 'model': 'ich9-uhci3', 'type': 'controller'}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '52e20b63-f8c6-4366-a5ec-1dd86c9162cb', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '81e2498f-5cd9-475e-9e54-7ef4dda44ced', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '935b6410-cf72-49d5-8d0c-0412f939bfa2', 'specParams': {}}, {'device': 'spicevmc', 'bus': 'usb', 'type': 'redir', 'deviceId': '03f2d01b-368d-46f5-b5e2-6a92b07e84e1', 'specParams': {}}, {'device': 'memballoon', 'specParams': {'model': 'virtio'}, 'type': 'balloon', 'deviceId': '57d13348-ec19-4963-be38-88e41626423f'}], 'smp': '1', 'vmType': 'kvm', 'memSize': 1024, 'displayIp': '0', 'spiceSecureChannels': 'smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard', 'smpCoresPerSocket': '1', 'vmName': 'TestVm', 'display': 'qxl', 'nice': '0'}}
Thread-165::INFO::2013-12-31 14:53:17,322::vm::2868::vm.Vm::(_run) vmId=`147a1deb-3795-4153-a820-ceabd8db15f3`::VM wrapper has started
Thread-165::DEBUG::2013-12-31 14:53:17,324::task::579::TaskManager.Task::(_updateState) Task=`e991c2ff-6381-43b6-a921-30948d621e6c`::moving from state init -> state preparing
Thread-165::INFO::2013-12-31