Attachment 690914 Details for Bug 906360 – engine.log

engine.log

engine.log (text/x-log), 44.84 KB, created by Jakub Libosvar on 2013-01-31 13:56:14 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Jakub Libosvar

Created: 2013-01-31 13:56:14 UTC

Size: 44.84 KB

patch

obsolete

>2013-01-31 14:55:16,245 INFO  [org.ovirt.engine.core.bll.AddDiskCommand] (ajp-/127.0.0.1:8702-5) [57b5fc6a] Running command: AddDiskCommand internal: false. Entities affected :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System
>2013-01-31 14:55:16,333 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-6) Running command: LoginUserCommand internal: false.
>2013-01-31 14:55:16,342 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-31 14:55:16,623 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-7) Running command: LoginUserCommand internal: false.
>2013-01-31 14:55:16,635 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-31 14:55:16,682 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-8) Running command: LoginUserCommand internal: false.
>2013-01-31 14:55:16,691 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-31 14:55:16,739 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-1) Running command: LoginUserCommand internal: false.
>2013-01-31 14:55:16,748 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-31 14:55:16,787 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-2) Running command: LoginUserCommand internal: false.
>2013-01-31 14:55:16,798 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-31 14:55:16,845 INFO  [org.ovirt.engine.core.bll.AttachDiskToVmCommand] (ajp-/127.0.0.1:8702-2) [3fb57512] Lock Acquired to object EngineLock [exclusiveLocks= key: 85887360-4458-4536-9269-a2822b722cd9 value: DISK
>, sharedLocks= ]
>2013-01-31 14:55:16,860 INFO  [org.ovirt.engine.core.bll.AttachDiskToVmCommand] (ajp-/127.0.0.1:8702-2) [3fb57512] Running command: AttachDiskToVmCommand internal: false. Entities affected :  ID: defc0b15-1e80-4fcd-9966-c2b0c41c3a98 Type: VM,  ID: 85887360-4458-4536-9269-a2822b722cd9 Type: Disk
>2013-01-31 14:55:16,908 INFO  [org.ovirt.engine.core.bll.AttachDiskToVmCommand] (ajp-/127.0.0.1:8702-2) [3fb57512] Lock freed to object EngineLock [exclusiveLocks= key: 85887360-4458-4536-9269-a2822b722cd9 value: DISK
>, sharedLocks= ]
>2013-01-31 14:55:16,909 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-2) [3fb57512] No severity for USER_ATTACH_DISK_TO_VM audit log type, assuming Normal severity
>2013-01-31 14:55:16,968 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-3) Running command: LoginUserCommand internal: false.
>2013-01-31 14:55:16,981 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-31 14:55:17,266 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-4) Running command: LoginUserCommand internal: false.
>2013-01-31 14:55:17,276 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-31 14:55:17,328 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-5) Running command: LoginUserCommand internal: false.
>2013-01-31 14:55:17,341 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-31 14:55:17,435 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8702-5) [4b5e10f4] START, IsVmDuringInitiatingVDSCommand( vmId = defc0b15-1e80-4fcd-9966-c2b0c41c3a98), log id: 65301421
>2013-01-31 14:55:17,436 INFO  [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8702-5) [4b5e10f4] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 65301421
>2013-01-31 14:55:17,485 INFO  [org.ovirt.engine.core.bll.RunVmOnceCommand] (ajp-/127.0.0.1:8702-5) [4b5e10f4] Running command: RunVmOnceCommand internal: false. Entities affected :  ID: defc0b15-1e80-4fcd-9966-c2b0c41c3a98 Type: VM
>2013-01-31 14:55:17,530 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp-/127.0.0.1:8702-5) [4b5e10f4] START, ConnectStorageServerVDSCommand(HostName = 10.35.160.61, HostId = aae9fe81-84b3-4fb4-a94e-d90210ddf32f, storagePoolId = 1142a509-0e5c-4a8f-9c86-79f31348b83a, storageType = ISCSI, connectionList = [{ id: 5185ea76-875a-461e-a9b8-1d171459ee7d, connection: 10.35.64.81, iqn: iqn.2003-01.org.linux-iscsi.lion.x8664:sn.20121002152127.jenkins-vm-01, vfsType: null, mountOptions: null, nfsVersion: null, nfsRetrans: null, nfsTimeo: null };]), log id: 2fd0f93a
>2013-01-31 14:55:17,599 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp-/127.0.0.1:8702-5) [4b5e10f4] FINISH, ConnectStorageServerVDSCommand, return: {5185ea76-875a-461e-a9b8-1d171459ee7d=0}, log id: 2fd0f93a
>2013-01-31 14:55:17,602 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (ajp-/127.0.0.1:8702-5) [4b5e10f4] START, CreateVmVDSCommand(HostName = 10.35.160.61, HostId = aae9fe81-84b3-4fb4-a94e-d90210ddf32f, vmId=defc0b15-1e80-4fcd-9966-c2b0c41c3a98, vm=VM [iscsiTestVm]), log id: 7ef25424
>2013-01-31 14:55:17,608 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (ajp-/127.0.0.1:8702-5) [4b5e10f4] START, CreateVDSCommand(HostName = 10.35.160.61, HostId = aae9fe81-84b3-4fb4-a94e-d90210ddf32f, vmId=defc0b15-1e80-4fcd-9966-c2b0c41c3a98, vm=VM [iscsiTestVm]), log id: 76089983
>2013-01-31 14:55:17,670 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (ajp-/127.0.0.1:8702-5) [4b5e10f4] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand spiceSslCipherSuite=DEFAULT,memSize=1024,kvmEnable=true,smp=1,vmType=kvm,emulatedMachine=rhel6.4.0,keyboardLayout=en-us,pitReinjection=false,nice=0,display=qxl,smartcardEnable=false,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard,timeOffset=0,transparentHugePages=true,vmId=defc0b15-1e80-4fcd-9966-c2b0c41c3a98,devices=[Ljava.util.Map;@16dc40fe,acpiEnable=true,vmName=iscsiTestVm,cpuType=Westmere,custom={device_260cf7ae-6843-4d3f-992d-b3ecfb8d69e6device_30c278a4-5a03-42ea-91aa-ec20e2dfea09device_71338ff7-1175-42f0-ab86-8a21def756d4device_8bd12174-ee8d-4262-b211-7656124f2aaa=VmDevice {vmId=defc0b15-1e80-4fcd-9966-c2b0c41c3a98, deviceId=8bd12174-ee8d-4262-b211-7656124f2aaa, 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_260cf7ae-6843-4d3f-992d-b3ecfb8d69e6device_30c278a4-5a03-42ea-91aa-ec20e2dfea09device_71338ff7-1175-42f0-ab86-8a21def756d4device_8bd12174-ee8d-4262-b211-7656124f2aaadevice_b070f8ad-d79c-4f34-b60c-938d76e3b800device_f2424618-f5ce-4efc-9854-d4adfbdb3e2b=VmDevice {vmId=defc0b15-1e80-4fcd-9966-c2b0c41c3a98, deviceId=f2424618-f5ce-4efc-9854-d4adfbdb3e2b, 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_260cf7ae-6843-4d3f-992d-b3ecfb8d69e6device_30c278a4-5a03-42ea-91aa-ec20e2dfea09=VmDevice {vmId=defc0b15-1e80-4fcd-9966-c2b0c41c3a98, deviceId=30c278a4-5a03-42ea-91aa-ec20e2dfea09, 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_260cf7ae-6843-4d3f-992d-b3ecfb8d69e6=VmDevice {vmId=defc0b15-1e80-4fcd-9966-c2b0c41c3a98, deviceId=260cf7ae-6843-4d3f-992d-b3ecfb8d69e6, 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_260cf7ae-6843-4d3f-992d-b3ecfb8d69e6device_30c278a4-5a03-42ea-91aa-ec20e2dfea09device_71338ff7-1175-42f0-ab86-8a21def756d4=VmDevice {vmId=defc0b15-1e80-4fcd-9966-c2b0c41c3a98, deviceId=71338ff7-1175-42f0-ab86-8a21def756d4, 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_260cf7ae-6843-4d3f-992d-b3ecfb8d69e6device_30c278a4-5a03-42ea-91aa-ec20e2dfea09device_71338ff7-1175-42f0-ab86-8a21def756d4device_8bd12174-ee8d-4262-b211-7656124f2aaadevice_b070f8ad-d79c-4f34-b60c-938d76e3b800=VmDevice {vmId=defc0b15-1e80-4fcd-9966-c2b0c41c3a98, deviceId=b070f8ad-d79c-4f34-b60c-938d76e3b800, 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-31 14:55:17,671 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (ajp-/127.0.0.1:8702-5) [4b5e10f4] FINISH, CreateVDSCommand, log id: 76089983
>2013-01-31 14:55:17,677 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (ajp-/127.0.0.1:8702-5) [4b5e10f4] IncreasePendingVms::CreateVmIncreasing vds 10.35.160.61 pending vcpu count, now 1. Vm: iscsiTestVm
>2013-01-31 14:55:17,679 INFO  [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (ajp-/127.0.0.1:8702-5) [4b5e10f4] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 7ef25424
>2013-01-31 14:55:17,731 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-6) Running command: LoginUserCommand internal: false.
>2013-01-31 14:55:17,746 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-31 14:55:21,373 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-99) VM iscsiTestVm defc0b15-1e80-4fcd-9966-c2b0c41c3a98 moved from WaitForLaunch --> PoweringUp
>2013-01-31 14:55:21,374 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (QuartzScheduler_Worker-99) START, FullListVdsCommand( HostId = aae9fe81-84b3-4fb4-a94e-d90210ddf32f, vds=null, vmIds=[defc0b15-1e80-4fcd-9966-c2b0c41c3a98]), log id: 43d01076
>2013-01-31 14:55:21,391 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (QuartzScheduler_Worker-99) FINISH, FullListVdsCommand, return: [Lorg.ovirt.engine.core.vdsbroker.xmlrpc.XmlRpcStruct;@7e049768, log id: 43d01076
>2013-01-31 14:55:21,466 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (QuartzScheduler_Worker-99) START, UpdateVdsDynamicDataVDSCommand(HostName = 10.35.160.61, HostId = aae9fe81-84b3-4fb4-a94e-d90210ddf32f, vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@c8dfccd9), log id: 3004280c
>2013-01-31 14:55:21,469 INFO  [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (QuartzScheduler_Worker-99) FINISH, UpdateVdsDynamicDataVDSCommand, log id: 3004280c
>2013-01-31 14:55:27,817 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-7) Running command: LoginUserCommand internal: false.
>2013-01-31 14:55:27,827 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-31 14:55:36,327 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-8) Running command: LoginUserCommand internal: false.
>2013-01-31 14:55:36,338 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-31 14:55:36,393 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-1) Running command: LoginUserCommand internal: false.
>2013-01-31 14:55:36,404 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-31 14:55:36,474 INFO  [org.ovirt.engine.core.bll.StopVmCommand] (ajp-/127.0.0.1:8702-1) [6de3f6ff] Running command: StopVmCommand internal: false. Entities affected :  ID: defc0b15-1e80-4fcd-9966-c2b0c41c3a98 Type: VM
>2013-01-31 14:55:36,489 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (ajp-/127.0.0.1:8702-1) [6de3f6ff] START, DestroyVmVDSCommand(HostName = 10.35.160.61, HostId = aae9fe81-84b3-4fb4-a94e-d90210ddf32f, vmId=defc0b15-1e80-4fcd-9966-c2b0c41c3a98, force=false, secondsToWait=0, gracefully=false), log id: 3b59a713
>2013-01-31 14:55:36,511 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (ajp-/127.0.0.1:8702-1) [6de3f6ff] START, DestroyVDSCommand(HostName = 10.35.160.61, HostId = aae9fe81-84b3-4fb4-a94e-d90210ddf32f, vmId=defc0b15-1e80-4fcd-9966-c2b0c41c3a98, force=false, secondsToWait=0, gracefully=false), log id: 16743755
>2013-01-31 14:55:37,491 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (ajp-/127.0.0.1:8702-1) [6de3f6ff] FINISH, DestroyVDSCommand, log id: 16743755
>2013-01-31 14:55:37,515 INFO  [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (ajp-/127.0.0.1:8702-1) [6de3f6ff] FINISH, DestroyVmVDSCommand, return: Down, log id: 3b59a713
>2013-01-31 14:55:37,575 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-2) Running command: LoginUserCommand internal: false.
>2013-01-31 14:55:37,588 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-31 14:55:37,965 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-3) Running command: LoginUserCommand internal: false.
>2013-01-31 14:55:37,975 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-31 14:55:38,015 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-4) Running command: LoginUserCommand internal: false.
>2013-01-31 14:55:38,025 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-31 14:55:38,078 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-5) Running command: LoginUserCommand internal: false.
>2013-01-31 14:55:38,089 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-31 14:55:38,136 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-6) Running command: LoginUserCommand internal: false.
>2013-01-31 14:55:38,147 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-31 14:55:38,490 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (ajp-/127.0.0.1:8702-6) [4f922893] START, GetImageInfoVDSCommand( storagePoolId = 1142a509-0e5c-4a8f-9c86-79f31348b83a, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 9fbc390d-7d43-4d2c-9084-979630a701b2, imageGroupId = 017df146-b3d0-4323-894c-f4bce0d8e8d1, imageId = 355c8067-87f2-4192-b7e3-2082174919a9), log id: 79222158
>2013-01-31 14:55:38,762 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (ajp-/127.0.0.1:8702-6) [4f922893] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@e309c257, log id: 79222158
>2013-01-31 14:55:38,780 INFO  [org.ovirt.engine.core.bll.AddVmTemplateCommand] (ajp-/127.0.0.1:8702-6) [4f922893] Running command: AddVmTemplateCommand internal: false. Entities affected :  ID: 1142a509-0e5c-4a8f-9c86-79f31348b83a Type: StoragePool
>2013-01-31 14:55:38,788 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8702-6) [4f922893] START, SetVmStatusVDSCommand( vmId = defc0b15-1e80-4fcd-9966-c2b0c41c3a98, status = ImageLocked), log id: 63f72d72
>2013-01-31 14:55:38,799 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8702-6) [4f922893] FINISH, SetVmStatusVDSCommand, log id: 63f72d72
>2013-01-31 14:55:38,825 INFO  [org.ovirt.engine.core.bll.CreateImageTemplateCommand] (ajp-/127.0.0.1:8702-6) [3ac53fea] Running command: CreateImageTemplateCommand internal: true. Entities affected :  ID: 9fbc390d-7d43-4d2c-9084-979630a701b2 Type: Storage
>2013-01-31 14:55:38,833 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (ajp-/127.0.0.1:8702-6) [3ac53fea] START, GetImageInfoVDSCommand( storagePoolId = 1142a509-0e5c-4a8f-9c86-79f31348b83a, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 9fbc390d-7d43-4d2c-9084-979630a701b2, imageGroupId = 017df146-b3d0-4323-894c-f4bce0d8e8d1, imageId = 355c8067-87f2-4192-b7e3-2082174919a9), log id: 7f6d2308
>2013-01-31 14:55:39,097 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (ajp-/127.0.0.1:8702-6) [3ac53fea] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@e309c257, log id: 7f6d2308
>2013-01-31 14:55:39,116 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8702-6) [3ac53fea] START, CopyImageVDSCommand( storagePoolId = 1142a509-0e5c-4a8f-9c86-79f31348b83a, ignoreFailoverLimit = false, compatabilityVersion = 3.2, storageDomainId = 9fbc390d-7d43-4d2c-9084-979630a701b2, imageGroupId = 017df146-b3d0-4323-894c-f4bce0d8e8d1, imageId = 355c8067-87f2-4192-b7e3-2082174919a9, dstImageGroupId = 7666ed29-0363-490b-87df-0725dffeb799, vmId = defc0b15-1e80-4fcd-9966-c2b0c41c3a98, dstImageId = c3d29c37-1185-499d-a3b7-1ce49780f237, imageDescription = Active VM, dstStorageDomainId = 9fbc390d-7d43-4d2c-9084-979630a701b2, copyVolumeType = SharedVol, volumeFormat = COW, preallocate = Sparse, postZero = false, force = false), log id: 763f29b2
>2013-01-31 14:55:39,117 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8702-6) [3ac53fea] -- CopyImageVDSCommand::ExecuteIrsBrokerCommand: calling 'copyImage' with two new parameters: description and UUID
>2013-01-31 14:55:39,117 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8702-6) [3ac53fea] -- copyImage parameters:
>                sdUUID=9fbc390d-7d43-4d2c-9084-979630a701b2
>                spUUID=1142a509-0e5c-4a8f-9c86-79f31348b83a
>                vmGUID=defc0b15-1e80-4fcd-9966-c2b0c41c3a98
>                srcImageGUID=017df146-b3d0-4323-894c-f4bce0d8e8d1
>                srcVolUUID=355c8067-87f2-4192-b7e3-2082174919a9
>                dstImageGUID=7666ed29-0363-490b-87df-0725dffeb799
>                dstVolUUID=c3d29c37-1185-499d-a3b7-1ce49780f237
>                descr=Active VM
>                
>2013-01-31 14:55:40,018 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8702-6) [3ac53fea] FINISH, CopyImageVDSCommand, return: 00000000-0000-0000-0000-000000000000, log id: 763f29b2
>2013-01-31 14:55:40,023 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (ajp-/127.0.0.1:8702-6) [3ac53fea] EntityAsyncTask::Adding EntityMultiAsyncTasks object for entity c180eb5e-4ae2-4742-a5dc-210a3e0dc326
>2013-01-31 14:55:40,023 INFO  [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (ajp-/127.0.0.1:8702-6) [3ac53fea] EntityMultiAsyncTasks::AttachTask: Attaching task fec928ca-f7ca-48c8-a911-bc47588e3751 to entity c180eb5e-4ae2-4742-a5dc-210a3e0dc326.
>2013-01-31 14:55:40,056 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp-/127.0.0.1:8702-6) [3ac53fea] Adding task fec928ca-f7ca-48c8-a911-bc47588e3751 (Parent Command AddVmTemplate, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet..
>2013-01-31 14:55:40,127 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (ajp-/127.0.0.1:8702-6) [3ac53fea] BaseAsyncTask::StartPollingTask: Starting to poll task fec928ca-f7ca-48c8-a911-bc47588e3751.
>2013-01-31 14:55:40,127 INFO  [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (ajp-/127.0.0.1:8702-6) [3ac53fea] EntityMultiAsyncTasks::StartPollingTask: Current Action Type for entity c180eb5e-4ae2-4742-a5dc-210a3e0dc326 is AddVmTemplate (determined by task fec928ca-f7ca-48c8-a911-bc47588e3751)
>2013-01-31 14:55:40,393 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-7) Running command: LoginUserCommand internal: false.
>2013-01-31 14:55:40,402 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-31 14:55:40,461 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-8) Running command: LoginUserCommand internal: false.
>2013-01-31 14:55:40,474 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-31 14:55:48,780 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-28) Polling and updating Async Tasks: 1 tasks, 1 tasks to poll now
>2013-01-31 14:55:48,799 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-28) SPMAsyncTask::PollTask: Polling task fec928ca-f7ca-48c8-a911-bc47588e3751 (Parent Command AddVmTemplate, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status running.
>2013-01-31 14:55:48,800 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-28) Finished polling Tasks, will poll again in 10 seconds.
>2013-01-31 14:55:50,519 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-1) Running command: LoginUserCommand internal: false.
>2013-01-31 14:55:50,540 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-31 14:56:00,589 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-2) Running command: LoginUserCommand internal: false.
>2013-01-31 14:56:00,601 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-31 14:56:10,656 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-3) Running command: LoginUserCommand internal: false.
>2013-01-31 14:56:10,667 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-31 14:56:20,745 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-4) Running command: LoginUserCommand internal: false.
>2013-01-31 14:56:20,756 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-31 14:56:29,198 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-70) SPMAsyncTask::PollTask: Polling task fec928ca-f7ca-48c8-a911-bc47588e3751 (Parent Command AddVmTemplate, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'.
>2013-01-31 14:56:29,204 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-70) BaseAsyncTask::OnTaskEndSuccess: Task fec928ca-f7ca-48c8-a911-bc47588e3751 (Parent Command AddVmTemplate, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully.
>2013-01-31 14:56:29,205 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (QuartzScheduler_Worker-70) EntityAsyncTask::EndActionIfNecessary: All tasks of entity c180eb5e-4ae2-4742-a5dc-210a3e0dc326 has ended -> executing EndAction
>2013-01-31 14:56:29,205 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (QuartzScheduler_Worker-70) EntityAsyncTask::EndAction: Ending action for 1 tasks (entity ID: c180eb5e-4ae2-4742-a5dc-210a3e0dc326): calling EndAction for action type AddVmTemplate.
>2013-01-31 14:56:29,206 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-3-thread-48) EntityAsyncTask::EndCommandAction [within thread] context: Attempting to EndAction AddVmTemplate, executionIndex: 0
>2013-01-31 14:56:29,288 INFO  [org.ovirt.engine.core.bll.AddVmTemplateCommand] (pool-3-thread-48) [4f922893] Ending command successfully: org.ovirt.engine.core.bll.AddVmTemplateCommand
>2013-01-31 14:56:29,292 INFO  [org.ovirt.engine.core.bll.CreateImageTemplateCommand] (pool-3-thread-48) [3ac53fea] Ending command successfully: org.ovirt.engine.core.bll.CreateImageTemplateCommand
>2013-01-31 14:56:29,300 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-3-thread-48) [3ac53fea] START, GetImageInfoVDSCommand( storagePoolId = 1142a509-0e5c-4a8f-9c86-79f31348b83a, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 9fbc390d-7d43-4d2c-9084-979630a701b2, imageGroupId = 7666ed29-0363-490b-87df-0725dffeb799, imageId = c3d29c37-1185-499d-a3b7-1ce49780f237), log id: 3f8cc323
>2013-01-31 14:56:29,586 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-3-thread-48) [3ac53fea] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@c6d3f77, log id: 3f8cc323
>2013-01-31 14:56:29,596 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (pool-3-thread-48) START, SetVmStatusVDSCommand( vmId = defc0b15-1e80-4fcd-9966-c2b0c41c3a98, status = Down), log id: 44eddd50
>2013-01-31 14:56:29,604 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (pool-3-thread-48) FINISH, SetVmStatusVDSCommand, log id: 44eddd50
>2013-01-31 14:56:29,619 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-3-thread-48) EntityAsyncTask::HandleEndActionResult [within thread]: EndAction for action type AddVmTemplate completed, handling the result.
>2013-01-31 14:56:29,619 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-3-thread-48) EntityAsyncTask::HandleEndActionResult [within thread]: EndAction for action type AddVmTemplate succeeded, clearing tasks.
>2013-01-31 14:56:29,623 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-3-thread-48) SPMAsyncTask::ClearAsyncTask: Attempting to clear task fec928ca-f7ca-48c8-a911-bc47588e3751
>2013-01-31 14:56:29,623 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-3-thread-48) START, SPMClearTaskVDSCommand( storagePoolId = 1142a509-0e5c-4a8f-9c86-79f31348b83a, ignoreFailoverLimit = false, compatabilityVersion = null, taskId = fec928ca-f7ca-48c8-a911-bc47588e3751), log id: 33537b2f
>2013-01-31 14:56:29,626 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-3-thread-48) START, HSMClearTaskVDSCommand(HostName = 10.35.160.61, HostId = aae9fe81-84b3-4fb4-a94e-d90210ddf32f, taskId=fec928ca-f7ca-48c8-a911-bc47588e3751), log id: 211925f8
>2013-01-31 14:56:29,647 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-3-thread-48) FINISH, HSMClearTaskVDSCommand, log id: 211925f8
>2013-01-31 14:56:29,648 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-3-thread-48) FINISH, SPMClearTaskVDSCommand, log id: 33537b2f
>2013-01-31 14:56:29,651 INFO  [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-3-thread-48) BaseAsyncTask::RemoveTaskFromDB: Removed task fec928ca-f7ca-48c8-a911-bc47588e3751 from DataBase
>2013-01-31 14:56:29,652 INFO  [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-3-thread-48) EntityAsyncTask::HandleEndActionResult [within thread]: Removing EntityMultiAsyncTasks object for entity c180eb5e-4ae2-4742-a5dc-210a3e0dc326
>2013-01-31 14:56:30,794 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-5) Running command: LoginUserCommand internal: false.
>2013-01-31 14:56:30,809 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-31 14:56:31,140 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-6) Running command: LoginUserCommand internal: false.
>2013-01-31 14:56:31,152 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-31 14:56:31,197 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-7) Running command: LoginUserCommand internal: false.
>2013-01-31 14:56:31,205 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-31 14:56:31,568 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-8) Running command: LoginUserCommand internal: false.
>2013-01-31 14:56:31,578 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-31 14:56:31,635 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-1) Running command: LoginUserCommand internal: false.
>2013-01-31 14:56:31,644 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-31 14:56:31,971 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-2) Running command: LoginUserCommand internal: false.
>2013-01-31 14:56:31,982 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-31 14:56:32,046 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-3) Running command: LoginUserCommand internal: false.
>2013-01-31 14:56:32,055 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-31 14:56:32,132 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-4) Running command: LoginUserCommand internal: false.
>2013-01-31 14:56:32,140 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-31 14:56:32,198 INFO  [org.ovirt.engine.core.bll.DetachDiskFromVmCommand] (ajp-/127.0.0.1:8702-4) [64f2cc7f] Running command: DetachDiskFromVmCommand internal: false. Entities affected :  ID: defc0b15-1e80-4fcd-9966-c2b0c41c3a98 Type: VM
>2013-01-31 14:56:32,236 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-4) [64f2cc7f] No severity for USER_DETACH_DISK_FROM_VM audit log type, assuming Normal severity
>2013-01-31 14:56:32,579 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-5) Running command: LoginUserCommand internal: false.
>2013-01-31 14:56:32,588 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-31 14:56:32,632 INFO  [org.ovirt.engine.core.bll.LoginUserCommand] (ajp-/127.0.0.1:8702-6) Running command: LoginUserCommand internal: false.
>2013-01-31 14:56:32,640 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-31 14:56:32,641 ERROR [org.ovirt.engine.api.restapi.resource.AbstractBackendCollectionResource] (ajp-/127.0.0.1:8702-6) Could not find sub-resource in the collection resource
>2013-01-31 14:56:32,677 INFO  [org.ovirt.engine.core.bll.RemoveDiskCommand] (ajp-/127.0.0.1:8702-6) [688166c0] Lock Acquired to object EngineLock [exclusiveLocks= key: 85887360-4458-4536-9269-a2822b722cd9 value: DISK
>, sharedLocks= ]
>2013-01-31 14:56:32,684 INFO  [org.ovirt.engine.core.bll.RemoveDiskCommand] (ajp-/127.0.0.1:8702-6) [688166c0] Running command: RemoveDiskCommand internal: false. Entities affected :  ID: 85887360-4458-4536-9269-a2822b722cd9 Type: Disk
>2013-01-31 14:56:32,697 INFO  [org.ovirt.engine.core.utils.transaction.TransactionSupport] (ajp-/127.0.0.1:8702-6) [688166c0] transaction rolled back
>2013-01-31 14:56:32,697 ERROR [org.ovirt.engine.core.bll.RemoveDiskCommand] (ajp-/127.0.0.1:8702-6) [688166c0] Command org.ovirt.engine.core.bll.RemoveDiskCommand throw exception: java.lang.NullPointerException
>	at org.ovirt.engine.core.bll.storage.StorageHelperBase.removeLun(StorageHelperBase.java:94) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.ImagesHandler.removeLunDisk(ImagesHandler.java:695) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.RemoveDiskCommand$1.runInTransaction(RemoveDiskCommand.java:297) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.RemoveDiskCommand$1.runInTransaction(RemoveDiskCommand.java:294) [engine-bll.jar:]
>	at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInNewTransaction(TransactionSupport.java:202) [engine-utils.jar:]
>	at org.ovirt.engine.core.bll.RemoveDiskCommand.removeLunDisk(RemoveDiskCommand.java:294) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.RemoveDiskCommand.executeCommand(RemoveDiskCommand.java:289) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1002) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1087) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1468) [engine-bll.jar:]
>	at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInSuppressed(TransactionSupport.java:166) [engine-utils.jar:]
>	at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:108) [engine-utils.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.execute(CommandBase.java:1105) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:302) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.Backend.runActionImpl(Backend.java:359) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.Backend.RunAction(Backend.java:305) [engine-bll.jar:]
>	at sun.reflect.GeneratedMethodAccessor192.invoke(Unknown Source) [:1.7.0_11-icedtea]
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_11-icedtea]
>	at java.lang.reflect.Method.invoke(Method.java:601) [rt.jar:1.7.0_11-icedtea]
>	at org.jboss.as.ee.component.ManagedReferenceMethodInterceptorFactory$ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptorFactory.java:72) [jboss-as-ee.jar:7.1.3.Final-redhat-4]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
>	at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:374) [jboss-invocation.jar:1.1.1.Final-redhat-2]
>	at org.ovirt.engine.core.utils.ThreadLocalSessionCleanerInterceptor.injectWebContextToThreadLocal(ThreadLocalSessionCleanerInterceptor.java:11) [engine-utils.jar:]
>	at sun.reflect.GeneratedMethodAccessor13.invoke(Unknown Source) [:1.7.0_11-icedtea]
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_11-icedtea]
>	at java.lang.reflect.Method.invoke(Method.java:601) [rt.jar:1.7.0_11-icedtea]
>	at org.jboss.as.ee.component.ManagedReferenceLifecycleMethodInterceptorFactory$ManagedReferenceLifecycleMethodInterceptor.processInvocation(ManagedReferenceLifecycleMethodInterceptorFactory.java:123) [jboss-as-ee.jar:7.1.3.Final-redhat-4]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
>	at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation.jar:1.1.1.Final-redhat-2]
>	at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee.jar:7.1.3.Final-redhat-4]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
>	at org.jboss.as.ejb3.component.invocationmetrics.ExecutionTimeInterceptor.processInvocation(ExecutionTimeInterceptor.java:43) [jboss-as-ejb3.jar:7.1.3.Final-redhat-4]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
>	at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:21) [jboss-invocation.jar:1.1.1.Final-redhat-2]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
>	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation.jar:1.1.1.Final-redhat-2]
>	at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:53) [jboss-as-ee.jar:7.1.3.Final-redhat-4]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
>	at org.jboss.as.ejb3.component.singleton.SingletonComponentInstanceAssociationInterceptor.processInvocation(SingletonComponentInstanceAssociationInterceptor.java:53) [jboss-as-ejb3.jar:7.1.3.Final-redhat-4]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
>	at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:209) [jboss-as-ejb3.jar:7.1.3.Final-redhat-4]
>	at org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:361) [jboss-as-ejb3.jar:7.1.3.Final-redhat-4]
>	at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:192) [jboss-as-ejb3.jar:7.1.3.Final-redhat-4]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
>	at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [jboss-as-ejb3.jar:7.1.3.Final-redhat-4]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
>	at org.jboss.as.ejb3.component.interceptors.ShutDownInterceptorFactory$1.processInvocation(ShutDownInterceptorFactory.java:42) [jboss-as-ejb3.jar:7.1.3.Final-redhat-4]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
>	at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:59) [jboss-as-ejb3.jar:7.1.3.Final-redhat-4]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
>	at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50) [jboss-as-ee.jar:7.1.3.Final-redhat-4]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
>	at org.jboss.as.ee.component.TCCLInterceptor.processInvocation(TCCLInterceptor.java:45) [jboss-as-ee.jar:7.1.3.Final-redhat-4]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
>	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation.jar:1.1.1.Final-redhat-2]
>	at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:165) [jboss-as-ee.jar:7.1.3.Final-redhat-4]
>	at org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:181) [jboss-as-ee.jar:7.1.3.Final-redhat-4]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation.jar:1.1.1.Final-redhat-2]
>	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation.jar:1.1.1.Final-redhat-2]
>	at org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:72) [jboss-as-ee.jar:7.1.3.Final-redhat-4]
>	at org.ovirt.engine.core.common.interfaces.BackendLocal$$$view6.RunAction(Unknown Source) [engine-common.jar:]
>	at org.ovirt.engine.api.restapi.resource.BackendResource.doAction(BackendResource.java:191)
>	at org.ovirt.engine.api.restapi.resource.BackendResource.performAction(BackendResource.java:145)
>	at org.ovirt.engine.api.restapi.resource.BackendResource.performAction(BackendResource.java:135)
>	at org.ovirt.engine.api.restapi.resource.BackendDisksResource.performRemove(BackendDisksResource.java:100)
>	at org.ovirt.engine.api.restapi.resource.AbstractBackendCollectionResource.remove(AbstractBackendCollectionResource.java:44)
>	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_11-icedtea]
>	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_11-icedtea]
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_11-icedtea]
>	at java.lang.reflect.Method.invoke(Method.java:601) [rt.jar:1.7.0_11-icedtea]
>	at org.jboss.resteasy.core.MethodInjectorImpl.invoke(MethodInjectorImpl.java:167) [resteasy-jaxrs.jar:2.3.4.Final-redhat-2]
>	at org.jboss.resteasy.core.ResourceMethod.invokeOnTarget(ResourceMethod.java:257) [resteasy-jaxrs.jar:2.3.4.Final-redhat-2]
>	at org.jboss.resteasy.core.ResourceMethod.invoke(ResourceMethod.java:222) [resteasy-jaxrs.jar:2.3.4.Final-redhat-2]
>	at org.jboss.resteasy.core.ResourceMethod.invoke(ResourceMethod.java:211) [resteasy-jaxrs.jar:2.3.4.Final-redhat-2]
>	at org.jboss.resteasy.core.SynchronousDispatcher.getResponse(SynchronousDispatcher.java:536) [resteasy-jaxrs.jar:2.3.4.Final-redhat-2]
>	at org.jboss.resteasy.core.SynchronousDispatcher.invoke(SynchronousDispatcher.java:513) [resteasy-jaxrs.jar:2.3.4.Final-redhat-2]
>	at org.jboss.resteasy.core.SynchronousDispatcher.invoke(SynchronousDispatcher.java:125) [resteasy-jaxrs.jar:2.3.4.Final-redhat-2]
>	at org.jboss.resteasy.plugins.server.servlet.ServletContainerDispatcher.service(ServletContainerDispatcher.java:208) [resteasy-jaxrs.jar:2.3.4.Final-redhat-2]
>	at org.jboss.resteasy.plugins.server.servlet.HttpServletDispatcher.service(HttpServletDispatcher.java:55) [resteasy-jaxrs.jar:2.3.4.Final-redhat-2]
>	at org.jboss.resteasy.plugins.server.servlet.HttpServletDispatcher.service(HttpServletDispatcher.java:50) [resteasy-jaxrs.jar:2.3.4.Final-redhat-2]
>	at javax.servlet.http.HttpServlet.service(HttpServlet.java:847) [jboss-servlet-api_3.0_spec.jar:1.0.1.Final-redhat-2]
>	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:329)
>	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
>	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:275)
>	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:161)
>	at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:489)
>	at org.jboss.as.web.security.SecurityContextAssociationValve.invoke(SecurityContextAssociationValve.java:169)
>	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:155)
>	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
>	at org.jboss.web.rewrite.RewriteValve.invoke(RewriteValve.java:466)
>	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
>	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:372)
>	at org.apache.coyote.ajp.AjpProcessor.process(AjpProcessor.java:505)
>	at org.apache.coyote.ajp.AjpProtocol$AjpConnectionHandler.process(AjpProtocol.java:453)
>	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:931)
>	at java.lang.Thread.run(Thread.java:722) [rt.jar:1.7.0_11-icedtea]
>
>2013-01-31 14:56:32,719 INFO  [org.ovirt.engine.core.bll.RemoveDiskCommand] (ajp-/127.0.0.1:8702-6) [688166c0] Lock freed to object EngineLock [exclusiveLocks= key: 85887360-4458-4536-9269-a2822b722cd9 value: DISK
>, sharedLocks= ]
>2013-01-31 14:56:32,734 ERROR [org.ovirt.engine.api.restapi.resource.AbstractBackendResource] (ajp-/127.0.0.1:8702-6) Operation Failed: [Internal Engine Error]

Actions: View

Attachments on bug 906360: 690914