Re: [ovirt-users] How can I import VMWare OVA file to oVirt 4.0?

aleksey . maksimov Mon, 12 Dec 2016 23:59:58 -0800

Log attached

13.12.2016, 10:04, "Shahar Havivi" <[email protected]>:

Please attach the log and will see what the error is.

On Tue, Dec 13, 2016 at 9:01 AM, <[email protected]> wrote:
Preallocated ?
I'm trying to import image of virtual appliance "HP 3PAR Virtual Service Processor"
This virtual machine has drive 256GB. But really, this is a 40GB disk never grows. I don't want to waste that much disk space.

There is no other way ?

13.12.2016, 09:53, "Shahar Havivi" <[email protected]>:
Ok
so try to import as preallocated and attach the log if you have any errors.

Shahar.

On Tue, Dec 13, 2016 at 8:47 AM, <[email protected]> wrote:

All my Data-Domains - FC SAN. Not ISCSI.

13.12.2016, 09:41, "Shahar Havivi" <[email protected]>:
What is the type of the target storage domain that you try to import to?(nfs or iscsi)
if its iscsi try to change it to preallocate instead of thin-provision,
also please attach the engine log if you have more problems.

Shahar.

On Tue, Dec 13, 2016 at 7:08 AM, <[email protected]> wrote:
# LIBGUESTFS_BACKEND=direct virt-v2v -v -x -i ova /tmp/rhel_Vsp-4.4.0.GA-53.ova -of raw -o null

....
fsync /dev/sda
guestfsd: main_loop: proc 282 (internal_autosync) took 0.01 seconds
libguestfs: trace: internal_autosync = 0
libguestfs: sending SIGTERM to process 3338
libguestfs: trace: shutdown = 0
libguestfs: trace: close
libguestfs: closing guestfs handle 0x2880a10 (state 0)
libguestfs: command: run: rm
libguestfs: command: run: \ -rf /tmp/libguestfsrxuByo
[ 99.0] Checking if the guest needs BIOS or UEFI to boot
[ 99.0] Copying disk 1/1 to /var/tmp/null.lP6vGa/sda (raw)
target_file = /var/tmp/null.lP6vGa/sda
target_format = raw
target_estimated_size = 13480052354
target_overlay = /var/tmp/v2vovle81937.qcow2
target_overlay.ov_source = /var/tmp/ova.zJ6ks8/rhel_Vsp-4.4.0.GA-53-disk1.vmdk
qemu-img convert -p -n -f qcow2 -O 'raw' '/var/tmp/v2vovle81937.qcow2' '/var/tmp/null.lP6vGa/sda'
(100.00/100%)
virtual copying rate: 95364.1 M bits/sec
real copying rate: 811.7 M bits/sec
sda: estimate 13480052354 (12.6G) versus actual 2339610624 (2.2G): 476.2%
[ 126.0] Creating output metadata
[ 126.0] Finishing off

The output is very voluminous. Errors are seen.

