Attachment 1455546 Details for Bug 1596810 – ovirt engine log

ovirt engine log

engine.log (text/plain), 60.30 KB, created by Nir Soffer on 2018-06-29 18:25:53 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Nir Soffer

Created: 2018-06-29 18:25:53 UTC

Size: 60.30 KB

patch

obsolete

>2018-06-29 20:33:18,239+03 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-58) [c6cbc1db-9a7e-4fd6-bb8b-bffb4e559c21] EVENT_ID: USER_VDS_MAINTENANCE_WITHOUT_REASON(620), Host voodoo1 was switched to Maintenance mode by admin@internal-authz.
>2018-06-29 20:33:19,640+03 INFO  [org.ovirt.engine.core.vdsbroker.monitoring.HostMonitoring] (EE-ManagedThreadFactory-engineScheduled-Thread-56) [] Updated host status from 'Preparing for Maintenance' to 'Maintenance' in database, host 'voodoo1'(b9c45d66-2067-4cd9-a9f2-d24a6f7a0fd9)
>2018-06-29 20:33:19,723+03 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxy] (EE-ManagedThreadFactory-engine-Thread-21399) [] Clearing cache of pool: 'd8c8ae56-74dc-11e8-826c-525400de233d' for problematic entities of VDS: 'voodoo1'.
>2018-06-29 20:33:19,723+03 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxy] (EE-ManagedThreadFactory-engine-Thread-21399) [] Removing vds '[b9c45d66-2067-4cd9-a9f2-d24a6f7a0fd9]' from the domain in maintenance cache
>2018-06-29 20:33:19,723+03 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsProxy] (EE-ManagedThreadFactory-engine-Thread-21399) [] Removing host(s) '[b9c45d66-2067-4cd9-a9f2-d24a6f7a0fd9]' from hosts unseen domain report cache
>2018-06-29 20:33:40,852+03 INFO  [org.ovirt.engine.core.bll.ActivateVdsCommand] (default task-118) [42653418-a5ff-4f42-b5cc-92383a1d3e92] Lock Acquired to object 'EngineLock:{exclusiveLocks='[b9c45d66-2067-4cd9-a9f2-d24a6f7a0fd9=VDS]', sharedLocks=''}'
>2018-06-29 20:33:40,975+03 INFO  [org.ovirt.engine.core.bll.ActivateVdsCommand] (EE-ManagedThreadFactory-engine-Thread-21402) [42653418-a5ff-4f42-b5cc-92383a1d3e92] Running command: ActivateVdsCommand internal: false. Entities affected :  ID: b9c45d66-2067-4cd9-a9f2-d24a6f7a0fd9 Type: VDSAction group MANIPULATE_HOST with role type ADMIN
>2018-06-29 20:33:40,975+03 INFO  [org.ovirt.engine.core.bll.ActivateVdsCommand] (EE-ManagedThreadFactory-engine-Thread-21402) [42653418-a5ff-4f42-b5cc-92383a1d3e92] Before acquiring lock in order to prevent monitoring for host 'voodoo1' from data-center 'Default'
>2018-06-29 20:33:40,976+03 INFO  [org.ovirt.engine.core.bll.ActivateVdsCommand] (EE-ManagedThreadFactory-engine-Thread-21402) [42653418-a5ff-4f42-b5cc-92383a1d3e92] Lock acquired, from now a monitoring of host will be skipped for host 'voodoo1' from data-center 'Default'
>2018-06-29 20:33:40,986+03 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (EE-ManagedThreadFactory-engine-Thread-21402) [42653418-a5ff-4f42-b5cc-92383a1d3e92] START, SetVdsStatusVDSCommand(HostName = voodoo1, SetVdsStatusVDSCommandParameters:{hostId='b9c45d66-2067-4cd9-a9f2-d24a6f7a0fd9', status='Unassigned', nonOperationalReason='NONE', stopSpmFailureLogged='false', maintenanceReason='null'}), log id: 45f90078
>2018-06-29 20:33:40,991+03 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (EE-ManagedThreadFactory-engine-Thread-21402) [42653418-a5ff-4f42-b5cc-92383a1d3e92] FINISH, SetVdsStatusVDSCommand, return: , log id: 45f90078
>2018-06-29 20:33:40,994+03 INFO  [org.ovirt.engine.core.bll.ActivateVdsCommand] (EE-ManagedThreadFactory-engine-Thread-21402) [42653418-a5ff-4f42-b5cc-92383a1d3e92] Activate host finished. Lock released. Monitoring can run now for host 'voodoo1' from data-center 'Default'
>2018-06-29 20:33:41,000+03 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-21402) [42653418-a5ff-4f42-b5cc-92383a1d3e92] EVENT_ID: VDS_ACTIVATE(16), Activation of host voodoo1 initiated by admin@internal-authz.
>2018-06-29 20:33:41,008+03 INFO  [org.ovirt.engine.core.bll.ActivateVdsCommand] (EE-ManagedThreadFactory-engine-Thread-21402) [42653418-a5ff-4f42-b5cc-92383a1d3e92] Lock freed to object 'EngineLock:{exclusiveLocks='[b9c45d66-2067-4cd9-a9f2-d24a6f7a0fd9=VDS]', sharedLocks=''}'
>2018-06-29 20:33:44,509+03 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoAsyncVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-78) [] START, GetHardwareInfoAsyncVDSCommand(HostName = voodoo1, VdsIdAndVdsVDSCommandParametersBase:{hostId='b9c45d66-2067-4cd9-a9f2-d24a6f7a0fd9', vds='Host[voodoo1,b9c45d66-2067-4cd9-a9f2-d24a6f7a0fd9]'}), log id: dd7f90
>2018-06-29 20:33:44,509+03 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoAsyncVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-78) [] FINISH, GetHardwareInfoAsyncVDSCommand, return: , log id: dd7f90
>2018-06-29 20:33:44,548+03 INFO  [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-78) [2a6130a8] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected :  ID: b9c45d66-2067-4cd9-a9f2-d24a6f7a0fd9 Type: VDS
>2018-06-29 20:33:44,785+03 INFO  [org.ovirt.engine.core.bll.InitVdsOnUpCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-85) [5dce3180] Running command: InitVdsOnUpCommand internal: true. Entities affected :  ID: d8c8ae56-74dc-11e8-826c-525400de233d Type: StoragePool
>2018-06-29 20:33:44,832+03 ERROR [org.ovirt.engine.core.bll.pm.FenceProxyLocator] (EE-ManagedThreadFactory-engineScheduled-Thread-85) [5dce3180] Can not run fence action on host 'voodoo1', no suitable proxy host was found.
>2018-06-29 20:33:44,834+03 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SetMOMPolicyParametersVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-85) [5dce3180] START, SetMOMPolicyParametersVDSCommand(HostName = voodoo1, MomPolicyVDSParameters:{hostId='b9c45d66-2067-4cd9-a9f2-d24a6f7a0fd9'}), log id: 54898b71
>2018-06-29 20:33:44,877+03 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SetMOMPolicyParametersVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-85) [5dce3180] FINISH, SetMOMPolicyParametersVDSCommand, return: , log id: 54898b71
>2018-06-29 20:33:44,901+03 INFO  [org.ovirt.engine.core.bll.hostdev.RefreshHostDevicesCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-85) [439abbb4] Running command: RefreshHostDevicesCommand internal: true. Entities affected :  ID: b9c45d66-2067-4cd9-a9f2-d24a6f7a0fd9 Type: VDSAction group MANIPULATE_HOST with role type ADMIN
>2018-06-29 20:33:44,967+03 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engineScheduled-Thread-85) [439abbb4] EVENT_ID: VDS_DETECTED(13), Status of host voodoo1 was set to Up.
>2018-06-29 20:33:44,971+03 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engineScheduled-Thread-85) [439abbb4] EVENT_ID: VDS_ALERT_FENCE_IS_NOT_CONFIGURED(9,000), Failed to verify Power Management configuration for Host voodoo1.
>2018-06-29 20:33:45,032+03 INFO  [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-85) [770a984b] Running command: HandleVdsVersionCommand internal: true. Entities affected :  ID: b9c45d66-2067-4cd9-a9f2-d24a6f7a0fd9 Type: VDS
>2018-06-29 20:35:29,075+03 INFO  [org.ovirt.engine.core.sso.utils.AuthenticationUtils] (default task-27) [] 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
>2018-06-29 20:35:29,104+03 INFO  [org.ovirt.engine.core.bll.aaa.CreateUserSessionCommand] (default task-22) [7570a4a2] Running command: CreateUserSessionCommand internal: false.
>2018-06-29 20:35:29,120+03 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-22) [7570a4a2] EVENT_ID: USER_VDC_LOGIN(30), User admin@internal-authz connecting from '10.35.0.100' using session 'Ej2gaZFcX9ZOm0AEg9FM0QMW+4mQqPA8ZkVCOeW1xmiqQmyj+/gHpDdtrdOd5EQe7LtpdpNTNcTnBWfnVRBroQ==' logged in.
>2018-06-29 20:36:38,235+03 INFO  [org.ovirt.engine.core.sso.utils.AuthenticationUtils] (default task-38) [] 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
>2018-06-29 20:36:38,264+03 INFO  [org.ovirt.engine.core.bll.aaa.CreateUserSessionCommand] (default task-35) [14860aab] Running command: CreateUserSessionCommand internal: false.
>2018-06-29 20:36:38,277+03 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-35) [14860aab] EVENT_ID: USER_VDC_LOGIN(30), User admin@internal-authz connecting from '10.35.0.100' using session 'lE8qDdG+D2S+4VKoKZ8Ew2HR7xXzjWFDYQExkGunzk8CO1few8gnfPn/Wa7Nok5Lv9pn1yLzaXsG13tqOcKEJg==' logged in.
>2018-06-29 20:36:38,346+03 INFO  [org.ovirt.engine.core.bll.storage.disk.AddDiskCommand] (default task-35) [2790b102-363e-46a7-8839-b1e70d212f33] Running command: AddDiskCommand internal: false. Entities affected :  ID: 27837a03-64f9-4f2b-abb0-daa2195b01ae Type: StorageAction group CREATE_DISK with role type USER
>2018-06-29 20:36:38,397+03 INFO  [org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand] (default task-35) [2790b102-363e-46a7-8839-b1e70d212f33] Running command: AddImageFromScratchCommand internal: true. Entities affected :  ID: 27837a03-64f9-4f2b-abb0-daa2195b01ae Type: Storage
>2018-06-29 20:36:38,439+03 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (default task-35) [2790b102-363e-46a7-8839-b1e70d212f33] START, CreateImageVDSCommand( CreateImageVDSCommandParameters:{storagePoolId='a0011271-88a4-491f-a566-aec38b2000e9', ignoreFailoverLimit='false', storageDomainId='27837a03-64f9-4f2b-abb0-daa2195b01ae', imageGroupId='ba390f85-2d45-4ea1-8e29-96020c4ba416', imageSizeInBytes='6442450944', volumeFormat='RAW', newImageId='55fb99f5-c2a3-4894-9942-400de5595531', imageType='Preallocated', newImageDescription='{"DiskAlias":"fedora-27-000","DiskDescription":"Uploaded by virt-v2v"}', imageInitialSizeInBytes='0'}), log id: 7d050b9a
>2018-06-29 20:36:38,440+03 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (default task-35) [2790b102-363e-46a7-8839-b1e70d212f33] -- executeIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID
>2018-06-29 20:36:38,937+03 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (default task-35) [2790b102-363e-46a7-8839-b1e70d212f33] FINISH, CreateImageVDSCommand, return: 55fb99f5-c2a3-4894-9942-400de5595531, log id: 7d050b9a
>2018-06-29 20:36:38,961+03 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (default task-35) [2790b102-363e-46a7-8839-b1e70d212f33] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command '3e358335-cbb9-4454-9984-774109f4f7f3'
>2018-06-29 20:36:38,961+03 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (default task-35) [2790b102-363e-46a7-8839-b1e70d212f33] CommandMultiAsyncTasks::attachTask: Attaching task 'dc0d6f17-5d63-4fb7-914c-4f70f67b94b6' to command '3e358335-cbb9-4454-9984-774109f4f7f3'.
>2018-06-29 20:36:39,136+03 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (default task-35) [2790b102-363e-46a7-8839-b1e70d212f33] Adding task 'dc0d6f17-5d63-4fb7-914c-4f70f67b94b6' (Parent Command 'AddImageFromScratch', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters'), polling hasn't started yet..
>2018-06-29 20:36:39,311+03 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (default task-35) [2790b102-363e-46a7-8839-b1e70d212f33] BaseAsyncTask::startPollingTask: Starting to poll task 'dc0d6f17-5d63-4fb7-914c-4f70f67b94b6'.
>2018-06-29 20:36:39,486+03 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-35) [2790b102-363e-46a7-8839-b1e70d212f33] EVENT_ID: USER_ADD_DISK(2,020), Add-Disk operation of 'fedora-27-000' was initiated by admin@internal-authz.
>2018-06-29 20:36:39,873+03 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (EE-ManagedThreadFactory-engineScheduled-Thread-52) [] Polling and updating Async Tasks: 1 tasks, 1 tasks to poll now
>2018-06-29 20:36:39,883+03 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-52) [] SPMAsyncTask::PollTask: Polling task 'dc0d6f17-5d63-4fb7-914c-4f70f67b94b6' (Parent Command 'AddImageFromScratch', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') returned status 'running'.
>2018-06-29 20:36:39,883+03 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (EE-ManagedThreadFactory-engineScheduled-Thread-52) [] Finished polling Tasks, will poll again in 10 seconds.
>2018-06-29 20:36:40,641+03 INFO  [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-65) [2790b102-363e-46a7-8839-b1e70d212f33] Command 'AddDisk' (id: 'ab486e33-8c37-427a-a7b4-71eabf02d907') waiting on child command id: '3e358335-cbb9-4454-9984-774109f4f7f3' type:'AddImageFromScratch' to complete
>2018-06-29 20:36:44,645+03 INFO  [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-36) [2790b102-363e-46a7-8839-b1e70d212f33] Command 'AddDisk' (id: 'ab486e33-8c37-427a-a7b4-71eabf02d907') waiting on child command id: '3e358335-cbb9-4454-9984-774109f4f7f3' type:'AddImageFromScratch' to complete
>2018-06-29 20:36:49,894+03 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-93) [] SPMAsyncTask::PollTask: Polling task 'dc0d6f17-5d63-4fb7-914c-4f70f67b94b6' (Parent Command 'AddImageFromScratch', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') returned status 'finished', result 'success'.
>2018-06-29 20:36:49,913+03 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-93) [] BaseAsyncTask::onTaskEndSuccess: Task 'dc0d6f17-5d63-4fb7-914c-4f70f67b94b6' (Parent Command 'AddImageFromScratch', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') ended successfully.
>2018-06-29 20:36:49,914+03 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-93) [] CommandAsyncTask::endActionIfNecessary: All tasks of command '3e358335-cbb9-4454-9984-774109f4f7f3' has ended -> executing 'endAction'
>2018-06-29 20:36:49,914+03 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engineScheduled-Thread-93) [] CommandAsyncTask::endAction: Ending action for '1' tasks (command ID: '3e358335-cbb9-4454-9984-774109f4f7f3'): calling endAction '.
>2018-06-29 20:36:49,914+03 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-21430) [] CommandAsyncTask::endCommandAction [within thread] context: Attempting to endAction 'AddImageFromScratch',
>2018-06-29 20:36:49,929+03 INFO  [org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand] (EE-ManagedThreadFactory-engine-Thread-21430) [2790b102-363e-46a7-8839-b1e70d212f33] Command [id=3e358335-cbb9-4454-9984-774109f4f7f3]: Updating status to 'SUCCEEDED', The command end method logic will be executed by one of its parent commands.
>2018-06-29 20:36:49,929+03 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-21430) [2790b102-363e-46a7-8839-b1e70d212f33] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type 'AddImageFromScratch' completed, handling the result.
>2018-06-29 20:36:49,929+03 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-21430) [2790b102-363e-46a7-8839-b1e70d212f33] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type 'AddImageFromScratch' succeeded, clearing tasks.
>2018-06-29 20:36:49,929+03 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engine-Thread-21430) [2790b102-363e-46a7-8839-b1e70d212f33] SPMAsyncTask::ClearAsyncTask: Attempting to clear task 'dc0d6f17-5d63-4fb7-914c-4f70f67b94b6'
>2018-06-29 20:36:49,930+03 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-21430) [2790b102-363e-46a7-8839-b1e70d212f33] START, SPMClearTaskVDSCommand( SPMTaskGuidBaseVDSCommandParameters:{storagePoolId='a0011271-88a4-491f-a566-aec38b2000e9', ignoreFailoverLimit='false', taskId='dc0d6f17-5d63-4fb7-914c-4f70f67b94b6'}), log id: 43e6cc78
>2018-06-29 20:36:49,931+03 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-21430) [2790b102-363e-46a7-8839-b1e70d212f33] START, HSMClearTaskVDSCommand(HostName = voodoo2, HSMTaskGuidBaseVDSCommandParameters:{hostId='5fbd71dd-5aed-48a0-92da-b4de36e7dff1', taskId='dc0d6f17-5d63-4fb7-914c-4f70f67b94b6'}), log id: 2edabc0d
>2018-06-29 20:36:50,005+03 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-21430) [2790b102-363e-46a7-8839-b1e70d212f33] FINISH, HSMClearTaskVDSCommand, return: , log id: 2edabc0d
>2018-06-29 20:36:50,005+03 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-21430) [2790b102-363e-46a7-8839-b1e70d212f33] FINISH, SPMClearTaskVDSCommand, return: , log id: 43e6cc78
>2018-06-29 20:36:50,038+03 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engine-Thread-21430) [2790b102-363e-46a7-8839-b1e70d212f33] BaseAsyncTask::removeTaskFromDB: Removed task 'dc0d6f17-5d63-4fb7-914c-4f70f67b94b6' from DataBase
>2018-06-29 20:36:50,038+03 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-21430) [2790b102-363e-46a7-8839-b1e70d212f33] CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity '3e358335-cbb9-4454-9984-774109f4f7f3'
>2018-06-29 20:36:52,648+03 INFO  [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-30) [2790b102-363e-46a7-8839-b1e70d212f33] Command 'AddDisk' id: 'ab486e33-8c37-427a-a7b4-71eabf02d907' child commands '[3e358335-cbb9-4454-9984-774109f4f7f3]' executions were completed, status 'SUCCEEDED'
>2018-06-29 20:36:53,698+03 INFO  [org.ovirt.engine.core.bll.storage.disk.AddDiskCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-49) [2790b102-363e-46a7-8839-b1e70d212f33] Ending command 'org.ovirt.engine.core.bll.storage.disk.AddDiskCommand' successfully.
>2018-06-29 20:36:53,714+03 INFO  [org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-49) [2790b102-363e-46a7-8839-b1e70d212f33] Ending command 'org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand' successfully.
>2018-06-29 20:36:53,722+03 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-49) [2790b102-363e-46a7-8839-b1e70d212f33] START, GetImageInfoVDSCommand( GetImageInfoVDSCommandParameters:{storagePoolId='a0011271-88a4-491f-a566-aec38b2000e9', ignoreFailoverLimit='false', storageDomainId='27837a03-64f9-4f2b-abb0-daa2195b01ae', imageGroupId='ba390f85-2d45-4ea1-8e29-96020c4ba416', imageId='55fb99f5-c2a3-4894-9942-400de5595531'}), log id: 58ce6447
>2018-06-29 20:36:53,723+03 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-49) [2790b102-363e-46a7-8839-b1e70d212f33] START, GetVolumeInfoVDSCommand(HostName = voodoo2, GetVolumeInfoVDSCommandParameters:{hostId='5fbd71dd-5aed-48a0-92da-b4de36e7dff1', storagePoolId='a0011271-88a4-491f-a566-aec38b2000e9', storageDomainId='27837a03-64f9-4f2b-abb0-daa2195b01ae', imageGroupId='ba390f85-2d45-4ea1-8e29-96020c4ba416', imageId='55fb99f5-c2a3-4894-9942-400de5595531'}), log id: 1d90a2c6
>2018-06-29 20:36:53,757+03 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-49) [2790b102-363e-46a7-8839-b1e70d212f33] FINISH, GetVolumeInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage.DiskImage@801c021c, log id: 1d90a2c6
>2018-06-29 20:36:53,757+03 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-49) [2790b102-363e-46a7-8839-b1e70d212f33] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage.DiskImage@801c021c, log id: 58ce6447
>2018-06-29 20:36:53,772+03 WARN  [org.ovirt.engine.core.bll.storage.disk.AddDiskCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-49) [] VM is null - no unlocking
>2018-06-29 20:36:53,805+03 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engineScheduled-Thread-49) [] EVENT_ID: USER_ADD_DISK_FINISHED_SUCCESS(2,021), The disk 'fedora-27-000' was successfully added.
>2018-06-29 20:36:54,647+03 INFO  [org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand] (default task-47) [b8987959-81ea-432e-9300-a3c449512693] Lock Acquired to object 'EngineLock:{exclusiveLocks='', sharedLocks='[ba390f85-2d45-4ea1-8e29-96020c4ba416=DISK]'}'
>2018-06-29 20:36:54,674+03 INFO  [org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand] (default task-47) [b8987959-81ea-432e-9300-a3c449512693] Running command: TransferDiskImageCommand internal: false. Entities affected :  ID: ba390f85-2d45-4ea1-8e29-96020c4ba416 Type: DiskAction group EDIT_DISK_PROPERTIES with role type USER
>2018-06-29 20:36:54,674+03 INFO  [org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand] (default task-47) [b8987959-81ea-432e-9300-a3c449512693] Creating ImageTransfer entity for command 'c4d1cee1-7117-42e8-b3f9-146a9749fdb2'
>2018-06-29 20:36:54,715+03 INFO  [org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand] (default task-47) [b8987959-81ea-432e-9300-a3c449512693] Successfully added Upload disk 'fedora-27-000' (disk id: 'ba390f85-2d45-4ea1-8e29-96020c4ba416', image id: '55fb99f5-c2a3-4894-9942-400de5595531') for image transfer command 'c4d1cee1-7117-42e8-b3f9-146a9749fdb2'
>2018-06-29 20:36:54,793+03 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand] (default task-47) [b8987959-81ea-432e-9300-a3c449512693] START, PrepareImageVDSCommand(HostName = voodoo1, PrepareImageVDSCommandParameters:{hostId='b9c45d66-2067-4cd9-a9f2-d24a6f7a0fd9'}), log id: 1c41b9bd
>2018-06-29 20:36:54,798+03 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand] (default task-47) [b8987959-81ea-432e-9300-a3c449512693] Failed in 'PrepareImageVDS' method, for vds: 'voodoo1'; host: 'voodoo1.tlv.redhat.com': null
>2018-06-29 20:36:54,798+03 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand] (default task-47) [b8987959-81ea-432e-9300-a3c449512693] Command 'PrepareImageVDSCommand(HostName = voodoo1, PrepareImageVDSCommandParameters:{hostId='b9c45d66-2067-4cd9-a9f2-d24a6f7a0fd9'})' execution failed: null
>2018-06-29 20:36:54,798+03 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand] (default task-47) [b8987959-81ea-432e-9300-a3c449512693] FINISH, PrepareImageVDSCommand, return: , log id: 1c41b9bd
>2018-06-29 20:36:54,798+03 ERROR [org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand] (default task-47) [b8987959-81ea-432e-9300-a3c449512693] Failed to prepare image for transfer session: {}: org.ovirt.engine.core.common.errors.EngineException: EngineException: java.lang.NullPointerException (Failed with error ENGINE and code 5001)
>	at org.ovirt.engine.core.bll.VdsHandler.handleVdsResult(VdsHandler.java:118) [bll.jar:]
>	at org.ovirt.engine.core.bll.VDSBrokerFrontendImpl.runVdsCommand(VDSBrokerFrontendImpl.java:33) [bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.runVdsCommand(CommandBase.java:2046) [bll.jar:]
>	at org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand.prepareImage(TransferDiskImageCommand.java:144) [bll.jar:]
>	at org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand.addImageTicketToDaemon(TransferDiskImageCommand.java:777) [bll.jar:]
>	at org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand.startImageTransferSession(TransferDiskImageCommand.java:749) [bll.jar:]
>	at org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand.handleImageIsReadyForTransfer(TransferDiskImageCommand.java:451) [bll.jar:]
>	at org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand.executeCommand(TransferDiskImageCommand.java:309) [bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1133) [bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1286) [bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1935) [bll.jar:]
>	at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInSuppressed(TransactionSupport.java:164) [utils.jar:]
>	at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:103) [utils.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.execute(CommandBase.java:1346) [bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:400) [bll.jar:]
>	at org.ovirt.engine.core.bll.executor.DefaultBackendActionExecutor.execute(DefaultBackendActionExecutor.java:13) [bll.jar:]
>	at org.ovirt.engine.core.bll.Backend.runAction(Backend.java:450) [bll.jar:]
>	at org.ovirt.engine.core.bll.Backend.runActionImpl(Backend.java:432) [bll.jar:]
>	at org.ovirt.engine.core.bll.Backend.runAction(Backend.java:387) [bll.jar:]
>	at sun.reflect.GeneratedMethodAccessor250.invoke(Unknown Source) [:1.8.0_171]
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_171]
>	at java.lang.reflect.Method.invoke(Method.java:498) [rt.jar:1.8.0_171]
>	at org.jboss.as.ee.component.ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptor.java:52)
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
>	at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:509)
>	at org.jboss.as.weld.ejb.DelegatingInterceptorInvocationContext.proceed(DelegatingInterceptorInvocationContext.java:92) [wildfly-weld-ejb-11.0.0.Final.jar:11.0.0.Final]
>	at org.jboss.weld.interceptor.proxy.WeldInvocationContext.interceptorChainCompleted(WeldInvocationContext.java:98) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final]
>	at org.jboss.weld.interceptor.proxy.WeldInvocationContext.proceed(WeldInvocationContext.java:117) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final]
>	at org.ovirt.engine.core.common.di.interceptor.LoggingInterceptor.apply(LoggingInterceptor.java:12) [common.jar:]
>	at sun.reflect.GeneratedMethodAccessor64.invoke(Unknown Source) [:1.8.0_171]
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_171]
>	at java.lang.reflect.Method.invoke(Method.java:498) [rt.jar:1.8.0_171]
>	at org.jboss.weld.interceptor.reader.SimpleInterceptorInvocation$SimpleMethodInvocation.invoke(SimpleInterceptorInvocation.java:73) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final]
>	at org.jboss.weld.interceptor.proxy.WeldInvocationContext.invokeNext(WeldInvocationContext.java:83) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final]
>	at org.jboss.weld.interceptor.proxy.WeldInvocationContext.proceed(WeldInvocationContext.java:115) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final]
>	at org.jboss.weld.bean.InterceptorImpl.intercept(InterceptorImpl.java:108) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final]
>	at org.jboss.as.weld.ejb.DelegatingInterceptorInvocationContext.proceed(DelegatingInterceptorInvocationContext.java:82) [wildfly-weld-ejb-11.0.0.Final.jar:11.0.0.Final]
>	at org.jboss.as.weld.interceptors.EjbComponentInterceptorSupport.delegateInterception(EjbComponentInterceptorSupport.java:60)
>	at org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.delegateInterception(Jsr299BindingsInterceptor.java:76)
>	at org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.doMethodInterception(Jsr299BindingsInterceptor.java:88)
>	at org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.processInvocation(Jsr299BindingsInterceptor.java:101)
>	at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63)
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
>	at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:509)
>	at org.ovirt.engine.core.bll.interceptors.CorrelationIdTrackerInterceptor.aroundInvoke(CorrelationIdTrackerInterceptor.java:13) [bll.jar:]
>	at sun.reflect.GeneratedMethodAccessor73.invoke(Unknown Source) [:1.8.0_171]
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_171]
>	at java.lang.reflect.Method.invoke(Method.java:498) [rt.jar:1.8.0_171]
>	at org.jboss.as.ee.component.ManagedReferenceLifecycleMethodInterceptor.processInvocation(ManagedReferenceLifecycleMethodInterceptor.java:89)
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
>	at org.jboss.as.ejb3.component.invocationmetrics.ExecutionTimeInterceptor.processInvocation(ExecutionTimeInterceptor.java:43) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
>	at org.jboss.as.ee.concurrent.ConcurrentContextInterceptor.processInvocation(ConcurrentContextInterceptor.java:45) [wildfly-ee-11.0.0.Final.jar:11.0.0.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
>	at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:40)
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
>	at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:53)
>	at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:52)
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
>	at org.jboss.as.ejb3.component.singleton.SingletonComponentInstanceAssociationInterceptor.processInvocation(SingletonComponentInstanceAssociationInterceptor.java:53) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
>	at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:264) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final]
>	at org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:379) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final]
>	at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:244) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
>	at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:509)
>	at org.jboss.weld.ejb.AbstractEJBRequestScopeActivationInterceptor.aroundInvoke(AbstractEJBRequestScopeActivationInterceptor.java:73) [weld-core-impl-2.4.3.Final.jar:2.4.3.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-11.0.0.Final.jar:11.0.0.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
>	at org.jboss.as.ejb3.component.invocationmetrics.WaitTimeInterceptor.processInvocation(WaitTimeInterceptor.java:47) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
>	at org.jboss.as.ejb3.security.SecurityContextInterceptor.processInvocation(SecurityContextInterceptor.java:100) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
>	at org.jboss.as.ejb3.deployment.processors.StartupAwaitInterceptor.processInvocation(StartupAwaitInterceptor.java:22) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.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-11.0.0.Final.jar:11.0.0.Final]
>	at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
>	at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:67) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.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:609)
>	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.common.interfaces.BackendLocal$$$view2.runAction(Unknown Source) [common.jar:]
>	at org.ovirt.engine.api.restapi.resource.BackendResource.doAction(BackendResource.java:250)
>	at org.ovirt.engine.api.restapi.resource.AbstractBackendCollectionResource.doCreateEntity(AbstractBackendCollectionResource.java:233)
>	at org.ovirt.engine.api.restapi.resource.AbstractBackendCollectionResource.performCreate(AbstractBackendCollectionResource.java:144)
>	at org.ovirt.engine.api.restapi.resource.AbstractBackendCollectionResource.performCreate(AbstractBackendCollectionResource.java:134)
>	at org.ovirt.engine.api.restapi.resource.AbstractBackendCollectionResource.performCreate(AbstractBackendCollectionResource.java:153)
>	at org.ovirt.engine.api.restapi.resource.BackendImageTransfersResource.performCreate(BackendImageTransfersResource.java:63)
>	at org.ovirt.engine.api.restapi.resource.BackendImageTransfersResource.addForDisk(BackendImageTransfersResource.java:45)
>	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.8.0_171]
>	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [rt.jar:1.8.0_171]
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_171]
>	at java.lang.reflect.Method.invoke(Method.java:498) [rt.jar:1.8.0_171]
>	at org.ovirt.engine.api.resource.ImageTransfersResource.add(ImageTransfersResource.java:101)
>	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.8.0_171]
>	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [rt.jar:1.8.0_171]
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_171]
>	at java.lang.reflect.Method.invoke(Method.java:498) [rt.jar:1.8.0_171]
>	at org.jboss.resteasy.core.MethodInjectorImpl.invoke(MethodInjectorImpl.java:140) [resteasy-jaxrs-3.0.24.Final.jar:3.0.24.Final]
>	at org.jboss.resteasy.core.ResourceMethodInvoker.invokeOnTarget(ResourceMethodInvoker.java:295) [resteasy-jaxrs-3.0.24.Final.jar:3.0.24.Final]
>	at org.jboss.resteasy.core.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:249) [resteasy-jaxrs-3.0.24.Final.jar:3.0.24.Final]
>	at org.jboss.resteasy.core.ResourceLocatorInvoker.invokeOnTargetObject(ResourceLocatorInvoker.java:138) [resteasy-jaxrs-3.0.24.Final.jar:3.0.24.Final]
>	at org.jboss.resteasy.core.ResourceLocatorInvoker.invoke(ResourceLocatorInvoker.java:101) [resteasy-jaxrs-3.0.24.Final.jar:3.0.24.Final]
>	at org.jboss.resteasy.core.SynchronousDispatcher.invoke(SynchronousDispatcher.java:406) [resteasy-jaxrs-3.0.24.Final.jar:3.0.24.Final]
>	at org.jboss.resteasy.core.SynchronousDispatcher.invoke(SynchronousDispatcher.java:213) [resteasy-jaxrs-3.0.24.Final.jar:3.0.24.Final]
>	at org.jboss.resteasy.plugins.server.servlet.ServletContainerDispatcher.service(ServletContainerDispatcher.java:228) [resteasy-jaxrs-3.0.24.Final.jar:3.0.24.Final]
>	at org.jboss.resteasy.plugins.server.servlet.HttpServletDispatcher.service(HttpServletDispatcher.java:56) [resteasy-jaxrs-3.0.24.Final.jar:3.0.24.Final]
>	at org.jboss.resteasy.plugins.server.servlet.HttpServletDispatcher.service(HttpServletDispatcher.java:51) [resteasy-jaxrs-3.0.24.Final.jar:3.0.24.Final]
>	at javax.servlet.http.HttpServlet.service(HttpServlet.java:790) [jboss-servlet-api_3.1_spec-1.0.0.Final.jar:1.0.0.Final]
>	at io.undertow.servlet.handlers.ServletHandler.handleRequest(ServletHandler.java:85)
>	at io.undertow.servlet.handlers.FilterHandler.handleRequest(FilterHandler.java:81)
>	at io.undertow.servlet.handlers.security.ServletSecurityRoleHandler.handleRequest(ServletSecurityRoleHandler.java:62)
>	at io.undertow.servlet.handlers.ServletDispatchingHandler.handleRequest(ServletDispatchingHandler.java:36)
>	at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
>	at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
>	at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
>	at io.undertow.servlet.handlers.ServletInitialHandler.dispatchRequest(ServletInitialHandler.java:274)
>	at io.undertow.servlet.handlers.ServletInitialHandler.dispatchToPath(ServletInitialHandler.java:209)
>	at io.undertow.servlet.spec.RequestDispatcherImpl.forwardImpl(RequestDispatcherImpl.java:221)
>	at io.undertow.servlet.spec.RequestDispatcherImpl.forwardImplSetup(RequestDispatcherImpl.java:147)
>	at io.undertow.servlet.spec.RequestDispatcherImpl.forward(RequestDispatcherImpl.java:111)
>	at org.ovirt.engine.api.restapi.invocation.VersionFilter.doFilter(VersionFilter.java:178)
>	at org.ovirt.engine.api.restapi.invocation.VersionFilter.doFilter(VersionFilter.java:98)
>	at io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
>	at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
>	at org.ovirt.engine.api.restapi.invocation.CurrentFilter.doFilter(CurrentFilter.java:117)
>	at org.ovirt.engine.api.restapi.invocation.CurrentFilter.doFilter(CurrentFilter.java:72)
>	at io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
>	at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
>	at org.ovirt.engine.core.aaa.filters.RestApiSessionMgmtFilter.doFilter(RestApiSessionMgmtFilter.java:78) [aaa.jar:]
>	at io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
>	at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
>	at org.ovirt.engine.core.aaa.filters.EnforceAuthFilter.doFilter(EnforceAuthFilter.java:42) [aaa.jar:]
>	at io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
>	at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
>	at org.ovirt.engine.core.aaa.filters.SsoRestApiNegotiationFilter.doFilter(SsoRestApiNegotiationFilter.java:84) [aaa.jar:]
>	at io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
>	at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
>	at org.ovirt.engine.core.aaa.filters.SsoRestApiAuthFilter.doFilter(SsoRestApiAuthFilter.java:47) [aaa.jar:]
>	at io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
>	at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
>	at org.ovirt.engine.core.aaa.filters.SessionValidationFilter.doFilter(SessionValidationFilter.java:59) [aaa.jar:]
>	at io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
>	at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
>	at org.ovirt.engine.core.aaa.filters.RestApiSessionValidationFilter.doFilter(RestApiSessionValidationFilter.java:35) [aaa.jar:]
>	at io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
>	at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
>	at org.ovirt.engine.api.restapi.security.CSRFProtectionFilter.doFilter(CSRFProtectionFilter.java:111)
>	at org.ovirt.engine.api.restapi.security.CSRFProtectionFilter.doFilter(CSRFProtectionFilter.java:102)
>	at io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
>	at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
>	at org.ovirt.engine.core.utils.servlet.CORSSupportFilter.doFilter(CORSSupportFilter.java:283) [utils.jar:]
>	at io.undertow.servlet.core.ManagedFilter.doFilter(ManagedFilter.java:61)
>	at io.undertow.servlet.handlers.FilterHandler$FilterChainImpl.doFilter(FilterHandler.java:131)
>	at io.undertow.servlet.handlers.FilterHandler.handleRequest(FilterHandler.java:84)
>	at io.undertow.servlet.handlers.security.ServletSecurityRoleHandler.handleRequest(ServletSecurityRoleHandler.java:62)
>	at io.undertow.servlet.handlers.ServletDispatchingHandler.handleRequest(ServletDispatchingHandler.java:36)
>	at org.wildfly.extension.undertow.security.SecurityContextAssociationHandler.handleRequest(SecurityContextAssociationHandler.java:78)
>	at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
>	at io.undertow.servlet.handlers.security.SSLInformationAssociationHandler.handleRequest(SSLInformationAssociationHandler.java:131)
>	at io.undertow.servlet.handlers.security.ServletAuthenticationCallHandler.handleRequest(ServletAuthenticationCallHandler.java:57)
>	at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
>	at io.undertow.security.handlers.AuthenticationConstraintHandler.handleRequest(AuthenticationConstraintHandler.java:53)
>	at io.undertow.security.handlers.AbstractConfidentialityHandler.handleRequest(AbstractConfidentialityHandler.java:46)
>	at io.undertow.servlet.handlers.security.ServletConfidentialityConstraintHandler.handleRequest(ServletConfidentialityConstraintHandler.java:64)
>	at io.undertow.servlet.handlers.security.ServletSecurityConstraintHandler.handleRequest(ServletSecurityConstraintHandler.java:59)
>	at io.undertow.security.handlers.AuthenticationMechanismsHandler.handleRequest(AuthenticationMechanismsHandler.java:60)
>	at io.undertow.servlet.handlers.security.CachedAuthenticatedSessionHandler.handleRequest(CachedAuthenticatedSessionHandler.java:77)
>	at io.undertow.security.handlers.NotificationReceiverHandler.handleRequest(NotificationReceiverHandler.java:50)
>	at io.undertow.security.handlers.AbstractSecurityContextAssociationHandler.handleRequest(AbstractSecurityContextAssociationHandler.java:43)
>	at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
>	at org.wildfly.extension.undertow.security.jacc.JACCContextIdHandler.handleRequest(JACCContextIdHandler.java:61)
>	at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
>	at io.undertow.server.handlers.PredicateHandler.handleRequest(PredicateHandler.java:43)
>	at io.undertow.servlet.handlers.ServletInitialHandler.handleFirstRequest(ServletInitialHandler.java:292)
>	at io.undertow.servlet.handlers.ServletInitialHandler.access$100(ServletInitialHandler.java:81)
>	at io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:138)
>	at io.undertow.servlet.handlers.ServletInitialHandler$2.call(ServletInitialHandler.java:135)
>	at io.undertow.servlet.core.ServletRequestContextThreadSetupAction$1.call(ServletRequestContextThreadSetupAction.java:48)
>	at io.undertow.servlet.core.ContextClassLoaderSetupAction$1.call(ContextClassLoaderSetupAction.java:43)
>	at org.wildfly.extension.undertow.security.SecurityContextThreadSetupAction.lambda$create$0(SecurityContextThreadSetupAction.java:105)
>	at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1508)
>	at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1508)
>	at org.wildfly.extension.undertow.deployment.UndertowDeploymentInfoService$UndertowThreadSetupAction.lambda$create$0(UndertowDeploymentInfoService.java:1508)
>	at io.undertow.servlet.handlers.ServletInitialHandler.dispatchRequest(ServletInitialHandler.java:272)
>	at io.undertow.servlet.handlers.ServletInitialHandler.access$000(ServletInitialHandler.java:81)
>	at io.undertow.servlet.handlers.ServletInitialHandler$1.handleRequest(ServletInitialHandler.java:104)
>	at io.undertow.server.Connectors.executeRootHandler(Connectors.java:326)
>	at io.undertow.server.HttpServerExchange$1.run(HttpServerExchange.java:812)
>	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [rt.jar:1.8.0_171]
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [rt.jar:1.8.0_171]
>	at java.lang.Thread.run(Thread.java:748) [rt.jar:1.8.0_171]
>Caused by: java.lang.NullPointerException
>	at org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageReturn.<init>(PrepareImageReturn.java:15) [vdsbroker.jar:]
>	at org.ovirt.engine.core.vdsbroker.jsonrpc.JsonRpcVdsServer.prepareImage(JsonRpcVdsServer.java:1898) [vdsbroker.jar:]
>	at org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand.executeImageActionVdsBrokerCommand(PrepareImageVDSCommand.java:18) [vdsbroker.jar:]
>	at org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand.executeImageActionVdsBrokerCommand(PrepareImageVDSCommand.java:5) [vdsbroker.jar:]
>	at org.ovirt.engine.core.vdsbroker.vdsbroker.ImageActionsVDSCommandBase.executeVdsBrokerCommand(ImageActionsVDSCommandBase.java:14) [vdsbroker.jar:]
>	at org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand.executeVdsCommandWithNetworkEvent(VdsBrokerCommand.java:123) [vdsbroker.jar:]
>	at org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand.executeVDSCommand(VdsBrokerCommand.java:111) [vdsbroker.jar:]
>	at org.ovirt.engine.core.vdsbroker.VDSCommandBase.executeCommand(VDSCommandBase.java:65) [vdsbroker.jar:]
>	at org.ovirt.engine.core.dal.VdcCommandBase.execute(VdcCommandBase.java:31) [dal.jar:]
>	at org.ovirt.engine.core.vdsbroker.vdsbroker.DefaultVdsCommandExecutor.execute(DefaultVdsCommandExecutor.java:14) [vdsbroker.jar:]
>	at org.ovirt.engine.core.vdsbroker.ResourceManager.runVdsCommand(ResourceManager.java:399) [vdsbroker.jar:]
>	at org.ovirt.engine.core.vdsbroker.ResourceManager$Proxy$_$$_WeldSubclass.runVdsCommand$$super(Unknown Source) [vdsbroker.jar:]
>	at sun.reflect.GeneratedMethodAccessor87.invoke(Unknown Source) [:1.8.0_171]
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_171]
>	at java.lang.reflect.Method.invoke(Method.java:498) [rt.jar:1.8.0_171]
>	at org.jboss.weld.interceptor.proxy.TerminalAroundInvokeInvocationContext.proceedInternal(TerminalAroundInvokeInvocationContext.java:49) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final]
>	at org.jboss.weld.interceptor.proxy.AroundInvokeInvocationContext.proceed(AroundInvokeInvocationContext.java:77) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final]
>	at org.ovirt.engine.core.common.di.interceptor.LoggingInterceptor.apply(LoggingInterceptor.java:12) [common.jar:]
>	at sun.reflect.GeneratedMethodAccessor64.invoke(Unknown Source) [:1.8.0_171]
>	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_171]
>	at java.lang.reflect.Method.invoke(Method.java:498) [rt.jar:1.8.0_171]
>	at org.jboss.weld.interceptor.reader.SimpleInterceptorInvocation$SimpleMethodInvocation.invoke(SimpleInterceptorInvocation.java:73) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final]
>	at org.jboss.weld.interceptor.proxy.InterceptorMethodHandler.executeAroundInvoke(InterceptorMethodHandler.java:84) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final]
>	at org.jboss.weld.interceptor.proxy.InterceptorMethodHandler.executeInterception(InterceptorMethodHandler.java:72) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final]
>	at org.jboss.weld.interceptor.proxy.InterceptorMethodHandler.invoke(InterceptorMethodHandler.java:56) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final]
>	at org.jboss.weld.bean.proxy.CombinedInterceptorAndDecoratorStackMethodHandler.invoke(CombinedInterceptorAndDecoratorStackMethodHandler.java:79) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final]
>	at org.jboss.weld.bean.proxy.CombinedInterceptorAndDecoratorStackMethodHandler.invoke(CombinedInterceptorAndDecoratorStackMethodHandler.java:68) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final]
>	at org.ovirt.engine.core.vdsbroker.ResourceManager$Proxy$_$$_WeldSubclass.runVdsCommand(Unknown Source) [vdsbroker.jar:]
>	... 203 more
>
>2018-06-29 20:36:54,818+03 ERROR [org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand] (default task-47) [b8987959-81ea-432e-9300-a3c449512693] Failed to add image ticket to ovirt-imageio-daemon
>2018-06-29 20:36:54,830+03 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-47) [b8987959-81ea-432e-9300-a3c449512693] EVENT_ID: TRANSFER_IMAGE_STOPPED_BY_SYSTEM_FAILED_TO_ADD_TICKET_TO_DAEMON(1,069), Transfer was stopped by system. Reason: failed to add image ticket to ovirt-imageio-daemon.
>2018-06-29 20:36:54,831+03 INFO  [org.ovirt.engine.core.bll.storage.disk.image.ImageTransferUpdater] (default task-47) [b8987959-81ea-432e-9300-a3c449512693] Updating image transfer c4d1cee1-7117-42e8-b3f9-146a9749fdb2 (image ba390f85-2d45-4ea1-8e29-96020c4ba416) phase to Paused by System
>2018-06-29 20:36:54,847+03 INFO  [org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand] (default task-47) [b8987959-81ea-432e-9300-a3c449512693] Returning from proceedCommandExecution after starting transfer session for image transfer command 'c4d1cee1-7117-42e8-b3f9-146a9749fdb2'
>2018-06-29 20:36:54,888+03 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-47) [b8987959-81ea-432e-9300-a3c449512693] EVENT_ID: TRANSFER_IMAGE_INITIATED(1,031), Image Upload with disk fedora-27-000 was initiated by admin@internal-authz.
>2018-06-29 20:36:56,842+03 INFO  [org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-1) [b8987959-81ea-432e-9300-a3c449512693] Transfer was paused by system. Upload disk 'fedora-27-000' (disk id: 'ba390f85-2d45-4ea1-8e29-96020c4ba416', image id: '55fb99f5-c2a3-4894-9942-400de5595531')
>2018-06-29 20:37:32,594+03 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'engine' is using 0 threads out of 500, 9 threads waiting for tasks and 0 tasks in queue.
>2018-06-29 20:37:32,594+03 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'engineScheduled' is using 0 threads out of 100, 100 threads waiting for tasks.
>2018-06-29 20:37:32,594+03 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'engineThreadMonitoring' is using 1 threads out of 1, 0 threads waiting for tasks.
>2018-06-29 20:37:32,594+03 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'hostUpdatesChecker' is using 0 threads out of 5, 3 threads waiting for tasks.
>2018-06-29 20:38:07,294+03 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (EE-ManagedThreadFactory-engineScheduled-Thread-14) [] Setting new tasks map. The map contains now 0 tasks
>2018-06-29 20:38:07,294+03 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (EE-ManagedThreadFactory-engineScheduled-Thread-14) [] Cleared all tasks of pool 'a0011271-88a4-491f-a566-aec38b2000e9'.
>2018-06-29 20:47:32,596+03 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'engine' is using 0 threads out of 500, 8 threads waiting for tasks and 0 tasks in queue.
>2018-06-29 20:47:32,596+03 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'engineScheduled' is using 0 threads out of 100, 100 threads waiting for tasks.
>2018-06-29 20:47:32,596+03 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'engineThreadMonitoring' is using 1 threads out of 1, 0 threads waiting for tasks.
>2018-06-29 20:47:32,597+03 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'hostUpdatesChecker' is using 0 threads out of 5, 3 threads waiting for tasks.
>2018-06-29 20:50:31,844+03 INFO  [org.ovirt.engine.core.sso.servlets.OAuthRevokeServlet] (default task-99) [] User admin@internal successfully logged out
>2018-06-29 20:50:31,855+03 INFO  [org.ovirt.engine.core.bll.aaa.TerminateSessionsForTokenCommand] (default task-105) [75dca4c] Running command: TerminateSessionsForTokenCommand internal: true.
>2018-06-29 20:50:31,881+03 INFO  [org.ovirt.engine.core.sso.servlets.OAuthRevokeServlet] (default task-106) [] User admin@internal successfully logged out
>2018-06-29 20:50:31,883+03 INFO  [org.ovirt.engine.core.bll.aaa.TerminateSessionsForTokenCommand] (default task-108) [286f8265] Running command: TerminateSessionsForTokenCommand internal: true.
>2018-06-29 20:55:31,845+03 INFO  [org.ovirt.engine.core.sso.servlets.OAuthRevokeServlet] (default task-1) [] User admin@internal successfully logged out
>2018-06-29 20:55:31,854+03 INFO  [org.ovirt.engine.core.bll.aaa.TerminateSessionsForTokenCommand] (default task-6) [42657266] Running command: TerminateSessionsForTokenCommand internal: true.
>2018-06-29 20:57:32,598+03 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'engine' is using 0 threads out of 500, 8 threads waiting for tasks and 0 tasks in queue.
>2018-06-29 20:57:32,598+03 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'engineScheduled' is using 0 threads out of 100, 100 threads waiting for tasks.
>2018-06-29 20:57:32,598+03 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'engineThreadMonitoring' is using 1 threads out of 1, 0 threads waiting for tasks.
>2018-06-29 20:57:32,598+03 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'hostUpdatesChecker' is using 0 threads out of 5, 3 threads waiting for tasks.
>2018-06-29 21:04:31,858+03 INFO  [org.ovirt.engine.core.sso.servlets.OAuthRevokeServlet] (default task-42) [] User admin@internal successfully logged out
>2018-06-29 21:04:31,867+03 INFO  [org.ovirt.engine.core.bll.aaa.TerminateSessionsForTokenCommand] (default task-47) [6b8267db] Running command: TerminateSessionsForTokenCommand internal: true.
>2018-06-29 21:05:31,851+03 INFO  [org.ovirt.engine.core.sso.servlets.OAuthRevokeServlet] (default task-56) [] User admin@internal successfully logged out
>2018-06-29 21:05:31,855+03 INFO  [org.ovirt.engine.core.bll.aaa.TerminateSessionsForTokenCommand] (default task-55) [7a656a58] Running command: TerminateSessionsForTokenCommand internal: true.
>2018-06-29 21:07:00,167+03 INFO  [org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-33) [b8987959-81ea-432e-9300-a3c449512693] Transfer was paused by system. Upload disk 'fedora-27-000' (disk id: 'ba390f85-2d45-4ea1-8e29-96020c4ba416', image id: '55fb99f5-c2a3-4894-9942-400de5595531')
>2018-06-29 21:07:32,601+03 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'engine' is using 0 threads out of 500, 8 threads waiting for tasks and 0 tasks in queue.
>2018-06-29 21:07:32,601+03 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'engineScheduled' is using 0 threads out of 100, 100 threads waiting for tasks.
>2018-06-29 21:07:32,601+03 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'engineThreadMonitoring' is using 1 threads out of 1, 0 threads waiting for tasks.
>2018-06-29 21:07:32,601+03 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'hostUpdatesChecker' is using 0 threads out of 5, 3 threads waiting for tasks.
>2018-06-29 21:17:32,605+03 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'engine' is using 0 threads out of 500, 8 threads waiting for tasks and 0 tasks in queue.
>2018-06-29 21:17:32,605+03 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'engineScheduled' is using 0 threads out of 100, 100 threads waiting for tasks.
>2018-06-29 21:17:32,605+03 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'engineThreadMonitoring' is using 1 threads out of 1, 0 threads waiting for tasks.
>2018-06-29 21:17:32,605+03 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'hostUpdatesChecker' is using 0 threads out of 5, 3 threads waiting for tasks.
>2018-06-29 21:17:34,304+03 INFO  [org.ovirt.engine.core.bll.storage.ovfstore.OvfDataUpdater] (EE-ManagedThreadFactory-engineScheduled-Thread-82) [] Attempting to update VMs/Templates Ovf.
>2018-06-29 21:17:34,306+03 INFO  [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-82) [7d3320b4] Before acquiring and wait lock 'EngineLock:{exclusiveLocks='[a0011271-88a4-491f-a566-aec38b2000e9=OVF_UPDATE]', sharedLocks=''}'
>2018-06-29 21:17:34,306+03 INFO  [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-82) [7d3320b4] Lock-wait acquired to object 'EngineLock:{exclusiveLocks='[a0011271-88a4-491f-a566-aec38b2000e9=OVF_UPDATE]', sharedLocks=''}'
>2018-06-29 21:17:34,308+03 INFO  [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-82) [7d3320b4] Running command: ProcessOvfUpdateForStoragePoolCommand internal: true. Entities affected :  ID: a0011271-88a4-491f-a566-aec38b2000e9 Type: StoragePool
>2018-06-29 21:17:34,313+03 INFO  [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-82) [7d3320b4] Attempting to update VM OVFs in Data Center 'v43'
>2018-06-29 21:17:34,314+03 INFO  [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-82) [7d3320b4] Successfully updated VM OVFs in Data Center 'v43'
>2018-06-29 21:17:34,314+03 INFO  [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-82) [7d3320b4] Attempting to update template OVFs in Data Center 'v43'
>2018-06-29 21:17:34,314+03 INFO  [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-82) [7d3320b4] Successfully updated templates OVFs in Data Center 'v43'
>2018-06-29 21:17:34,314+03 INFO  [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-82) [7d3320b4] Attempting to remove unneeded template/vm OVFs in Data Center 'v43'
>2018-06-29 21:17:34,315+03 INFO  [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-82) [7d3320b4] Successfully removed unneeded template/vm OVFs in Data Center 'v43'
>2018-06-29 21:17:34,316+03 INFO  [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-82) [7d3320b4] Lock freed to object 'EngineLock:{exclusiveLocks='[a0011271-88a4-491f-a566-aec38b2000e9=OVF_UPDATE]', sharedLocks=''}'

Actions: View

Attachments on bug 1596810: 1455544 | 1455546