[ovirt-users] Re: Fail to attach/create disk from template when running via Ansible

Friday, 15 November 2019

Hi Marko,

could you please create a bug for that and attach full SOS collection logs
from engine and relevant host?

Thanks,
Martin

On Wed, Nov 13, 2019 at 2:54 AM Vrgotic, Marko <M.Vrgotic(a)activevideo.com&gt;
wrote:

...
 Hi Ondra,

 I have rebuilt the staging with 4.3.6 and still same issue. Images are
 there, I can again create VM via UI, but not via Ansible.
 Here is the output of the complete run form engine.log:

 2019-11-12 12:09:00,457Z INFO
 [org.ovirt.engine.core.sso.utils.AuthenticationUtils] (default task-49) []
 User admin@internal successfully logged in with scopes: ovirt-app-api
 ovirt-ext=token-info:authz-search ovirt-ext=token-info:public-authz-search
 ovirt-ext=token-info:validate ovirt-ext=token:password-access
 2019-11-12 12:09:00,978Z INFO
 [org.ovirt.engine.core.sso.utils.AuthenticationUtils] (default task-49) []
 User admin@internal successfully logged in with scopes: ovirt-app-api
 ovirt-ext=token-info:authz-search ovirt-ext=token-info:public-authz-search
 ovirt-ext=token-info:validate ovirt-ext=token:password-access
 2019-11-12 12:09:01,538Z INFO
 [org.ovirt.engine.core.bll.aaa.CreateUserSessionCommand] (default task-49)
 [233de27] Running command: CreateUserSessionCommand internal: false.
 2019-11-12 12:09:01,549Z INFO
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
 (default task-49) [233de27] EVENT_ID: USER_VDC_LOGIN(30), User
 admin@internal-authz connecting from '172.16.105.206' using session

'bsgNpfGJTNtzuuQWSyXVJqHhpLK4TY9TQRERWxxzGtpHonoqdk7WIHGZ7mID2+QkBKH9bPp8HVhyvDu66tzPnQ=='
 logged in.
 2019-11-12 12:09:01,691Z INFO  [org.ovirt.engine.core.bll.AddVmCommand]
 (default task-49) [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Lock Acquired to
 object 'EngineLock:{exclusiveLocks='[centos-testvm-a910=VM_NAME]',
 sharedLocks='[400688e8-3c5b-4cca-9ef8-bbb6c79d54ed=DISK,
 819aecf6-c83d-4470-9915-e309390d8098=TEMPLATE]'}'
 2019-11-12 12:09:01,773Z INFO  [org.ovirt.engine.core.bll.AddVmCommand]
 (default task-49) [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Running command:
 AddVmCommand internal: false. Entities affected :  ID:
 d3c90169-ecc8-46a0-a4ec-7c3d99b81b61 Type: ClusterAction group CREATE_VM
 with role type USER,  ID: 819aecf6-c83d-4470-9915-e309390d8098 Type:
 VmTemplateAction group CREATE_VM with role type USER,  ID:
 78bfcb57-3a0d-4a71-a3f8-480ebae37c74 Type: StorageAction group CREATE_DISK
 with role type USER
 2019-11-12 12:09:01,866Z INFO
 [org.ovirt.engine.core.bll.AddRngDeviceCommand] (default task-49)
 [4d4703a1] Running command: AddRngDeviceCommand internal: true. Entities
 affected :  ID: 6343c55a-c67e-4d75-a767-dfc9412006e6 Type: VMAction group
 EDIT_VM_PROPERTIES with role type USER
 2019-11-12 12:09:01,876Z INFO
 [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (default task-49)
 [4d4703a1] START, SetVmStatusVDSCommand(
 SetVmStatusVDSCommandParameters:{vmId='6343c55a-c67e-4d75-a767-dfc9412006e6',
 status='ImageLocked', exitStatus='Normal'}), log id: 4eedcefb
 2019-11-12 12:09:01,887Z INFO
 [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (default task-49)
 [4d4703a1] FINISH, SetVmStatusVDSCommand, return: , log id: 4eedcefb
 2019-11-12 12:09:01,906Z INFO
 [org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand]
 (default task-49) [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Running command:
 CreateSnapshotFromTemplateCommand internal: true. Entities affected :  ID:
 78bfcb57-3a0d-4a71-a3f8-480ebae37c74 Type: Storage
 2019-11-12 12:09:01,928Z INFO
 [org.ovirt.engine.core.vdsbroker.irsbroker.CreateVolumeVDSCommand] (default
 task-49) [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] START,
 CreateVolumeVDSCommand(

CreateVolumeVDSCommandParameters:{storagePoolId='47523e52-311f-4829-a731-3d91f7885974',
 ignoreFailoverLimit='false',
 storageDomainId='78bfcb57-3a0d-4a71-a3f8-480ebae37c74',
 imageGroupId='56ddac45-1913-4d24-af97-2f969fc3fa2e',
 imageSizeInBytes='8589934592', volumeFormat='COW',
 newImageId='7270dd54-a075-4b14-aca3-7c2a118057f0', imageType='Sparse',
 newImageDescription='', imageInitialSizeInBytes='0',
 imageId='767c6837-5191-4650-a49d-abca7d69a708',
 sourceImageGroupId='400688e8-3c5b-4cca-9ef8-bbb6c79d54ed'}), log id:
 2dfac68e
 2019-11-12 12:09:02,009Z INFO
 [org.ovirt.engine.core.vdsbroker.irsbroker.CreateVolumeVDSCommand] (default
 task-49) [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] FINISH,
 CreateVolumeVDSCommand, return: 7270dd54-a075-4b14-aca3-7c2a118057f0, log
 id: 2dfac68e
 2019-11-12 12:09:02,014Z INFO
 [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (default task-49)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] CommandAsyncTask::Adding
 CommandMultiAsyncTasks object for command
 'fb915b7e-4814-4e93-a3ef-4f40c4e3c41b'
 2019-11-12 12:09:02,014Z INFO
 [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (default task-49)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] CommandMultiAsyncTasks::attachTask:
 Attaching task '0eb09758-113f-43b9-aa67-97d2e5a57e05' to command
 'fb915b7e-4814-4e93-a3ef-4f40c4e3c41b'.
 2019-11-12 12:09:02,027Z INFO
 [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (default task-49)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Adding task
 '0eb09758-113f-43b9-aa67-97d2e5a57e05' (Parent Command
 'CreateSnapshotFromTemplate', Parameters Type
 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters'), polling
 hasn't started yet..
 2019-11-12 12:09:02,098Z INFO
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
 (default task-49) [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] EVENT_ID:
 USER_ADD_VM_STARTED(37), VM centos-testvm-a910 creation was initiated by
 admin@internal-authz.
 2019-11-12 12:09:02,098Z INFO
 [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (default task-49)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] BaseAsyncTask::startPollingTask:
 Starting to poll task '0eb09758-113f-43b9-aa67-97d2e5a57e05'.
 2019-11-12 12:09:02,758Z INFO
 [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback]
 (EE-ManagedThreadFactory-engineScheduled-Thread-19)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Command 'AddVm' (id:
 '97d4103b-acef-4b55-a379-71a1baa04a4f') waiting on child command id:
 'fb915b7e-4814-4e93-a3ef-4f40c4e3c41b' type:'CreateSnapshotFromTemplate'
to
 complete
 2019-11-12 12:09:04,773Z INFO
 [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback]
 (EE-ManagedThreadFactory-engineScheduled-Thread-52)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Command 'AddVm' (id:
 '97d4103b-acef-4b55-a379-71a1baa04a4f') waiting on child command id:
 'fb915b7e-4814-4e93-a3ef-4f40c4e3c41b' type:'CreateSnapshotFromTemplate'
to
 complete
 2019-11-12 12:09:06,117Z INFO
 [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
 (EE-ManagedThreadFactory-engineScheduled-Thread-26) [] Polling and updating
 Async Tasks: 1 tasks, 1 tasks to poll now
 2019-11-12 12:09:06,121Z ERROR
 [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksStatusesVDSCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-26) [] Failed in
 'HSMGetAllTasksStatusesVDS' method
 2019-11-12 12:09:06,128Z ERROR
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
 (EE-ManagedThreadFactory-engineScheduled-Thread-26) [] EVENT_ID:
 VDS_BROKER_COMMAND_FAILURE(10,802), VDSM ovirt-staging-hv-02.avinity.tv
 command HSMGetAllTasksStatusesVDS failed: Invalid parameter:
 'capacity=8589934592'
 2019-11-12 12:09:06,128Z INFO
 [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
 (EE-ManagedThreadFactory-engineScheduled-Thread-26) []
 SPMAsyncTask::PollTask: Polling task '0eb09758-113f-43b9-aa67-97d2e5a57e05'
 (Parent Command 'CreateSnapshotFromTemplate', Parameters Type
 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') returned
 status 'finished', result 'cleanSuccess'.
 2019-11-12 12:09:06,131Z ERROR
 [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
 (EE-ManagedThreadFactory-engineScheduled-Thread-26) []
 BaseAsyncTask::logEndTaskFailure: Task
 '0eb09758-113f-43b9-aa67-97d2e5a57e05' (Parent Command
 'CreateSnapshotFromTemplate', Parameters Type
 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') ended with
 failure:
 -- Result: 'cleanSuccess'
 -- Message: 'VDSGenericException: VDSErrorException: Failed to
 HSMGetAllTasksStatusesVDS, error = Invalid parameter:
 'capacity=8589934592', code = 100',
 -- Exception: 'VDSGenericException: VDSErrorException: Failed to
 HSMGetAllTasksStatusesVDS, error = Invalid parameter:
 'capacity=8589934592', code = 100'
 2019-11-12 12:09:06,133Z INFO
 [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
 (EE-ManagedThreadFactory-engineScheduled-Thread-26) []
 CommandAsyncTask::endActionIfNecessary: All tasks of command
 'fb915b7e-4814-4e93-a3ef-4f40c4e3c41b' has ended -> executing
'endAction'
 2019-11-12 12:09:06,133Z INFO
 [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
 (EE-ManagedThreadFactory-engineScheduled-Thread-26) []
 CommandAsyncTask::endAction: Ending action for '1' tasks (command ID:
 'fb915b7e-4814-4e93-a3ef-4f40c4e3c41b'): calling endAction '.
 2019-11-12 12:09:06,133Z INFO
 [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
 (EE-ManagedThreadFactory-engine-Thread-274926) []
 CommandAsyncTask::endCommandAction [within thread] context: Attempting to
 endAction 'CreateSnapshotFromTemplate',
 2019-11-12 12:09:06,137Z INFO
 [org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand]
 (EE-ManagedThreadFactory-engine-Thread-274926)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Command
 [id=fb915b7e-4814-4e93-a3ef-4f40c4e3c41b]: Updating status to 'FAILED', The
 command end method logic will be executed by one of its parent commands.
 2019-11-12 12:09:06,137Z INFO
 [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
 (EE-ManagedThreadFactory-engine-Thread-274926)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa]
 CommandAsyncTask::HandleEndActionResult [within thread]: endAction for
 action type 'CreateSnapshotFromTemplate' completed, handling the result.
 2019-11-12 12:09:06,137Z INFO
 [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
 (EE-ManagedThreadFactory-engine-Thread-274926)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa]
 CommandAsyncTask::HandleEndActionResult [within thread]: endAction for
 action type 'CreateSnapshotFromTemplate' succeeded, clearing tasks.
 2019-11-12 12:09:06,137Z INFO
 [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
 (EE-ManagedThreadFactory-engine-Thread-274926)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] SPMAsyncTask::ClearAsyncTask:
 Attempting to clear task '0eb09758-113f-43b9-aa67-97d2e5a57e05'
 2019-11-12 12:09:06,137Z INFO
 [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand]
 (EE-ManagedThreadFactory-engine-Thread-274926)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] START, SPMClearTaskVDSCommand(

SPMTaskGuidBaseVDSCommandParameters:{storagePoolId='47523e52-311f-4829-a731-3d91f7885974',
 ignoreFailoverLimit='false',
 taskId='0eb09758-113f-43b9-aa67-97d2e5a57e05'}), log id: baaf58a
 2019-11-12 12:09:06,138Z INFO
 [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand]
 (EE-ManagedThreadFactory-engine-Thread-274926)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] START,
 HSMClearTaskVDSCommand(HostName = ovirt-staging-hv-02.avinity.tv,

HSMTaskGuidBaseVDSCommandParameters:{hostId='3c70c563-e32e-4352-b1e1-f6bcc41f77e2',
 taskId='0eb09758-113f-43b9-aa67-97d2e5a57e05'}), log id: 7321c57d
 2019-11-12 12:09:06,147Z INFO
 [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand]
 (EE-ManagedThreadFactory-engine-Thread-274926)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] FINISH, HSMClearTaskVDSCommand,
 return: , log id: 7321c57d
 2019-11-12 12:09:06,147Z INFO
 [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand]
 (EE-ManagedThreadFactory-engine-Thread-274926)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] FINISH, SPMClearTaskVDSCommand,
 return: , log id: baaf58a
 2019-11-12 12:09:06,152Z INFO
 [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
 (EE-ManagedThreadFactory-engine-Thread-274926)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] BaseAsyncTask::removeTaskFromDB:
 Removed task '0eb09758-113f-43b9-aa67-97d2e5a57e05' from DataBase
 2019-11-12 12:09:06,152Z INFO
 [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
 (EE-ManagedThreadFactory-engine-Thread-274926)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa]
 CommandAsyncTask::HandleEndActionResult [within thread]: Removing
 CommandMultiAsyncTasks object for entity
 'fb915b7e-4814-4e93-a3ef-4f40c4e3c41b'
 2019-11-12 12:09:08,790Z INFO
 [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback]
 (EE-ManagedThreadFactory-engineScheduled-Thread-34)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Command 'AddVm' id:
 '97d4103b-acef-4b55-a379-71a1baa04a4f' child commands
 '[fb915b7e-4814-4e93-a3ef-4f40c4e3c41b]' executions were completed, status
 'FAILED'
 2019-11-12 12:09:09,821Z ERROR [org.ovirt.engine.core.bll.AddVmCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-13)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Ending command
 'org.ovirt.engine.core.bll.AddVmCommand' with failure.
 2019-11-12 12:09:09,825Z ERROR
 [org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-13)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Ending command
 'org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand'
 with failure.
 2019-11-12 12:09:09,835Z INFO
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-13) [44f6ef0c] Running
 command: RemoveImageCommand internal: true. Entities affected :  ID:
 78bfcb57-3a0d-4a71-a3f8-480ebae37c74 Type: Storage
 2019-11-12 12:09:09,873Z INFO
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-13) [44f6ef0c] START,
 DeleteImageGroupVDSCommand(

DeleteImageGroupVDSCommandParameters:{storagePoolId='47523e52-311f-4829-a731-3d91f7885974',
 ignoreFailoverLimit='false',
 storageDomainId='78bfcb57-3a0d-4a71-a3f8-480ebae37c74',
 imageGroupId='56ddac45-1913-4d24-af97-2f969fc3fa2e', postZeros='false',
 discard='false', forceDelete='false'}), log id: 28915db3
 2019-11-12 12:09:09,907Z ERROR
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
 (EE-ManagedThreadFactory-engineScheduled-Thread-13) [44f6ef0c] EVENT_ID:
 IRS_BROKER_COMMAND_FAILURE(10,803), VDSM command DeleteImageGroupVDS
 failed: Image does not exist in domain:
 u'image=56ddac45-1913-4d24-af97-2f969fc3fa2e,
 domain=78bfcb57-3a0d-4a71-a3f8-480ebae37c74'
 2019-11-12 12:09:09,907Z ERROR
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-13) [44f6ef0c] Command
 'DeleteImageGroupVDSCommand(

DeleteImageGroupVDSCommandParameters:{storagePoolId='47523e52-311f-4829-a731-3d91f7885974',
 ignoreFailoverLimit='false',
 storageDomainId='78bfcb57-3a0d-4a71-a3f8-480ebae37c74',
 imageGroupId='56ddac45-1913-4d24-af97-2f969fc3fa2e', postZeros='false',
 discard='false', forceDelete='false'})' execution failed:
 IRSGenericException: IRSErrorException: Image does not exist in domain:
 u'image=56ddac45-1913-4d24-af97-2f969fc3fa2e,
 domain=78bfcb57-3a0d-4a71-a3f8-480ebae37c74'
 2019-11-12 12:09:09,907Z INFO
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-13) [44f6ef0c] FINISH,
 DeleteImageGroupVDSCommand, return: , log id: 28915db3
 2019-11-12 12:09:09,907Z INFO
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-13) [44f6ef0c] Disk
 '56ddac45-1913-4d24-af97-2f969fc3fa2e' doesn't exist on storage domain
 '78bfcb57-3a0d-4a71-a3f8-480ebae37c74', rolling forward
 2019-11-12 12:09:09,918Z INFO
 [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
 (EE-ManagedThreadFactory-engineScheduled-Thread-13) [44f6ef0c] Removed task
 '25bf448d-9271-4a85-ad9b-9c63012753ac' from DataBase
 2019-11-12 12:09:09,983Z INFO
 [org.ovirt.engine.core.utils.transaction.TransactionSupport]
 (EE-ManagedThreadFactory-engineScheduled-Thread-13) [] transaction rolled
 back
 2019-11-12 12:09:09,983Z INFO  [org.ovirt.engine.core.bll.AddVmCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-13) [] Lock freed to object
 'EngineLock:{exclusiveLocks='[centos-testvm-a910=VM_NAME]',
 sharedLocks='[400688e8-3c5b-4cca-9ef8-bbb6c79d54ed=DISK,
 819aecf6-c83d-4470-9915-e309390d8098=TEMPLATE]'}'
 2019-11-12 12:09:10,001Z ERROR
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
 (EE-ManagedThreadFactory-engineScheduled-Thread-13) [] EVENT_ID:
 USER_ADD_VM_FINISHED_FAILURE(60), Failed to complete VM centos-testvm-a910
 creation.
 2019-11-12 12:09:10,002Z ERROR
 [org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller]
 (EE-ManagedThreadFactory-engineScheduled-Thread-13) [] Failed invoking
 callback end method 'onFailed' for command
 '97d4103b-acef-4b55-a379-71a1baa04a4f' with exception 'null', the
callback
 is marked for end method retries
 2019-11-12 12:09:12,019Z ERROR [org.ovirt.engine.core.bll.AddVmCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-67)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Ending command
 'org.ovirt.engine.core.bll.AddVmCommand' with failure.
 2019-11-12 12:09:12,023Z ERROR
 [org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-67)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Ending command
 'org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand'
 with failure.
 2019-11-12 12:09:12,041Z INFO
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-67) [1b861e85] Running
 command: RemoveImageCommand internal: true. Entities affected :  ID:
 78bfcb57-3a0d-4a71-a3f8-480ebae37c74 Type: Storage
 2019-11-12 12:09:12,063Z INFO
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-67) [1b861e85] START,
 DeleteImageGroupVDSCommand(

DeleteImageGroupVDSCommandParameters:{storagePoolId='47523e52-311f-4829-a731-3d91f7885974',
 ignoreFailoverLimit='false',
 storageDomainId='78bfcb57-3a0d-4a71-a3f8-480ebae37c74',
 imageGroupId='56ddac45-1913-4d24-af97-2f969fc3fa2e', postZeros='false',
 discard='false', forceDelete='false'}), log id: 376f65dc
 2019-11-12 12:09:12,090Z ERROR
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
 (EE-ManagedThreadFactory-engineScheduled-Thread-67) [1b861e85] EVENT_ID:
 IRS_BROKER_COMMAND_FAILURE(10,803), VDSM command DeleteImageGroupVDS
 failed: Image does not exist in domain:
 u'image=56ddac45-1913-4d24-af97-2f969fc3fa2e,
 domain=78bfcb57-3a0d-4a71-a3f8-480ebae37c74'
 2019-11-12 12:09:12,090Z ERROR
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-67) [1b861e85] Command
 'DeleteImageGroupVDSCommand(

DeleteImageGroupVDSCommandParameters:{storagePoolId='47523e52-311f-4829-a731-3d91f7885974',
 ignoreFailoverLimit='false',
 storageDomainId='78bfcb57-3a0d-4a71-a3f8-480ebae37c74',
 imageGroupId='56ddac45-1913-4d24-af97-2f969fc3fa2e', postZeros='false',
 discard='false', forceDelete='false'})' execution failed:
 IRSGenericException: IRSErrorException: Image does not exist in domain:
 u'image=56ddac45-1913-4d24-af97-2f969fc3fa2e,
 domain=78bfcb57-3a0d-4a71-a3f8-480ebae37c74'
 2019-11-12 12:09:12,090Z INFO
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-67) [1b861e85] FINISH,
 DeleteImageGroupVDSCommand, return: , log id: 376f65dc
 2019-11-12 12:09:12,090Z INFO
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-67) [1b861e85] Disk
 '56ddac45-1913-4d24-af97-2f969fc3fa2e' doesn't exist on storage domain
 '78bfcb57-3a0d-4a71-a3f8-480ebae37c74', rolling forward
 2019-11-12 12:09:12,101Z INFO
 [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
 (EE-ManagedThreadFactory-engineScheduled-Thread-67) [1b861e85] Removed task
 'ebe0e879-8595-44db-9d64-e237afd6c8e8' from DataBase
 2019-11-12 12:09:12,157Z INFO
 [org.ovirt.engine.core.utils.transaction.TransactionSupport]
 (EE-ManagedThreadFactory-engineScheduled-Thread-67) [] transaction rolled
 back
 2019-11-12 12:09:12,157Z WARN
 [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
 (EE-ManagedThreadFactory-engineScheduled-Thread-67) [] Trying to release a
 shared lock for key: '400688e8-3c5b-4cca-9ef8-bbb6c79d54edDISK' , but lock
 does not exist
 2019-11-12 12:09:12,157Z WARN
 [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
 (EE-ManagedThreadFactory-engineScheduled-Thread-67) [] Trying to release a
 shared lock for key: '819aecf6-c83d-4470-9915-e309390d8098TEMPLATE' , but
 lock does not exist
 2019-11-12 12:09:12,157Z WARN
 [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
 (EE-ManagedThreadFactory-engineScheduled-Thread-67) [] Trying to release
 exclusive lock which does not exist, lock key: 'centos-testvm-a910VM_NAME'
 2019-11-12 12:09:12,157Z INFO  [org.ovirt.engine.core.bll.AddVmCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-67) [] Lock freed to object
 'EngineLock:{exclusiveLocks='[centos-testvm-a910=VM_NAME]',
 sharedLocks='[400688e8-3c5b-4cca-9ef8-bbb6c79d54ed=DISK,
 819aecf6-c83d-4470-9915-e309390d8098=TEMPLATE]'}'
 2019-11-12 12:09:12,172Z ERROR
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
 (EE-ManagedThreadFactory-engineScheduled-Thread-67) [] EVENT_ID:
 USER_ADD_VM_FINISHED_FAILURE(60), Failed to complete VM centos-testvm-a910
 creation.
 2019-11-12 12:09:12,172Z ERROR
 [org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller]
 (EE-ManagedThreadFactory-engineScheduled-Thread-67) [] Failed invoking
 callback end method 'onFailed' for command
 '97d4103b-acef-4b55-a379-71a1baa04a4f' with exception 'null', the
callback
 is marked for end method retries
 2019-11-12 12:09:16,192Z ERROR [org.ovirt.engine.core.bll.AddVmCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-78)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Ending command
 'org.ovirt.engine.core.bll.AddVmCommand' with failure.
 2019-11-12 12:09:16,195Z ERROR
 [org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-78)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Ending command
 'org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand'
 with failure.
 2019-11-12 12:09:16,215Z INFO
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-78) [8f3849f] Running
 command: RemoveImageCommand internal: true. Entities affected :  ID:
 78bfcb57-3a0d-4a71-a3f8-480ebae37c74 Type: Storage
 2019-11-12 12:09:16,240Z INFO
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-78) [8f3849f] START,
 DeleteImageGroupVDSCommand(

DeleteImageGroupVDSCommandParameters:{storagePoolId='47523e52-311f-4829-a731-3d91f7885974',
 ignoreFailoverLimit='false',
 storageDomainId='78bfcb57-3a0d-4a71-a3f8-480ebae37c74',
 imageGroupId='56ddac45-1913-4d24-af97-2f969fc3fa2e', postZeros='false',
 discard='false', forceDelete='false'}), log id: 75027448
 2019-11-12 12:09:16,263Z ERROR
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
 (EE-ManagedThreadFactory-engineScheduled-Thread-78) [8f3849f] EVENT_ID:
 IRS_BROKER_COMMAND_FAILURE(10,803), VDSM command DeleteImageGroupVDS
 failed: Image does not exist in domain:
 u'image=56ddac45-1913-4d24-af97-2f969fc3fa2e,
 domain=78bfcb57-3a0d-4a71-a3f8-480ebae37c74'
 2019-11-12 12:09:16,263Z ERROR
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-78) [8f3849f] Command
 'DeleteImageGroupVDSCommand(

