*** This bug has been marked as a duplicate of bug 1798425 ***

It is not the same bug. I do not get same message as bug 1798425. I DO NOT get such message (The version of imported VM is too old. Only VMs with version 3.6 or higher can be imported.)

The documentation text flag should only be set after 'doc text' field is provided. Please provide the documentation text and set the flag to '?' again.

can you please include vdsm.log from the host

From the oVirt installation or from the KVM side ?

Please find below the logfile content 
2020-02-20 11:38:17,966+0200 INFO  (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call Host.getStats succeeded in 0.40 seconds (__init__:312)
2020-02-20 11:38:19,781+0200 WARN  (qgapoller/5) [virt.periodic.VmDispatcher] could not run <function <lambda> at 0x7f08105da9b0> on ['da3316a5-a171-4155-a9b5-e9d1b48bbd96', 'd424729d-42b1-4333-bc84-8932d81dd35d', '10215558-0b57-46f5-a193-778481212655', 'bd57ce59-b225-4c52-8dbd-6c3e9108aac0', 'a9dd3e10-cabf-44ea-b9dc-54d97b172489', '8595ee07-1163-48ae-ac06-b0ad05ed72da', '8c7367d0-c42f-4c7c-9a37-65996bb59046'] (periodic:289)
2020-02-20 11:38:20,230+0200 INFO  (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312)
2020-02-20 11:38:20,244+0200 INFO  (jsonrpc/3) [api.virt] START getStats() from=::1,55366, vmId=0212bfb9-fa6a-49ec-b323-bbc41da3f036 (api:48)
2020-02-20 11:38:20,244+0200 INFO  (jsonrpc/3) [api] FINISH getStats error=Virtual machine does not exist: {'vmId': u'0212bfb9-fa6a-49ec-b323-bbc41da3f036'} (api:129)
2020-02-20 11:38:20,244+0200 INFO  (jsonrpc/3) [api.virt] FINISH getStats return={'status': {'message': "Virtual machine does not exist: {'vmId': u'0212bfb9-fa6a-49ec-b323-bbc41da3f036'}", 'code': 1}} from=::1,55366, vmId=0212bfb9-fa6a-49ec-b323-bbc41da3f036 (api:54)
2020-02-20 11:38:20,245+0200 INFO  (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call VM.getStats failed (error 1) in 0.01 seconds (__init__:312)
2020-02-20 11:38:21,462+0200 INFO  (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312)
2020-02-20 11:38:21,469+0200 INFO  (jsonrpc/0) [vdsm.api] START repoStats(domains=[u'a94f874f-e5c4-4538-ba71-3901487515e7']) from=::1,55366, task_id=78b12b67-0aaa-4913-a239-e6f4d2e39f89 (api:48)
2020-02-20 11:38:21,469+0200 INFO  (jsonrpc/0) [vdsm.api] FINISH repoStats return={u'a94f874f-e5c4-4538-ba71-3901487515e7': {'code': 0, 'actual': True, 'version': 5, 'acquired': True, 'delay': '0.000655593', 'lastCheck': '2.8', 'valid': True}} from=::1,55366, task_id=78b12b67-0aaa-4913-a239-e6f4d2e39f89 (api:54)
2020-02-20 11:38:21,470+0200 INFO  (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call Host.getStorageRepoStats succeeded in 0.00 seconds (__init__:312)
2020-02-20 11:38:21,893+0200 INFO  (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call Host.ping2 succeeded in 0.00 seconds (__init__:312)
2020-02-20 11:38:21,901+0200 INFO  (jsonrpc/7) [api.host] START getCapabilities() from=::1,55366 (api:48)

I have no answer the last days, do you need more info ? I am able to provide. 
Thanks.

(In reply to con_louca from comment #7)
> I have no answer the last days, do you need more info ? I am able to
> provide. 
> Thanks.

Could you attach the engine and vdsm logs to this issue via the "add an attachment" link above? Also please provide the time for when you start the import and when it fails. I performed an import and after the fix for bug 1798425, it worked fine.

(In reply to Steven Rosenberg from comment #8)
> (In reply to con_louca from comment #7)
> > I have no answer the last days, do you need more info ? I am able to
> > provide. 
> > Thanks.
> 
> Could you attach the engine and vdsm logs to this issue via the "add an
> attachment" link above? Also please provide the time for when you start the
> import and when it fails. I performed an import and after the fix for bug
> 1798425, it worked fine.

The bug you mentioned fixed a problem that refers version 3.6 but we are working on 4.3.6.6 so this is a new issue I think. I will try to get new logs but except these messages there is nothing else more related to that issue. I do not want to post full log since there are sensitive date included in this. But I think I captured the errors related to this issue. I will test it again anyway.

Ping!

(In reply to con_louca from comment #10)
> Ping!

Is there are problem with providing the logs?

Okay, I will send you more logs.

con_louca, ping?

Hello Ryan, I tried to collect more logs, but the other information are not relative with this error. Please consider that in these logs there are confidential information like IP and ports so I cannot publish. Please try to replicate the error I mentioned before. 
Thanks.

Ok, thanks. We'll reevaluate after 4.4 GA

Thanks Ryan

There's no much we can do without logs here.
The steps to reproduce are the same as those for bz 1663135

Hello!

I have the same issue. Same steps to reproduce. 

Version: ovirt-node-ng-4.4.6.1-0.20210510.0+1 

Here's relevant excerpt from vdsm.log

2021-05-12 22:40:03,667+0300 INFO  (jsonrpc/3) [api.host] START getAllVmStats() from=::1,46022 (api:48)
2021-05-12 22:40:03,667+0300 INFO  (jsonrpc/3) [api.host] FINISH getAllVmStats return={'status': {'code': 0, 'message': 'Done'}, 'statsList': (suppressed)} from=::1,46022 (api:54)
2021-05-12 22:40:06,508+0300 INFO  (jsonrpc/1) [api.virt] START getStats() from=::1,46446, vmId=fa66859d-b11b-4629-aa23-726ad7c87b5d (api:48)
2021-05-12 22:40:06,508+0300 INFO  (jsonrpc/1) [api] FINISH getStats error=Virtual machine does not exist: {'vmId': 'fa66859d-b11b-4629-aa23-726ad7c87b5d'} (api:129)
2021-05-12 22:40:06,508+0300 INFO  (jsonrpc/1) [api.virt] FINISH getStats return={'status': {'code': 1, 'message': "Virtual machine does not exist: {'vmId': 'fa66859d-b11b-4629-aa23-726ad7c87b5d'}"}} from=::1,46446, vmId=fa66859d-b11b-4629-aa23-726ad7c87b5d (api:54)
2021-05-12 22:40:06,508+0300 INFO  (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call VM.getStats failed (error 1) in 0.00 seconds (__init__:312)
2021-05-12 22:40:06,523+0300 INFO  (jsonrpc/4) [vdsm.api] START repoStats(domains=['32e2b7f2-873b-462a-bb45-6e4931e646ca']) from=::1,46446, task_id=4aaeedf0-8625-4c98-813a-3db18f7a4ab3 (api:48)
2021-05-12 22:40:06,523+0300 INFO  (jsonrpc/4) [vdsm.api] FINISH repoStats return={'32e2b7f2-873b-462a-bb45-6e4931e646ca': {'code': 0, 'lastCheck': '1.8', 'delay': '0.00190975', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}} from=::1,46446, task_id=4aaeedf0-8625-4c98-813a-3db18f7a4ab3 (api:54)
2021-05-12 22:40:06,677+0300 INFO  (jsonrpc/6) [api.host] START getStats() from=::1,46446 (api:48)
2021-05-12 22:40:06,682+0300 INFO  (jsonrpc/6) [vdsm.api] START repoStats(domains=()) from=::1,46446, task_id=d3100e5b-03fe-4a3e-86c5-678770904805 (api:48)
2021-05-12 22:40:06,682+0300 INFO  (jsonrpc/6) [vdsm.api] FINISH repoStats return={'cfafc73f-1b6a-425f-8f3d-aefe23d87b72': {'code': 0, 'lastCheck': '0.8', 'delay': '0.00153002', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '32e2b7f2-873b-462a-bb45-6e4931e646ca': {'code': 0, 'lastCheck': '1.9', 'delay': '0.00190975', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '09c995a0-8934-4be6-a69f-ce92cc7ecaa2': {'code': 0, 'lastCheck': '0.7', 'delay': '0.00165331', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}} from=::1,46446, task_id=d3100e5b-03fe-4a3e-86c5-678770904805 (api:54)
2021-05-12 22:40:06,682+0300 INFO  (jsonrpc/6) [vdsm.api] START multipath_health() from=::1,46446, task_id=568eef8c-4bb5-4e7f-9cdc-060f26a5438c (api:48)
2021-05-12 22:40:06,682+0300 INFO  (jsonrpc/6) [vdsm.api] FINISH multipath_health return={} from=::1,46446, task_id=568eef8c-4bb5-4e7f-9cdc-060f26a5438c (api:54)
2021-05-12 22:40:06,688+0300 INFO  (jsonrpc/6) [api.host] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'info': (suppressed)} from=::1,46446 (api:54)
2021-05-12 22:40:06,693+0300 INFO  (jsonrpc/3) [api.host] START getStats() from=::1,46446 (api:48)
2021-05-12 22:40:06,698+0300 INFO  (jsonrpc/3) [vdsm.api] START repoStats(domains=()) from=::1,46446, task_id=01086371-d0a2-4d82-bf95-cafea2c514dd (api:48)
2021-05-12 22:40:06,698+0300 INFO  (jsonrpc/3) [vdsm.api] FINISH repoStats return={'cfafc73f-1b6a-425f-8f3d-aefe23d87b72': {'code': 0, 'lastCheck': '0.8', 'delay': '0.00153002', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '32e2b7f2-873b-462a-bb45-6e4931e646ca': {'code': 0, 'lastCheck': '1.9', 'delay': '0.00190975', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '09c995a0-8934-4be6-a69f-ce92cc7ecaa2': {'code': 0, 'lastCheck': '0.8', 'delay': '0.00165331', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}} from=::1,46446, task_id=01086371-d0a2-4d82-bf95-cafea2c514dd (api:54)
2021-05-12 22:40:06,698+0300 INFO  (jsonrpc/3) [vdsm.api] START multipath_health() from=::1,46446, task_id=b023c7bc-6889-47bb-851e-8c4ea40a3a28 (api:48)
2021-05-12 22:40:06,698+0300 INFO  (jsonrpc/3) [vdsm.api] FINISH multipath_health return={} from=::1,46446, task_id=b023c7bc-6889-47bb-851e-8c4ea40a3a28 (api:54)
2021-05-12 22:40:06,703+0300 INFO  (jsonrpc/3) [api.host] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'info': (suppressed)} from=::1,46446 (api:54)
2021-05-12 22:40:08,946+0300 INFO  (periodic/1) [vdsm.api] START repoStats(domains=()) from=internal, task_id=a2eb2783-8c1d-4239-9fc8-432edcb3ea98 (api:48)
2021-05-12 22:40:08,946+0300 INFO  (periodic/1) [vdsm.api] FINISH repoStats return={'cfafc73f-1b6a-425f-8f3d-aefe23d87b72': {'code': 0, 'lastCheck': '3.1', 'delay': '0.00153002', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '32e2b7f2-873b-462a-bb45-6e4931e646ca': {'code': 0, 'lastCheck': '4.2', 'delay': '0.00190975', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '09c995a0-8934-4be6-a69f-ce92cc7ecaa2': {'code': 0, 'lastCheck': '3.0', 'delay': '0.00165331', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}} from=internal, task_id=a2eb2783-8c1d-4239-9fc8-432edcb3ea98 (api:54)
2021-05-12 22:40:09,002+0300 INFO  (jsonrpc/0) [vdsm.api] START repoStats(domains=['32e2b7f2-873b-462a-bb45-6e4931e646ca']) from=::1,46446, task_id=c4e8bf92-536b-433a-ad62-26e81b803bad (api:48)
2021-05-12 22:40:09,003+0300 INFO  (jsonrpc/0) [vdsm.api] FINISH repoStats return={'32e2b7f2-873b-462a-bb45-6e4931e646ca': {'code': 0, 'lastCheck': '4.2', 'delay': '0.00190975', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}} from=::1,46446, task_id=c4e8bf92-536b-433a-ad62-26e81b803bad (api:54)
2021-05-12 22:40:12,772+0300 INFO  (jsonrpc/4) [api.host] START getStats() from=::ffff:10.xx.xx.xx,45164 (api:48)
2021-05-12 22:40:12,777+0300 INFO  (jsonrpc/4) [vdsm.api] START repoStats(domains=()) from=::ffff:10.xx.xx.xx,45164, task_id=9ebb572a-b04e-42f8-bbba-e76983a7bbbb (api:48)
2021-05-12 22:40:12,777+0300 INFO  (jsonrpc/4) [vdsm.api] FINISH repoStats return={'cfafc73f-1b6a-425f-8f3d-aefe23d87b72': {'code': 0, 'lastCheck': '6.9', 'delay': '0.00153002', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '32e2b7f2-873b-462a-bb45-6e4931e646ca': {'code': 0, 'lastCheck': '3.2', 'delay': '0.00190975', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}, '09c995a0-8934-4be6-a69f-ce92cc7ecaa2': {'code': 0, 'lastCheck': '0.3', 'delay': '0.00165331', 'valid': True, 'version': 5, 'acquired': True, 'actual': True}} from=::ffff:10.xx.xx.xx,45164, task_id=9ebb572a-b04e-42f8-bbba-e76983a7bbbb (api:54)
2021-05-12 22:40:12,777+0300 INFO  (jsonrpc/4) [vdsm.api] START multipath_health() from=::ffff:10.xx.xx.xx,45164, task_id=5eda4cff-7578-4111-be66-a447b92357de (api:48)
2021-05-12 22:40:12,778+0300 INFO  (jsonrpc/4) [vdsm.api] FINISH multipath_health return={} from=::ffff:10.xx.xx.xx,45164, task_id=5eda4cff-7578-4111-be66-a447b92357de (api:54)
2021-05-12 22:40:12,782+0300 INFO  (jsonrpc/4) [api.host] FINISH getStats return={'status': {'code': 0, 'message': 'Done'}, 'info': (suppressed)} from=::ffff:10.xx.xx.xx,45164 (api:54)
2021-05-12 22:40:13,243+0300 INFO  (jsonrpc/2) [api.host] START getAllVmStats() from=::ffff:10.xx.xx.xx,45164 (api:48)
2021-05-12 22:40:13,243+0300 INFO  (jsonrpc/2) [api.host] FINISH getAllVmStats return={'status': {'code': 0, 'message': 'Done'}, 'statsList': (suppressed)} from=::ffff:10.xx.xx.xx,45164 (api:54)

Here's relevant excerpt from engine.log

2021-05-12 22:40:06,003+03 INFO  [org.ovirt.engine.core.bll.exportimport.ImportVmFromExternalProviderCommand] (default task-18) [e9306d23-945c-47e3-bc8b-7ae79ed2d368] Lock Acquired to object 'EngineLock:{exclusiveLocks='[817324a6-8658-48
eb-b940-debf61960478=VM, hassio=VM_NAME]', sharedLocks=''}'
2021-05-12 22:40:06,179+03 INFO  [org.ovirt.engine.core.bll.exportimport.ImportVmFromExternalProviderCommand] (EE-ManagedThreadFactory-engine-Thread-23375) [e9306d23-945c-47e3-bc8b-7ae79ed2d368] Running command: ImportVmFromExternalProvi
derCommand internal: false. Entities affected :  ID: 0d7e8cf8-aead-11eb-8bb5-00163e718bab Type: ClusterAction group CREATE_VM with role type USER,  ID: 09c995a0-8934-4be6-a69f-ce92cc7ecaa2 Type: StorageAction group IMPORT_EXPORT_VM with
role type ADMIN
2021-05-12 22:40:06,388+03 INFO  [org.ovirt.engine.core.bll.storage.disk.AddDiskCommand] (EE-ManagedThreadFactory-engine-Thread-23375) [e9306d23-945c-47e3-bc8b-7ae79ed2d368] Lock Acquired to object 'EngineLock:{exclusiveLocks='[817324a6-
8658-48eb-b940-debf61960478=VM_DISK_BOOT, 14be8282-12f0-4b94-a9e1-a71009ec02d6=DISK]', sharedLocks=''}'
2021-05-12 22:40:06,404+03 ERROR [org.ovirt.engine.core.bll.storage.disk.AddDiskCommand] (EE-ManagedThreadFactory-engine-Thread-23375) [e9306d23-945c-47e3-bc8b-7ae79ed2d368] Error during ValidateFailure.: java.lang.NullPointerException
        at deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.validator.storage.DiskVmElementValidator.isDiskInterfaceSupported(DiskVmElementValidator.java:88)
        at deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.storage.disk.AddDiskCommand.checkIfImageDiskCanBeAdded(AddDiskCommand.java:302)
        at deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.storage.disk.AddDiskCommand.validate(AddDiskCommand.java:197)
        at deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.internalValidateInTransaction(CommandBase.java:824)
        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.executeInSuppressed(TransactionSupport.java:157)
        at deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.internalValidate(CommandBase.java:803)
        at deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:417)
        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:442)
        at deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.Backend.runActionImpl(Backend.java:424)
        at deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.Backend.runInternalAction(Backend.java:630)
        at jdk.internal.reflect.GeneratedMethodAccessor133.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.0.Final//org.jboss.as.ee.component.ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptor.java:52)
        at org.jboss.invocation.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
        at org.jboss.invocation.0.Final//org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:509)
        at org.jboss.as.weld.common.0.Final//org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.delegateInterception(Jsr299BindingsInterceptor.java:79)
        at org.jboss.as.weld.common.0.Final//org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.doMethodInterception(Jsr299BindingsInterceptor.java:89)
        at org.jboss.as.weld.common.0.Final//org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.processInvocation(Jsr299BindingsInterceptor.java:102)
        at org.jboss.as.ee.0.Final//org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63)
        at org.jboss.invocation.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
        at org.jboss.as.ejb3.0.Final//org.jboss.as.ejb3.component.invocationmetrics.ExecutionTimeInterceptor.processInvocation(ExecutionTimeInterceptor.java:43)
        at org.jboss.invocation.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
        at org.jboss.as.ee.0.Final//org.jboss.as.ee.concurrent.ConcurrentContextInterceptor.processInvocation(ConcurrentContextInterceptor.java:45)
        at org.jboss.invocation.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
        at org.jboss.invocation.0.Final//org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:40)
        at org.jboss.invocation.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
        at org.jboss.invocation.0.Final//org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:53)
        at org.jboss.as.ee.0.Final//org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:52)
        at org.jboss.invocation.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
        at org.jboss.as.ejb3.0.Final//org.jboss.as.ejb3.component.singleton.SingletonComponentInstanceAssociationInterceptor.processInvocation(SingletonComponentInstanceAssociationInterceptor.java:53)
        at org.jboss.invocation.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
        at org.jboss.as.ejb3.0.Final//org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:230)
        at org.jboss.as.ejb3.0.Final//org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:444)
        at org.jboss.as.ejb3.0.Final//org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:162)
        at org.jboss.invocation.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
        at org.jboss.invocation.0.Final//org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:509)
        at org.jboss.weld.core.5.Final//org.jboss.weld.module.ejb.AbstractEJBRequestScopeActivationInterceptor.aroundInvoke(AbstractEJBRequestScopeActivationInterceptor.java:81)
        at org.jboss.as.weld.common.0.Final//org.jboss.as.weld.ejb.EjbRequestScopeActivationInterceptor.processInvocation(EjbRequestScopeActivationInterceptor.java:89)
        at org.jboss.invocation.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
        at org.jboss.as.ejb3.0.Final//org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41)
        at org.jboss.invocation.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
        at org.jboss.as.ejb3.0.Final//org.jboss.as.ejb3.component.invocationmetrics.WaitTimeInterceptor.processInvocation(WaitTimeInterceptor.java:47)
        at org.jboss.invocation.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
        at org.jboss.as.ejb3.0.Final//org.jboss.as.ejb3.security.SecurityContextInterceptor.processInvocation(SecurityContextInterceptor.java:100)
        at org.jboss.invocation.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
        at org.jboss.as.ejb3.0.Final//org.jboss.as.ejb3.deployment.processors.StartupAwaitInterceptor.processInvocation(StartupAwaitInterceptor.java:22)
        at org.jboss.invocation.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
        at org.jboss.as.ejb3.0.Final//org.jboss.as.ejb3.component.interceptors.ShutDownInterceptorFactory$1.processInvocation(ShutDownInterceptorFactory.java:64)
        at org.jboss.invocation.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
        at org.jboss.as.ejb3.0.Final//org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:67)
        at org.jboss.invocation.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
        at org.jboss.as.ee.0.Final//org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50)
        at org.jboss.invocation.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
        at org.jboss.invocation.0.Final//org.jboss.invocation.ContextClassLoaderInterceptor.processInvocation(ContextClassLoaderInterceptor.java:60)
        at org.jboss.invocation.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
        at org.jboss.invocation.0.Final//org.jboss.invocation.InterceptorContext.run(InterceptorContext.java:438)
        at org.wildfly.security.elytron-private.1.Final//org.wildfly.security.manager.WildFlySecurityManager.doChecked(WildFlySecurityManager.java:633)
        at org.jboss.invocation.0.Final//org.jboss.invocation.AccessCheckingInterceptor.processInvocation(AccessCheckingInterceptor.java:57)
        at org.jboss.invocation.0.Final//org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422)
        at org.jboss.invocation.0.Final//org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:53)
        at org.jboss.as.ee.0.Final//org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:198)
        at org.jboss.as.ee.0.Final//org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:191)
        at org.jboss.as.ee.0.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.GeneratedMethodAccessor132.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.5.Final//org.jboss.weld.util.reflection.Reflections.invokeAndUnwrap(Reflections.java:410)
        at org.jboss.weld.core.5.Final//org.jboss.weld.module.ejb.EnterpriseBeanProxyMethodHandler.invoke(EnterpriseBeanProxyMethodHandler.java:134)
        at org.jboss.weld.core.5.Final//org.jboss.weld.bean.proxy.EnterpriseTargetBeanInstance.invoke(EnterpriseTargetBeanInstance.java:56)
        at org.jboss.weld.core.5.Final//org.jboss.weld.module.ejb.InjectionPointPropagatingEnterpriseTargetBeanInstance.invoke(InjectionPointPropagatingEnterpriseTargetBeanInstance.java:68)
        at org.jboss.weld.core.5.Final//org.jboss.weld.bean.proxy.ProxyMethodHandler.invoke(ProxyMethodHandler.java:106)
        at deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.BackendCommandObjectsHandler$BackendInternal$BackendLocal$2049259618$Proxy$_$$_Weld$EnterpriseProxy$.runInternalAction(Unknown Source)
        at deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.runInternalAction(CommandBase.java:2386)
        at deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.runInternalActionWithTasksContext(CommandBase.java:2411)
        at deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.CommandBase.runInternalActionWithTasksContext(CommandBase.java:2406)
        at deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.exportimport.ImportVmFromExternalProviderCommand.createDisk(ImportVmFromExternalProviderCommand.java:323)
        at java.base/java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:195)
        at java.base/java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:195)
        at java.base/java.util.ArrayList$ArrayListSpliterator.forEachRemaining(ArrayList.java:1655)
        at java.base/java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:484)
        at java.base/java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:474)
        at java.base/java.util.stream.ReduceOps$ReduceOp.evaluateSequential(ReduceOps.java:913)
        at java.base/java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234)
        at java.base/java.util.stream.ReferencePipeline.collect(ReferencePipeline.java:578)
        at deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.exportimport.ImportVmFromExternalProviderCommand.processImages(ImportVmFromExternalProviderCommand.java:295)
        at deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.exportimport.ImportVmCommandBase.executeVmCommand(ImportVmCommandBase.java:510)
        at deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.VmCommand.executeCommand(VmCommand.java:179)
        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:2008)
        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.PrevalidatingMultipleActionsRunner.executeValidatedCommand(PrevalidatingMultipleActionsRunner.java:204)
        at deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.PrevalidatingMultipleActionsRunner.runCommands(PrevalidatingMultipleActionsRunner.java:176)
        at deployment.engine.ear.bll.jar//org.ovirt.engine.core.bll.PrevalidatingMultipleActionsRunner.lambda$invokeCommands$3(PrevalidatingMultipleActionsRunner.java:182)
        at org.ovirt.engine.core.utils//org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil$InternalWrapperRunnable.run(ThreadPoolUtil.java:96)
        at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
        at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
        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)

2021-05-12 22:40:06,430+03 INFO  [org.ovirt.engine.core.bll.storage.disk.AddDiskCommand] (EE-ManagedThreadFactory-engine-Thread-23375) [e9306d23-945c-47e3-bc8b-7ae79ed2d368] Lock freed to object 'EngineLock:{exclusiveLocks='[817324a6-8658-48eb-b940-debf61960478=VM_DISK_BOOT, 14be8282-12f0-4b94-a9e1-a71009ec02d6=DISK]', sharedLocks=''}'
2021-05-12 22:40:06,454+03 ERROR [org.ovirt.engine.core.bll.exportimport.ImportVmFromExternalProviderCommand] (EE-ManagedThreadFactory-engine-Thread-23375) [e9306d23-945c-47e3-bc8b-7ae79ed2d368] Command 'org.ovirt.engine.core.bll.exportimport.ImportVmFromExternalProviderCommand' failed: EngineException: Failed to create disk! (Failed with error ENGINE and code 5001)
2021-05-12 22:40:06,455+03 INFO  [org.ovirt.engine.core.bll.CommandCompensator] (EE-ManagedThreadFactory-engine-Thread-23375) [e9306d23-945c-47e3-bc8b-7ae79ed2d368] Command [id=fc2e171a-f888-46da-bf95-ac8902d1ea22]: Compensating NEW_ENTITY_ID of org.ovirt.engine.core.common.businessentities.network.VmNetworkStatistics; snapshot: c85f038a-dcde-45a3-8cd6-3f2b8fb8b495.
2021-05-12 22:40:06,456+03 INFO  [org.ovirt.engine.core.bll.CommandCompensator] (EE-ManagedThreadFactory-engine-Thread-23375) [e9306d23-945c-47e3-bc8b-7ae79ed2d368] Command [id=fc2e171a-f888-46da-bf95-ac8902d1ea22]: Compensating NEW_ENTITY_ID of org.ovirt.engine.core.common.businessentities.network.VmNetworkInterface; snapshot: c85f038a-dcde-45a3-8cd6-3f2b8fb8b495.
2021-05-12 22:40:06,456+03 INFO  [org.ovirt.engine.core.bll.CommandCompensator] (EE-ManagedThreadFactory-engine-Thread-23375) [e9306d23-945c-47e3-bc8b-7ae79ed2d368] Command [id=fc2e171a-f888-46da-bf95-ac8902d1ea22]: Compensating TRANSIENT_ENTITY of org.ovirt.engine.core.common.businessentities.ReleaseMacsTransientCompensation; snapshot: org.ovirt.engine.core.common.businessentities.ReleaseMacsTransientCompensation@459475e2.
2021-05-12 22:40:06,457+03 INFO  [org.ovirt.engine.core.bll.CommandCompensator] (EE-ManagedThreadFactory-engine-Thread-23375) [e9306d23-945c-47e3-bc8b-7ae79ed2d368] Command [id=fc2e171a-f888-46da-bf95-ac8902d1ea22]: Compensating NEW_ENTITY_ID of org.ovirt.engine.core.common.businessentities.VmStatistics; snapshot: 817324a6-8658-48eb-b940-debf61960478.
2021-05-12 22:40:06,457+03 INFO  [org.ovirt.engine.core.bll.CommandCompensator] (EE-ManagedThreadFactory-engine-Thread-23375) [e9306d23-945c-47e3-bc8b-7ae79ed2d368] Command [id=fc2e171a-f888-46da-bf95-ac8902d1ea22]: Compensating NEW_ENTITY_ID of org.ovirt.engine.core.common.businessentities.VmDynamic; snapshot: 817324a6-8658-48eb-b940-debf61960478.
2021-05-12 22:40:06,458+03 INFO  [org.ovirt.engine.core.bll.CommandCompensator] (EE-ManagedThreadFactory-engine-Thread-23375) [e9306d23-945c-47e3-bc8b-7ae79ed2d368] Command [id=fc2e171a-f888-46da-bf95-ac8902d1ea22]: Compensating NEW_ENTITY_ID of org.ovirt.engine.core.common.businessentities.Snapshot; snapshot: f79e8916-7959-455c-bcbf-6eda6caf6678.
2021-05-12 22:40:06,458+03 INFO  [org.ovirt.engine.core.bll.CommandCompensator] (EE-ManagedThreadFactory-engine-Thread-23375) [e9306d23-945c-47e3-bc8b-7ae79ed2d368] Command [id=fc2e171a-f888-46da-bf95-ac8902d1ea22]: Compensating NEW_ENTITY_ID of org.ovirt.engine.core.common.businessentities.VmStatic; snapshot: 817324a6-8658-48eb-b940-debf61960478.
2021-05-12 22:40:06,535+03 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-23375) [e9306d23-945c-47e3-bc8b-7ae79ed2d368] EVENT_ID: IMPORTEXPORT_IMPORT_VM_FAILED(1,153), Failed to import Vm hassio to Data Center Default, Cluster Default
2021-05-12 22:40:06,619+03 INFO  [org.ovirt.engine.core.bll.exportimport.ImportVmFromExternalProviderCommand] (EE-ManagedThreadFactory-engine-Thread-23375) [e9306d23-945c-47e3-bc8b-7ae79ed2d368] Lock freed to object 'EngineLock:{exclusiveLocks='[817324a6-8658-48eb-b940-debf61960478=VM, hassio=VM_NAME]', sharedLocks=''}'
2021-05-12 22:40:07,055+03 INFO  [org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86) [e9306d23-945c-47e3-bc8b-7ae79ed2d368] Command 'ImportVmFromExternalProvider' id: 'fc2e171a-f888-46da-bf95-ac8902d1ea22' execution didn't complete, not proceeding to perform the next operation
2021-05-12 22:40:07,055+03 INFO  [org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-86) [e9306d23-945c-47e3-bc8b-7ae79ed2d368] Command 'ImportVmFromExternalProvider' id: 'fc2e171a-f888-46da-bf95-ac8902d1ea22' child commands '[fd878aa6-2f0c-4942-867b-eb8e213cde41]' executions were completed, status 'FAILED'
2021-05-12 22:40:08,120+03 ERROR [org.ovirt.engine.core.bll.exportimport.ImportVmFromExternalProviderCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-23) [e9306d23-945c-47e3-bc8b-7ae79ed2d368] Ending command 'org.ovirt.engine.core.bll.exportimport.ImportVmFromExternalProviderCommand' with failure.
2021-05-12 22:40:08,126+03 INFO  [org.ovirt.engine.core.bll.storage.disk.image.RemoveAllVmImagesCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-23) [15f6289e] Running command: RemoveAllVmImagesCommand internal: true. Entities affected :  ID: 817324a6-8658-48eb-b940-debf61960478 Type: VM
2021-05-12 22:40:08,153+03 WARN  [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-23) [15f6289e] Trying to release exclusive lock which does not exist, lock key: '817324a6-8658-48eb-b940-debf61960478VM'
2021-05-12 22:40:08,153+03 WARN  [org.ovirt.engine.core.bll.lock.InMemoryLockManager] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-23) [15f6289e] Trying to release exclusive lock which does not exist, lock key: 'hassioVM_NAME'
2021-05-12 22:40:08,153+03 INFO  [org.ovirt.engine.core.bll.exportimport.ImportVmFromExternalProviderCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-23) [15f6289e] Lock freed to object 'EngineLock:{exclusiveLocks='[817324a6-8658-48eb-b940-debf61960478=VM, hassio=VM_NAME]', sharedLocks=''}'
2021-05-12 22:40:08,211+03 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-23) [15f6289e] EVENT_ID: IMPORTEXPORT_IMPORT_VM_FAILED(1,153), Failed to import Vm hassio to Data Center Default, Cluster Default

(In reply to Saku Vainikainen from comment #18)
> Hello!
> 
> I have the same issue. Same steps to reproduce. 

Thanks for reporting this -
You face a different issue, please check bz 1957595