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@redhat.com
To: Alex Bartonek <Alex@unix1337.com>
users <users@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/nullpointerexception-in-java-with-no-stacktrace

On Fri, Dec 29, 2017 at 12:25 AM, Alex Bartonek <Alex@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-31289b2d1d57'
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.utils.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.utils.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.utils.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.utils.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.utils.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.storage.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.storage.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.vdsbroker.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.vdsbroker.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.vdsbroker.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.vdsbroker.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.vdsbroker.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.vdsbroker.irsbroker.CopyImageVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15515) [aa503a62-d439-4131-9b46-e161779847f4] ++ srcImageGUID=5499e522-db23-4a71-b5a2-e30933d7d547
2017-12-28 16:21:32,760-06 INFO  [org.ovirt.engine.core.vdsbroker.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.vdsbroker.irsbroker.CopyImageVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15515) [aa503a62-d439-4131-9b46-e161779847f4] ++ dstImageGUID=a2578b49-5edf-47b8-a98a-12a20b47688c
2017-12-28 16:21:32,760-06 INFO  [org.ovirt.engine.core.vdsbroker.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.vdsbroker.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.vdsbroker.irsbroker.CopyImageVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15515) [aa503a62-d439-4131-9b46-e161779847f4] ++ dstSdUUID=4108dd94-7538-4fdb-a01e-2eb51b9083b9
2017-12-28 16:21:32,858-06 INFO  [org.ovirt.engine.core.vdsbroker.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-d5e4479766a3'
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.dbbroker.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.ConcurrentChildCommandsExecutionCallback] (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.SerialChildCommandsExecutionCallback] (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.ConcurrentChildCommandsExecutionCallback] (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.SerialChildCommandsExecutionCallback] (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.ConcurrentChildCommandsExecutionCallback] (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.SerialChildCommandsExecutionCallback] (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.ConcurrentChildCommandsExecutionCallback] (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.SerialChildCommandsExecutionCallback] (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.ConcurrentChildCommandsExecutionCallback] (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.SerialChildCommandsExecutionCallback] (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.ConcurrentChildCommandsExecutionCallback] (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.SerialChildCommandsExecutionCallback] (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.ConcurrentChildCommandsExecutionCallback] (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.SerialChildCommandsExecutionCallback] (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.ConcurrentChildCommandsExecutionCallback] (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.SerialChildCommandsExecutionCallback] (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.ConcurrentChildCommandsExecutionCallback] (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.SerialChildCommandsExecutionCallback] (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.ConcurrentChildCommandsExecutionCallback] (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.SerialChildCommandsExecutionCallback] (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.storage.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.vdsbroker.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.vdsbroker.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.vdsbroker.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.vdsbroker.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.ConcurrentChildCommandsExecutionCallback] (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.SerialChildCommandsExecutionCallback] (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.storage.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.storage.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.vdsbroker.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.vdsbroker.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.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-61) [aa503a62-d439-4131-9b46-e161779847f4] FINISH, GetVolumeInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage.DiskImage@95bb5b65, log id: c3fea19
2017-12-28 16:23:08,135-06 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-61) [aa503a62-d439-4131-9b46-e161779847f4] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage.DiskImage@95bb5b65, log id: 2cec8f8
2017-12-28 16:23:08,232-06 INFO  [org.ovirt.engine.core.vdsbroker.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.vdsbroker.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.vdsbroker.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.vdsbroker.vdsbroker.GetQemuImageInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-61) [aa503a62-d439-4131-9b46-e161779847f4] FINISH, GetQemuImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage.QemuImageInfo@3412697c, log id: 1fb3c78e
2017-12-28 16:23:08,499-06 INFO  [org.ovirt.engine.core.vdsbroker.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.vdsbroker.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.vdsbroker.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.vdsbroker.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.vdsbroker.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.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-61) [436aa42a] FINISH, GetVolumeInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage.DiskImage@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.SerialChildCommandsExecutionCallback] (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.SerialChildCommandsExecutionCallback] (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.dbbroker.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.storage.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.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15529) [31f8fbf6] START, DeleteImageGroupVDSCommand( DeleteImageGroupVDSCommandParameters:{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.vdsbroker.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.dbbroker.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@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.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-15532) [31f8fbf6] START, SPMClearTaskVDSCommand( SPMTaskGuidBaseVDSCommandParameters:{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.vdsbroker.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.vdsbroker.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.vdsbroker.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-83f04da2de73'
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 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
To: Alex Bartonek <Alex@unix1337.com>

:/
Can you please attach the new log?

On Fri, Dec 29, 2017 at 12:18 AM, Alex Bartonek <Alex@unix1337.com> wrote:
Yes it was empty.  Tried again, same failure.


Sent with ProtonMail 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
To: Alex Bartonek <Alex@unix1337.com>

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@unix1337.com> wrote:
Attached.  Its 3.8 mb decompressed.


Sent with ProtonMail 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
To: Alex Bartonek <Alex@unix1337.com>



On Thu, Dec 28, 2017 at 11:28 PM, Alex Bartonek <Alex@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 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
To: Alex Bartonek <Alex@unix1337.com>



On Thu, Dec 28, 2017 at 10:46 PM, Alex Bartonek <Alex@unix1337.com> wrote:
Log uploaded to:



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
To: Alex Bartonek <Alex@unix1337.com>



בתאריך 28 בדצמ׳ 2017 10:38 PM,‏ "Alex Bartonek" <Alex@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