DeleteImageGroupVDSCommandParameters:{storagePoolId='47523e52-311f-4829-a731-3d91f7885974',
 ignoreFailoverLimit='false',
 storageDomainId='78bfcb57-3a0d-4a71-a3f8-480ebae37c74',
 imageGroupId='56ddac45-1913-4d24-af97-2f969fc3fa2e', postZeros='false',
 discard='false', forceDelete='false'})' execution failed:
 IRSGenericException: IRSErrorException: Image does not exist in domain:
 u'image=56ddac45-1913-4d24-af97-2f969fc3fa2e,
 domain=78bfcb57-3a0d-4a71-a3f8-480ebae37c74'
 2019-11-12 12:09:16,263Z INFO
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-78) [8f3849f] FINISH,
 DeleteImageGroupVDSCommand, return: , log id: 75027448
 2019-11-12 12:09:16,263Z INFO
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-78) [8f3849f] Disk
 '56ddac45-1913-4d24-af97-2f969fc3fa2e' doesn't exist on storage domain
 '78bfcb57-3a0d-4a71-a3f8-480ebae37c74', rolling forward
 2019-11-12 12:09:16,274Z INFO
 [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
 (EE-ManagedThreadFactory-engineScheduled-Thread-78) [8f3849f] Removed task
 '688274fc-0596-44b5-b167-36c86b8b8e5d' from DataBase
 2019-11-12 12:09:16,341Z INFO
 [org.ovirt.engine.core.utils.transaction.TransactionSupport]
 (EE-ManagedThreadFactory-engineScheduled-Thread-78) [] transaction rolled
 back
 2019-11-12 12:09:16,341Z WARN
 [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
 (EE-ManagedThreadFactory-engineScheduled-Thread-78) [] Trying to release a
 shared lock for key: '400688e8-3c5b-4cca-9ef8-bbb6c79d54edDISK' , but lock
 does not exist
 2019-11-12 12:09:16,341Z WARN
 [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
 (EE-ManagedThreadFactory-engineScheduled-Thread-78) [] Trying to release a
 shared lock for key: '819aecf6-c83d-4470-9915-e309390d8098TEMPLATE' , but
 lock does not exist
 2019-11-12 12:09:16,342Z WARN
 [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
 (EE-ManagedThreadFactory-engineScheduled-Thread-78) [] Trying to release
 exclusive lock which does not exist, lock key: 'centos-testvm-a910VM_NAME'
 2019-11-12 12:09:16,342Z INFO  [org.ovirt.engine.core.bll.AddVmCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-78) [] Lock freed to object
 'EngineLock:{exclusiveLocks='[centos-testvm-a910=VM_NAME]',
 sharedLocks='[400688e8-3c5b-4cca-9ef8-bbb6c79d54ed=DISK,
 819aecf6-c83d-4470-9915-e309390d8098=TEMPLATE]'}'
 2019-11-12 12:09:16,357Z ERROR
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
 (EE-ManagedThreadFactory-engineScheduled-Thread-78) [] EVENT_ID:
 USER_ADD_VM_FINISHED_FAILURE(60), Failed to complete VM centos-testvm-a910
 creation.
 2019-11-12 12:09:16,357Z ERROR
 [org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller]
 (EE-ManagedThreadFactory-engineScheduled-Thread-78) [] Failed invoking
 callback end method 'onFailed' for command
 '97d4103b-acef-4b55-a379-71a1baa04a4f' with exception 'null', the
callback
 is marked for end method retries
 2019-11-12 12:09:24,376Z ERROR [org.ovirt.engine.core.bll.AddVmCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-20)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Ending command
 'org.ovirt.engine.core.bll.AddVmCommand' with failure.
 2019-11-12 12:09:24,380Z ERROR
 [org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-20)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Ending command
 'org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand'
 with failure.
 2019-11-12 12:09:24,387Z INFO
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-20) [153e3c43] Running
 command: RemoveImageCommand internal: true. Entities affected :  ID:
 78bfcb57-3a0d-4a71-a3f8-480ebae37c74 Type: Storage
 2019-11-12 12:09:24,419Z INFO
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-20) [153e3c43] START,
 DeleteImageGroupVDSCommand(

DeleteImageGroupVDSCommandParameters:{storagePoolId='47523e52-311f-4829-a731-3d91f7885974',
 ignoreFailoverLimit='false',
 storageDomainId='78bfcb57-3a0d-4a71-a3f8-480ebae37c74',
 imageGroupId='56ddac45-1913-4d24-af97-2f969fc3fa2e', postZeros='false',
 discard='false', forceDelete='false'}), log id: 29a560b1
 2019-11-12 12:09:24,444Z ERROR
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
 (EE-ManagedThreadFactory-engineScheduled-Thread-20) [153e3c43] EVENT_ID:
 IRS_BROKER_COMMAND_FAILURE(10,803), VDSM command DeleteImageGroupVDS
 failed: Image does not exist in domain:
 u'image=56ddac45-1913-4d24-af97-2f969fc3fa2e,
 domain=78bfcb57-3a0d-4a71-a3f8-480ebae37c74'
 2019-11-12 12:09:24,444Z ERROR
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-20) [153e3c43] Command
 'DeleteImageGroupVDSCommand(

DeleteImageGroupVDSCommandParameters:{storagePoolId='47523e52-311f-4829-a731-3d91f7885974',
 ignoreFailoverLimit='false',
 storageDomainId='78bfcb57-3a0d-4a71-a3f8-480ebae37c74',
 imageGroupId='56ddac45-1913-4d24-af97-2f969fc3fa2e', postZeros='false',
 discard='false', forceDelete='false'})' execution failed:
 IRSGenericException: IRSErrorException: Image does not exist in domain:
 u'image=56ddac45-1913-4d24-af97-2f969fc3fa2e,
 domain=78bfcb57-3a0d-4a71-a3f8-480ebae37c74'
 2019-11-12 12:09:24,444Z INFO
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-20) [153e3c43] FINISH,
 DeleteImageGroupVDSCommand, return: , log id: 29a560b1
 2019-11-12 12:09:24,445Z INFO
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-20) [153e3c43] Disk
 '56ddac45-1913-4d24-af97-2f969fc3fa2e' doesn't exist on storage domain
 '78bfcb57-3a0d-4a71-a3f8-480ebae37c74', rolling forward
 2019-11-12 12:09:24,455Z INFO
 [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
 (EE-ManagedThreadFactory-engineScheduled-Thread-20) [153e3c43] Removed task
 '13b2490d-09a6-4230-a5d3-7b86b536e8c3' from DataBase
 2019-11-12 12:09:24,509Z INFO
 [org.ovirt.engine.core.utils.transaction.TransactionSupport]
 (EE-ManagedThreadFactory-engineScheduled-Thread-20) [] transaction rolled
 back
 2019-11-12 12:09:24,509Z WARN
 [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
 (EE-ManagedThreadFactory-engineScheduled-Thread-20) [] Trying to release a
 shared lock for key: '400688e8-3c5b-4cca-9ef8-bbb6c79d54edDISK' , but lock
 does not exist
 2019-11-12 12:09:24,509Z WARN
 [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
 (EE-ManagedThreadFactory-engineScheduled-Thread-20) [] Trying to release a
 shared lock for key: '819aecf6-c83d-4470-9915-e309390d8098TEMPLATE' , but
 lock does not exist
 2019-11-12 12:09:24,509Z WARN
 [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
 (EE-ManagedThreadFactory-engineScheduled-Thread-20) [] Trying to release
 exclusive lock which does not exist, lock key: 'centos-testvm-a910VM_NAME'
 2019-11-12 12:09:24,509Z INFO  [org.ovirt.engine.core.bll.AddVmCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-20) [] Lock freed to object
 'EngineLock:{exclusiveLocks='[centos-testvm-a910=VM_NAME]',
 sharedLocks='[400688e8-3c5b-4cca-9ef8-bbb6c79d54ed=DISK,
 819aecf6-c83d-4470-9915-e309390d8098=TEMPLATE]'}'
 2019-11-12 12:09:24,527Z ERROR
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
 (EE-ManagedThreadFactory-engineScheduled-Thread-20) [] EVENT_ID:
 USER_ADD_VM_FINISHED_FAILURE(60), Failed to complete VM centos-testvm-a910
 creation.
 2019-11-12 12:09:24,527Z ERROR
 [org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller]
 (EE-ManagedThreadFactory-engineScheduled-Thread-20) [] Failed invoking
 callback end method 'onFailed' for command
 '97d4103b-acef-4b55-a379-71a1baa04a4f' with exception 'null', the
callback
 is marked for end method retries

 2019-11-12 12:09:34,547Z ERROR [org.ovirt.engine.core.bll.AddVmCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-33)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Ending command
 'org.ovirt.engine.core.bll.AddVmCommand' with failure.

 2019-11-12 12:09:34,551Z ERROR
 [org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-33)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Ending command
 'org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand'
 with failure.
 2019-11-12 12:09:34,568Z INFO
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-33) [28afe05e] Running
 command: RemoveImageCommand internal: true. Entities affected :  ID:
 78bfcb57-3a0d-4a71-a3f8-480ebae37c74 Type: Storage
 2019-11-12 12:09:34,591Z INFO
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-33) [28afe05e] START,
 DeleteImageGroupVDSCommand(

DeleteImageGroupVDSCommandParameters:{storagePoolId='47523e52-311f-4829-a731-3d91f7885974',
 ignoreFailoverLimit='false',
 storageDomainId='78bfcb57-3a0d-4a71-a3f8-480ebae37c74',
 imageGroupId='56ddac45-1913-4d24-af97-2f969fc3fa2e', postZeros='false',
 discard='false', forceDelete='false'}), log id: 207e5265
 2019-11-12 12:09:34,617Z ERROR
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
 (EE-ManagedThreadFactory-engineScheduled-Thread-33) [28afe05e] EVENT_ID:
 IRS_BROKER_COMMAND_FAILURE(10,803), VDSM command DeleteImageGroupVDS
 failed: Image does not exist in domain:
 u'image=56ddac45-1913-4d24-af97-2f969fc3fa2e,
 domain=78bfcb57-3a0d-4a71-a3f8-480ebae37c74'
 2019-11-12 12:09:34,617Z ERROR
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-33) [28afe05e] Command
 'DeleteImageGroupVDSCommand(

