Hi All, after adding an oVirt node as a local storage machine I am unable to
upload a disk to the datastore. The button "test connection" shows:
"Connection to ovirt-imageio was successful.".
Version: 4.5.4-1.el8
OS: AlmaLinux 8.9 (Midnight Oncilla)
Below a excerpt from the engine.log showing the entire upload session.
Note 1: The machine "kvm-sandbox-qm7" is the machine for the
localstorage cluster. The machine "kvm-sandbox-gcz" is a machine from
the other "default cluster" and I was under the impression that the two
clusters would be completely separate things and should not interfere
with each other. But I am not sure about that.
--- snip ---
2024-01-03 10:05:26,898Z INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand] (default task-1)
[7855db64-635d-430f-9de8-21b1983e43a0] Lock Acquired to object
'EngineLock:{exclusiveLocks='[]', sharedLocks='[]'}'
2024-01-03 10:05:26,938Z INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand] (default task-1)
[7855db64-635d-430f-9de8-21b1983e43a0] Running command: TransferDiskImageCommand internal:
false. Entities affected : ID: 95dfc5bc-2a31-405c-ada0-6015edd281da Type: StorageAction
group CREATE_DISK with role type USER
2024-01-03 10:05:26,938Z INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand] (default task-1)
[7855db64-635d-430f-9de8-21b1983e43a0] Creating ImageTransfer entity for command
'ec704c40-89bc-4fdf-a44a-607dd7b9b2f7', proxyEnabled: true
2024-01-03 10:05:26,940Z INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand] (default task-1)
[7855db64-635d-430f-9de8-21b1983e43a0] Starting image transfer:
ImageTransfer:{id='ec704c40-89bc-4fdf-a44a-607dd7b9b2f7',
phase='Initializing', type='Upload', active='false',
lastUpdated='Wed Jan 03 10:05:26 UTC 2024', message='null',
vdsId='null', diskId='null', imagedTicketId='null',
proxyUri='null', bytesSent='null', bytesTotal='697434112',
clientInactivityTimeout='60', timeoutPolicy='legacy',
imageFormat='COW', transferClientType='Transfer via browser',
shallow='false'}
2024-01-03 10:05:26,940Z INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand] (default task-1)
[7855db64-635d-430f-9de8-21b1983e43a0] Creating disk image
2024-01-03 10:05:26,953Z INFO [org.ovirt.engine.core.bll.storage.disk.AddDiskCommand]
(default task-1) [7855db64-635d-430f-9de8-21b1983e43a0] Running command: AddDiskCommand
internal: true. Entities affected : ID: 95dfc5bc-2a31-405c-ada0-6015edd281da Type:
StorageAction group CREATE_DISK with role type USER
2024-01-03 10:05:26,961Z INFO
[org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand] (default task-1)
[7855db64-635d-430f-9de8-21b1983e43a0] Running command: AddImageFromScratchCommand
internal: true. Entities affected : ID: 95dfc5bc-2a31-405c-ada0-6015edd281da Type:
Storage
2024-01-03 10:05:26,981Z INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.CreateVolumeVDSCommand] (default task-1)
[7855db64-635d-430f-9de8-21b1983e43a0] START, CreateVolumeVDSCommand(
CreateVolumeVDSCommandParameters:{storagePoolId='4c5f1e92-239b-471c-9db0-970029129a62',
ignoreFailoverLimit='false',
storageDomainId='95dfc5bc-2a31-405c-ada0-6015edd281da',
imageGroupId='13dcaf25-6b58-4c79-85a7-0aecd153fb59',
imageSizeInBytes='10737418240', volumeFormat='COW',
newImageId='6b80fba5-c2ae-4b68-a24d-21d7f657da8f', imageType='Sparse',
newImageDescription='{"DiskAlias":"aaa","DiskDescription":""}',
imageInitialSizeInBytes='0',
imageId='00000000-0000-0000-0000-000000000000',
sourceImageGroupId='00000000-0000-0000-0000-000000000000',
shouldAddBitmaps='false', legal='true', sequenceNumber='1',
bitmap='null'}), log id: b4c79ef
2024-01-03 10:05:27,406Z INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.CreateVolumeVDSCommand] (default task-1)
[7855db64-635d-430f-9de8-21b1983e43a0] FINISH, CreateVolumeVDSCommand, return:
6b80fba5-c2ae-4b68-a24d-21d7f657da8f, log id: b4c79ef
2024-01-03 10:05:27,409Z INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (default
task-1) [7855db64-635d-430f-9de8-21b1983e43a0] CommandAsyncTask::Adding
CommandMultiAsyncTasks object for command 'a6f3ff83-daa4-4799-908a-07029ff8f6ef'
2024-01-03 10:05:27,410Z INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (default
task-1) [7855db64-635d-430f-9de8-21b1983e43a0] CommandMultiAsyncTasks::attachTask:
Attaching task '5a29a235-b61c-4efb-959a-f29ae7f863be' to command
'a6f3ff83-daa4-4799-908a-07029ff8f6ef'.
2024-01-03 10:05:27,427Z INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (default
task-1) [7855db64-635d-430f-9de8-21b1983e43a0] Adding task
'5a29a235-b61c-4efb-959a-f29ae7f863be' (Parent Command
'AddImageFromScratch', Parameters Type
'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters'), polling hasn't
started yet..
2024-01-03 10:05:27,435Z INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (default
task-1) [7855db64-635d-430f-9de8-21b1983e43a0] BaseAsyncTask::startPollingTask: Starting
to poll task '5a29a235-b61c-4efb-959a-f29ae7f863be'.
2024-01-03 10:05:27,449Z INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-1)
[7855db64-635d-430f-9de8-21b1983e43a0] EVENT_ID: ADD_DISK_INTERNAL(2,036), Add-Disk
operation of 'aaa' was initiated by the system.
2024-01-03 10:05:27,457Z INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-1)
[7855db64-635d-430f-9de8-21b1983e43a0] EVENT_ID: TRANSFER_IMAGE_INITIATED(1,031), Image
Upload with disk aaa was initiated by [[redacted user]]@[[redacted]]@[[redacted]].
2024-01-03 10:05:27,866Z INFO
[org.ovirt.engine.core.bll.storage.disk.AddDiskCommandCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-95)
[7855db64-635d-430f-9de8-21b1983e43a0] Command 'AddDisk' (id:
'dfe023fa-0a96-40e8-9934-fb94a156bff6') waiting on child command id:
'a6f3ff83-daa4-4799-908a-07029ff8f6ef' type:'AddImageFromScratch' to
complete
2024-01-03 10:05:27,869Z INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-95)
[7855db64-635d-430f-9de8-21b1983e43a0] Waiting for disk to be added for image transfer
'ec704c40-89bc-4fdf-a44a-607dd7b9b2f7'
2024-01-03 10:05:29,872Z INFO
[org.ovirt.engine.core.bll.storage.disk.AddDiskCommandCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-16)
[7855db64-635d-430f-9de8-21b1983e43a0] Command 'AddDisk' (id:
'dfe023fa-0a96-40e8-9934-fb94a156bff6') waiting on child command id:
'a6f3ff83-daa4-4799-908a-07029ff8f6ef' type:'AddImageFromScratch' to
complete
2024-01-03 10:05:29,875Z INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-16)
[7855db64-635d-430f-9de8-21b1983e43a0] Waiting for disk to be added for image transfer
'ec704c40-89bc-4fdf-a44a-607dd7b9b2f7'
2024-01-03 10:05:30,014Z INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-46) [] Polling and
updating Async Tasks: 1 tasks, 1 tasks to poll now
2024-01-03 10:05:30,019Z INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-46) []
SPMAsyncTask::PollTask: Polling task '5a29a235-b61c-4efb-959a-f29ae7f863be'
(Parent Command 'AddImageFromScratch', Parameters Type
'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') returned status
'finished', result 'success'.
2024-01-03 10:05:30,019Z INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-46) []
BaseAsyncTask::onTaskEndSuccess: Task '5a29a235-b61c-4efb-959a-f29ae7f863be'
(Parent Command 'AddImageFromScratch', Parameters Type
'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') ended
successfully.
2024-01-03 10:05:30,021Z INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-46) []
CommandAsyncTask::endActionIfNecessary: All tasks of command
'a6f3ff83-daa4-4799-908a-07029ff8f6ef' has ended -> executing
'endAction'
2024-01-03 10:05:30,022Z INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-46) []
CommandAsyncTask::endAction: Ending action for '1' tasks (command ID:
'a6f3ff83-daa4-4799-908a-07029ff8f6ef'): calling endAction '.
2024-01-03 10:05:30,022Z INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(EE-ManagedThreadFactory-engine-Thread-2956) [] CommandAsyncTask::endCommandAction [within
thread] context: Attempting to endAction 'AddImageFromScratch',
2024-01-03 10:05:30,027Z INFO
[org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand]
(EE-ManagedThreadFactory-engine-Thread-2956) [7855db64-635d-430f-9de8-21b1983e43a0]
Command [id=a6f3ff83-daa4-4799-908a-07029ff8f6ef]: Updating status to 'SUCCEEDED',
The command end method logic will be executed by one of its parent commands.
2024-01-03 10:05:30,027Z INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(EE-ManagedThreadFactory-engine-Thread-2956) [7855db64-635d-430f-9de8-21b1983e43a0]
CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type
'AddImageFromScratch' completed, handling the result.
2024-01-03 10:05:30,027Z INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(EE-ManagedThreadFactory-engine-Thread-2956) [7855db64-635d-430f-9de8-21b1983e43a0]
CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type
'AddImageFromScratch' succeeded, clearing tasks.
2024-01-03 10:05:30,027Z INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(EE-ManagedThreadFactory-engine-Thread-2956) [7855db64-635d-430f-9de8-21b1983e43a0]
SPMAsyncTask::ClearAsyncTask: Attempting to clear task
'5a29a235-b61c-4efb-959a-f29ae7f863be'
2024-01-03 10:05:30,028Z INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-2956) [7855db64-635d-430f-9de8-21b1983e43a0] START,
SPMClearTaskVDSCommand(
SPMTaskGuidBaseVDSCommandParameters:{storagePoolId='4c5f1e92-239b-471c-9db0-970029129a62',
ignoreFailoverLimit='false',
taskId='5a29a235-b61c-4efb-959a-f29ae7f863be'}), log id: af95363
2024-01-03 10:05:30,028Z INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-2956) [7855db64-635d-430f-9de8-21b1983e43a0] START,
HSMClearTaskVDSCommand(HostName = kvm-sandbox-qm7,
HSMTaskGuidBaseVDSCommandParameters:{hostId='9fb846b0-58cf-41ab-875c-3e3118a24b89',
taskId='5a29a235-b61c-4efb-959a-f29ae7f863be'}), log id: 52de524b
2024-01-03 10:05:30,044Z INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-2956) [7855db64-635d-430f-9de8-21b1983e43a0]
FINISH, HSMClearTaskVDSCommand, return: , log id: 52de524b
2024-01-03 10:05:30,044Z INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-2956) [7855db64-635d-430f-9de8-21b1983e43a0]
FINISH, SPMClearTaskVDSCommand, return: , log id: af95363
2024-01-03 10:05:30,050Z INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(EE-ManagedThreadFactory-engine-Thread-2956) [7855db64-635d-430f-9de8-21b1983e43a0]
BaseAsyncTask::removeTaskFromDB: Removed task
'5a29a235-b61c-4efb-959a-f29ae7f863be' from DataBase
2024-01-03 10:05:30,050Z INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(EE-ManagedThreadFactory-engine-Thread-2956) [7855db64-635d-430f-9de8-21b1983e43a0]
CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks
object for entity 'a6f3ff83-daa4-4799-908a-07029ff8f6ef'
2024-01-03 10:05:31,567Z INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferImageStatusCommand] (default task-6)
[1ab78812-4bb8-4889-bea0-d2f2a82d52af] Running command: TransferImageStatusCommand
internal: false. Entities affected : ID: 95dfc5bc-2a31-405c-ada0-6015edd281da Type:
SystemAction group CREATE_DISK with role type USER
2024-01-03 10:05:33,877Z INFO
[org.ovirt.engine.core.bll.storage.disk.AddDiskCommandCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[7855db64-635d-430f-9de8-21b1983e43a0] Getting volume info for image
'13dcaf25-6b58-4c79-85a7-0aecd153fb59/6b80fba5-c2ae-4b68-a24d-21d7f657da8f'
2024-01-03 10:05:33,894Z INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[7855db64-635d-430f-9de8-21b1983e43a0] START, GetVolumeInfoVDSCommand(HostName =
kvm-sandbox-qm7,
GetVolumeInfoVDSCommandParameters:{hostId='9fb846b0-58cf-41ab-875c-3e3118a24b89',
storagePoolId='4c5f1e92-239b-471c-9db0-970029129a62',
storageDomainId='95dfc5bc-2a31-405c-ada0-6015edd281da',
imageGroupId='13dcaf25-6b58-4c79-85a7-0aecd153fb59',
imageId='6b80fba5-c2ae-4b68-a24d-21d7f657da8f'}), log id: 78edc340
2024-01-03 10:05:33,908Z INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[7855db64-635d-430f-9de8-21b1983e43a0] FINISH, GetVolumeInfoVDSCommand, return:
org.ovirt.engine.core.common.businessentities.storage.DiskImage@14184952, log id:
78edc340
2024-01-03 10:05:33,908Z INFO
[org.ovirt.engine.core.bll.storage.disk.AddDiskCommandCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[7855db64-635d-430f-9de8-21b1983e43a0] Command 'AddDisk' id:
'dfe023fa-0a96-40e8-9934-fb94a156bff6' child commands
'[a6f3ff83-daa4-4799-908a-07029ff8f6ef]' executions were completed, status
'SUCCEEDED'
2024-01-03 10:05:33,967Z INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[7855db64-635d-430f-9de8-21b1983e43a0] Successfully added Upload disk 'aaa' (disk
id: '13dcaf25-6b58-4c79-85a7-0aecd153fb59', image id:
'6b80fba5-c2ae-4b68-a24d-21d7f657da8f') for image transfer
'ec704c40-89bc-4fdf-a44a-607dd7b9b2f7'
2024-01-03 10:05:33,978Z INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[7855db64-635d-430f-9de8-21b1983e43a0] START, PrepareImageVDSCommand(HostName =
kvm-sandbox-gcz,
PrepareImageVDSCommandParameters:{hostId='059c7eaf-da39-41f2-bb61-659ab3bd1b61'}),
log id: 104764a4
2024-01-03 10:05:33,981Z ERROR
[org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[7855db64-635d-430f-9de8-21b1983e43a0] Failed in 'PrepareImageVDS' method, for
vds: 'kvm-sandbox-gcz'; host:
'kvm-sandbox-gcz.hprvsr.infra.pdc.[[redacted]]': null
2024-01-03 10:05:33,982Z ERROR
[org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[7855db64-635d-430f-9de8-21b1983e43a0] Command 'PrepareImageVDSCommand(HostName =
kvm-sandbox-gcz,
PrepareImageVDSCommandParameters:{hostId='059c7eaf-da39-41f2-bb61-659ab3bd1b61'})'
execution failed: null
2024-01-03 10:05:33,982Z INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[7855db64-635d-430f-9de8-21b1983e43a0] FINISH, PrepareImageVDSCommand, return: , log id:
104764a4
2024-01-03 10:05:33,982Z ERROR
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[7855db64-635d-430f-9de8-21b1983e43a0] Failed to prepare image for image transfer
'ec704c40-89bc-4fdf-a44a-607dd7b9b2f7': {}:
org.ovirt.engine.core.common.errors.EngineException: EngineException:
java.lang.NullPointerException (Failed with error ENGINE and code 5001)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.VdsHandler.handleVdsResult(VdsHandler.java:114)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.VDSBrokerFrontendImpl.runVdsCommand(VDSBrokerFrontendImpl.java:33)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.runVdsCommand(CommandBase.java:2121)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand.prepareImage(TransferDiskImageCommand.java:188)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand.startImageTransferSession(TransferDiskImageCommand.java:1064)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand.handleImageIsReadyForTransfer(TransferDiskImageCommand.java:681)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand.handleInitializing(TransferDiskImageCommand.java:654)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand.executeStateHandler(TransferDiskImageCommand.java:587)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand.proceedCommandExecution(TransferDiskImageCommand.java:574)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.storage.disk.image.TransferImageCommandCallback.doPolling(TransferImageCommandCallback.java:21)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller.invokeCallbackMethodsImpl(CommandCallbacksPoller.java:175)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller.invokeCallbackMethods(CommandCallbacksPoller.java:109)
at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
at java.base/java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305)
at
org.glassfish.javax.enterprise.concurrent//org.glassfish.enterprise.concurrent.internal.ManagedScheduledThreadPoolExecutor$ManagedScheduledFutureTask.access$201(ManagedScheduledThreadPoolExecutor.java:360)
at
org.glassfish.javax.enterprise.concurrent//org.glassfish.enterprise.concurrent.internal.ManagedScheduledThreadPoolExecutor$ManagedScheduledFutureTask.run(ManagedScheduledThreadPoolExecutor.java:511)
at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:829)
at
org.glassfish.javax.enterprise.concurrent//org.glassfish.enterprise.concurrent.ManagedThreadFactoryImpl$ManagedThread.run(ManagedThreadFactoryImpl.java:227)
Caused by: java.lang.NullPointerException
at
deployment.engine.ear//org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageReturn.<init>(PrepareImageReturn.java:15)
at
deployment.engine.ear//org.ovirt.engine.core.vdsbroker.jsonrpc.JsonRpcVdsServer.prepareImage(JsonRpcVdsServer.java:1947)
at
deployment.engine.ear//org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand.executeImageActionVdsBrokerCommand(PrepareImageVDSCommand.java:18)
at
deployment.engine.ear//org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand.executeImageActionVdsBrokerCommand(PrepareImageVDSCommand.java:5)
at
deployment.engine.ear//org.ovirt.engine.core.vdsbroker.vdsbroker.ImageActionsVDSCommandBase.executeVdsBrokerCommand(ImageActionsVDSCommandBase.java:14)
at
deployment.engine.ear//org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand.executeVdsCommandWithNetworkEvent(VdsBrokerCommand.java:123)
at
deployment.engine.ear//org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand.executeVDSCommand(VdsBrokerCommand.java:111)
at
deployment.engine.ear//org.ovirt.engine.core.vdsbroker.VDSCommandBase.executeCommand(VDSCommandBase.java:65)
at
org.ovirt.engine.core.dal//org.ovirt.engine.core.dal.VdcCommandBase.execute(VdcCommandBase.java:31)
at
deployment.engine.ear//org.ovirt.engine.core.vdsbroker.vdsbroker.DefaultVdsCommandExecutor.execute(DefaultVdsCommandExecutor.java:14)
at
deployment.engine.ear//org.ovirt.engine.core.vdsbroker.ResourceManager.runVdsCommand(ResourceManager.java:410)
at
deployment.engine.ear//org.ovirt.engine.core.vdsbroker.ResourceManager$Proxy$_$$_WeldSubclass.runVdsCommand$$super(Unknown
Source)
at jdk.internal.reflect.GeneratedMethodAccessor87.invoke(Unknown Source)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at
org.jboss.weld.core@3.1.7.SP1//org.jboss.weld.interceptor.proxy.TerminalAroundInvokeInvocationContext.proceedInternal(TerminalAroundInvokeInvocationContext.java:51)
at
org.jboss.weld.core@3.1.7.SP1//org.jboss.weld.interceptor.proxy.AroundInvokeInvocationContext.proceed(AroundInvokeInvocationContext.java:78)
at
org.ovirt.engine.core.common//org.ovirt.engine.core.common.di.interceptor.LoggingInterceptor.apply(LoggingInterceptor.java:12)
at jdk.internal.reflect.GeneratedMethodAccessor80.invoke(Unknown Source)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at
org.jboss.weld.core@3.1.7.SP1//org.jboss.weld.interceptor.reader.SimpleInterceptorInvocation$SimpleMethodInvocation.invoke(SimpleInterceptorInvocation.java:73)
at
org.jboss.weld.core@3.1.7.SP1//org.jboss.weld.interceptor.proxy.InterceptorMethodHandler.executeAroundInvoke(InterceptorMethodHandler.java:84)
at
org.jboss.weld.core@3.1.7.SP1//org.jboss.weld.interceptor.proxy.InterceptorMethodHandler.executeInterception(InterceptorMethodHandler.java:72)
at
org.jboss.weld.core@3.1.7.SP1//org.jboss.weld.interceptor.proxy.InterceptorMethodHandler.invoke(InterceptorMethodHandler.java:56)
at
org.jboss.weld.core@3.1.7.SP1//org.jboss.weld.bean.proxy.CombinedInterceptorAndDecoratorStackMethodHandler.invoke(CombinedInterceptorAndDecoratorStackMethodHandler.java:79)
at
org.jboss.weld.core@3.1.7.SP1//org.jboss.weld.bean.proxy.CombinedInterceptorAndDecoratorStackMethodHandler.invoke(CombinedInterceptorAndDecoratorStackMethodHandler.java:68)
at
deployment.engine.ear//org.ovirt.engine.core.vdsbroker.ResourceManager$Proxy$_$$_WeldSubclass.runVdsCommand(Unknown
Source)
... 19 more
2024-01-03 10:05:34,991Z INFO [org.ovirt.engine.core.bll.storage.disk.AddDiskCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47)
[7855db64-635d-430f-9de8-21b1983e43a0] Ending command
'org.ovirt.engine.core.bll.storage.disk.AddDiskCommand' successfully.
2024-01-03 10:05:34,997Z INFO
[org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47)
[7855db64-635d-430f-9de8-21b1983e43a0] Ending command
'org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand'
successfully.
2024-01-03 10:05:35,010Z INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47)
[7855db64-635d-430f-9de8-21b1983e43a0] START, GetImageInfoVDSCommand(
GetImageInfoVDSCommandParameters:{storagePoolId='4c5f1e92-239b-471c-9db0-970029129a62',
ignoreFailoverLimit='false',
storageDomainId='95dfc5bc-2a31-405c-ada0-6015edd281da',
imageGroupId='13dcaf25-6b58-4c79-85a7-0aecd153fb59',
imageId='6b80fba5-c2ae-4b68-a24d-21d7f657da8f'}), log id: 127cba80
2024-01-03 10:05:35,011Z INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47)
[7855db64-635d-430f-9de8-21b1983e43a0] START, GetVolumeInfoVDSCommand(HostName =
kvm-sandbox-qm7,
GetVolumeInfoVDSCommandParameters:{hostId='9fb846b0-58cf-41ab-875c-3e3118a24b89',
storagePoolId='4c5f1e92-239b-471c-9db0-970029129a62',
storageDomainId='95dfc5bc-2a31-405c-ada0-6015edd281da',
imageGroupId='13dcaf25-6b58-4c79-85a7-0aecd153fb59',
imageId='6b80fba5-c2ae-4b68-a24d-21d7f657da8f'}), log id: 1f0ed2a8
2024-01-03 10:05:35,024Z INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47)
[7855db64-635d-430f-9de8-21b1983e43a0] FINISH, GetVolumeInfoVDSCommand, return:
org.ovirt.engine.core.common.businessentities.storage.DiskImage@14184952, log id:
1f0ed2a8
2024-01-03 10:05:35,024Z INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47)
[7855db64-635d-430f-9de8-21b1983e43a0] FINISH, GetImageInfoVDSCommand, return:
org.ovirt.engine.core.common.businessentities.storage.DiskImage@14184952, log id:
127cba80
2024-01-03 10:05:35,046Z INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47)
[7855db64-635d-430f-9de8-21b1983e43a0] START, PrepareImageVDSCommand(HostName =
kvm-sandbox-qm7,
PrepareImageVDSCommandParameters:{hostId='9fb846b0-58cf-41ab-875c-3e3118a24b89'}),
log id: 39df22a1
2024-01-03 10:05:35,076Z INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47)
[7855db64-635d-430f-9de8-21b1983e43a0] FINISH, PrepareImageVDSCommand, return:
PrepareImageReturn:{status='Status [code=0, message=Done]'}, log id: 39df22a1
2024-01-03 10:05:35,077Z INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetQemuImageInfoVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47)
[7855db64-635d-430f-9de8-21b1983e43a0] START, GetQemuImageInfoVDSCommand(HostName =
kvm-sandbox-qm7,
GetVolumeInfoVDSCommandParameters:{hostId='9fb846b0-58cf-41ab-875c-3e3118a24b89',
storagePoolId='4c5f1e92-239b-471c-9db0-970029129a62',
storageDomainId='95dfc5bc-2a31-405c-ada0-6015edd281da',
imageGroupId='13dcaf25-6b58-4c79-85a7-0aecd153fb59',
imageId='6b80fba5-c2ae-4b68-a24d-21d7f657da8f'}), log id: 59083d66
2024-01-03 10:05:35,093Z INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetQemuImageInfoVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47)
[7855db64-635d-430f-9de8-21b1983e43a0] FINISH, GetQemuImageInfoVDSCommand, return:
org.ovirt.engine.core.common.businessentities.storage.QemuImageInfo@12025249, log id:
59083d66
2024-01-03 10:05:35,095Z INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.TeardownImageVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47)
[7855db64-635d-430f-9de8-21b1983e43a0] START, TeardownImageVDSCommand(HostName =
kvm-sandbox-qm7,
ImageActionsVDSCommandParameters:{hostId='9fb846b0-58cf-41ab-875c-3e3118a24b89'}),
log id: 2c3b32ab
2024-01-03 10:05:35,097Z INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.TeardownImageVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47)
[7855db64-635d-430f-9de8-21b1983e43a0] FINISH, TeardownImageVDSCommand, return:
StatusReturn:{status='Status [code=0, message=Done]'}, log id: 2c3b32ab
2024-01-03 10:05:35,106Z WARN [org.ovirt.engine.core.bll.storage.disk.AddDiskCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47) [] VM is null -
no unlocking
2024-01-03 10:05:35,132Z INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47) [] EVENT_ID:
USER_ADD_DISK_FINISHED_SUCCESS(2,021), The disk 'aaa' was successfully added.
2024-01-03 10:05:35,134Z ERROR
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47)
[7855db64-635d-430f-9de8-21b1983e43a0] Ending command
'org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand' with
failure.
2024-01-03 10:05:35,134Z ERROR
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47)
[7855db64-635d-430f-9de8-21b1983e43a0] Failed to transfer disk
'00000000-0000-0000-0000-000000000000' for image transfer
'ec704c40-89bc-4fdf-a44a-607dd7b9b2f7'
2024-01-03 10:05:35,157Z INFO [org.ovirt.engine.core.bll.storage.disk.RemoveDiskCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47) [5871c85c]
Running command: RemoveDiskCommand internal: true. Entities affected : ID:
13dcaf25-6b58-4c79-85a7-0aecd153fb59 Type: DiskAction group DELETE_DISK with role type
USER
2024-01-03 10:05:35,176Z INFO
[org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47) [5871c85c]
Running command: RemoveImageCommand internal: true. Entities affected : ID:
95dfc5bc-2a31-405c-ada0-6015edd281da Type: Storage
2024-01-03 10:05:35,202Z INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47) [5871c85c] START,
DeleteImageGroupVDSCommand(
DeleteImageGroupVDSCommandParameters:{storagePoolId='4c5f1e92-239b-471c-9db0-970029129a62',
ignoreFailoverLimit='false',
storageDomainId='95dfc5bc-2a31-405c-ada0-6015edd281da',
imageGroupId='13dcaf25-6b58-4c79-85a7-0aecd153fb59', postZeros='false',
discard='false', forceDelete='false'}), log id: 10fab46b
2024-01-03 10:05:35,449Z INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47) [5871c85c]
FINISH, DeleteImageGroupVDSCommand, return: , log id: 10fab46b
2024-01-03 10:05:35,451Z INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47) [5871c85c]
CommandAsyncTask::Adding CommandMultiAsyncTasks object for command
'8640049f-0ead-486a-93b4-dcbbaa353294'
2024-01-03 10:05:35,451Z INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47) [5871c85c]
CommandMultiAsyncTasks::attachTask: Attaching task
'b51f31d8-944b-4539-beb6-a0ab995073c6' to command
'8640049f-0ead-486a-93b4-dcbbaa353294'.
2024-01-03 10:05:35,463Z INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47) [5871c85c] Adding
task 'b51f31d8-944b-4539-beb6-a0ab995073c6' (Parent Command 'RemoveImage',
Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters'),
polling hasn't started yet..
2024-01-03 10:05:35,468Z INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47) [5871c85c]
BaseAsyncTask::startPollingTask: Starting to poll task
'b51f31d8-944b-4539-beb6-a0ab995073c6'.
2024-01-03 10:05:35,468Z INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47) [5871c85c]
BaseAsyncTask::startPollingTask: Starting to poll task
'b51f31d8-944b-4539-beb6-a0ab995073c6'.
2024-01-03 10:05:35,533Z INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47) [5871c85c]
EVENT_ID: USER_FINISHED_REMOVE_DISK(2,014), Disk aaa was successfully removed from domain
localstorage (User [[redacted user]]@[[redacted]]@[[redacted]]).
2024-01-03 10:05:35,534Z INFO [org.ovirt.engine.core.bll.storage.disk.RemoveDiskCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47) [5871c85c] Lock
freed to object 'EngineLock:{exclusiveLocks='[]',
sharedLocks='[]'}'
2024-01-03 10:05:35,534Z INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferDiskImageCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47) [5871c85c] Lock
freed to object 'EngineLock:{exclusiveLocks='[]',
sharedLocks='[]'}'
2024-01-03 10:05:35,535Z INFO
[org.ovirt.engine.core.bll.storage.disk.image.ImageTransferUpdater]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47) [5871c85c]
Updating image transfer 'ec704c40-89bc-4fdf-a44a-607dd7b9b2f7' phase from
'Initializing' to 'Finished Failure'
2024-01-03 10:05:35,547Z ERROR
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-47) [5871c85c]
EVENT_ID: TRANSFER_IMAGE_FAILED(1,034), Image Upload with disk aaa failed.
2024-01-03 10:05:35,646Z INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferImageStatusCommand] (default task-1)
[d4821bf6-c786-4129-a3b0-4a79fc2d61d7] Running command: TransferImageStatusCommand
internal: false. Entities affected : ID: 95dfc5bc-2a31-405c-ada0-6015edd281da Type:
SystemAction group CREATE_DISK with role type USER
2024-01-03 10:05:36,562Z INFO
[org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-62) [5871c85c]
Command 'RemoveDisk' (id: 'b01c95d8-b3c3-40db-9a41-6fe1e817fe5a') waiting
on child command id: '8640049f-0ead-486a-93b4-dcbbaa353294'
type:'RemoveImage' to complete
2024-01-03 10:05:36,565Z INFO
[org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommandCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-62) [5871c85c]
Waiting on remove image command to complete the task
'b51f31d8-944b-4539-beb6-a0ab995073c6'
2024-01-03 10:05:38,569Z INFO
[org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-32) [5871c85c]
Command 'RemoveDisk' (id: 'b01c95d8-b3c3-40db-9a41-6fe1e817fe5a') waiting
on child command id: '8640049f-0ead-486a-93b4-dcbbaa353294'
type:'RemoveImage' to complete
2024-01-03 10:05:38,573Z INFO
[org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommandCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-32) [5871c85c]
Waiting on remove image command to complete the task
'b51f31d8-944b-4539-beb6-a0ab995073c6'
2024-01-03 10:05:39,645Z INFO
[org.ovirt.engine.core.bll.storage.disk.image.TransferImageStatusCommand] (default task-1)
[95e8a4ca-324f-4f9e-86a0-ef324383ca64] Running command: TransferImageStatusCommand
internal: false. Entities affected : ID: 95dfc5bc-2a31-405c-ada0-6015edd281da Type:
SystemAction group CREATE_DISK with role type USER
2024-01-03 10:05:40,022Z INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-40) [] Polling and
updating Async Tasks: 2 tasks, 1 tasks to poll now
2024-01-03 10:05:40,027Z INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-40) []
SPMAsyncTask::PollTask: Polling task 'b51f31d8-944b-4539-beb6-a0ab995073c6'
(Parent Command 'RemoveImage', Parameters Type
'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') returned status
'finished', result 'success'.
2024-01-03 10:05:40,027Z INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-40) []
BaseAsyncTask::onTaskEndSuccess: Task 'b51f31d8-944b-4539-beb6-a0ab995073c6'
(Parent Command 'RemoveImage', Parameters Type
'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') ended
successfully.
2024-01-03 10:05:40,030Z INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-40) []
CommandAsyncTask::endActionIfNecessary: All tasks of command
'8640049f-0ead-486a-93b4-dcbbaa353294' has ended -> executing
'endAction'
2024-01-03 10:05:40,030Z INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-40) []
CommandAsyncTask::endAction: Ending action for '1' tasks (command ID:
'8640049f-0ead-486a-93b4-dcbbaa353294'): calling endAction '.
2024-01-03 10:05:40,031Z INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(EE-ManagedThreadFactory-engine-Thread-2965) [] CommandAsyncTask::endCommandAction [within
thread] context: Attempting to endAction 'RemoveImage',
2024-01-03 10:05:40,033Z INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(EE-ManagedThreadFactory-engine-Thread-2965) [5871c85c]
CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type
'RemoveImage' completed, handling the result.
2024-01-03 10:05:40,034Z INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(EE-ManagedThreadFactory-engine-Thread-2965) [5871c85c]
CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type
'RemoveImage' succeeded, clearing tasks.
2024-01-03 10:05:40,034Z INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(EE-ManagedThreadFactory-engine-Thread-2965) [5871c85c] SPMAsyncTask::ClearAsyncTask:
Attempting to clear task 'b51f31d8-944b-4539-beb6-a0ab995073c6'
2024-01-03 10:05:40,034Z INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-2965) [5871c85c] START, SPMClearTaskVDSCommand(
SPMTaskGuidBaseVDSCommandParameters:{storagePoolId='4c5f1e92-239b-471c-9db0-970029129a62',
ignoreFailoverLimit='false',
taskId='b51f31d8-944b-4539-beb6-a0ab995073c6'}), log id: 7535a00c
2024-01-03 10:05:40,035Z INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-2965) [5871c85c] START,
HSMClearTaskVDSCommand(HostName = kvm-sandbox-qm7,
HSMTaskGuidBaseVDSCommandParameters:{hostId='9fb846b0-58cf-41ab-875c-3e3118a24b89',
taskId='b51f31d8-944b-4539-beb6-a0ab995073c6'}), log id: 3522ca07
2024-01-03 10:05:40,046Z INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-2965) [5871c85c] FINISH, HSMClearTaskVDSCommand,
return: , log id: 3522ca07
2024-01-03 10:05:40,046Z INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-2965) [5871c85c] FINISH, SPMClearTaskVDSCommand,
return: , log id: 7535a00c
2024-01-03 10:05:40,050Z INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(EE-ManagedThreadFactory-engine-Thread-2965) [5871c85c] BaseAsyncTask::removeTaskFromDB:
Removed task 'b51f31d8-944b-4539-beb6-a0ab995073c6' from DataBase
2024-01-03 10:05:40,050Z INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(EE-ManagedThreadFactory-engine-Thread-2965) [5871c85c]
CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks
object for entity '8640049f-0ead-486a-93b4-dcbbaa353294'
2024-01-03 10:05:42,583Z INFO
[org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-11) [5871c85c]
Command 'RemoveDisk' (id: 'b01c95d8-b3c3-40db-9a41-6fe1e817fe5a') waiting
on child command id: '8640049f-0ead-486a-93b4-dcbbaa353294'
type:'RemoveImage' to complete
2024-01-03 10:05:42,593Z INFO
[org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommandCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-11) [5871c85c] Remove
image command has completed successfully for disk
'13dcaf25-6b58-4c79-85a7-0aecd153fb59' with async task(s)
'[b51f31d8-944b-4539-beb6-a0ab995073c6]'.
2024-01-03 10:05:44,692Z INFO
[org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-39) [5871c85c]
Command 'RemoveDisk' id: 'b01c95d8-b3c3-40db-9a41-6fe1e817fe5a' child
commands '[8640049f-0ead-486a-93b4-dcbbaa353294]' executions were completed,
status 'SUCCEEDED'
2024-01-03 10:05:45,719Z INFO [org.ovirt.engine.core.bll.storage.disk.RemoveDiskCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-26) [5871c85c] Ending
command 'org.ovirt.engine.core.bll.storage.disk.RemoveDiskCommand' successfully.
--- /snip ---
Please let me know if I can supply additional information which could be
relevant.
Kind Regards,
Justin Zandbergen.