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

Hi Arik, Please find attached the the HV2 logs as requested. Thanks

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.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.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/ >>>> >>>> >>> >> >