DeleteImageGroupVDSCommandParameters:{storagePoolId='47523e52-311f-4829-a731-3d91f7885974',
 ignoreFailoverLimit='false',
 storageDomainId='78bfcb57-3a0d-4a71-a3f8-480ebae37c74',
 imageGroupId='56ddac45-1913-4d24-af97-2f969fc3fa2e', postZeros='false',
 discard='false', forceDelete='false'})' execution failed:
 IRSGenericException: IRSErrorException: Image does not exist in domain:
 u'image=56ddac45-1913-4d24-af97-2f969fc3fa2e,
 domain=78bfcb57-3a0d-4a71-a3f8-480ebae37c74'
 2019-11-12 12:09:34,618Z INFO
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-33) [28afe05e] FINISH,
 DeleteImageGroupVDSCommand, return: , log id: 207e5265
 2019-11-12 12:09:34,618Z INFO
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-33) [28afe05e] Disk
 '56ddac45-1913-4d24-af97-2f969fc3fa2e' doesn't exist on storage domain
 '78bfcb57-3a0d-4a71-a3f8-480ebae37c74', rolling forward
 2019-11-12 12:09:34,629Z INFO
 [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
 (EE-ManagedThreadFactory-engineScheduled-Thread-33) [28afe05e] Removed task
 'ad58e8d8-13cc-4ae4-8785-9df252b38c1f' from DataBase
 2019-11-12 12:09:34,688Z INFO
 [org.ovirt.engine.core.utils.transaction.TransactionSupport]
 (EE-ManagedThreadFactory-engineScheduled-Thread-33) [] transaction rolled
 back
 2019-11-12 12:09:34,688Z WARN
 [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
 (EE-ManagedThreadFactory-engineScheduled-Thread-33) [] Trying to release a
 shared lock for key: '400688e8-3c5b-4cca-9ef8-bbb6c79d54edDISK' , but lock
 does not exist
 2019-11-12 12:09:34,688Z WARN
 [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
 (EE-ManagedThreadFactory-engineScheduled-Thread-33) [] Trying to release a
 shared lock for key: '819aecf6-c83d-4470-9915-e309390d8098TEMPLATE' , but
 lock does not exist
 2019-11-12 12:09:34,688Z WARN
 [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
 (EE-ManagedThreadFactory-engineScheduled-Thread-33) [] Trying to release
 exclusive lock which does not exist, lock key: 'centos-testvm-a910VM_NAME'
 2019-11-12 12:09:34,688Z INFO  [org.ovirt.engine.core.bll.AddVmCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-33) [] Lock freed to object
 'EngineLock:{exclusiveLocks='[centos-testvm-a910=VM_NAME]',
 sharedLocks='[400688e8-3c5b-4cca-9ef8-bbb6c79d54ed=DISK,
 819aecf6-c83d-4470-9915-e309390d8098=TEMPLATE]'}'
 2019-11-12 12:09:34,707Z ERROR
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
 (EE-ManagedThreadFactory-engineScheduled-Thread-33) [] EVENT_ID:
 USER_ADD_VM_FINISHED_FAILURE(60), Failed to complete VM centos-testvm-a910
 creation.
 2019-11-12 12:09:34,707Z ERROR
 [org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller]
 (EE-ManagedThreadFactory-engineScheduled-Thread-33) [] Failed invoking
 callback end method 'onFailed' for command
 '97d4103b-acef-4b55-a379-71a1baa04a4f' with exception 'null', the
callback
 is marked for end method retries

 2019-11-12 12:09:42,871Z INFO
 [org.ovirt.engine.core.bll.provider.network.SyncNetworkProviderCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-75) [58fc7cd2] Lock
 Acquired to object

'EngineLock:{exclusiveLocks='[02a18cd9-ead2-44c7-9923-8fe84d1865ee=PROVIDER]',
 sharedLocks=''}'
 2019-11-12 12:09:42,880Z INFO
 [org.ovirt.engine.core.bll.provider.network.SyncNetworkProviderCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-75) [58fc7cd2] Running
 command: SyncNetworkProviderCommand internal: true.
 2019-11-12 12:09:42,975Z INFO
 [org.ovirt.engine.core.sso.utils.AuthenticationUtils] (default task-49) []
 User admin@internal successfully logged in with scopes: ovirt-app-api
 ovirt-ext=token-info:authz-search ovirt-ext=token-info:public-authz-search
 ovirt-ext=token-info:validate ovirt-ext=token:password-access
 2019-11-12 12:09:43,096Z INFO
 [org.ovirt.engine.core.bll.provider.network.SyncNetworkProviderCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-75) [58fc7cd2] Lock freed
 to object

'EngineLock:{exclusiveLocks='[02a18cd9-ead2-44c7-9923-8fe84d1865ee=PROVIDER]',
 sharedLocks=''}'
 2019-11-12 12:09:44,729Z ERROR [org.ovirt.engine.core.bll.AddVmCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-98)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Ending command
 'org.ovirt.engine.core.bll.AddVmCommand' with failure.
 2019-11-12 12:09:44,732Z ERROR
 [org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-98)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Ending command
 'org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand'
 with failure.
 2019-11-12 12:09:44,757Z INFO
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-98) [14057067] Running
 command: RemoveImageCommand internal: true. Entities affected :  ID:
 78bfcb57-3a0d-4a71-a3f8-480ebae37c74 Type: Storage
 2019-11-12 12:09:44,780Z INFO
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-98) [14057067] START,
 DeleteImageGroupVDSCommand(

DeleteImageGroupVDSCommandParameters:{storagePoolId='47523e52-311f-4829-a731-3d91f7885974',
 ignoreFailoverLimit='false',
 storageDomainId='78bfcb57-3a0d-4a71-a3f8-480ebae37c74',
 imageGroupId='56ddac45-1913-4d24-af97-2f969fc3fa2e', postZeros='false',
 discard='false', forceDelete='false'}), log id: aaea0bc
 2019-11-12 12:09:44,804Z ERROR
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
 (EE-ManagedThreadFactory-engineScheduled-Thread-98) [14057067] EVENT_ID:
 IRS_BROKER_COMMAND_FAILURE(10,803), VDSM command DeleteImageGroupVDS
 failed: Image does not exist in domain:
 u'image=56ddac45-1913-4d24-af97-2f969fc3fa2e,
 domain=78bfcb57-3a0d-4a71-a3f8-480ebae37c74'
 2019-11-12 12:09:44,804Z ERROR
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-98) [14057067] Command
 'DeleteImageGroupVDSCommand(

DeleteImageGroupVDSCommandParameters:{storagePoolId='47523e52-311f-4829-a731-3d91f7885974',
 ignoreFailoverLimit='false',
 storageDomainId='78bfcb57-3a0d-4a71-a3f8-480ebae37c74',
 imageGroupId='56ddac45-1913-4d24-af97-2f969fc3fa2e', postZeros='false',
 discard='false', forceDelete='false'})' execution failed:
 IRSGenericException: IRSErrorException: Image does not exist in domain:
 u'image=56ddac45-1913-4d24-af97-2f969fc3fa2e,
 domain=78bfcb57-3a0d-4a71-a3f8-480ebae37c74'
 2019-11-12 12:09:44,804Z INFO
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-98) [14057067] FINISH,
 DeleteImageGroupVDSCommand, return: , log id: aaea0bc
 2019-11-12 12:09:44,804Z INFO
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-98) [14057067] Disk
 '56ddac45-1913-4d24-af97-2f969fc3fa2e' doesn't exist on storage domain
 '78bfcb57-3a0d-4a71-a3f8-480ebae37c74', rolling forward
 2019-11-12 12:09:44,815Z INFO
 [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
 (EE-ManagedThreadFactory-engineScheduled-Thread-98) [14057067] Removed task
 'c964f397-f054-4d81-98d6-052ce5a30168' from DataBase
 2019-11-12 12:09:44,887Z INFO
 [org.ovirt.engine.core.utils.transaction.TransactionSupport]
 (EE-ManagedThreadFactory-engineScheduled-Thread-98) [] transaction rolled
 back
 2019-11-12 12:09:44,887Z WARN
 [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
 (EE-ManagedThreadFactory-engineScheduled-Thread-98) [] Trying to release a
 shared lock for key: '400688e8-3c5b-4cca-9ef8-bbb6c79d54edDISK' , but lock
 does not exist
 2019-11-12 12:09:44,887Z WARN
 [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
 (EE-ManagedThreadFactory-engineScheduled-Thread-98) [] Trying to release a
 shared lock for key: '819aecf6-c83d-4470-9915-e309390d8098TEMPLATE' , but
 lock does not exist
 2019-11-12 12:09:44,888Z WARN
 [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
 (EE-ManagedThreadFactory-engineScheduled-Thread-98) [] Trying to release
 exclusive lock which does not exist, lock key: 'centos-testvm-a910VM_NAME'
 2019-11-12 12:09:44,888Z INFO  [org.ovirt.engine.core.bll.AddVmCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-98) [] Lock freed to object
 'EngineLock:{exclusiveLocks='[centos-testvm-a910=VM_NAME]',
 sharedLocks='[400688e8-3c5b-4cca-9ef8-bbb6c79d54ed=DISK,
 819aecf6-c83d-4470-9915-e309390d8098=TEMPLATE]'}'
 2019-11-12 12:09:44,903Z ERROR
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
 (EE-ManagedThreadFactory-engineScheduled-Thread-98) [] EVENT_ID:
 USER_ADD_VM_FINISHED_FAILURE(60), Failed to complete VM centos-testvm-a910
 creation.
 2019-11-12 12:09:44,924Z ERROR
 [org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller]
 (EE-ManagedThreadFactory-engineScheduled-Thread-98)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Failed invoking callback end method
 'onFailed' for command '97d4103b-acef-4b55-a379-71a1baa04a4f' with
 exception 'null', the callback is marked for end method retries but max
 number of retries have been attempted. The command will be marked as Failed.
 2019-11-12 12:09:44,925Z INFO
 [org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller]
 (EE-ManagedThreadFactory-engineScheduled-Thread-98)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Exception in invoking callback of
 command AddVm (97d4103b-acef-4b55-a379-71a1baa04a4f): NullPointerException:
 2019-11-12 12:09:44,925Z ERROR
 [org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller]
 (EE-ManagedThreadFactory-engineScheduled-Thread-98)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Error invoking callback method
 'onFailed' for 'FAILED' command
'97d4103b-acef-4b55-a379-71a1baa04a4f'
 2019-11-12 12:09:44,925Z ERROR
 [org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller]
 (EE-ManagedThreadFactory-engineScheduled-Thread-98)
 [7748e6ab-7fb3-4ec5-9b2a-c7012a050efa] Exception:
 javax.ejb.EJBTransactionRolledbackException
         at
 org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInCallerTx(CMTTxInterceptor.java:203)
 [wildfly-ejb3-17.0.1.Final.jar:17.0.1.Final]
         at
 org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:420)
 [wildfly-ejb3-17.0.1.Final.jar:17.0.1.Final]
         at
 org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:148)
 [wildfly-ejb3-17.0.1.Final.jar:17.0.1.Final]
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at
 org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:509)
         at

org.jboss.weld.module.ejb.AbstractEJBRequestScopeActivationInterceptor.aroundInvoke(AbstractEJBRequestScopeActivationInterceptor.java:81)
 [weld-ejb-3.1.1.Final.jar:3.1.1.Final]
         at

org.jboss.as.weld.ejb.EjbRequestScopeActivationInterceptor.processInvocation(EjbRequestScopeActivationInterceptor.java:89)
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at

org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41)
 [wildfly-ejb3-17.0.1.Final.jar:17.0.1.Final]
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at

org.jboss.as.ejb3.component.invocationmetrics.WaitTimeInterceptor.processInvocation(WaitTimeInterceptor.java:47)
 [wildfly-ejb3-17.0.1.Final.jar:17.0.1.Final]
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at

org.jboss.as.ejb3.security.SecurityContextInterceptor.processInvocation(SecurityContextInterceptor.java:100)
 [wildfly-ejb3-17.0.1.Final.jar:17.0.1.Final]
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at

org.jboss.as.ejb3.deployment.processors.StartupAwaitInterceptor.processInvocation(StartupAwaitInterceptor.java:22)
 [wildfly-ejb3-17.0.1.Final.jar:17.0.1.Final]
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at

org.jboss.as.ejb3.component.interceptors.ShutDownInterceptorFactory$1.processInvocation(ShutDownInterceptorFactory.java:64)
 [wildfly-ejb3-17.0.1.Final.jar:17.0.1.Final]
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at

org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:67)
 [wildfly-ejb3-17.0.1.Final.jar:17.0.1.Final]
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at

org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50)
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at

org.jboss.invocation.ContextClassLoaderInterceptor.processInvocation(ContextClassLoaderInterceptor.java:60)
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at
 org.jboss.invocation.InterceptorContext.run(InterceptorContext.java:438)
         at

org.wildfly.security.manager.WildFlySecurityManager.doChecked(WildFlySecurityManager.java:618)
         at

org.jboss.invocation.AccessCheckingInterceptor.processInvocation(AccessCheckingInterceptor.java:57)
         at
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
         at
 org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:53)
         at
 org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:198)
         at
 org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:185)
         at
 org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:81)
         at
 org.ovirt.engine.core.bll.interfaces.BackendInternal$$$view2.endAction(Unknown
 Source) [bll.jar:]
         at sun.reflect.GeneratedMethodAccessor1071.invoke(Unknown Source)
 [:1.8.0_222]
         at
 sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
 [rt.jar:1.8.0_222]
         at java.lang.reflect.Method.invoke(Method.java:498)
 [rt.jar:1.8.0_222]
         at
 org.jboss.weld.util.reflection.Reflections.invokeAndUnwrap(Reflections.java:410)
 [weld-core-impl-3.1.1.Final.jar:3.1.1.Final]
         at

org.jboss.weld.module.ejb.EnterpriseBeanProxyMethodHandler.invoke(EnterpriseBeanProxyMethodHandler.java:134)
 [weld-ejb-3.1.1.Final.jar:3.1.1.Final]
         at

org.jboss.weld.bean.proxy.EnterpriseTargetBeanInstance.invoke(EnterpriseTargetBeanInstance.java:56)
 [weld-core-impl-3.1.1.Final.jar:3.1.1.Final]
         at

org.jboss.weld.module.ejb.InjectionPointPropagatingEnterpriseTargetBeanInstance.invoke(InjectionPointPropagatingEnterpriseTargetBeanInstance.java:68)
 [weld-ejb-3.1.1.Final.jar:3.1.1.Final]
         at
 org.jboss.weld.bean.proxy.ProxyMethodHandler.invoke(ProxyMethodHandler.java:106)
 [weld-core-impl-3.1.1.Final.jar:3.1.1.Final]
         at

org.ovirt.engine.core.bll.BackendCommandObjectsHandler$BackendInternal$BackendLocal$2049259618$Proxy$_$$_Weld$EnterpriseProxy$.endAction(Unknown
 Source) [bll.jar:]
         at
 org.ovirt.engine.core.bll.VmCommand.endActionOnDisks(VmCommand.java:266)
 [bll.jar:]
         at
 org.ovirt.engine.core.bll.AddVmCommand.endWithFailure(AddVmCommand.java:1452)
 [bll.jar:]
         at
 org.ovirt.engine.core.bll.CommandBase.internalEndWithFailure(CommandBase.java:736)
 [bll.jar:]
         at
 org.ovirt.engine.core.bll.CommandBase.endActionInTransactionScope(CommandBase.java:658)
 [bll.jar:]
         at
 org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:2003)
 [bll.jar:]
         at

org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInNewTransaction(TransactionSupport.java:202)
 [utils.jar:]
         at

org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInRequired(TransactionSupport.java:137)
 [utils.jar:]
         at

org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:105)
 [utils.jar:]
         at
 org.ovirt.engine.core.bll.CommandBase.endAction(CommandBase.java:520)
 [bll.jar:]
         at

org.ovirt.engine.core.bll.ChildCommandsCallbackBase.endAction(ChildCommandsCallbackBase.java:134)
 [bll.jar:]
         at

org.ovirt.engine.core.bll.ChildCommandsCallbackBase.onFailed(ChildCommandsCallbackBase.java:174)
 [bll.jar:]
         at

org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller.endCallback(CommandCallbacksPoller.java:67)
 [bll.jar:]
         at

org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller.invokeCallbackMethodsImpl(CommandCallbacksPoller.java:166)
 [bll.jar:]
         at

org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller.invokeCallbackMethods(CommandCallbacksPoller.java:109)
 [bll.jar:]
         at
 java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
 [rt.jar:1.8.0_222]
         at
 java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
 [rt.jar:1.8.0_222]
         at

org.glassfish.enterprise.concurrent.internal.ManagedScheduledThreadPoolExecutor$ManagedScheduledFutureTask.access$201(ManagedScheduledThreadPoolExecutor.java:383)
 [javax.enterprise.concurrent-1.0.jar:]
         at

org.glassfish.enterprise.concurrent.internal.ManagedScheduledThreadPoolExecutor$ManagedScheduledFutureTask.run(ManagedScheduledThreadPoolExecutor.java:534)
 [javax.enterprise.concurrent-1.0.jar:]
         at
 java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
 [rt.jar:1.8.0_222]
         at
 java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
 [rt.jar:1.8.0_222]
         at java.lang.Thread.run(Thread.java:748) [rt.jar:1.8.0_222]
         at

org.glassfish.enterprise.concurrent.ManagedThreadFactoryImpl$ManagedThread.run(ManagedThreadFactoryImpl.java:250)
 [javax.enterprise.concurrent-1.0.jar:]
 Caused by: java.lang.NullPointerException

 Please take a look.

 -----
 kind regards/met vrindelijke groet

 Marko Vrgotic
 ActiveVideo

 On 10/10/2019, 14:23, "Vrgotic, Marko" <M.Vrgotic(a)activevideo.com&gt;
