8:33 a.m.
First of all, thanks Tomas for all of the help. You have been a wonder to work with, and
I'm pleased to make progress on this issue.
Secondly, no panic here. My statements to Simon were more to derail the thought that there
was something wrong with my VMs and nothing wrong with oVirt's handling of them (as
we've been discussing and discovering the evidence of so far). I figured there was
more to this issue than the OVF exports, since this is happening to all of the VMs
I've tried (and they're all Windows Server VMs so far. I do have a BSD VM to
import from VMware, but that'll be last as that's my pfSense VM.).
Below are the log lines from engine.log referencing the flow_id starting 593daf15:
2023-10-26 15:56:27,980-05 INFO
[org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand] (default task-8)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Lock Acquired to object
'EngineLock:{exclusiveLocks='[sevenofnine=VM_NAME,
4a6237d9-05a0-4d5a-a7b1-ff01764a761f=VM]', sharedLocks=''}'
2023-10-26 15:56:28,079-05 INFO
[org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand]
(EE-ManagedThreadFactory-engine-Thread-12496) [593daf15-49dc-4616-8b11-7a62f5f6ace4]
Running command: ImportVmFromOvaCommand internal: false. Entities affected : ID:
c7d8bdb6-6ba8-11ee-b6e4-00163e1989b9 Type: ClusterAction group CREATE_VM with role type
USER, ID: dfe7c94d-f724-4bbc-9156-d47761f417c6 Type: StorageAction group IMPORT_EXPORT_VM
with role type ADMIN
2023-10-26 15:56:28,210-05 INFO [org.ovirt.engine.core.bll.storage.disk.AddDiskCommand]
(EE-ManagedThreadFactory-engine-Thread-12496) [593daf15-49dc-4616-8b11-7a62f5f6ace4]
Running command: AddDiskCommand internal: true. Entities affected : ID:
4a6237d9-05a0-4d5a-a7b1-ff01764a761f Type: VMAction group CONFIGURE_VM_STORAGE with role
type USER, ID: dfe7c94d-f724-4bbc-9156-d47761f417c6 Type: StorageAction group CREATE_DISK
with role type USER
2023-10-26 15:56:28,226-05 INFO
[org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand]
(EE-ManagedThreadFactory-engine-Thread-12496) [593daf15-49dc-4616-8b11-7a62f5f6ace4]
Running command: AddImageFromScratchCommand internal: true. Entities affected : ID:
dfe7c94d-f724-4bbc-9156-d47761f417c6 Type: Storage
2023-10-26 15:56:28,238-05 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.CreateVolumeVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-12496) [593daf15-49dc-4616-8b11-7a62f5f6ace4]
START, CreateVolumeVDSCommand(
CreateVolumeVDSCommandParameters:{storagePoolId='c7d57de0-6ba8-11ee-9a48-00163e1989b9',
ignoreFailoverLimit='false',
storageDomainId='dfe7c94d-f724-4bbc-9156-d47761f417c6',
imageGroupId='752b9199-8083-4e60-9be2-b2f13f54790f',
imageSizeInBytes='214748364800', volumeFormat='RAW',
newImageId='e562465d-dc03-427f-8cc9-20fc1055424f', imageType='Sparse',
newImageDescription='{"DiskAlias":"vmdisk1","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: 23351bc6
2023-10-26 15:56:28,273-05 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.CreateVolumeVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-12496) [593daf15-49dc-4616-8b11-7a62f5f6ace4]
FINISH, CreateVolumeVDSCommand, return: e562465d-dc03-427f-8cc9-20fc1055424f, log id:
23351bc6
2023-10-26 15:56:28,278-05 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(EE-ManagedThreadFactory-engine-Thread-12496) [593daf15-49dc-4616-8b11-7a62f5f6ace4]
CommandAsyncTask::Adding CommandMultiAsyncTasks object for command
'55e4e71d-ff96-4207-a06c-3ff0ab2b62ed'
2023-10-26 15:56:28,278-05 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks]
(EE-ManagedThreadFactory-engine-Thread-12496) [593daf15-49dc-4616-8b11-7a62f5f6ace4]
CommandMultiAsyncTasks::attachTask: Attaching task
'344cdd34-2603-4fd6-afee-73ab95ac9c4f' to command
'55e4e71d-ff96-4207-a06c-3ff0ab2b62ed'.
2023-10-26 15:56:28,288-05 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
(EE-ManagedThreadFactory-engine-Thread-12496) [593daf15-49dc-4616-8b11-7a62f5f6ace4]
Adding task '344cdd34-2603-4fd6-afee-73ab95ac9c4f' (Parent Command
'AddImageFromScratch', Parameters Type
'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters'), polling hasn't
started yet..
2023-10-26 15:56:28,296-05 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(EE-ManagedThreadFactory-engine-Thread-12496) [593daf15-49dc-4616-8b11-7a62f5f6ace4]
BaseAsyncTask::startPollingTask: Starting to poll task
'344cdd34-2603-4fd6-afee-73ab95ac9c4f'.
2023-10-26 15:56:28,326-05 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedThreadFactory-engine-Thread-12496) [593daf15-49dc-4616-8b11-7a62f5f6ace4]
EVENT_ID: ADD_DISK_INTERNAL(2,036), Add-Disk operation of 'vmdisk1' was initiated
by the system.
2023-10-26 15:56:28,410-05 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedThreadFactory-engine-Thread-12496) [593daf15-49dc-4616-8b11-7a62f5f6ace4]
EVENT_ID: IMPORTEXPORT_STARTING_IMPORT_VM(1,165), Starting to import Vm sevenofnine to
Data Center Default, Cluster Default
2023-10-26 15:56:28,439-05 INFO
[org.ovirt.engine.core.bll.storage.disk.AddDiskCommandCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-51)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'AddDisk' (id:
'31ef6635-4a9e-4d61-9f32-3dfde3f727ca') waiting on child command id:
'55e4e71d-ff96-4207-a06c-3ff0ab2b62ed' type:'AddImageFromScratch' to
complete
2023-10-26 15:56:28,441-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-51)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'31ef6635-4a9e-4d61-9f32-3dfde3f727ca' type:'AddDisk' to complete
2023-10-26 15:56:30,442-05 INFO
[org.ovirt.engine.core.bll.storage.disk.AddDiskCommandCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-16)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'AddDisk' (id:
'31ef6635-4a9e-4d61-9f32-3dfde3f727ca') waiting on child command id:
'55e4e71d-ff96-4207-a06c-3ff0ab2b62ed' type:'AddImageFromScratch' to
complete
2023-10-26 15:56:30,443-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-16)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'31ef6635-4a9e-4d61-9f32-3dfde3f727ca' type:'AddDisk' to complete
2023-10-26 15:56:34,265-05 INFO
[org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand]
(EE-ManagedThreadFactory-engine-Thread-12499) [593daf15-49dc-4616-8b11-7a62f5f6ace4]
Command [id=55e4e71d-ff96-4207-a06c-3ff0ab2b62ed]: Updating status to 'SUCCEEDED',
The command end method logic will be executed by one of its parent commands.
2023-10-26 15:56:34,265-05 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(EE-ManagedThreadFactory-engine-Thread-12499) [593daf15-49dc-4616-8b11-7a62f5f6ace4]
CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type
'AddImageFromScratch' completed, handling the result.
2023-10-26 15:56:34,265-05 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(EE-ManagedThreadFactory-engine-Thread-12499) [593daf15-49dc-4616-8b11-7a62f5f6ace4]
CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type
'AddImageFromScratch' succeeded, clearing tasks.
2023-10-26 15:56:34,265-05 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(EE-ManagedThreadFactory-engine-Thread-12499) [593daf15-49dc-4616-8b11-7a62f5f6ace4]
SPMAsyncTask::ClearAsyncTask: Attempting to clear task
'344cdd34-2603-4fd6-afee-73ab95ac9c4f'
2023-10-26 15:56:34,265-05 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-12499) [593daf15-49dc-4616-8b11-7a62f5f6ace4]
START, SPMClearTaskVDSCommand(
SPMTaskGuidBaseVDSCommandParameters:{storagePoolId='c7d57de0-6ba8-11ee-9a48-00163e1989b9',
ignoreFailoverLimit='false',
taskId='344cdd34-2603-4fd6-afee-73ab95ac9c4f'}), log id: 6d799bbe
2023-10-26 15:56:34,265-05 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-12499) [593daf15-49dc-4616-8b11-7a62f5f6ace4]
START, HSMClearTaskVDSCommand(HostName = ovirt.theclocktower.home,
HSMTaskGuidBaseVDSCommandParameters:{hostId='22e0db94-dd02-4788-abdd-bfa6f20b6b02',
taskId='344cdd34-2603-4fd6-afee-73ab95ac9c4f'}), log id: 549e1bf7
2023-10-26 15:56:34,274-05 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-12499) [593daf15-49dc-4616-8b11-7a62f5f6ace4]
FINISH, HSMClearTaskVDSCommand, return: , log id: 549e1bf7
2023-10-26 15:56:34,274-05 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand]
(EE-ManagedThreadFactory-engine-Thread-12499) [593daf15-49dc-4616-8b11-7a62f5f6ace4]
FINISH, SPMClearTaskVDSCommand, return: , log id: 6d799bbe
2023-10-26 15:56:34,277-05 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(EE-ManagedThreadFactory-engine-Thread-12499) [593daf15-49dc-4616-8b11-7a62f5f6ace4]
BaseAsyncTask::removeTaskFromDB: Removed task
'344cdd34-2603-4fd6-afee-73ab95ac9c4f' from DataBase
2023-10-26 15:56:34,277-05 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(EE-ManagedThreadFactory-engine-Thread-12499) [593daf15-49dc-4616-8b11-7a62f5f6ace4]
CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks
object for entity '55e4e71d-ff96-4207-a06c-3ff0ab2b62ed'
2023-10-26 15:56:34,446-05 INFO
[org.ovirt.engine.core.bll.storage.disk.AddDiskCommandCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-6)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Getting volume info for image
'752b9199-8083-4e60-9be2-b2f13f54790f/e562465d-dc03-427f-8cc9-20fc1055424f'
2023-10-26 15:56:34,452-05 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-6)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] START, GetVolumeInfoVDSCommand(HostName =
ovirt.theclocktower.home,
GetVolumeInfoVDSCommandParameters:{hostId='22e0db94-dd02-4788-abdd-bfa6f20b6b02',
storagePoolId='c7d57de0-6ba8-11ee-9a48-00163e1989b9',
storageDomainId='dfe7c94d-f724-4bbc-9156-d47761f417c6',
imageGroupId='752b9199-8083-4e60-9be2-b2f13f54790f',
imageId='e562465d-dc03-427f-8cc9-20fc1055424f'}), log id: 102a3be9
2023-10-26 15:56:34,469-05 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-6)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] FINISH, GetVolumeInfoVDSCommand, return:
org.ovirt.engine.core.common.businessentities.storage.DiskImage@81f4a719, log id:
102a3be9
2023-10-26 15:56:34,469-05 INFO
[org.ovirt.engine.core.bll.storage.disk.AddDiskCommandCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-6)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'AddDisk' id:
'31ef6635-4a9e-4d61-9f32-3dfde3f727ca' child commands
'[55e4e71d-ff96-4207-a06c-3ff0ab2b62ed]' executions were completed, status
'SUCCEEDED'
2023-10-26 15:56:34,473-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-6)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'31ef6635-4a9e-4d61-9f32-3dfde3f727ca' type:'AddDisk' to complete
2023-10-26 15:56:35,475-05 INFO [org.ovirt.engine.core.bll.storage.disk.AddDiskCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-38)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Ending command
'org.ovirt.engine.core.bll.storage.disk.AddDiskCommand' successfully.
2023-10-26 15:56:35,481-05 INFO
[org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-38)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Ending command
'org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand'
successfully.
2023-10-26 15:56:35,487-05 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-38)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] START, GetImageInfoVDSCommand(
GetImageInfoVDSCommandParameters:{storagePoolId='c7d57de0-6ba8-11ee-9a48-00163e1989b9',
ignoreFailoverLimit='false',
storageDomainId='dfe7c94d-f724-4bbc-9156-d47761f417c6',
imageGroupId='752b9199-8083-4e60-9be2-b2f13f54790f',
imageId='e562465d-dc03-427f-8cc9-20fc1055424f'}), log id: 17b2e51a
2023-10-26 15:56:35,488-05 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-38)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] START, GetVolumeInfoVDSCommand(HostName =
ovirt.theclocktower.home,
GetVolumeInfoVDSCommandParameters:{hostId='22e0db94-dd02-4788-abdd-bfa6f20b6b02',
storagePoolId='c7d57de0-6ba8-11ee-9a48-00163e1989b9',
storageDomainId='dfe7c94d-f724-4bbc-9156-d47761f417c6',
imageGroupId='752b9199-8083-4e60-9be2-b2f13f54790f',
imageId='e562465d-dc03-427f-8cc9-20fc1055424f'}), log id: 580d1d9a
2023-10-26 15:56:35,504-05 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-38)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] FINISH, GetVolumeInfoVDSCommand, return:
org.ovirt.engine.core.common.businessentities.storage.DiskImage@81f4a719, log id:
580d1d9a
2023-10-26 15:56:35,504-05 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-38)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] FINISH, GetImageInfoVDSCommand, return:
org.ovirt.engine.core.common.businessentities.storage.DiskImage@81f4a719, log id:
17b2e51a
2023-10-26 15:56:35,530-05 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-38) [] EVENT_ID:
USER_ADD_DISK_TO_VM_FINISHED_SUCCESS(97), The disk vmdisk1 was successfully added to VM
sevenofnine.
2023-10-26 15:56:35,559-05 INFO
[org.ovirt.engine.core.bll.exportimport.ConvertOvaCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-38)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Running command: ConvertOvaCommand internal: true.
Entities affected : ID: 4a6237d9-05a0-4d5a-a7b1-ff01764a761f Type: VM
2023-10-26 15:56:35,566-05 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-38)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] START, PrepareImageVDSCommand(HostName =
ovirt.theclocktower.home,
PrepareImageVDSCommandParameters:{hostId='22e0db94-dd02-4788-abdd-bfa6f20b6b02'}),
log id: 2b62c867
2023-10-26 15:56:35,596-05 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-38)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] FINISH, PrepareImageVDSCommand, return:
PrepareImageReturn:{status='Status [code=0, message=Done]'}, log id: 2b62c867
2023-10-26 15:56:35,597-05 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConvertOvaVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-38)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] START, ConvertOvaVDSCommand(HostName =
ovirt.theclocktower.home,
ConvertOvaVDSParameters:{hostId='22e0db94-dd02-4788-abdd-bfa6f20b6b02',
ovaPath='/mnt/vmexports/sevenofnine', vmName='sevenofnine',
storageDomainId='dfe7c94d-f724-4bbc-9156-d47761f417c6',
storagePoolId='c7d57de0-6ba8-11ee-9a48-00163e1989b9',
virtioIsoPath='/rhev/data-center/mnt/ovirt.theclocktower.home:_home_ovirt__storage/dfe7c94d-f724-4bbc-9156-d47761f417c6/images/d238694b-485c-4795-87c4-69f0498c7dff/b9ed1511-90ac-4d47-b92d-33a8b0258827',
Disk0='752b9199-8083-4e60-9be2-b2f13f54790f'}), log id: 5b134171
2023-10-26 15:56:35,921-05 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.ConvertOvaVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-38)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] FINISH, ConvertOvaVDSCommand, return:
4a6237d9-05a0-4d5a-a7b1-ff01764a761f, log id: 5b134171
2023-10-26 15:56:37,938-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-58)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 15:56:41,943-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-52)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 15:56:49,952-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-100)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 15:56:59,960-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-74)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 15:57:09,966-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-83)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 15:57:19,972-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-4)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 15:57:29,979-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 15:57:39,985-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-39)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 15:57:49,992-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-58)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 15:57:59,999-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-75)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 15:58:10,005-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-4)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 15:58:20,011-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-44)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 15:58:30,019-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-13)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 15:58:40,026-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-88)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 15:58:50,032-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-1)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 15:59:00,038-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-70)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 15:59:10,072-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-59)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 15:59:20,078-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-51)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 15:59:30,085-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-88)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 15:59:40,093-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-75)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 15:59:50,099-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-14)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:00:00,111-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-53)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:00:10,118-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-25)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:00:20,138-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-69)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:00:30,147-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-63)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:00:40,154-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-98)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:00:50,160-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-53)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:01:00,168-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-50)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:01:10,178-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:01:20,185-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-39)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:01:30,192-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-6)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:01:40,200-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-53)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:01:50,209-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-93)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:02:00,217-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-17)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:02:10,226-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-51)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:02:20,233-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-9)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:02:30,241-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-65)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:02:40,250-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-70)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:02:50,259-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-71)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:03:00,267-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-15)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:03:10,277-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-7)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:03:20,285-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-64)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:03:30,294-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-70)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:03:40,302-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:03:50,311-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-99)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:04:00,318-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-94)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:04:10,324-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-97)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:04:20,330-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-85)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:04:30,336-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' (id:
'9739df2d-a895-450e-b810-238dd6f2f0bf') waiting on child command id:
'61dd3ced-7537-4412-848d-40320b322aef' type:'ConvertOva' to complete
2023-10-26 16:04:32,357-05 INFO
[org.ovirt.engine.core.bll.exportimport.ConvertOvaCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-96)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Ending command
'org.ovirt.engine.core.bll.exportimport.ConvertOvaCommand' successfully.
2023-10-26 16:04:32,392-05 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.TeardownImageVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-96)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] START, TeardownImageVDSCommand(HostName =
ovirt.theclocktower.home,
ImageActionsVDSCommandParameters:{hostId='22e0db94-dd02-4788-abdd-bfa6f20b6b02'}),
log id: 2276828a
2023-10-26 16:04:32,397-05 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.TeardownImageVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-96)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] FINISH, TeardownImageVDSCommand, return:
StatusReturn:{status='Status [code=0, message=Done]'}, log id: 2276828a
2023-10-26 16:04:32,509-05 INFO
[org.ovirt.engine.core.bll.exportimport.UpdateConvertedVmCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-96)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Running command: UpdateConvertedVmCommand internal:
true. Entities affected : ID: 4a6237d9-05a0-4d5a-a7b1-ff01764a761f Type: VM
2023-10-26 16:04:32,510-05 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetConvertedOvfVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-96)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] START, GetConvertedOvfVDSCommand(HostName =
ovirt.theclocktower.home,
VdsAndVmIDVDSParametersBase:{hostId='22e0db94-dd02-4788-abdd-bfa6f20b6b02',
vmId='4a6237d9-05a0-4d5a-a7b1-ff01764a761f'}), log id: 1b7f502a
2023-10-26 16:04:32,514-05 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetConvertedOvfVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-96)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] FINISH, GetConvertedOvfVDSCommand, return: <?xml
version='1.0' encoding='utf-8'?>
<ovf:Envelope
xmlns:rasd='http://schemas.dmtf.org/wbem/wscim/1/cim-schema/2/CIM_Res...
xmlns:vssd='http://schemas.dmtf.org/wbem/wscim/1/cim-schema/2/CIM_Vir...
xmlns:xsi='http://www.w3.org/2001/XMLSchema-instance'
xmlns:ovf='http://schemas.dmtf.org/ovf/envelope/1/'
xmlns:ovirt='http://www.ovirt.org/ovf' ovf:version='0.9'>
<!-- generated by virt-v2v 2.3.4rhel=9,release=5.el9 -->
<References>
<File
ovf:href='752b9199-8083-4e60-9be2-b2f13f54790f/e562465d-dc03-427f-8cc9-20fc1055424f'
ovf:id='e562465d-dc03-427f-8cc9-20fc1055424f' ovf:description='generated by
virt-v2v 2.3.4rhel=9,release=5.el9'/>
</References>
<Section xsi:type='ovf:NetworkSection_Type'>
<Info>List of networks</Info>
<Network ovf:name='DPG_VMNetwork'/>
</Section>
<Section xsi:type='ovf:DiskSection_Type'>
<Info>List of Virtual Disks</Info>
<Disk ovf:diskId='e562465d-dc03-427f-8cc9-20fc1055424f'
ovf:size='200' ovf:capacity='214748364800'
ovf:fileRef='752b9199-8083-4e60-9be2-b2f13f54790f/e562465d-dc03-427f-8cc9-20fc1055424f'
ovf:parentRef='' ovf:vm_snapshot_id='84038453-d7da-4dbc-8ecc-8af6b8c7c6e2'
ovf:volume-format='RAW' ovf:volume-type='Sparse'
ovf:format='http://en.wikipedia.org/wiki/Byte' ovf:disk-interface='IDE'
ovf:disk-type='System' ovf:boot='True'/>
</Section>
<Content ovf:id='out' xsi:type='ovf:VirtualSystem_Type'>
<Name>sevenofnine</Name>
<TemplateId>00000000-0000-0000-0000-000000000000</TemplateId>
<TemplateName>Blank</TemplateName>
<Description>generated by virt-v2v
2.3.4rhel=9,release=5.el9</Description>
<Domain/>
<CreationDate>2023/10/26 20:56:35</CreationDate>
<IsInitilized>True</IsInitilized>
<IsAutoSuspend>False</IsAutoSuspend>
<TimeZone/>
<IsStateless>False</IsStateless>
<VmType>1</VmType>
<DefaultDisplayType>2</DefaultDisplayType>
<BiosType>2</BiosType>
<Origin>1</Origin>
<Section ovf:id='4a6237d9-05a0-4d5a-a7b1-ff01764a761f'
ovf:required='false' xsi:type='ovf:OperatingSystemSection_Type'>
<Info>Windows Server 2019 Datacenter</Info>
<Description>windows_2019x64</Description>
</Section>
<Section xsi:type='ovf:VirtualHardwareSection_Type'>
<Info>8 CPU, 16384 Memory</Info>
<Item>
<rasd:Caption>8 virtual cpu</rasd:Caption>
<rasd:Description>Number of virtual CPU</rasd:Description>
<rasd:InstanceId>1</rasd:InstanceId>
<rasd:ResourceType>3</rasd:ResourceType>
<rasd:num_of_sockets>1</rasd:num_of_sockets>
<rasd:cpu_per_socket>8</rasd:cpu_per_socket>
</Item>
<Item>
<rasd:Caption>16384 MB of memory</rasd:Caption>
<rasd:Description>Memory Size</rasd:Description>
<rasd:InstanceId>2</rasd:InstanceId>
<rasd:ResourceType>4</rasd:ResourceType>
<rasd:AllocationUnits>MegaBytes</rasd:AllocationUnits>
<rasd:VirtualQuantity>16384</rasd:VirtualQuantity>
</Item>
<Item>
<rasd:Caption>USB Controller</rasd:Caption>
<rasd:InstanceId>3</rasd:InstanceId>
<rasd:ResourceType>23</rasd:ResourceType>
<rasd:UsbPolicy>Disabled</rasd:UsbPolicy>
</Item>
<Item>
<rasd:Caption>Graphical Controller</rasd:Caption>
<rasd:InstanceId>e183b91f-6e02-4803-b3c7-d7e2a8d8f30e</rasd:InstanceId>
<rasd:ResourceType>20</rasd:ResourceType>
<Type>video</Type>
<rasd:VirtualQuantity>1</rasd:VirtualQuantity>
<Device>vga</Device>
</Item>
<Item>
<rasd:Caption>Drive 1</rasd:Caption>
<rasd:InstanceId>e562465d-dc03-427f-8cc9-20fc1055424f</rasd:InstanceId>
<rasd:ResourceType>17</rasd:ResourceType>
<Type>disk</Type>
<rasd:HostResource>752b9199-8083-4e60-9be2-b2f13f54790f/e562465d-dc03-427f-8cc9-20fc1055424f</rasd:HostResource>
<rasd:Parent>00000000-0000-0000-0000-000000000000</rasd:Parent>
<rasd:Template>00000000-0000-0000-0000-000000000000</rasd:Template>
<rasd:ApplicationList/>
<rasd:StorageId>dfe7c94d-f724-4bbc-9156-d47761f417c6</rasd:StorageId>
<rasd:StoragePoolId>00000000-0000-0000-0000-000000000000</rasd:StoragePoolId>
<rasd:CreationDate>2023/10/26 20:56:35</rasd:CreationDate>
<rasd:LastModified>2023/10/26 20:56:35</rasd:LastModified>
<rasd:last_modified_date>2023/10/26
20:56:35</rasd:last_modified_date>
<BootOrder>1</BootOrder>
</Item>
<Item>
<rasd:InstanceId>ab4bf497-a995-470c-8f4e-91a18c4b74fa</rasd:InstanceId>
<rasd:Caption>Ethernet adapter on DPG_VMNetwork</rasd:Caption>
<rasd:ResourceType>10</rasd:ResourceType>
<rasd:ResourceSubType>1</rasd:ResourceSubType>
<Type>interface</Type>
<rasd:Connection>DPG_VMNetwork</rasd:Connection>
<rasd:Name>eth0</rasd:Name>
</Item>
</Section>
</Content>
</ovf:Envelope>
, log id: 1b7f502a
2023-10-26 16:04:32,525-05 ERROR [org.ovirt.engine.core.utils.ovf.OvfManager]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-96)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Error parsing OVF due to OVF error: [Empty Name]:
cannot read '//*/Section/Disk' with value: null
2023-10-26 16:04:32,534-05 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-96)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] EVENT_ID: IMPORTEXPORT_INVALID_OVF(1,196), Failed
to process the configuration of converted Vm sevenofnine
2023-10-26 16:04:32,535-05 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.DeleteV2VJobVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-96)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] START, DeleteV2VJobVDSCommand(HostName =
ovirt.theclocktower.home,
VdsAndVmIDVDSParametersBase:{hostId='22e0db94-dd02-4788-abdd-bfa6f20b6b02',
vmId='4a6237d9-05a0-4d5a-a7b1-ff01764a761f'}), log id: 59666f6d
2023-10-26 16:04:32,539-05 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.DeleteV2VJobVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-96)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] FINISH, DeleteV2VJobVDSCommand, return: , log id:
59666f6d
2023-10-26 16:04:32,547-05 INFO
[org.ovirt.engine.core.bll.exportimport.UpdateConvertedVmCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-96)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Lock freed to object
'EngineLock:{exclusiveLocks='[sevenofnine=VM_NAME,
4a6237d9-05a0-4d5a-a7b1-ff01764a761f=VM]', sharedLocks=''}'
2023-10-26 16:04:32,549-05 ERROR
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-96)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' id:
'9739df2d-a895-450e-b810-238dd6f2f0bf' with children
[31ef6635-4a9e-4d61-9f32-3dfde3f727ca, 61dd3ced-7537-4412-848d-40320b322aef] failed when
attempting to perform the next operation, marking as 'ACTIVE'
2023-10-26 16:04:32,549-05 ERROR
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-96)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] java.lang.NullPointerException:
javax.ejb.EJBException: java.lang.NullPointerException
at
org.jboss.as.ejb3@24.0.1.Final//org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:239)
at
org.jboss.as.ejb3@24.0.1.Final//org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:446)
at
org.jboss.as.ejb3@24.0.1.Final//org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:164)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:509)
at
org.jboss.weld.core@3.1.7.SP1//org.jboss.weld.module.ejb.AbstractEJBRequestScopeActivationInterceptor.aroundInvoke(AbstractEJBRequestScopeActivationInterceptor.java:81)
at
org.jboss.as.weld.common@24.0.1.Final//org.jboss.as.weld.ejb.EjbRequestScopeActivationInterceptor.processInvocation(EjbRequestScopeActivationInterceptor.java:89)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.as.ejb3@24.0.1.Final//org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.as.ejb3@24.0.1.Final//org.jboss.as.ejb3.component.invocationmetrics.WaitTimeInterceptor.processInvocation(WaitTimeInterceptor.java:47)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.as.ejb3@24.0.1.Final//org.jboss.as.ejb3.security.SecurityContextInterceptor.processInvocation(SecurityContextInterceptor.java:100)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.as.ejb3@24.0.1.Final//org.jboss.as.ejb3.deployment.processors.StartupAwaitInterceptor.processInvocation(StartupAwaitInterceptor.java:22)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.as.ejb3@24.0.1.Final//org.jboss.as.ejb3.component.interceptors.ShutDownInterceptorFactory$1.processInvocation(ShutDownInterceptorFactory.java:64)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.as.ejb3@24.0.1.Final//org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:67)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.as.ee@24.0.1.Final//org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.ContextClassLoaderInterceptor.processInvocation(ContextClassLoaderInterceptor.java:60)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.run(InterceptorContext.java:438)
at
org.wildfly.security.elytron-base@1.16.1.Final//org.wildfly.security.manager.WildFlySecurityManager.doChecked(WildFlySecurityManager.java:633)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.AccessCheckingInterceptor.processInvocation(AccessCheckingInterceptor.java:57)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:53)
at
org.jboss.as.ee@24.0.1.Final//org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:198)
at
org.jboss.as.ee@24.0.1.Final//org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:191)
at
org.jboss.as.ee@24.0.1.Final//org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:81)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.interfaces.BackendInternal$$$view2.runInternalAction(Unknown
Source)
at jdk.internal.reflect.GeneratedMethodAccessor841.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.util.reflection.Reflections.invokeAndUnwrap(Reflections.java:410)
at
org.jboss.weld.core@3.1.7.SP1//org.jboss.weld.module.ejb.EnterpriseBeanProxyMethodHandler.invoke(EnterpriseBeanProxyMethodHandler.java:134)
at
org.jboss.weld.core@3.1.7.SP1//org.jboss.weld.bean.proxy.EnterpriseTargetBeanInstance.invoke(EnterpriseTargetBeanInstance.java:56)
at
org.jboss.weld.core@3.1.7.SP1//org.jboss.weld.module.ejb.InjectionPointPropagatingEnterpriseTargetBeanInstance.invoke(InjectionPointPropagatingEnterpriseTargetBeanInstance.java:68)
at
org.jboss.weld.core@3.1.7.SP1//org.jboss.weld.bean.proxy.ProxyMethodHandler.invoke(ProxyMethodHandler.java:106)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.interfaces.BackendCommandObjectsHandler$BackendInternal$BackendLocal$2049259618$Proxy$_$$_Weld$EnterpriseProxy$.runInternalAction(Unknown
Source)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.runInternalAction(CommandBase.java:2382)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.exportimport.ImportVmFromExternalProviderCommand.updateVm(ImportVmFromExternalProviderCommand.java:426)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.exportimport.ImportVmFromExternalProviderCommand.executeNextOperation(ImportVmFromExternalProviderCommand.java:563)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.exportimport.ImportVmFromExternalProviderCommand.performNextOperation(ImportVmFromExternalProviderCommand.java:548)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback.childCommandsExecutionEnded(SerialChildCommandsExecutionCallback.java:32)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.ChildCommandsCallbackBase.doPolling(ChildCommandsCallbackBase.java:80)
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
org.ovirt.engine.core.common//org.ovirt.engine.core.common.errors.EngineException.getMessage(EngineException.java:81)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1180)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1332)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:2010)
at
org.ovirt.engine.core.utils//org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInSuppressed(TransactionSupport.java:140)
at
org.ovirt.engine.core.utils//org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:79)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.execute(CommandBase.java:1392)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:424)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.executor.DefaultBackendActionExecutor.execute(DefaultBackendActionExecutor.java:13)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.Backend.runAction(Backend.java:450)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.Backend.runActionImpl(Backend.java:432)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.Backend.runInternalAction(Backend.java:638)
at jdk.internal.reflect.GeneratedMethodAccessor842.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.as.ee@24.0.1.Final//org.jboss.as.ee.component.ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptor.java:52)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:509)
at
org.jboss.as.weld.common@24.0.1.Final//org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.delegateInterception(Jsr299BindingsInterceptor.java:79)
at
org.jboss.as.weld.common@24.0.1.Final//org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.doMethodInterception(Jsr299BindingsInterceptor.java:89)
at
org.jboss.as.weld.common@24.0.1.Final//org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.processInvocation(Jsr299BindingsInterceptor.java:102)
at
org.jboss.as.ee@24.0.1.Final//org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.as.ejb3@24.0.1.Final//org.jboss.as.ejb3.component.invocationmetrics.ExecutionTimeInterceptor.processInvocation(ExecutionTimeInterceptor.java:43)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.as.ee@24.0.1.Final//org.jboss.as.ee.concurrent.ConcurrentContextInterceptor.processInvocation(ConcurrentContextInterceptor.java:45)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:40)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:53)
at
org.jboss.as.ee@24.0.1.Final//org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:52)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.as.ejb3@24.0.1.Final//org.jboss.as.ejb3.component.singleton.SingletonComponentInstanceAssociationInterceptor.processInvocation(SingletonComponentInstanceAssociationInterceptor.java:53)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.as.ejb3@24.0.1.Final//org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:232)
... 57 more
2023-10-26 16:04:32,549-05 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-96)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Command 'ImportVmFromOva' id:
'9739df2d-a895-450e-b810-238dd6f2f0bf' child commands
'[31ef6635-4a9e-4d61-9f32-3dfde3f727ca, 61dd3ced-7537-4412-848d-40320b322aef]'
executions were completed, status 'FAILED'
2023-10-26 16:04:33,562-05 ERROR
[org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-83)
[593daf15-49dc-4616-8b11-7a62f5f6ace4] Ending command
'org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand' with failure.
2023-10-26 16:04:33,585-05 INFO
[org.ovirt.engine.core.bll.storage.disk.image.RemoveAllVmImagesCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-83) [6c747b69]
Running command: RemoveAllVmImagesCommand internal: true. Entities affected : ID:
4a6237d9-05a0-4d5a-a7b1-ff01764a761f Type: VM
2023-10-26 16:04:33,605-05 INFO
[org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-83) [6c747b69]
Running command: RemoveImageCommand internal: true. Entities affected : ID:
00000000-0000-0000-0000-000000000000 Type: Storage
2023-10-26 16:04:33,645-05 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-83) [6c747b69] START,
DeleteImageGroupVDSCommand(
DeleteImageGroupVDSCommandParameters:{storagePoolId='c7d57de0-6ba8-11ee-9a48-00163e1989b9',
ignoreFailoverLimit='false',
storageDomainId='dfe7c94d-f724-4bbc-9156-d47761f417c6',
imageGroupId='752b9199-8083-4e60-9be2-b2f13f54790f', postZeros='false',
discard='false', forceDelete='false'}), log id: 2c338fae
2023-10-26 16:04:33,691-05 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-83) [6c747b69]
FINISH, DeleteImageGroupVDSCommand, return: , log id: 2c338fae
From what I can see above, there's a parsing error of the OVF generated by virt-v2v
(please correct me if I'm wrong). It's stating there's a null value in
'//*/Section/Disk' but I see nothing explicitly named that (so maybe that's
what it's trying to say?). It then fails on marking the VM as ACTIVE, a few more lines
down. Any ideas, Tomas?
Here is a link to a copy of the entire engine.log from the management VM:
https://www.dropbox.com/scl/fi/orl7x5u8yxy7datw01v0a/engine-10.27.23-08.1...