[ovirt-users] Exporting/Copying VM to another oVirt server

Arik Hadas ahadas at redhat.com
Thu Dec 28 23:17:07 UTC 2017 

Cool, glad to hear that it works now :)
Filed a bug about that NPE [1]

[1] https://bugzilla.redhat.com/show_bug.cgi?id=1529607

On Fri, Dec 29, 2017 at 1:09 AM, Alex Bartonek <Alex at unix1337.com> wrote:

> geez... yes, you were correct. I added the description to the VM not the
> *disk*.  :-/
> Sorry about that.  That took care of it.  Simple.  Very cool!!  Thank you
> very  much!
>
>
> Sent with ProtonMail <https://protonmail.com> Secure Email.
>
> -------- Original Message --------
> Subject: Re: [ovirt-users] Exporting/Copying VM to another oVirt server
> Local Time: December 28, 2017 4:57 PM
> UTC Time: December 28, 2017 10:57 PM
> From: ahadas at redhat.com
> To: Alex Bartonek <Alex at unix1337.com>
> users <users at ovirt.org>
>
> Thanks.
> It seems that it fails at the same place it failed before [1]. The only
> reason for this failure is that the disk's description is empty.
> Are you sure that you set a description for the right disk? Can you attach
> a screenshot of the VM's disk info?
>
> [1] https://github.com/oVirt/ovirt-engine/blob/ovirt-
> engine-4.2.0/backend/manager/modules/utils/src/main/java/
> org/ovirt/engine/core/utils/ovf/OvfOvaWriter.java#L55
>
> On Fri, Dec 29, 2017 at 12:45 AM, Alex Bartonek <Alex at unix1337.com> wrote:
>
>> shut down the engine, deleted engine.log, started up and attached entire
>> log.  It does contain a java stack dump.
>>
>>
>> -------- Original Message --------
>> Subject: Re: [ovirt-users] Exporting/Copying VM to another oVirt server
>> Local Time: December 28, 2017 4:36 PM
>> UTC Time: December 28, 2017 10:36 PM
>> From: ahadas at redhat.com
>> To: Alex Bartonek <Alex at unix1337.com>
>> users <users at ovirt.org>
>>
>> That's weird that we don't get the stacktrace for that NPE, I wonder if
>> we encounter the problem that was discussed in this old thread: [1]
>> Could you restart the engine and try again, hoping that then we'll get
>> the stacktrace (it was shown previously in the engine,log and was related
>> to that empty disk description)
>>
>> [1] https://stackoverflow.com/questions/2411487/nullpointere
>> xception-in-java-with-no-stacktrace
>>
>> On Fri, Dec 29, 2017 at 12:25 AM, Alex Bartonek <Alex at unix1337.com>
>> wrote:
>>
>>> 2017-12-28 16:14:59,452-06 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
>>> (EE-ManagedThreadFactory-engine-Thread-15462) [10f0e6a5]
>>> BaseAsyncTask::removeTaskFromDB: Removed task
>>> '3e1cfaa1-07a5-49cb-a9bc-8937c7cc75b2' from DataBase
>>> 2017-12-28 16:14:59,452-06 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
>>> (EE-ManagedThreadFactory-engine-Thread-15462) [10f0e6a5]
>>> CommandAsyncTask::HandleEndActionResult [within thread]: Removing
>>> CommandMultiAsyncTasks object for entity '84686e92-7ca6-4228-9d3c-31289
>>> b2d1d57'
>>> 2017-12-28 16:15:48,805-06 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
>>> (EE-ManagedThreadFactory-engineScheduled-Thread-45) [] Setting new
>>> tasks map. The map contains now 1 tasks
>>> 2017-12-28 16:16:18,805-06 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
>>> (EE-ManagedThreadFactory-engineScheduled-Thread-5) [] Setting new tasks
>>> map. The map contains now 0 tasks
>>> 2017-12-28 16:16:18,805-06 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
>>> (EE-ManagedThreadFactory-engineScheduled-Thread-5) [] Cleared all tasks
>>> of pool '3bb4edf8-be27-42e4-b860-a1f1f293b81c'.
>>> 2017-12-28 16:21:17,538-06 INFO  [org.ovirt.engine.core.bll.uti
>>> ls.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1)
>>> [] Thread pool 'default' is using 0 threads out of 1 and 5 tasks are
>>> waiting in the queue.
>>> 2017-12-28 16:21:17,538-06 INFO  [org.ovirt.engine.core.bll.uti
>>> ls.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1)
>>> [] Thread pool 'engine' is using 0 threads out of 500, 8 threads waiting
>>> for tasks and 0 tasks in queue.
>>> 2017-12-28 16:21:17,538-06 INFO  [org.ovirt.engine.core.bll.uti
>>> ls.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1)
>>> [] Thread pool 'engineScheduled' is using 0 threads out of 100 and 100
>>> tasks are waiting in the queue.
>>> 2017-12-28 16:21:17,538-06 INFO  [org.ovirt.engine.core.bll.uti
>>> ls.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1)
>>> [] Thread pool 'engineThreadMonitoring' is using 1 threads out of 1 and 0
>>> tasks are waiting in the queue.
>>> 2017-12-28 16:21:17,538-06 INFO  [org.ovirt.engine.core.bll.uti
>>> ls.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1)
>>> [] Thread pool 'hostUpdatesChecker' is using 0 threads out of 5 and 2 tasks
>>> are waiting in the queue.
>>> 2017-12-28 16:21:28,068-06 INFO  [org.ovirt.engine.core.bll.exportimport.ExportOvaCommand]
>>> (default task-39) [aa503a62-d439-4131-9b46-e161779847f4] Lock Acquired
>>> to object 'EngineLock:{exclusiveLocks='[43c82580-6c7a-4319-97f6-6cfb17a4445a=VM]',
>>> sharedLocks=''}'
>>> 2017-12-28 16:21:28,082-06 INFO  [org.ovirt.engine.core.common.utils.ansible.AnsibleExecutor]
>>> (default task-39) [aa503a62-d439-4131-9b46-e161779847f4] Executing
>>> Ansible command:  [/usr/bin/ansible-playbook, --private-key=/etc/pki/ovirt-engine/keys/engine_id_rsa,
>>> --inventory=/tmp/ansible-inventory8939720115375947799,
>>> --extra-vars=target_directory=/media/primary/extra_space,
>>> --extra-vars=validate_only=True, /usr/share/ovirt-engine/playbooks/ovirt-ova-export.yml]
>>> [Logfile: /var/log/ovirt-engine/ova/ovirt-export-ova-validate-ansible-
>>> 20171228162128-reindeer.themagicm.com-aa503a62-d439-4131-
>>> 9b46-e161779847f4.log]
>>> 2017-12-28 16:21:32,496-06 INFO  [org.ovirt.engine.core.common.utils.ansible.AnsibleExecutor]
>>> (default task-39) [aa503a62-d439-4131-9b46-e161779847f4] Ansible
>>> playbook command has exited with value: 0
>>> 2017-12-28 16:21:32,548-06 INFO  [org.ovirt.engine.core.bll.exportimport.ExportOvaCommand]
>>> (EE-ManagedThreadFactory-engine-Thread-15515)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Running command:
>>> ExportOvaCommand internal: false. Entities affected :  ID:
>>> 43c82580-6c7a-4319-97f6-6cfb17a4445a Type: VMAction group
>>> IMPORT_EXPORT_VM with role type ADMIN
>>> 2017-12-28 16:21:32,584-06 INFO  [org.ovirt.engine.core.bll.sto
>>> rage.disk.CreateAllOvaDisksCommand] (EE-ManagedThreadFactory-engine-Thread-15515)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Running command:
>>> CreateAllOvaDisksCommand internal: true.
>>> 2017-12-28 16:21:32,645-06 INFO  [org.ovirt.engine.core.bll.sto
>>> rage.disk.image.CopyImageGroupCommand] (EE-ManagedThreadFactory-engine-Thread-15515)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Running command:
>>> CopyImageGroupCommand internal: true. Entities affected :  ID:
>>> 4108dd94-7538-4fdb-a01e-2eb51b9083b9 Type: Storage
>>> 2017-12-28 16:21:32,757-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.irsbroker.CopyImageVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15515)
>>> [aa503a62-d439-4131-9b46-e161779847f4] START, CopyImageVDSCommand(
>>> CopyImageVDSCommandParameters:{storagePoolId='3bb4edf8-be27-42e4-b860-a1f1f293b81c',
>>> ignoreFailoverLimit='false', storageDomainId='4108dd94-7538-4fdb-a01e-2eb51b9083b9',
>>> imageGroupId='5499e522-db23-4a71-b5a2-e30933d7d547',
>>> imageId='5770b269-f98f-4d12-b627-e960b9e295b4',
>>> dstImageGroupId='a2578b49-5edf-47b8-a98a-12a20b47688c',
>>> vmId='43c82580-6c7a-4319-97f6-6cfb17a4445a',
>>> dstImageId='34885dca-e1c6-4e92-9f06-957a63c0ae56', imageDescription='',
>>> dstStorageDomainId='4108dd94-7538-4fdb-a01e-2eb51b9083b9',
>>> copyVolumeType='LeafVol', volumeFormat='COW', preallocate='Sparse',
>>> postZero='false', discard='false', force='true'}), log id: 40b6c4b9
>>> 2017-12-28 16:21:32,760-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.irsbroker.CopyImageVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15515)
>>> [aa503a62-d439-4131-9b46-e161779847f4] -- executeIrsBrokerCommand:
>>> calling 'copyImage' with two new parameters: description and UUID.
>>> Parameters:
>>> 2017-12-28 16:21:32,760-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.irsbroker.CopyImageVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15515)
>>> [aa503a62-d439-4131-9b46-e161779847f4] ++ sdUUID=4108dd94-7538-4fdb-a01e
>>> -2eb51b9083b9
>>> 2017-12-28 16:21:32,760-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.irsbroker.CopyImageVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15515)
>>> [aa503a62-d439-4131-9b46-e161779847f4] ++ spUUID=3bb4edf8-be27-42e4-b860
>>> -a1f1f293b81c
>>> 2017-12-28 16:21:32,760-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.irsbroker.CopyImageVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15515)
>>> [aa503a62-d439-4131-9b46-e161779847f4] ++ vmGUID=43c82580-6c7a-4319-97f6
>>> -6cfb17a4445a
>>> 2017-12-28 16:21:32,760-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.irsbroker.CopyImageVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15515)
>>> [aa503a62-d439-4131-9b46-e161779847f4] ++ srcImageGUID=5499e522-db23-4a7
>>> 1-b5a2-e30933d7d547
>>> 2017-12-28 16:21:32,760-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.irsbroker.CopyImageVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15515)
>>> [aa503a62-d439-4131-9b46-e161779847f4] ++ srcVolUUID=5770b269-f98f-4d12-
>>> b627-e960b9e295b4
>>> 2017-12-28 16:21:32,760-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.irsbroker.CopyImageVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15515)
>>> [aa503a62-d439-4131-9b46-e161779847f4] ++ dstImageGUID=a2578b49-5edf-47b
>>> 8-a98a-12a20b47688c
>>> 2017-12-28 16:21:32,760-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.irsbroker.CopyImageVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15515)
>>> [aa503a62-d439-4131-9b46-e161779847f4] ++ dstVolUUID=34885dca-e1c6-4e92-
>>> 9f06-957a63c0ae56
>>> 2017-12-28 16:21:32,760-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.irsbroker.CopyImageVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15515)
>>> [aa503a62-d439-4131-9b46-e161779847f4] ++ descr=
>>> 2017-12-28 16:21:32,760-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.irsbroker.CopyImageVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15515)
>>> [aa503a62-d439-4131-9b46-e161779847f4] ++ dstSdUUID=4108dd94-7538-4fdb-a
>>> 01e-2eb51b9083b9
>>> 2017-12-28 16:21:32,858-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.irsbroker.CopyImageVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15515)
>>> [aa503a62-d439-4131-9b46-e161779847f4] FINISH, CopyImageVDSCommand,
>>> return: f543383a-f369-488a-b625-73706b871a51, log id: 40b6c4b9
>>> 2017-12-28 16:21:32,875-06 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
>>> (EE-ManagedThreadFactory-engine-Thread-15515)
>>> [aa503a62-d439-4131-9b46-e161779847f4] CommandAsyncTask::Adding
>>> CommandMultiAsyncTasks object for command 'd8db5f12-cf59-40d2-b1b9-d5e44
>>> 79766a3'
>>> 2017-12-28 16:21:32,876-06 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks]
>>> (EE-ManagedThreadFactory-engine-Thread-15515)
>>> [aa503a62-d439-4131-9b46-e161779847f4] CommandMultiAsyncTasks::attachTask:
>>> Attaching task 'f543383a-f369-488a-b625-73706b871a51' to command
>>> 'd8db5f12-cf59-40d2-b1b9-d5e4479766a3'.
>>> 2017-12-28 16:21:32,906-06 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
>>> (EE-ManagedThreadFactory-engine-Thread-15515)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Adding task
>>> 'f543383a-f369-488a-b625-73706b871a51' (Parent Command
>>> 'CopyImageGroup', Parameters Type 'org.ovirt.engine.core.common.
>>> asynctasks.AsyncTaskParameters'), polling hasn't started yet..
>>> 2017-12-28 16:21:32,948-06 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
>>> (EE-ManagedThreadFactory-engine-Thread-15515)
>>> [aa503a62-d439-4131-9b46-e161779847f4] BaseAsyncTask::startPollingTask:
>>> Starting to poll task 'f543383a-f369-488a-b625-73706b871a51'.
>>> 2017-12-28 16:21:32,991-06 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
>>> (EE-ManagedThreadFactory-engine-Thread-15515)
>>> [aa503a62-d439-4131-9b46-e161779847f4] BaseAsyncTask::startPollingTask:
>>> Starting to poll task 'f543383a-f369-488a-b625-73706b871a51'.
>>> 2017-12-28 16:21:33,054-06 INFO  [org.ovirt.engine.core.dal.dbb
>>> roker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-15515)
>>> [aa503a62-d439-4131-9b46-e161779847f4] EVENT_ID:
>>> IMPORTEXPORT_STARTING_EXPORT_VM_TO_OVA(1,223), Starting to export Vm
>>> CentOS7 as a Virtual Appliance
>>> 2017-12-28 16:21:34,769-06 INFO  [org.ovirt.engine.core.bll.Con
>>> currentChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-29)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Command 'CreateAllOvaDisks' (id:
>>> '42331a77-2e38-4653-ba5f-29e5dccaea69') waiting on child command id:
>>> 'd8db5f12-cf59-40d2-b1b9-d5e4479766a3' type:'CopyImageGroup' to complete
>>> 2017-12-28 16:21:34,865-06 INFO  [org.ovirt.engine.core.bll.Ser
>>> ialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-29)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Command 'ExportOva' (id:
>>> '22e1df85-794b-4bb6-a6b6-a1679cbe2f78') waiting on child command id:
>>> '42331a77-2e38-4653-ba5f-29e5dccaea69' type:'CreateAllOvaDisks' to
>>> complete
>>> 2017-12-28 16:21:38,872-06 INFO  [org.ovirt.engine.core.bll.Con
>>> currentChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-77)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Command 'CreateAllOvaDisks' (id:
>>> '42331a77-2e38-4653-ba5f-29e5dccaea69') waiting on child command id:
>>> 'd8db5f12-cf59-40d2-b1b9-d5e4479766a3' type:'CopyImageGroup' to complete
>>> 2017-12-28 16:21:38,883-06 INFO  [org.ovirt.engine.core.bll.Ser
>>> ialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-77)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Command 'ExportOva' (id:
>>> '22e1df85-794b-4bb6-a6b6-a1679cbe2f78') waiting on child command id:
>>> '42331a77-2e38-4653-ba5f-29e5dccaea69' type:'CreateAllOvaDisks' to
>>> complete
>>> 2017-12-28 16:21:39,355-06 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
>>> (EE-ManagedThreadFactory-engineScheduled-Thread-64) [] Polling and
>>> updating Async Tasks: 1 tasks, 1 tasks to poll now
>>> 2017-12-28 16:21:39,366-06 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
>>> (EE-ManagedThreadFactory-engineScheduled-Thread-64) []
>>> SPMAsyncTask::PollTask: Polling task 'f543383a-f369-488a-b625-73706b871a51'
>>> (Parent Command 'CopyImageGroup', Parameters Type
>>> 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') returned
>>> status 'running'.
>>> 2017-12-28 16:21:39,367-06 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
>>> (EE-ManagedThreadFactory-engineScheduled-Thread-64) [] Finished polling
>>> Tasks, will poll again in 10 seconds.
>>> 2017-12-28 16:21:46,893-06 INFO  [org.ovirt.engine.core.bll.Con
>>> currentChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-7)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Command 'CreateAllOvaDisks' (id:
>>> '42331a77-2e38-4653-ba5f-29e5dccaea69') waiting on child command id:
>>> 'd8db5f12-cf59-40d2-b1b9-d5e4479766a3' type:'CopyImageGroup' to complete
>>> 2017-12-28 16:21:46,903-06 INFO  [org.ovirt.engine.core.bll.Ser
>>> ialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-7)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Command 'ExportOva' (id:
>>> '22e1df85-794b-4bb6-a6b6-a1679cbe2f78') waiting on child command id:
>>> '42331a77-2e38-4653-ba5f-29e5dccaea69' type:'CreateAllOvaDisks' to
>>> complete
>>> 2017-12-28 16:21:56,916-06 INFO  [org.ovirt.engine.core.bll.Con
>>> currentChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-92)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Command 'CreateAllOvaDisks' (id:
>>> '42331a77-2e38-4653-ba5f-29e5dccaea69') waiting on child command id:
>>> 'd8db5f12-cf59-40d2-b1b9-d5e4479766a3' type:'CopyImageGroup' to complete
>>> 2017-12-28 16:21:56,926-06 INFO  [org.ovirt.engine.core.bll.Ser
>>> ialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-92)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Command 'ExportOva' (id:
>>> '22e1df85-794b-4bb6-a6b6-a1679cbe2f78') waiting on child command id:
>>> '42331a77-2e38-4653-ba5f-29e5dccaea69' type:'CreateAllOvaDisks' to
>>> complete
>>> 2017-12-28 16:22:06,935-06 INFO  [org.ovirt.engine.core.bll.Con
>>> currentChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-38)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Command 'CreateAllOvaDisks' (id:
>>> '42331a77-2e38-4653-ba5f-29e5dccaea69') waiting on child command id:
>>> 'd8db5f12-cf59-40d2-b1b9-d5e4479766a3' type:'CopyImageGroup' to complete
>>> 2017-12-28 16:22:06,945-06 INFO  [org.ovirt.engine.core.bll.Ser
>>> ialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-38)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Command 'ExportOva' (id:
>>> '22e1df85-794b-4bb6-a6b6-a1679cbe2f78') waiting on child command id:
>>> '42331a77-2e38-4653-ba5f-29e5dccaea69' type:'CreateAllOvaDisks' to
>>> complete
>>> 2017-12-28 16:22:16,956-06 INFO  [org.ovirt.engine.core.bll.Con
>>> currentChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-15)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Command 'CreateAllOvaDisks' (id:
>>> '42331a77-2e38-4653-ba5f-29e5dccaea69') waiting on child command id:
>>> 'd8db5f12-cf59-40d2-b1b9-d5e4479766a3' type:'CopyImageGroup' to complete
>>> 2017-12-28 16:22:16,966-06 INFO  [org.ovirt.engine.core.bll.Ser
>>> ialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-15)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Command 'ExportOva' (id:
>>> '22e1df85-794b-4bb6-a6b6-a1679cbe2f78') waiting on child command id:
>>> '42331a77-2e38-4653-ba5f-29e5dccaea69' type:'CreateAllOvaDisks' to
>>> complete
>>> 2017-12-28 16:22:26,974-06 INFO  [org.ovirt.engine.core.bll.Con
>>> currentChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-58)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Command 'CreateAllOvaDisks' (id:
>>> '42331a77-2e38-4653-ba5f-29e5dccaea69') waiting on child command id:
>>> 'd8db5f12-cf59-40d2-b1b9-d5e4479766a3' type:'CopyImageGroup' to complete
>>> 2017-12-28 16:22:26,995-06 INFO  [org.ovirt.engine.core.bll.Ser
>>> ialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-58)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Command 'ExportOva' (id:
>>> '22e1df85-794b-4bb6-a6b6-a1679cbe2f78') waiting on child command id:
>>> '42331a77-2e38-4653-ba5f-29e5dccaea69' type:'CreateAllOvaDisks' to
>>> complete
>>> 2017-12-28 16:22:37,004-06 INFO  [org.ovirt.engine.core.bll.Con
>>> currentChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-2)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Command 'CreateAllOvaDisks' (id:
>>> '42331a77-2e38-4653-ba5f-29e5dccaea69') waiting on child command id:
>>> 'd8db5f12-cf59-40d2-b1b9-d5e4479766a3' type:'CopyImageGroup' to complete
>>> 2017-12-28 16:22:37,016-06 INFO  [org.ovirt.engine.core.bll.Ser
>>> ialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-2)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Command 'ExportOva' (id:
>>> '22e1df85-794b-4bb6-a6b6-a1679cbe2f78') waiting on child command id:
>>> '42331a77-2e38-4653-ba5f-29e5dccaea69' type:'CreateAllOvaDisks' to
>>> complete
>>> 2017-12-28 16:22:47,023-06 INFO  [org.ovirt.engine.core.bll.Con
>>> currentChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-5)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Command 'CreateAllOvaDisks' (id:
>>> '42331a77-2e38-4653-ba5f-29e5dccaea69') waiting on child command id:
>>> 'd8db5f12-cf59-40d2-b1b9-d5e4479766a3' type:'CopyImageGroup' to complete
>>> 2017-12-28 16:22:47,036-06 INFO  [org.ovirt.engine.core.bll.Ser
>>> ialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-5)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Command 'ExportOva' (id:
>>> '22e1df85-794b-4bb6-a6b6-a1679cbe2f78') waiting on child command id:
>>> '42331a77-2e38-4653-ba5f-29e5dccaea69' type:'CreateAllOvaDisks' to
>>> complete
>>> 2017-12-28 16:22:57,045-06 INFO  [org.ovirt.engine.core.bll.Con
>>> currentChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-77)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Command 'CreateAllOvaDisks' (id:
>>> '42331a77-2e38-4653-ba5f-29e5dccaea69') waiting on child command id:
>>> 'd8db5f12-cf59-40d2-b1b9-d5e4479766a3' type:'CopyImageGroup' to complete
>>> 2017-12-28 16:22:57,055-06 INFO  [org.ovirt.engine.core.bll.Ser
>>> ialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-77)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Command 'ExportOva' (id:
>>> '22e1df85-794b-4bb6-a6b6-a1679cbe2f78') waiting on child command id:
>>> '42331a77-2e38-4653-ba5f-29e5dccaea69' type:'CreateAllOvaDisks' to
>>> complete
>>> 2017-12-28 16:22:59,497-06 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
>>> (EE-ManagedThreadFactory-engineScheduled-Thread-23) []
>>> SPMAsyncTask::PollTask: Polling task 'f543383a-f369-488a-b625-73706b871a51'
>>> (Parent Command 'CopyImageGroup', Parameters Type
>>> 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') returned
>>> status 'finished', result 'success'.
>>> 2017-12-28 16:22:59,503-06 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
>>> (EE-ManagedThreadFactory-engineScheduled-Thread-23) []
>>> BaseAsyncTask::onTaskEndSuccess: Task 'f543383a-f369-488a-b625-73706b871a51'
>>> (Parent Command 'CopyImageGroup', Parameters Type
>>> 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') ended
>>> successfully.
>>> 2017-12-28 16:22:59,504-06 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
>>> (EE-ManagedThreadFactory-engineScheduled-Thread-23) []
>>> CommandAsyncTask::endActionIfNecessary: All tasks of command
>>> 'd8db5f12-cf59-40d2-b1b9-d5e4479766a3' has ended -> executing
>>> 'endAction'
>>> 2017-12-28 16:22:59,505-06 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
>>> (EE-ManagedThreadFactory-engineScheduled-Thread-23) []
>>> CommandAsyncTask::endAction: Ending action for '1' tasks (command ID:
>>> 'd8db5f12-cf59-40d2-b1b9-d5e4479766a3'): calling endAction '.
>>> 2017-12-28 16:22:59,505-06 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
>>> (EE-ManagedThreadFactory-engine-Thread-15528) []
>>> CommandAsyncTask::endCommandAction [within thread] context: Attempting
>>> to endAction 'CopyImageGroup',
>>> 2017-12-28 16:22:59,515-06 INFO  [org.ovirt.engine.core.bll.sto
>>> rage.disk.image.CopyImageGroupCommand] (EE-ManagedThreadFactory-engine-Thread-15528)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Command
>>> [id=d8db5f12-cf59-40d2-b1b9-d5e4479766a3]: Updating status to
>>> 'SUCCEEDED', The command end method logic will be executed by one of its
>>> parent commands.
>>> 2017-12-28 16:22:59,516-06 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
>>> (EE-ManagedThreadFactory-engine-Thread-15528)
>>> [aa503a62-d439-4131-9b46-e161779847f4] CommandAsyncTask::HandleEndActionResult
>>> [within thread]: endAction for action type 'CopyImageGroup' completed,
>>> handling the result.
>>> 2017-12-28 16:22:59,516-06 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
>>> (EE-ManagedThreadFactory-engine-Thread-15528)
>>> [aa503a62-d439-4131-9b46-e161779847f4] CommandAsyncTask::HandleEndActionResult
>>> [within thread]: endAction for action type 'CopyImageGroup' succeeded,
>>> clearing tasks.
>>> 2017-12-28 16:22:59,516-06 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
>>> (EE-ManagedThreadFactory-engine-Thread-15528)
>>> [aa503a62-d439-4131-9b46-e161779847f4] SPMAsyncTask::ClearAsyncTask:
>>> Attempting to clear task 'f543383a-f369-488a-b625-73706b871a51'
>>> 2017-12-28 16:22:59,518-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.irsbroker.SPMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15528)
>>> [aa503a62-d439-4131-9b46-e161779847f4] START, SPMClearTaskVDSCommand(
>>> SPMTaskGuidBaseVDSCommandParameters:{storagePoolId='3bb4edf8-be27-42e4-b860-a1f1f293b81c',
>>> ignoreFailoverLimit='false', taskId='f543383a-f369-488a-b625-73706b871a51'}),
>>> log id: 2e9c1d1
>>> 2017-12-28 16:22:59,521-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.vdsbroker.HSMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15528)
>>> [aa503a62-d439-4131-9b46-e161779847f4] START,
>>> HSMClearTaskVDSCommand(HostName = reindeerHost,
>>> HSMTaskGuidBaseVDSCommandParameters:{hostId='4f344875-08d8-49d8-b2ee-5ca3e1810051',
>>> taskId='f543383a-f369-488a-b625-73706b871a51'}), log id: 4ad7abb5
>>> 2017-12-28 16:22:59,535-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.vdsbroker.HSMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15528)
>>> [aa503a62-d439-4131-9b46-e161779847f4] FINISH, HSMClearTaskVDSCommand,
>>> log id: 4ad7abb5
>>> 2017-12-28 16:22:59,535-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.irsbroker.SPMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15528)
>>> [aa503a62-d439-4131-9b46-e161779847f4] FINISH, SPMClearTaskVDSCommand,
>>> log id: 2e9c1d1
>>> 2017-12-28 16:22:59,545-06 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
>>> (EE-ManagedThreadFactory-engine-Thread-15528)
>>> [aa503a62-d439-4131-9b46-e161779847f4] BaseAsyncTask::removeTaskFromDB:
>>> Removed task 'f543383a-f369-488a-b625-73706b871a51' from DataBase
>>> 2017-12-28 16:22:59,546-06 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
>>> (EE-ManagedThreadFactory-engine-Thread-15528)
>>> [aa503a62-d439-4131-9b46-e161779847f4] CommandAsyncTask::HandleEndActionResult
>>> [within thread]: Removing CommandMultiAsyncTasks object for entity
>>> 'd8db5f12-cf59-40d2-b1b9-d5e4479766a3'
>>> 2017-12-28 16:23:07,064-06 INFO  [org.ovirt.engine.core.bll.Con
>>> currentChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-45)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Command 'CreateAllOvaDisks' id:
>>> '42331a77-2e38-4653-ba5f-29e5dccaea69' child commands
>>> '[d8db5f12-cf59-40d2-b1b9-d5e4479766a3]' executions were completed,
>>> status 'SUCCEEDED'
>>> 2017-12-28 16:23:07,078-06 INFO  [org.ovirt.engine.core.bll.Ser
>>> ialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-45)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Command 'ExportOva' (id:
>>> '22e1df85-794b-4bb6-a6b6-a1679cbe2f78') waiting on child command id:
>>> '42331a77-2e38-4653-ba5f-29e5dccaea69' type:'CreateAllOvaDisks' to
>>> complete
>>> 2017-12-28 16:23:08,089-06 INFO  [org.ovirt.engine.core.bll.sto
>>> rage.disk.CreateAllOvaDisksCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-61)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Ending command
>>> 'org.ovirt.engine.core.bll.storage.disk.CreateAllOvaDisksCommand'
>>> successfully.
>>> 2017-12-28 16:23:08,106-06 INFO  [org.ovirt.engine.core.bll.sto
>>> rage.disk.image.CopyImageGroupCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-61)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Ending command
>>> 'org.ovirt.engine.core.bll.storage.disk.image.CopyImageGroupCommand'
>>> successfully.
>>> 2017-12-28 16:23:08,118-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.irsbroker.GetImageInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-61)
>>> [aa503a62-d439-4131-9b46-e161779847f4] START, GetImageInfoVDSCommand(
>>> GetImageInfoVDSCommandParameters:{storagePoolId='3bb4edf8-be27-42e4-b860-a1f1f293b81c',
>>> ignoreFailoverLimit='false', storageDomainId='4108dd94-7538-4fdb-a01e-2eb51b9083b9',
>>> imageGroupId='a2578b49-5edf-47b8-a98a-12a20b47688c',
>>> imageId='34885dca-e1c6-4e92-9f06-957a63c0ae56'}), log id: 2cec8f8
>>> 2017-12-28 16:23:08,122-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.vdsbroker.GetVolumeInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-61)
>>> [aa503a62-d439-4131-9b46-e161779847f4] START,
>>> GetVolumeInfoVDSCommand(HostName = reindeerHost,
>>> GetVolumeInfoVDSCommandParameters:{hostId='4f344875-08d8-49d8-b2ee-5ca3e1810051',
>>> storagePoolId='3bb4edf8-be27-42e4-b860-a1f1f293b81c',
>>> storageDomainId='4108dd94-7538-4fdb-a01e-2eb51b9083b9',
>>> imageGroupId='a2578b49-5edf-47b8-a98a-12a20b47688c',
>>> imageId='34885dca-e1c6-4e92-9f06-957a63c0ae56'}), log id: c3fea19
>>> 2017-12-28 16:23:08,135-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.vdsbroker.GetVolumeInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-61)
>>> [aa503a62-d439-4131-9b46-e161779847f4] FINISH, GetVolumeInfoVDSCommand,
>>> return: org.ovirt.engine.core.common.businessentities.storage.DiskIm
>>> age at 95bb5b65, log id: c3fea19
>>> 2017-12-28 16:23:08,135-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.irsbroker.GetImageInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-61)
>>> [aa503a62-d439-4131-9b46-e161779847f4] FINISH, GetImageInfoVDSCommand,
>>> return: org.ovirt.engine.core.common.businessentities.storage.DiskIm
>>> age at 95bb5b65, log id: 2cec8f8
>>> 2017-12-28 16:23:08,232-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.vdsbroker.PrepareImageVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-61)
>>> [aa503a62-d439-4131-9b46-e161779847f4] START,
>>> PrepareImageVDSCommand(HostName = reindeerHost,
>>> PrepareImageVDSCommandParameters:{hostId='4f344875-08d8-49d8-b2ee-5ca3e1810051'}),
>>> log id: 6824c68f
>>> 2017-12-28 16:23:08,246-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.vdsbroker.PrepareImageVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-61)
>>> [aa503a62-d439-4131-9b46-e161779847f4] FINISH, PrepareImageVDSCommand,
>>> return: PrepareImageReturn:{status='Status [code=0, message=Done]'},
>>> log id: 6824c68f
>>> 2017-12-28 16:23:08,251-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.vdsbroker.GetQemuImageInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-61)
>>> [aa503a62-d439-4131-9b46-e161779847f4] START,
>>> GetQemuImageInfoVDSCommand(HostName = reindeerHost,
>>> GetVolumeInfoVDSCommandParameters:{hostId='4f344875-08d8-49d8-b2ee-5ca3e1810051',
>>> storagePoolId='3bb4edf8-be27-42e4-b860-a1f1f293b81c',
>>> storageDomainId='4108dd94-7538-4fdb-a01e-2eb51b9083b9',
>>> imageGroupId='a2578b49-5edf-47b8-a98a-12a20b47688c',
>>> imageId='34885dca-e1c6-4e92-9f06-957a63c0ae56'}), log id: 1fb3c78e
>>> 2017-12-28 16:23:08,495-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.vdsbroker.GetQemuImageInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-61)
>>> [aa503a62-d439-4131-9b46-e161779847f4] FINISH,
>>> GetQemuImageInfoVDSCommand, return: org.ovirt.engine.core.common.b
>>> usinessentities.storage.QemuImageInfo at 3412697c, log id: 1fb3c78e
>>> 2017-12-28 16:23:08,499-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.vdsbroker.TeardownImageVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-61)
>>> [aa503a62-d439-4131-9b46-e161779847f4] START,
>>> TeardownImageVDSCommand(HostName = reindeerHost,
>>> ImageActionsVDSCommandParameters:{hostId='4f344875-08d8-49d8-b2ee-5ca3e1810051'}),
>>> log id: 6377d6a3
>>> 2017-12-28 16:23:08,508-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.vdsbroker.TeardownImageVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-61)
>>> [aa503a62-d439-4131-9b46-e161779847f4] FINISH, TeardownImageVDSCommand,
>>> return: StatusReturn:{status='Status [code=0, message=Done]'}, log id:
>>> 6377d6a3
>>> 2017-12-28 16:23:08,561-06 INFO  [org.ovirt.engine.core.bll.CreateOvaCommand]
>>> (EE-ManagedThreadFactory-engineScheduled-Thread-61) [436aa42a] Running
>>> command: CreateOvaCommand internal: true.
>>> 2017-12-28 16:23:08,564-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.vdsbroker.PrepareImageVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-61)
>>> [436aa42a] START, PrepareImageVDSCommand(HostName = reindeerHost,
>>> PrepareImageVDSCommandParameters:{hostId='4f344875-08d8-49d8-b2ee-5ca3e1810051'}),
>>> log id: 4490da3c
>>> 2017-12-28 16:23:08,575-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.vdsbroker.PrepareImageVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-61)
>>> [436aa42a] FINISH, PrepareImageVDSCommand, return:
>>> PrepareImageReturn:{status='Status [code=0, message=Done]'}, log id:
>>> 4490da3c
>>> 2017-12-28 16:23:08,580-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.vdsbroker.GetVolumeInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-61)
>>> [436aa42a] START, GetVolumeInfoVDSCommand(HostName = reindeerHost,
>>> GetVolumeInfoVDSCommandParameters:{hostId='4f344875-08d8-49d8-b2ee-5ca3e1810051',
>>> storagePoolId='3bb4edf8-be27-42e4-b860-a1f1f293b81c',
>>> storageDomainId='4108dd94-7538-4fdb-a01e-2eb51b9083b9',
>>> imageGroupId='a2578b49-5edf-47b8-a98a-12a20b47688c',
>>> imageId='34885dca-e1c6-4e92-9f06-957a63c0ae56'}), log id: 60f52262
>>> 2017-12-28 16:23:08,591-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.vdsbroker.GetVolumeInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-61)
>>> [436aa42a] FINISH, GetVolumeInfoVDSCommand, return:
>>> org.ovirt.engine.core.common.businessentities.storage.DiskImage at 95bb5b65,
>>> log id: 60f52262
>>> 2017-12-28 16:23:08,596-06 ERROR [org.ovirt.engine.core.bll.CreateOvaCommand]
>>> (EE-ManagedThreadFactory-engineScheduled-Thread-61) [436aa42a] Command
>>> 'org.ovirt.engine.core.bll.CreateOvaCommand' failed: null
>>> 2017-12-28 16:23:08,596-06 ERROR [org.ovirt.engine.core.bll.CreateOvaCommand]
>>> (EE-ManagedThreadFactory-engineScheduled-Thread-61) [436aa42a]
>>> Exception: java.lang.NullPointerException
>>>
>>> 2017-12-28 16:23:08,600-06 ERROR [org.ovirt.engine.core.bll.exportimport.ExportOvaCommand]
>>> (EE-ManagedThreadFactory-engineScheduled-Thread-61) [436aa42a] Failed
>>> to create OVA file
>>> 2017-12-28 16:23:08,600-06 INFO  [org.ovirt.engine.core.bll.Ser
>>> ialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-61)
>>> [436aa42a] Command 'ExportOva' id: '22e1df85-794b-4bb6-a6b6-a1679cbe2f78'
>>> failed when attempting to perform the next operation, marking as FAILED
>>> '[42331a77-2e38-4653-ba5f-29e5dccaea69]'
>>> 2017-12-28 16:23:08,600-06 INFO  [org.ovirt.engine.core.bll.Ser
>>> ialChildCommandsExecutionCallback] (EE-ManagedThreadFactory-engineScheduled-Thread-61)
>>> [436aa42a] Command 'ExportOva' id: '22e1df85-794b-4bb6-a6b6-a1679cbe2f78'
>>> child commands '[42331a77-2e38-4653-ba5f-29e5dccaea69]' executions were
>>> completed, status 'FAILED'
>>> 2017-12-28 16:23:09,628-06 ERROR [org.ovirt.engine.core.bll.exportimport.ExportOvaCommand]
>>> (EE-ManagedThreadFactory-engineScheduled-Thread-15)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Ending command
>>> 'org.ovirt.engine.core.bll.exportimport.ExportOvaCommand' with failure.
>>> 2017-12-28 16:23:09,637-06 INFO  [org.ovirt.engine.core.bll.exportimport.ExportOvaCommand]
>>> (EE-ManagedThreadFactory-engineScheduled-Thread-15)
>>> [aa503a62-d439-4131-9b46-e161779847f4] Lock freed to object
>>> 'EngineLock:{exclusiveLocks='[43c82580-6c7a-4319-97f6-6cfb17a4445a=VM]',
>>> sharedLocks=''}'
>>> 2017-12-28 16:23:09,667-06 ERROR [org.ovirt.engine.core.dal.dbb
>>> roker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engineScheduled-Thread-15)
>>> [aa503a62-d439-4131-9b46-e161779847f4] EVENT_ID:
>>> IMPORTEXPORT_EXPORT_VM_TO_OVA_FAILED(1,225), Failed to export Vm
>>> CentOS7 as a Virtual Appliance to path /media/primary/extra_space/CentOS7.ova
>>> on Host reindeerHost
>>> 2017-12-28 16:23:09,716-06 INFO  [org.ovirt.engine.core.bll.storage.disk.RemoveDiskCommand]
>>> (EE-ManagedThreadFactory-engine-Thread-15529) [31f8fbf6] Running
>>> command: RemoveDiskCommand internal: true. Entities affected :  ID:
>>> a2578b49-5edf-47b8-a98a-12a20b47688c Type: DiskAction group DELETE_DISK
>>> with role type USER
>>> 2017-12-28 16:23:09,734-06 INFO  [org.ovirt.engine.core.bll.sto
>>> rage.disk.image.RemoveImageCommand] (EE-ManagedThreadFactory-engine-Thread-15529)
>>> [31f8fbf6] Running command: RemoveImageCommand internal: true. Entities
>>> affected :  ID: 4108dd94-7538-4fdb-a01e-2eb51b9083b9 Type: Storage
>>> 2017-12-28 16:23:09,796-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.irsbroker.DeleteImageGroupVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15529)
>>> [31f8fbf6] START, DeleteImageGroupVDSCommand( DeleteImageGroupVDSCommandPara
>>> meters:{storagePoolId='3bb4edf8-be27-42e4-b860-a1f1f293b81c',
>>> ignoreFailoverLimit='false', storageDomainId='4108dd94-7538-4fdb-a01e-2eb51b9083b9',
>>> imageGroupId='a2578b49-5edf-47b8-a98a-12a20b47688c', postZeros='false',
>>> discard='false', forceDelete='true'}), log id: 389abdf
>>> 2017-12-28 16:23:09,845-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.irsbroker.DeleteImageGroupVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15529)
>>> [31f8fbf6] FINISH, DeleteImageGroupVDSCommand, log id: 389abdf
>>> 2017-12-28 16:23:09,860-06 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
>>> (EE-ManagedThreadFactory-engine-Thread-15529) [31f8fbf6]
>>> CommandAsyncTask::Adding CommandMultiAsyncTasks object for command
>>> 'f9ca5d6f-effe-41a2-835f-83f04da2de73'
>>> 2017-12-28 16:23:09,860-06 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks]
>>> (EE-ManagedThreadFactory-engine-Thread-15529) [31f8fbf6]
>>> CommandMultiAsyncTasks::attachTask: Attaching task
>>> 'f2c7f5ec-b166-4cf2-97b6-b2cc02f715e1' to command
>>> 'f9ca5d6f-effe-41a2-835f-83f04da2de73'.
>>> 2017-12-28 16:23:09,888-06 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
>>> (EE-ManagedThreadFactory-engine-Thread-15529) [31f8fbf6] Adding task
>>> 'f2c7f5ec-b166-4cf2-97b6-b2cc02f715e1' (Parent Command 'RemoveDisk',
>>> Parameters Type 'org.ovirt.engine.core.common.
>>> asynctasks.AsyncTaskParameters'), polling hasn't started yet..
>>> 2017-12-28 16:23:10,056-06 INFO  [org.ovirt.engine.core.dal.dbb
>>> roker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-15529)
>>> [31f8fbf6] EVENT_ID: USER_FINISHED_REMOVE_DISK(2,014), Disk
>>> Debian7_Disk1 was successfully removed from domain data (User
>>> admin at internal-authz).
>>> 2017-12-28 16:23:10,057-06 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
>>> (EE-ManagedThreadFactory-engine-Thread-15529) [31f8fbf6]
>>> BaseAsyncTask::startPollingTask: Starting to poll task
>>> 'f2c7f5ec-b166-4cf2-97b6-b2cc02f715e1'.
>>> 2017-12-28 16:23:10,057-06 WARN  [org.ovirt.engine.core.bll.lock.InMemoryLockManager]
>>> (EE-ManagedThreadFactory-engine-Thread-15529) [31f8fbf6] Trying to
>>> release exclusive lock which does not exist, lock key:
>>> '43c82580-6c7a-4319-97f6-6cfb17a4445aVM'
>>> 2017-12-28 16:23:10,057-06 INFO  [org.ovirt.engine.core.bll.storage.disk.RemoveDiskCommand]
>>> (EE-ManagedThreadFactory-engine-Thread-15529) [31f8fbf6] Lock freed to
>>> object 'EngineLock:{exclusiveLocks='[43c82580-6c7a-4319-97f6-6cfb17a4445a=VM]',
>>> sharedLocks=''}'
>>> 2017-12-28 16:23:19,505-06 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
>>> (EE-ManagedThreadFactory-engineScheduled-Thread-31) [] Polling and
>>> updating Async Tasks: 2 tasks, 1 tasks to poll now
>>> 2017-12-28 16:23:19,519-06 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
>>> (EE-ManagedThreadFactory-engineScheduled-Thread-31) []
>>> SPMAsyncTask::PollTask: Polling task 'f2c7f5ec-b166-4cf2-97b6-b2cc02f715e1'
>>> (Parent Command 'RemoveDisk', Parameters Type 'org.ovirt.engine.core.common.
>>> asynctasks.AsyncTaskParameters') returned status 'finished', result
>>> 'success'.
>>> 2017-12-28 16:23:19,530-06 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
>>> (EE-ManagedThreadFactory-engineScheduled-Thread-31) []
>>> BaseAsyncTask::onTaskEndSuccess: Task 'f2c7f5ec-b166-4cf2-97b6-b2cc02f715e1'
>>> (Parent Command 'RemoveDisk', Parameters Type 'org.ovirt.engine.core.common.
>>> asynctasks.AsyncTaskParameters') ended successfully.
>>> 2017-12-28 16:23:19,563-06 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
>>> (EE-ManagedThreadFactory-engineScheduled-Thread-31) []
>>> CommandAsyncTask::endActionIfNecessary: All tasks of command
>>> 'f9ca5d6f-effe-41a2-835f-83f04da2de73' has ended -> executing
>>> 'endAction'
>>> 2017-12-28 16:23:19,563-06 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
>>> (EE-ManagedThreadFactory-engineScheduled-Thread-31) []
>>> CommandAsyncTask::endAction: Ending action for '1' tasks (command ID:
>>> 'f9ca5d6f-effe-41a2-835f-83f04da2de73'): calling endAction '.
>>> 2017-12-28 16:23:19,564-06 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
>>> (EE-ManagedThreadFactory-engine-Thread-15532) []
>>> CommandAsyncTask::endCommandAction [within thread] context: Attempting
>>> to endAction 'RemoveDisk',
>>> 2017-12-28 16:23:19,579-06 INFO  [org.ovirt.engine.core.bll.storage.disk.RemoveDiskCommand]
>>> (EE-ManagedThreadFactory-engine-Thread-15532) [31f8fbf6] Ending command
>>> 'org.ovirt.engine.core.bll.storage.disk.RemoveDiskCommand' successfully.
>>> 2017-12-28 16:23:19,585-06 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
>>> (EE-ManagedThreadFactory-engine-Thread-15532) [31f8fbf6]
>>> CommandAsyncTask::HandleEndActionResult [within thread]: endAction for
>>> action type 'RemoveDisk' completed, handling the result.
>>> 2017-12-28 16:23:19,585-06 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
>>> (EE-ManagedThreadFactory-engine-Thread-15532) [31f8fbf6]
>>> CommandAsyncTask::HandleEndActionResult [within thread]: endAction for
>>> action type 'RemoveDisk' succeeded, clearing tasks.
>>> 2017-12-28 16:23:19,585-06 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
>>> (EE-ManagedThreadFactory-engine-Thread-15532) [31f8fbf6]
>>> SPMAsyncTask::ClearAsyncTask: Attempting to clear task
>>> 'f2c7f5ec-b166-4cf2-97b6-b2cc02f715e1'
>>> 2017-12-28 16:23:19,588-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.irsbroker.SPMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15532)
>>> [31f8fbf6] START, SPMClearTaskVDSCommand( SPMTaskGuidBaseVDSCommandParam
>>> eters:{storagePoolId='3bb4edf8-be27-42e4-b860-a1f1f293b81c',
>>> ignoreFailoverLimit='false', taskId='f2c7f5ec-b166-4cf2-97b6-b2cc02f715e1'}),
>>> log id: 6c25b62
>>> 2017-12-28 16:23:19,591-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.vdsbroker.HSMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15532)
>>> [31f8fbf6] START, HSMClearTaskVDSCommand(HostName = reindeerHost,
>>> HSMTaskGuidBaseVDSCommandParameters:{hostId='4f344875-08d8-49d8-b2ee-5ca3e1810051',
>>> taskId='f2c7f5ec-b166-4cf2-97b6-b2cc02f715e1'}), log id: 4d4fcced
>>> 2017-12-28 16:23:19,602-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.vdsbroker.HSMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15532)
>>> [31f8fbf6] FINISH, HSMClearTaskVDSCommand, log id: 4d4fcced
>>> 2017-12-28 16:23:19,602-06 INFO  [org.ovirt.engine.core.vdsbrok
>>> er.irsbroker.SPMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15532)
>>> [31f8fbf6] FINISH, SPMClearTaskVDSCommand, log id: 6c25b62
>>> 2017-12-28 16:23:19,614-06 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
>>> (EE-ManagedThreadFactory-engine-Thread-15532) [31f8fbf6]
>>> BaseAsyncTask::removeTaskFromDB: Removed task
>>> 'f2c7f5ec-b166-4cf2-97b6-b2cc02f715e1' from DataBase
>>> 2017-12-28 16:23:19,614-06 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
>>> (EE-ManagedThreadFactory-engine-Thread-15532) [31f8fbf6]
>>> CommandAsyncTask::HandleEndActionResult [within thread]: Removing
>>> CommandMultiAsyncTasks object for entity 'f9ca5d6f-effe-41a2-835f-83f04
>>> da2de73'
>>> 2017-12-28 16:24:18,808-06 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
>>> (EE-ManagedThreadFactory-engineScheduled-Thread-55) [] Setting new
>>> tasks map. The map contains now 1 tasks
>>>
>>>
>>>
>>> Sent with ProtonMail <https://protonmail.com> Secure Email.
>>>
>>> -------- Original Message --------
>>> Subject: Re: [ovirt-users] Exporting/Copying VM to another oVirt server
>>> Local Time: December 28, 2017 4:20 PM
>>> UTC Time: December 28, 2017 10:20 PM
>>> From: ahadas at redhat.com
>>> To: Alex Bartonek <Alex at unix1337.com>
>>> users <users at ovirt.org>
>>>
>>> :/
>>> Can you please attach the new log?
>>>
>>> On Fri, Dec 29, 2017 at 12:18 AM, Alex Bartonek <Alex at unix1337.com>
>>> wrote:
>>>
>>>> Yes it was empty.  Tried again, same failure.
>>>>
>>>>
>>>> Sent with ProtonMail <https://protonmail.com> Secure Email.
>>>>
>>>> -------- Original Message --------
>>>> Subject: Re: [ovirt-users] Exporting/Copying VM to another oVirt server
>>>> Local Time: December 28, 2017 4:11 PM
>>>> UTC Time: December 28, 2017 10:11 PM
>>>> From: ahadas at redhat.com
>>>> To: Alex Bartonek <Alex at unix1337.com>
>>>> users <users at ovirt.org>
>>>>
>>>> Thanks.
>>>> Is the description of the disk of the VM empty?
>>>> If it is, please type some description and then try again.
>>>>
>>>> On Fri, Dec 29, 2017 at 12:02 AM, Alex Bartonek <Alex at unix1337.com>
>>>> wrote:
>>>>
>>>>> Attached.  Its 3.8 mb decompressed.
>>>>>
>>>>>
>>>>> Sent with ProtonMail <https://protonmail.com> Secure Email.
>>>>>
>>>>> -------- Original Message --------
>>>>> Subject: Re: [ovirt-users] Exporting/Copying VM to another oVirt server
>>>>> Local Time: December 28, 2017 3:56 PM
>>>>> UTC Time: December 28, 2017 9:56 PM
>>>>> From: ahadas at redhat.com
>>>>> To: Alex Bartonek <Alex at unix1337.com>
>>>>> users <users at ovirt.org>
>>>>>
>>>>>
>>>>>
>>>>> On Thu, Dec 28, 2017 at 11:28 PM, Alex Bartonek <Alex at unix1337.com>
>>>>> wrote:
>>>>>
>>>>>> That is the excerpt from the  engine log file.   There isnt any  more
>>>>>> java stack dump in it other than what I captured.
>>>>>>
>>>>>
>>>>> Can you please attach the entire engine.log to this thread or to a new
>>>>> bug (https://bugzilla.redhat.com)?
>>>>>
>>>>>
>>>>>>
>>>>>>
>>>>>>
>>>>>> Sent with ProtonMail <https://protonmail.com> Secure Email.
>>>>>>
>>>>>> -------- Original Message --------
>>>>>> Subject: Re: [ovirt-users] Exporting/Copying VM to another oVirt
>>>>>> server
>>>>>> Local Time: December 28, 2017 3:18 PM
>>>>>> UTC Time: December 28, 2017 9:18 PM
>>>>>> From: ahadas at redhat.com
>>>>>> To: Alex Bartonek <Alex at unix1337.com>
>>>>>> users <users at ovirt.org>
>>>>>>
>>>>>>
>>>>>>
>>>>>> On Thu, Dec 28, 2017 at 10:46 PM, Alex Bartonek <Alex at unix1337.com>
>>>>>> wrote:
>>>>>>
>>>>>>> Log uploaded to:
>>>>>>>
>>>>>>> http://textuploader.com/dc2be
>>>>>>>
>>>>>>>
>>>>>> Thanks!
>>>>>> Exporting and importing as OVA files is the new shiny way of doing
>>>>>> that as from 4.2 but unfortunately, there seems to be an error while
>>>>>> generating OVF for the VM you're trying to export.
>>>>>> I see NullPointerException in the log and an empty line afterwards,
>>>>>> can you please check if there is a stacktrace right below in the engine.log
>>>>>> that was not copied? It should be below:
>>>>>> 2017-12-28 14:45:03,728-06 ERROR [org.ovirt.engine.core.bll.CreateOvaCommand]
>>>>>> (EE-ManagedThreadFactory-engineScheduled-Thread-22) [38896e98]
>>>>>> Exception: java.lang.NullPointerException
>>>>>>
>>>>>>
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>> -------- Original Message --------
>>>>>>> Subject: Re: [ovirt-users] Exporting/Copying VM to another oVirt
>>>>>>> server
>>>>>>> Local Time: December 28, 2017 2:41 PM
>>>>>>> UTC Time: December 28, 2017 8:41 PM
>>>>>>> From: ahadas at redhat.com
>>>>>>> To: Alex Bartonek <Alex at unix1337.com>
>>>>>>> users <users at ovirt.org>
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>> בתאריך 28 בדצמ׳ 2017 10:38 PM,‏ "Alex Bartonek" <Alex at unix1337.com>
>>>>>>> כתב:
>>>>>>>
>>>>>>> After the success of getting my VM's working on my test server, I
>>>>>>> decided to wipe out another server I had and wanted to move my VMs to it.
>>>>>>> I tried the "Export" as ova and it seems to work but in the Tasks its red:
>>>>>>>
>>>>>>> "Exporting VM CentOS7 as an OVA to /ovirt_data/CentOS7.ova on Host
>>>>>>> junkHost".
>>>>>>>
>>>>>>> The .ova is never created.   Is there another way to do this?  Both
>>>>>>> servers are running 4.2.
>>>>>>>
>>>>>>> Googling hasnt helped me find a solution yet...
>>>>>>>
>>>>>>>
>>>>>>> Can you please share engine.log?
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>> _______________________________________________
>>>>>>> Users mailing list
>>>>>>> Users at ovirt.org
>>>>>>> http://lists.ovirt.org/mailman/listinfo/users
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>
>>>>>
>>>>
>>>
>>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.ovirt.org/pipermail/users/attachments/20171229/7b486fe1/attachment.html>

More information about the Users mailing list