wrote:

     Hi,

     I could not check as in the meantime I destroyed my staging setup.

     Re-provisioning it now and will retest with ansible.

     On 10/10/2019, 10:51, "Vrgotic, Marko" <M.Vrgotic(a)activevideo.com&gt;
 wrote:

         Hi Ondra,

         That is true,

         But why does it work from Portal? Image ID is not differ when run
 when ansible against same template?

         I will check anyway.

         On 10/10/2019, 09:47, "Ondra Machacek" <omachace(a)redhat.com&gt;
 wrote:

             The error message is:

             Image does not exist in domain:
             u'image=d680a46a-7188-45f6-b6a7-3333f830c0b4,
             domain=935de3ac-a735-4fc8-9161-26bfd751ffc7'

             Can you please check if the image with id
             'd680a46a-7188-45f6-b6a7-3333f830c0b4' exists on storage
 domain with id
             '935de3ac-a735-4fc8-9161-26bfd751ffc7'?

             On 08/10/2019 13:05, Vrgotic, Marko wrote:
             > Dear oVirt,
             >
             > Just recently, I have upgraded oVirt staging environment
 from 4.3.4 to
             > 4.3.6 release.
             >
             > Since then I noticed the issue when creating VMs from
 template, using
             > Ansible. When creating VM from template using UI, all works
 well.
             >
             > Tests were executed with same user, with SuperUser
 priviledges.
             >
             > pip freeze
             >
             > *ansible==2.7.13*
             >
             > asn1crypto==1.0.0
             >
             > bcrypt==3.1.7
             >
             > cffi==1.12.3
             >
             > cryptography==2.7
             >
             > dnspython==1.16.0
             >
             > ipaddress==1.0.22
             >
             > Jinja2==2.10.1
             >
             > lxml==4.4.1
             >
             > MarkupSafe==1.1.1
             >
             > netaddr==0.7.19
             >
             > *ovirt-engine-sdk-python==4.3.3*
             >
             > paramiko==2.6.0
             >
             > pycparser==2.19
             >
             > pycurl==7.43.0.3
             >
             > PyNaCl==1.3.0
             >
             > PyYAML==5.1.2
             >
             > six==1.12.0
             >
             > *Logs from engine: *
             >
             > 2019-10-08 09:57:42,294Z INFO
 [org.ovirt.engine.core.bll.AddVmCommand]
             > (default task-23) [067ec489-ae6c-4871-8ab6-8296016ca1ce]
 Lock Acquired
             > to object
 'EngineLock:{exclusiveLocks='[centos-testvm-024=VM_NAME]',
             > sharedLocks='[9ac6f4ad-58d0-4a7e-b424-91f2d76abcac=TEMPLATE,
             > a7e42574-be60-4c94-94a5-cc4b30fdb16f=DISK]'}'
             >
             > 2019-10-08 09:57:42,299Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand]
             > (EE-ManagedThreadFactory-engine-Thread-43687) [390b4c75]
 FINISH,
             > HSMClearTaskVDSCommand, return: , log id: be932a0
             >
             > 2019-10-08 09:57:42,299Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand]
             > (EE-ManagedThreadFactory-engine-Thread-43687) [390b4c75]
 FINISH,
             > SPMClearTaskVDSCommand, return: , log id: 3e25883
             >
             > 2019-10-08 09:57:42,303Z INFO
             > [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
             > (EE-ManagedThreadFactory-engine-Thread-43687) [390b4c75]
             > BaseAsyncTask::removeTaskFromDB: Removed task
             > '7e1d68a2-bb49-4456-b228-db851fe6603c' from DataBase
             >
             > 2019-10-08 09:57:42,303Z INFO
             > [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
             > (EE-ManagedThreadFactory-engine-Thread-43687) [390b4c75]
             > CommandAsyncTask::HandleEndActionResult [within thread]:
 Removing
             > CommandMultiAsyncTasks object for entity
             > '4f8a6897-66e8-453b-b706-246cb8505b3e'
             >
             > 2019-10-08 09:57:42,375Z INFO
 [org.ovirt.engine.core.bll.AddVmCommand]
             > (default task-23) [067ec489-ae6c-4871-8ab6-8296016ca1ce]
 Running
             > command: AddVmCommand internal: false. Entities affected :
 ID:
             > 2e428504-c339-11e9-87ef-00163e3ec101 Type: ClusterAction
 group CREATE_VM
             > with role type USER,  ID:
 9ac6f4ad-58d0-4a7e-b424-91f2d76abcac Type:
             > VmTemplateAction group CREATE_VM with role type USER,  ID:
             > 935de3ac-a735-4fc8-9161-26bfd751ffc7 Type: StorageAction
 group
             > CREATE_DISK with role type USER
             >
             > 2019-10-08 09:57:42,488Z INFO
             > [org.ovirt.engine.core.bll.AddRngDeviceCommand] (default
 task-23)
             > [332c57d4] Running command: AddRngDeviceCommand internal:
 true. Entities
             > affected :  ID: d5719cf0-2a64-4a28-bbf2-edc1bb05b777 Type:
 VMAction
             > group EDIT_VM_PROPERTIES with role type USER
             >
             > 2019-10-08 09:57:42,498Z INFO
             > [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand]
 (default
             > task-23) [332c57d4] START, SetVmStatusVDSCommand(
             >
 SetVmStatusVDSCommandParameters:{vmId='d5719cf0-2a64-4a28-bbf2-edc1bb05b777',

             > status='ImageLocked', exitStatus='Normal'}), log id:
2fe004e2
             >
             > 2019-10-08 09:57:42,507Z INFO
             > [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand]
 (default
             > task-23) [332c57d4] FINISH, SetVmStatusVDSCommand, return: ,
 log id:
             > 2fe004e2
             >
             > 2019-10-08 09:57:42,528Z INFO
             >
 [org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand]
             > (default task-23) [067ec489-ae6c-4871-8ab6-8296016ca1ce]
 Running
             > command: CreateSnapshotFromTemplateCommand internal: true.
 Entities
             > affected :  ID: 935de3ac-a735-4fc8-9161-26bfd751ffc7 Type:
 Storage
             >
             > 2019-10-08 09:57:42,554Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.CreateVolumeVDSCommand]
             > (default task-23) [067ec489-ae6c-4871-8ab6-8296016ca1ce]
 START,
             > CreateVolumeVDSCommand(
             >

CreateVolumeVDSCommandParameters:{storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',

             > ignoreFailoverLimit='false',
             > storageDomainId='935de3ac-a735-4fc8-9161-26bfd751ffc7',
             > imageGroupId='d680a46a-7188-45f6-b6a7-3333f830c0b4',
             > imageSizeInBytes='8589934592', volumeFormat='COW',
             > newImageId='9a96018c-ce7d-4c77-a91a-30fc16c7b2ba',
 imageType='Sparse',
             > newImageDescription='', imageInitialSizeInBytes='0',
             > imageId='f9e0c4d9-81dd-4e44-8a71-33e55d4399c6',
             > sourceImageGroupId='a7e42574-be60-4c94-94a5-cc4b30fdb16f'}),
 log id:
             > 37b29c7d
             >
             > 2019-10-08 09:57:42,639Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.CreateVolumeVDSCommand]
             > (default task-23) [067ec489-ae6c-4871-8ab6-8296016ca1ce]
 FINISH,
             > CreateVolumeVDSCommand, return:
 9a96018c-ce7d-4c77-a91a-30fc16c7b2ba,
             > log id: 37b29c7d
             >
             > 2019-10-08 09:57:42,645Z INFO
             > [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (default
 task-23)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce]
 CommandAsyncTask::Adding
             > CommandMultiAsyncTasks object for command
             > 'f1665b66-2032-4a60-944e-c04beace088e'
             >
             > 2019-10-08 09:57:42,645Z INFO
             > [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (default
 task-23)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce]
             > CommandMultiAsyncTasks::attachTask: Attaching task
             > '99abc310-b44b-435c-8d43-922b782a69dc' to command
             > 'f1665b66-2032-4a60-944e-c04beace088e'.
             >
             > 2019-10-08 09:57:42,660Z INFO
             > [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (default
 task-23)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] Adding task
             > '99abc310-b44b-435c-8d43-922b782a69dc' (Parent Command
             > 'CreateSnapshotFromTemplate', Parameters Type
             >
 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters'), polling
             > hasn't started yet..
             >
             > 2019-10-08 09:57:42,934Z INFO
             >
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
             > (default task-23) [067ec489-ae6c-4871-8ab6-8296016ca1ce]
 EVENT_ID:
             > USER_ADD_VM_STARTED(37), VM centos-testvm-024 creation was
 initiated by
             > mteam@internal-authz.
             >
             > 2019-10-08 09:57:42,935Z INFO
             > [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (default
 task-23)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce]
 BaseAsyncTask::startPollingTask:
             > Starting to poll task '99abc310-b44b-435c-8d43-922b782a69dc'.
             >
             > 2019-10-08 09:57:44,384Z INFO
             >
 [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-74)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] Command 'AddVm' (id:
             > '1b65f576-365d-4e8b-a920-621516cbdaed') waiting on child
 command id:
             > 'f1665b66-2032-4a60-944e-c04beace088e'
 type:'CreateSnapshotFromTemplate'
             > to complete
             >
             > 2019-10-08 09:57:48,393Z INFO
             >
 [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-37)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] Command 'AddVm' (id:
             > '1b65f576-365d-4e8b-a920-621516cbdaed') waiting on child
 command id:
             > 'f1665b66-2032-4a60-944e-c04beace088e'
 type:'CreateSnapshotFromTemplate'
             > to complete
             >
             > 2019-10-08 09:57:48,394Z INFO
             >
 [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-37)
 [5830ba58] Command
             > 'AddDisk' id: '937776b8-b94d-4178-b9c2-e39fe42cb06c'
child
 commands
             > '[63409b0b-749a-45d5-85a5-baf55d5fa2a4]' executions were
 completed,
             > status 'SUCCEEDED'
             >
             > 2019-10-08 09:57:48,394Z INFO
             >
 [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-37)
 [5830ba58] Command
             > 'AddDisk' id: '937776b8-b94d-4178-b9c2-e39fe42cb06c'
 Updating status to
             > 'SUCCEEDED', The command end method logic will be executed
 by one of its
             > parent commands.
             >
             > 2019-10-08 09:57:48,398Z INFO
             >
 [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-37)
 [5830ba58] Command
             > 'AddDisk' id: 'fdc5571e-a925-4400-a54a-a1ef1dcd23ae'
child
 commands
             > '[54d68cae-6585-4351-aaf7-fe687905acf4]' executions were
 completed,
             > status 'SUCCEEDED'
             >
             > 2019-10-08 09:57:48,398Z INFO
             >
 [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-37)
 [5830ba58] Command
             > 'AddDisk' id: 'fdc5571e-a925-4400-a54a-a1ef1dcd23ae'
 Updating status to
             > 'SUCCEEDED', The command end method logic will be executed
 by one of its
             > parent commands.
             >
             > 2019-10-08 09:57:48,401Z INFO
             >
 [org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-37)
 [5830ba58] Command
             > 'ProcessOvfUpdateForStorageDomain' (id:
             > 'bdcdae3a-00f6-4176-9d27-0bb0f04912a3') waiting on child
 command id:
             > 'b4050edd-62a7-4b83-a4ab-a21c83bf3ade'
             > type:'CreateOvfVolumeForStorageDomain' to complete
             >
             > 2019-10-08 09:57:48,402Z INFO
             >
 [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-37)
 [5830ba58] Command
             > 'CreateOvfVolumeForStorageDomain' id:
             > 'b4050edd-62a7-4b83-a4ab-a21c83bf3ade' child commands
             > '[937776b8-b94d-4178-b9c2-e39fe42cb06c]' executions were
 completed,
             > status 'SUCCEEDED'
             >
             > 2019-10-08 09:57:48,404Z INFO
             >
 [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-37)
 [5830ba58] Command
             > 'CreateOvfVolumeForStorageDomain' id:
             > '5fd47910-95b2-4387-8e26-ea2c80b078a0' child commands
             > '[fdc5571e-a925-4400-a54a-a1ef1dcd23ae]' executions were
 completed,
             > status 'SUCCEEDED'
             >
             > 2019-10-08 09:57:49,416Z INFO
             >
 [org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-3)
 [390b4c75] Command
             > 'ProcessOvfUpdateForStorageDomain' id:
             > '849bd16c-00d2-48a2-8fde-631af0dfe1b5' child commands
             > '[a80a3657-c813-42c1-a43d-27442b5dc238,
             > 4f8a6897-66e8-453b-b706-246cb8505b3e]' executions were
 completed, status
             > 'SUCCEEDED'
             >
             > 2019-10-08 09:57:49,418Z INFO
             >
 [org.ovirt.engine.core.bll.storage.ovfstore.CreateOvfVolumeForStorageDomainCommand]

             > (EE-ManagedThreadFactory-engineScheduled-Thread-3)
 [5830ba58] Ending
             > command
             >

'org.ovirt.engine.core.bll.storage.ovfstore.CreateOvfVolumeForStorageDomainCommand'

             > successfully.
             >
             > 2019-10-08 09:57:49,420Z INFO
             > [org.ovirt.engine.core.bll.storage.disk.AddDiskCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-3)
 [5830ba58] Ending
             > command
 'org.ovirt.engine.core.bll.storage.disk.AddDiskCommand'
             > successfully.
             >
             > 2019-10-08 09:57:49,426Z INFO
             >
 [org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-3)
             > [5830ba58] Ending command
             >
 'org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand'
 successfully.
             >
             > 2019-10-08 09:57:49,427Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-3)
 [5830ba58] START,
             > GetImageInfoVDSCommand(
             >

GetImageInfoVDSCommandParameters:{storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',

             > ignoreFailoverLimit='false',
             > storageDomainId='935de3ac-a735-4fc8-9161-26bfd751ffc7',
             > imageGroupId='66984468-bbfa-4941-8c6b-df72a6d920f2',
             > imageId='7db55211-041a-40c8-85a6-a2ff1bd182ce'}), log id:
 6c02a73c
             >
             > 2019-10-08 09:57:49,429Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-3)
 [5830ba58] START,
             > GetVolumeInfoVDSCommand(HostName =
 ovirt-staging-hv-01.avinity.tv,
             >

GetVolumeInfoVDSCommandParameters:{hostId='bfc4c379-bd5f-45be-98a4-f0469dc3817c',

             > storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',
             > storageDomainId='935de3ac-a735-4fc8-9161-26bfd751ffc7',
             > imageGroupId='66984468-bbfa-4941-8c6b-df72a6d920f2',
             > imageId='7db55211-041a-40c8-85a6-a2ff1bd182ce'}), log id:
 2e131d9d
             >
             > 2019-10-08 09:57:49,456Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-3)
 [5830ba58] FINISH,
             > GetVolumeInfoVDSCommand, return:
             >
 org.ovirt.engine.core.common.businessentities.storage.DiskImage@26992dd0,
 log
             > id: 2e131d9d
             >
             > 2019-10-08 09:57:49,456Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-3)
 [5830ba58] FINISH,
             > GetImageInfoVDSCommand, return:
             >
 org.ovirt.engine.core.common.businessentities.storage.DiskImage@26992dd0,
 log
             > id: 6c02a73c
             >
             > 2019-10-08 09:57:49,464Z WARN
             > [org.ovirt.engine.core.bll.storage.disk.AddDiskCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-3) [] VM is
 null - no
             > unlocking
             >
             > 2019-10-08 09:57:49,481Z INFO
             >
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-3) []
 EVENT_ID:
             > USER_ADD_DISK_FINISHED_SUCCESS(2,021), The disk 'OVF_STORE'
 was
             > successfully added.
             >
             > 2019-10-08 09:57:49,490Z INFO
             >
 [org.ovirt.engine.core.bll.storage.ovfstore.CreateOvfVolumeForStorageDomainCommand]

             > (EE-ManagedThreadFactory-engineScheduled-Thread-3)
 [5830ba58] Ending
             > command
             >

'org.ovirt.engine.core.bll.storage.ovfstore.CreateOvfVolumeForStorageDomainCommand'

             > successfully.
             >
             > 2019-10-08 09:57:49,491Z INFO
             > [org.ovirt.engine.core.bll.storage.disk.AddDiskCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-3)
 [5830ba58] Ending
             > command
 'org.ovirt.engine.core.bll.storage.disk.AddDiskCommand'
             > successfully.
             >
             > 2019-10-08 09:57:49,495Z INFO
             >
 [org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand]
 (EE-ManagedThreadFactory-engineScheduled-Thread-3)
             > [5830ba58] Ending command
             >
 'org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand'
 successfully.
             >
             > 2019-10-08 09:57:49,497Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-3)
 [5830ba58] START,
             > GetImageInfoVDSCommand(
             >

GetImageInfoVDSCommandParameters:{storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',

             > ignoreFailoverLimit='false',
             > storageDomainId='935de3ac-a735-4fc8-9161-26bfd751ffc7',
             > imageGroupId='ee208fd3-e05c-4ba8-aba9-fa521d69a870',
             > imageId='484c1eed-b03a-4fa0-a12d-0087294dec86'}), log id:
 7a3dad31
             >
             > 2019-10-08 09:57:49,498Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-3)
 [5830ba58] START,
             > GetVolumeInfoVDSCommand(HostName =
 ovirt-staging-hv-01.avinity.tv,
             >

GetVolumeInfoVDSCommandParameters:{hostId='bfc4c379-bd5f-45be-98a4-f0469dc3817c',

             > storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',
             > storageDomainId='935de3ac-a735-4fc8-9161-26bfd751ffc7',
             > imageGroupId='ee208fd3-e05c-4ba8-aba9-fa521d69a870',
             > imageId='484c1eed-b03a-4fa0-a12d-0087294dec86'}), log id:
 5b85c4a5
             >
             > 2019-10-08 09:57:49,522Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-3)
 [5830ba58] FINISH,
             > GetVolumeInfoVDSCommand, return:
             >
 org.ovirt.engine.core.common.businessentities.storage.DiskImage@13555e4,
             > log id: 5b85c4a5
             >
             > 2019-10-08 09:57:49,522Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-3)
 [5830ba58] FINISH,
             > GetImageInfoVDSCommand, return:
             >
 org.ovirt.engine.core.common.businessentities.storage.DiskImage@13555e4,
             > log id: 7a3dad31
             >
             > 2019-10-08 09:57:49,529Z WARN
             > [org.ovirt.engine.core.bll.storage.disk.AddDiskCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-3) [] VM is
 null - no
             > unlocking
             >
             > 2019-10-08 09:57:49,540Z INFO
             >
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-3) []
 EVENT_ID:
             > USER_ADD_DISK_FINISHED_SUCCESS(2,021), The disk 'OVF_STORE'
 was
             > successfully added.
             >
             > 2019-10-08 09:57:50,552Z INFO
             >
 [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStorageDomainCommand]

             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [390b4c75] Ending
             > command
             >

