[ovirt-users] Re: Failure to import a VM

On Mon, Jun 4, 2018 at 2:53 PM, Aziz <azizgstest(a)gmail.com&gt; wrote: > Hi Arik, > > Please find attached the the HV2 logs as requested. Thanks > Adding Richard. Seems like the problem is: virt-v2v: error: no bootloader detected What operating system is installed in that image? > > Best regards > > On Fri, Jun 1, 2018 at 8:44 PM, Arik Hadas <ahadas(a)redhat.com&gt; wrote: > >> >> >> On Fri, Jun 1, 2018 at 4:12 PM, Aziz <azizgstest(a)gmail.com&gt; wrote: >> >>> Hi Arik, >>> >>> Thank you for your feedback, indeed the new version resolved my >>> problem, however I'm still unable to upload the OVA VM, in the new logs I'm >>> seeing a different error, any hints ? >>> >> >>> >> >>> ------------------------------------------------------------ >>> ------------------------------ >>> 2018-06-01 12:59:58,656Z INFO [org.ovirt.engine.core.dal.dbb >>> roker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-196) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] EVENT_ID: >>> ADD_DISK_INTERNAL(2,036), Add-Disk operation of 'vmdisk1' was initiated by >>> the system. >>> 2018-06-01 12:59:58,757Z INFO [org.ovirt.engine.core.dal.dbb >>> roker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-196) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] EVENT_ID: >>> IMPORTEXPORT_STARTING_IMPORT_VM(1,165), Starting to import Vm >>> PA-VM-8.0.0 to Data Center DC2, Cluster Cluster2 >>> 2018-06-01 13:00:00,292Z INFO [org.ovirt.engine.core.bll.Ser >>> ialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-84) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] Command 'ImportVmFromOva' (id: >>> 'ba2a1ec9-36b3-451b-b6b9-2b2d698ed900') waiting on child command id: >>> 'f20e8e29-39be-48d4-aee9-de3cb4cd7751' type:'AddDisk' to complete >>> 2018-06-01 13:00:00,297Z INFO [org.ovirt.engine.core.bll.Con >>> currentChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-84) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] Command 'AddDisk' (id: >>> 'f20e8e29-39be-48d4-aee9-de3cb4cd7751') waiting on child command id: >>> '698a4fae-9420-4ebb-afd3-ad16df881166' type:'AddImageFromScratch' to >>> complete >>> 2018-06-01 13:00:04,310Z INFO [org.ovirt.engine.core.bll.Ser >>> ialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-19) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] Command 'ImportVmFromOva' (id: >>> 'ba2a1ec9-36b3-451b-b6b9-2b2d698ed900') waiting on child command id: >>> 'f20e8e29-39be-48d4-aee9-de3cb4cd7751' type:'AddDisk' to complete >>> 2018-06-01 13:00:04,316Z INFO [org.ovirt.engine.core.bll.Con >>> currentChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-19) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] Command 'AddDisk' (id: >>> 'f20e8e29-39be-48d4-aee9-de3cb4cd7751') waiting on child command id: >>> '698a4fae-9420-4ebb-afd3-ad16df881166' type:'AddImageFromScratch' to >>> complete >>> 2018-06-01 13:00:06,418Z INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-26) [] Polling and >>> updating Async Tasks: 1 tasks, 1 tasks to poll now >>> 2018-06-01 13:00:06,424Z INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-26) [] >>> SPMAsyncTask::PollTask: Polling task 'fed324cf-c16b-41f0-a1b3-17f60a20f52e' >>> (Parent Command 'AddImageFromScratch', Parameters Type >>> 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') >>> returned status 'finished', result 'success'. >>> 2018-06-01 13:00:06,436Z INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-26) [] >>> BaseAsyncTask::onTaskEndSuccess: Task 'fed324cf-c16b-41f0-a1b3-17f60a20f52e' >>> (Parent Command 'AddImageFromScratch', Parameters Type >>> 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') ended >>> successfully. >>> 2018-06-01 13:00:06,437Z INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-26) [] >>> CommandAsyncTask::endActionIfNecessary: All tasks of command >>> '698a4fae-9420-4ebb-afd3-ad16df881166' has ended -> executing >>> 'endAction' >>> 2018-06-01 13:00:06,437Z INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-26) [] >>> CommandAsyncTask::endAction: Ending action for '1' tasks (command ID: >>> '698a4fae-9420-4ebb-afd3-ad16df881166'): calling endAction '. >>> 2018-06-01 13:00:06,437Z INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] >>> (EE-ManagedThreadFactory-engine-Thread-197) [] >>> CommandAsyncTask::endCommandAction [within thread] context: Attempting >>> to endAction 'AddImageFromScratch', >>> 2018-06-01 13:00:06,444Z INFO [org.ovirt.engine.core.bll.sto >>> rage.disk.image.AddImageFromScratchCommand] >>> (EE-ManagedThreadFactory-engine-Thread-197) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] Command >>> [id=698a4fae-9420-4ebb-afd3-ad16df881166]: Updating status to >>> 'SUCCEEDED', The command end method logic will be executed by one of its >>> parent commands. >>> 2018-06-01 13:00:06,444Z INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] >>> (EE-ManagedThreadFactory-engine-Thread-197) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] CommandAsyncTask::HandleEndActionResult >>> [within thread]: endAction for action type 'AddImageFromScratch' completed, >>> handling the result. >>> 2018-06-01 13:00:06,444Z INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] >>> (EE-ManagedThreadFactory-engine-Thread-197) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] CommandAsyncTask::HandleEndActionResult >>> [within thread]: endAction for action type 'AddImageFromScratch' succeeded, >>> clearing tasks. >>> 2018-06-01 13:00:06,444Z INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] >>> (EE-ManagedThreadFactory-engine-Thread-197) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] SPMAsyncTask::ClearAsyncTask: >>> Attempting to clear task 'fed324cf-c16b-41f0-a1b3-17f60a20f52e' >>> 2018-06-01 13:00:06,446Z INFO [org.ovirt.engine.core.vdsbrok >>> er.irsbroker.SPMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-197) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] START, SPMClearTaskVDSCommand( >>> SPMTaskGuidBaseVDSCommandParameters:{storagePoolId='ea6cc5f6-ed8f-40e5-8dd2-3924dc52bb82', >>> ignoreFailoverLimit='false', taskId='fed324cf-c16b-41f0-a1b3-17f60a20f52e'}), >>> log id: 743385d5 >>> 2018-06-01 13:00:06,446Z INFO [org.ovirt.engine.core.vdsbrok >>> er.vdsbroker.HSMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-197) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] START, >>> HSMClearTaskVDSCommand(HostName = HV2, HSMTaskGuidBaseVDSCommandParam >>> eters:{hostId='350ca910-32eb-41d2-8598-405951fb14a3', >>> taskId='fed324cf-c16b-41f0-a1b3-17f60a20f52e'}), log id: 43dabfa >>> 2018-06-01 13:00:06,457Z INFO [org.ovirt.engine.core.vdsbrok >>> er.vdsbroker.HSMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-197) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] FINISH, HSMClearTaskVDSCommand, >>> log id: 43dabfa >>> 2018-06-01 13:00:06,457Z INFO [org.ovirt.engine.core.vdsbrok >>> er.irsbroker.SPMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-197) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] FINISH, SPMClearTaskVDSCommand, >>> log id: 743385d5 >>> 2018-06-01 13:00:06,469Z INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] >>> (EE-ManagedThreadFactory-engine-Thread-197) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] BaseAsyncTask::removeTaskFromDB: >>> Removed task 'fed324cf-c16b-41f0-a1b3-17f60a20f52e' from DataBase >>> 2018-06-01 13:00:06,470Z INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] >>> (EE-ManagedThreadFactory-engine-Thread-197) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] CommandAsyncTask::HandleEndActionResult >>> [within thread]: Removing CommandMultiAsyncTasks object for entity >>> '698a4fae-9420-4ebb-afd3-ad16df881166' >>> 2018-06-01 13:00:12,330Z INFO [org.ovirt.engine.core.bll.Ser >>> ialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-34) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] Command 'ImportVmFromOva' (id: >>> 'ba2a1ec9-36b3-451b-b6b9-2b2d698ed900') waiting on child command id: >>> 'f20e8e29-39be-48d4-aee9-de3cb4cd7751' type:'AddDisk' to complete >>> 2018-06-01 13:00:12,336Z INFO [org.ovirt.engine.core.bll.Con >>> currentChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-34) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] Command 'AddDisk' id: >>> 'f20e8e29-39be-48d4-aee9-de3cb4cd7751' child commands >>> '[698a4fae-9420-4ebb-afd3-ad16df881166]' executions were completed, >>> status 'SUCCEEDED' >>> 2018-06-01 13:00:13,387Z INFO [org.ovirt.engine.core.bll.storage.disk.AddDiskCommand] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-65) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] Ending command >>> 'org.ovirt.engine.core.bll.storage.disk.AddDiskCommand' successfully. >>> 2018-06-01 13:00:13,431Z INFO [org.ovirt.engine.core.bll.sto >>> rage.disk.image.AddImageFromScratchCommand] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-65) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] Ending command >>> 'org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand' >>> successfully. >>> 2018-06-01 13:00:13,445Z INFO [org.ovirt.engine.core.vdsbrok >>> er.irsbroker.GetImageInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-65) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] START, GetImageInfoVDSCommand( >>> GetImageInfoVDSCommandParameters:{storagePoolId='ea6cc5f6-ed8f-40e5-8dd2-3924dc52bb82', >>> ignoreFailoverLimit='false', storageDomainId='bea06b61-861d-498b-9ead-cf8f8aed69ab', >>> imageGroupId='65f8bb77-0c62-4a0e-95a6-f96228f466a6', >>> imageId='f86c72ea-3b6d-48ef-bb55-f2a99dc28995'}), log id: 65518c74 >>> 2018-06-01 13:00:13,448Z INFO [org.ovirt.engine.core.vdsbrok >>> er.vdsbroker.GetVolumeInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-65) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] START, >>> GetVolumeInfoVDSCommand(HostName = HV2, GetVolumeInfoVDSCommandParamet >>> ers:{hostId='350ca910-32eb-41d2-8598-405951fb14a3', >>> storagePoolId='ea6cc5f6-ed8f-40e5-8dd2-3924dc52bb82', >>> storageDomainId='bea06b61-861d-498b-9ead-cf8f8aed69ab', >>> imageGroupId='65f8bb77-0c62-4a0e-95a6-f96228f466a6', >>> imageId='f86c72ea-3b6d-48ef-bb55-f2a99dc28995'}), log id: 7f2c1e37 >>> 2018-06-01 13:00:13,460Z INFO [org.ovirt.engine.core.vdsbrok >>> er.vdsbroker.GetVolumeInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-65) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] FINISH, >>> GetVolumeInfoVDSCommand, return: org.ovirt.engine.core.common.b >>> usinessentities.storage.DiskImage@9005ff9a, log id: 7f2c1e37 >>> 2018-06-01 13:00:13,460Z INFO [org.ovirt.engine.core.vdsbrok >>> er.irsbroker.GetImageInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-65) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] FINISH, GetImageInfoVDSCommand, >>> return: org.ovirt.engine.core.common.businessentities.storage.DiskIm >>> age@9005ff9a, log id: 65518c74 >>> 2018-06-01 13:00:13,612Z INFO [org.ovirt.engine.core.dal.dbb >>> roker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engineScheduled-Thread-65) >>> [] EVENT_ID: USER_ADD_DISK_TO_VM_FINISHED_SUCCESS(97), The disk >>> vmdisk1 was successfully added to VM PA-VM-8.0.0. >>> 2018-06-01 13:00:14,797Z INFO [org.ovirt.engine.core.bll.exportimport.ConvertOvaCommand] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-77) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] Running command: >>> ConvertOvaCommand internal: true. Entities affected : ID: >>> 632fec81-6271-4ae7-866e-2aad43530594 Type: VM >>> 2018-06-01 13:00:14,800Z INFO [org.ovirt.engine.core.vdsbrok >>> er.vdsbroker.ConvertOvaVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-77) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] START, >>> ConvertOvaVDSCommand(HostName = HV2, ConvertOvaVDSParameters:{hostI >>> d='350ca910-32eb-41d2-8598-405951fb14a3', >>> ovaPath='/var/VDIEXPORTDOMAIN-HV2/PA-VM-ESX-8.0.0.ova', >>> vmName='PA-VM-8.0.0', storageDomainId='bea06b61-861d-498b-9ead-cf8f8aed69ab', >>> storagePoolId='ea6cc5f6-ed8f-40e5-8dd2-3924dc52bb82', >>> virtioIsoPath='null', Disk0='65f8bb77-0c62-4a0e-95a6-f96228f466a6'}), >>> log id: 5a78f1db >>> 2018-06-01 13:00:14,920Z INFO [org.ovirt.engine.core.vdsbrok >>> er.vdsbroker.ConvertOvaVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-77) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] FINISH, ConvertOvaVDSCommand, >>> return: 632fec81-6271-4ae7-866e-2aad43530594, log id: 5a78f1db >>> 2018-06-01 13:00:16,972Z INFO [org.ovirt.engine.core.bll.Ser >>> ialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-83) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] Command 'ImportVmFromOva' (id: >>> 'ba2a1ec9-36b3-451b-b6b9-2b2d698ed900') waiting on child command id: >>> '55573f5f-6eb0-4d11-9a0f-d857117eb8d5' type:'ConvertOva' to complete >>> 2018-06-01 13:00:20,990Z INFO [org.ovirt.engine.core.bll.Ser >>> ialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-84) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] Command 'ImportVmFromOva' (id: >>> 'ba2a1ec9-36b3-451b-b6b9-2b2d698ed900') waiting on child command id: >>> '55573f5f-6eb0-4d11-9a0f-d857117eb8d5' type:'ConvertOva' to complete >>> 2018-06-01 13:00:29,010Z INFO [org.ovirt.engine.core.bll.Ser >>> ialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-6) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] Command 'ImportVmFromOva' (id: >>> 'ba2a1ec9-36b3-451b-b6b9-2b2d698ed900') waiting on child command id: >>> '55573f5f-6eb0-4d11-9a0f-d857117eb8d5' type:'ConvertOva' to complete >>> 2018-06-01 13:00:39,024Z INFO [org.ovirt.engine.core.bll.Ser >>> ialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-88) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] Command 'ImportVmFromOva' (id: >>> 'ba2a1ec9-36b3-451b-b6b9-2b2d698ed900') waiting on child command id: >>> '55573f5f-6eb0-4d11-9a0f-d857117eb8d5' type:'ConvertOva' to complete >>> 2018-06-01 13:00:40,025Z INFO [org.ovirt.engine.core.bll.exportimport.ConvertVmCallback] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-86) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] Conversion of VM from external >>> environment failed: Job u'632fec81-6271-4ae7-866e-2aad43530594' >>> process failed exit-code: 1 >>> >> >> This failure means that virt-v2v failed to convert this OVA. >> To investigate this we need a different log that can be found on the >> host that executed virt-v2v, in this case it was HV2. You'll find the log >> in /var/log/vdsm/import. >> >> >>> 2018-06-01 13:00:41,109Z ERROR [org.ovirt.engine.core.bll.exportimport.ConvertOvaCommand] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-89) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] Ending command >>> 'org.ovirt.engine.core.bll.exportimport.ConvertOvaCommand' with >>> failure. >>> 2018-06-01 13:00:41,111Z INFO [org.ovirt.engine.core.vdsbrok >>> er.vdsbroker.DeleteV2VJobVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-89) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] START, >>> DeleteV2VJobVDSCommand(HostName = HV2, VdsAndVmIDVDSParametersBase:{h >>> ostId='350ca910-32eb-41d2-8598-405951fb14a3', >>> vmId='632fec81-6271-4ae7-866e-2aad43530594'}), log id: 1710d32c >>> 2018-06-01 13:00:41,115Z INFO [org.ovirt.engine.core.vdsbrok >>> er.vdsbroker.DeleteV2VJobVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-89) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] FINISH, DeleteV2VJobVDSCommand, >>> log id: 1710d32c >>> 2018-06-01 13:00:42,165Z INFO [org.ovirt.engine.core.bll.Ser >>> ialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-92) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] Command 'ImportVmFromOva' id: >>> 'ba2a1ec9-36b3-451b-b6b9-2b2d698ed900' child commands >>> '[f20e8e29-39be-48d4-aee9-de3cb4cd7751, 55573f5f-6eb0-4d11-9a0f-d857117eb8d5]' >>> executions were completed, status 'FAILED' >>> 2018-06-01 13:00:43,266Z ERROR [org.ovirt.engine.core.bll.exp >>> ortimport.ImportVmFromOvaCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-87) >>> [e0846352-12c0-4bff-bdff-43d1938ec9be] Ending command >>> 'org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand' with >>> failure. >>> 2018-06-01 13:00:43,352Z INFO [org.ovirt.engine.core.bll.sto >>> rage.disk.image.RemoveAllVmImagesCommand] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-87) [36a1e4cd] Running >>> command: RemoveAllVmImagesCommand internal: true. Entities affected : ID: >>> 632fec81-6271-4ae7-866e-2aad43530594 Type: VM >>> 2018-06-01 13:00:43,371Z INFO [org.ovirt.engine.core.bll.sto >>> rage.disk.image.RemoveImageCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-87) >>> [36a1e4cd] Running command: RemoveImageCommand internal: true. Entities >>> affected : ID: 00000000-0000-0000-0000-000000000000 Type: Storage >>> 2018-06-01 13:00:43,431Z INFO [org.ovirt.engine.core.vdsbrok >>> er.irsbroker.DeleteImageGroupVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-87) >>> [36a1e4cd] START, DeleteImageGroupVDSCommand( DeleteImageGroupVDSCommandPara >>> meters:{storagePoolId='ea6cc5f6-ed8f-40e5-8dd2-3924dc52bb82', >>> ignoreFailoverLimit='false', storageDomainId='bea06b61-861d-498b-9ead-cf8f8aed69ab', >>> imageGroupId='65f8bb77-0c62-4a0e-95a6-f96228f466a6', >>> postZeros='false', discard='false', forceDelete='false'}), log id: a727c35 >>> 2018-06-01 13:00:43,474Z INFO [org.ovirt.engine.core.vdsbrok >>> er.irsbroker.DeleteImageGroupVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-87) >>> [36a1e4cd] FINISH, DeleteImageGroupVDSCommand, log id: a727c35 >>> 2018-06-01 13:00:43,477Z INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-87) [36a1e4cd] >>> CommandAsyncTask::Adding CommandMultiAsyncTasks object for command >>> '505d63f7-bda1-4b72-8db9-980964d56a82' >>> 2018-06-01 13:00:43,477Z INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-87) [36a1e4cd] >>> CommandMultiAsyncTasks::attachTask: Attaching task >>> '0eee4fa8-16eb-4211-9ef1-9a8081952028' to command >>> '505d63f7-bda1-4b72-8db9-980964d56a82'. >>> 2018-06-01 13:00:43,509Z INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-87) [36a1e4cd] Adding >>> task '0eee4fa8-16eb-4211-9ef1-9a8081952028' (Parent Command >>> 'RemoveAllVmImages', Parameters Type 'org.ovirt.engine.core.common. >>> asynctasks.AsyncTaskParameters'), polling hasn't started yet.. >>> 2018-06-01 13:00:43,643Z INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-87) [36a1e4cd] >>> BaseAsyncTask::startPollingTask: Starting to poll task >>> '0eee4fa8-16eb-4211-9ef1-9a8081952028'. >>> 2018-06-01 13:00:43,645Z INFO [org.ovirt.engine.core.bll.CommandCompensator] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-87) [36a1e4cd] Command >>> [id=ba2a1ec9-36b3-451b-b6b9-2b2d698ed900]: Compensating NEW_ENTITY_ID >>> of org.ovirt.engine.core.common.businessentities.network.VmNetworkStatistics; >>> snapshot: 08b82af7-9eb8-46f2-8501-4b9b79f9d292. >>> 2018-06-01 13:00:43,646Z INFO [org.ovirt.engine.core.bll.CommandCompensator] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-87) [36a1e4cd] Command >>> [id=ba2a1ec9-36b3-451b-b6b9-2b2d698ed900]: Compensating NEW_ENTITY_ID >>> of org.ovirt.engine.core.common.businessentities.network.VmNetworkInterface; >>> snapshot: 08b82af7-9eb8-46f2-8501-4b9b79f9d292. >>> 2018-06-01 13:00:43,648Z INFO [org.ovirt.engine.core.bll.CommandCompensator] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-87) [36a1e4cd] Command >>> [id=ba2a1ec9-36b3-451b-b6b9-2b2d698ed900]: Compensating >>> TRANSIENT_ENTITY of org.ovirt.engine.core.common.b >>> usinessentities.ReleaseMacsTransientCompensation; snapshot: >>> org.ovirt.engine.core.common.businessentities.ReleaseMacsTra >>> nsientCompensation@3306fa7b. >>> 2018-06-01 13:00:43,649Z INFO [org.ovirt.engine.core.bll.CommandCompensator] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-87) [36a1e4cd] Command >>> [id=ba2a1ec9-36b3-451b-b6b9-2b2d698ed900]: Compensating NEW_ENTITY_ID >>> of org.ovirt.engine.core.common.businessentities.network.VmNetworkStatistics; >>> snapshot: afafda74-57f4-4f79-83a4-d875461ab557. >>> 2018-06-01 13:00:43,649Z INFO [org.ovirt.engine.core.bll.CommandCompensator] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-87) [36a1e4cd] Command >>> [id=ba2a1ec9-36b3-451b-b6b9-2b2d698ed900]: Compensating NEW_ENTITY_ID >>> of org.ovirt.engine.core.common.businessentities.network.VmNetworkInterface; >>> snapshot: afafda74-57f4-4f79-83a4-d875461ab557. >>> 2018-06-01 13:00:43,650Z INFO [org.ovirt.engine.core.bll.CommandCompensator] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-87) [36a1e4cd] Command >>> [id=ba2a1ec9-36b3-451b-b6b9-2b2d698ed900]: Compensating >>> TRANSIENT_ENTITY of org.ovirt.engine.core.common.b >>> usinessentities.ReleaseMacsTransientCompensation; snapshot: >>> org.ovirt.engine.core.common.businessentities.ReleaseMacsTra >>> nsientCompensation@53a4230e. >>> 2018-06-01 13:00:43,651Z INFO [org.ovirt.engine.core.bll.CommandCompensator] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-87) [36a1e4cd] Command >>> [id=ba2a1ec9-36b3-451b-b6b9-2b2d698ed900]: Compensating NEW_ENTITY_ID >>> of org.ovirt.engine.core.common.businessentities.VmStatistics; >>> snapshot: 632fec81-6271-4ae7-866e-2aad43530594. >>> 2018-06-01 13:00:43,651Z INFO [org.ovirt.engine.core.bll.CommandCompensator] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-87) [36a1e4cd] Command >>> [id=ba2a1ec9-36b3-451b-b6b9-2b2d698ed900]: Compensating NEW_ENTITY_ID >>> of org.ovirt.engine.core.common.businessentities.VmDynamic; snapshot: >>> 632fec81-6271-4ae7-866e-2aad43530594. >>> 2018-06-01 13:00:43,652Z INFO [org.ovirt.engine.core.bll.CommandCompensator] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-87) [36a1e4cd] Command >>> [id=ba2a1ec9-36b3-451b-b6b9-2b2d698ed900]: Compensating NEW_ENTITY_ID >>> of org.ovirt.engine.core.common.businessentities.Snapshot; snapshot: >>> bf4518c0-1624-49f7-82be-2b079bfe0111. >>> 2018-06-01 13:00:43,653Z INFO [org.ovirt.engine.core.bll.CommandCompensator] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-87) [36a1e4cd] Command >>> [id=ba2a1ec9-36b3-451b-b6b9-2b2d698ed900]: Compensating NEW_ENTITY_ID >>> of org.ovirt.engine.core.common.businessentities.VmStatic; snapshot: >>> 632fec81-6271-4ae7-866e-2aad43530594. >>> 2018-06-01 13:00:43,677Z INFO [org.ovirt.engine.core.bll.exp >>> ortimport.ImportVmFromOvaCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-87) >>> [36a1e4cd] Lock freed to object 'EngineLock:{exclusiveLocks='[PA-VM-8.0.0=VM_NAME, >>> 632fec81-6271-4ae7-866e-2aad43530594=VM]', sharedLocks=''}' >>> 2018-06-01 13:00:43,702Z ERROR [org.ovirt.engine.core.dal.dbb >>> roker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engineScheduled-Thread-87) >>> [36a1e4cd] *EVENT_ID: IMPORTEXPORT_IMPORT_VM_FAILED(1,153), Failed to >>> import Vm PA-VM-8.0.0 to Data Center DC2, Cluster Cluster2* >>> 2018-06-01 13:00:46,438Z INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-80) [] Task id >>> '0eee4fa8-16eb-4211-9ef1-9a8081952028' is in pre-polling period and >>> should not be polled. Pre-polling period is 60000 millis. >>> 2018-06-01 13:00:56,439Z INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-33) [] Task id >>> '0eee4fa8-16eb-4211-9ef1-9a8081952028' is in pre-polling period and >>> should not be polled. Pre-polling period is 60000 millis. >>> 2018-06-01 13:01:06,440Z INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-95) [] Task id >>> '0eee4fa8-16eb-4211-9ef1-9a8081952028' is in pre-polling period and >>> should not be polled. Pre-polling period is 60000 millis. >>> 2018-06-01 13:01:16,274Z INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] >>> (EE-ManagedThreadFactory-engineScheduled-Thread-5) [] Setting new >>> tasks map. The map contains now 1 tasks >>> >>> ------------------------------------------------------------ >>> ----------------------------- >>> >>> >>> Best regards >>> >>> On Mon, May 28, 2018 at 5:57 PM, Arik Hadas <ahadas(a)redhat.com&gt; wrote: >>> >>>> >>>> >>>> On Mon, May 28, 2018 at 7:55 PM, Aziz <azizgstest(a)gmail.com&gt; wrote: >>>> >>>>> Hi All, >>>>> >>>>> I'm getting an error when trying to import an OVA file to my oVirt. >>>>> It seems that a JAVA command is failing with an exception : >>>>> >>>>> Here are the logs from the engine.log file, while importing the OVA >>>>> file. >>>>> >>>>> 2018-05-28 16:47:09,491Z ERROR [org.ovirt.engine.core.bll.exp >>>>> ortimport.ImportVmFromOvaCommand] (EE-ManagedThreadFactory-engine-Thread-19767) >>>>> [e0306ff3-db73-4030-924b-57d86023db28] Command >>>>> 'org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand' >>>>> failed: For input string: "" >>>>> 2018-05-28 16:47:09,491Z ERROR [org.ovirt.engine.core.bll.exp >>>>> ortimport.ImportVmFromOvaCommand] (EE-ManagedThreadFactory-engine-Thread-19767) >>>>> [e0306ff3-db73-4030-924b-57d86023db28] Exception: >>>>> java.lang.NumberFormatException: For input string: "" >>>>> at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65) >>>>> [rt.jar:1.8.0_171] >>>>> at java.lang.Long.parseLong(Long.java:601) [rt.jar:1.8.0_171] >>>>> at org.ovirt.engine.core.utils.MacAddressRangeUtils.macToLong(MacAddressRangeUtils.java:124) >>>>> [utils.jar:] >>>>> at org.ovirt.engine.core.bll.network.macpool.MacPoolUsingRanges >>>>> .isMacInRange(MacPoolUsingRanges.java:193) [bll.jar:] >>>>> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) >>>>> [rt.jar:1.8.0_171] >>>>> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) >>>>> [rt.jar:1.8.0_171] >>>>> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) >>>>> [rt.jar:1.8.0_171] >>>>> at java.lang.reflect.Method.invoke(Method.java:498) >>>>> [rt.jar:1.8.0_171] >>>>> at org.ovirt.engine.core.utils.lock.LockedObjectFactory$Locking >>>>> InvocationHandler.invoke(LockedObjectFactory.java:66) [utils.jar:] >>>>> at com.sun.proxy.$Proxy187.isMacInRange(Unknown Source) >>>>> at java.util.function.Predicate.lambda$negate$1(Predicate.java:80) >>>>> [rt.jar:1.8.0_171] >>>>> at java.util.stream.ReferencePipeline$2$1.accept(ReferencePipeline.java:174) >>>>> [rt.jar:1.8.0_171] >>>>> at java.util.stream.ReferencePipeline$2$1.accept(ReferencePipeline.java:175) >>>>> [rt.jar:1.8.0_171] >>>>> at java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:193) >>>>> [rt.jar:1.8.0_171] >>>>> at java.util.ArrayList$ArrayListSpliterator.forEachRemaining(ArrayList.java:1382) >>>>> [rt.jar:1.8.0_171] >>>>> at java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:481) >>>>> [rt.jar:1.8.0_171] >>>>> at java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:471) >>>>> [rt.jar:1.8.0_171] >>>>> at java.util.stream.ReduceOps$ReduceOp.evaluateSequential(ReduceOps.java:708) >>>>> [rt.jar:1.8.0_171] >>>>> at java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234) >>>>> [rt.jar:1.8.0_171] >>>>> at java.util.stream.ReferencePipeline.collect(ReferencePipeline.java:499) >>>>> [rt.jar:1.8.0_171] >>>>> at org.ovirt.engine.core.bll.network.vm.ExternalVmMacsFinder.fi >>>>> ndExternalMacAddresses(ExternalVmMacsFinder.java:37) [bll.jar:] >>>>> at org.ovirt.engine.core.bll.exportimport.ImportVmCommandBase.r >>>>> eportExternalMacs(ImportVmCommandBase.java:512) [bll.jar:] >>>>> at org.ovirt.engine.core.bll.exportimport.ImportVmCommandBase.a >>>>> ddVmInterfaces(ImportVmCommandBase.java:640) [bll.jar:] >>>>> at org.ovirt.engine.core.bll.exportimport.ImportVmCommandBase.l >>>>> ambda$addVmToDb$2(ImportVmCommandBase.java:533) [bll.jar:] >>>>> at org.ovirt.engine.core.utils.transaction.TransactionSupport.e >>>>> xecuteInNewTransaction(TransactionSupport.java:202) [utils.jar:] >>>>> at org.ovirt.engine.core.bll.exportimport.ImportVmCommandBase.a >>>>> ddVmToDb(ImportVmCommandBase.java:529) [bll.jar:] >>>>> at org.ovirt.engine.core.bll.exportimport.ImportVmFromExternalP >>>>> roviderCommand.addVmToDb(ImportVmFromExternalProviderCommand.java:364) >>>>> [bll.jar:] >>>>> at org.ovirt.engine.core.bll.exportimport.ImportVmCommandBase.e >>>>> xecuteVmCommand(ImportVmCommandBase.java:474) [bll.jar:] >>>>> at org.ovirt.engine.core.bll.VmCommand.executeCommand(VmCommand.java:161) >>>>> [bll.jar:] >>>>> at org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1133) >>>>> [bll.jar:] >>>>> at org.ovirt.engine.core.bll.CommandBase.executeActionInTransac >>>>> tionScope(CommandBase.java:1285) [bll.jar:] >>>>> at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1934) >>>>> [bll.jar:] >>>>> at org.ovirt.engine.core.utils.transaction.TransactionSupport.e >>>>> xecuteInSuppressed(TransactionSupport.java:164) [utils.jar:] >>>>> at org.ovirt.engine.core.utils.transaction.TransactionSupport.e >>>>> xecuteInScope(TransactionSupport.java:103) [utils.jar:] >>>>> at org.ovirt.engine.core.bll.CommandBase.execute(CommandBase.java:1345) >>>>> [bll.jar:] >>>>> at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:400) >>>>> [bll.jar:] >>>>> at org.ovirt.engine.core.bll.PrevalidatingMultipleActionsRunner >>>>> .executeValidatedCommand(PrevalidatingMultipleActionsRunner.java:204) >>>>> [bll.jar:] >>>>> at org.ovirt.engine.core.bll.PrevalidatingMultipleActionsRunner >>>>> .runCommands(PrevalidatingMultipleActionsRunner.java:176) [bll.jar:] >>>>> at org.ovirt.engine.core.bll.PrevalidatingMultipleActionsRunner >>>>> .lambda$invokeCommands$3(PrevalidatingMultipleActionsRunner.java:182) >>>>> [bll.jar:] >>>>> at org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil$Intern >>>>> alWrapperRunnable.run(ThreadPoolUtil.java:96) [utils.jar:] >>>>> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) >>>>> [rt.jar:1.8.0_171] >>>>> at java.util.concurrent.FutureTask.run(FutureTask.java:266) >>>>> [rt.jar:1.8.0_171] >>>>> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) >>>>> [rt.jar:1.8.0_171] >>>>> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) >>>>> [rt.jar:1.8.0_171] >>>>> at java.lang.Thread.run(Thread.java:748) [rt.jar:1.8.0_171] >>>>> at org.glassfish.enterprise.concurrent.ManagedThreadFactoryImpl >>>>> $ManagedThread.run(ManagedThreadFactoryImpl.java:250) >>>>> [javax.enterprise.concurrent-1.0.jar:] >>>>> at org.jboss.as.ee.concurrent.service.ElytronManagedThreadFacto >>>>> ry$ElytronManagedThread.run(ElytronManagedThreadFactory.java:78) >>>>> >>>>> 2018-05-28 16:47:09,515Z ERROR [org.ovirt.engine.core.dal.dbb >>>>> roker.auditloghandling.AuditLogDirector] >>>>> (EE-ManagedThreadFactory-engine-Thread-19767) >>>>> [e0306ff3-db73-4030-924b-57d86023db28] EVENT_ID: >>>>> IMPORTEXPORT_IMPORT_VM_FAILED(1,153), Failed to import Vm >>>>> PA-VM-8.0.0 to Data Center DC2, Cluster Cluster2 >>>>> 2018-05-28 16:47:09,523Z INFO [org.ovirt.engine.core.bll.exp >>>>> ortimport.ImportVmFromOvaCommand] (EE-ManagedThreadFactory-engine-Thread-19767) >>>>> [e0306ff3-db73-4030-924b-57d86023db28] Lock freed to object >>>>> 'EngineLock:{exclusiveLocks='[PA-VM-8.0.0=VM_NAME, >>>>> 2b384fab-2ddb-4069-b4ba-59d0fe4fc8c5=VM]', sharedLocks=''}' >>>>> 2018-05-28 16:47:11,057Z ERROR [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] >>>>> (EE-ManagedThreadFactory-engineScheduled-Thread-81) [] >>>>> IrsBroker::Failed::GetStoragePoolInfoVDS >>>>> >>>>> Can anyone help to get this resolved ? >>>>> >>>> >>>> That is solved in 4.2.3 [1], please update ovirt-engine. >>>> >>>> [1] https://bugzilla.redhat.com/show_bug.cgi?id=1547936 >>>> >>>> >>>>> >>>>> Thanks >>>>> >>>>> _______________________________________________ >>>>> Users mailing list -- users(a)ovirt.org >>>>> To unsubscribe send an email to users-leave(a)ovirt.org >>>>> Privacy Statement: https://www.ovirt.org/site/privacy-policy/ >>>>> oVirt Code of Conduct: https://www.ovirt.org/communit >>>>> y/about/community-guidelines/ >>>>> List Archives: https://lists.ovirt.org/archiv >>>>> es/list/users(a)ovirt.org/message/DOK7CJH4LBHMP25FWXXQVBSKSEZEFIHR/ >>>>> >>>>> >>>> >>> >> >