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

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.businessentities.storage.DiskIm > age@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.businessentities.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.businessentities.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/ >>> >>> >> >