'org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStorageDomainCommand'

             > successfully.
             >
             > 2019-10-08 09:57:50,575Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] START,
             > SetVolumeDescriptionVDSCommand(
             >

SetVolumeDescriptionVDSCommandParameters:{storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',

             > ignoreFailoverLimit='false',
             > storageDomainId='935de3ac-a735-4fc8-9161-26bfd751ffc7',
             > imageGroupId='ee208fd3-e05c-4ba8-aba9-fa521d69a870',
             > imageId='484c1eed-b03a-4fa0-a12d-0087294dec86'}), log id:
 7e77a9a8
             >
             > 2019-10-08 09:57:50,576Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] --
             > executeIrsBrokerCommand: calling 'setVolumeDescription',
 parameters:
             >
             > 2019-10-08 09:57:50,576Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > spUUID=2e3b3484-c339-11e9-8d02-00163e3ec101
             >
             > 2019-10-08 09:57:50,576Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > sdUUID=935de3ac-a735-4fc8-9161-26bfd751ffc7
             >
             > 2019-10-08 09:57:50,576Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > imageGroupGUID=ee208fd3-e05c-4ba8-aba9-fa521d69a870
             >
             > 2019-10-08 09:57:50,576Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > volUUID=484c1eed-b03a-4fa0-a12d-0087294dec86
             >
             > 2019-10-08 09:57:50,576Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > description={"Updated":false,"Last
Updated":null,"Storage
             >

Domains":[{"uuid":"935de3ac-a735-4fc8-9161-26bfd751ffc7"}],"Disk
             > Description":"OVF_STORE"}
             >
             > 2019-10-08 09:57:50,606Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] FINISH,
             > SetVolumeDescriptionVDSCommand, return: , log id: 7e77a9a8
             >
             > 2019-10-08 09:57:50,614Z INFO
             >
 [org.ovirt.engine.core.bll.storage.ovfstore.UploadStreamCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] Lock
             > Acquired to object 'EngineLock:{exclusiveLocks='',
             >
 sharedLocks='[bfc4c379-bd5f-45be-98a4-f0469dc3817c=VDS_EXECUTION]'}'
             >
             > 2019-10-08 09:57:50,623Z INFO
             >
 [org.ovirt.engine.core.bll.storage.ovfstore.UploadStreamCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] Running
             > command: UploadStreamCommand internal: true. Entities
 affected :  ID:
             > 935de3ac-a735-4fc8-9161-26bfd751ffc7 Type: Storage
             >
             > 2019-10-08 09:57:50,624Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] START,
             > UploadStreamVDSCommand(HostName =
 ovirt-staging-hv-01.avinity.tv,
             >

UploadStreamVDSCommandParameters:{hostId='bfc4c379-bd5f-45be-98a4-f0469dc3817c'}),

             > log id: 531a4a4a
             >
             > 2019-10-08 09:57:50,625Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] --
             > executeVdsBrokerCommand, parameters:
             >
             > 2019-10-08 09:57:50,625Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > spUUID=2e3b3484-c339-11e9-8d02-00163e3ec101
             >
             > 2019-10-08 09:57:50,625Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > sdUUID=935de3ac-a735-4fc8-9161-26bfd751ffc7
             >
             > 2019-10-08 09:57:50,625Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > imageGUID=ee208fd3-e05c-4ba8-aba9-fa521d69a870
             >
             > 2019-10-08 09:57:50,625Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > volUUID=484c1eed-b03a-4fa0-a12d-0087294dec86
             >
             > 2019-10-08 09:57:50,625Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++ size=30720
             >
             > 2019-10-08 09:57:51,331Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] FINISH,
             > UploadStreamVDSCommand, return: , log id: 531a4a4a
             >
             > 2019-10-08 09:57:51,342Z INFO
             > [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58]
             > CommandAsyncTask::Adding CommandMultiAsyncTasks object for
 command
             > 'c11f4d3c-f85b-43c5-986f-a517aed66d7e'
             >
             > 2019-10-08 09:57:51,342Z INFO
             > [org.ovirt.engine.core.bll.CommandMultiAsyncTasks]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58]
             > CommandMultiAsyncTasks::attachTask: Attaching task
             > 'e5d00ff5-eaa2-48e9-ab10-d6d06a01e89d' to command
             > 'c11f4d3c-f85b-43c5-986f-a517aed66d7e'.
             >
             > 2019-10-08 09:57:51,351Z INFO
             > [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] Adding
             > task 'e5d00ff5-eaa2-48e9-ab10-d6d06a01e89d' (Parent Command
             > 'UploadStream', Parameters Type
             >
 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters'), polling
             > hasn't started yet..
             >
             > 2019-10-08 09:57:51,355Z INFO
             > [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58]
             > BaseAsyncTask::startPollingTask: Starting to poll task
             > 'e5d00ff5-eaa2-48e9-ab10-d6d06a01e89d'.
             >
             > 2019-10-08 09:57:51,358Z INFO
             >
 [org.ovirt.engine.core.bll.storage.ovfstore.UploadStreamCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] Lock freed
             > to object 'EngineLock:{exclusiveLocks='',
             >
 sharedLocks='[bfc4c379-bd5f-45be-98a4-f0469dc3817c=VDS_EXECUTION]'}'
             >
             > 2019-10-08 09:57:51,360Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] START,
             > SetVolumeDescriptionVDSCommand(
             >

SetVolumeDescriptionVDSCommandParameters:{storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',

             > ignoreFailoverLimit='false',
             > storageDomainId='935de3ac-a735-4fc8-9161-26bfd751ffc7',
             > imageGroupId='ee208fd3-e05c-4ba8-aba9-fa521d69a870',
             > imageId='484c1eed-b03a-4fa0-a12d-0087294dec86'}), log id:
 238169f6
             >
             > 2019-10-08 09:57:51,360Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] --
             > executeIrsBrokerCommand: calling 'setVolumeDescription',
 parameters:
             >
             > 2019-10-08 09:57:51,360Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > spUUID=2e3b3484-c339-11e9-8d02-00163e3ec101
             >
             > 2019-10-08 09:57:51,360Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > sdUUID=935de3ac-a735-4fc8-9161-26bfd751ffc7
             >
             > 2019-10-08 09:57:51,360Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > imageGroupGUID=ee208fd3-e05c-4ba8-aba9-fa521d69a870
             >
             > 2019-10-08 09:57:51,360Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > volUUID=484c1eed-b03a-4fa0-a12d-0087294dec86
             >
             > 2019-10-08 09:57:51,360Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > description={"Updated":true,"Size":30720,"Last
Updated":"Tue
 Oct 08
             > 09:57:50 UTC 2019","Storage
             >

Domains":[{"uuid":"935de3ac-a735-4fc8-9161-26bfd751ffc7"}],"Disk
             > Description":"OVF_STORE"}
             >
             > 2019-10-08 09:57:51,389Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] FINISH,
             > SetVolumeDescriptionVDSCommand, return: , log id: 238169f6
             >
             > 2019-10-08 09:57:51,596Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] START,
             > SetVolumeDescriptionVDSCommand(
             >

SetVolumeDescriptionVDSCommandParameters:{storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',

             > ignoreFailoverLimit='false',
             > storageDomainId='935de3ac-a735-4fc8-9161-26bfd751ffc7',
             > imageGroupId='66984468-bbfa-4941-8c6b-df72a6d920f2',
             > imageId='7db55211-041a-40c8-85a6-a2ff1bd182ce'}), log id:
 59bde70f
             >
             > 2019-10-08 09:57:51,627Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] --
             > executeIrsBrokerCommand: calling 'setVolumeDescription',
 parameters:
             >
             > 2019-10-08 09:57:51,627Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > spUUID=2e3b3484-c339-11e9-8d02-00163e3ec101
             >
             > 2019-10-08 09:57:51,627Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > sdUUID=935de3ac-a735-4fc8-9161-26bfd751ffc7
             >
             > 2019-10-08 09:57:51,627Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > imageGroupGUID=66984468-bbfa-4941-8c6b-df72a6d920f2
             >
             > 2019-10-08 09:57:51,627Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > volUUID=7db55211-041a-40c8-85a6-a2ff1bd182ce
             >
             > 2019-10-08 09:57:51,627Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > description={"Updated":false,"Last
Updated":null,"Storage
             >

Domains":[{"uuid":"935de3ac-a735-4fc8-9161-26bfd751ffc7"}],"Disk
             > Description":"OVF_STORE"}
             >
             > 2019-10-08 09:57:51,664Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] FINISH,
             > SetVolumeDescriptionVDSCommand, return: , log id: 59bde70f
             >
             > 2019-10-08 09:57:51,672Z INFO
             >
 [org.ovirt.engine.core.bll.storage.ovfstore.UploadStreamCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] Lock
             > Acquired to object 'EngineLock:{exclusiveLocks='',
             >
 sharedLocks='[bfc4c379-bd5f-45be-98a4-f0469dc3817c=VDS_EXECUTION]'}'
             >
             > 2019-10-08 09:57:51,683Z INFO
             >
 [org.ovirt.engine.core.bll.storage.ovfstore.UploadStreamCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] Running
             > command: UploadStreamCommand internal: true. Entities
 affected :  ID:
             > 935de3ac-a735-4fc8-9161-26bfd751ffc7 Type: Storage
             >
             > 2019-10-08 09:57:51,684Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] START,
             > UploadStreamVDSCommand(HostName =
 ovirt-staging-hv-01.avinity.tv,
             >

UploadStreamVDSCommandParameters:{hostId='bfc4c379-bd5f-45be-98a4-f0469dc3817c'}),

             > log id: 1cb812b6
             >
             > 2019-10-08 09:57:51,684Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] --
             > executeVdsBrokerCommand, parameters:
             >
             > 2019-10-08 09:57:51,684Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > spUUID=2e3b3484-c339-11e9-8d02-00163e3ec101
             >
             > 2019-10-08 09:57:51,684Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > sdUUID=935de3ac-a735-4fc8-9161-26bfd751ffc7
             >
             > 2019-10-08 09:57:51,684Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > imageGUID=66984468-bbfa-4941-8c6b-df72a6d920f2
             >
             > 2019-10-08 09:57:51,684Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > volUUID=7db55211-041a-40c8-85a6-a2ff1bd182ce
             >
             > 2019-10-08 09:57:51,684Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++ size=30720
             >
             > 2019-10-08 09:57:51,933Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.UploadStreamVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] FINISH,
             > UploadStreamVDSCommand, return: , log id: 1cb812b6
             >
             > 2019-10-08 09:57:51,943Z INFO
             > [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58]
             > CommandAsyncTask::Adding CommandMultiAsyncTasks object for
 command
             > 'd0c07988-0e43-4e6d-b86c-e736b28d4537'
             >
             > 2019-10-08 09:57:51,943Z INFO
             > [org.ovirt.engine.core.bll.CommandMultiAsyncTasks]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58]
             > CommandMultiAsyncTasks::attachTask: Attaching task
             > 'ba88edd0-8188-472e-8f59-aa3f90e63181' to command
             > 'd0c07988-0e43-4e6d-b86c-e736b28d4537'.
             >
             > 2019-10-08 09:57:51,952Z INFO
             > [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] Adding
             > task 'ba88edd0-8188-472e-8f59-aa3f90e63181' (Parent Command
             > 'UploadStream', Parameters Type
             >
 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters'), polling
             > hasn't started yet..
             >
             > 2019-10-08 09:57:51,958Z INFO
             > [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58]
             > BaseAsyncTask::startPollingTask: Starting to poll task
             > 'ba88edd0-8188-472e-8f59-aa3f90e63181'.
             >
             > 2019-10-08 09:57:51,961Z INFO
             >
 [org.ovirt.engine.core.bll.storage.ovfstore.UploadStreamCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] Lock freed
             > to object 'EngineLock:{exclusiveLocks='',
             >
 sharedLocks='[bfc4c379-bd5f-45be-98a4-f0469dc3817c=VDS_EXECUTION]'}'
             >
             > 2019-10-08 09:57:51,962Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] START,
             > SetVolumeDescriptionVDSCommand(
             >

SetVolumeDescriptionVDSCommandParameters:{storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',

             > ignoreFailoverLimit='false',
             > storageDomainId='935de3ac-a735-4fc8-9161-26bfd751ffc7',
             > imageGroupId='66984468-bbfa-4941-8c6b-df72a6d920f2',
             > imageId='7db55211-041a-40c8-85a6-a2ff1bd182ce'}), log id:
 213b3075
             >
             > 2019-10-08 09:57:51,962Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] --
             > executeIrsBrokerCommand: calling 'setVolumeDescription',
 parameters:
             >
             > 2019-10-08 09:57:51,962Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > spUUID=2e3b3484-c339-11e9-8d02-00163e3ec101
             >
             > 2019-10-08 09:57:51,962Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > sdUUID=935de3ac-a735-4fc8-9161-26bfd751ffc7
             >
             > 2019-10-08 09:57:51,962Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > imageGroupGUID=66984468-bbfa-4941-8c6b-df72a6d920f2
             >
             > 2019-10-08 09:57:51,962Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > volUUID=7db55211-041a-40c8-85a6-a2ff1bd182ce
             >
             > 2019-10-08 09:57:51,962Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] ++
             > description={"Updated":true,"Size":30720,"Last
Updated":"Tue
 Oct 08
             > 09:57:50 UTC 2019","Storage
             >

Domains":[{"uuid":"935de3ac-a735-4fc8-9161-26bfd751ffc7"}],"Disk
             > Description":"OVF_STORE"}
             >
             > 2019-10-08 09:57:52,028Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SetVolumeDescriptionVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-1)
 [5830ba58] FINISH,
             > SetVolumeDescriptionVDSCommand, return: , log id: 213b3075
             >
             > 2019-10-08 09:57:52,224Z INFO
             > [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-91) []
 Polling and
             > updating Async Tasks: 7 tasks, 3 tasks to poll now
             >
             > 2019-10-08 09:57:52,228Z ERROR
             >
 [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksStatusesVDSCommand]

             > (EE-ManagedThreadFactory-engineScheduled-Thread-91) []
 Failed in
             > 'HSMGetAllTasksStatusesVDS' method
             >
             > 2019-10-08 09:57:52,235Z ERROR
             >
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-91) []
 EVENT_ID:
             > VDS_BROKER_COMMAND_FAILURE(10,802), VDSM
 ovirt-staging-hv-01.avinity.tv
             > command HSMGetAllTasksStatusesVDS failed: Invalid parameter:
 'size=16777216'
             >
             > 2019-10-08 09:57:52,235Z INFO
             > [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-91) []
             > SPMAsyncTask::PollTask: Polling task
             > '99abc310-b44b-435c-8d43-922b782a69dc' (Parent Command
             > 'CreateSnapshotFromTemplate', Parameters Type
             >
 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') returned
             > status 'finished', result 'cleanSuccess'.
             >
             > 2019-10-08 09:57:52,240Z ERROR
             > [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-91) []
             > BaseAsyncTask::logEndTaskFailure: Task
             > '99abc310-b44b-435c-8d43-922b782a69dc' (Parent Command
             > 'CreateSnapshotFromTemplate', Parameters Type
             >
 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') ended
             > with failure:
             >
             > -- Result: 'cleanSuccess'
             >
             > -- Message: 'VDSGenericException: VDSErrorException: Failed
 to
             > HSMGetAllTasksStatusesVDS, error = Invalid parameter:
 'size=16777216',
             > code = 100',
             >
             > -- Exception: 'VDSGenericException: VDSErrorException:
 Failed to
             > HSMGetAllTasksStatusesVDS, error = Invalid parameter:
 'size=16777216',
             > code = 100'
             >
             > 2019-10-08 09:57:52,241Z INFO
             > [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-91) []
             > CommandAsyncTask::endActionIfNecessary: All tasks of command
             > 'f1665b66-2032-4a60-944e-c04beace088e' has ended ->
 executing 'endAction'
             >
             > 2019-10-08 09:57:52,241Z INFO
             > [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-91) []
             > CommandAsyncTask::endAction: Ending action for '1' tasks
 (command ID:
             > 'f1665b66-2032-4a60-944e-c04beace088e'): calling endAction
'.
             >
             > 2019-10-08 09:57:52,241Z INFO
             > [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-91) []
             > SPMAsyncTask::PollTask: Polling task
             > 'e5d00ff5-eaa2-48e9-ab10-d6d06a01e89d' (Parent Command
 'UploadStream',
             > Parameters Type
             >
 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') returned
             > status 'finished', result 'success'.
             >
             > 2019-10-08 09:57:52,241Z INFO
             > [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-91) []
             > BaseAsyncTask::onTaskEndSuccess: Task
             > 'e5d00ff5-eaa2-48e9-ab10-d6d06a01e89d' (Parent Command
 'UploadStream',
             > Parameters Type
             >
 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') ended
             > successfully.
             >
             > 2019-10-08 09:57:52,241Z INFO
             > [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
             > (EE-ManagedThreadFactory-engine-Thread-43693) []
             > CommandAsyncTask::endCommandAction [within thread] context:
 Attempting
             > to endAction 'CreateSnapshotFromTemplate',
             >
             > 2019-10-08 09:57:52,242Z INFO
             > [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-91) []
             > CommandAsyncTask::endActionIfNecessary: All tasks of command
             > 'c11f4d3c-f85b-43c5-986f-a517aed66d7e' has ended ->
 executing 'endAction'
             >
             > 2019-10-08 09:57:52,243Z INFO
             > [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-91) []
             > CommandAsyncTask::endAction: Ending action for '1' tasks
 (command ID:
             > 'c11f4d3c-f85b-43c5-986f-a517aed66d7e'): calling endAction
'.
             >
             > 2019-10-08 09:57:52,243Z INFO
             > [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-91) []
             > SPMAsyncTask::PollTask: Polling task
             > 'ba88edd0-8188-472e-8f59-aa3f90e63181' (Parent Command
 'UploadStream',
             > Parameters Type
             >
 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') returned
             > status 'finished', result 'success'.
             >
             > 2019-10-08 09:57:52,243Z INFO
             > [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-91) []
             > BaseAsyncTask::onTaskEndSuccess: Task
             > 'ba88edd0-8188-472e-8f59-aa3f90e63181' (Parent Command
 'UploadStream',
             > Parameters Type
             >
 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') ended
             > successfully.
             >
             > 2019-10-08 09:57:52,243Z INFO
             > [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
             > (EE-ManagedThreadFactory-engine-Thread-43694) []
             > CommandAsyncTask::endCommandAction [within thread] context:
 Attempting
             > to endAction 'UploadStream',
             >
             > 2019-10-08 09:57:52,244Z INFO
             > [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-91) []
             > CommandAsyncTask::endActionIfNecessary: All tasks of command
             > 'd0c07988-0e43-4e6d-b86c-e736b28d4537' has ended ->
 executing 'endAction'
             >
             > 2019-10-08 09:57:52,244Z INFO
             > [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-91) []
             > CommandAsyncTask::endAction: Ending action for '1' tasks
 (command ID:
             > 'd0c07988-0e43-4e6d-b86c-e736b28d4537'): calling endAction
'.
             >
             > 2019-10-08 09:57:52,245Z INFO
             > [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
             > (EE-ManagedThreadFactory-engine-Thread-43695) []
             > CommandAsyncTask::endCommandAction [within thread] context:
 Attempting
             > to endAction 'UploadStream',
             >
             > 2019-10-08 09:57:52,245Z INFO
             >
 [org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand]
             > (EE-ManagedThreadFactory-engine-Thread-43693)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] Command
             > [id=f1665b66-2032-4a60-944e-c04beace088e]: Updating status
 to 'FAILED',
             > The command end method logic will be executed by one of its
 parent commands.
             >
             > 2019-10-08 09:57:52,247Z INFO
             > [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
             > (EE-ManagedThreadFactory-engine-Thread-43693)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce]
             > CommandAsyncTask::HandleEndActionResult [within thread]:
 endAction for
             > action type 'CreateSnapshotFromTemplate' completed, handling
 the result.
             >
             > 2019-10-08 09:57:52,247Z INFO
             > [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
             > (EE-ManagedThreadFactory-engine-Thread-43693)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce]
             > CommandAsyncTask::HandleEndActionResult [within thread]:
 endAction for
             > action type 'CreateSnapshotFromTemplate' succeeded, clearing
 tasks.
             >
             > 2019-10-08 09:57:52,247Z INFO
             > [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
             > (EE-ManagedThreadFactory-engine-Thread-43693)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce]
 SPMAsyncTask::ClearAsyncTask:
             > Attempting to clear task
 '99abc310-b44b-435c-8d43-922b782a69dc'
             >
             > 2019-10-08 09:57:52,247Z INFO
             >
 [org.ovirt.engine.core.bll.storage.ovfstore.UploadStreamCommand]
             > (EE-ManagedThreadFactory-engine-Thread-43695) [5830ba58]
 Ending command
             >
 'org.ovirt.engine.core.bll.storage.ovfstore.UploadStreamCommand'
             > successfully.
             >
             > 2019-10-08 09:57:52,247Z INFO
             >
 [org.ovirt.engine.core.bll.storage.ovfstore.UploadStreamCommand]
             > (EE-ManagedThreadFactory-engine-Thread-43694) [5830ba58]
 Ending command
             >
 'org.ovirt.engine.core.bll.storage.ovfstore.UploadStreamCommand'
             > successfully.
             >
             > 2019-10-08 09:57:52,247Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand]
             > (EE-ManagedThreadFactory-engine-Thread-43693)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] START,
 SPMClearTaskVDSCommand(
             >

SPMTaskGuidBaseVDSCommandParameters:{storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',

             > ignoreFailoverLimit='false',
             > taskId='99abc310-b44b-435c-8d43-922b782a69dc'}), log id:
 66a4e67
             >
             > 2019-10-08 09:57:52,247Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand]
             > (EE-ManagedThreadFactory-engine-Thread-43693)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] START,
             > HSMClearTaskVDSCommand(HostName =
 ovirt-staging-hv-01.avinity.tv,
             >

HSMTaskGuidBaseVDSCommandParameters:{hostId='bfc4c379-bd5f-45be-98a4-f0469dc3817c',

             > taskId='99abc310-b44b-435c-8d43-922b782a69dc'}), log id:
 7ec6d8b8
             >
             > 2019-10-08 09:57:52,249Z INFO
             > [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
             > (EE-ManagedThreadFactory-engine-Thread-43694) [5830ba58]
             > CommandAsyncTask::HandleEndActionResult [within thread]:
 endAction for
             > action type 'UploadStream' completed, handling the result.
             >
             > 2019-10-08 09:57:52,249Z INFO
             > [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
             > (EE-ManagedThreadFactory-engine-Thread-43694) [5830ba58]
             > CommandAsyncTask::HandleEndActionResult [within thread]:
 endAction for
             > action type 'UploadStream' succeeded, clearing tasks.
             >
             > 2019-10-08 09:57:52,249Z INFO
             > [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
             > (EE-ManagedThreadFactory-engine-Thread-43694) [5830ba58]
             > SPMAsyncTask::ClearAsyncTask: Attempting to clear task
             > 'e5d00ff5-eaa2-48e9-ab10-d6d06a01e89d'
             >
             > 2019-10-08 09:57:52,249Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand]
             > (EE-ManagedThreadFactory-engine-Thread-43694) [5830ba58]
 START,
             > SPMClearTaskVDSCommand(
             >

SPMTaskGuidBaseVDSCommandParameters:{storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',

             > ignoreFailoverLimit='false',
             > taskId='e5d00ff5-eaa2-48e9-ab10-d6d06a01e89d'}), log id:
 356927d
             >
             > 2019-10-08 09:57:52,250Z INFO
             > [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
             > (EE-ManagedThreadFactory-engine-Thread-43695) [5830ba58]
             > CommandAsyncTask::HandleEndActionResult [within thread]:
 endAction for
             > action type 'UploadStream' completed, handling the result.
             >
             > 2019-10-08 09:57:52,250Z INFO
             > [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
             > (EE-ManagedThreadFactory-engine-Thread-43695) [5830ba58]
             > CommandAsyncTask::HandleEndActionResult [within thread]:
 endAction for
             > action type 'UploadStream' succeeded, clearing tasks.
             >
             > 2019-10-08 09:57:52,250Z INFO
             > [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
             > (EE-ManagedThreadFactory-engine-Thread-43695) [5830ba58]
             > SPMAsyncTask::ClearAsyncTask: Attempting to clear task
             > 'ba88edd0-8188-472e-8f59-aa3f90e63181'
             >
             > 2019-10-08 09:57:52,251Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand]
             > (EE-ManagedThreadFactory-engine-Thread-43695) [5830ba58]
 START,
             > SPMClearTaskVDSCommand(
             >

SPMTaskGuidBaseVDSCommandParameters:{storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',

             > ignoreFailoverLimit='false',
             > taskId='ba88edd0-8188-472e-8f59-aa3f90e63181'}), log id:
 75844e34
             >
             > 2019-10-08 09:57:52,259Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand]
             > (EE-ManagedThreadFactory-engine-Thread-43693)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] FINISH,
 HSMClearTaskVDSCommand,
             > return: , log id: 7ec6d8b8
             >
             > 2019-10-08 09:57:52,259Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand]
             > (EE-ManagedThreadFactory-engine-Thread-43693)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] FINISH,
 SPMClearTaskVDSCommand,
             > return: , log id: 66a4e67
             >
             > 2019-10-08 09:57:52,260Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand]
             > (EE-ManagedThreadFactory-engine-Thread-43694) [5830ba58]
 START,
             > HSMClearTaskVDSCommand(HostName =
 ovirt-staging-hv-01.avinity.tv,
             >

HSMTaskGuidBaseVDSCommandParameters:{hostId='bfc4c379-bd5f-45be-98a4-f0469dc3817c',

             > taskId='e5d00ff5-eaa2-48e9-ab10-d6d06a01e89d'}), log id:
 3b239769
             >
             > 2019-10-08 09:57:52,263Z INFO
             > [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
             > (EE-ManagedThreadFactory-engine-Thread-43693)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce]
 BaseAsyncTask::removeTaskFromDB:
             > Removed task '99abc310-b44b-435c-8d43-922b782a69dc' from
 DataBase
             >
             > 2019-10-08 09:57:52,263Z INFO
             > [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
             > (EE-ManagedThreadFactory-engine-Thread-43693)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce]
             > CommandAsyncTask::HandleEndActionResult [within thread]:
 Removing
             > CommandMultiAsyncTasks object for entity
             > 'f1665b66-2032-4a60-944e-c04beace088e'
             >
             > 2019-10-08 09:57:52,297Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand]
             > (EE-ManagedThreadFactory-engine-Thread-43694) [5830ba58]
 FINISH,
             > HSMClearTaskVDSCommand, return: , log id: 3b239769
             >
             > 2019-10-08 09:57:52,297Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand]
             > (EE-ManagedThreadFactory-engine-Thread-43694) [5830ba58]
 FINISH,
             > SPMClearTaskVDSCommand, return: , log id: 356927d
             >
             > 2019-10-08 09:57:52,298Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand]
             > (EE-ManagedThreadFactory-engine-Thread-43695) [5830ba58]
 START,
             > HSMClearTaskVDSCommand(HostName =
 ovirt-staging-hv-01.avinity.tv,
             >

HSMTaskGuidBaseVDSCommandParameters:{hostId='bfc4c379-bd5f-45be-98a4-f0469dc3817c',

             > taskId='ba88edd0-8188-472e-8f59-aa3f90e63181'}), log id:
 32d44e1
             >
             > 2019-10-08 09:57:52,301Z INFO
             > [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
             > (EE-ManagedThreadFactory-engine-Thread-43694) [5830ba58]
             > BaseAsyncTask::removeTaskFromDB: Removed task
             > 'e5d00ff5-eaa2-48e9-ab10-d6d06a01e89d' from DataBase
             >
             > 2019-10-08 09:57:52,301Z INFO
             > [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
             > (EE-ManagedThreadFactory-engine-Thread-43694) [5830ba58]
             > CommandAsyncTask::HandleEndActionResult [within thread]:
 Removing
             > CommandMultiAsyncTasks object for entity
             > 'c11f4d3c-f85b-43c5-986f-a517aed66d7e'
             >
             > 2019-10-08 09:57:52,314Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand]
             > (EE-ManagedThreadFactory-engine-Thread-43695) [5830ba58]
 FINISH,
             > HSMClearTaskVDSCommand, return: , log id: 32d44e1
             >
             > 2019-10-08 09:57:52,314Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand]
             > (EE-ManagedThreadFactory-engine-Thread-43695) [5830ba58]
 FINISH,
             > SPMClearTaskVDSCommand, return: , log id: 75844e34
             >
             > 2019-10-08 09:57:52,317Z INFO
             > [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
             > (EE-ManagedThreadFactory-engine-Thread-43695) [5830ba58]
             > BaseAsyncTask::removeTaskFromDB: Removed task
             > 'ba88edd0-8188-472e-8f59-aa3f90e63181' from DataBase
             >
             > 2019-10-08 09:57:52,317Z INFO
             > [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
             > (EE-ManagedThreadFactory-engine-Thread-43695) [5830ba58]
             > CommandAsyncTask::HandleEndActionResult [within thread]:
 Removing
             > CommandMultiAsyncTasks object for entity
             > 'd0c07988-0e43-4e6d-b86c-e736b28d4537'
             >
             > 2019-10-08 09:57:54,039Z INFO
             >
 [org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-44)
 [5830ba58] Command
             > 'ProcessOvfUpdateForStorageDomain' id:
             > 'bdcdae3a-00f6-4176-9d27-0bb0f04912a3' child commands
             > '[b4050edd-62a7-4b83-a4ab-a21c83bf3ade,
             > 5fd47910-95b2-4387-8e26-ea2c80b078a0,
             > c11f4d3c-f85b-43c5-986f-a517aed66d7e,
             > d0c07988-0e43-4e6d-b86c-e736b28d4537]' executions were
 completed, status
             > 'SUCCEEDED'
             >
             > 2019-10-08 09:57:55,046Z INFO
             >
 [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStorageDomainCommand]

             > (EE-ManagedThreadFactory-engineScheduled-Thread-40)
 [5830ba58] Ending
             > command
             >

'org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStorageDomainCommand'

             > successfully.
             >
             > 2019-10-08 09:57:58,077Z INFO
             >
 [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-76)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] Command 'AddVm' id:
             > '1b65f576-365d-4e8b-a920-621516cbdaed' child commands
             > '[f1665b66-2032-4a60-944e-c04beace088e]' executions were
 completed,
             > status 'FAILED'
             >
             > 2019-10-08 09:57:59,094Z ERROR
 [org.ovirt.engine.core.bll.AddVmCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-27)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] Ending command
             > 'org.ovirt.engine.core.bll.AddVmCommand' with failure.
             >
             > 2019-10-08 09:57:59,099Z ERROR
             >
 [org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-27)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] Ending command
             >
 'org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand'
             > with failure.
             >
             > 2019-10-08 09:57:59,104Z INFO
             >
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-27)
 [5794ed92] Running
             > command: RemoveImageCommand internal: true. Entities
 affected :  ID:
             > 935de3ac-a735-4fc8-9161-26bfd751ffc7 Type: Storage
             >
             > 2019-10-08 09:57:59,126Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-27)
 [5794ed92] START,
             > DeleteImageGroupVDSCommand(
             >

DeleteImageGroupVDSCommandParameters:{storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',

             > ignoreFailoverLimit='false',
             > storageDomainId='935de3ac-a735-4fc8-9161-26bfd751ffc7',
             > imageGroupId='d680a46a-7188-45f6-b6a7-3333f830c0b4',
 postZeros='false',
             > discard='false', forceDelete='false'}), log id:
