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

Friday, 29 December 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(a)redhat.com
 To: Alex Bartonek <Alex(a)unix1337.com&gt;
 users <users(a)ovirt.org&gt;

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

 On Fri, Dec 29, 2017 at 12:25 AM, Alex Bartonek <Alex(a)unix1337.com&gt; 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](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(a)redhat.com
>> To: Alex Bartonek <Alex(a)unix1337.com&gt;
>> users <users(a)ovirt.org&gt;
>>
>> :/
>> Can you please attach the new log?
>>
>> On Fri, Dec 29, 2017 at 12:18 AM, Alex Bartonek <Alex(a)unix1337.com&gt; 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(a)redhat.com
>>>> To: Alex Bartonek <Alex(a)unix1337.com&gt;
>>>> users <users(a)ovirt.org&gt;
>>>>
>>>> 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(a)unix1337.com&gt;
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(a)redhat.com
>>>>>> To: Alex Bartonek <Alex(a)unix1337.com&gt;
>>>>>> users <users(a)ovirt.org&gt;
>>>>>>
>>>>>> On Thu, Dec 28, 2017 at 11:28 PM, Alex Bartonek
<Alex(a)unix1337.com&gt; 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(a)redhat.com
>>>>>>>> To: Alex Bartonek <Alex(a)unix1337.com&gt;
>>>>>>>> users <users(a)ovirt.org&gt;
>>>>>>>>
>>>>>>>> On Thu, Dec 28, 2017 at 10:46 PM, Alex Bartonek
<Alex(a)unix1337.com&gt; 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(a)redhat.com
>>>>>>>>>> To: Alex Bartonek <Alex(a)unix1337.com&gt;
>>>>>>>>>> users <users(a)ovirt.org&gt;
>>>>>>>>>>
>>>>>>>>>> בתאריך 28 בדצמ׳ 2017 10:38 PM,‏ "Alex
Bartonek" <Alex(a)unix1337.com&gt; כתב:
>>>>>>>>>>
>>>>>>>>>>> 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(a)ovirt.org
>>>>>>>>>>> http://lists.ovirt.org/mailman/listinfo/users

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

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