13.12.2016, 07:59, "[email protected]" <[email protected]>:
> -bash: annotate-output: command not found
>
> 12.12.2016, 21:26, "Derek Atkins" <[email protected]>:
>> Hi,
>>
>> What happened when you ran the virt-v2v command I suggested?
>>
>> -derek
>>
>> On Mon, December 12, 2016 12:32 pm, [email protected] wrote:
>>>   I mount 5TB nfs-share to my host and tried to import OVA from this
>>>   mount-point.
>>>   Exact same error.
>>>
>>>   Do the import process attempts to use 256G inside the Engine virtual
>>>   machine ???
>>>
>>>   12.12.2016, 18:01, "[email protected]"
>>>   <[email protected]>:
>>>>   1.2G is real OVA image size.
>>>>   256G is virtual disk size.
>>>>   Could this be a problem?
>>>>
>>>>   But before I start the import, in the settings I choose "Thin provision"
>>>>   (see the attached screenshot)
>>>>
>>>>   12.12.2016, 17:47, "[email protected]"
>>>>   <[email protected]>:
>>>>>    Hi Derek
>>>>>
>>>>>    # df -H /tmp/
>>>>>
>>>>>    Filesystem Size Used Avail Use% Mounted on
>>>>>    /dev/cciss/c0d0p2 53G 5.7G 45G 12% /
>>>>>
>>>>>    But my OVA file size - 1.2G
>>>>>
>>>>>    12.12.2016, 17:26, "Derek Atkins" <[email protected]>:
>>>>>>     Hi,
>>>>>>
>>>>>>     Did you perhaps run out of disk space in /tmp? I'm not sure where
>>>>>>   the
>>>>>>     virt-v2v does it work, but the error "Conversion of VM from exteral
>>>>>>     enironment failed: copy-disk stream closed unexpectedly" seems to
>>>>>>   imply an
>>>>>>     error there.
>>>>>>
>>>>>>     I had no issues myself on 4.0.5 (modulo setfiles taking a very long
>>>>>>   time
>>>>>>     in a few cases).
>>>>>>
>>>>>>     -derek
>>>>>>
>>>>>>     On Mon, December 12, 2016 9:19 am, [email protected] wrote:
>>>>>>>      Hello oVirt guru`s!
>>>>>>>
>>>>>>>      In the process of importing from OVA file (from
>>>>>>>      /tmp/rhel_Vsp-4.4.0.GA-53.ova in one of hosts) VM first appears in
>>>>>>>   the web
>>>>>>>      console, then disappears.
>>>>>>>
>>>>>>>      In the oVirt Engine log:
>>>>>>>
>>>>>>>      ...
>>>>>>>      2016-12-12 16:55:20,209 INFO
>>>>>>>      [org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand]
>>>>>>>   (default
>>>>>>>      task-28) [6179021a] Lock Acquired to object
>>>>>>>      'EngineLock:{exclusiveLocks='[rhel_Vsp-4.4.0.GA-53=<VM_NAME,
>>>>>>>      ACTION_TYPE_FAILED_NAME_ALREADY_USED>,
>>>>>>>      2a3d20f9-502a-4c60-8c14-371456cd4c99=<VM,
>>>>>>>      ACTION_TYPE_FAILED_VM_IS_BEING_IMPORTED$VmName
>>>>>>>   rhel_Vsp-4.4.0.GA-53>]',
>>>>>>>      sharedLocks='null'}'
>>>>>>>      2016-12-12 16:55:20,326 WARN
>>>>>>>      [org.ovirt.engine.core.dal.job.ExecutionMessageDirector]
>>>>>>>      (org.ovirt.thread.pool-6-thread-35) [6179021a] The message key
>>>>>>>      'ImportVmFromOva' is missing from 'bundles/ExecutionMessages'
>>>>>>>      ...
>>>>>>>      2016-12-12 16:57:34,758 INFO
>>>>>>>      [org.ovirt.engine.core.bll.exportimport.ConvertVmCallback]
>>>>>>>      (DefaultQuartzScheduler4) [27df05b8] Conversion of VM from exteral
>>>>>>>      enironment failed: copy-disk stream closed unexpectedly
>>>>>>>      2016-12-12 16:57:35,761 ERROR
>>>>>>>      [org.ovirt.engine.core.bll.exportimport.ConvertOvaCommand]
>>>>>>>      (DefaultQuartzScheduler8) [77b50303] Ending command
>>>>>>>      'org.ovirt.engine.core.bll.exportimport.ConvertOvaCommand' with
>>>>>>>   failure.
>>>>>>>      2016-12-12 16:57:35,827 INFO
>>>>>>>      [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
>>>>>>>      (DefaultQuartzScheduler8) [77b50303] Correlation ID: 3187db75,
>>>>>>>   Call Stack:
>>>>>>>      null, Custom Event ID: -1, Message: Failed to convert Vm
>>>>>>>      rhel_Vsp-4.4.0.GA-53
>>>>>>>      ...
>>>>>>>      2016-12-12 16:57:38,458 INFO
>>>>>>>      [org.ovirt.engine.core.vdsbroker.vdsbroker.DeleteV2VJobVDSCommand]
>>>>>>>      (DefaultQuartzScheduler8) [5237b44a] FINISH,
>>>>>>>   DeleteV2VJobVDSCommand, log
>>>>>>>      id: 1a69f33d
>>>>>>>      2016-12-12 16:57:38,459 WARN
>>>>>>>      [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
>>>>>>>      (DefaultQuartzScheduler8) [5237b44a] Trying to release exclusive
>>>>>>>   lock
>>>>>>>      which does not exist, lock key:
>>>>>>>   '2a3d20f9-502a-4c60-8c14-371456cd4c99VM'
>>>>>>>      2016-12-12 16:57:38,459 INFO
>>>>>>>      [org.ovirt.engine.core.bll.exportimport.ConvertOvaCommand]
>>>>>>>      (DefaultQuartzScheduler8) [5237b44a] Lock freed to object
>>>>>>>      'EngineLock:{exclusiveLocks='[2a3d20f9-502a-4c60-8c14-371456cd4c99=<VM,
>>>>>>>      ACTION_TYPE_FAILED_VM_IS_BEING_IMPORTED$VmName
>>>>>>>   rhel_Vsp-4.4.0.GA-53>]',
>>>>>>>      sharedLocks='null'}'
>>>>>>>      2016-12-12 16:57:38,463 ERROR
>>>>>>>      [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
>>>>>>>      (DefaultQuartzScheduler8) [5237b44a] Correlation ID: 3187db75,
>>>>>>>   Call Stack:
>>>>>>>      null, Custom Event ID: -1, Message: Failed to import Vm
>>>>>>>      rhel_Vsp-4.4.0.GA-53 to Data Center Default, Cluster Default
>>>>>>>
>>>>>>>      What could be the problem?
>>>>>>>      _______________________________________________
>>>>>>>      Users mailing list
>>>>>>>      [email protected]
>>>>>>>      http://lists.phx.ovirt.org/mailman/listinfo/users
>>>>>>
>>>>>>     --
>>>>>>            Derek Atkins 617-623-3745
>>>>>>            [email protected] www.ihtfp.com
>>>>>>            Computer and Internet Security Consultant
>>
>> --
>>         Derek Atkins 617-623-3745
>>         [email protected] www.ihtfp.com
>>         Computer and Internet Security Consultant
_______________________________________________
Users mailing list
[email protected]
http://lists.phx.ovirt.org/mailman/listinfo/users

2016-12-13 10:37:46,145 INFO  [org.ovirt.engine.core.bll.scheduling.HaReservationHandling] (DefaultQuartzScheduler1) [29d5fdb8] HA reservation status for cluster 'Default' is 'OK'
2016-12-13 10:39:54,352 INFO  [org.ovirt.vdsm.jsonrpc.client.reactors.ReactorClient] (SSL Stomp Reactor) [] Connecting to kom-ad01-vm31.sub.holding.com/10.1.0.31
2016-12-13 10:42:46,223 INFO  [org.ovirt.engine.core.bll.scheduling.HaReservationHandling] (DefaultQuartzScheduler5) [29d5fdb8] HA reservation status for cluster 'Default' is 'OK'
2016-12-13 10:43:21,575 INFO  [org.ovirt.engine.core.bll.storage.ovfstore.OvfDataUpdater] (DefaultQuartzScheduler2) [29d5fdb8] Attempting to update VMs/Templates Ovf.
2016-12-13 10:43:21,577 INFO  [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (DefaultQuartzScheduler2) [493a5272] Before acquiring and wait lock 'EngineLock:{exclusiveLocks='[00000001-0001-0001-0001-0000000001c2=<OVF_UPDATE, ACTION_TYPE_FAILED_OBJECT_LOCKED>]', sharedLocks='null'}'
2016-12-13 10:43:21,577 INFO  [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (DefaultQuartzScheduler2) [493a5272] Lock-wait acquired to object 'EngineLock:{exclusiveLocks='[00000001-0001-0001-0001-0000000001c2=<OVF_UPDATE, ACTION_TYPE_FAILED_OBJECT_LOCKED>]', sharedLocks='null'}'
2016-12-13 10:43:21,577 INFO  [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (DefaultQuartzScheduler2) [493a5272] Running command: ProcessOvfUpdateForStoragePoolCommand internal: true. Entities affected :  ID: 00000001-0001-0001-0001-0000000001c2 Type: StoragePool
2016-12-13 10:43:21,583 INFO  [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (DefaultQuartzScheduler2) [493a5272] Attempting to update VM OVFs in Data Center 'Default'
2016-12-13 10:43:21,589 INFO  [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (DefaultQuartzScheduler2) [493a5272] Successfully updated VM OVFs in Data Center 'Default'
2016-12-13 10:43:21,589 INFO  [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (DefaultQuartzScheduler2) [493a5272] Attempting to update template OVFs in Data Center 'Default'
2016-12-13 10:43:21,590 INFO  [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (DefaultQuartzScheduler2) [493a5272] Successfully updated templates OVFs in Data Center 'Default'
2016-12-13 10:43:21,590 INFO  [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (DefaultQuartzScheduler2) [493a5272] Attempting to remove unneeded template/vm OVFs in Data Center 'Default'
2016-12-13 10:43:21,592 INFO  [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (DefaultQuartzScheduler2) [493a5272] Successfully removed unneeded template/vm OVFs in Data Center 'Default'
2016-12-13 10:43:21,592 INFO  [org.ovirt.engine.core.bll.storage.ovfstore.ProcessOvfUpdateForStoragePoolCommand] (DefaultQuartzScheduler2) [493a5272] Lock freed to object 'EngineLock:{exclusiveLocks='[00000001-0001-0001-0001-0000000001c2=<OVF_UPDATE, ACTION_TYPE_FAILED_OBJECT_LOCKED>]', sharedLocks='null'}'
2016-12-13 10:43:53,058 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetOvaInfoVDSCommand] (default task-19) [] START, GetOvaInfoVDSCommand(HostName = KOM-AD01-VM31, GetOvaInfoParameters:{runAsync='true', hostId='231bf5d8-0c17-4529-9f26-0781deb7153f', path='/tmp/rhel_Vsp-4.4.0.GA-53.ova'}), log id: 623d4c38
2016-12-13 10:43:53,484 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetOvaInfoVDSCommand] (default task-19) [] FINISH, GetOvaInfoVDSCommand, return: VM [rhel_Vsp-4.4.0.GA-53], log id: 623d4c38
2016-12-13 10:44:54,893 INFO  [org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand] (default task-23) [b4bf162] Lock Acquired to object 'EngineLock:{exclusiveLocks='[b5e0df10-0dbd-400b-9fd6-7535c6f87503=<VM, ACTION_TYPE_FAILED_VM_IS_BEING_IMPORTED$VmName rhel_Vsp-4.4.0.GA-53>, rhel_Vsp-4.4.0.GA-53=<VM_NAME, ACTION_TYPE_FAILED_NAME_ALREADY_USED>]', sharedLocks='null'}'
2016-12-13 10:44:54,998 WARN  [org.ovirt.engine.core.dal.job.ExecutionMessageDirector] (org.ovirt.thread.pool-6-thread-3) [b4bf162] The message key 'ImportVmFromOva' is missing from 'bundles/ExecutionMessages'
2016-12-13 10:44:55,004 INFO  [org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand] (org.ovirt.thread.pool-6-thread-3) [b4bf162] Running command: ImportVmFromOvaCommand internal: false. Entities affected :  ID: a55c4470-9b8b-4aac-95f9-4c7016b90d30 Type: StorageAction group IMPORT_EXPORT_VM with role type ADMIN
2016-12-13 10:44:55,188 INFO  [org.ovirt.engine.core.bll.storage.disk.AddDiskCommand] (org.ovirt.thread.pool-6-thread-3) [3dc9145a] Running command: AddDiskCommand internal: true. Entities affected :  ID: b5e0df10-0dbd-400b-9fd6-7535c6f87503 Type: VMAction group CONFIGURE_VM_STORAGE with role type USER,  ID: a55c4470-9b8b-4aac-95f9-4c7016b90d30 Type: StorageAction group CREATE_DISK with role type USER
2016-12-13 10:44:55,210 INFO  [org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand] (org.ovirt.thread.pool-6-thread-3) [729d71ab] Running command: AddImageFromScratchCommand internal: true. Entities affected :  ID: a55c4470-9b8b-4aac-95f9-4c7016b90d30 Type: Storage
2016-12-13 10:44:55,233 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (org.ovirt.thread.pool-6-thread-3) [729d71ab] START, CreateImageVDSCommand( CreateImageVDSCommandParameters:{runAsync='true', storagePoolId='00000001-0001-0001-0001-0000000001c2', ignoreFailoverLimit='false', storageDomainId='a55c4470-9b8b-4aac-95f9-4c7016b90d30', imageGroupId='082f9dc6-51bf-4005-828b-35aa005f09ea', imageSizeInBytes='274877906944', volumeFormat='COW', newImageId='e3911e1f-89c9-444a-a791-73e6abca8c46', newImageDescription='{"DiskAlias":"rhel_Vsp-4.4.0.GA-53-disk1.vmdk","DiskDescription":""}', imageInitialSizeInBytes='1297053696'}), log id: 53b4c827
2016-12-13 10:44:55,234 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (org.ovirt.thread.pool-6-thread-3) [729d71ab] -- executeIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID
2016-12-13 10:44:55,537 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (org.ovirt.thread.pool-6-thread-3) [729d71ab] FINISH, CreateImageVDSCommand, return: e3911e1f-89c9-444a-a791-73e6abca8c46, log id: 53b4c827
2016-12-13 10:44:55,541 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-6-thread-3) [729d71ab] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 'dab8b468-5381-4a3a-8edf-be9c72b63d5a'
2016-12-13 10:44:55,541 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (org.ovirt.thread.pool-6-thread-3) [729d71ab] CommandMultiAsyncTasks::attachTask: Attaching task '1c0192ae-46c9-4e54-babc-aaf1ae781c10' to command 'dab8b468-5381-4a3a-8edf-be9c72b63d5a'.
2016-12-13 10:44:55,550 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (org.ovirt.thread.pool-6-thread-3) [729d71ab] Adding task '1c0192ae-46c9-4e54-babc-aaf1ae781c10' (Parent Command 'ImportVmFromOva', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters'), polling hasn't started yet..
2016-12-13 10:44:55,641 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-3) [729d71ab] Correlation ID: 3dc9145a, Job ID: f717583b-1670-49a7-b8c0-f69845af2768, Call Stack: null, Custom Event ID: -1, Message: Add-Disk operation of 'rhel_Vsp-4.4.0.GA-53-disk1.vmdk' was initiated by the system.
2016-12-13 10:44:55,652 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-3) [729d71ab] Correlation ID: b4bf162, Job ID: f717583b-1670-49a7-b8c0-f69845af2768, Call Stack: null, Custom Event ID: -1, Message: Starting to import Vm rhel_Vsp-4.4.0.GA-53 to Data Center Default, Cluster Default
2016-12-13 10:44:55,652 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-6-thread-3) [729d71ab] BaseAsyncTask::startPollingTask: Starting to poll task '1c0192ae-46c9-4e54-babc-aaf1ae781c10'.
2016-12-13 10:44:57,059 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler5) [29d5fdb8] Polling and updating Async Tasks: 1 tasks, 1 tasks to poll now
2016-12-13 10:44:58,067 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler5) [29d5fdb8] SPMAsyncTask::PollTask: Polling task '1c0192ae-46c9-4e54-babc-aaf1ae781c10' (Parent Command 'ImportVmFromOva', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') returned status 'finished', result 'success'.
2016-12-13 10:44:58,070 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler5) [29d5fdb8] BaseAsyncTask::onTaskEndSuccess: Task '1c0192ae-46c9-4e54-babc-aaf1ae781c10' (Parent Command 'ImportVmFromOva', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') ended successfully.
2016-12-13 10:44:58,074 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler5) [29d5fdb8] CommandAsyncTask::endActionIfNecessary: All tasks of command 'dab8b468-5381-4a3a-8edf-be9c72b63d5a' has ended -> executing 'endAction'
2016-12-13 10:44:58,074 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler5) [29d5fdb8] CommandAsyncTask::endAction: Ending action for '1' tasks (command ID: 'dab8b468-5381-4a3a-8edf-be9c72b63d5a'): calling endAction '.
2016-12-13 10:44:58,074 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-6-thread-10) [29d5fdb8] CommandAsyncTask::endCommandAction [within thread] context: Attempting to endAction 'ImportVmFromOva', executionIndex: '0'
2016-12-13 10:44:58,086 INFO  [org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand] (org.ovirt.thread.pool-6-thread-10) [29d5fdb8] Ending command 'org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand' successfully.
2016-12-13 10:44:58,105 INFO  [org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand] (org.ovirt.thread.pool-6-thread-10) [729d71ab] Ending command 'org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand' successfully.
2016-12-13 10:44:58,117 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-6-thread-10) [729d71ab] START, GetImageInfoVDSCommand( GetImageInfoVDSCommandParameters:{runAsync='true', storagePoolId='00000001-0001-0001-0001-0000000001c2', ignoreFailoverLimit='false', storageDomainId='a55c4470-9b8b-4aac-95f9-4c7016b90d30', imageGroupId='082f9dc6-51bf-4005-828b-35aa005f09ea', imageId='e3911e1f-89c9-444a-a791-73e6abca8c46'}), log id: 4878f370
2016-12-13 10:44:58,367 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (org.ovirt.thread.pool-6-thread-10) [729d71ab] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage.DiskImage@d47fd057, log id: 4878f370
2016-12-13 10:44:58,370 INFO  [org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand] (org.ovirt.thread.pool-6-thread-10) [] Lock freed to object 'EngineLock:{exclusiveLocks='[b5e0df10-0dbd-400b-9fd6-7535c6f87503=<VM, ACTION_TYPE_FAILED_VM_IS_BEING_IMPORTED$VmName rhel_Vsp-4.4.0.GA-53>, rhel_Vsp-4.4.0.GA-53=<VM_NAME, ACTION_TYPE_FAILED_NAME_ALREADY_USED>]', sharedLocks='null'}'
2016-12-13 10:44:58,402 INFO  [org.ovirt.engine.core.bll.exportimport.ConvertOvaCommand] (pool-5-thread-5) [775158df] Lock Acquired to object 'EngineLock:{exclusiveLocks='[b5e0df10-0dbd-400b-9fd6-7535c6f87503=<VM, ACTION_TYPE_FAILED_VM_IS_BEING_IMPORTED$VmName rhel_Vsp-4.4.0.GA-53>]', sharedLocks='null'}'
2016-12-13 10:44:58,407 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-6-thread-10) [] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type 'ImportVmFromOva' completed, handling the result.
2016-12-13 10:44:58,407 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-6-thread-10) [] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type 'ImportVmFromOva' succeeded, clearing tasks.
2016-12-13 10:44:58,407 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-6-thread-10) [] SPMAsyncTask::ClearAsyncTask: Attempting to clear task '1c0192ae-46c9-4e54-babc-aaf1ae781c10'
2016-12-13 10:44:58,409 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-6-thread-10) [] START, SPMClearTaskVDSCommand( SPMTaskGuidBaseVDSCommandParameters:{runAsync='true', storagePoolId='00000001-0001-0001-0001-0000000001c2', ignoreFailoverLimit='false', taskId='1c0192ae-46c9-4e54-babc-aaf1ae781c10'}), log id: 56f5f126
2016-12-13 10:44:58,409 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-6-thread-10) [] START, HSMClearTaskVDSCommand(HostName = KOM-AD01-VM32, HSMTaskGuidBaseVDSCommandParameters:{runAsync='true', hostId='393e4303-63ac-47ab-be44-83f908581ca4', taskId='1c0192ae-46c9-4e54-babc-aaf1ae781c10'}), log id: 21b1b05
2016-12-13 10:44:58,426 INFO  [org.ovirt.engine.core.bll.exportimport.ConvertOvaCommand] (pool-5-thread-5) [775158df] Running command: ConvertOvaCommand internal: true. Entities affected :  ID: b5e0df10-0dbd-400b-9fd6-7535c6f87503 Type: VM
2016-12-13 10:44:58,427 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConvertOvaVDSCommand] (pool-5-thread-5) [775158df] START, ConvertOvaVDSCommand(HostName = KOM-AD01-VM31, ConvertOvaVDSParameters:{runAsync='true', hostId='231bf5d8-0c17-4529-9f26-0781deb7153f', ovaPath='/tmp/rhel_Vsp-4.4.0.GA-53.ova', vmName='rhel_Vsp-4.4.0.GA-53', storageDomainId='a55c4470-9b8b-4aac-95f9-4c7016b90d30', storagePoolId='00000001-0001-0001-0001-0000000001c2', virtioIsoPath='null', Disk0='082f9dc6-51bf-4005-828b-35aa005f09ea'}), log id: 54211163
2016-12-13 10:44:58,438 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConvertOvaVDSCommand] (pool-5-thread-5) [775158df] FINISH, ConvertOvaVDSCommand, return: b5e0df10-0dbd-400b-9fd6-7535c6f87503, log id: 54211163
2016-12-13 10:44:58,451 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (pool-5-thread-5) [775158df] Correlation ID: 775158df, Call Stack: null, Custom Event ID: -1, Message: Starting to convert Vm rhel_Vsp-4.4.0.GA-53
2016-12-13 10:44:58,524 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-6-thread-10) [] FINISH, HSMClearTaskVDSCommand, log id: 21b1b05
2016-12-13 10:44:58,525 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-6-thread-10) [] FINISH, SPMClearTaskVDSCommand, log id: 56f5f126
2016-12-13 10:44:58,528 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-6-thread-10) [] BaseAsyncTask::removeTaskFromDB: Removed task '1c0192ae-46c9-4e54-babc-aaf1ae781c10' from DataBase
2016-12-13 10:44:58,528 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-6-thread-10) [] CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 'dab8b468-5381-4a3a-8edf-be9c72b63d5a'
2016-12-13 10:46:14,115 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler5) [29d5fdb8] Setting new tasks map. The map contains now 0 tasks
2016-12-13 10:46:14,115 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler5) [29d5fdb8] Cleared all tasks of pool '00000001-0001-0001-0001-0000000001c2'.
2016-12-13 10:47:12,467 INFO  [org.ovirt.engine.core.bll.exportimport.ConvertVmCallback] (DefaultQuartzScheduler2) [493a5272] Conversion of VM from exteral enironment failed: copy-disk stream closed unexpectedly
2016-12-13 10:47:13,469 ERROR [org.ovirt.engine.core.bll.exportimport.ConvertOvaCommand] (DefaultQuartzScheduler9) [2c3d37b6] Ending command 'org.ovirt.engine.core.bll.exportimport.ConvertOvaCommand' with failure.
2016-12-13 10:47:13,534 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler9) [2c3d37b6] Correlation ID: 775158df, Call Stack: null, Custom Event ID: -1, Message: Failed to convert Vm rhel_Vsp-4.4.0.GA-53
2016-12-13 10:47:13,592 INFO  [org.ovirt.engine.core.bll.RemoveVmCommand] (DefaultQuartzScheduler9) [59a2e520] Running command: RemoveVmCommand internal: true. Entities affected :  ID: b5e0df10-0dbd-400b-9fd6-7535c6f87503 Type: VMAction group DELETE_VM with role type USER
2016-12-13 10:47:13,593 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (DefaultQuartzScheduler9) [59a2e520] START, SetVmStatusVDSCommand( SetVmStatusVDSCommandParameters:{runAsync='true', vmId='b5e0df10-0dbd-400b-9fd6-7535c6f87503', status='ImageLocked', exitStatus='Normal'}), log id: 521bc8c0
2016-12-13 10:47:13,597 INFO  [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (DefaultQuartzScheduler9) [59a2e520] FINISH, SetVmStatusVDSCommand, log id: 521bc8c0
2016-12-13 10:47:13,598 INFO  [org.ovirt.engine.core.bll.RemoveVmCommand] (DefaultQuartzScheduler9) [59a2e520] Lock freed to object 'EngineLock:{exclusiveLocks='[b5e0df10-0dbd-400b-9fd6-7535c6f87503=<VM, ACTION_TYPE_FAILED_VM_IS_BEING_IMPORTED$VmName rhel_Vsp-4.4.0.GA-53>]', sharedLocks='null'}'
2016-12-13 10:47:13,668 INFO  [org.ovirt.engine.core.bll.storage.disk.image.RemoveAllVmImagesCommand] (DefaultQuartzScheduler9) [68be7ce4] Running command: RemoveAllVmImagesCommand internal: true. Entities affected :  ID: b5e0df10-0dbd-400b-9fd6-7535c6f87503 Type: VM
2016-12-13 10:47:13,694 INFO  [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand] (DefaultQuartzScheduler9) [38a0f7c3] Running command: RemoveImageCommand internal: true. Entities affected :  ID: 00000000-0000-0000-0000-000000000000 Type: Storage
2016-12-13 10:47:13,703 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (DefaultQuartzScheduler9) [38a0f7c3] START, DeleteImageGroupVDSCommand( DeleteImageGroupVDSCommandParameters:{runAsync='true', storagePoolId='00000001-0001-0001-0001-0000000001c2', ignoreFailoverLimit='false', storageDomainId='a55c4470-9b8b-4aac-95f9-4c7016b90d30', imageGroupId='082f9dc6-51bf-4005-828b-35aa005f09ea', postZeros='false', forceDelete='false'}), log id: 290e7f02
2016-12-13 10:47:15,156 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (DefaultQuartzScheduler9) [38a0f7c3] FINISH, DeleteImageGroupVDSCommand, log id: 290e7f02
2016-12-13 10:47:15,157 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler9) [38a0f7c3] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 'c6ffd52f-0e2c-4baa-805b-ed13e0b09278'
2016-12-13 10:47:15,157 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (DefaultQuartzScheduler9) [38a0f7c3] CommandMultiAsyncTasks::attachTask: Attaching task '7baab261-7e17-49e2-90a0-44b1d910d630' to command 'c6ffd52f-0e2c-4baa-805b-ed13e0b09278'.
2016-12-13 10:47:15,162 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler9) [38a0f7c3] Adding task '7baab261-7e17-49e2-90a0-44b1d910d630' (Parent Command 'RemoveVm', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters'), polling hasn't started yet..
2016-12-13 10:47:15,267 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler9) [38a0f7c3] Correlation ID: 59a2e520, Call Stack: null, Custom Event ID: -1, Message: VM rhel_Vsp-4.4.0.GA-53 was successfully removed.
2016-12-13 10:47:15,267 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler9) [38a0f7c3] BaseAsyncTask::startPollingTask: Starting to poll task '7baab261-7e17-49e2-90a0-44b1d910d630'.
2016-12-13 10:47:15,269 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DeleteV2VJobVDSCommand] (DefaultQuartzScheduler9) [38a0f7c3] START, DeleteV2VJobVDSCommand(HostName = KOM-AD01-VM31, VdsAndVmIDVDSParametersBase:{runAsync='true', hostId='231bf5d8-0c17-4529-9f26-0781deb7153f', vmId='b5e0df10-0dbd-400b-9fd6-7535c6f87503'}), log id: 1995d81b
2016-12-13 10:47:15,403 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DeleteV2VJobVDSCommand] (DefaultQuartzScheduler9) [38a0f7c3] FINISH, DeleteV2VJobVDSCommand, log id: 1995d81b
2016-12-13 10:47:15,405 WARN  [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (DefaultQuartzScheduler9) [38a0f7c3] Trying to release exclusive lock which does not exist, lock key: 'b5e0df10-0dbd-400b-9fd6-7535c6f87503VM'
2016-12-13 10:47:15,405 INFO  [org.ovirt.engine.core.bll.exportimport.ConvertOvaCommand] (DefaultQuartzScheduler9) [38a0f7c3] Lock freed to object 'EngineLock:{exclusiveLocks='[b5e0df10-0dbd-400b-9fd6-7535c6f87503=<VM, ACTION_TYPE_FAILED_VM_IS_BEING_IMPORTED$VmName rhel_Vsp-4.4.0.GA-53>]', sharedLocks='null'}'
2016-12-13 10:47:15,410 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler9) [38a0f7c3] Correlation ID: 775158df, Call Stack: null, Custom Event ID: -1, Message: Failed to import Vm rhel_Vsp-4.4.0.GA-53 to Data Center Default, Cluster Default
2016-12-13 10:47:18,078 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (DefaultQuartzScheduler2) [493a5272] Polling and updating Async Tasks: 1 tasks, 1 tasks to poll now
2016-12-13 10:47:18,527 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler2) [493a5272] SPMAsyncTask::PollTask: Polling task '7baab261-7e17-49e2-90a0-44b1d910d630' (Parent Command 'RemoveVm', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') returned status 'finished', result 'success'.
2016-12-13 10:47:18,527 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (DefaultQuartzScheduler2) [493a5272] BaseAsyncTask::onTaskEndSuccess: Task '7baab261-7e17-49e2-90a0-44b1d910d630' (Parent Command 'RemoveVm', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') ended successfully.
2016-12-13 10:47:18,531 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler2) [493a5272] CommandAsyncTask::endActionIfNecessary: All tasks of command 'c6ffd52f-0e2c-4baa-805b-ed13e0b09278' has ended -> executing 'endAction'
2016-12-13 10:47:18,531 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (DefaultQuartzScheduler2) [493a5272] CommandAsyncTask::endAction: Ending action for '1' tasks (command ID: 'c6ffd52f-0e2c-4baa-805b-ed13e0b09278'): calling endAction '.
2016-12-13 10:47:18,531 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-6-thread-24) [493a5272] CommandAsyncTask::endCommandAction [within thread] context: Attempting to endAction 'RemoveVm', executionIndex: '0'
2016-12-13 10:47:18,591 INFO  [org.ovirt.engine.core.bll.RemoveVmCommand] (org.ovirt.thread.pool-6-thread-24) [59a2e520] Ending command 'org.ovirt.engine.core.bll.RemoveVmCommand' successfully.
2016-12-13 10:47:18,591 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-6-thread-24) [59a2e520] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type 'RemoveVm' completed, handling the result.
2016-12-13 10:47:18,591 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-6-thread-24) [59a2e520] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type 'RemoveVm' succeeded, clearing tasks.
2016-12-13 10:47:18,591 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-6-thread-24) [59a2e520] SPMAsyncTask::ClearAsyncTask: Attempting to clear task '7baab261-7e17-49e2-90a0-44b1d910d630'
2016-12-13 10:47:18,593 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-6-thread-24) [59a2e520] START, SPMClearTaskVDSCommand( SPMTaskGuidBaseVDSCommandParameters:{runAsync='true', storagePoolId='00000001-0001-0001-0001-0000000001c2', ignoreFailoverLimit='false', taskId='7baab261-7e17-49e2-90a0-44b1d910d630'}), log id: 2eca4e22
2016-12-13 10:47:18,594 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-6-thread-24) [59a2e520] START, HSMClearTaskVDSCommand(HostName = KOM-AD01-VM32, HSMTaskGuidBaseVDSCommandParameters:{runAsync='true', hostId='393e4303-63ac-47ab-be44-83f908581ca4', taskId='7baab261-7e17-49e2-90a0-44b1d910d630'}), log id: 36cb1787
2016-12-13 10:47:19,523 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (org.ovirt.thread.pool-6-thread-24) [59a2e520] FINISH, HSMClearTaskVDSCommand, log id: 36cb1787
2016-12-13 10:47:19,523 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (org.ovirt.thread.pool-6-thread-24) [59a2e520] FINISH, SPMClearTaskVDSCommand, log id: 2eca4e22
2016-12-13 10:47:19,527 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (org.ovirt.thread.pool-6-thread-24) [59a2e520] BaseAsyncTask::removeTaskFromDB: Removed task '7baab261-7e17-49e2-90a0-44b1d910d630' from DataBase
2016-12-13 10:47:19,527 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (org.ovirt.thread.pool-6-thread-24) [59a2e520] CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 'c6ffd52f-0e2c-4baa-805b-ed13e0b09278'
2016-12-13 10:47:30,429 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetFileStatsVDSCommand] (org.ovirt.thread.pool-6-thread-46) [775158df] START, GetFileStatsVDSCommand( GetFileStatsParameters:{runAsync='true', storagePoolId='00000001-0001-0001-0001-0000000001c2', ignoreFailoverLimit='false'}), log id: 3ff63a3e
2016-12-13 10:47:31,441 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetFileStatsVDSCommand] (org.ovirt.thread.pool-6-thread-46) [775158df] FINISH, GetFileStatsVDSCommand, return: {debian-8.6.0-amd64-DVD-1.iso={status=0, ctime=1477054779.0, size=3994091520}}, log id: 3ff63a3e
2016-12-13 10:47:31,444 INFO  [org.ovirt.engine.core.bll.storage.domain.IsoDomainListSyncronizer] (org.ovirt.thread.pool-6-thread-46) [775158df] Finished automatic refresh process for 'ISO' file type with success, for storage domain id '6b97f854-b9ca-4d44-b9e0-8ec5489e3770'.
2016-12-13 10:47:46,303 INFO  [org.ovirt.engine.core.bll.scheduling.HaReservationHandling] (DefaultQuartzScheduler4) [29d5fdb8] HA reservation status for cluster 'Default' is 'OK'

_______________________________________________
Users mailing list
[email protected]
http://lists.phx.ovirt.org/mailman/listinfo/users

Re: [ovirt-users] How can I import VMWare OVA file to oVirt 4.0?

Reply via email to