69861a78
             >
             > 2019-10-08 09:57:59,139Z ERROR
             >
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-27)
 [5794ed92] EVENT_ID:
             > IRS_BROKER_COMMAND_FAILURE(10,803), VDSM command
 DeleteImageGroupVDS
             > failed: Image does not exist in domain:
             > u'image=d680a46a-7188-45f6-b6a7-3333f830c0b4,
             > domain=935de3ac-a735-4fc8-9161-26bfd751ffc7'
             >
             > 2019-10-08 09:57:59,139Z ERROR
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-27)
 [5794ed92] Command
             > 'DeleteImageGroupVDSCommand(
             >

DeleteImageGroupVDSCommandParameters:{storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',

             > ignoreFailoverLimit='false',
             > storageDomainId='935de3ac-a735-4fc8-9161-26bfd751ffc7',
             > imageGroupId='d680a46a-7188-45f6-b6a7-3333f830c0b4',
 postZeros='false',
             > discard='false', forceDelete='false'})' execution
failed:
             > IRSGenericException: IRSErrorException: Image does not exist
 in domain:
             > u'image=d680a46a-7188-45f6-b6a7-3333f830c0b4,
             > domain=935de3ac-a735-4fc8-9161-26bfd751ffc7'
             >
             > 2019-10-08 09:57:59,139Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-27)
 [5794ed92] FINISH,
             > DeleteImageGroupVDSCommand, return: , log id: 69861a78
             >
             > 2019-10-08 09:57:59,139Z INFO
             >
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-27)
 [5794ed92] Disk
             > 'd680a46a-7188-45f6-b6a7-3333f830c0b4' doesn't exist on
 storage domain
             > '935de3ac-a735-4fc8-9161-26bfd751ffc7', rolling forward
             >
             > 2019-10-08 09:57:59,149Z INFO
             > [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-27)
 [5794ed92] Removed
             > task 'b4c4c723-4ba1-4d5a-86e6-24658a293a04' from DataBase
             >
             > 2019-10-08 09:57:59,220Z INFO
             > [org.ovirt.engine.core.utils.transaction.TransactionSupport]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-27) []
 transaction
             > rolled back
             >
             > 2019-10-08 09:57:59,220Z INFO
 [org.ovirt.engine.core.bll.AddVmCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-27) [] Lock
 freed to
             > object
 'EngineLock:{exclusiveLocks='[centos-testvm-024=VM_NAME]',
             > sharedLocks='[9ac6f4ad-58d0-4a7e-b424-91f2d76abcac=TEMPLATE,
             > a7e42574-be60-4c94-94a5-cc4b30fdb16f=DISK]'}'
             >
             > 2019-10-08 09:57:59,233Z ERROR
             >
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-27) []
 EVENT_ID:
             > USER_ADD_VM_FINISHED_FAILURE(60), Failed to complete VM
             > centos-testvm-024 creation.
             >
             > 2019-10-08 09:57:59,233Z ERROR
             > [org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-27) []
 Failed invoking
             > callback end method 'onFailed' for command
             > '1b65f576-365d-4e8b-a920-621516cbdaed' with exception
 'null', the
             > callback is marked for end method retries
             >
             > 2019-10-08 09:58:01,252Z ERROR
 [org.ovirt.engine.core.bll.AddVmCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-85)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] Ending command
             > 'org.ovirt.engine.core.bll.AddVmCommand' with failure.
             >
             > 2019-10-08 09:58:01,257Z ERROR
             >
 [org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-85)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] Ending command
             >
 'org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand'
             > with failure.
             >
             > 2019-10-08 09:58:01,270Z INFO
             >
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-85)
 [19251787] Running
             > command: RemoveImageCommand internal: true. Entities
 affected :  ID:
             > 935de3ac-a735-4fc8-9161-26bfd751ffc7 Type: Storage
             >
             > 2019-10-08 09:58:01,308Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-85)
 [19251787] START,
             > DeleteImageGroupVDSCommand(
             >

DeleteImageGroupVDSCommandParameters:{storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',

             > ignoreFailoverLimit='false',
             > storageDomainId='935de3ac-a735-4fc8-9161-26bfd751ffc7',
             > imageGroupId='d680a46a-7188-45f6-b6a7-3333f830c0b4',
 postZeros='false',
             > discard='false', forceDelete='false'}), log id: 35fec55
             >
             > 2019-10-08 09:58:01,324Z ERROR
             >
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-85)
 [19251787] EVENT_ID:
             > IRS_BROKER_COMMAND_FAILURE(10,803), VDSM command
 DeleteImageGroupVDS
             > failed: Image does not exist in domain:
             > u'image=d680a46a-7188-45f6-b6a7-3333f830c0b4,
             > domain=935de3ac-a735-4fc8-9161-26bfd751ffc7'
             >
             > 2019-10-08 09:58:01,324Z ERROR
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-85)
 [19251787] Command
             > 'DeleteImageGroupVDSCommand(
             >

DeleteImageGroupVDSCommandParameters:{storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',

             > ignoreFailoverLimit='false',
             > storageDomainId='935de3ac-a735-4fc8-9161-26bfd751ffc7',
             > imageGroupId='d680a46a-7188-45f6-b6a7-3333f830c0b4',
 postZeros='false',
             > discard='false', forceDelete='false'})' execution
