<div dir="ltr"><div>OOps, my bad!<br><br></div>here it is:<br><br>Thread-130::DEBUG::2014-03-31 14:31:00,570::BindingXMLRPC::1067::vds::(wrapper) client [10.125.1.171]::call vmCreate with ({&#39;acpiEnable&#39;: &#39;true&#39;, &#39;emulatedMachine&#39;: &#39;pc-1.0&#39;, &#39;vmId&#39;: &#39;2f50cbb4-f80f-4761-bb0d-1d351f497af7&#39;, &#39;memGuaranteedSize&#39;: 1024, &#39;spiceSslCipherSuite&#39;: &#39;DEFAULT&#39;, &#39;timeOffset&#39;: &#39;0&#39;, &#39;cpuType&#39;: &#39;Conroe&#39;, &#39;smp&#39;: &#39;1&#39;, &#39;custom&#39;: {&#39;device_7a09b642-7018-43b8-9938-33432b99cdc1device_dce622cc-29fa-431d-b10f-677903ffebbadevice_156942ac-5c99-496b-8c6a-3ffab96c3164device_e2e8af70-760d-4d07-b27e-cbbcb638affb&#39;: &#39;VmDevice {vmId=2f50cbb4-f80f-4761-bb0d-1d351f497af7, deviceId=e2e8af70-760d-4d07-b27e-cbbcb638affb, device=ide, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x01, function=0x1}, managed=false, plugged=true, readOnly=false, deviceAlias=ide0, customProperties={}, snapshotId=null}&#39;, &#39;device_7a09b642-7018-43b8-9938-33432b99cdc1&#39;: &#39;VmDevice {vmId=2f50cbb4-f80f-4761-bb0d-1d351f497af7, deviceId=7a09b642-7018-43b8-9938-33432b99cdc1, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=1, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel0, customProperties={}, snapshotId=null}&#39;, &#39;device_7a09b642-7018-43b8-9938-33432b99cdc1device_dce622cc-29fa-431d-b10f-677903ffebbadevice_156942ac-5c99-496b-8c6a-3ffab96c3164device_e2e8af70-760d-4d07-b27e-cbbcb638affbdevice_87060fa4-d4d9-4140-9915-73babd76fc12&#39;: &#39;VmDevice {vmId=2f50cbb4-f80f-4761-bb0d-1d351f497af7, deviceId=87060fa4-d4d9-4140-9915-73babd76fc12, device=virtio-serial, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x04, function=0x0}, managed=false, plugged=true, readOnly=false, deviceAlias=virtio-serial0, customProperties={}, snapshotId=null}&#39;, &#39;device_7a09b642-7018-43b8-9938-33432b99cdc1device_dce622cc-29fa-431d-b10f-677903ffebbadevice_156942ac-5c99-496b-8c6a-3ffab96c3164&#39;: &#39;VmDevice {vmId=2f50cbb4-f80f-4761-bb0d-1d351f497af7, deviceId=156942ac-5c99-496b-8c6a-3ffab96c3164, device=spicevmc, type=CHANNEL, bootOrder=0, specParams={}, address={port=3, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel2, customProperties={}, snapshotId=null}&#39;, &#39;device_7a09b642-7018-43b8-9938-33432b99cdc1device_dce622cc-29fa-431d-b10f-677903ffebba&#39;: &#39;VmDevice {vmId=2f50cbb4-f80f-4761-bb0d-1d351f497af7, deviceId=dce622cc-29fa-431d-b10f-677903ffebba, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=2, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel1, customProperties={}, snapshotId=null}&#39;}, &#39;vmType&#39;: &#39;kvm&#39;, &#39;memSize&#39;: 10240, &#39;smpCoresPerSocket&#39;: &#39;1&#39;, &#39;vmName&#39;: &#39;satelit&#39;, &#39;nice&#39;: &#39;0&#39;, &#39;smartcardEnable&#39;: &#39;false&#39;, &#39;keyboardLayout&#39;: &#39;en-us&#39;, &#39;kvmEnable&#39;: &#39;true&#39;, &#39;pitReinjection&#39;: &#39;false&#39;, &#39;transparentHugePages&#39;: &#39;true&#39;, &#39;devices&#39;: [{&#39;device&#39;: &#39;qxl&#39;, &#39;specParams&#39;: {&#39;vram&#39;: &#39;32768&#39;, &#39;ram&#39;: &#39;65536&#39;, &#39;heads&#39;: &#39;1&#39;}, &#39;type&#39;: &#39;video&#39;, &#39;deviceId&#39;: &#39;21fbf3bc-a4e0-4706-a2f2-af14f3433a2d&#39;, &#39;address&#39;: {&#39;slot&#39;: &#39;0x02&#39;, &#39;bus&#39;: &#39;0x00&#39;, &#39;domain&#39;: &#39;0x0000&#39;, &#39;type&#39;: &#39;pci&#39;, &#39;function&#39;: &#39;0x0&#39;}}, {&#39;index&#39;: &#39;2&#39;, &#39;iface&#39;: &#39;ide&#39;, &#39;bootOrder&#39;: &#39;2&#39;, &#39;specParams&#39;: {&#39;path&#39;: &#39;&#39;}, &#39;readonly&#39;: &#39;true&#39;, &#39;deviceId&#39;: &#39;af3420c5-b179-4761-ac69-85831cee3b49&#39;, &#39;address&#39;: {&#39;bus&#39;: &#39;1&#39;, &#39;controller&#39;: &#39;0&#39;, &#39;type&#39;: &#39;drive&#39;, &#39;target&#39;: &#39;0&#39;, &#39;unit&#39;: &#39;0&#39;}, &#39;device&#39;: &#39;cdrom&#39;, &#39;shared&#39;: &#39;false&#39;, &#39;path&#39;: &#39;&#39;, &#39;type&#39;: &#39;disk&#39;}, {&#39;index&#39;: 0, &#39;iface&#39;: &#39;virtio&#39;, &#39;format&#39;: &#39;raw&#39;, &#39;bootOrder&#39;: &#39;1&#39;, &#39;poolID&#39;: &#39;5849b030-626e-47cb-ad90-3ce782d831b3&#39;, &#39;volumeID&#39;: &#39;82231f1f-2c7a-4aee-a596-af209fe5e081&#39;, &#39;imageID&#39;: &#39;faa6b382-eaa7-48d4-80fb-3e5999e1a167&#39;, &#39;specParams&#39;: {}, &#39;readonly&#39;: &#39;false&#39;, &#39;domainID&#39;: &#39;041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39;, &#39;optional&#39;: &#39;false&#39;, &#39;deviceId&#39;: &#39;faa6b382-eaa7-48d4-80fb-3e5999e1a167&#39;, &#39;address&#39;: {&#39;slot&#39;: &#39;0x05&#39;, &#39;bus&#39;: &#39;0x00&#39;, &#39;domain&#39;: &#39;0x0000&#39;, &#39;type&#39;: &#39;pci&#39;, &#39;function&#39;: &#39;0x0&#39;}, &#39;device&#39;: &#39;disk&#39;, &#39;shared&#39;: &#39;false&#39;, &#39;propagateErrors&#39;: &#39;off&#39;, &#39;type&#39;: &#39;disk&#39;}, {&#39;nicModel&#39;: &#39;pv&#39;, &#39;macAddr&#39;: &#39;00:1a:4a:69:90:dd&#39;, &#39;linkActive&#39;: &#39;true&#39;, &#39;network&#39;: &#39;VPO_IPPROXY&#39;, &#39;filter&#39;: &#39;vdsm-no-mac-spoofing&#39;, &#39;specParams&#39;: {&#39;inbound&#39;: {&#39;average&#39;: 25600, &#39;peak&#39;: 26880, &#39;burst&#39;: 256000}, &#39;outbound&#39;: {&#39;average&#39;: 25600, &#39;peak&#39;: 26880, &#39;burst&#39;: 256000}}, &#39;deviceId&#39;: &#39;10591bf3-0131-480a-8f89-5b8db7d49cd8&#39;, &#39;address&#39;: {&#39;slot&#39;: &#39;0x07&#39;, &#39;bus&#39;: &#39;0x00&#39;, &#39;domain&#39;: &#39;0x0000&#39;, &#39;type&#39;: &#39;pci&#39;, &#39;function&#39;: &#39;0x0&#39;}, &#39;device&#39;: &#39;bridge&#39;, &#39;type&#39;: &#39;interface&#39;}, {&#39;device&#39;: &#39;memballoon&#39;, &#39;specParams&#39;: {&#39;model&#39;: &#39;virtio&#39;}, &#39;type&#39;: &#39;balloon&#39;, &#39;deviceId&#39;: &#39;b9561cf2-37f0-4e64-bc7b-8c1921c06f9b&#39;}, {&#39;index&#39;: &#39;0&#39;, &#39;specParams&#39;: {}, &#39;deviceId&#39;: &#39;d02fc85d-4862-4e0f-a7c5-eadce3509935&#39;, &#39;address&#39;: {&#39;slot&#39;: &#39;0x03&#39;, &#39;bus&#39;: &#39;0x00&#39;, &#39;domain&#39;: &#39;0x0000&#39;, &#39;type&#39;: &#39;pci&#39;, &#39;function&#39;: &#39;0x0&#39;}, &#39;device&#39;: &#39;scsi&#39;, &#39;model&#39;: &#39;virtio-scsi&#39;, &#39;type&#39;: &#39;controller&#39;}], &#39;spiceSecureChannels&#39;: &#39;smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard&#39;, &#39;display&#39;: &#39;qxl&#39;},) {}<br>
Thread-130::INFO::2014-03-31 14:31:00,578::API::668::vds::(_getNetworkIp) network None: using 0<br>Thread-130::INFO::2014-03-31 14:31:00,578::clientIF::366::vds::(createVm) vmContainerLock acquired by vm 2f50cbb4-f80f-4761-bb0d-1d351f497af7<br>
Thread-130::DEBUG::2014-03-31 14:31:00,582::clientIF::379::vds::(createVm) Total desktops after creation of 2f50cbb4-f80f-4761-bb0d-1d351f497af7 is 2<br>Thread-130::DEBUG::2014-03-31 14:31:00,582::BindingXMLRPC::1074::vds::(wrapper) return vmCreate with {&#39;status&#39;: {&#39;message&#39;: &#39;Done&#39;, &#39;code&#39;: 0}, &#39;vmList&#39;: {&#39;status&#39;: &#39;WaitForLaunch&#39;, &#39;acpiEnable&#39;: &#39;true&#39;, &#39;emulatedMachine&#39;: &#39;pc-1.0&#39;, &#39;vmId&#39;: &#39;2f50cbb4-f80f-4761-bb0d-1d351f497af7&#39;, &#39;pid&#39;: &#39;0&#39;, &#39;memGuaranteedSize&#39;: 1024, &#39;timeOffset&#39;: &#39;0&#39;, &#39;keyboardLayout&#39;: &#39;en-us&#39;, &#39;displayPort&#39;: &#39;-1&#39;, &#39;displaySecurePort&#39;: &#39;-1&#39;, &#39;spiceSslCipherSuite&#39;: &#39;DEFAULT&#39;, &#39;cpuType&#39;: &#39;Conroe&#39;, &#39;custom&#39;: {&#39;device_7a09b642-7018-43b8-9938-33432b99cdc1device_dce622cc-29fa-431d-b10f-677903ffebbadevice_156942ac-5c99-496b-8c6a-3ffab96c3164device_e2e8af70-760d-4d07-b27e-cbbcb638affb&#39;: &#39;VmDevice {vmId=2f50cbb4-f80f-4761-bb0d-1d351f497af7, deviceId=e2e8af70-760d-4d07-b27e-cbbcb638affb, device=ide, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x01, function=0x1}, managed=false, plugged=true, readOnly=false, deviceAlias=ide0, customProperties={}, snapshotId=null}&#39;, &#39;device_7a09b642-7018-43b8-9938-33432b99cdc1&#39;: &#39;VmDevice {vmId=2f50cbb4-f80f-4761-bb0d-1d351f497af7, deviceId=7a09b642-7018-43b8-9938-33432b99cdc1, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=1, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel0, customProperties={}, snapshotId=null}&#39;, &#39;device_7a09b642-7018-43b8-9938-33432b99cdc1device_dce622cc-29fa-431d-b10f-677903ffebbadevice_156942ac-5c99-496b-8c6a-3ffab96c3164device_e2e8af70-760d-4d07-b27e-cbbcb638affbdevice_87060fa4-d4d9-4140-9915-73babd76fc12&#39;: &#39;VmDevice {vmId=2f50cbb4-f80f-4761-bb0d-1d351f497af7, deviceId=87060fa4-d4d9-4140-9915-73babd76fc12, device=virtio-serial, type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci, slot=0x04, function=0x0}, managed=false, plugged=true, readOnly=false, deviceAlias=virtio-serial0, customProperties={}, snapshotId=null}&#39;, &#39;device_7a09b642-7018-43b8-9938-33432b99cdc1device_dce622cc-29fa-431d-b10f-677903ffebbadevice_156942ac-5c99-496b-8c6a-3ffab96c3164&#39;: &#39;VmDevice {vmId=2f50cbb4-f80f-4761-bb0d-1d351f497af7, deviceId=156942ac-5c99-496b-8c6a-3ffab96c3164, device=spicevmc, type=CHANNEL, bootOrder=0, specParams={}, address={port=3, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel2, customProperties={}, snapshotId=null}&#39;, &#39;device_7a09b642-7018-43b8-9938-33432b99cdc1device_dce622cc-29fa-431d-b10f-677903ffebba&#39;: &#39;VmDevice {vmId=2f50cbb4-f80f-4761-bb0d-1d351f497af7, deviceId=dce622cc-29fa-431d-b10f-677903ffebba, device=unix, type=CHANNEL, bootOrder=0, specParams={}, address={port=2, bus=0, controller=0, type=virtio-serial}, managed=false, plugged=true, readOnly=false, deviceAlias=channel1, customProperties={}, snapshotId=null}&#39;}, &#39;clientIp&#39;: &#39;&#39;, &#39;nicModel&#39;: &#39;rtl8139,pv&#39;, &#39;smartcardEnable&#39;: &#39;false&#39;, &#39;kvmEnable&#39;: &#39;true&#39;, &#39;pitReinjection&#39;: &#39;false&#39;, &#39;transparentHugePages&#39;: &#39;true&#39;, &#39;devices&#39;: [{&#39;device&#39;: &#39;qxl&#39;, &#39;specParams&#39;: {&#39;vram&#39;: &#39;32768&#39;, &#39;ram&#39;: &#39;65536&#39;, &#39;heads&#39;: &#39;1&#39;}, &#39;type&#39;: &#39;video&#39;, &#39;deviceId&#39;: &#39;21fbf3bc-a4e0-4706-a2f2-af14f3433a2d&#39;, &#39;address&#39;: {&#39;slot&#39;: &#39;0x02&#39;, &#39;bus&#39;: &#39;0x00&#39;, &#39;domain&#39;: &#39;0x0000&#39;, &#39;type&#39;: &#39;pci&#39;, &#39;function&#39;: &#39;0x0&#39;}}, {&#39;index&#39;: &#39;2&#39;, &#39;iface&#39;: &#39;ide&#39;, &#39;bootOrder&#39;: &#39;2&#39;, &#39;specParams&#39;: {&#39;path&#39;: &#39;&#39;}, &#39;readonly&#39;: &#39;true&#39;, &#39;deviceId&#39;: &#39;af3420c5-b179-4761-ac69-85831cee3b49&#39;, &#39;address&#39;: {&#39;bus&#39;: &#39;1&#39;, &#39;controller&#39;: &#39;0&#39;, &#39;type&#39;: &#39;drive&#39;, &#39;target&#39;: &#39;0&#39;, &#39;unit&#39;: &#39;0&#39;}, &#39;device&#39;: &#39;cdrom&#39;, &#39;shared&#39;: &#39;false&#39;, &#39;path&#39;: &#39;&#39;, &#39;type&#39;: &#39;disk&#39;}, {&#39;index&#39;: 0, &#39;iface&#39;: &#39;virtio&#39;, &#39;format&#39;: &#39;raw&#39;, &#39;bootOrder&#39;: &#39;1&#39;, &#39;poolID&#39;: &#39;5849b030-626e-47cb-ad90-3ce782d831b3&#39;, &#39;volumeID&#39;: &#39;82231f1f-2c7a-4aee-a596-af209fe5e081&#39;, &#39;imageID&#39;: &#39;faa6b382-eaa7-48d4-80fb-3e5999e1a167&#39;, &#39;specParams&#39;: {}, &#39;readonly&#39;: &#39;false&#39;, &#39;domainID&#39;: &#39;041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39;, &#39;optional&#39;: &#39;false&#39;, &#39;deviceId&#39;: &#39;faa6b382-eaa7-48d4-80fb-3e5999e1a167&#39;, &#39;address&#39;: {&#39;slot&#39;: &#39;0x05&#39;, &#39;bus&#39;: &#39;0x00&#39;, &#39;domain&#39;: &#39;0x0000&#39;, &#39;type&#39;: &#39;pci&#39;, &#39;function&#39;: &#39;0x0&#39;}, &#39;device&#39;: &#39;disk&#39;, &#39;shared&#39;: &#39;false&#39;, &#39;propagateErrors&#39;: &#39;off&#39;, &#39;type&#39;: &#39;disk&#39;}, {&#39;nicModel&#39;: &#39;pv&#39;, &#39;macAddr&#39;: &#39;00:1a:4a:69:90:dd&#39;, &#39;linkActive&#39;: &#39;true&#39;, &#39;network&#39;: &#39;VPO_IPPROXY&#39;, &#39;filter&#39;: &#39;vdsm-no-mac-spoofing&#39;, &#39;specParams&#39;: {&#39;inbound&#39;: {&#39;average&#39;: 25600, &#39;peak&#39;: 26880, &#39;burst&#39;: 256000}, &#39;outbound&#39;: {&#39;average&#39;: 25600, &#39;peak&#39;: 26880, &#39;burst&#39;: 256000}}, &#39;deviceId&#39;: &#39;10591bf3-0131-480a-8f89-5b8db7d49cd8&#39;, &#39;address&#39;: {&#39;slot&#39;: &#39;0x07&#39;, &#39;bus&#39;: &#39;0x00&#39;, &#39;domain&#39;: &#39;0x0000&#39;, &#39;type&#39;: &#39;pci&#39;, &#39;function&#39;: &#39;0x0&#39;}, &#39;device&#39;: &#39;bridge&#39;, &#39;type&#39;: &#39;interface&#39;}, {&#39;device&#39;: &#39;memballoon&#39;, &#39;specParams&#39;: {&#39;model&#39;: &#39;virtio&#39;}, &#39;type&#39;: &#39;balloon&#39;, &#39;deviceId&#39;: &#39;b9561cf2-37f0-4e64-bc7b-8c1921c06f9b&#39;}, {&#39;index&#39;: &#39;0&#39;, &#39;specParams&#39;: {}, &#39;deviceId&#39;: &#39;d02fc85d-4862-4e0f-a7c5-eadce3509935&#39;, &#39;address&#39;: {&#39;slot&#39;: &#39;0x03&#39;, &#39;bus&#39;: &#39;0x00&#39;, &#39;domain&#39;: &#39;0x0000&#39;, &#39;type&#39;: &#39;pci&#39;, &#39;function&#39;: &#39;0x0&#39;}, &#39;device&#39;: &#39;scsi&#39;, &#39;model&#39;: &#39;virtio-scsi&#39;, &#39;type&#39;: &#39;controller&#39;}], &#39;smp&#39;: &#39;1&#39;, &#39;vmType&#39;: &#39;kvm&#39;, &#39;memSize&#39;: 10240, &#39;displayIp&#39;: &#39;0&#39;, &#39;spiceSecureChannels&#39;: &#39;smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard&#39;, &#39;smpCoresPerSocket&#39;: &#39;1&#39;, &#39;vmName&#39;: &#39;satelit&#39;, &#39;display&#39;: &#39;qxl&#39;, &#39;nice&#39;: &#39;0&#39;}}<br>
Thread-469::DEBUG::2014-03-31 14:31:00,584::vm::2242::vm.Vm::(_startUnderlyingVm) vmId=`2f50cbb4-f80f-4761-bb0d-1d351f497af7`::Start<br>Thread-469::DEBUG::2014-03-31 14:31:00,584::vm::2246::vm.Vm::(_startUnderlyingVm) vmId=`2f50cbb4-f80f-4761-bb0d-1d351f497af7`::_ongoingCreations acquired<br>
Thread-469::INFO::2014-03-31 14:31:00,585::vm::3097::vm.Vm::(_run) vmId=`2f50cbb4-f80f-4761-bb0d-1d351f497af7`::VM wrapper has started<br>Thread-469::DEBUG::2014-03-31 14:31:00,585::task::595::TaskManager.Task::(_updateState) Task=`afd2f80d-4245-4368-aa74-ce9326e49816`::moving from state init -&gt; state preparing<br>
Thread-469::INFO::2014-03-31 14:31:00,585::logUtils::44::dispatcher::(wrapper) Run and protect: getVolumeSize(sdUUID=&#39;041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39;, spUUID=&#39;5849b030-626e-47cb-ad90-3ce782d831b3&#39;, imgUUID=&#39;faa6b382-eaa7-48d4-80fb-3e5999e1a167&#39;, volUUID=&#39;82231f1f-2c7a-4aee-a596-af209fe5e081&#39;, options=None)<br>
Thread-469::INFO::2014-03-31 14:31:00,590::logUtils::47::dispatcher::(wrapper) Run and protect: getVolumeSize, Return response: {&#39;truesize&#39;: &#39;128230096896&#39;, &#39;apparentsize&#39;: &#39;161061273600&#39;}<br>
Thread-469::DEBUG::2014-03-31 14:31:00,590::task::1185::TaskManager.Task::(prepare) Task=`afd2f80d-4245-4368-aa74-ce9326e49816`::finished: {&#39;truesize&#39;: &#39;128230096896&#39;, &#39;apparentsize&#39;: &#39;161061273600&#39;}<br>
Thread-469::DEBUG::2014-03-31 14:31:00,590::task::595::TaskManager.Task::(_updateState) Task=`afd2f80d-4245-4368-aa74-ce9326e49816`::moving from state preparing -&gt; state finished<br>Thread-469::DEBUG::2014-03-31 14:31:00,590::resourceManager::940::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}<br>
Thread-469::DEBUG::2014-03-31 14:31:00,590::resourceManager::977::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}<br>Thread-469::DEBUG::2014-03-31 14:31:00,591::task::990::TaskManager.Task::(_decref) Task=`afd2f80d-4245-4368-aa74-ce9326e49816`::ref 0 aborting False<br>
Thread-469::INFO::2014-03-31 14:31:00,591::clientIF::324::vds::(prepareVolumePath) prepared volume path: <br>Thread-469::DEBUG::2014-03-31 14:31:00,591::task::595::TaskManager.Task::(_updateState) Task=`ebc21d48-03ca-4764-a5eb-6544c680fb6c`::moving from state init -&gt; state preparing<br>
Thread-469::INFO::2014-03-31 14:31:00,591::logUtils::44::dispatcher::(wrapper) Run and protect: prepareImage(sdUUID=&#39;041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39;, spUUID=&#39;5849b030-626e-47cb-ad90-3ce782d831b3&#39;, imgUUID=&#39;faa6b382-eaa7-48d4-80fb-3e5999e1a167&#39;, leafUUID=&#39;82231f1f-2c7a-4aee-a596-af209fe5e081&#39;)<br>
Thread-469::DEBUG::2014-03-31 14:31:00,592::resourceManager::198::ResourceManager.Request::(__init__) ResName=`Storage.041e8dbe-f405-4aca-bb78-3bf3cc7dc190`ReqID=`6bd72828-e18d-4461-822d-4b5c80152dce`::Request was made in &#39;/usr/share/vdsm/storage/hsm.py&#39; line &#39;3237&#39; at &#39;prepareImage&#39;<br>
Thread-469::DEBUG::2014-03-31 14:31:00,592::resourceManager::542::ResourceManager::(registerResource) Trying to register resource &#39;Storage.041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39; for lock type &#39;shared&#39;<br>Thread-469::DEBUG::2014-03-31 14:31:00,592::resourceManager::601::ResourceManager::(registerResource) Resource &#39;Storage.041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39; is free. Now locking as &#39;shared&#39; (1 active user)<br>
Thread-469::DEBUG::2014-03-31 14:31:00,592::resourceManager::238::ResourceManager.Request::(grant) ResName=`Storage.041e8dbe-f405-4aca-bb78-3bf3cc7dc190`ReqID=`6bd72828-e18d-4461-822d-4b5c80152dce`::Granted request<br>Thread-469::DEBUG::2014-03-31 14:31:00,593::task::827::TaskManager.Task::(resourceAcquired) Task=`ebc21d48-03ca-4764-a5eb-6544c680fb6c`::_resourcesAcquired: Storage.041e8dbe-f405-4aca-bb78-3bf3cc7dc190 (shared)<br>
Thread-469::DEBUG::2014-03-31 14:31:00,593::task::990::TaskManager.Task::(_decref) Task=`ebc21d48-03ca-4764-a5eb-6544c680fb6c`::ref 1 aborting False<br>Thread-469::WARNING::2014-03-31 14:31:00,600::fileUtils::167::Storage.fileUtils::(createdir) Dir /var/run/vdsm/storage/041e8dbe-f405-4aca-bb78-3bf3cc7dc190 already exists<br>
Thread-469::DEBUG::2014-03-31 14:31:00,600::fileSD::446::Storage.StorageDomain::(createImageLinks) img run dir already exists: /var/run/vdsm/storage/041e8dbe-f405-4aca-bb78-3bf3cc7dc190/faa6b382-eaa7-48d4-80fb-3e5999e1a167<br>
Thread-469::DEBUG::2014-03-31 14:31:00,601::fileVolume::529::Storage.Volume::(validateVolumePath) validate path for 82231f1f-2c7a-4aee-a596-af209fe5e081<br>Thread-469::INFO::2014-03-31 14:31:00,602::logUtils::47::dispatcher::(wrapper) Run and protect: prepareImage, Return response: {&#39;info&#39;: {&#39;domainID&#39;: &#39;041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39;, &#39;volType&#39;: &#39;path&#39;, &#39;leaseOffset&#39;: 0, &#39;path&#39;: &#39;/rhev/data-center/mnt/glusterSD/10.125.1.194:gluster__data2/041e8dbe-f405-4aca-bb78-3bf3cc7dc190/images/faa6b382-eaa7-48d4-80fb-3e5999e1a167/82231f1f-2c7a-4aee-a596-af209fe5e081&#39;, &#39;volumeID&#39;: &#39;82231f1f-2c7a-4aee-a596-af209fe5e081&#39;, &#39;leasePath&#39;: &#39;/rhev/data-center/mnt/glusterSD/10.125.1.194:gluster__data2/041e8dbe-f405-4aca-bb78-3bf3cc7dc190/images/faa6b382-eaa7-48d4-80fb-3e5999e1a167/82231f1f-2c7a-4aee-a596-af209fe5e081.lease&#39;, &#39;imageID&#39;: &#39;faa6b382-eaa7-48d4-80fb-3e5999e1a167&#39;}, &#39;path&#39;: &#39;/rhev/data-center/mnt/glusterSD/10.125.1.194:gluster__data2/041e8dbe-f405-4aca-bb78-3bf3cc7dc190/images/faa6b382-eaa7-48d4-80fb-3e5999e1a167/82231f1f-2c7a-4aee-a596-af209fe5e081&#39;, &#39;imgVolumesInfo&#39;: [{&#39;domainID&#39;: &#39;041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39;, &#39;volType&#39;: &#39;path&#39;, &#39;leaseOffset&#39;: 0, &#39;path&#39;: &#39;/rhev/data-center/mnt/glusterSD/10.125.1.194:gluster__data2/041e8dbe-f405-4aca-bb78-3bf3cc7dc190/images/faa6b382-eaa7-48d4-80fb-3e5999e1a167/82231f1f-2c7a-4aee-a596-af209fe5e081&#39;, &#39;volumeID&#39;: &#39;82231f1f-2c7a-4aee-a596-af209fe5e081&#39;, &#39;leasePath&#39;: &#39;/rhev/data-center/mnt/glusterSD/10.125.1.194:gluster__data2/041e8dbe-f405-4aca-bb78-3bf3cc7dc190/images/faa6b382-eaa7-48d4-80fb-3e5999e1a167/82231f1f-2c7a-4aee-a596-af209fe5e081.lease&#39;, &#39;imageID&#39;: &#39;faa6b382-eaa7-48d4-80fb-3e5999e1a167&#39;}]}<br>
Thread-469::DEBUG::2014-03-31 14:31:00,603::task::1185::TaskManager.Task::(prepare) Task=`ebc21d48-03ca-4764-a5eb-6544c680fb6c`::finished: {&#39;info&#39;: {&#39;domainID&#39;: &#39;041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39;, &#39;volType&#39;: &#39;path&#39;, &#39;leaseOffset&#39;: 0, &#39;path&#39;: &#39;/rhev/data-center/mnt/glusterSD/10.125.1.194:gluster__data2/041e8dbe-f405-4aca-bb78-3bf3cc7dc190/images/faa6b382-eaa7-48d4-80fb-3e5999e1a167/82231f1f-2c7a-4aee-a596-af209fe5e081&#39;, &#39;volumeID&#39;: &#39;82231f1f-2c7a-4aee-a596-af209fe5e081&#39;, &#39;leasePath&#39;: &#39;/rhev/data-center/mnt/glusterSD/10.125.1.194:gluster__data2/041e8dbe-f405-4aca-bb78-3bf3cc7dc190/images/faa6b382-eaa7-48d4-80fb-3e5999e1a167/82231f1f-2c7a-4aee-a596-af209fe5e081.lease&#39;, &#39;imageID&#39;: &#39;faa6b382-eaa7-48d4-80fb-3e5999e1a167&#39;}, &#39;path&#39;: &#39;/rhev/data-center/mnt/glusterSD/10.125.1.194:gluster__data2/041e8dbe-f405-4aca-bb78-3bf3cc7dc190/images/faa6b382-eaa7-48d4-80fb-3e5999e1a167/82231f1f-2c7a-4aee-a596-af209fe5e081&#39;, &#39;imgVolumesInfo&#39;: [{&#39;domainID&#39;: &#39;041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39;, &#39;volType&#39;: &#39;path&#39;, &#39;leaseOffset&#39;: 0, &#39;path&#39;: &#39;/rhev/data-center/mnt/glusterSD/10.125.1.194:gluster__data2/041e8dbe-f405-4aca-bb78-3bf3cc7dc190/images/faa6b382-eaa7-48d4-80fb-3e5999e1a167/82231f1f-2c7a-4aee-a596-af209fe5e081&#39;, &#39;volumeID&#39;: &#39;82231f1f-2c7a-4aee-a596-af209fe5e081&#39;, &#39;leasePath&#39;: &#39;/rhev/data-center/mnt/glusterSD/10.125.1.194:gluster__data2/041e8dbe-f405-4aca-bb78-3bf3cc7dc190/images/faa6b382-eaa7-48d4-80fb-3e5999e1a167/82231f1f-2c7a-4aee-a596-af209fe5e081.lease&#39;, &#39;imageID&#39;: &#39;faa6b382-eaa7-48d4-80fb-3e5999e1a167&#39;}]}<br>
Thread-469::DEBUG::2014-03-31 14:31:00,603::task::595::TaskManager.Task::(_updateState) Task=`ebc21d48-03ca-4764-a5eb-6544c680fb6c`::moving from state preparing -&gt; state finished<br>Thread-469::DEBUG::2014-03-31 14:31:00,603::resourceManager::940::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {&#39;Storage.041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39;: &lt; ResourceRef &#39;Storage.041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39;, isValid: &#39;True&#39; obj: &#39;None&#39;&gt;}<br>
Thread-469::DEBUG::2014-03-31 14:31:00,603::resourceManager::977::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}<br>Thread-469::DEBUG::2014-03-31 14:31:00,603::resourceManager::616::ResourceManager::(releaseResource) Trying to release resource &#39;Storage.041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39;<br>
Thread-469::DEBUG::2014-03-31 14:31:00,604::resourceManager::635::ResourceManager::(releaseResource) Released resource &#39;Storage.041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39; (0 active users)<br>Thread-469::DEBUG::2014-03-31 14:31:00,604::resourceManager::641::ResourceManager::(releaseResource) Resource &#39;Storage.041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39; is free, finding out if anyone is waiting for it.<br>
Thread-469::DEBUG::2014-03-31 14:31:00,604::resourceManager::649::ResourceManager::(releaseResource) No one is waiting for resource &#39;Storage.041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39;, Clearing records.<br>Thread-469::DEBUG::2014-03-31 14:31:00,604::task::990::TaskManager.Task::(_decref) Task=`ebc21d48-03ca-4764-a5eb-6544c680fb6c`::ref 0 aborting False<br>
Thread-469::INFO::2014-03-31 14:31:00,604::clientIF::324::vds::(prepareVolumePath) prepared volume path: /rhev/data-center/mnt/glusterSD/10.125.1.194:gluster__data2/041e8dbe-f405-4aca-bb78-3bf3cc7dc190/images/faa6b382-eaa7-48d4-80fb-3e5999e1a167/82231f1f-2c7a-4aee-a596-af209fe5e081<br>
Thread-469::DEBUG::2014-03-31 14:31:00,613::vm::2263::vm.Vm::(_startUnderlyingVm) vmId=`2f50cbb4-f80f-4761-bb0d-1d351f497af7`::_ongoingCreations released<br>Thread-469::ERROR::2014-03-31 14:31:00,614::vm::2289::vm.Vm::(_startUnderlyingVm) vmId=`2f50cbb4-f80f-4761-bb0d-1d351f497af7`::The vm start process failed<br>
Traceback (most recent call last):<br>  File &quot;/usr/share/vdsm/vm.py&quot;, line 2249, in _startUnderlyingVm<br>    self._run()<br>  File &quot;/usr/share/vdsm/vm.py&quot;, line 3135, in _run<br>    domxml = hooks.before_vm_start(self._buildCmdLine(), self.conf)<br>
  File &quot;/usr/share/vdsm/vm.py&quot;, line 2965, in _buildCmdLine<br>    self._appendDevices(domxml)<br>  File &quot;/usr/share/vdsm/vm.py&quot;, line 2922, in _appendDevices<br>    deviceXML = dev.getXML().toxml(encoding=&#39;utf-8&#39;)<br>
  File &quot;/usr/lib64/python2.7/xml/dom/minidom.py&quot;, line 46, in toxml<br>    return self.toprettyxml(&quot;&quot;, &quot;&quot;, encoding)<br>  File &quot;/usr/lib64/python2.7/xml/dom/minidom.py&quot;, line 60, in toprettyxml<br>
    self.writexml(writer, &quot;&quot;, indent, newl)<br>  File &quot;/usr/share/vdsm/vm.py&quot;, line 5268, in __hacked_writexml<br>    node.writexml(writer, indent + addindent, addindent, newl)<br>  File &quot;/usr/share/vdsm/vm.py&quot;, line 5268, in __hacked_writexml<br>
    node.writexml(writer, indent + addindent, addindent, newl)<br>  File &quot;/usr/share/vdsm/vm.py&quot;, line 5256, in __hacked_writexml<br>    xml.dom.minidom._write_data(writer, attrs[a_name].value)<br>  File &quot;/usr/lib64/python2.7/xml/dom/minidom.py&quot;, line 296, in _write_data<br>
    data = data.replace(&quot;&amp;&quot;, &quot;&amp;amp;&quot;).replace(&quot;&lt;&quot;, &quot;&amp;lt;&quot;). \<br>AttributeError: &#39;int&#39; object has no attribute &#39;replace&#39;<br>Thread-469::DEBUG::2014-03-31 14:31:00,618::vm::2731::vm.Vm::(setDownStatus) vmId=`2f50cbb4-f80f-4761-bb0d-1d351f497af7`::Changed state to Down: &#39;int&#39; object has no attribute &#39;replace&#39;<br>
Thread-130::DEBUG::2014-03-31 14:31:02,693::BindingXMLRPC::1067::vds::(wrapper) client [10.125.1.171]::call vmGetStats with (&#39;2f50cbb4-f80f-4761-bb0d-1d351f497af7&#39;,) {}<br>Thread-130::DEBUG::2014-03-31 14:31:02,694::BindingXMLRPC::1074::vds::(wrapper) return vmGetStats with {&#39;status&#39;: {&#39;message&#39;: &#39;Done&#39;, &#39;code&#39;: 0}, &#39;statsList&#39;: [{&#39;status&#39;: &#39;Down&#39;, &#39;hash&#39;: &#39;0&#39;, &#39;exitMessage&#39;: &quot;&#39;int&#39; object has no attribute &#39;replace&#39;&quot;, &#39;vmId&#39;: &#39;2f50cbb4-f80f-4761-bb0d-1d351f497af7&#39;, &#39;timeOffset&#39;: &#39;0&#39;, &#39;exitCode&#39;: 1}]}<br>
Thread-130::DEBUG::2014-03-31 14:31:02,707::BindingXMLRPC::1067::vds::(wrapper) client [10.125.1.171]::call vmDestroy with (&#39;2f50cbb4-f80f-4761-bb0d-1d351f497af7&#39;,) {}<br>Thread-130::INFO::2014-03-31 14:31:02,708::API::327::vds::(destroy) vmContainerLock acquired by vm 2f50cbb4-f80f-4761-bb0d-1d351f497af7<br>
Thread-130::DEBUG::2014-03-31 14:31:02,708::vm::4629::vm.Vm::(destroy) vmId=`2f50cbb4-f80f-4761-bb0d-1d351f497af7`::destroy Called<br>Thread-130::INFO::2014-03-31 14:31:02,708::vm::4575::vm.Vm::(releaseVm) vmId=`2f50cbb4-f80f-4761-bb0d-1d351f497af7`::Release VM resources<br>
Thread-130::WARNING::2014-03-31 14:31:02,708::vm::1967::vm.Vm::(_set_lastStatus) vmId=`2f50cbb4-f80f-4761-bb0d-1d351f497af7`::trying to set state to Powering down when already Down<br>Thread-130::DEBUG::2014-03-31 14:31:02,709::task::595::TaskManager.Task::(_updateState) Task=`e42042ca-c4fe-467b-8810-99099482dfdb`::moving from state init -&gt; state preparing<br>
Thread-130::INFO::2014-03-31 14:31:02,709::logUtils::44::dispatcher::(wrapper) Run and protect: teardownImage(sdUUID=&#39;041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39;, spUUID=&#39;5849b030-626e-47cb-ad90-3ce782d831b3&#39;, imgUUID=&#39;faa6b382-eaa7-48d4-80fb-3e5999e1a167&#39;, volUUID=None)<br>
Thread-130::DEBUG::2014-03-31 14:31:02,710::resourceManager::198::ResourceManager.Request::(__init__) ResName=`Storage.041e8dbe-f405-4aca-bb78-3bf3cc7dc190`ReqID=`94ad1f7a-b152-49ae-ba64-83ffeeb82ee1`::Request was made in &#39;/usr/share/vdsm/storage/hsm.py&#39; line &#39;3285&#39; at &#39;teardownImage&#39;<br>
Thread-130::DEBUG::2014-03-31 14:31:02,710::resourceManager::542::ResourceManager::(registerResource) Trying to register resource &#39;Storage.041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39; for lock type &#39;shared&#39;<br>Thread-130::DEBUG::2014-03-31 14:31:02,710::resourceManager::601::ResourceManager::(registerResource) Resource &#39;Storage.041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39; is free. Now locking as &#39;shared&#39; (1 active user)<br>
Thread-130::DEBUG::2014-03-31 14:31:02,711::resourceManager::238::ResourceManager.Request::(grant) ResName=`Storage.041e8dbe-f405-4aca-bb78-3bf3cc7dc190`ReqID=`94ad1f7a-b152-49ae-ba64-83ffeeb82ee1`::Granted request<br>Thread-130::DEBUG::2014-03-31 14:31:02,711::task::827::TaskManager.Task::(resourceAcquired) Task=`e42042ca-c4fe-467b-8810-99099482dfdb`::_resourcesAcquired: Storage.041e8dbe-f405-4aca-bb78-3bf3cc7dc190 (shared)<br>
Thread-130::DEBUG::2014-03-31 14:31:02,711::task::990::TaskManager.Task::(_decref) Task=`e42042ca-c4fe-467b-8810-99099482dfdb`::ref 1 aborting False<br>Thread-130::INFO::2014-03-31 14:31:02,712::logUtils::47::dispatcher::(wrapper) Run and protect: teardownImage, Return response: None<br>
Thread-130::DEBUG::2014-03-31 14:31:02,712::task::1185::TaskManager.Task::(prepare) Task=`e42042ca-c4fe-467b-8810-99099482dfdb`::finished: None<br>Thread-130::DEBUG::2014-03-31 14:31:02,713::task::595::TaskManager.Task::(_updateState) Task=`e42042ca-c4fe-467b-8810-99099482dfdb`::moving from state preparing -&gt; state finished<br>
Thread-130::DEBUG::2014-03-31 14:31:02,713::resourceManager::940::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {&#39;Storage.041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39;: &lt; ResourceRef &#39;Storage.041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39;, isValid: &#39;True&#39; obj: &#39;None&#39;&gt;}<br>
Thread-130::DEBUG::2014-03-31 14:31:02,713::resourceManager::977::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}<br>Thread-130::DEBUG::2014-03-31 14:31:02,713::resourceManager::616::ResourceManager::(releaseResource) Trying to release resource &#39;Storage.041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39;<br>
Thread-130::DEBUG::2014-03-31 14:31:02,714::resourceManager::635::ResourceManager::(releaseResource) Released resource &#39;Storage.041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39; (0 active users)<br>Thread-130::DEBUG::2014-03-31 14:31:02,714::resourceManager::641::ResourceManager::(releaseResource) Resource &#39;Storage.041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39; is free, finding out if anyone is waiting for it.<br>
Thread-130::DEBUG::2014-03-31 14:31:02,714::resourceManager::649::ResourceManager::(releaseResource) No one is waiting for resource &#39;Storage.041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39;, Clearing records.<br>Thread-130::DEBUG::2014-03-31 14:31:02,714::task::990::TaskManager.Task::(_decref) Task=`e42042ca-c4fe-467b-8810-99099482dfdb`::ref 0 aborting False<br>
Thread-130::WARNING::2014-03-31 14:31:02,715::utils::129::root::(rmFile) File: /var/lib/libvirt/qemu/channels/2f50cbb4-f80f-4761-bb0d-1d351f497af7.com.redhat.rhevm.vdsm already removed<br>Thread-130::WARNING::2014-03-31 14:31:02,716::utils::129::root::(rmFile) File: /var/lib/libvirt/qemu/channels/2f50cbb4-f80f-4761-bb0d-1d351f497af7.org.qemu.guest_agent.0 already removed<br>
Thread-130::DEBUG::2014-03-31 14:31:02,717::task::595::TaskManager.Task::(_updateState) Task=`2a6b057d-fe6e-4768-b32b-986295e416f5`::moving from state init -&gt; state preparing<br>Thread-130::INFO::2014-03-31 14:31:02,717::logUtils::44::dispatcher::(wrapper) Run and protect: inappropriateDevices(thiefId=&#39;2f50cbb4-f80f-4761-bb0d-1d351f497af7&#39;)<br>
Thread-130::INFO::2014-03-31 14:31:02,718::logUtils::47::dispatcher::(wrapper) Run and protect: inappropriateDevices, Return response: None<br>Thread-130::DEBUG::2014-03-31 14:31:02,718::task::1185::TaskManager.Task::(prepare) Task=`2a6b057d-fe6e-4768-b32b-986295e416f5`::finished: None<br>
Thread-130::DEBUG::2014-03-31 14:31:02,718::task::595::TaskManager.Task::(_updateState) Task=`2a6b057d-fe6e-4768-b32b-986295e416f5`::moving from state preparing -&gt; state finished<br>Thread-130::DEBUG::2014-03-31 14:31:02,718::resourceManager::940::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}<br>
Thread-130::DEBUG::2014-03-31 14:31:02,718::resourceManager::977::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}<br>Thread-130::DEBUG::2014-03-31 14:31:02,719::task::990::TaskManager.Task::(_decref) Task=`2a6b057d-fe6e-4768-b32b-986295e416f5`::ref 0 aborting False<br>
Thread-130::DEBUG::2014-03-31 14:31:02,719::vm::4623::vm.Vm::(deleteVm) vmId=`2f50cbb4-f80f-4761-bb0d-1d351f497af7`::Total desktops after destroy of 2f50cbb4-f80f-4761-bb0d-1d351f497af7 is 1<br>Thread-130::DEBUG::2014-03-31 14:31:02,720::BindingXMLRPC::1074::vds::(wrapper) return vmDestroy with {&#39;status&#39;: {&#39;message&#39;: &#39;Machine destroyed&#39;, &#39;code&#39;: 0}}<br>
Thread-23::DEBUG::2014-03-31 14:31:02,742::fileSD::225::Storage.Misc.excCmd::(getReadDelay) &#39;/usr/bin/dd iflag=direct if=/rhev/data-center/mnt/10.125.1.193:_mnt_storage__filer1_export__nfs1_export/62f795ed-486c-443d-93e9-2c16595fe546/dom_md/metadata bs=4096 count=1&#39; (cwd None)<br>
Thread-23::DEBUG::2014-03-31 14:31:02,837::fileSD::225::Storage.Misc.excCmd::(getReadDelay) SUCCESS: &lt;err&gt; = &#39;0+1 records in\n0+1 records out\n491 bytes (491 B) copied, 0.000564355 s, 870 kB/s\n&#39;; &lt;rc&gt; = 0<br>
Thread-21::DEBUG::2014-03-31 14:31:02,870::fileSD::225::Storage.Misc.excCmd::(getReadDelay) &#39;/usr/bin/dd iflag=direct if=/rhev/data-center/mnt/10.125.1.193:_mnt_storage__filer1_export__nfs2_iso/b157f887-ae9a-4337-8c55-7c8a9df017bb/dom_md/metadata bs=4096 count=1&#39; (cwd None)<br>
Thread-21::DEBUG::2014-03-31 14:31:02,905::fileSD::225::Storage.Misc.excCmd::(getReadDelay) SUCCESS: &lt;err&gt; = &#39;0+1 records in\n0+1 records out\n369 bytes (369 B) copied, 0.000450599 s, 819 kB/s\n&#39;; &lt;rc&gt; = 0<br>
Thread-20::DEBUG::2014-03-31 14:31:05,798::fileSD::225::Storage.Misc.excCmd::(getReadDelay) &#39;/usr/bin/dd iflag=direct if=/rhev/data-center/mnt/glusterSD/10.125.1.194:gluster__data2/041e8dbe-f405-4aca-bb78-3bf3cc7dc190/dom_md/metadata bs=4096 count=1&#39; (cwd None)<br>
Thread-20::DEBUG::2014-03-31 14:31:05,825::fileSD::225::Storage.Misc.excCmd::(getReadDelay) SUCCESS: &lt;err&gt; = &#39;0+1 records in\n0+1 records out\n608 bytes (608 B) copied, 0.000467098 s, 1.3 MB/s\n&#39;; &lt;rc&gt; = 0<br>
Thread-130::DEBUG::2014-03-31 14:31:05,835::task::595::TaskManager.Task::(_updateState) Task=`4ccb0515-1051-4084-87a8-5f672be6755b`::moving from state init -&gt; state preparing<br>Thread-130::INFO::2014-03-31 14:31:05,835::logUtils::44::dispatcher::(wrapper) Run and protect: repoStats(options=None)<br>
Thread-130::INFO::2014-03-31 14:31:05,835::logUtils::47::dispatcher::(wrapper) Run and protect: repoStats, Return response: {&#39;041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39;: {&#39;code&#39;: 0, &#39;version&#39;: 3, &#39;acquired&#39;: True, &#39;delay&#39;: &#39;0.000467098&#39;, &#39;lastCheck&#39;: &#39;0.0&#39;, &#39;valid&#39;: True}, &#39;b157f887-ae9a-4337-8c55-7c8a9df017bb&#39;: {&#39;code&#39;: 0, &#39;version&#39;: 0, &#39;acquired&#39;: True, &#39;delay&#39;: &#39;0.000450599&#39;, &#39;lastCheck&#39;: &#39;2.9&#39;, &#39;valid&#39;: True}, &#39;5fe658b1-43ff-4bfd-97d6-28217cf415df&#39;: {&#39;code&#39;: 0, &#39;version&#39;: 3, &#39;acquired&#39;: True, &#39;delay&#39;: &#39;0.000212863&#39;, &#39;lastCheck&#39;: &#39;8.5&#39;, &#39;valid&#39;: True}, &#39;62f795ed-486c-443d-93e9-2c16595fe546&#39;: {&#39;code&#39;: 0, &#39;version&#39;: 0, &#39;acquired&#39;: True, &#39;delay&#39;: &#39;0.000564355&#39;, &#39;lastCheck&#39;: &#39;3.0&#39;, &#39;valid&#39;: True}}<br>
Thread-130::DEBUG::2014-03-31 14:31:05,836::task::1185::TaskManager.Task::(prepare) Task=`4ccb0515-1051-4084-87a8-5f672be6755b`::finished: {&#39;041e8dbe-f405-4aca-bb78-3bf3cc7dc190&#39;: {&#39;code&#39;: 0, &#39;version&#39;: 3, &#39;acquired&#39;: True, &#39;delay&#39;: &#39;0.000467098&#39;, &#39;lastCheck&#39;: &#39;0.0&#39;, &#39;valid&#39;: True}, &#39;b157f887-ae9a-4337-8c55-7c8a9df017bb&#39;: {&#39;code&#39;: 0, &#39;version&#39;: 0, &#39;acquired&#39;: True, &#39;delay&#39;: &#39;0.000450599&#39;, &#39;lastCheck&#39;: &#39;2.9&#39;, &#39;valid&#39;: True}, &#39;5fe658b1-43ff-4bfd-97d6-28217cf415df&#39;: {&#39;code&#39;: 0, &#39;version&#39;: 3, &#39;acquired&#39;: True, &#39;delay&#39;: &#39;0.000212863&#39;, &#39;lastCheck&#39;: &#39;8.5&#39;, &#39;valid&#39;: True}, &#39;62f795ed-486c-443d-93e9-2c16595fe546&#39;: {&#39;code&#39;: 0, &#39;version&#39;: 0, &#39;acquired&#39;: True, &#39;delay&#39;: &#39;0.000564355&#39;, &#39;lastCheck&#39;: &#39;3.0&#39;, &#39;valid&#39;: True}}<br>
Thread-130::DEBUG::2014-03-31 14:31:05,836::task::595::TaskManager.Task::(_updateState) Task=`4ccb0515-1051-4084-87a8-5f672be6755b`::moving from state preparing -&gt; state finished<br>Thread-130::DEBUG::2014-03-31 14:31:05,836::resourceManager::940::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}<br>
Thread-130::DEBUG::2014-03-31 14:31:05,836::resourceManager::977::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}<br>Thread-130::DEBUG::2014-03-31 14:31:05,836::task::990::TaskManager.Task::(_decref) Task=`4ccb0515-1051-4084-87a8-5f672be6755b`::ref 0 aborting False<br>
Thread-22::DEBUG::2014-03-31 14:31:07,355::fileSD::225::Storage.Misc.excCmd::(getReadDelay) &#39;/usr/bin/dd iflag=direct if=/rhev/data-center/mnt/glusterSD/10.125.1.194:gluster__data3/5fe658b1-43ff-4bfd-97d6-28217cf415df/dom_md/metadata bs=4096 count=1&#39; (cwd None)<br>
Thread-22::DEBUG::2014-03-31 14:31:07,437::fileSD::225::Storage.Misc.excCmd::(getReadDelay) SUCCESS: &lt;err&gt; = &#39;0+1 records in\n0+1 records out\n653 bytes (653 B) copied, 0.00062957 s, 1.0 MB/s\n&#39;; &lt;rc&gt; = 0<br>
<br></div><div class="gmail_extra"><br><br><div class="gmail_quote">On Mon, Mar 31, 2014 at 2:21 PM, Omer Frenkel <span dir="ltr">&lt;<a href="mailto:ofrenkel@redhat.com" target="_blank">ofrenkel@redhat.com</a>&gt;</span> wrote:<br>
<blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><br>
<br>
----- Original Message -----<br>
&gt; From: &quot;Gabi C&quot; &lt;<a href="mailto:gabicr@gmail.com">gabicr@gmail.com</a>&gt;<br>
&gt; To: &quot;Dan Kenigsberg&quot; &lt;<a href="mailto:danken@redhat.com">danken@redhat.com</a>&gt;<br>
&gt; Cc: <a href="mailto:users@ovirt.org">users@ovirt.org</a><br>
&gt; Sent: Monday, March 31, 2014 1:58:26 PM<br>
&gt; Subject: Re: [Users] VM satelit is down. Exit message: &#39;int&#39; object has no attribute &#39;replace&#39;.<br>
&gt;<br>
&gt; Thanks!<br>
&gt;<br>
&gt; Here it is:<br>
&gt;<br>
<br>
thanks, but this is engine.log<br>
we would find more information on /var/log/vdsm/vdsm.log the host that tried to run the vm (virtual6)<br>
<div><div class="h5"><br>
&gt;<br>
&gt; 2014-03-31 13:53:54,737 INFO [org.ovirt.engine.core.bll.RunVmCommand]<br>
&gt; (ajp--127.0.0.1-8702-20) [787f133c] Lock Acquired to object EngineLock<br>
&gt; [exclusiveLocks= key: 92fbccc5-1c99-4020-822b-74d43ad214c3 value: VM<br>
&gt; , sharedLocks= ]<br>
&gt; 2014-03-31 13:53:54,763 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]<br>
&gt; (ajp--127.0.0.1-8702-20) [787f133c] START, IsVmDuringInitiatingVDSCommand(<br>
&gt; vmId = 92fbccc5-1c99-4020-822b-74d43ad214c3), log id: 685abcbf<br>
&gt; 2014-03-31 13:53:54,767 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]<br>
&gt; (ajp--127.0.0.1-8702-20) [787f133c] FINISH, IsVmDuringInitiatingVDSCommand,<br>
&gt; return: false, log id: 685abcbf<br>
&gt; 2014-03-31 13:53:54,811 INFO [org.ovirt.engine.core.bll.RunVmCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-4) [787f133c] Running command: RunVmCommand<br>
&gt; internal: false. Entities affected : ID:<br>
&gt; 92fbccc5-1c99-4020-822b-74d43ad214c3 Type: VM<br>
&gt; 2014-03-31 13:53:54,839 INFO<br>
&gt; [org.ovirt.engine.core.bll.scheduling.policyunits.HaReservationWeightPolicyUnit]<br>
&gt; (org.ovirt.thread.pool-6-thread-4) [787f133c] Started HA reservation scoring<br>
&gt; method<br>
&gt; 2014-03-31 13:53:54,855 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-4) [787f133c] START,<br>
&gt; CreateVmVDSCommand(HostName = virtual6, HostId =<br>
&gt; e11c37b0-d79d-417b-bd6f-6dc172f7921c,<br>
&gt; vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, vm=VM [PRTG]), log id: 497b9920<br>
&gt; 2014-03-31 13:53:54,863 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-4) [787f133c] START,<br>
&gt; CreateVDSCommand(HostName = virtual6, HostId =<br>
&gt; e11c37b0-d79d-417b-bd6f-6dc172f7921c,<br>
&gt; vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, vm=VM [PRTG]), log id: 7b64d5f2<br>
&gt; 2014-03-31 13:53:54,923 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-4) [787f133c]<br>
&gt; org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand<br>
&gt; spiceSslCipherSuite=DEFAULT,memSize=2048,kvmEnable=true,smp=1,vmType=kvm,emulatedMachine=pc-1.0,keyboardLayout=en-us,memGuaranteedSize=1365,nice=0,display=qxl,smartcardEnable=false,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,timeOffset=3600,transparentHugePages=true,vmId=92fbccc5-1c99-4020-822b-74d43ad214c3,devices=[{address={bus=0x00,<br>

&gt; domain=0x0000, slot=0x02, type=pci, function=0x0}, specParams={vram=32768,<br>
&gt; heads=1}, device=qxl, type=video,<br>
&gt; deviceId=e46018f8-fbfc-484d-891a-e7ade9f88c51}, {shared=false, bootOrder=2,<br>
&gt; iface=ide, index=2, address={unit=0, bus=1, target=0, controller=0,<br>
&gt; type=drive}, specParams={path=}, path=, device=cdrom, type=disk,<br>
&gt; readonly=true, deviceId=8eac07a3-d353-43db-b2e3-5f8fe414bdfe},<br>
&gt; {shared=false, index=0, volumeID=438299fb-0a72-4005-b0c8-5544eece6dba,<br>
&gt; propagateErrors=off, format=raw, type=disk, iface=virtio, bootOrder=1,<br>
&gt; address={bus=0x00, domain=0x0000, slot=0x06, type=pci, function=0x0},<br>
&gt; domainID=041e8dbe-f405-4aca-bb78-3bf3cc7dc190,<br>
&gt; imageID=68051a25-d3de-46b4-9e4b-686d824146bf, specParams={}, optional=false,<br>
&gt; device=disk, poolID=5849b030-626e-47cb-ad90-3ce782d831b3, readonly=false,<br>
&gt; deviceId=68051a25-d3de-46b4-9e4b-686d824146bf}, {nicModel=pv,<br>
&gt; address={bus=0x00, domain=0x0000, slot=0x03, type=pci, function=0x0},<br>
&gt; specParams={outbound={peak=26880, burst=256000, average=25600},<br>
&gt; inbound={peak=26880, burst=256000, average=25600}},<br>
&gt; macAddr=00:1a:4a:69:90:de, device=bridge, linkActive=true, type=interface,<br>
&gt; filter=vdsm-no-mac-spoofing, network=VPO_IPPROXY,<br>
&gt; deviceId=553de5f6-e108-4c45-84d2-7a292304211f}, {specParams={model=virtio},<br>
&gt; device=memballoon, type=balloon,<br>
&gt; deviceId=68c759aa-6266-432c-9b8b-7a4b215eee57}, {index=0, model=virtio-scsi,<br>
&gt; address={bus=0x00, domain=0x0000, slot=0x04, type=pci, function=0x0},<br>
&gt; specParams={}, device=scsi, type=controller,<br>
&gt; deviceId=fc27e446-7932-40e4-98bd-2d13237ed6f5}],acpiEnable=true,cpuShares=1024,vmName=PRTG,cpuType=Conroe,custom={device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3cdevice_b6ac752a-13e2-4107-aa8e-ddc054551f15=VmDevice<br>

&gt; {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3,<br>
&gt; deviceId=b6ac752a-13e2-4107-aa8e-ddc054551f15, device=unix, type=CHANNEL,<br>
&gt; bootOrder=0, specParams={}, address={port=1, bus=0, controller=0,<br>
&gt; type=virtio-serial}, managed=false, plugged=true, readOnly=false,<br>
&gt; deviceAlias=channel0, customProperties={}, snapshotId=null},<br>
&gt; device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3cdevice_b6ac752a-13e2-4107-aa8e-ddc054551f15device_83ffb451-8ba0-4b56-8082-b243b793d83e=VmDevice<br>
&gt; {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3,<br>
&gt; deviceId=83ffb451-8ba0-4b56-8082-b243b793d83e, device=unix, type=CHANNEL,<br>
&gt; bootOrder=0, specParams={}, address={port=2, bus=0, controller=0,<br>
&gt; type=virtio-serial}, managed=false, plugged=true, readOnly=false,<br>
&gt; deviceAlias=channel1, customProperties={}, snapshotId=null},<br>
&gt; device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3cdevice_b6ac752a-13e2-4107-aa8e-ddc054551f15device_83ffb451-8ba0-4b56-8082-b243b793d83edevice_f8e59a5e-20a3-4a45-91bf-804ad60f9efb=VmDevice<br>

&gt; {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3,<br>
&gt; deviceId=f8e59a5e-20a3-4a45-91bf-804ad60f9efb, device=spicevmc,<br>
&gt; type=CHANNEL, bootOrder=0, specParams={}, address={port=3, bus=0,<br>
&gt; controller=0, type=virtio-serial}, managed=false, plugged=true,<br>
&gt; readOnly=false, deviceAlias=channel2, customProperties={}, snapshotId=null},<br>
&gt; device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9ee=VmDevice<br>
&gt; {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3,<br>
&gt; deviceId=ac3c9c1c-9d8d-4ced-ba59-977f54ccb9ee, device=ide, type=CONTROLLER,<br>
&gt; bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci,<br>
&gt; slot=0x01, function=0x1}, managed=false, plugged=true, readOnly=false,<br>
&gt; deviceAlias=ide0, customProperties={}, snapshotId=null},<br>
&gt; device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3c=VmDevice<br>
&gt; {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3,<br>
&gt; deviceId=804006d1-ed61-4c0e-9818-0d0f4f0e8e3c, device=virtio-serial,<br>
&gt; type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00,<br>
&gt; domain=0x0000, type=pci, slot=0x05, function=0x0}, managed=false,<br>
&gt; plugged=true, readOnly=false, deviceAlias=virtio-serial0,<br>
&gt; customProperties={}, snapshotId=null}}<br>
&gt; 2014-03-31 13:53:54,971 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-4) [787f133c] FINISH, CreateVDSCommand, log<br>
&gt; id: 7b64d5f2<br>
&gt; 2014-03-31 13:53:54,982 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-4) [787f133c] FINISH, CreateVmVDSCommand,<br>
&gt; return: WaitForLaunch, log id: 497b9920<br>
&gt; 2014-03-31 13:53:54,986 INFO [org.ovirt.engine.core.bll.RunVmCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-4) [787f133c] Lock freed to object<br>
&gt; EngineLock [exclusiveLocks= key: 92fbccc5-1c99-4020-822b-74d43ad214c3 value:<br>
&gt; VM<br>
&gt; , sharedLocks= ]<br>
&gt; 2014-03-31 13:53:54,995 INFO<br>
&gt; [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]<br>
&gt; (org.ovirt.thread.pool-6-thread-4) [787f133c] Correlation ID: 787f133c, Job<br>
&gt; ID: e9bc3c5d-a16f-4cfc-ae35-340d153893b9, Call Stack: null, Custom Event ID:<br>
&gt; -1, Message: VM PRTG was started by admin (Host: virtual6).<br>
&gt; 2014-03-31 13:53:55,232 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand]<br>
&gt; (DefaultQuartzScheduler_Worker-15) START, DestroyVDSCommand(HostName =<br>
&gt; virtual6, HostId = e11c37b0-d79d-417b-bd6f-6dc172f7921c,<br>
&gt; vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, force=false, secondsToWait=0,<br>
&gt; gracefully=false), log id: 780a285e<br>
&gt; 2014-03-31 13:53:55,252 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand]<br>
&gt; (DefaultQuartzScheduler_Worker-15) FINISH, DestroyVDSCommand, log id:<br>
&gt; 780a285e<br>
&gt; 2014-03-31 13:53:55,315 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-22) Domain<br>
&gt; b157f887-ae9a-4337-8c55-7c8a9df017bb:imagini recovered from problem. vds:<br>
&gt; virtual5<br>
&gt; 2014-03-31 13:53:55,319 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-22) Domain<br>
&gt; 62f795ed-486c-443d-93e9-2c16595fe546:masini recovered from problem. vds:<br>
&gt; virtual5<br>
&gt; 2014-03-31 13:53:55,342 INFO<br>
&gt; [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]<br>
&gt; (DefaultQuartzScheduler_Worker-15) Correlation ID: null, Call Stack: null,<br>
&gt; Custom Event ID: -1, Message: VM PRTG is down. Exit message: &#39;int&#39; object<br>
&gt; has no attribute &#39;replace&#39;.<br>
&gt; 2014-03-31 13:53:55,346 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]<br>
&gt; (DefaultQuartzScheduler_Worker-15) Running on vds during rerun failed vm:<br>
&gt; null<br>
&gt; 2014-03-31 13:53:55,351 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]<br>
&gt; (DefaultQuartzScheduler_Worker-15) VM PRTG<br>
&gt; (92fbccc5-1c99-4020-822b-74d43ad214c3) is running in db and not running in<br>
&gt; VDS virtual6<br>
&gt; 2014-03-31 13:53:55,355 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]<br>
&gt; (DefaultQuartzScheduler_Worker-15) add VM PRTG to HA rerun treatment<br>
&gt; 2014-03-31 13:53:55,366 ERROR<br>
&gt; [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]<br>
&gt; (DefaultQuartzScheduler_Worker-15) Rerun vm<br>
&gt; 92fbccc5-1c99-4020-822b-74d43ad214c3. Called from vds virtual6<br>
&gt; 2014-03-31 13:53:55,375 INFO<br>
&gt; [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]<br>
&gt; (org.ovirt.thread.pool-6-thread-25) Correlation ID: 787f133c, Job ID:<br>
&gt; e9bc3c5d-a16f-4cfc-ae35-340d153893b9, Call Stack: null, Custom Event ID: -1,<br>
&gt; Message: Failed to run VM PRTG on Host virtual6.<br>
&gt; 2014-03-31 13:53:55,399 INFO [org.ovirt.engine.core.bll.RunVmCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-25) Lock Acquired to object EngineLock<br>
&gt; [exclusiveLocks= key: 92fbccc5-1c99-4020-822b-74d43ad214c3 value: VM<br>
&gt; , sharedLocks= ]<br>
&gt; 2014-03-31 13:53:55,424 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-25) START, IsVmDuringInitiatingVDSCommand(<br>
&gt; vmId = 92fbccc5-1c99-4020-822b-74d43ad214c3), log id: 80df73c<br>
&gt; 2014-03-31 13:53:55,429 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-25) FINISH, IsVmDuringInitiatingVDSCommand,<br>
&gt; return: false, log id: 80df73c<br>
&gt; 2014-03-31 13:53:55,458 INFO [org.ovirt.engine.core.bll.RunVmCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-25) Running command: RunVmCommand internal:<br>
&gt; false. Entities affected : ID: 92fbccc5-1c99-4020-822b-74d43ad214c3 Type: VM<br>
&gt; 2014-03-31 13:53:55,464 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-21) Domain<br>
&gt; b157f887-ae9a-4337-8c55-7c8a9df017bb:imagini recovered from problem. vds:<br>
&gt; virtual4<br>
&gt; 2014-03-31 13:53:55,482 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-21) Domain<br>
&gt; 62f795ed-486c-443d-93e9-2c16595fe546:masini recovered from problem. vds:<br>
&gt; virtual4<br>
&gt; 2014-03-31 13:53:55,534 INFO<br>
&gt; [org.ovirt.engine.core.bll.scheduling.policyunits.HaReservationWeightPolicyUnit]<br>
&gt; (org.ovirt.thread.pool-6-thread-25) Started HA reservation scoring method<br>
&gt; 2014-03-31 13:53:55,551 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-25) START, CreateVmVDSCommand(HostName =<br>
&gt; virtual5, HostId = ee8c0f32-5129-48ec-b024-8ae83d5835eb,<br>
&gt; vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, vm=VM [PRTG]), log id: 5db07e26<br>
&gt; 2014-03-31 13:53:55,561 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-25) START, CreateVDSCommand(HostName =<br>
&gt; virtual5, HostId = ee8c0f32-5129-48ec-b024-8ae83d5835eb,<br>
&gt; vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, vm=VM [PRTG]), log id: 41f8abfd<br>
&gt; 2014-03-31 13:53:55,624 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-25)<br>
&gt; org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand<br>
&gt; spiceSslCipherSuite=DEFAULT,memSize=2048,kvmEnable=true,smp=1,vmType=kvm,emulatedMachine=pc-1.0,keyboardLayout=en-us,memGuaranteedSize=1365,nice=0,display=qxl,smartcardEnable=false,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,timeOffset=3600,transparentHugePages=true,vmId=92fbccc5-1c99-4020-822b-74d43ad214c3,devices=[{address={bus=0x00,<br>

&gt; domain=0x0000, slot=0x02, type=pci, function=0x0}, specParams={vram=32768,<br>
&gt; heads=1}, device=qxl, type=video,<br>
&gt; deviceId=e46018f8-fbfc-484d-891a-e7ade9f88c51}, {shared=false, bootOrder=2,<br>
&gt; iface=ide, index=2, address={unit=0, bus=1, target=0, controller=0,<br>
&gt; type=drive}, specParams={path=}, path=, device=cdrom, type=disk,<br>
&gt; readonly=true, deviceId=8eac07a3-d353-43db-b2e3-5f8fe414bdfe},<br>
&gt; {shared=false, index=0, volumeID=438299fb-0a72-4005-b0c8-5544eece6dba,<br>
&gt; propagateErrors=off, format=raw, type=disk, iface=virtio, bootOrder=1,<br>
&gt; address={bus=0x00, domain=0x0000, slot=0x06, type=pci, function=0x0},<br>
&gt; domainID=041e8dbe-f405-4aca-bb78-3bf3cc7dc190,<br>
&gt; imageID=68051a25-d3de-46b4-9e4b-686d824146bf, specParams={}, optional=false,<br>
&gt; device=disk, poolID=5849b030-626e-47cb-ad90-3ce782d831b3, readonly=false,<br>
&gt; deviceId=68051a25-d3de-46b4-9e4b-686d824146bf}, {nicModel=pv,<br>
&gt; address={bus=0x00, domain=0x0000, slot=0x03, type=pci, function=0x0},<br>
&gt; specParams={outbound={peak=26880, burst=256000, average=25600},<br>
&gt; inbound={peak=26880, burst=256000, average=25600}},<br>
&gt; macAddr=00:1a:4a:69:90:de, device=bridge, linkActive=true, type=interface,<br>
&gt; filter=vdsm-no-mac-spoofing, network=VPO_IPPROXY,<br>
&gt; deviceId=553de5f6-e108-4c45-84d2-7a292304211f}, {specParams={model=virtio},<br>
&gt; device=memballoon, type=balloon,<br>
&gt; deviceId=68c759aa-6266-432c-9b8b-7a4b215eee57}, {index=0, model=virtio-scsi,<br>
&gt; address={bus=0x00, domain=0x0000, slot=0x04, type=pci, function=0x0},<br>
&gt; specParams={}, device=scsi, type=controller,<br>
&gt; deviceId=fc27e446-7932-40e4-98bd-2d13237ed6f5}],acpiEnable=true,cpuShares=1024,vmName=PRTG,cpuType=Conroe,custom={device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3cdevice_b6ac752a-13e2-4107-aa8e-ddc054551f15=VmDevice<br>

&gt; {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3,<br>
&gt; deviceId=b6ac752a-13e2-4107-aa8e-ddc054551f15, device=unix, type=CHANNEL,<br>
&gt; bootOrder=0, specParams={}, address={port=1, bus=0, controller=0,<br>
&gt; type=virtio-serial}, managed=false, plugged=true, readOnly=false,<br>
&gt; deviceAlias=channel0, customProperties={}, snapshotId=null},<br>
&gt; device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3cdevice_b6ac752a-13e2-4107-aa8e-ddc054551f15device_83ffb451-8ba0-4b56-8082-b243b793d83e=VmDevice<br>
&gt; {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3,<br>
&gt; deviceId=83ffb451-8ba0-4b56-8082-b243b793d83e, device=unix, type=CHANNEL,<br>
&gt; bootOrder=0, specParams={}, address={port=2, bus=0, controller=0,<br>
&gt; type=virtio-serial}, managed=false, plugged=true, readOnly=false,<br>
&gt; deviceAlias=channel1, customProperties={}, snapshotId=null},<br>
&gt; device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3cdevice_b6ac752a-13e2-4107-aa8e-ddc054551f15device_83ffb451-8ba0-4b56-8082-b243b793d83edevice_f8e59a5e-20a3-4a45-91bf-804ad60f9efb=VmDevice<br>

&gt; {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3,<br>
&gt; deviceId=f8e59a5e-20a3-4a45-91bf-804ad60f9efb, device=spicevmc,<br>
&gt; type=CHANNEL, bootOrder=0, specParams={}, address={port=3, bus=0,<br>
&gt; controller=0, type=virtio-serial}, managed=false, plugged=true,<br>
&gt; readOnly=false, deviceAlias=channel2, customProperties={}, snapshotId=null},<br>
&gt; device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9ee=VmDevice<br>
&gt; {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3,<br>
&gt; deviceId=ac3c9c1c-9d8d-4ced-ba59-977f54ccb9ee, device=ide, type=CONTROLLER,<br>
&gt; bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci,<br>
&gt; slot=0x01, function=0x1}, managed=false, plugged=true, readOnly=false,<br>
&gt; deviceAlias=ide0, customProperties={}, snapshotId=null},<br>
&gt; device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3c=VmDevice<br>
&gt; {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3,<br>
&gt; deviceId=804006d1-ed61-4c0e-9818-0d0f4f0e8e3c, device=virtio-serial,<br>
&gt; type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00,<br>
&gt; domain=0x0000, type=pci, slot=0x05, function=0x0}, managed=false,<br>
&gt; plugged=true, readOnly=false, deviceAlias=virtio-serial0,<br>
&gt; customProperties={}, snapshotId=null}}<br>
&gt; 2014-03-31 13:53:55,675 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-25) FINISH, CreateVDSCommand, log id:<br>
&gt; 41f8abfd<br>
&gt; 2014-03-31 13:53:55,683 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-25) FINISH, CreateVmVDSCommand, return:<br>
&gt; WaitForLaunch, log id: 5db07e26<br>
&gt; 2014-03-31 13:53:55,685 INFO [org.ovirt.engine.core.bll.RunVmCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-25) Lock freed to object EngineLock<br>
&gt; [exclusiveLocks= key: 92fbccc5-1c99-4020-822b-74d43ad214c3 value: VM<br>
&gt; , sharedLocks= ]<br>
&gt; 2014-03-31 13:53:55,692 INFO<br>
&gt; [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]<br>
&gt; (org.ovirt.thread.pool-6-thread-25) Correlation ID: 787f133c, Job ID:<br>
&gt; e9bc3c5d-a16f-4cfc-ae35-340d153893b9, Call Stack: null, Custom Event ID: -1,<br>
&gt; Message: VM PRTG was started by admin (Host: virtual5).<br>
&gt; 2014-03-31 13:53:58,350 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand]<br>
&gt; (DefaultQuartzScheduler_Worker-25) START, DestroyVDSCommand(HostName =<br>
&gt; virtual5, HostId = ee8c0f32-5129-48ec-b024-8ae83d5835eb,<br>
&gt; vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, force=false, secondsToWait=0,<br>
&gt; gracefully=false), log id: 49a8c278<br>
&gt; 2014-03-31 13:53:58,370 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand]<br>
&gt; (DefaultQuartzScheduler_Worker-25) FINISH, DestroyVDSCommand, log id:<br>
&gt; 49a8c278<br>
&gt; 2014-03-31 13:53:58,433 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-36) Domain<br>
&gt; b157f887-ae9a-4337-8c55-7c8a9df017bb:imagini recovered from problem. vds:<br>
&gt; virtual6<br>
&gt; 2014-03-31 13:53:58,436 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-36) Domain<br>
&gt; b157f887-ae9a-4337-8c55-7c8a9df017bb:imagini has recovered from problem. No<br>
&gt; active host in the DC is reporting it as problematic, so clearing the domain<br>
&gt; recovery timer.<br>
&gt; 2014-03-31 13:53:58,445 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-36) Domain<br>
&gt; 62f795ed-486c-443d-93e9-2c16595fe546:masini recovered from problem. vds:<br>
&gt; virtual6<br>
&gt; 2014-03-31 13:53:58,450 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-36) Domain<br>
&gt; 62f795ed-486c-443d-93e9-2c16595fe546:masini has recovered from problem. No<br>
&gt; active host in the DC is reporting it as problematic, so clearing the domain<br>
&gt; recovery timer.<br>
&gt; 2014-03-31 13:53:58,454 INFO<br>
&gt; [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]<br>
&gt; (DefaultQuartzScheduler_Worker-25) Correlation ID: null, Call Stack: null,<br>
&gt; Custom Event ID: -1, Message: VM PRTG is down. Exit message: &#39;int&#39; object<br>
&gt; has no attribute &#39;replace&#39;.<br>
&gt; 2014-03-31 13:53:58,460 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]<br>
&gt; (DefaultQuartzScheduler_Worker-25) Running on vds during rerun failed vm:<br>
&gt; null<br>
&gt; 2014-03-31 13:53:58,463 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]<br>
&gt; (DefaultQuartzScheduler_Worker-25) VM PRTG<br>
&gt; (92fbccc5-1c99-4020-822b-74d43ad214c3) is running in db and not running in<br>
&gt; VDS virtual5<br>
&gt; 2014-03-31 13:53:58,466 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]<br>
&gt; (DefaultQuartzScheduler_Worker-25) add VM PRTG to HA rerun treatment<br>
&gt; 2014-03-31 13:53:58,475 ERROR<br>
&gt; [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]<br>
&gt; (DefaultQuartzScheduler_Worker-25) Rerun vm<br>
&gt; 92fbccc5-1c99-4020-822b-74d43ad214c3. Called from vds virtual5<br>
&gt; 2014-03-31 13:53:58,482 INFO<br>
&gt; [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]<br>
&gt; (org.ovirt.thread.pool-6-thread-19) Correlation ID: 787f133c, Job ID:<br>
&gt; e9bc3c5d-a16f-4cfc-ae35-340d153893b9, Call Stack: null, Custom Event ID: -1,<br>
&gt; Message: Failed to run VM PRTG on Host virtual5.<br>
&gt; 2014-03-31 13:53:58,501 INFO [org.ovirt.engine.core.bll.RunVmCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-19) Lock Acquired to object EngineLock<br>
&gt; [exclusiveLocks= key: 92fbccc5-1c99-4020-822b-74d43ad214c3 value: VM<br>
&gt; , sharedLocks= ]<br>
&gt; 2014-03-31 13:53:58,526 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-19) START, IsVmDuringInitiatingVDSCommand(<br>
&gt; vmId = 92fbccc5-1c99-4020-822b-74d43ad214c3), log id: 2b5bee5b<br>
&gt; 2014-03-31 13:53:58,532 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-19) FINISH, IsVmDuringInitiatingVDSCommand,<br>
&gt; return: false, log id: 2b5bee5b<br>
&gt; 2014-03-31 13:53:58,556 INFO [org.ovirt.engine.core.bll.RunVmCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-19) Running command: RunVmCommand internal:<br>
&gt; false. Entities affected : ID: 92fbccc5-1c99-4020-822b-74d43ad214c3 Type: VM<br>
&gt; 2014-03-31 13:53:58,589 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-19) START, CreateVmVDSCommand(HostName =<br>
&gt; virtual4, HostId = f4fc34ed-4261-46f1-ac5e-c849eb7e9b7a,<br>
&gt; vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, vm=VM [PRTG]), log id: 5bfb709<br>
&gt; 2014-03-31 13:53:58,596 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-19) START, CreateVDSCommand(HostName =<br>
&gt; virtual4, HostId = f4fc34ed-4261-46f1-ac5e-c849eb7e9b7a,<br>
&gt; vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, vm=VM [PRTG]), log id: 2fa9cbb3<br>
&gt; 2014-03-31 13:53:58,887 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-19)<br>
&gt; org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand<br>
&gt; spiceSslCipherSuite=DEFAULT,memSize=2048,kvmEnable=true,smp=1,vmType=kvm,emulatedMachine=pc-1.0,keyboardLayout=en-us,memGuaranteedSize=1365,nice=0,display=qxl,smartcardEnable=false,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,timeOffset=3600,transparentHugePages=true,vmId=92fbccc5-1c99-4020-822b-74d43ad214c3,devices=[{address={bus=0x00,<br>

&gt; domain=0x0000, slot=0x02, type=pci, function=0x0}, specParams={vram=32768,<br>
&gt; heads=1}, device=qxl, type=video,<br>
&gt; deviceId=e46018f8-fbfc-484d-891a-e7ade9f88c51}, {shared=false, bootOrder=2,<br>
&gt; iface=ide, index=2, address={unit=0, bus=1, target=0, controller=0,<br>
&gt; type=drive}, specParams={path=}, path=, device=cdrom, type=disk,<br>
&gt; readonly=true, deviceId=8eac07a3-d353-43db-b2e3-5f8fe414bdfe},<br>
&gt; {shared=false, index=0, volumeID=438299fb-0a72-4005-b0c8-5544eece6dba,<br>
&gt; propagateErrors=off, format=raw, type=disk, iface=virtio, bootOrder=1,<br>
&gt; address={bus=0x00, domain=0x0000, slot=0x06, type=pci, function=0x0},<br>
&gt; domainID=041e8dbe-f405-4aca-bb78-3bf3cc7dc190,<br>
&gt; imageID=68051a25-d3de-46b4-9e4b-686d824146bf, specParams={}, optional=false,<br>
&gt; device=disk, poolID=5849b030-626e-47cb-ad90-3ce782d831b3, readonly=false,<br>
&gt; deviceId=68051a25-d3de-46b4-9e4b-686d824146bf}, {nicModel=pv,<br>
&gt; address={bus=0x00, domain=0x0000, slot=0x03, type=pci, function=0x0},<br>
&gt; specParams={outbound={peak=26880, burst=256000, average=25600},<br>
&gt; inbound={peak=26880, burst=256000, average=25600}},<br>
&gt; macAddr=00:1a:4a:69:90:de, device=bridge, linkActive=true, type=interface,<br>
&gt; filter=vdsm-no-mac-spoofing, network=VPO_IPPROXY,<br>
&gt; deviceId=553de5f6-e108-4c45-84d2-7a292304211f}, {specParams={model=virtio},<br>
&gt; device=memballoon, type=balloon,<br>
&gt; deviceId=68c759aa-6266-432c-9b8b-7a4b215eee57}, {index=0, model=virtio-scsi,<br>
&gt; address={bus=0x00, domain=0x0000, slot=0x04, type=pci, function=0x0},<br>
&gt; specParams={}, device=scsi, type=controller,<br>
&gt; deviceId=fc27e446-7932-40e4-98bd-2d13237ed6f5}],acpiEnable=true,cpuShares=1024,vmName=PRTG,cpuType=Conroe,custom={device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3cdevice_b6ac752a-13e2-4107-aa8e-ddc054551f15=VmDevice<br>

&gt; {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3,<br>
&gt; deviceId=b6ac752a-13e2-4107-aa8e-ddc054551f15, device=unix, type=CHANNEL,<br>
&gt; bootOrder=0, specParams={}, address={port=1, bus=0, controller=0,<br>
&gt; type=virtio-serial}, managed=false, plugged=true, readOnly=false,<br>
&gt; deviceAlias=channel0, customProperties={}, snapshotId=null},<br>
&gt; device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3cdevice_b6ac752a-13e2-4107-aa8e-ddc054551f15device_83ffb451-8ba0-4b56-8082-b243b793d83e=VmDevice<br>
&gt; {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3,<br>
&gt; deviceId=83ffb451-8ba0-4b56-8082-b243b793d83e, device=unix, type=CHANNEL,<br>
&gt; bootOrder=0, specParams={}, address={port=2, bus=0, controller=0,<br>
&gt; type=virtio-serial}, managed=false, plugged=true, readOnly=false,<br>
&gt; deviceAlias=channel1, customProperties={}, snapshotId=null},<br>
&gt; device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3cdevice_b6ac752a-13e2-4107-aa8e-ddc054551f15device_83ffb451-8ba0-4b56-8082-b243b793d83edevice_f8e59a5e-20a3-4a45-91bf-804ad60f9efb=VmDevice<br>

&gt; {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3,<br>
&gt; deviceId=f8e59a5e-20a3-4a45-91bf-804ad60f9efb, device=spicevmc,<br>
&gt; type=CHANNEL, bootOrder=0, specParams={}, address={port=3, bus=0,<br>
&gt; controller=0, type=virtio-serial}, managed=false, plugged=true,<br>
&gt; readOnly=false, deviceAlias=channel2, customProperties={}, snapshotId=null},<br>
&gt; device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9ee=VmDevice<br>
&gt; {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3,<br>
&gt; deviceId=ac3c9c1c-9d8d-4ced-ba59-977f54ccb9ee, device=ide, type=CONTROLLER,<br>
&gt; bootOrder=0, specParams={}, address={bus=0x00, domain=0x0000, type=pci,<br>
&gt; slot=0x01, function=0x1}, managed=false, plugged=true, readOnly=false,<br>
&gt; deviceAlias=ide0, customProperties={}, snapshotId=null},<br>
&gt; device_ac3c9c1c-9d8d-4ced-ba59-977f54ccb9eedevice_804006d1-ed61-4c0e-9818-0d0f4f0e8e3c=VmDevice<br>
&gt; {vmId=92fbccc5-1c99-4020-822b-74d43ad214c3,<br>
&gt; deviceId=804006d1-ed61-4c0e-9818-0d0f4f0e8e3c, device=virtio-serial,<br>
&gt; type=CONTROLLER, bootOrder=0, specParams={}, address={bus=0x00,<br>
&gt; domain=0x0000, type=pci, slot=0x05, function=0x0}, managed=false,<br>
&gt; plugged=true, readOnly=false, deviceAlias=virtio-serial0,<br>
&gt; customProperties={}, snapshotId=null}}<br>
&gt; 2014-03-31 13:53:58,936 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-19) FINISH, CreateVDSCommand, log id:<br>
&gt; 2fa9cbb3<br>
&gt; 2014-03-31 13:53:58,943 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-19) FINISH, CreateVmVDSCommand, return:<br>
&gt; WaitForLaunch, log id: 5bfb709<br>
&gt; 2014-03-31 13:53:58,946 INFO [org.ovirt.engine.core.bll.RunVmCommand]<br>
&gt; (org.ovirt.thread.pool-6-thread-19) Lock freed to object EngineLock<br>
&gt; [exclusiveLocks= key: 92fbccc5-1c99-4020-822b-74d43ad214c3 value: VM<br>
&gt; , sharedLocks= ]<br>
&gt; 2014-03-31 13:53:58,952 INFO<br>
&gt; [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]<br>
&gt; (org.ovirt.thread.pool-6-thread-19) Correlation ID: 787f133c, Job ID:<br>
&gt; e9bc3c5d-a16f-4cfc-ae35-340d153893b9, Call Stack: null, Custom Event ID: -1,<br>
&gt; Message: VM PRTG was started by admin (Host: virtual4).<br>
&gt; 2014-03-31 13:53:59,142 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand]<br>
&gt; (DefaultQuartzScheduler_Worker-26) START,<br>
&gt; GlusterVolumesListVDSCommand(HostName = virtual6, HostId =<br>
&gt; e11c37b0-d79d-417b-bd6f-6dc172f7921c), log id: 45331b03<br>
&gt; 2014-03-31 13:53:59,186 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.gluster.GlusterVolumesListVDSCommand]<br>
&gt; (DefaultQuartzScheduler_Worker-26) FINISH, GlusterVolumesListVDSCommand,<br>
&gt; return:<br>
&gt; {3eb2a0de-c6f7-4ef8-a9fd-777d9e529adc=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@7ab347f8,<br>
&gt; a297bf18-e147-4792-b590-1f485732f8ee=org.ovirt.engine.core.common.businessentities.gluster.GlusterVolumeEntity@237e1375},<br>
&gt; log id: 45331b03<br>
&gt; 2014-03-31 13:54:01,519 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand]<br>
&gt; (DefaultQuartzScheduler_Worker-32) START, DestroyVDSCommand(HostName =<br>
&gt; virtual4, HostId = f4fc34ed-4261-46f1-ac5e-c849eb7e9b7a,<br>
&gt; vmId=92fbccc5-1c99-4020-822b-74d43ad214c3, force=false, secondsToWait=0,<br>
&gt; gracefully=false), log id: 27d3bc97<br>
&gt; 2014-03-31 13:54:01,540 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand]<br>
&gt; (DefaultQuartzScheduler_Worker-32) FINISH, DestroyVDSCommand, log id:<br>
&gt; 27d3bc97<br>
&gt; 2014-03-31 13:54:01,561 INFO<br>
&gt; [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]<br>
&gt; (DefaultQuartzScheduler_Worker-32) Correlation ID: null, Call Stack: null,<br>
&gt; Custom Event ID: -1, Message: VM PRTG is down. Exit message: &#39;int&#39; object<br>
&gt; has no attribute &#39;replace&#39;.<br>
&gt; 2014-03-31 13:54:01,565 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]<br>
&gt; (DefaultQuartzScheduler_Worker-32) Running on vds during rerun failed vm:<br>
&gt; null<br>
&gt; 2014-03-31 13:54:01,568 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]<br>
&gt; (DefaultQuartzScheduler_Worker-32) VM PRTG<br>
&gt; (92fbccc5-1c99-4020-822b-74d43ad214c3) is running in db and not running in<br>
&gt; VDS virtual4<br>
&gt; 2014-03-31 13:54:01,571 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]<br>
&gt; (DefaultQuartzScheduler_Worker-32) add VM PRTG to HA rerun treatment<br>
&gt; 2014-03-31 13:54:01,579 ERROR<br>
&gt; [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]<br>
&gt; (DefaultQuartzScheduler_Worker-32) Rerun vm<br>
&gt; 92fbccc5-1c99-4020-822b-74d43ad214c3. Called from vds virtual4<br>
&gt; 2014-03-31 13:54:01,587 INFO<br>
&gt; [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]<br>
&gt; (org.ovirt.thread.pool-6-thread-14) Correlation ID: 787f133c, Job ID:<br>
&gt; e9bc3c5d-a16f-4cfc-ae35-340d153893b9, Call Stack: null, Custom Event ID: -1,<br>
&gt; Message: Failed to run VM PRTG on Host virtual4.<br>
&gt; 2014-03-31 13:54:01,605 INFO<br>
&gt; [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]<br>
&gt; (org.ovirt.thread.pool-6-thread-14) Correlation ID: 787f133c, Job ID:<br>
&gt; e9bc3c5d-a16f-4cfc-ae35-340d153893b9, Call Stack: null, Custom Event ID: -1,<br>
&gt; Message: Failed to run VM PRTG (User: admin).<br>
&gt;<br>
&gt;<br>
&gt;<br>
&gt; On Mon, Mar 31, 2014 at 1:47 PM, Dan Kenigsberg &lt; <a href="mailto:danken@redhat.com">danken@redhat.com</a> &gt; wrote:<br>
&gt;<br>
&gt;<br>
&gt;<br>
&gt; On Mon, Mar 31, 2014 at 01:38:29PM +0300, Gabi C wrote:<br>
&gt; &gt; Hello!<br>
&gt; &gt;<br>
&gt; &gt; 3.3.3 migrated to latest stable 3.4.0<br>
&gt; &gt;<br>
&gt; &gt; 3 nodes F19<br>
&gt; &gt;<br>
&gt; &gt; KVM Version:1.4.2 - 15.fc19<br>
&gt; &gt; LIBVIRT Version:libvirt-1.0.5.9-1.fc19<br>
&gt; &gt; VDSM Version:libvirt-1.0.5.9-1.fc19<br>
&gt; &gt;<br>
&gt; &gt; oVirt Engine Version: 3.4.0-1.fc19<br>
&gt; &gt;<br>
&gt; &gt;<br>
&gt; &gt; Upgraded took place with 3 Vm up and 2 down: first upgraded engine,then<br>
&gt; &gt; after freeing one node at a time node of and updating it.<br>
&gt; &gt;<br>
&gt; &gt;<br>
&gt; &gt; After upgrade when I try to run any of the 2 powered down machines, I get<br>
&gt; &gt; &quot;VM ......... is down. Exit message: &#39;int&#39; object has no attribute<br>
&gt; &gt; &#39;replace&#39;.&quot;<br>
&gt;<br>
&gt; Would you share an excerpt of your /var/log/vdsm/vdsm.log? Most<br>
&gt; interesting is the backtrace ending up with this error message.<br>
&gt;<br>
&gt;<br>
</div></div>&gt; _______________________________________________<br>
&gt; Users mailing list<br>
&gt; <a href="mailto:Users@ovirt.org">Users@ovirt.org</a><br>
&gt; <a href="http://lists.ovirt.org/mailman/listinfo/users" target="_blank">http://lists.ovirt.org/mailman/listinfo/users</a><br>
&gt;<br>
</blockquote></div><br></div>