7 a.m.
Anyone getting issues with OVA Imports on 4.4?
I exported this from ovirt back in March although now importing again it is failing.
My engine logs show it erroring out here:
Command, return: StatusReturn:{status='Status [code=0, message=Done]'}, log id:
7f970c1f
2022-05-12 17:14:40,396-06 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68) [] EVENT_ID:
USER_ADD_DISK_TO_VM_FINISHED_SUCCESS(97), The d isk
Nextgen-CC-Tools_Disk1 was successfully added to VM Nextgen-CC-Tools.
2022-05-12 17:14:40,678-06 INFO
[org.ovirt.engine.core.bll.exportimport.ExtractOvaCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[192f636b-0dd4-4a61-a673-1b946d7e0b56] Running command: ExtractOvaComman
d internal: true. Entities affected : ID:
66ff7ebd-7682-4b94-b98d-5ceb76661830 Type: VM
2022-05-12 17:14:40,691-06 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[192f636b-0dd4-4a61-a673-1b946d7e0b56] START, PrepareImageVDSCom
mand(HostName = ovirt-2.removed.com,
PrepareImageVDSCommandParameters:{hostId='0fbd6429-9c91-44d7-8d50-dcc08c8301f9'}),
log id: 6915fcb6
2022-05-12 17:14:40,731-06 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[192f636b-0dd4-4a61-a673-1b946d7e0b56] FINISH, PrepareImageVDSCo
mmand, return: PrepareImageReturn:{status='Status [code=0, message=Done]'},
log id: 6915fcb6
2022-05-12 17:14:46,893-06 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[192f636b-0dd4-4a61-a673-1b946d7e0b56] EVENT_ID: ANSIBLE_RUN
NER_EVENT_NOTIFICATION(559), Import OVA. Run import yaml on py3.
2022-05-12 17:14:46,918-06 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[192f636b-0dd4-4a61-a673-1b946d7e0b56] EVENT_ID: ANSIBLE_RUN
NER_EVENT_NOTIFICATION(559), Import OVA. Set facts.
2022-05-12 17:14:49,936-06 ERROR
[org.ovirt.engine.core.bll.exportimport.ExtractOvaCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[192f636b-0dd4-4a61-a673-1b946d7e0b56] Command 'org.ovirt.engine.core.bl
l.exportimport.ExtractOvaCommand' failed: Task Run extraction
script failed to execute. Please check logs for more details:
/var/log/ovirt-engine/ova/ovirt-import-ova-ansible-20220512171440-ovirt-2.removed.com-192f636b-0dd4-4a61-a673-1b94
6d7e0b56.log
2022-05-12 17:14:49,936-06 ERROR
[org.ovirt.engine.core.bll.exportimport.ExtractOvaCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[192f636b-0dd4-4a61-a673-1b946d7e0b56] Exception: org.ovirt.engine.core.
common.utils.ansible.AnsibleRunnerCallException: Task Run extraction script
failed to execute. Please check logs for more details:
/var/log/ovirt-engine/ova/ovirt-import-ova-ansible-20220512171440-ovirt-2.removed.com-192f636b-0dd4-4a61-a6
73-1b946d7e0b56.log
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.common.utils.ansible.AnsibleRunnerHttpClient.processEvents(AnsibleRunnerHttpClient.java:251)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.common.utils.ansible.AnsibleExecutor.runCommand(AnsibleExecutor.java:227)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.common.utils.ansible.AnsibleExecutor.runCommand(AnsibleExecutor.java:74)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.common.utils.ansible.AnsibleExecutor.runCommand(AnsibleExecutor.java:51)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.exportimport.ExtractOvaCommand.runAnsibleImportOvaPlaybook(ExtractOvaCommand.java:129)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.exportimport.ExtractOvaCommand.executeVmCommand(ExtractOvaCommand.java:79)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.VmCommand.executeCommand(VmCommand.java:178)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1174)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1332)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:2010)
at
org.ovirt.engine.core.utils//org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInSuppressed(TransactionSupport.java:140)
at
org.ovirt.engine.core.utils//org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:79)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.execute(CommandBase.java:1392)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:424)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.executor.DefaultBackendActionExecutor.execute(DefaultBackendActionExecutor.java:13)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.Backend.runAction(Backend.java:450)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.Backend.runActionImpl(Backend.java:432)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.Backend.runInternalAction(Backend.java:638)
at jdk.internal.reflect.GeneratedMethodAccessor202.invoke(Unknown Source)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at
org.jboss.as.ee@23.0.2.Final//org.jboss.as.ee.component.ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptor.java:52)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:509)
at
org.jboss.as.weld.common@23.0.2.Final//org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.delegateInterception(Jsr299BindingsInterceptor.java:79)
at
org.jboss.as.weld.common@23.0.2.Final//org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.doMethodInterception(Jsr299BindingsInterceptor.java:89)
at
org.jboss.as.weld.common@23.0.2.Final//org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.processInvocation(Jsr299BindingsInterceptor.java:102)
at
org.jboss.as.ee@23.0.2.Final//org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.as.ejb3@23.0.2.Final//org.jboss.as.ejb3.component.invocationmetrics.ExecutionTimeInterceptor.processInvocation(ExecutionTimeInterceptor.java:43)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.as.ee@23.0.2.Final//org.jboss.as.ee.concurrent.ConcurrentContextInterceptor.processInvocation(ConcurrentContextInterceptor.java:45)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:40)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:53)
at
org.jboss.as.ee@23.0.2.Final//org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:52)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.as.ejb3@23.0.2.Final//org.jboss.as.ejb3.component.singleton.SingletonComponentInstanceAssociationInterceptor.processInvocation(SingletonComponentInstanceAssociationInterceptor.java:53)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.as.ejb3@23.0.2.Final//org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:232)
at
org.jboss.as.ejb3@23.0.2.Final//org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:446)
at
org.jboss.as.ejb3@23.0.2.Final//org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:164)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:509)
at
org.jboss.weld.core@3.1.6.Final//org.jboss.weld.module.ejb.AbstractEJBRequestScopeActivationInterceptor.aroundInvoke(AbstractEJBRequestScopeActivationInterceptor.java:81)
at
org.jboss.as.weld.common@23.0.2.Final//org.jboss.as.weld.ejb.EjbRequestScopeActivationInterceptor.processInvocation(EjbRequestScopeActivationInterceptor.java:89)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.as.ejb3@23.0.2.Final//org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.as.ejb3@23.0.2.Final//org.jboss.as.ejb3.component.invocationmetrics.WaitTimeInterceptor.processInvocation(WaitTimeInterceptor.java:47)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.as.ejb3@23.0.2.Final//org.jboss.as.ejb3.security.SecurityContextInterceptor.processInvocation(SecurityContextInterceptor.java:100)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.as.ejb3@23.0.2.Final//org.jboss.as.ejb3.deployment.processors.StartupAwaitInterceptor.processInvocation(StartupAwaitInterceptor.java:22)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.as.ejb3@23.0.2.Final//org.jboss.as.ejb3.component.interceptors.ShutDownInterceptorFactory$1.processInvocation(ShutDownInterceptorFactory.java:64)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.as.ejb3@23.0.2.Final//org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:67)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.as.ee@23.0.2.Final//org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.ContextClassLoaderInterceptor.processInvocation(ContextClassLoaderInterceptor.java:60)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.run(InterceptorContext.java:438)
at
org.wildfly.security.elytron-private@1.15.3.Final//org.wildfly.security.manager.WildFlySecurityManager.doChecked(WildFlySecurityManager.java:633)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.AccessCheckingInterceptor.processInvocation(AccessCheckingInterceptor.java:57)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at
org.jboss.invocation@1.6.0.Final//org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:53)
at
org.jboss.as.ee@23.0.2.Final//org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:198)
at
org.jboss.as.ee@23.0.2.Final//org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:191)
at
org.jboss.as.ee@23.0.2.Final//org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:81)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.interfaces.BackendInternal$$$view4.runInternalAction(Unknown
Source)
at jdk.internal.reflect.GeneratedMethodAccessor201.invoke(Unknown Source)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at
org.jboss.weld.core@3.1.6.Final//org.jboss.weld.util.reflection.Reflections.invokeAndUnwrap(Reflections.java:410)
at
org.jboss.weld.core@3.1.6.Final//org.jboss.weld.module.ejb.EnterpriseBeanProxyMethodHandler.invoke(EnterpriseBeanProxyMethodHandler.java:134)
at
org.jboss.weld.core@3.1.6.Final//org.jboss.weld.bean.proxy.EnterpriseTargetBeanInstance.invoke(EnterpriseTargetBeanInstance.java:56)
at
org.jboss.weld.core@3.1.6.Final//org.jboss.weld.module.ejb.InjectionPointPropagatingEnterpriseTargetBeanInstance.invoke(InjectionPointPropagatingEnterpriseTargetBeanInstance.java:68)
at
org.jboss.weld.core@3.1.6.Final//org.jboss.weld.bean.proxy.ProxyMethodHandler.invoke(ProxyMethodHandler.java:106)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.BackendCommandObjectsHandler$BackendInternal$BackendLocal2049259618$Proxy$_$$_Weld$EnterpriseProxy$.runInternalAction(Unknown
Source)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.runInternalAction(CommandBase.java:2388)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand.convert(ImportVmFromOvaCommand.java:59)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.exportimport.ImportVmFromExternalProviderCommand.executeNextOperation(ImportVmFromExternalProviderCommand.java:510)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.exportimport.ImportVmFromExternalProviderCommand.performNextOperation(ImportVmFromExternalProviderCommand.java:502)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback.childCommandsExecutionEnded(SerialChildCommandsExecutionCallback.java:32)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.ChildCommandsCallbackBase.doPolling(ChildCommandsCallbackBase.java:80)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller.invokeCallbackMethodsImpl(CommandCallbacksPoller.java:175)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller.invokeCallbackMethods(CommandCallbacksPoller.java:109)
at
java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
at java.base/java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305)
at
org.glassfish.javax.enterprise.concurrent//org.glassfish.enterprise.concurrent.internal.ManagedScheduledThreadPoolExecutor$ManagedScheduledFutureTask.access$201(ManagedScheduledThreadPoolExecutor.java:360)
at
org.glassfish.javax.enterprise.concurrent//org.glassfish.enterprise.concurrent.internal.ManagedScheduledThreadPoolExecutor$ManagedScheduledFutureTask.run(ManagedScheduledThreadPoolExecutor.java:511)
at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:829)
at
org.glassfish.javax.enterprise.concurrent//org.glassfish.enterprise.concurrent.ManagedThreadFactoryImpl$ManagedThread.run(ManagedThreadFactoryImpl.java:227)
2022-05-12 17:14:50,972-06 INFO
[org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-74)
[192f636b-0dd4-4a61-a673-1b946d7e0b56] Command 'ExtractOva' id
: '6ece0932-038c-485e-8d7c-76927c04ea1c' child commands '[]'
executions were completed, status 'FAILED'
2022-05-12 17:14:52,061-06 ERROR
[org.ovirt.engine.core.bll.exportimport.ExtractOvaCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-87)
[192f636b-0dd4-4a61-a673-1b946d7e0b56] Ending command 'org.ovirt.engine.
core.bll.exportimport.ExtractOvaCommand' with failure.
2022-05-12 17:14:52,091-06 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-87)
[192f636b-0dd4-4a61-a673-1b946d7e0b56] Command 'ImportVmFromOva' i
d: 'c8937f2f-4a54-4dc1-9787-6a147d3e318e' child commands
'[94c02f37-4f26-401d-802c-11cc2c2bed92, 6ece0932-038c-485e-8d7c-76927c04ea1c]'
executions were completed, status 'FAILED'
2022-05-12 17:14:53,123-06 ERROR
[org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86)
[192f636b-0dd4-4a61-a673-1b946d7e0b56] Ending command 'org.ovirt.en
gine.core.bll.exportimport.ImportVmFromOvaCommand' with failure.
2022-05-12 17:14:53,138-06 INFO
[org.ovirt.engine.core.bll.storage.disk.image.RemoveAllVmImagesCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86) [1a392932]
Running command: RemoveAllVmImagesCommand intern al:
true. Entities affected : ID: 66ff7ebd-7682-4b94-b98d-5ceb76661830 Type: VM
2022-05-12 17:14:53,147-06 INFO
[org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86) [1a392932]
Running command: RemoveImageCommand internal: true. En
tities affected : ID: 00000000-0000-0000-0000-000000000000 Type: Storage
2022-05-12 17:14:53,337-06 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86) [1a392932] START,
DeleteImageGroupVDSCommand( DeleteImageGro
upVDSCommandParameters:{storagePoolId='3a1daa08-d23f-11ec-9e55-000c29861b3c',
ignoreFailoverLimit='false',
storageDomainId='ddeb67aa-9ec8-488b-9632-5cc19a244815',
imageGroupId='6706932e-ef61-4559-a4c3-f6a28a2f92a5', postZeros='false', di
scard='false', forceDelete='false'}), log
id: 73957233
2022-05-12 17:14:54,575-06 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86) [1a392932]
FINISH, DeleteImageGroupVDSCommand, return: , log id:
73957233
2022-05-12 17:14:54,576-06 INFO [org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86) [1a392932]
CommandAsyncTask::Adding CommandMultiAsyncTasks object for command '0
4405e70-986f-427a-b298-c1cdc992cc27'
2022-05-12 17:14:54,576-06 INFO [org.ovirt.engine.core.bll.CommandMultiAsyncTasks]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86) [1a392932]
CommandMultiAsyncTasks::attachTask: Attaching task '6e789867-0096-40f
0-a5e5-444047a6f056' to command
'04405e70-986f-427a-b298-c1cdc992cc27'.
2022-05-12 17:14:54,609-06 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86) [1a392932] Adding
task '6e789867-0096-40f0-a5e5-444047a6f056' (Parent Command 'R
emoveAllVmImages', Parameters Type
'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters'), polling hasn't
started yet..
2022-05-12 17:14:54,779-06 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86) [1a392932]
BaseAsyncTask::startPollingTask: Starting to poll task '6e789867-0096-40f
0-a5e5-444047a6f056'.
2022-05-12 17:14:54,781-06 INFO [org.ovirt.engine.core.bll.CommandCompensator]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86) [1a392932]
Command [id=c8937f2f-4a54-4dc1-9787-6a147d3e318e]: Compensating NEW_ENTIT
Y_ID of
org.ovirt.engine.core.common.businessentities.network.VmNetworkStatistics; snapshot:
9d496b9e-f56f-4175-8274-d1cedff52551.
2022-05-12 17:14:54,782-06 INFO [org.ovirt.engine.core.bll.CommandCompensator]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86) [1a392932]
Command [id=c8937f2f-4a54-4dc1-9787-6a147d3e318e]: Compensating NEW_ENTIT
Y_ID of
org.ovirt.engine.core.common.businessentities.network.VmNetworkInterface; snapshot:
9d496b9e-f56f-4175-8274-d1cedff52551.
2022-05-12 17:14:54,783-06 INFO [org.ovirt.engine.core.bll.CommandCompensator]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86) [1a392932]
Command [id=c8937f2f-4a54-4dc1-9787-6a147d3e318e]: Compensating TRANSIENT
_ENTITY of
org.ovirt.engine.core.common.businessentities.ReleaseMacsTransientCompensation; snapshot:
org.ovirt.engine.core.common.businessentities.ReleaseMacsTransientCompensation@6bea50f2.
2022-05-12 17:14:54,784-06 INFO [org.ovirt.engine.core.bll.CommandCompensator]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86) [1a392932]
Command [id=c8937f2f-4a54-4dc1-9787-6a147d3e318e]: Compensating NEW_ENTIT
Y_ID of org.ovirt.engine.core.common.businessentities.VmStatistics;
snapshot: 66ff7ebd-7682-4b94-b98d-5ceb76661830.
2022-05-12 17:14:54,784-06 INFO [org.ovirt.engine.core.bll.CommandCompensator]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86) [1a392932]
Command [id=c8937f2f-4a54-4dc1-9787-6a147d3e318e]: Compensating NEW_ENTIT
Y_ID of org.ovirt.engine.core.common.businessentities.VmDynamic; snapshot:
66ff7ebd-7682-4b94-b98d-5ceb76661830.
2022-05-12 17:14:54,785-06 INFO [org.ovirt.engine.core.bll.CommandCompensator]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86) [1a392932]
Command [id=c8937f2f-4a54-4dc1-9787-6a147d3e318e]: Compensating NEW_ENTIT
Y_ID of org.ovirt.engine.core.common.businessentities.Snapshot; snapshot:
7456013c-8278-463b-8ebc-7b8461f2e43a.
2022-05-12 17:14:54,786-06 INFO [org.ovirt.engine.core.bll.CommandCompensator]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86) [1a392932]
Command [id=c8937f2f-4a54-4dc1-9787-6a147d3e318e]: Compensating NEW_ENTIT
Y_ID of org.ovirt.engine.core.common.businessentities.VmStatic; snapshot:
66ff7ebd-7682-4b94-b98d-5ceb76661830.
2022-05-12 17:14:54,805-06 INFO
[org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86) [1a392932] Lock
freed to object 'EngineLock:{exclusiveLocks='[66ff7
ebd-7682-4b94-b98d-5ceb76661830=VM, Nextgen-CC-Tools=VM_NAME]',
sharedLocks=''}'
2022-05-12 17:14:54,869-06 ERROR
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86) [1a392932]
EVENT_ID: IMPORTEXPORT_IMPORT_VM_FAILED(1,153), F ailed
to import Vm Nextgen-CC-Tools to Data Center Default, Cluster Default
2022-05-12 17:15:01,472-06 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-26) [] Task id
'6e789867-0096-40f0-a5e5-444047a6f056' is in pre-polling period and shou
ld not be polled. Pre-polling period is 60000 millis.
2022-05-12 17:15:11,473-06 INFO [org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-58) [] Task id
'6e789867-0096-40f0-a5e5-444047a6f056' is in pre-polling period and shou
ld not be polled. Pre-polling period is 60000 millis.
^C
3:29 p.m.
On Fri, May 13, 2022 at 7:21 AM Abe E <aellahib(a)gmail.com> wrote:
Anyone getting issues with OVA Imports on 4.4?
I exported this from ovirt back in March although now importing again it
is failing.
My engine logs show it erroring out here:
Command, return: StatusReturn:{status='Status [code=0, message=Done]'},
log id: 7f970c1f
2022-05-12 17:14:40,396-06 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68) []
EVENT_ID: USER_ADD_DISK_TO_VM_FINISHED_SUCCESS(97), The d
isk Nextgen-CC-Tools_Disk1 was successfully added to VM
Nextgen-CC-Tools.
2022-05-12 17:14:40,678-06 INFO
[org.ovirt.engine.core.bll.exportimport.ExtractOvaCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[192f636b-0dd4-4a61-a673-1b946d7e0b56] Running command: ExtractOvaComman
d internal: true. Entities affected : ID:
66ff7ebd-7682-4b94-b98d-5ceb76661830 Type: VM
2022-05-12 17:14:40,691-06 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[192f636b-0dd4-4a61-a673-1b946d7e0b56] START, PrepareImageVDSCom
mand(HostName = ovirt-2.removed.com,
PrepareImageVDSCommandParameters:{hostId='0fbd6429-9c91-44d7-8d50-dcc08c8301f9'}),
log id: 6915fcb6
2022-05-12 17:14:40,731-06 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[192f636b-0dd4-4a61-a673-1b946d7e0b56] FINISH, PrepareImageVDSCo
mmand, return: PrepareImageReturn:{status='Status
[code=0, message=Done]'}, log id: 6915fcb6
2022-05-12 17:14:46,893-06 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[192f636b-0dd4-4a61-a673-1b946d7e0b56] EVENT_ID: ANSIBLE_RUN
NER_EVENT_NOTIFICATION(559), Import OVA. Run import yaml
on py3.
2022-05-12 17:14:46,918-06 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[192f636b-0dd4-4a61-a673-1b946d7e0b56] EVENT_ID: ANSIBLE_RUN
NER_EVENT_NOTIFICATION(559), Import OVA. Set facts.
2022-05-12 17:14:49,936-06 ERROR
[org.ovirt.engine.core.bll.exportimport.ExtractOvaCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[192f636b-0dd4-4a61-a673-1b946d7e0b56] Command 'org.ovirt.engine.core.bl
l.exportimport.ExtractOvaCommand' failed:
Task Run extraction script failed to execute. Please check logs for more
details:
/var/log/ovirt-engine/ova/ovirt-import-ova-ansible-20220512171440-ovirt-2.removed.com-192f636b-0dd4-4a61-a673-1b94
6d7e0b56.log
2022-05-12 17:14:49,936-06 ERROR
[org.ovirt.engine.core.bll.exportimport.ExtractOvaCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[192f636b-0dd4-4a61-a673-1b946d7e0b56] Exception: org.ovirt.engine.core.
common.utils.ansible.AnsibleRunnerCallException: Task Run extraction script
failed to execute. Please check logs for more details:
/var/log/ovirt-engine/ova/ovirt-import-ova-ansible-20220512171440-ovirt-2.removed.com-192f636b-0dd4-4a61-a6
73-1b946d7e0b56.log
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.common.utils.ansible.AnsibleRunnerHttpClient.processEvents(AnsibleRunnerHttpClient.java:251)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.common.utils.ansible.AnsibleExecutor.runCommand(AnsibleExecutor.java:227)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.common.utils.ansible.AnsibleExecutor.runCommand(AnsibleExecutor.java:74)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.common.utils.ansible.AnsibleExecutor.runCommand(AnsibleExecutor.java:51)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.exportimport.ExtractOvaCommand.runAnsibleImportOvaPlaybook(ExtractOvaCommand.java:129)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.exportimport.ExtractOvaCommand.executeVmCommand(ExtractOvaCommand.java:79)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.VmCommand.executeCommand(VmCommand.java:178)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1174)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1332)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:2010)
at
org.ovirt.engine.core.utils//org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInSuppressed(TransactionSupport.java:140)
at
org.ovirt.engine.core.utils//org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:79)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.execute(CommandBase.java:1392)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:424)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.executor.DefaultBackendActionExecutor.execute(DefaultBackendActionExecutor.java:13)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.Backend.runAction(Backend.java:450)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.Backend.runActionImpl(Backend.java:432)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.Backend.runInternalAction(Backend.java:638)
at jdk.internal.reflect.GeneratedMethodAccessor202.invoke(Unknown
Source)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at org.jboss.as.ee(a)23.0.2.Final
//org.jboss.as.ee.component.ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptor.java:52)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:509)
at org.jboss.as.weld.common(a)23.0.2.Final
//org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.delegateInterception(Jsr299BindingsInterceptor.java:79)
at org.jboss.as.weld.common(a)23.0.2.Final
//org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.doMethodInterception(Jsr299BindingsInterceptor.java:89)
at org.jboss.as.weld.common(a)23.0.2.Final
//org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.processInvocation(Jsr299BindingsInterceptor.java:102)
at org.jboss.as.ee(a)23.0.2.Final
//org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at org.jboss.as.ejb3(a)23.0.2.Final
//org.jboss.as.ejb3.component.invocationmetrics.ExecutionTimeInterceptor.processInvocation(ExecutionTimeInterceptor.java:43)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at org.jboss.as.ee(a)23.0.2.Final
//org.jboss.as.ee.concurrent.ConcurrentContextInterceptor.processInvocation(ConcurrentContextInterceptor.java:45)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:40)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:53)
at org.jboss.as.ee(a)23.0.2.Final
//org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:52)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at org.jboss.as.ejb3(a)23.0.2.Final
//org.jboss.as.ejb3.component.singleton.SingletonComponentInstanceAssociationInterceptor.processInvocation(SingletonComponentInstanceAssociationInterceptor.java:53)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at org.jboss.as.ejb3(a)23.0.2.Final
//org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:232)
at org.jboss.as.ejb3(a)23.0.2.Final
//org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:446)
at org.jboss.as.ejb3(a)23.0.2.Final
//org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:164)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:509)
at org.jboss.weld.core(a)3.1.6.
Final//org.jboss.weld.module.ejb.AbstractEJBRequestScopeActivationInterceptor.aroundInvoke(AbstractEJBRequestScopeActivationInterceptor.java:81)
at org.jboss.as.weld.common(a)23.0.2.Final
//org.jboss.as.weld.ejb.EjbRequestScopeActivationInterceptor.processInvocation(EjbRequestScopeActivationInterceptor.java:89)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at org.jboss.as.ejb3(a)23.0.2.Final
//org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at org.jboss.as.ejb3(a)23.0.2.Final
//org.jboss.as.ejb3.component.invocationmetrics.WaitTimeInterceptor.processInvocation(WaitTimeInterceptor.java:47)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at org.jboss.as.ejb3(a)23.0.2.Final
//org.jboss.as.ejb3.security.SecurityContextInterceptor.processInvocation(SecurityContextInterceptor.java:100)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at org.jboss.as.ejb3(a)23.0.2.Final
//org.jboss.as.ejb3.deployment.processors.StartupAwaitInterceptor.processInvocation(StartupAwaitInterceptor.java:22)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at org.jboss.as.ejb3(a)23.0.2.Final
//org.jboss.as.ejb3.component.interceptors.ShutDownInterceptorFactory$1.processInvocation(ShutDownInterceptorFactory.java:64)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at org.jboss.as.ejb3(a)23.0.2.Final
//org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:67)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at org.jboss.as.ee(a)23.0.2.Final
//org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.ContextClassLoaderInterceptor.processInvocation(ContextClassLoaderInterceptor.java:60)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.InterceptorContext.run(InterceptorContext.java:438)
at org.wildfly.security.elytron-private(a)1.15.3.Final
//org.wildfly.security.manager.WildFlySecurityManager.doChecked(WildFlySecurityManager.java:633)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.AccessCheckingInterceptor.processInvocation(AccessCheckingInterceptor.java:57)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
at org.jboss.invocation(a)1.6.0.
Final//org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:53)
at org.jboss.as.ee(a)23.0.2.Final
//org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:198)
at org.jboss.as.ee(a)23.0.2.Final
//org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:191)
at org.jboss.as.ee(a)23.0.2.Final
//org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:81)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.interfaces.BackendInternal$$$view4.runInternalAction(Unknown
Source)
at jdk.internal.reflect.GeneratedMethodAccessor201.invoke(Unknown
Source)
at
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at org.jboss.weld.core(a)3.1.6.
Final//org.jboss.weld.util.reflection.Reflections.invokeAndUnwrap(Reflections.java:410)
at org.jboss.weld.core(a)3.1.6.
Final//org.jboss.weld.module.ejb.EnterpriseBeanProxyMethodHandler.invoke(EnterpriseBeanProxyMethodHandler.java:134)
at org.jboss.weld.core(a)3.1.6.
Final//org.jboss.weld.bean.proxy.EnterpriseTargetBeanInstance.invoke(EnterpriseTargetBeanInstance.java:56)
at org.jboss.weld.core(a)3.1.6.
Final//org.jboss.weld.module.ejb.InjectionPointPropagatingEnterpriseTargetBeanInstance.invoke(InjectionPointPropagatingEnterpriseTargetBeanInstance.java:68)
at org.jboss.weld.core(a)3.1.6.
Final//org.jboss.weld.bean.proxy.ProxyMethodHandler.invoke(ProxyMethodHandler.java:106)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.BackendCommandObjectsHandler$BackendInternal$BackendLocal2049259618$Proxy$_$$_Weld$EnterpriseProxy$.runInternalAction(Unknown
Source)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.runInternalAction(CommandBase.java:2388)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand.convert(ImportVmFromOvaCommand.java:59)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.exportimport.ImportVmFromExternalProviderCommand.executeNextOperation(ImportVmFromExternalProviderCommand.java:510)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.exportimport.ImportVmFromExternalProviderCommand.performNextOperation(ImportVmFromExternalProviderCommand.java:502)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback.childCommandsExecutionEnded(SerialChildCommandsExecutionCallback.java:32)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.ChildCommandsCallbackBase.doPolling(ChildCommandsCallbackBase.java:80)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller.invokeCallbackMethodsImpl(CommandCallbacksPoller.java:175)
at
deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.tasks.CommandCallbacksPoller.invokeCallbackMethods(CommandCallbacksPoller.java:109)
at
java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
at
java.base/java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305)
at
org.glassfish.javax.enterprise.concurrent//org.glassfish.enterprise.concurrent.internal.ManagedScheduledThreadPoolExecutor$ManagedScheduledFutureTask.access$201(ManagedScheduledThreadPoolExecutor.java:360)
at
org.glassfish.javax.enterprise.concurrent//org.glassfish.enterprise.concurrent.internal.ManagedScheduledThreadPoolExecutor$ManagedScheduledFutureTask.run(ManagedScheduledThreadPoolExecutor.java:511)
at
java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at
java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
at java.base/java.lang.Thread.run(Thread.java:829)
at
org.glassfish.javax.enterprise.concurrent//org.glassfish.enterprise.concurrent.ManagedThreadFactoryImpl$ManagedThread.run(ManagedThreadFactoryImpl.java:227)
2022-05-12 17:14:50,972-06 INFO
[org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-74)
[192f636b-0dd4-4a61-a673-1b946d7e0b56] Command 'ExtractOva' id
: '6ece0932-038c-485e-8d7c-76927c04ea1c' child commands
'[]' executions were completed, status 'FAILED'
2022-05-12 17:14:52,061-06 ERROR
[org.ovirt.engine.core.bll.exportimport.ExtractOvaCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-87)
[192f636b-0dd4-4a61-a673-1b946d7e0b56] Ending command 'org.ovirt.engine.
core.bll.exportimport.ExtractOvaCommand' with
failure.
2022-05-12 17:14:52,091-06 INFO
[org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-87)
[192f636b-0dd4-4a61-a673-1b946d7e0b56] Command 'ImportVmFromOva' i
d: 'c8937f2f-4a54-4dc1-9787-6a147d3e318e' child
commands '[94c02f37-4f26-401d-802c-11cc2c2bed92,
6ece0932-038c-485e-8d7c-76927c04ea1c]' executions were completed, status
'FAILED'
2022-05-12 17:14:53,123-06 ERROR
[org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86)
[192f636b-0dd4-4a61-a673-1b946d7e0b56] Ending command 'org.ovirt.en
gine.core.bll.exportimport.ImportVmFromOvaCommand' with failure.
2022-05-12 17:14:53,138-06 INFO
[org.ovirt.engine.core.bll.storage.disk.image.RemoveAllVmImagesCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86)
[1a392932] Running command: RemoveAllVmImagesCommand intern
al: true. Entities affected : ID:
66ff7ebd-7682-4b94-b98d-5ceb76661830 Type: VM
2022-05-12 17:14:53,147-06 INFO
[org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86)
[1a392932] Running command: RemoveImageCommand internal: true. En
tities affected : ID:
00000000-0000-0000-0000-000000000000 Type: Storage
2022-05-12 17:14:53,337-06 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86)
[1a392932] START, DeleteImageGroupVDSCommand( DeleteImageGro
upVDSCommandParameters:{storagePoolId='3a1daa08-d23f-11ec-9e55-000c29861b3c',
ignoreFailoverLimit='false',
storageDomainId='ddeb67aa-9ec8-488b-9632-5cc19a244815',
imageGroupId='6706932e-ef61-4559-a4c3-f6a28a2f92a5', postZeros='false',
di
scard='false', forceDelete='false'}),
log
id: 73957233
2022-05-12 17:14:54,575-06 INFO
[org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86)
[1a392932] FINISH, DeleteImageGroupVDSCommand, return: , log
id: 73957233
2022-05-12 17:14:54,576-06 INFO
[org.ovirt.engine.core.bll.tasks.CommandAsyncTask]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86)
[1a392932] CommandAsyncTask::Adding CommandMultiAsyncTasks object for
command '0
4405e70-986f-427a-b298-c1cdc992cc27'
2022-05-12 17:14:54,576-06 INFO
[org.ovirt.engine.core.bll.CommandMultiAsyncTasks]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86)
[1a392932] CommandMultiAsyncTasks::attachTask: Attaching task
'6e789867-0096-40f 0-a5e5-444047a6f056' to
command '04405e70-986f-427a-b298-c1cdc992cc27'.
2022-05-12 17:14:54,609-06 INFO
[org.ovirt.engine.core.bll.tasks.AsyncTaskManager]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86)
[1a392932] Adding task '6e789867-0096-40f0-a5e5-444047a6f056' (Parent
Command 'R emoveAllVmImages', Parameters
Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters'),
polling hasn't started yet..
2022-05-12 17:14:54,779-06 INFO
[org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86)
[1a392932] BaseAsyncTask::startPollingTask: Starting to poll task
'6e789867-0096-40f 0-a5e5-444047a6f056'.
2022-05-12 17:14:54,781-06 INFO
[org.ovirt.engine.core.bll.CommandCompensator]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86)
[1a392932] Command [id=c8937f2f-4a54-4dc1-9787-6a147d3e318e]: Compensating
NEW_ENTIT Y_ID of
org.ovirt.engine.core.common.businessentities.network.VmNetworkStatistics;
snapshot: 9d496b9e-f56f-4175-8274-d1cedff52551.
2022-05-12 17:14:54,782-06 INFO
[org.ovirt.engine.core.bll.CommandCompensator]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86)
[1a392932] Command [id=c8937f2f-4a54-4dc1-9787-6a147d3e318e]: Compensating
NEW_ENTIT Y_ID of
org.ovirt.engine.core.common.businessentities.network.VmNetworkInterface;
snapshot: 9d496b9e-f56f-4175-8274-d1cedff52551.
2022-05-12 17:14:54,783-06 INFO
[org.ovirt.engine.core.bll.CommandCompensator]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86)
[1a392932] Command [id=c8937f2f-4a54-4dc1-9787-6a147d3e318e]: Compensating
TRANSIENT _ENTITY of
org.ovirt.engine.core.common.businessentities.ReleaseMacsTransientCompensation;
snapshot:
org.ovirt.engine.core.common.businessentities.ReleaseMacsTransientCompensation@6bea50f2.
2022-05-12 17:14:54,784-06 INFO
[org.ovirt.engine.core.bll.CommandCompensator]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86)
[1a392932] Command [id=c8937f2f-4a54-4dc1-9787-6a147d3e318e]: Compensating
NEW_ENTIT Y_ID of
org.ovirt.engine.core.common.businessentities.VmStatistics; snapshot:
66ff7ebd-7682-4b94-b98d-5ceb76661830.
2022-05-12 17:14:54,784-06 INFO
[org.ovirt.engine.core.bll.CommandCompensator]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86)
[1a392932] Command [id=c8937f2f-4a54-4dc1-9787-6a147d3e318e]: Compensating
NEW_ENTIT Y_ID of
org.ovirt.engine.core.common.businessentities.VmDynamic; snapshot:
66ff7ebd-7682-4b94-b98d-5ceb76661830.
2022-05-12 17:14:54,785-06 INFO
[org.ovirt.engine.core.bll.CommandCompensator]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86)
[1a392932] Command [id=c8937f2f-4a54-4dc1-9787-6a147d3e318e]: Compensating
NEW_ENTIT Y_ID of
org.ovirt.engine.core.common.businessentities.Snapshot; snapshot:
7456013c-8278-463b-8ebc-7b8461f2e43a.
2022-05-12 17:14:54,786-06 INFO
[org.ovirt.engine.core.bll.CommandCompensator]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86)
[1a392932] Command [id=c8937f2f-4a54-4dc1-9787-6a147d3e318e]: Compensating
NEW_ENTIT Y_ID of
org.ovirt.engine.core.common.businessentities.VmStatic; snapshot:
66ff7ebd-7682-4b94-b98d-5ceb76661830.
2022-05-12 17:14:54,805-06 INFO
[org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86)
[1a392932] Lock freed to object 'EngineLock:{exclusiveLocks='[66ff7
ebd-7682-4b94-b98d-5ceb76661830=VM,
Nextgen-CC-Tools=VM_NAME]', sharedLocks=''}'
2022-05-12 17:14:54,869-06 ERROR
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86)
[1a392932] EVENT_ID: IMPORTEXPORT_IMPORT_VM_FAILED(1,153), F
ailed to import Vm Nextgen-CC-Tools to Data Center
Default, Cluster Default
2022-05-12 17:15:01,472-06 INFO
[org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-26) []
Task id '6e789867-0096-40f0-a5e5-444047a6f056' is in pre-polling period
and shou ld not be polled. Pre-polling
period is 60000 millis.
2022-05-12 17:15:11,473-06 INFO
[org.ovirt.engine.core.bll.tasks.SPMAsyncTask]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-58) []
Task id '6e789867-0096-40f0-a5e5-444047a6f056' is in pre-polling period
and shou ld not be polled. Pre-polling
period is 60000 millis.
^C
Hi,
Please check the ansible log and possibly share it.
2022-05-12 17:14:49,936-06 ERROR
[org.ovirt.engine.core.bll.exportimport.ExtractOvaCommand]
(EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-68)
[192f636b-0dd4-4a61-a673-1b946d7e0b56] Command 'org.ovirt.engine.core.bl
l.exportimport.ExtractOvaCommand' failed:
Task Run extraction script failed to execute. Please check logs for more
details:
/var/log/ovirt-engine/ova/ovirt-import-ova-ansible-20220512171440-ovirt-2.removed.com-192f636b-0dd4-4a61-a673-1b94
6d7e0b56.log
That would be:
/var/log/ovirt-engine/ova/ovirt-import-ova-ansible-20220512171440-ovirt-2.removed.com-192f636b-0dd4-4a61-a673-1b946d7e0b56.log
Regards,
Liran
_______________________________________________
Users mailing list -- users(a)ovirt.org
To unsubscribe send an email to users-leave(a)ovirt.org
Privacy Statement: https://www.ovirt.org/privacy-policy.html
oVirt Code of Conduct:
https://www.ovirt.org/community/about/community-guidelines/
List Archives:
https://lists.ovirt.org/archives/list/users@ovirt.org/message/JM2CWJ6BIYW...
921
days inactive
925
days old
2 comments
2 participants
participants (2)
-
Abe E -
Liran Rotenberg