failed:
             > IRSGenericException: IRSErrorException: Image does not exist
 in domain:
             > u'image=d680a46a-7188-45f6-b6a7-3333f830c0b4,
             > domain=935de3ac-a735-4fc8-9161-26bfd751ffc7'
             >
             > 2019-10-08 09:58:01,324Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-85)
 [19251787] FINISH,
             > DeleteImageGroupVDSCommand, return: , log id: 35fec55
             >
             > 2019-10-08 09:58:01,324Z INFO
             >
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-85)
 [19251787] Disk
             > 'd680a46a-7188-45f6-b6a7-3333f830c0b4' doesn't exist on
 storage domain
             > '935de3ac-a735-4fc8-9161-26bfd751ffc7', rolling forward
             >
             > 2019-10-08 09:58:01,335Z INFO
             > [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-85)
 [19251787] Removed
             > task '6ed0a844-5363-4afd-bffc-a80b6ace69ea' from DataBase
             >
             > 2019-10-08 09:58:01,395Z INFO
             > [org.ovirt.engine.core.utils.transaction.TransactionSupport]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-85) []
 transaction
             > rolled back
             >
             > 2019-10-08 09:58:01,395Z WARN
             > [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-85) []
 Trying to release
             > a shared lock for key:
 '9ac6f4ad-58d0-4a7e-b424-91f2d76abcacTEMPLATE' ,
             > but lock does not exist
             >
             > 2019-10-08 09:58:01,395Z WARN
             > [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-85) []
 Trying to release
             > a shared lock for key:
 'a7e42574-be60-4c94-94a5-cc4b30fdb16fDISK' , but
             > lock does not exist
             >
             > 2019-10-08 09:58:01,395Z WARN
             > [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-85) []
 Trying to release
             > exclusive lock which does not exist, lock key:
 'centos-testvm-024VM_NAME'
             >
             > 2019-10-08 09:58:01,395Z INFO
 [org.ovirt.engine.core.bll.AddVmCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-85) [] Lock
 freed to
             > object
 'EngineLock:{exclusiveLocks='[centos-testvm-024=VM_NAME]',
             > sharedLocks='[9ac6f4ad-58d0-4a7e-b424-91f2d76abcac=TEMPLATE,
             > a7e42574-be60-4c94-94a5-cc4b30fdb16f=DISK]'}'
             >
             > 2019-10-08 09:58:01,407Z ERROR
             >
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-85) []
 EVENT_ID:
             > USER_ADD_VM_FINISHED_FAILURE(60), Failed to complete VM
             > centos-testvm-024 creation.
             >
             > 2019-10-08 09:58:01,407Z ERROR
             > [org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-85) []
 Failed invoking
             > callback end method 'onFailed' for command
             > '1b65f576-365d-4e8b-a920-621516cbdaed' with exception
 'null', the
             > callback is marked for end method retries
             >
             > 2019-10-08 09:58:05,427Z ERROR
 [org.ovirt.engine.core.bll.AddVmCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-17)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] Ending command
             > 'org.ovirt.engine.core.bll.AddVmCommand' with failure.
             >
             > 2019-10-08 09:58:05,430Z ERROR
             >
 [org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-17)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] Ending command
             >
 'org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand'
             > with failure.
             >
             > 2019-10-08 09:58:05,437Z INFO
             >
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-17)
 [cf91364] Running
             > command: RemoveImageCommand internal: true. Entities
 affected :  ID:
             > 935de3ac-a735-4fc8-9161-26bfd751ffc7 Type: Storage
             >
             > 2019-10-08 09:58:05,452Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-17)
 [cf91364] START,
             > DeleteImageGroupVDSCommand(
             >

DeleteImageGroupVDSCommandParameters:{storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',

             > ignoreFailoverLimit='false',
             > storageDomainId='935de3ac-a735-4fc8-9161-26bfd751ffc7',
             > imageGroupId='d680a46a-7188-45f6-b6a7-3333f830c0b4',
 postZeros='false',
             > discard='false', forceDelete='false'}), log id:
6ac726b6
             >
             > 2019-10-08 09:58:05,466Z ERROR
             >
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-17)
 [cf91364] EVENT_ID:
             > IRS_BROKER_COMMAND_FAILURE(10,803), VDSM command
 DeleteImageGroupVDS
             > failed: Image does not exist in domain:
             > u'image=d680a46a-7188-45f6-b6a7-3333f830c0b4,
             > domain=935de3ac-a735-4fc8-9161-26bfd751ffc7'
             >
             > 2019-10-08 09:58:05,466Z ERROR
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-17)
 [cf91364] Command
             > 'DeleteImageGroupVDSCommand(
             >

DeleteImageGroupVDSCommandParameters:{storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',

             > ignoreFailoverLimit='false',
             > storageDomainId='935de3ac-a735-4fc8-9161-26bfd751ffc7',
             > imageGroupId='d680a46a-7188-45f6-b6a7-3333f830c0b4',
 postZeros='false',
             > discard='false', forceDelete='false'})' execution
failed:
             > IRSGenericException: IRSErrorException: Image does not exist
 in domain:
             > u'image=d680a46a-7188-45f6-b6a7-3333f830c0b4,
             > domain=935de3ac-a735-4fc8-9161-26bfd751ffc7'
             >
             > 2019-10-08 09:58:05,466Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-17)
 [cf91364] FINISH,
             > DeleteImageGroupVDSCommand, return: , log id: 6ac726b6
             >
             > 2019-10-08 09:58:05,467Z INFO
             >
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-17)
 [cf91364] Disk
             > 'd680a46a-7188-45f6-b6a7-3333f830c0b4' doesn't exist on
 storage domain
             > '935de3ac-a735-4fc8-9161-26bfd751ffc7', rolling forward
             >
             > 2019-10-08 09:58:05,477Z INFO
             > [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-17)
 [cf91364] Removed
             > task '31bd7a11-203d-4459-ac54-3c398c85771d' from DataBase
             >
             > 2019-10-08 09:58:05,490Z INFO
             > [org.ovirt.engine.core.utils.transaction.TransactionSupport]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-17) []
 transaction
             > rolled back
             >
             > 2019-10-08 09:58:05,490Z WARN
             > [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-17) []
 Trying to release
             > a shared lock for key:
 '9ac6f4ad-58d0-4a7e-b424-91f2d76abcacTEMPLATE' ,
             > but lock does not exist
             >
             > 2019-10-08 09:58:05,490Z WARN
             > [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-17) []
 Trying to release
             > a shared lock for key:
 'a7e42574-be60-4c94-94a5-cc4b30fdb16fDISK' , but
             > lock does not exist
             >
             > 2019-10-08 09:58:05,490Z WARN
             > [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-17) []
 Trying to release
             > exclusive lock which does not exist, lock key:
 'centos-testvm-024VM_NAME'
             >
             > 2019-10-08 09:58:05,490Z INFO
 [org.ovirt.engine.core.bll.AddVmCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-17) [] Lock
 freed to
             > object
 'EngineLock:{exclusiveLocks='[centos-testvm-024=VM_NAME]',
             > sharedLocks='[9ac6f4ad-58d0-4a7e-b424-91f2d76abcac=TEMPLATE,
             > a7e42574-be60-4c94-94a5-cc4b30fdb16f=DISK]'}'
             >
             > 2019-10-08 09:58:05,503Z ERROR
             >
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-17) []
 EVENT_ID:
             > USER_ADD_VM_FINISHED_FAILURE(60), Failed to complete VM
             > centos-testvm-024 creation.
             >
             > 2019-10-08 09:58:05,503Z ERROR
             > [org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-17) []
 Failed invoking
             > callback end method 'onFailed' for command
             > '1b65f576-365d-4e8b-a920-621516cbdaed' with exception
 'null', the
             > callback is marked for end method retries
             >
             > 2019-10-08 09:58:13,523Z ERROR
 [org.ovirt.engine.core.bll.AddVmCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-35)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] Ending command
             > 'org.ovirt.engine.core.bll.AddVmCommand' with failure.
             >
             > 2019-10-08 09:58:13,527Z ERROR
             >
 [org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-35)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] Ending command
             >
 'org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand'
             > with failure.
             >
             > 2019-10-08 09:58:13,533Z INFO
             >
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-35)
 [56103b5a] Running
             > command: RemoveImageCommand internal: true. Entities
 affected :  ID:
             > 935de3ac-a735-4fc8-9161-26bfd751ffc7 Type: Storage
             >
             > 2019-10-08 09:58:13,548Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-35)
 [56103b5a] START,
             > DeleteImageGroupVDSCommand(
             >

DeleteImageGroupVDSCommandParameters:{storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',

             > ignoreFailoverLimit='false',
             > storageDomainId='935de3ac-a735-4fc8-9161-26bfd751ffc7',
             > imageGroupId='d680a46a-7188-45f6-b6a7-3333f830c0b4',
 postZeros='false',
             > discard='false', forceDelete='false'}), log id:
33164673
             >
             > 2019-10-08 09:58:13,560Z ERROR
             >
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-35)
 [56103b5a] EVENT_ID:
             > IRS_BROKER_COMMAND_FAILURE(10,803), VDSM command
 DeleteImageGroupVDS
             > failed: Image does not exist in domain:
             > u'image=d680a46a-7188-45f6-b6a7-3333f830c0b4,
             > domain=935de3ac-a735-4fc8-9161-26bfd751ffc7'
             >
             > 2019-10-08 09:58:13,560Z ERROR
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-35)
 [56103b5a] Command
             > 'DeleteImageGroupVDSCommand(
             >

DeleteImageGroupVDSCommandParameters:{storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',

             > ignoreFailoverLimit='false',
             > storageDomainId='935de3ac-a735-4fc8-9161-26bfd751ffc7',
             > imageGroupId='d680a46a-7188-45f6-b6a7-3333f830c0b4',
 postZeros='false',
             > discard='false', forceDelete='false'})' execution
failed:
             > IRSGenericException: IRSErrorException: Image does not exist
 in domain:
             > u'image=d680a46a-7188-45f6-b6a7-3333f830c0b4,
             > domain=935de3ac-a735-4fc8-9161-26bfd751ffc7'
             >
             > 2019-10-08 09:58:13,560Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-35)
 [56103b5a] FINISH,
             > DeleteImageGroupVDSCommand, return: , log id: 33164673
             >
             > 2019-10-08 09:58:13,560Z INFO
             >
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-35)
 [56103b5a] Disk
             > 'd680a46a-7188-45f6-b6a7-3333f830c0b4' doesn't exist on
 storage domain
             > '935de3ac-a735-4fc8-9161-26bfd751ffc7', rolling forward
             >
             > 2019-10-08 09:58:13,570Z INFO
             > [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-35)
 [56103b5a] Removed
             > task 'ce6882de-2d89-4298-bdcf-86d621a54084' from DataBase
             >
             > 2019-10-08 09:58:13,583Z INFO
             > [org.ovirt.engine.core.utils.transaction.TransactionSupport]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-35) []
 transaction
             > rolled back
             >
             > 2019-10-08 09:58:13,583Z WARN
             > [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-35) []
 Trying to release
             > a shared lock for key:
 '9ac6f4ad-58d0-4a7e-b424-91f2d76abcacTEMPLATE' ,
             > but lock does not exist
             >
             > 2019-10-08 09:58:13,583Z WARN
             > [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-35) []
 Trying to release
             > a shared lock for key:
 'a7e42574-be60-4c94-94a5-cc4b30fdb16fDISK' , but
             > lock does not exist
             >
             > 2019-10-08 09:58:13,583Z WARN
             > [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-35) []
 Trying to release
             > exclusive lock which does not exist, lock key:
 'centos-testvm-024VM_NAME'
             >
             > 2019-10-08 09:58:13,583Z INFO
 [org.ovirt.engine.core.bll.AddVmCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-35) [] Lock
 freed to
             > object
 'EngineLock:{exclusiveLocks='[centos-testvm-024=VM_NAME]',
             > sharedLocks='[9ac6f4ad-58d0-4a7e-b424-91f2d76abcac=TEMPLATE,
             > a7e42574-be60-4c94-94a5-cc4b30fdb16f=DISK]'}'
             >
             > 2019-10-08 09:58:13,595Z ERROR
             >
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-35) []
 EVENT_ID:
             > USER_ADD_VM_FINISHED_FAILURE(60), Failed to complete VM
             > centos-testvm-024 creation.
             >
             > 2019-10-08 09:58:13,595Z ERROR
             > [org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-35) []
 Failed invoking
             > callback end method 'onFailed' for command
             > '1b65f576-365d-4e8b-a920-621516cbdaed' with exception
 'null', the
             > callback is marked for end method retries
             >
             > 2019-10-08 09:58:23,616Z ERROR
 [org.ovirt.engine.core.bll.AddVmCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-71)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] Ending command
             > 'org.ovirt.engine.core.bll.AddVmCommand' with failure.
             >
             > 2019-10-08 09:58:23,620Z ERROR
             >
 [org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-71)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] Ending command
             >
 'org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand'
             > with failure.
             >
             > 2019-10-08 09:58:23,627Z INFO
             >
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-71)
 [48f2f9bd] Running
             > command: RemoveImageCommand internal: true. Entities
 affected :  ID:
             > 935de3ac-a735-4fc8-9161-26bfd751ffc7 Type: Storage
             >
             > 2019-10-08 09:58:23,642Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-71)
 [48f2f9bd] START,
             > DeleteImageGroupVDSCommand(
             >

DeleteImageGroupVDSCommandParameters:{storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',

             > ignoreFailoverLimit='false',
             > storageDomainId='935de3ac-a735-4fc8-9161-26bfd751ffc7',
             > imageGroupId='d680a46a-7188-45f6-b6a7-3333f830c0b4',
 postZeros='false',
             > discard='false', forceDelete='false'}), log id:
7346a5bd
             >
             > 2019-10-08 09:58:23,663Z ERROR
             >
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-71)
 [48f2f9bd] EVENT_ID:
             > IRS_BROKER_COMMAND_FAILURE(10,803), VDSM command
 DeleteImageGroupVDS
             > failed: Image does not exist in domain:
             > u'image=d680a46a-7188-45f6-b6a7-3333f830c0b4,
             > domain=935de3ac-a735-4fc8-9161-26bfd751ffc7'
             >
             > 2019-10-08 09:58:23,663Z ERROR
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-71)
 [48f2f9bd] Command
             > 'DeleteImageGroupVDSCommand(
             >

DeleteImageGroupVDSCommandParameters:{storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',

             > ignoreFailoverLimit='false',
             > storageDomainId='935de3ac-a735-4fc8-9161-26bfd751ffc7',
             > imageGroupId='d680a46a-7188-45f6-b6a7-3333f830c0b4',
 postZeros='false',
             > discard='false', forceDelete='false'})' execution
failed:
             > IRSGenericException: IRSErrorException: Image does not exist
 in domain:
             > u'image=d680a46a-7188-45f6-b6a7-3333f830c0b4,
             > domain=935de3ac-a735-4fc8-9161-26bfd751ffc7'
             >
             > 2019-10-08 09:58:23,663Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-71)
 [48f2f9bd] FINISH,
             > DeleteImageGroupVDSCommand, return: , log id: 7346a5bd
             >
             > 2019-10-08 09:58:23,663Z INFO
             >
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-71)
 [48f2f9bd] Disk
             > 'd680a46a-7188-45f6-b6a7-3333f830c0b4' doesn't exist on
 storage domain
             > '935de3ac-a735-4fc8-9161-26bfd751ffc7', rolling forward
             >
             > 2019-10-08 09:58:23,682Z INFO
             > [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-71)
 [48f2f9bd] Removed
             > task '8483357d-6d4f-418e-9751-70fcb69685cb' from DataBase
             >
             > 2019-10-08 09:58:23,695Z INFO
             > [org.ovirt.engine.core.utils.transaction.TransactionSupport]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-71) []
 transaction
             > rolled back
             >
             > 2019-10-08 09:58:23,695Z WARN
             > [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-71) []
 Trying to release
             > a shared lock for key:
 '9ac6f4ad-58d0-4a7e-b424-91f2d76abcacTEMPLATE' ,
             > but lock does not exist
             >
             > 2019-10-08 09:58:23,695Z WARN
             > [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-71) []
 Trying to release
             > a shared lock for key:
 'a7e42574-be60-4c94-94a5-cc4b30fdb16fDISK' , but
             > lock does not exist
             >
             > 2019-10-08 09:58:23,695Z WARN
             > [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-71) []
 Trying to release
             > exclusive lock which does not exist, lock key:
 'centos-testvm-024VM_NAME'
             >
             > 2019-10-08 09:58:23,695Z INFO
 [org.ovirt.engine.core.bll.AddVmCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-71) [] Lock
 freed to
             > object
 'EngineLock:{exclusiveLocks='[centos-testvm-024=VM_NAME]',
             > sharedLocks='[9ac6f4ad-58d0-4a7e-b424-91f2d76abcac=TEMPLATE,
             > a7e42574-be60-4c94-94a5-cc4b30fdb16f=DISK]'}'
             >
             > 2019-10-08 09:58:23,706Z ERROR
             >
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-71) []
 EVENT_ID:
             > USER_ADD_VM_FINISHED_FAILURE(60), Failed to complete VM
             > centos-testvm-024 creation.
             >
             > 2019-10-08 09:58:23,706Z ERROR
             > [org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-71) []
 Failed invoking
             > callback end method 'onFailed' for command
             > '1b65f576-365d-4e8b-a920-621516cbdaed' with exception
 'null', the
             > callback is marked for end method retries
             >
             > 2019-10-08 09:58:33,725Z ERROR
 [org.ovirt.engine.core.bll.AddVmCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-63)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] Ending command
             > 'org.ovirt.engine.core.bll.AddVmCommand' with failure.
             >
             > 2019-10-08 09:58:33,729Z ERROR
             >
 [org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-63)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] Ending command
             >
 'org.ovirt.engine.core.bll.snapshots.CreateSnapshotFromTemplateCommand'
             > with failure.
             >
             > 2019-10-08 09:58:33,736Z INFO
             >
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-63)
 [3eea0406] Running
             > command: RemoveImageCommand internal: true. Entities
 affected :  ID:
             > 935de3ac-a735-4fc8-9161-26bfd751ffc7 Type: Storage
             >
             > 2019-10-08 09:58:33,752Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-63)
 [3eea0406] START,
             > DeleteImageGroupVDSCommand(
             >

DeleteImageGroupVDSCommandParameters:{storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',

             > ignoreFailoverLimit='false',
             > storageDomainId='935de3ac-a735-4fc8-9161-26bfd751ffc7',
             > imageGroupId='d680a46a-7188-45f6-b6a7-3333f830c0b4',
 postZeros='false',
             > discard='false', forceDelete='false'}), log id:
6d120001
             >
             > 2019-10-08 09:58:33,767Z ERROR
             >
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-63)
 [3eea0406] EVENT_ID:
             > IRS_BROKER_COMMAND_FAILURE(10,803), VDSM command
 DeleteImageGroupVDS
             > failed: Image does not exist in domain:
             > u'image=d680a46a-7188-45f6-b6a7-3333f830c0b4,
             > domain=935de3ac-a735-4fc8-9161-26bfd751ffc7'
             >
             > 2019-10-08 09:58:33,767Z ERROR
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-63)
 [3eea0406] Command
             > 'DeleteImageGroupVDSCommand(
             >

DeleteImageGroupVDSCommandParameters:{storagePoolId='2e3b3484-c339-11e9-8d02-00163e3ec101',

             > ignoreFailoverLimit='false',
             > storageDomainId='935de3ac-a735-4fc8-9161-26bfd751ffc7',
             > imageGroupId='d680a46a-7188-45f6-b6a7-3333f830c0b4',
 postZeros='false',
             > discard='false', forceDelete='false'})' execution
