Attachment 680225 Details for Bug 896554 – Engine log

Engine log

engine.log (text/x-log), 82.88 KB, created by Jakub Libosvar on 2013-01-17 14:26:31 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Jakub Libosvar

Created: 2013-01-17 14:26:31 UTC

Size: 82.88 KB

patch

obsolete

>2013-01-17 15:11:34,615 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8702-17) [35ac7373] START, IsVmDuringInitiatingVDSCommand( vmId = 4851cfc8-aee0-4ac5-8ec4-988f4175ea6f), log id: 1de32156
>2013-01-17 15:11:34,615 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8702-17) [35ac7373] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 1de32156
>2013-01-17 15:11:34,617 INFO  [org.ovirt.engine.core.bll.VdsSelector] (ajp-/127.0.0.1:8702-17) [35ac7373] Checking for a specific VDS only - id:11650eb9-c053-4ceb-b017-7d5821e0153d, name:10.34.65.211, host_name(ip):10.34.65.211
>2013-01-17 15:11:34,655 INFO  [org.ovirt.engine.core.bll.RunVmOnceCommand] (ajp-/127.0.0.1:8702-17) [35ac7373] Running command: RunVmOnceCommand internal: false. Entities affected :  ID: 4851cfc8-aee0-4ac5-8ec4-988f4175ea6f Type: VM
>2013-01-17 15:11:34,691 INFO  [org.ovirt.engine.core.bll.VdsSelector] (ajp-/127.0.0.1:8702-17) [35ac7373] Checking for a specific VDS only - id:11650eb9-c053-4ceb-b017-7d5821e0153d, name:10.34.65.211, host_name(ip):10.34.65.211
>2013-01-17 15:11:34,702 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (ajp-/127.0.0.1:8702-17) [35ac7373] START, CreateVmVDSCommand(HostName = 10.34.65.211, HostId = 11650eb9-c053-4ceb-b017-7d5821e0153d, vmId=4851cfc8-aee0-4ac5-8ec4-988f4175ea6f, vm=org.ovirt.engine.core.common.businessentities.VM@21407df6), log id: 2a7e14a5
>2013-01-17 15:11:34,705 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (ajp-/127.0.0.1:8702-17) [35ac7373] START, CreateVDSCommand(HostName = 10.34.65.211, HostId = 11650eb9-c053-4ceb-b017-7d5821e0153d, vmId=4851cfc8-aee0-4ac5-8ec4-988f4175ea6f, vm=org.ovirt.engine.core.common.businessentities.VM@21407df6), log id: 658ddace
>2013-01-17 15:11:34,818 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-44) VM vm_ide_raw_hsm_2 3f3a52d6-aa51-4ef0-8039-f246ac819e09 moved from WaitForLaunch --> PoweringUp
>2013-01-17 15:11:34,819 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (QuartzScheduler_Worker-44) START, FullListVdsCommand( HostId = da258110-fb54-4705-9122-908f8c0b36b8, vds=null, vmIds=[3f3a52d6-aa51-4ef0-8039-f246ac819e09]), log id: 5bd0ed95
>2013-01-17 15:11:34,825 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (ajp-/127.0.0.1:8702-17) [35ac7373] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand memSize=1024,kvmEnable=true,smp=1,emulatedMachine=rhel6.3.0,vmType=kvm,keyboardLayout=en-us,pitReinjection=false,nice=0,display=qxl,smartcardEnable=false,smpCoresPerSocket=1,timeOffset=-1,transparentHugePages=true,vmId=4851cfc8-aee0-4ac5-8ec4-988f4175ea6f,devices=[Ljava.util.Map;@719e004a,acpiEnable=true,vmName=vm_virtio_cow_spm_2,cpuType=Conroe,custom={device_92f448aa-9a0a-4608-92d0-cca4f93776f6device_40af63f3-f631-46bf-b017-dafc0dc4937bdevice_02b91290-795d-41f1-b4fe-ed40a73d9640=VmDevice {vmId=4851cfc8-aee0-4ac5-8ec4-988f4175ea6f, deviceId=02b91290-795d-41f1-b4fe-ed40a73d9640, 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}, device_92f448aa-9a0a-4608-92d0-cca4f93776f6device_40af63f3-f631-46bf-b017-dafc0dc4937bdevice_02b91290-795d-41f1-b4fe-ed40a73d9640device_b59d5779-8476-47a8-a2f6-9cb34171112fdevice_dd0cff16-105f-492e-944d-a6fd7b950675device_f92df0f3-256b-43d2-86bf-10a54b6516bb=VmDevice {vmId=4851cfc8-aee0-4ac5-8ec4-988f4175ea6f, deviceId=f92df0f3-256b-43d2-86bf-10a54b6516bb, 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}, device_92f448aa-9a0a-4608-92d0-cca4f93776f6=VmDevice {vmId=4851cfc8-aee0-4ac5-8ec4-988f4175ea6f, deviceId=92f448aa-9a0a-4608-92d0-cca4f93776f6, device=memballoon, type=balloon, bootOrder=0, specParams={model=virtio}, address={bus=0x00, domain=0x0000, type=pci, slot=0x06, function=0x0}, managed=false, plugged=true, readOnly=false, deviceAlias=balloon0}, device_92f448aa-9a0a-4608-92d0-cca4f93776f6device_40af63f3-f631-46bf-b017-dafc0dc4937bdevice_02b91290-795d-41f1-b4fe-ed40a73d9640device_b59d5779-8476-47a8-a2f6-9cb34171112fdevice_dd0cff16-105f-492e-944d-a6fd7b950675=VmDevice {vmId=4851cfc8-aee0-4ac5-8ec4-988f4175ea6f, deviceId=dd0cff16-105f-492e-944d-a6fd7b950675, 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}, device_92f448aa-9a0a-4608-92d0-cca4f93776f6device_40af63f3-f631-46bf-b017-dafc0dc4937bdevice_02b91290-795d-41f1-b4fe-ed40a73d9640device_b59d5779-8476-47a8-a2f6-9cb34171112f=VmDevice {vmId=4851cfc8-aee0-4ac5-8ec4-988f4175ea6f, deviceId=b59d5779-8476-47a8-a2f6-9cb34171112f, 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}, device_92f448aa-9a0a-4608-92d0-cca4f93776f6device_40af63f3-f631-46bf-b017-dafc0dc4937b=VmDevice {vmId=4851cfc8-aee0-4ac5-8ec4-988f4175ea6f, deviceId=40af63f3-f631-46bf-b017-dafc0dc4937b, 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}}
>2013-01-17 15:11:34,826 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (ajp-/127.0.0.1:8702-17) [35ac7373] FINISH, CreateVDSCommand, log id: 658ddace
>2013-01-17 15:11:34,827 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (QuartzScheduler_Worker-44) FINISH, FullListVdsCommand, return: [Lorg.ovirt.engine.core.vdsbroker.xmlrpc.XmlRpcStruct;@385dd07e, log id: 5bd0ed95
>2013-01-17 15:11:34,847 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (ajp-/127.0.0.1:8702-17) [35ac7373] IncreasePendingVms::CreateVmIncreasing vds 10.34.65.211 pending vcpu count, now 3. Vm: vm_virtio_cow_spm_2
>2013-01-17 15:11:34,849 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (ajp-/127.0.0.1:8702-17) [35ac7373] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 2a7e14a5
>2013-01-17 15:11:34,901 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (QuartzScheduler_Worker-44) START, UpdateVdsDynamicDataVDSCommand(HostName = 10.34.63.135, HostId = da258110-fb54-4705-9122-908f8c0b36b8, vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@e3b1874b), log id: 4207a567
>2013-01-17 15:11:34,914 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (QuartzScheduler_Worker-44) FINISH, UpdateVdsDynamicDataVDSCommand, log id: 4207a567
>2013-01-17 15:11:34,933 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-11) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:34,933 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-11) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:34,963 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-11) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:35,559 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (QuartzScheduler_Worker-36) START, FullListVdsCommand( HostId = 11650eb9-c053-4ceb-b017-7d5821e0153d, vds=null, vmIds=[dee1911f-3486-4c6b-a174-d06b7b95ee07, 3b44efee-eb92-473e-8f0f-633ca732314e, 4851cfc8-aee0-4ac5-8ec4-988f4175ea6f]), log id: 5c228cce
>2013-01-17 15:11:35,570 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (QuartzScheduler_Worker-36) FINISH, FullListVdsCommand, return: [Lorg.ovirt.engine.core.vdsbroker.xmlrpc.XmlRpcStruct;@604a57a3, log id: 5c228cce
>2013-01-17 15:11:35,581 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-36) Recieved a memballoon Device without an address when processing VM 3b44efee-eb92-473e-8f0f-633ca732314e devices, skipping device: {specParams={model=virtio}, target=1048576, device=memballoon, type=balloon, deviceId=772221e8-651f-47ce-9c91-15a8d9d20abd}
>2013-01-17 15:11:35,581 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-36) Recieved a disk Device without an address when processing VM 3b44efee-eb92-473e-8f0f-633ca732314e devices, skipping device: {shared=false, index=1, volumeID=455e2bca-4802-46d3-85d7-ad6ce89293ce, propagateErrors=off, format=cow, type=disk, truesize=1073741824, reqsize=0, iface=ide, volumeChain=[Ljava.lang.Object;@301c45e6, imageID=fa73f129-992f-4d2c-a5db-8a421cbe9bc2, domainID=d2a1c0cc-3c82-43b7-89d3-ca8bf4be3b91, specParams={}, optional=false, path=/rhev/data-center/d093d101-0427-45fc-80df-e15a57621226/d2a1c0cc-3c82-43b7-89d3-ca8bf4be3b91/images/fa73f129-992f-4d2c-a5db-8a421cbe9bc2/455e2bca-4802-46d3-85d7-ad6ce89293ce, device=disk, poolID=d093d101-0427-45fc-80df-e15a57621226, readonly=false, apparentsize=1073741824, deviceId=fa73f129-992f-4d2c-a5db-8a421cbe9bc2}
>2013-01-17 15:11:35,587 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-36) Recieved a disk Device without an address when processing VM 4851cfc8-aee0-4ac5-8ec4-988f4175ea6f devices, skipping device: {shared=false, index=1, volumeID=5b2e85a7-ae55-4fa8-9aff-a433d755b9c2, propagateErrors=off, format=cow, type=disk, truesize=1073741824, reqsize=0, iface=virtio, imageID=9e346280-16a7-42a2-ab76-2bf9fea98229, domainID=d2a1c0cc-3c82-43b7-89d3-ca8bf4be3b91, specParams={}, optional=false, device=disk, poolID=d093d101-0427-45fc-80df-e15a57621226, readonly=false, apparentsize=1073741824, deviceId=9e346280-16a7-42a2-ab76-2bf9fea98229}
>2013-01-17 15:11:35,587 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-36) Recieved a memballoon Device without an address when processing VM 4851cfc8-aee0-4ac5-8ec4-988f4175ea6f devices, skipping device: {specParams={model=virtio}, target=1048576, device=memballoon, type=balloon, deviceId=4c0d3493-d6aa-4329-8210-29d51e8fc066}
>2013-01-17 15:11:36,584 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-8) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:36,584 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-8) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:36,638 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-8) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:36,720 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-14) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:36,720 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-14) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:36,746 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-14) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:36,892 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-5) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:36,893 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-5) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:36,930 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-5) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:37,079 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-15) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:37,079 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-15) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:37,112 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-15) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:37,315 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8702-15) [7926811f] START, IsVmDuringInitiatingVDSCommand( vmId = f8003131-02ba-49a8-94ea-934c3aa63e63), log id: 5d565dbc
>2013-01-17 15:11:37,315 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8702-15) [7926811f] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 5d565dbc
>2013-01-17 15:11:37,317 INFO  [org.ovirt.engine.core.bll.VdsSelector] (ajp-/127.0.0.1:8702-15) [7926811f] Checking for a specific VDS only - id:11650eb9-c053-4ceb-b017-7d5821e0153d, name:10.34.65.211, host_name(ip):10.34.65.211
>2013-01-17 15:11:37,353 INFO  [org.ovirt.engine.core.bll.RunVmOnceCommand] (ajp-/127.0.0.1:8702-15) [7926811f] Running command: RunVmOnceCommand internal: false. Entities affected :  ID: f8003131-02ba-49a8-94ea-934c3aa63e63 Type: VM
>2013-01-17 15:11:37,387 INFO  [org.ovirt.engine.core.bll.VdsSelector] (ajp-/127.0.0.1:8702-15) [7926811f] Checking for a specific VDS only - id:11650eb9-c053-4ceb-b017-7d5821e0153d, name:10.34.65.211, host_name(ip):10.34.65.211
>2013-01-17 15:11:37,399 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (ajp-/127.0.0.1:8702-15) [7926811f] START, CreateVmVDSCommand(HostName = 10.34.65.211, HostId = 11650eb9-c053-4ceb-b017-7d5821e0153d, vmId=f8003131-02ba-49a8-94ea-934c3aa63e63, vm=org.ovirt.engine.core.common.businessentities.VM@5d58dcf8), log id: 11626b84
>2013-01-17 15:11:37,405 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (ajp-/127.0.0.1:8702-15) [7926811f] START, CreateVDSCommand(HostName = 10.34.65.211, HostId = 11650eb9-c053-4ceb-b017-7d5821e0153d, vmId=f8003131-02ba-49a8-94ea-934c3aa63e63, vm=org.ovirt.engine.core.common.businessentities.VM@5d58dcf8), log id: 728bd14
>2013-01-17 15:11:37,463 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (ajp-/127.0.0.1:8702-15) [7926811f] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand memSize=1024,kvmEnable=true,smp=1,emulatedMachine=rhel6.3.0,vmType=kvm,keyboardLayout=en-us,pitReinjection=false,nice=0,display=qxl,smartcardEnable=false,smpCoresPerSocket=1,timeOffset=-1,transparentHugePages=true,vmId=f8003131-02ba-49a8-94ea-934c3aa63e63,devices=[Ljava.util.Map;@f29ecaf,acpiEnable=true,vmName=vm_ide_raw_spm_2,cpuType=Conroe,custom={device_15e7bb53-b460-4eda-86f4-4e26f38d0385device_3d973e4a-fd69-4b58-8274-ee906db0c58b=VmDevice {vmId=f8003131-02ba-49a8-94ea-934c3aa63e63, deviceId=3d973e4a-fd69-4b58-8274-ee906db0c58b, 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}, device_15e7bb53-b460-4eda-86f4-4e26f38d0385device_3d973e4a-fd69-4b58-8274-ee906db0c58bdevice_5151ebb4-0ed8-4b1d-a042-10b62d15aaa0device_5ba85685-b2de-4c11-951c-2b026ce5c100device_5f7e405e-675a-46a1-b837-0d873c6ca61bdevice_9f02d4f6-bad5-474f-9cb8-5b711dbd4d49=VmDevice {vmId=f8003131-02ba-49a8-94ea-934c3aa63e63, deviceId=9f02d4f6-bad5-474f-9cb8-5b711dbd4d49, 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}, device_15e7bb53-b460-4eda-86f4-4e26f38d0385device_3d973e4a-fd69-4b58-8274-ee906db0c58bdevice_5151ebb4-0ed8-4b1d-a042-10b62d15aaa0device_5ba85685-b2de-4c11-951c-2b026ce5c100device_5f7e405e-675a-46a1-b837-0d873c6ca61b=VmDevice {vmId=f8003131-02ba-49a8-94ea-934c3aa63e63, deviceId=5f7e405e-675a-46a1-b837-0d873c6ca61b, 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}, device_15e7bb53-b460-4eda-86f4-4e26f38d0385device_3d973e4a-fd69-4b58-8274-ee906db0c58bdevice_5151ebb4-0ed8-4b1d-a042-10b62d15aaa0=VmDevice {vmId=f8003131-02ba-49a8-94ea-934c3aa63e63, deviceId=5151ebb4-0ed8-4b1d-a042-10b62d15aaa0, device=memballoon, type=balloon, bootOrder=0, specParams={model=virtio}, address={bus=0x00, domain=0x0000, type=pci, slot=0x05, function=0x0}, managed=false, plugged=true, readOnly=false, deviceAlias=balloon0}, device_15e7bb53-b460-4eda-86f4-4e26f38d0385device_3d973e4a-fd69-4b58-8274-ee906db0c58bdevice_5151ebb4-0ed8-4b1d-a042-10b62d15aaa0device_5ba85685-b2de-4c11-951c-2b026ce5c100=VmDevice {vmId=f8003131-02ba-49a8-94ea-934c3aa63e63, deviceId=5ba85685-b2de-4c11-951c-2b026ce5c100, 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}, device_15e7bb53-b460-4eda-86f4-4e26f38d0385=VmDevice {vmId=f8003131-02ba-49a8-94ea-934c3aa63e63, deviceId=15e7bb53-b460-4eda-86f4-4e26f38d0385, 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}}
>2013-01-17 15:11:37,463 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (ajp-/127.0.0.1:8702-15) [7926811f] FINISH, CreateVDSCommand, log id: 728bd14
>2013-01-17 15:11:37,479 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (ajp-/127.0.0.1:8702-15) [7926811f] IncreasePendingVms::CreateVmIncreasing vds 10.34.65.211 pending vcpu count, now 4. Vm: vm_ide_raw_spm_2
>2013-01-17 15:11:37,481 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (ajp-/127.0.0.1:8702-15) [7926811f] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 11626b84
>2013-01-17 15:11:37,550 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-19) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:37,550 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-19) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:37,587 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-19) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:37,796 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-53) VM vm_virtio_raw_spm_2 dee1911f-3486-4c6b-a174-d06b7b95ee07 moved from WaitForLaunch --> PoweringUp
>2013-01-17 15:11:37,796 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-53) VM vm_ide_cow_spm_2 3b44efee-eb92-473e-8f0f-633ca732314e moved from WaitForLaunch --> PoweringUp
>2013-01-17 15:11:37,796 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (QuartzScheduler_Worker-53) START, FullListVdsCommand( HostId = 11650eb9-c053-4ceb-b017-7d5821e0153d, vds=null, vmIds=[3b44efee-eb92-473e-8f0f-633ca732314e]), log id: 554a612b
>2013-01-17 15:11:37,803 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (QuartzScheduler_Worker-53) FINISH, FullListVdsCommand, return: [Lorg.ovirt.engine.core.vdsbroker.xmlrpc.XmlRpcStruct;@63179cc, log id: 554a612b
>2013-01-17 15:11:37,866 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (QuartzScheduler_Worker-53) START, UpdateVdsDynamicDataVDSCommand(HostName = 10.34.65.211, HostId = 11650eb9-c053-4ceb-b017-7d5821e0153d, vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@aada4093), log id: 595f3cda
>2013-01-17 15:11:37,879 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (QuartzScheduler_Worker-53) FINISH, UpdateVdsDynamicDataVDSCommand, log id: 595f3cda
>2013-01-17 15:11:37,888 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (QuartzScheduler_Worker-53) START, UpdateVdsDynamicDataVDSCommand(HostName = 10.34.65.211, HostId = 11650eb9-c053-4ceb-b017-7d5821e0153d, vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@c07e81f4), log id: 3765d970
>2013-01-17 15:11:37,904 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (QuartzScheduler_Worker-53) FINISH, UpdateVdsDynamicDataVDSCommand, log id: 3765d970
>2013-01-17 15:11:38,871 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-7) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:38,871 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-7) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:38,920 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-7) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:39,240 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-6) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:39,240 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-6) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:39,270 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-6) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:39,270 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-6) calling GetConfigurationValueQuery (VdcVersion) with null version, using default general for version
>2013-01-17 15:11:39,931 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-42) VM vm_virtio_cow_spm_2 4851cfc8-aee0-4ac5-8ec4-988f4175ea6f moved from WaitForLaunch --> PoweringUp
>2013-01-17 15:11:39,931 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (QuartzScheduler_Worker-42) START, FullListVdsCommand( HostId = 11650eb9-c053-4ceb-b017-7d5821e0153d, vds=null, vmIds=[4851cfc8-aee0-4ac5-8ec4-988f4175ea6f]), log id: 39d61f6a
>2013-01-17 15:11:39,937 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (QuartzScheduler_Worker-42) FINISH, FullListVdsCommand, return: [Lorg.ovirt.engine.core.vdsbroker.xmlrpc.XmlRpcStruct;@18a885d0, log id: 39d61f6a
>2013-01-17 15:11:40,010 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (QuartzScheduler_Worker-42) START, UpdateVdsDynamicDataVDSCommand(HostName = 10.34.65.211, HostId = 11650eb9-c053-4ceb-b017-7d5821e0153d, vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@d622c355), log id: 3610c007
>2013-01-17 15:11:40,020 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (QuartzScheduler_Worker-42) FINISH, UpdateVdsDynamicDataVDSCommand, log id: 3610c007
>2013-01-17 15:11:41,163 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-52) VM vm_virtio_cow_hsm_2 b293b39c-3f1f-45f5-b43d-65c147a73589 moved from WaitForLaunch --> PoweringUp
>2013-01-17 15:11:41,166 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-52) VM vm_ide_cow_hsm_2 16be496c-2819-4a0b-aac6-6253f8cad724 moved from WaitForLaunch --> PoweringUp
>2013-01-17 15:11:41,175 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (QuartzScheduler_Worker-52) START, FullListVdsCommand( HostId = da258110-fb54-4705-9122-908f8c0b36b8, vds=null, vmIds=[b293b39c-3f1f-45f5-b43d-65c147a73589, 16be496c-2819-4a0b-aac6-6253f8cad724]), log id: 309f9c62
>2013-01-17 15:11:41,185 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (QuartzScheduler_Worker-52) FINISH, FullListVdsCommand, return: [Lorg.ovirt.engine.core.vdsbroker.xmlrpc.XmlRpcStruct;@3ed3fede, log id: 309f9c62
>2013-01-17 15:11:41,408 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (QuartzScheduler_Worker-52) START, UpdateVdsDynamicDataVDSCommand(HostName = 10.34.63.135, HostId = da258110-fb54-4705-9122-908f8c0b36b8, vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@cec3fcc9), log id: 27ec9943
>2013-01-17 15:11:41,428 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (QuartzScheduler_Worker-52) FINISH, UpdateVdsDynamicDataVDSCommand, log id: 27ec9943
>2013-01-17 15:11:41,432 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (QuartzScheduler_Worker-52) START, UpdateVdsDynamicDataVDSCommand(HostName = 10.34.63.135, HostId = da258110-fb54-4705-9122-908f8c0b36b8, vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@e4683e2a), log id: 4058c0f9
>2013-01-17 15:11:41,447 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (QuartzScheduler_Worker-52) FINISH, UpdateVdsDynamicDataVDSCommand, log id: 4058c0f9
>2013-01-17 15:11:42,053 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-58) VM vm_ide_raw_spm_2 f8003131-02ba-49a8-94ea-934c3aa63e63 moved from WaitForLaunch --> PoweringUp
>2013-01-17 15:11:42,054 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (QuartzScheduler_Worker-58) START, FullListVdsCommand( HostId = 11650eb9-c053-4ceb-b017-7d5821e0153d, vds=null, vmIds=[f8003131-02ba-49a8-94ea-934c3aa63e63]), log id: 1c2accc5
>2013-01-17 15:11:42,108 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (QuartzScheduler_Worker-58) FINISH, FullListVdsCommand, return: [Lorg.ovirt.engine.core.vdsbroker.xmlrpc.XmlRpcStruct;@20de46ec, log id: 1c2accc5
>2013-01-17 15:11:42,193 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (QuartzScheduler_Worker-58) START, UpdateVdsDynamicDataVDSCommand(HostName = 10.34.65.211, HostId = 11650eb9-c053-4ceb-b017-7d5821e0153d, vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@ebc704b6), log id: 4071db2e
>2013-01-17 15:11:42,206 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (QuartzScheduler_Worker-58) FINISH, UpdateVdsDynamicDataVDSCommand, log id: 4071db2e
>2013-01-17 15:11:42,555 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-4) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:42,555 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-4) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:42,597 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-4) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:43,150 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-21) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:43,150 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-21) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:43,206 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-21) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:43,761 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-10) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:43,762 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-10) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:43,796 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-10) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:43,861 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-18) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:43,861 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-18) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:43,921 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-18) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:43,996 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-2) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:43,996 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-2) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:43,996 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-9) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:43,996 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-9) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:44,031 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-2) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:44,045 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-9) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:45,046 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-11) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:45,046 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-11) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:45,079 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-11) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:47,626 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-60) VM vm_virtio_raw_hsm_2 355f4a13-e764-41ae-b56d-54ea934bbbfa moved from PoweringUp --> Up
>2013-01-17 15:11:47,675 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-8) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:47,675 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-8) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:47,711 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-8) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:49,018 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-14) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:49,018 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-14) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:49,078 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-14) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:52,762 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-15) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:52,763 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-15) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:52,801 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-15) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:53,294 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-19) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:53,295 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-19) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:53,385 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-19) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:53,902 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-7) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:53,903 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-7) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:53,967 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-7) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:54,147 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-12) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:54,148 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-12) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:54,171 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-6) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:54,171 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-6) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:54,192 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-12) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:54,226 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-6) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:54,291 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-13) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:54,291 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-13) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:54,326 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-13) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:55,165 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-10) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:55,165 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-10) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:55,191 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-10) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:57,805 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-18) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:57,805 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-18) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:57,865 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-18) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:11:59,238 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-2) Checking if user admin@internal is an admin, result true
>2013-01-17 15:11:59,238 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-2) Running command: LoginUserCommand internal: false.
>2013-01-17 15:11:59,266 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-2) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:00,163 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-79) VM vm_virtio_cow_hsm_2 b293b39c-3f1f-45f5-b43d-65c147a73589 moved from PoweringUp --> Up
>2013-01-17 15:12:03,140 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-39) VM vm_virtio_raw_spm_2 dee1911f-3486-4c6b-a174-d06b7b95ee07 moved from PoweringUp --> Up
>2013-01-17 15:12:03,590 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-16) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:03,590 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-16) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:03,630 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-16) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:04,142 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-11) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:04,142 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-11) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:04,189 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-11) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:04,356 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-8) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:04,358 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-8) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:04,397 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-8) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:04,482 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-3) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:04,483 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-3) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:04,513 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-3) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:04,566 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-5) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:04,566 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-5) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:04,596 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-5) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:05,339 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-15) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:05,340 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-15) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:05,371 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-15) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:07,345 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-89) VM vm_virtio_cow_spm_2 4851cfc8-aee0-4ac5-8ec4-988f4175ea6f moved from PoweringUp --> Up
>2013-01-17 15:12:08,030 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-19) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:08,030 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-19) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:08,062 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-19) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:09,414 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-12) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:09,414 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-12) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:09,454 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-12) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:13,779 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-1) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:13,779 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-1) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:13,817 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-8) Setting new tasks map. The map contains now 6 tasks
>2013-01-17 15:12:13,828 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-1) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:14,776 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-2) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:14,776 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-2) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:14,819 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-2) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:15,529 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-14) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:15,529 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-14) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:15,560 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-14) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:18,211 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-17) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:18,211 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-17) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:18,251 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-17) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:19,623 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-5) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:19,624 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-5) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:19,676 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-5) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:23,985 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-15) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:23,986 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-15) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:24,096 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-15) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:24,989 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-6) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:24,989 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-6) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:25,038 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-6) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:25,301 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-22) VM vm_ide_raw_hsm_2 3f3a52d6-aa51-4ef0-8039-f246ac819e09 moved from PoweringUp --> Up
>2013-01-17 15:12:27,399 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-99) VM vm_ide_cow_hsm_2 16be496c-2819-4a0b-aac6-6253f8cad724 moved from PoweringUp --> Up
>2013-01-17 15:12:28,407 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-12) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:28,408 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-12) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:28,439 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-12) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:29,858 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-9) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:29,859 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-9) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:29,895 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-9) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:30,404 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-85) VM vm_ide_cow_spm_2 3b44efee-eb92-473e-8f0f-633ca732314e moved from PoweringUp --> Up
>2013-01-17 15:12:34,258 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-18) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:34,258 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-18) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:34,301 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-18) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:34,539 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-21) VM vm_ide_raw_spm_2 f8003131-02ba-49a8-94ea-934c3aa63e63 moved from PoweringUp --> Up
>2013-01-17 15:12:35,189 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-1) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:35,189 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-1) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:35,217 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-1) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:38,602 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-2) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:38,602 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-2) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:38,715 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-2) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:38,997 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-16) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:38,997 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-16) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:39,032 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-16) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:39,260 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-11) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:39,260 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-11) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:39,357 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-11) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:39,357 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-11) calling GetConfigurationValueQuery (VdcVersion) with null version, using default general for version
>2013-01-17 15:12:39,589 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-8) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:39,590 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-8) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:39,623 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-8) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:39,773 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-3) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:39,774 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-3) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:39,823 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-3) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:39,967 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-5) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:39,967 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-5) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:40,007 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-5) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:40,139 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-15) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:40,140 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-15) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:40,172 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-15) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:40,305 WARN  [org.ovirt.engine.core.bll.MoveVmCommand] (ajp-/127.0.0.1:8702-15) [1345de24] CanDoAction of action MoveVm failed. Reasons:VAR__ACTION__MOVE,VAR__TYPE__VM,ACTION_TYPE_FAILED_VM_IS_NOT_DOWN
>2013-01-17 15:12:40,323 ERROR [org.ovirt.engine.api.restapi.resource.AbstractBackendResource] (ajp-/127.0.0.1:8702-15) Operation Failed: [Cannot move VM. At least one of the VMs is not down.]
>2013-01-17 15:12:40,599 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-19) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:40,599 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-19) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:40,648 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-19) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:40,817 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-7) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:40,817 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-7) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:41,508 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-7) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:41,667 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-6) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:41,667 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-6) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:41,736 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-6) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:41,813 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (ajp-/127.0.0.1:8702-6) [6b13d6ef] Running command: ShutdownVmCommand internal: false. Entities affected :  ID: 5b49f3bc-0dcd-4c20-8136-f34c53855ba8 Type: VM
>2013-01-17 15:12:41,815 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (ajp-/127.0.0.1:8702-6) [6b13d6ef] VmHandler.cs:ShutdownVmCommand:Perform: entered (Desktop vm_virtio_cow_spm_1).
>2013-01-17 15:12:41,815 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (ajp-/127.0.0.1:8702-6) [6b13d6ef] VmHandler.cs:ShutdownVmCommand:Perform: sending shutdown command for Desktop vm_virtio_cow_spm_1.
>2013-01-17 15:12:41,816 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (ajp-/127.0.0.1:8702-6) [6b13d6ef] START, DestroyVmVDSCommand(HostName = 10.34.65.211, HostId = 11650eb9-c053-4ceb-b017-7d5821e0153d, vmId=5b49f3bc-0dcd-4c20-8136-f34c53855ba8, force=false, secondsToWait=30, gracefully=true), log id: 7e34e76e
>2013-01-17 15:12:41,822 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (ajp-/127.0.0.1:8702-6) [6b13d6ef] START, DestroyVDSCommand(HostName = 10.34.65.211, HostId = 11650eb9-c053-4ceb-b017-7d5821e0153d, vmId=5b49f3bc-0dcd-4c20-8136-f34c53855ba8, force=false, secondsToWait=30, gracefully=true), log id: 681cc4d9
>2013-01-17 15:12:41,825 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (ajp-/127.0.0.1:8702-6) [6b13d6ef] FINISH, DestroyVDSCommand, log id: 681cc4d9
>2013-01-17 15:12:41,847 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (ajp-/127.0.0.1:8702-6) [6b13d6ef] FINISH, DestroyVmVDSCommand, return: PoweringDown, log id: 7e34e76e
>2013-01-17 15:12:41,922 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-12) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:41,923 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-12) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:41,963 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-12) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:12:43,817 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-37) Setting new tasks map. The map contains now 0 tasks
>2013-01-17 15:12:43,817 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-37) Cleared all tasks of pool d093d101-0427-45fc-80df-e15a57621226.
>2013-01-17 15:12:43,820 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-37) Could not find vds that is spm and non-operational.
>2013-01-17 15:12:52,058 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-21) Checking if user admin@internal is an admin, result true
>2013-01-17 15:12:52,058 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-21) Running command: LoginUserCommand internal: false.
>2013-01-17 15:12:52,093 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-21) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:13:02,183 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-14) Checking if user admin@internal is an admin, result true
>2013-01-17 15:13:02,184 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-14) Running command: LoginUserCommand internal: false.
>2013-01-17 15:13:02,251 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-14) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:13:12,386 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-10) Checking if user admin@internal is an admin, result true
>2013-01-17 15:13:12,387 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-10) Running command: LoginUserCommand internal: false.
>2013-01-17 15:13:12,420 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-10) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:13:22,496 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-18) Checking if user admin@internal is an admin, result true
>2013-01-17 15:13:22,496 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-18) Running command: LoginUserCommand internal: false.
>2013-01-17 15:13:22,554 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-18) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:13:32,658 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-9) Checking if user admin@internal is an admin, result true
>2013-01-17 15:13:32,658 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-9) Running command: LoginUserCommand internal: false.
>2013-01-17 15:13:32,696 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-9) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:13:33,487 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (QuartzScheduler_Worker-83) START, DestroyVDSCommand(HostName = 10.34.65.211, HostId = 11650eb9-c053-4ceb-b017-7d5821e0153d, vmId=5b49f3bc-0dcd-4c20-8136-f34c53855ba8, force=false, secondsToWait=0, gracefully=false), log id: 5be729ad
>2013-01-17 15:13:33,503 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (QuartzScheduler_Worker-83) FINISH, DestroyVDSCommand, log id: 5be729ad
>2013-01-17 15:13:33,561 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-83) vm vm_virtio_cow_spm_1 running in db and not running in vds - add to rerun treatment. vds 10.34.65.211
>2013-01-17 15:13:39,260 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-8) Checking if user admin@internal is an admin, result true
>2013-01-17 15:13:39,260 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-8) Running command: LoginUserCommand internal: false.
>2013-01-17 15:13:39,307 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-8) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:13:39,307 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-8) calling GetConfigurationValueQuery (VdcVersion) with null version, using default general for version
>2013-01-17 15:13:42,782 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-12) Checking if user admin@internal is an admin, result true
>2013-01-17 15:13:42,782 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-12) Running command: LoginUserCommand internal: false.
>2013-01-17 15:13:42,880 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-12) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:13:42,964 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-6) Checking if user admin@internal is an admin, result true
>2013-01-17 15:13:42,964 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-6) Running command: LoginUserCommand internal: false.
>2013-01-17 15:13:42,996 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-6) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:13:43,148 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-9) Checking if user admin@internal is an admin, result true
>2013-01-17 15:13:43,149 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-9) Running command: LoginUserCommand internal: false.
>2013-01-17 15:13:43,213 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-9) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:13:43,365 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-4) Checking if user admin@internal is an admin, result true
>2013-01-17 15:13:43,365 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-4) Running command: LoginUserCommand internal: false.
>2013-01-17 15:13:43,404 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-4) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:13:43,521 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (ajp-/127.0.0.1:8702-4) [24f3ad3e] Running command: ShutdownVmCommand internal: false. Entities affected :  ID: cb283e44-8e29-440e-af59-7ea619c6fa71 Type: VM
>2013-01-17 15:13:43,523 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (ajp-/127.0.0.1:8702-4) [24f3ad3e] VmHandler.cs:ShutdownVmCommand:Perform: entered (Desktop vm_virtio_raw_spm_1).
>2013-01-17 15:13:43,524 INFO  [org.ovirt.engine.core.bll.ShutdownVmCommand] (ajp-/127.0.0.1:8702-4) [24f3ad3e] VmHandler.cs:ShutdownVmCommand:Perform: sending shutdown command for Desktop vm_virtio_raw_spm_1.
>2013-01-17 15:13:43,526 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (ajp-/127.0.0.1:8702-4) [24f3ad3e] START, DestroyVmVDSCommand(HostName = 10.34.65.211, HostId = 11650eb9-c053-4ceb-b017-7d5821e0153d, vmId=cb283e44-8e29-440e-af59-7ea619c6fa71, force=false, secondsToWait=30, gracefully=true), log id: caf0a03
>2013-01-17 15:13:43,531 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (ajp-/127.0.0.1:8702-4) [24f3ad3e] START, DestroyVDSCommand(HostName = 10.34.65.211, HostId = 11650eb9-c053-4ceb-b017-7d5821e0153d, vmId=cb283e44-8e29-440e-af59-7ea619c6fa71, force=false, secondsToWait=30, gracefully=true), log id: 4d8af86e
>2013-01-17 15:13:43,536 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (ajp-/127.0.0.1:8702-4) [24f3ad3e] FINISH, DestroyVDSCommand, log id: 4d8af86e
>2013-01-17 15:13:43,554 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (ajp-/127.0.0.1:8702-4) [24f3ad3e] FINISH, DestroyVmVDSCommand, return: PoweringDown, log id: caf0a03
>2013-01-17 15:13:43,636 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-21) Checking if user admin@internal is an admin, result true
>2013-01-17 15:13:43,637 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-21) Running command: LoginUserCommand internal: false.
>2013-01-17 15:13:43,679 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-21) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:13:53,765 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-6) Checking if user admin@internal is an admin, result true
>2013-01-17 15:13:53,765 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-6) Running command: LoginUserCommand internal: false.
>2013-01-17 15:13:53,833 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-6) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:14:03,933 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-10) Checking if user admin@internal is an admin, result true
>2013-01-17 15:14:03,934 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-10) Running command: LoginUserCommand internal: false.
>2013-01-17 15:14:03,974 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-10) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:14:14,159 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-18) Checking if user admin@internal is an admin, result true
>2013-01-17 15:14:14,160 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-18) Running command: LoginUserCommand internal: false.
>2013-01-17 15:14:14,187 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-18) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:14:24,294 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-2) Checking if user admin@internal is an admin, result true
>2013-01-17 15:14:24,294 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-2) Running command: LoginUserCommand internal: false.
>2013-01-17 15:14:24,326 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-2) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:14:33,811 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (QuartzScheduler_Worker-38) START, DestroyVDSCommand(HostName = 10.34.65.211, HostId = 11650eb9-c053-4ceb-b017-7d5821e0153d, vmId=cb283e44-8e29-440e-af59-7ea619c6fa71, force=false, secondsToWait=0, gracefully=false), log id: 291f92ad
>2013-01-17 15:14:33,815 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (QuartzScheduler_Worker-38) FINISH, DestroyVDSCommand, log id: 291f92ad
>2013-01-17 15:14:33,845 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-38) vm vm_virtio_raw_spm_1 running in db and not running in vds - add to rerun treatment. vds 10.34.65.211
>2013-01-17 15:14:34,414 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-17) Checking if user admin@internal is an admin, result true
>2013-01-17 15:14:34,415 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-17) Running command: LoginUserCommand internal: false.
>2013-01-17 15:14:34,488 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-17) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:14:34,581 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-8) Checking if user admin@internal is an admin, result true
>2013-01-17 15:14:34,581 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-8) Running command: LoginUserCommand internal: false.
>2013-01-17 15:14:34,613 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-8) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:14:34,762 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-7) Checking if user admin@internal is an admin, result true
>2013-01-17 15:14:34,762 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-7) Running command: LoginUserCommand internal: false.
>2013-01-17 15:14:34,804 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-7) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:14:34,970 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-10) Checking if user admin@internal is an admin, result true
>2013-01-17 15:14:34,971 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-10) Running command: LoginUserCommand internal: false.
>2013-01-17 15:14:35,012 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-10) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:14:35,088 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-5) Checking if user admin@internal is an admin, result true
>2013-01-17 15:14:35,088 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-5) Running command: LoginUserCommand internal: false.
>2013-01-17 15:14:35,121 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-5) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:14:35,196 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-6) Checking if user admin@internal is an admin, result true
>2013-01-17 15:14:35,197 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-6) Running command: LoginUserCommand internal: false.
>2013-01-17 15:14:35,228 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-6) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:14:35,290 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-9) Checking if user admin@internal is an admin, result true
>2013-01-17 15:14:35,290 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-9) Running command: LoginUserCommand internal: false.
>2013-01-17 15:14:35,320 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-9) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:14:35,472 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-4) Checking if user admin@internal is an admin, result true
>2013-01-17 15:14:35,472 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-4) Running command: LoginUserCommand internal: false.
>2013-01-17 15:14:35,531 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-4) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:14:35,658 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-18) Checking if user admin@internal is an admin, result true
>2013-01-17 15:14:35,658 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-18) Running command: LoginUserCommand internal: false.
>2013-01-17 15:14:35,696 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-18) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:14:35,835 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-14) Checking if user admin@internal is an admin, result true
>2013-01-17 15:14:35,835 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-14) Running command: LoginUserCommand internal: false.
>2013-01-17 15:14:35,879 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-14) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:14:36,036 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (ajp-/127.0.0.1:8702-14) [56033809] START, GetImageInfoVDSCommand( storagePoolId = d093d101-0427-45fc-80df-e15a57621226, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = d2a1c0cc-3c82-43b7-89d3-ca8bf4be3b91, imageGroupId = c60e4560-08e5-44fa-b3f0-aa6641cfe264, imageId = 513cc7a3-445b-4bd5-9d6b-ef0128a4c0bb), log id: 7b116961
>2013-01-17 15:14:36,619 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (ajp-/127.0.0.1:8702-14) [56033809] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@97ff7cf0, log id: 7b116961
>2013-01-17 15:14:36,678 INFO  [org.ovirt.engine.core.bll.AddVmTemplateCommand] (ajp-/127.0.0.1:8702-14) [56033809] Running command: AddVmTemplateCommand internal: false. Entities affected :  ID: d093d101-0427-45fc-80df-e15a57621226 Type: StoragePool
>2013-01-17 15:14:36,681 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8702-14) [56033809] START, SetVmStatusVDSCommand( vmId = 5b49f3bc-0dcd-4c20-8136-f34c53855ba8, status = ImageLocked), log id: 1bc08ced
>2013-01-17 15:14:36,685 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8702-14) [56033809] FINISH, SetVmStatusVDSCommand, log id: 1bc08ced
>2013-01-17 15:14:36,719 INFO  [org.ovirt.engine.core.bll.CreateImageTemplateCommand] (ajp-/127.0.0.1:8702-14) [91afd9c] Running command: CreateImageTemplateCommand internal: true. Entities affected :  ID: d2a1c0cc-3c82-43b7-89d3-ca8bf4be3b91 Type: Storage
>2013-01-17 15:14:36,729 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (ajp-/127.0.0.1:8702-14) [91afd9c] START, GetImageInfoVDSCommand( storagePoolId = d093d101-0427-45fc-80df-e15a57621226, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = d2a1c0cc-3c82-43b7-89d3-ca8bf4be3b91, imageGroupId = c60e4560-08e5-44fa-b3f0-aa6641cfe264, imageId = 513cc7a3-445b-4bd5-9d6b-ef0128a4c0bb), log id: 19812534
>2013-01-17 15:14:37,134 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (ajp-/127.0.0.1:8702-14) [91afd9c] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@97ff7cf0, log id: 19812534
>2013-01-17 15:14:37,147 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8702-14) [91afd9c] START, CopyImageVDSCommand( storagePoolId = d093d101-0427-45fc-80df-e15a57621226, ignoreFailoverLimit = false, compatabilityVersion = 3.1, storageDomainId = d2a1c0cc-3c82-43b7-89d3-ca8bf4be3b91, imageGroupId = c60e4560-08e5-44fa-b3f0-aa6641cfe264, imageId = 513cc7a3-445b-4bd5-9d6b-ef0128a4c0bb, dstImageGroupId = 7cfb9859-9060-49c2-a5d8-4138733c3160, vmId = 5b49f3bc-0dcd-4c20-8136-f34c53855ba8, dstImageId = 955c8cea-9f2c-46da-b315-58120bf162b3, imageDescription = Active VM, dstStorageDomainId = d2a1c0cc-3c82-43b7-89d3-ca8bf4be3b91, copyVolumeType = SharedVol, volumeFormat = COW, preallocate = Sparse, postZero = false, force = false), log id: 57773e65
>2013-01-17 15:14:37,147 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8702-14) [91afd9c] -- CopyImageVDSCommand::ExecuteIrsBrokerCommand: calling 'copyImage' with two new parameters: description and UUID
>2013-01-17 15:14:37,147 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8702-14) [91afd9c] -- copyImage parameters:
>                sdUUID=d2a1c0cc-3c82-43b7-89d3-ca8bf4be3b91
>                spUUID=d093d101-0427-45fc-80df-e15a57621226
>                vmGUID=5b49f3bc-0dcd-4c20-8136-f34c53855ba8
>                srcImageGUID=c60e4560-08e5-44fa-b3f0-aa6641cfe264
>                srcVolUUID=513cc7a3-445b-4bd5-9d6b-ef0128a4c0bb
>                dstImageGUID=7cfb9859-9060-49c2-a5d8-4138733c3160
>                dstVolUUID=955c8cea-9f2c-46da-b315-58120bf162b3
>                descr=Active VM
>                
>2013-01-17 15:14:38,255 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8702-14) [91afd9c] FINISH, CopyImageVDSCommand, return: 00000000-0000-0000-0000-000000000000, log id: 57773e65
>2013-01-17 15:14:38,298 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (ajp-/127.0.0.1:8702-14) [91afd9c] EntityAsyncTask::Adding EntityMultiAsyncTasks object for entity 86221730-3b43-4634-a041-343516461111
>2013-01-17 15:14:38,298 INFO  [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (ajp-/127.0.0.1:8702-14) [91afd9c] EntityMultiAsyncTasks::AttachTask: Attaching task 51f06251-72e9-4767-81ac-b0a09a7f72f4 to entity 86221730-3b43-4634-a041-343516461111.
>2013-01-17 15:14:38,325 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp-/127.0.0.1:8702-14) [91afd9c] Adding task 51f06251-72e9-4767-81ac-b0a09a7f72f4 (Parent Command AddVmTemplate, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
>2013-01-17 15:14:38,445 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (ajp-/127.0.0.1:8702-14) [91afd9c] BaseAsyncTask::StartPollingTask: Starting to poll task 51f06251-72e9-4767-81ac-b0a09a7f72f4.
>2013-01-17 15:14:38,445 INFO  [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (ajp-/127.0.0.1:8702-14) [91afd9c] EntityMultiAsyncTasks::StartPollingTask: Current Action Type for entity 86221730-3b43-4634-a041-343516461111 is AddVmTemplate (determined by task 51f06251-72e9-4767-81ac-b0a09a7f72f4)
>2013-01-17 15:14:38,513 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-11) Checking if user admin@internal is an admin, result true
>2013-01-17 15:14:38,513 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-11) Running command: LoginUserCommand internal: false.
>2013-01-17 15:14:38,571 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-11) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:14:38,719 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-21) Checking if user admin@internal is an admin, result true
>2013-01-17 15:14:38,720 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-21) Running command: LoginUserCommand internal: false.
>2013-01-17 15:14:38,774 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-21) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:14:39,248 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-1) Checking if user admin@internal is an admin, result true
>2013-01-17 15:14:39,248 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-1) Running command: LoginUserCommand internal: false.
>2013-01-17 15:14:39,314 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-1) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:14:39,315 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-1) calling GetConfigurationValueQuery (VdcVersion) with null version, using default general for version
>2013-01-17 15:14:41,912 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-32) Polling and updating Async Tasks: 1 tasks, 1 tasks to poll now
>2013-01-17 15:14:41,931 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-32) SPMAsyncTask::PollTask: Polling task 51f06251-72e9-4767-81ac-b0a09a7f72f4 (Parent Command AddVmTemplate, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status running.
>2013-01-17 15:14:41,931 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-32) Finished polling Tasks, will poll again in 10 seconds.
>2013-01-17 15:14:48,862 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-15) Checking if user admin@internal is an admin, result true
>2013-01-17 15:14:48,862 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-15) Running command: LoginUserCommand internal: false.
>2013-01-17 15:14:48,951 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-15) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:14:59,038 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-7) Checking if user admin@internal is an admin, result true
>2013-01-17 15:14:59,040 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-7) Running command: LoginUserCommand internal: false.
>2013-01-17 15:14:59,111 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-7) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:15:00,000 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-29) Checking autorecoverable hosts
>2013-01-17 15:15:00,005 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-29) Autorecovering 0 hosts
>2013-01-17 15:15:00,005 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-29) Checking autorecoverable hosts done
>2013-01-17 15:15:00,005 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-29) Autorecovering storage domains is disabled, skipping
>2013-01-17 15:15:09,192 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-4) Checking if user admin@internal is an admin, result true
>2013-01-17 15:15:09,193 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-4) Running command: LoginUserCommand internal: false.
>2013-01-17 15:15:09,221 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-4) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:15:19,363 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-16) Checking if user admin@internal is an admin, result true
>2013-01-17 15:15:19,363 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-16) Running command: LoginUserCommand internal: false.
>2013-01-17 15:15:19,435 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-16) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:15:29,511 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-12) Checking if user admin@internal is an admin, result true
>2013-01-17 15:15:29,511 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-12) Running command: LoginUserCommand internal: false.
>2013-01-17 15:15:29,544 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-12) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:15:39,233 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-18) Checking if user admin@internal is an admin, result true
>2013-01-17 15:15:39,234 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-18) Running command: LoginUserCommand internal: false.
>2013-01-17 15:15:39,299 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-18) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:15:39,299 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-18) calling GetConfigurationValueQuery (VdcVersion) with null version, using default general for version
>2013-01-17 15:15:39,649 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-21) Checking if user admin@internal is an admin, result true
>2013-01-17 15:15:39,650 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-21) Running command: LoginUserCommand internal: false.
>2013-01-17 15:15:39,691 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-21) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version
>2013-01-17 15:15:49,772 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-19) Checking if user admin@internal is an admin, result true
>2013-01-17 15:15:49,773 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-19) Running command: LoginUserCommand internal: false.
>2013-01-17 15:15:49,810 WARN  [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (ajp-/127.0.0.1:8702-19) calling GetConfigurationValueQuery (ApplicationMode) with null version, using default general for version

Actions: View

Attachments on bug 896554: 680225