[Users] VM not starting up
Nauman Abbas
nauman.abbas at seecs.edu.pk
Tue Dec 31 10:24:03 UTC 2013
I've attached the portions with vmcreate. I found 5 instances of pairs of
call vmcreate and return vmcreate. I've separated them with lines. See if
that helps.
On Tue, Dec 31, 2013 at 2:58 PM, Gadi Ickowicz <gickowic at redhat.com> wrote:
> Hi,
>
> Maybe you can send just the relevant part of the vdsm logs that contain
> the attempt to launch the vm itself - it should start with vmCreate. Try to
> find "::call vmCreate with (..." and either find the matching "return
> vmCreate with"
> or an maybe there is an ERROR or Traceback in the vdsm log following the
> vmCreate call.
>
> Gadi Ickowicz
>
> ----- Original Message -----
> From: "Nauman Abbas" <nauman.abbas at seecs.edu.pk>
> To: "Meital Bourvine" <mbourvin at redhat.com>
> Cc: "users at ovirt.org" <Users at ovirt.org>
> Sent: Tuesday, December 31, 2013 11:47:12 AM
> Subject: Re: [Users] VM not starting up
>
> Vdsm is running fine. Also checked the clocks. You were right, the Engine
> clock was 5 hours ahead of Node clock. I've corrected that but still the
> same issue.
>
>
> On Tue, Dec 31, 2013 at 12:44 PM, Meital Bourvine < mbourvin at redhat.com >
> wrote:
>
>
>
> Are you sure that vdsm is running?
>
> The error happened at 2013-12-31 11:32:56,999 (engine.log)
> but the last line in vdsm.log is from 2013-12-31 07:20:50,807
>
> Also make sure that your clocked are synced.
>
>
>
>
>
>
> From: "Nauman Abbas" < nauman.abbas at seecs.edu.pk >
> To: "Meital Bourvine" < mbourvin at redhat.com >
> Cc: " users at ovirt.org " < Users at ovirt.org >
> Sent: Tuesday, December 31, 2013 9:39:09 AM
>
> Subject: Re: [Users] VM not starting up
>
> Sending files one by one. Vdsm.log
>
>
> On Tue, Dec 31, 2013 at 11:48 AM, Meital Bourvine < mbourvin at redhat.com >
> wrote:
>
>
>
> Engine log can be found on the engine (while vdsm.log is on the host).
> The full path is - /var/log/ovirt-engine/engine.log
>
> audit.log - /var/log/audit/audit.log
>
> About the vdsm log - I don't see any error there, I think that you cut too
> much from it.
>
>
>
>
> From: "Nauman Abbas" < nauman.abbas at seecs.edu.pk >
> To: "Meital Bourvine" < mbourvin at redhat.com >
> Cc: " users at ovirt.org " < Users at ovirt.org >
> Sent: Tuesday, December 31, 2013 8:42:43 AM
> Subject: Re: [Users] VM not starting up
>
>
> Hello Meital
>
> SELinux is off. The cluster doesn't work if SELinux is on, my hosts and
> storage is showing up as okay but VM is not starting up.
>
> I've managed to get vdsm.log but cannot find engine.log and audit.log.
> Sorry for being a noob, if you can tell me the path to these files I'll
> send them right away.
>
>
> On Tue, Dec 31, 2013 at 11:17 AM, Meital Bourvine < mbourvin at redhat.com >
> wrote:
>
>
>
> Hi Nauman,
>
> Maybe it's a selinux issue?
> Please try running `setenforce 0` before starting the vm.
>
> Also please attach engine.log, vdsm.log, and audit.log
>
>
>
>
> From: "Nauman Abbas" < nauman.abbas at seecs.edu.pk >
> To: " users at ovirt.org " < Users at ovirt.org >
> Sent: Tuesday, December 31, 2013 7:13:01 AM
> Subject: [Users] VM not starting up
>
>
> Hello all,
>
> First of all thank you for your help, I've finally managed to get my
> Cluster configured properly but now that I create VMs I'm facing another
> problem. Whenever I start my VM I get this error.
>
> " VM TestVm is down. Exit message: Child quit during startup handshake:
> Input/output error."
>
> I've googled around but haven't found any satisfactory solution. If anyone
> can help out it would be great.
>
> Regards
>
> Nauman Abbas
> Assistant System Administrator (LMS),
> Room No. A-207, SEECS,
> National University of Sciences & Technology,
> + 92 321 5359946
>
> _______________________________________________
> Users mailing list
> Users at ovirt.org
> http://lists.ovirt.org/mailman/listinfo/users
>
>
>
>
> --
>
> Nauman Abbas
> Assistant System Administrator (LMS),
> Room No. A-207, SEECS,
> National University of Sciences & Technology,
> + 92 321 5359946
>
>
>
>
> --
>
> Nauman Abbas
> Assistant System Administrator (LMS),
> Room No. A-207, SEECS,
> National University of Sciences & Technology,
> + 92 321 5359946
>
>
>
>
> --
>
> Nauman Abbas
> Assistant System Administrator (LMS),
> Room No. A-207, SEECS,
> National University of Sciences & Technology,
> + 92 321 5359946
>
> _______________________________________________
> Users mailing list
> Users at ovirt.org
> http://lists.ovirt.org/mailman/listinfo/users
>
--
Nauman Abbas
Assistant System Administrator (LMS),
Room No. A-207, SEECS,
National University of Sciences & Technology,
+ 92 321 5359946
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.ovirt.org/pipermail/users/attachments/20131231/23bb004a/attachment-0001.html>
-------------- next part --------------
[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
More information about the Users
mailing list