failed:
             > IRSGenericException: IRSErrorException: Image does not exist
 in domain:
             > u'image=d680a46a-7188-45f6-b6a7-3333f830c0b4,
             > domain=935de3ac-a735-4fc8-9161-26bfd751ffc7'
             >
             > 2019-10-08 09:58:33,767Z INFO
             >
 [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-63)
 [3eea0406] FINISH,
             > DeleteImageGroupVDSCommand, return: , log id: 6d120001
             >
             > 2019-10-08 09:58:33,767Z INFO
             >
 [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-63)
 [3eea0406] Disk
             > 'd680a46a-7188-45f6-b6a7-3333f830c0b4' doesn't exist on
 storage domain
             > '935de3ac-a735-4fc8-9161-26bfd751ffc7', rolling forward
             >
             > 2019-10-08 09:58:33,778Z INFO
             > [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-63)
 [3eea0406] Removed
             > task 'bfb5e88a-0e62-4e03-b02b-a5a0b36f84db' from DataBase
             >
             > 2019-10-08 09:58:33,791Z INFO
             > [org.ovirt.engine.core.utils.transaction.TransactionSupport]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-63) []
 transaction
             > rolled back
             >
             > 2019-10-08 09:58:33,791Z WARN
             > [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-63) []
 Trying to release
             > a shared lock for key:
 '9ac6f4ad-58d0-4a7e-b424-91f2d76abcacTEMPLATE' ,
             > but lock does not exist
             >
             > 2019-10-08 09:58:33,791Z WARN
             > [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-63) []
 Trying to release
             > a shared lock for key:
 'a7e42574-be60-4c94-94a5-cc4b30fdb16fDISK' , but
             > lock does not exist
             >
             > 2019-10-08 09:58:33,791Z WARN
             > [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-63) []
 Trying to release
             > exclusive lock which does not exist, lock key:
 'centos-testvm-024VM_NAME'
             >
             > 2019-10-08 09:58:33,791Z INFO
 [org.ovirt.engine.core.bll.AddVmCommand]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-63) [] Lock
 freed to
             > object
 'EngineLock:{exclusiveLocks='[centos-testvm-024=VM_NAME]',
             > sharedLocks='[9ac6f4ad-58d0-4a7e-b424-91f2d76abcac=TEMPLATE,
             > a7e42574-be60-4c94-94a5-cc4b30fdb16f=DISK]'}'
             >
             > 2019-10-08 09:58:33,804Z ERROR
             >
 [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-63) []
 EVENT_ID:
             > USER_ADD_VM_FINISHED_FAILURE(60), Failed to complete VM
             > centos-testvm-024 creation.
             >
             > 2019-10-08 09:58:33,824Z ERROR
             > [org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-63)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] Failed invoking
 callback end
             > method 'onFailed' for command
 '1b65f576-365d-4e8b-a920-621516cbdaed'
             > with exception 'null', the callback is marked for end method
 retries but
             > max number of retries have been attempted. The command will
 be marked as
             > Failed.
             >
             > 2019-10-08 09:58:33,824Z INFO
             > [org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-63)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] Exception in invoking
 callback of
             > command AddVm (1b65f576-365d-4e8b-a920-621516cbdaed):
 NullPointerException:
             >
             > 2019-10-08 09:58:33,824Z ERROR
             > [org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-63)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] Error invoking
 callback method
             > 'onFailed' for 'FAILED' command
 '1b65f576-365d-4e8b-a920-621516cbdaed'
             >
             > 2019-10-08 09:58:33,824Z ERROR
             > [org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller]
             > (EE-ManagedThreadFactory-engineScheduled-Thread-63)
             > [067ec489-ae6c-4871-8ab6-8296016ca1ce] Exception:
             > javax.ejb.EJBTransactionRolledbackException
             >
             >                  at
             >
 org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInCallerTx(CMTTxInterceptor.java:203)

             > [wildfly-ejb3-17.0.1.Final.jar:17.0.1.Final]
             >
             >                  at
             >
 org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:420)
 [wildfly-ejb3-17.0.1.Final.jar:17.0.1.Final]
             >
             >                  at
             >
 org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:148)

             > [wildfly-ejb3-17.0.1.Final.jar:17.0.1.Final]
             >
             >                  at
             >
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
             >
             >                  at
             >
 org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:509)
             >
             >                  at
             >

org.jboss.weld.module.ejb.AbstractEJBRequestScopeActivationInterceptor.aroundInvoke(AbstractEJBRequestScopeActivationInterceptor.java:81)

             > [weld-ejb-3.1.1.Final.jar:3.1.1.Final]
             >
             >                  at
             >

org.jboss.as.weld.ejb.EjbRequestScopeActivationInterceptor.processInvocation(EjbRequestScopeActivationInterceptor.java:89)
             >
             >                  at
             >
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
             >
             >                  at
             >

org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41)

             > [wildfly-ejb3-17.0.1.Final.jar:17.0.1.Final]
             >
             >                  at
             >
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
             >
             >                  at
             >

org.jboss.as.ejb3.component.invocationmetrics.WaitTimeInterceptor.processInvocation(WaitTimeInterceptor.java:47)

             > [wildfly-ejb3-17.0.1.Final.jar:17.0.1.Final]
             >
             >                  at
             >
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
             >
             >                  at
             >

org.jboss.as.ejb3.security.SecurityContextInterceptor.processInvocation(SecurityContextInterceptor.java:100)

             > [wildfly-ejb3-17.0.1.Final.jar:17.0.1.Final]
             >
             >                  at
             >
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
             >
             >                  at
             >

org.jboss.as.ejb3.deployment.processors.StartupAwaitInterceptor.processInvocation(StartupAwaitInterceptor.java:22)

             > [wildfly-ejb3-17.0.1.Final.jar:17.0.1.Final]
             >
             >                  at
             >
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
             >
             >                  at
             >

org.jboss.as.ejb3.component.interceptors.ShutDownInterceptorFactory$1.processInvocation(ShutDownInterceptorFactory.java:64)

             > [wildfly-ejb3-17.0.1.Final.jar:17.0.1.Final]
             >
             >                  at
             >
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
             >
             >                  at
             >

org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:67)

             > [wildfly-ejb3-17.0.1.Final.jar:17.0.1.Final]
             >
             >                  at
             >
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
             >
             >                  at
             >

org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50)
             >
             >                  at
             >
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
             >
             >                  at
             >

org.jboss.invocation.ContextClassLoaderInterceptor.processInvocation(ContextClassLoaderInterceptor.java:60)
             >
             >                  at
             >
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
             >
             >                  at
             >
 org.jboss.invocation.InterceptorContext.run(InterceptorContext.java:438)
             >
             >                  at
             >

org.wildfly.security.manager.WildFlySecurityManager.doChecked(WildFlySecurityManager.java:618)
             >
             >                  at
             >

org.jboss.invocation.AccessCheckingInterceptor.processInvocation(AccessCheckingInterceptor.java:57)
             >
             >                  at
             >
 org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
             >
             >                  at
             >
 org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:53)
             >
             >                  at
             >
 org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:198)
             >
             >                  at
             >
 org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:185)
             >
             >                  at
             >
 org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:81)
             >
             >                  at
             >
 org.ovirt.engine.core.bll.interfaces.BackendInternal$$$view3.endAction(Unknown

             > Source) [bll.jar:]
             >
             >                  at
             > sun.reflect.GeneratedMethodAccessor1032.invoke(Unknown
 Source) [:1.8.0_222]
             >
             >                  at
             >
 sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)

             > [rt.jar:1.8.0_222]
             >
             >                  at
 java.lang.reflect.Method.invoke(Method.java:498)
             > [rt.jar:1.8.0_222]
             >
             >                  at
             >
 org.jboss.weld.util.reflection.Reflections.invokeAndUnwrap(Reflections.java:410)

             > [weld-core-impl-3.1.1.Final.jar:3.1.1.Final]
             >
             >                  at
             >

org.jboss.weld.module.ejb.EnterpriseBeanProxyMethodHandler.invoke(EnterpriseBeanProxyMethodHandler.java:134)

             > [weld-ejb-3.1.1.Final.jar:3.1.1.Final]
             >
             >                  at
             >

org.jboss.weld.bean.proxy.EnterpriseTargetBeanInstance.invoke(EnterpriseTargetBeanInstance.java:56)

             > [weld-core-impl-3.1.1.Final.jar:3.1.1.Final]
             >
             >                  at
             >

org.jboss.weld.module.ejb.InjectionPointPropagatingEnterpriseTargetBeanInstance.invoke(InjectionPointPropagatingEnterpriseTargetBeanInstance.java:68)

             > [weld-ejb-3.1.1.Final.jar:3.1.1.Final]
             >
             >                  at
             >
 org.jboss.weld.bean.proxy.ProxyMethodHandler.invoke(ProxyMethodHandler.java:106)

             > [weld-core-impl-3.1.1.Final.jar:3.1.1.Final]
             >
             >                  at
             >

org.ovirt.engine.core.bll.BackendCommandObjectsHandler$BackendInternal$BackendLocal$2049259618$Proxy$_$$_Weld$EnterpriseProxy$.endAction(Unknown

             > Source) [bll.jar:]
             >
             >                  at
             >
 org.ovirt.engine.core.bll.VmCommand.endActionOnDisks(VmCommand.java:266)
             > [bll.jar:]
             >
             >                  at
             >
 org.ovirt.engine.core.bll.AddVmCommand.endWithFailure(AddVmCommand.java:1452)

             > [bll.jar:]
             >
             >                  at
             >
 org.ovirt.engine.core.bll.CommandBase.internalEndWithFailure(CommandBase.java:736)

             > [bll.jar:]
             >
             >                  at
             >
 org.ovirt.engine.core.bll.CommandBase.endActionInTransactionScope(CommandBase.java:658)

             > [bll.jar:]
             >
             >                  at
             >
 org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:2003)

             > [bll.jar:]
             >
             >                  at
             >

org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInNewTransaction(TransactionSupport.java:202)

             > [utils.jar:]
             >
             >                  at
             >

org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInRequired(TransactionSupport.java:137)

             > [utils.jar:]
             >
             >                  at
             >

org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:105)

             > [utils.jar:]
             >
             >                  at
             >
 org.ovirt.engine.core.bll.CommandBase.endAction(CommandBase.java:520)
             > [bll.jar:]
             >
             >                  at
             >

org.ovirt.engine.core.bll.ChildCommandsCallbackBase.endAction(ChildCommandsCallbackBase.java:134)

             > [bll.jar:]
             >
             >                  at
             >

org.ovirt.engine.core.bll.ChildCommandsCallbackBase.onFailed(ChildCommandsCallbackBase.java:174)

             > [bll.jar:]
             >
             >                  at
             >

org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller.endCallback(CommandCallbacksPoller.java:67)

             > [bll.jar:]
             >
             >                  at
             >

org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller.invokeCallbackMethodsImpl(CommandCallbacksPoller.java:166)

             > [bll.jar:]
             >
             >                  at
             >

org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller.invokeCallbackMethods(CommandCallbacksPoller.java:109)

             > [bll.jar:]
             >
             >                  at
             >
 java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
             > [rt.jar:1.8.0_222]
             >
             >                  at
             >
 java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
             > [rt.jar:1.8.0_222]
             >
             >                  at
             >

org.glassfish.enterprise.concurrent.internal.ManagedScheduledThreadPoolExecutor$ManagedScheduledFutureTask.access$201(ManagedScheduledThreadPoolExecutor.java:383)

             > [javax.enterprise.concurrent-1.0.jar:]
             >
             >                  at
             >

org.glassfish.enterprise.concurrent.internal.ManagedScheduledThreadPoolExecutor$ManagedScheduledFutureTask.run(ManagedScheduledThreadPoolExecutor.java:534)

             > [javax.enterprise.concurrent-1.0.jar:]
             >
             >                  at
             >
 java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)

             > [rt.jar:1.8.0_222]
             >
             >                  at
             >
 java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)

             > [rt.jar:1.8.0_222]
             >
             >                  at java.lang.Thread.run(Thread.java:748)
 [rt.jar:1.8.0_222]
             >
             >                  at
             >

org.glassfish.enterprise.concurrent.ManagedThreadFactoryImpl$ManagedThread.run(ManagedThreadFactoryImpl.java:250)

             > [javax.enterprise.concurrent-1.0.jar:]
             >
             > Caused by: java.lang.NullPointerException
             >
             > *Ansible output:*
             >
             > The full traceback is:
             >
             > Traceback (most recent call last):
             >
             >    File
             >

"/var/folders/40/w2c8fp151854mddz_4n3czwm0000gn/T/ansible_ovirt_vm_payload_uy4lqway/__main__.py",

             > line 2024, in main
             >
             >      clone_permissions=module.params['clone_permissions'],
             >
             >    File
             >

"/var/folders/40/w2c8fp151854mddz_4n3czwm0000gn/T/ansible_ovirt_vm_payload_uy4lqway/ansible_ovirt_vm_payload.zip/ansible/module_utils/ovirt.py",

             > line 610, in create
             >
             >      poll_interval=self._module.params['poll_interval'],
             >
             >    File
             >

"/var/folders/40/w2c8fp151854mddz_4n3czwm0000gn/T/ansible_ovirt_vm_payload_uy4lqway/ansible_ovirt_vm_payload.zip/ansible/module_utils/ovirt.py",

             > line 341, in wait
             >
             >      raise Exception("Timeout exceed while waiting on result
 state of
             > the entity.")
             >
             > Exception: Timeout exceed while waiting on result state of
 the entity.
             >
             > fatal: [centos-testvm-024.avinity.tv -> localhost]: FAILED!
 => {
             >
             >      "changed": false,
             >
             >      "invocation": {
             >
             >          "module_args": {
             >
             >              "affinity_group_mappings": [],
             >
             >              "affinity_label_mappings": [],
             >
             >              "allow_partial_import": null,
             >
             >              "ballooning_enabled": null,
             >
             >              "boot_devices": null,
             >
             >              "boot_menu": null,
             >
             >              "cd_iso": null,
             >
             >              "clone": false,
             >
             >              "clone_permissions": false,
             >
             >              "cloud_init": {
             >
             >                  "authorized_ssh_keys": "ssh-rsa removed
 pubkey",
             >
             >                  "host_name":
"centos-testvm-024.avinity.tv
 ",
             >
             >                  "user_name": "centos"
             >
             >              },
             >
             >              "cloud_init_nics": [],
             >
             >              "cloud_init_persist": false,
             >
             >              "cluster": "avshared1",
             >
             >              "cluster_mappings": [],
             >
             >              "comment": "oVirt Template Bakery
Validation",
             >
             >              "cpu_cores": null,
             >
             >              "cpu_mode": null,
             >
             >              "cpu_pinning": null,
             >
             >              "cpu_shares": null,
             >
             >              "cpu_sockets": null,
             >
             >              "cpu_threads": null,
             >
             >              "custom_compatibility_version": null,
             >
             >              "custom_properties": null,
             >
             >              "delete_protected": null,
             >
             >              "description": null,
             >
             >              "disk_format": "cow",
             >
             >              "disks": [],
             >
             >              "domain_mappings": [],
             >
             >              "fetch_nested": false,
             >
             >              "force": false,
             >
             >              "graphical_console": null,
             >
             >              "high_availability": null,
             >
             >              "high_availability_priority": null,
             >
             >              "host": null,
             >
             >              "host_devices": null,
             >
             >              "id": null,
             >
             >              "initrd_path": null,
             >
             >              "instance_type": "Medium",
             >
             >              "io_threads": null,
             >
             >              "kernel_params": null,
             >
             >              "kernel_path": null,
             >
             >              "kvm": null,
             >
             >             "lease": null,
             >
             >              "lun_mappings": [],
             >
             >              "memory": null,
             >
             >              "memory_guaranteed": null,
             >
             >              "memory_max": null,
             >
             >              "name": "centos-testvm-024",
             >
             >              "nested_attributes": [],
             >
             >              "nics": [
             >
             >                  {
             >
             >                      "interface": "virtio",
             >
             >                      "name": "nic1",
             >
             >                      "profile_name": "tenant1"
             >
             >                  }
             >
             >              ],
             >
             >              "numa_nodes": [],
             >
             >              "numa_tune_mode": null,
             >
             >              "operating_system": null,
             >
             >              "placement_policy": null,
             >
             >              "poll_interval": 3,
             >
             >              "quota_id": null,
             >
             >              "reassign_bad_macs": null,
             >
             >              "rng_device": null,
             >
             >              "role_mappings": [],
             >
             >              "serial_console": null,
             >
             >              "serial_policy": null,
             >
             >              "serial_policy_value": null,
             >
             >              "smartcard_enabled": null,
             >
             >              "soundcard_enabled": null,
             >
             >              "sso": null,
             >
             >              "state": "running",
             >
             >              "stateless": null,
             >
             >              "storage_domain": null,
             >
             >              "sysprep": null,
             >
             >              "template":
"av-centos-75-baseimage_nfs9000",
             >
             >              "template_version": null,
             >
             >              "ticket": null,
             >
             >              "timeout": 180,
             >
             >              "timezone": null,
             >
             >              "type": null,
             >
             >              "usb_support": null,
             >
             >              "use_latest_template_version": null,
             >
             >              "vmware": null,
             >
             >              "vnic_profile_mappings": [],
             >
             >              "wait": true,
             >
             >              "watchdog": null,
             >
             >              "xen": null
             >
             >          }
             >
             >      },
             >
             >      "msg": "Timeout exceed while waiting on result state
of
 the entity."
             >
             > }
             >
             >
             > _______________________________________________
             > Users mailing list -- users(a)ovirt.org
             > To unsubscribe send an email to users-leave(a)ovirt.org
             > Privacy Statement:
 https://www.ovirt.org/site/privacy-policy/
             > oVirt Code of Conduct:
 https://www.ovirt.org/community/about/community-guidelines/
             > List Archives:

https://lists.ovirt.org/archives/list/users@ovirt.org/message/EB5AXLUNU6L...
             >

 _______________________________________________
 Users mailing list -- users(a)ovirt.org
 To unsubscribe send an email to users-leave(a)ovirt.org
 Privacy Statement: https://www.ovirt.org/site/privacy-policy/
 oVirt Code of Conduct:
 https://www.ovirt.org/community/about/community-guidelines/
 List Archives:

https://lists.ovirt.org/archives/list/users@ovirt.org/message/ATKD7TYOKB2...

-- 
Martin Perina
Manager, Software Engineering
Red Hat Czech s.r.o.

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

[ovirt-users] Re: Fail to attach/create disk from template when running via Ansible