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

Alex Bartonek Alex at unix1337.com
Thu Dec 28 22:45:48 UTC 2017 

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/nullpointerexception-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-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 at 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 at 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 at 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 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.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 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.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](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/20171228/83fdf443/attachment.html>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: engine_newlog.zip
Type: application/zip
Size: 14380 bytes
Desc: not available
URL: <http://lists.ovirt.org/pipermail/users/attachments/20171228/83fdf443/attachment.zip>

More information about the Users mailing list