New subject: Importing VM from OVA/OVF that was exported from VSphere fails ConvertOvaCommand - ImportVmFromOvaCommand

27 Feb 2023

      Description of problem: when importing Windows VM from OVA/OVF import fails:
EVENT_ID: IMPORTEXPORT_IMPORT_VM_FAILED(1,153), Failed to import Vm Implementacion_02-2 to Data Center dc_rcloud, Cluster cl_rcloud

Infrastructure:
VMware ESXi, 7.0.3, 19193900
oVirt Version 4.5.4-1.el8
oVirt self-hosted engine

Steps to Reproduce:
1.Export Windows to OVA/OVF from VSphere that with ovftool.
2. import the VM in oVirt

I'm getting some alerts just selected the VM
2023-02-27 11:09:00,676-05 WARN  [org.ovirt.engine.core.utils.ovf.OvfReader] (default task-38) [3eba88b2-7b98-48d4-8488-2f3331fb41e6] could not retrieve volume id of file1 from ovf, generating new guid
2023-02-27 11:09:00,676-05 WARN  [org.ovirt.engine.core.utils.ovf.OvfReader] (default task-38) [3eba88b2-7b98-48d4-8488-2f3331fb41e6] could not retrieve disk id of vmdisk1 from ovf, generating new guid
2023-02-27 11:09:00,677-05 WARN  [org.ovirt.engine.core.utils.ovf.OvfReader] (default task-38) [3eba88b2-7b98-48d4-8488-2f3331fb41e6] could not retrieve volume id of file2 from ovf, generating new guid
2023-02-27 11:09:00,677-05 WARN  [org.ovirt.engine.core.utils.ovf.OvfReader] (default task-38) [3eba88b2-7b98-48d4-8488-2f3331fb41e6] could not retrieve disk id of vmdisk2 from ovf, generating new guid

then I start the import process and the first Errors that appear is:
2023-02-27 11:09:42,353-05 ERROR [org.ovirt.engine.core.bll.exportimport.ConvertOvaCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-18) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Ending command 'org.ovirt.engine.core.bll.exportimport.ConvertOvaCommand' with failure.
2023-02-27 11:09:43,639-05 ERROR [org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Ending command 'org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand' with failure.
2023-02-27 11:09:44,409-05 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] EVENT_ID: IMPORTEXPORT_IMPORT_VM_FAILED(1,153), Failed to import Vm Implementacion_02-2 to Data Center dc_rcloud, Cluster cl_rcloud

# ls -l Implementacion_02-2/
total 234998648
-rw-rw-rw-. 1 vdsm kvm 111599878656 Feb 27 10:15 Implementacion_02-2-disk1.vmdk
-rw-rw-rw-. 1 vdsm kvm 129038421504 Feb 27 10:22 Implementacion_02-2-disk2.vmdk
-rw-rw-rw-. 1 vdsm kvm       270840 Feb 27 10:22 Implementacion_02-2-file1.nvram
-rw-rw-rw-. 1 vdsm kvm          414 Feb 27 10:22 Implementacion_02-2.mf
-rw-rw-rw-. 1 vdsm kvm        10891 Feb 27 10:26 Implementacion_02-2.ovf

# cat Implementacion_02-2.ovf
<?xml version="1.0" encoding="UTF-8"?>
<!--Generated by VMware VirtualCenter Server, User: VSPHERE.LOCAL\Administrator, UTC time: 2023-02-25T21:32:50.888437Z-->
<Envelope vmw:buildId="build-19480866" xmlns="http://schemas.dmtf.org/ovf/envelope/1" xmlns:cim="http://schemas.dmtf.org/wbem/wscim/1/common" xmlns:ovf="http://schemas.dmtf.org/ovf/envelope/1" xmlns:rasd="http://schemas.dmtf.org/wbem/wscim/1/cim-schema/2/CIM_ResourceAllocationSett..." xmlns:vmw="http://www.vmware.com/schema/ovf" xmlns:vssd="http://schemas.dmtf.org/wbem/wscim/1/cim-schema/2/CIM_VirtualSystemSettingDa..." xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance">
  <References>
    <File ovf:href="Implementacion_02-2-disk1.vmdk" ovf:id="file1" ovf:size="111599878656"/>
    <File ovf:href="Implementacion_02-2-disk2.vmdk" ovf:id="file2" ovf:size="129038421504"/>
    <File ovf:href="Implementacion_02-2-file1.nvram" ovf:id="file3" ovf:size="270840"/>
  </References>
  <DiskSection>
    <Info>Virtual disk information</Info>
    <Disk ovf:capacity="150" ovf:capacityAllocationUnits="byte * 2^30" ovf:diskId="vmdisk1" ovf:fileRef="file1" ovf:format="http://www.vmware.com/interfaces/specifications/vmdk.html#streamOptimized" ovf:populatedSize="117837135872"/>
    <Disk ovf:capacity="150" ovf:capacityAllocationUnits="byte * 2^30" ovf:diskId="vmdisk2" ovf:fileRef="file2" ovf:format="http://www.vmware.com/interfaces/specifications/vmdk.html#streamOptimized" ovf:populatedSize="148116078592"/>
  </DiskSection>
  <NetworkSection>
    <Info>The list of logical networks</Info>
    <Network ovf:name="DVPG_102">
      <Description>The DVPG_102 network</Description>
    </Network>
  </NetworkSection>
  <VirtualSystem ovf:id="Implementacion_02-2">
    <Info>A virtual machine</Info>
    <Name>Implementacion_02-2</Name>
    <OperatingSystemSection ovf:id="122" vmw:osType="windows2019srv_64Guest">
      <Info>The kind of installed guest operating system</Info>
      <Description>Microsoft Windows Server 2019 (64-bit)</Description>
    </OperatingSystemSection>
    <VirtualHardwareSection>
      <Info>Virtual hardware requirements</Info>
      <System>
        <vssd:ElementName>Virtual Hardware Family</vssd:ElementName>
        <vssd:InstanceID>0</vssd:InstanceID>
        <vssd:VirtualSystemIdentifier>Implementacion_02-2</vssd:VirtualSystemIdentifier>
        <vssd:VirtualSystemType>vmx-18</vssd:VirtualSystemType>
      </System>
      <Item>
        <rasd:AllocationUnits>hertz * 10^6</rasd:AllocationUnits>
        <rasd:Description>Number of Virtual CPUs</rasd:Description>
        <rasd:ElementName>2 virtual CPU(s)</rasd:ElementName>
        <rasd:InstanceID>1</rasd:InstanceID>
        <rasd:ResourceType>3</rasd:ResourceType>
        <rasd:VirtualQuantity>2</rasd:VirtualQuantity>
      </Item>
      <Item>
        <rasd:AllocationUnits>byte * 2^20</rasd:AllocationUnits>
        <rasd:Description>Memory Size</rasd:Description>
        <rasd:ElementName>4096MB of memory</rasd:ElementName>
        <rasd:InstanceID>2</rasd:InstanceID>
        <rasd:ResourceType>4</rasd:ResourceType>
        <rasd:VirtualQuantity>4096</rasd:VirtualQuantity>
      </Item>
      <Item>
        <rasd:Address>0</rasd:Address>
        <rasd:Description>SATA Controller</rasd:Description>
        <rasd:ElementName>SATA controller 0</rasd:ElementName>
        <rasd:InstanceID>3</rasd:InstanceID>
        <rasd:ResourceSubType>vmware.sata.ahci</rasd:ResourceSubType>
        <rasd:ResourceType>20</rasd:ResourceType>
        <vmw:Config ovf:required="false" vmw:key="slotInfo.pciSlotNumber" vmw:value="33"/>
      </Item>
      <Item>
        <rasd:Address>0</rasd:Address>
        <rasd:Description>SCSI Controller</rasd:Description>
        <rasd:ElementName>SCSI controller 0</rasd:ElementName>
        <rasd:InstanceID>4</rasd:InstanceID>
        <rasd:ResourceSubType>lsilogicsas</rasd:ResourceSubType>
        <rasd:ResourceType>6</rasd:ResourceType>
        <vmw:Config ovf:required="false" vmw:key="slotInfo.pciSlotNumber" vmw:value="160"/>
      </Item>
      <Item ovf:required="false">
        <rasd:Address>0</rasd:Address>
        <rasd:Description>USB Controller (XHCI)</rasd:Description>
        <rasd:ElementName>USB xHCI controller</rasd:ElementName>
        <rasd:InstanceID>5</rasd:InstanceID>
        <rasd:ResourceSubType>vmware.usb.xhci</rasd:ResourceSubType>
        <rasd:ResourceType>23</rasd:ResourceType>
        <vmw:Config ovf:required="false" vmw:key="slotInfo.pciSlotNumber" vmw:value="224"/>
      </Item>
      <Item>
        <rasd:Address>1</rasd:Address>
        <rasd:Description>IDE Controller</rasd:Description>
        <rasd:ElementName>IDE 1</rasd:ElementName>
        <rasd:InstanceID>6</rasd:InstanceID>
        <rasd:ResourceType>5</rasd:ResourceType>
      </Item>
      <Item>
        <rasd:Address>0</rasd:Address>
        <rasd:Description>IDE Controller</rasd:Description>
        <rasd:ElementName>IDE 0</rasd:ElementName>
        <rasd:InstanceID>7</rasd:InstanceID>
        <rasd:ResourceType>5</rasd:ResourceType>
      </Item>
      <Item ovf:required="false">
        <rasd:AutomaticAllocation>false</rasd:AutomaticAllocation>
        <rasd:ElementName>Video card</rasd:ElementName>
        <rasd:InstanceID>8</rasd:InstanceID>
        <rasd:ResourceType>24</rasd:ResourceType>
        <vmw:Config ovf:required="false" vmw:key="useAutoDetect" vmw:value="true"/>
        <vmw:Config ovf:required="false" vmw:key="videoRamSizeInKB" vmw:value="16384"/>
        <vmw:Config ovf:required="false" vmw:key="enable3DSupport" vmw:value="false"/>
        <vmw:Config ovf:required="false" vmw:key="use3dRenderer" vmw:value="automatic"/>
        <vmw:Config ovf:required="false" vmw:key="graphicsMemorySizeInKB" vmw:value="262144"/>
      </Item>
      <Item ovf:required="false">
        <rasd:AutomaticAllocation>false</rasd:AutomaticAllocation>
        <rasd:ElementName>VMCI device</rasd:ElementName>
        <rasd:InstanceID>9</rasd:InstanceID>
        <rasd:ResourceSubType>vmware.vmci</rasd:ResourceSubType>
        <rasd:ResourceType>1</rasd:ResourceType>
        <vmw:Config ovf:required="false" vmw:key="slotInfo.pciSlotNumber" vmw:value="32"/>
        <vmw:Config ovf:required="false" vmw:key="allowUnrestrictedCommunication" vmw:value="false"/>
      </Item>
      <Item ovf:required="false">
        <rasd:AddressOnParent>0</rasd:AddressOnParent>
        <rasd:AutomaticAllocation>false</rasd:AutomaticAllocation>
        <rasd:ElementName>CD/DVD drive 1</rasd:ElementName>
        <rasd:InstanceID>10</rasd:InstanceID>
        <rasd:Parent>3</rasd:Parent>
        <rasd:ResourceSubType>vmware.cdrom.remoteatapi</rasd:ResourceSubType>
        <rasd:ResourceType>15</rasd:ResourceType>
        <vmw:Config ovf:required="false" vmw:key="connectable.allowGuestControl" vmw:value="true"/>
      </Item>
      <Item>
        <rasd:AddressOnParent>1</rasd:AddressOnParent>
        <rasd:ElementName>Hard disk 1</rasd:ElementName>
        <rasd:HostResource>ovf:/disk/vmdisk1</rasd:HostResource>
        <rasd:InstanceID>11</rasd:InstanceID>
        <rasd:Parent>4</rasd:Parent>
        <rasd:ResourceType>17</rasd:ResourceType>
        <vmw:Config ovf:required="false" vmw:key="backing.writeThrough" vmw:value="false"/>
      </Item>
      <Item>
        <rasd:AddressOnParent>0</rasd:AddressOnParent>
        <rasd:ElementName>Hard disk 2</rasd:ElementName>
        <rasd:HostResource>ovf:/disk/vmdisk2</rasd:HostResource>
        <rasd:InstanceID>12</rasd:InstanceID>
        <rasd:Parent>4</rasd:Parent>
        <rasd:ResourceType>17</rasd:ResourceType>
        <vmw:Config ovf:required="false" vmw:key="backing.writeThrough" vmw:value="false"/>
      </Item>
      <Item>
        <rasd:AddressOnParent>7</rasd:AddressOnParent>
        <rasd:AutomaticAllocation>true</rasd:AutomaticAllocation>
        <rasd:Connection>DVPG_102</rasd:Connection>
        <rasd:Description>VmxNet3 ethernet adapter on "DVPG_102"</rasd:Description>
        <rasd:ElementName>Network adapter 1</rasd:ElementName>
        <rasd:InstanceID>13</rasd:InstanceID>
        <rasd:ResourceSubType>VmxNet3</rasd:ResourceSubType>
        <rasd:ResourceType>10</rasd:ResourceType>
        <vmw:Config ovf:required="false" vmw:key="slotInfo.pciSlotNumber" vmw:value="192"/>
        <vmw:Config ovf:required="false" vmw:key="wakeOnLanEnabled" vmw:value="true"/>
        <vmw:Config ovf:required="false" vmw:key="connectable.allowGuestControl" vmw:value="true"/>
      </Item>
      <vmw:Config ovf:required="false" vmw:key="cpuHotAddEnabled" vmw:value="false"/>
      <vmw:Config ovf:required="false" vmw:key="cpuHotRemoveEnabled" vmw:value="false"/>
      <vmw:Config ovf:required="false" vmw:key="memoryHotAddEnabled" vmw:value="false"/>
      <vmw:Config ovf:required="false" vmw:key="firmware" vmw:value="efi"/>
      <vmw:Config ovf:required="false" vmw:key="cpuAllocation.shares.shares" vmw:value="2000"/>
      <vmw:Config ovf:required="false" vmw:key="cpuAllocation.shares.level" vmw:value="normal"/>
      <vmw:Config ovf:required="false" vmw:key="tools.syncTimeWithHost" vmw:value="false"/>
      <vmw:Config ovf:required="false" vmw:key="tools.syncTimeWithHostAllowed" vmw:value="true"/>
      <vmw:Config ovf:required="false" vmw:key="tools.afterPowerOn" vmw:value="true"/>
      <vmw:Config ovf:required="false" vmw:key="tools.afterResume" vmw:value="true"/>
      <vmw:Config ovf:required="false" vmw:key="tools.beforeGuestShutdown" vmw:value="true"/>
      <vmw:Config ovf:required="false" vmw:key="tools.beforeGuestStandby" vmw:value="true"/>
      <vmw:Config ovf:required="false" vmw:key="tools.toolsUpgradePolicy" vmw:value="manual"/>
      <vmw:Config ovf:required="false" vmw:key="powerOpInfo.powerOffType" vmw:value="soft"/>
      <vmw:Config ovf:required="false" vmw:key="powerOpInfo.resetType" vmw:value="soft"/>
      <vmw:Config ovf:required="false" vmw:key="powerOpInfo.suspendType" vmw:value="soft"/>
      <vmw:Config ovf:required="false" vmw:key="nestedHVEnabled" vmw:value="false"/>
      <vmw:Config ovf:required="false" vmw:key="vPMCEnabled" vmw:value="false"/>
      <vmw:Config ovf:required="false" vmw:key="virtualICH7MPresent" vmw:value="false"/>
      <vmw:Config ovf:required="false" vmw:key="virtualSMCPresent" vmw:value="false"/>
      <vmw:Config ovf:required="false" vmw:key="flags.vvtdEnabled" vmw:value="false"/>
      <vmw:Config ovf:required="false" vmw:key="flags.vbsEnabled" vmw:value="false"/>
      <vmw:Config ovf:required="false" vmw:key="bootOptions.efiSecureBootEnabled" vmw:value="true"/>
      <vmw:Config ovf:required="false" vmw:key="powerOpInfo.standbyAction" vmw:value="checkpoint"/>
      <vmw:ExtraConfig ovf:required="false" vmw:key="nvram" vmw:value="ovf:/file/file3"/>
      <vmw:ExtraConfig ovf:required="false" vmw:key="svga.autodetect" vmw:value="TRUE"/>
    </VirtualHardwareSection>
  </VirtualSystem>
</Envelope>

the complete log is:
2023-02-27 11:08:57,658-05 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-38) [3eba88b2-7b98-48d4-8488-2f3331fb41e6] EVENT_ID: ANSIBLE_RUNNER_EVENT_NOTIFICATION(559), Query OVA info. Run query script.
2023-02-27 11:08:57,665-05 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-38) [3eba88b2-7b98-48d4-8488-2f3331fb41e6] EVENT_ID: ANSIBLE_RUNNER_EVENT_NOTIFICATION(559), Query OVA info. Remove temp directory.
2023-02-27 11:09:00,676-05 WARN  [org.ovirt.engine.core.utils.ovf.OvfReader] (default task-38) [3eba88b2-7b98-48d4-8488-2f3331fb41e6] could not retrieve volume id of file1 from ovf, generating new guid
2023-02-27 11:09:00,676-05 WARN  [org.ovirt.engine.core.utils.ovf.OvfReader] (default task-38) [3eba88b2-7b98-48d4-8488-2f3331fb41e6] could not retrieve disk id of vmdisk1 from ovf, generating new guid
2023-02-27 11:09:00,677-05 WARN  [org.ovirt.engine.core.utils.ovf.OvfReader] (default task-38) [3eba88b2-7b98-48d4-8488-2f3331fb41e6] could not retrieve volume id of file2 from ovf, generating new guid
2023-02-27 11:09:00,677-05 WARN  [org.ovirt.engine.core.utils.ovf.OvfReader] (default task-38) [3eba88b2-7b98-48d4-8488-2f3331fb41e6] could not retrieve disk id of vmdisk2 from ovf, generating new guid
2023-02-27 11:09:19,408-05 INFO  [org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand] (default task-38) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Lock Acquired to object 'EngineLock:{exclusiveLocks='[557421e0-d5f8-4caa-a84c-d1df24cb5a08=VM, 8e4c49b1-5078-4c89-9ccc-237b95b5dce4=DISK, Implementacion_02-2=VM_NAME]', sharedLocks=''}'
2023-02-27 11:09:19,493-05 INFO  [org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand] (EE-ManagedThreadFactory-engine-Thread-31181) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Running command: ImportVmFromOvaCommand internal: false. Entities affected :  ID: 5d22cf63-93e3-4d4d-9e53-395608923d6d Type: ClusterAction group CREATE_VM with role type USER,  ID: 588d888c-ff14-4191-a5bc-a38f65dc29e9 Type: StorageAction group IMPORT_EXPORT_VM with role type ADMIN
2023-02-27 11:09:19,603-05 INFO  [org.ovirt.engine.core.bll.storage.disk.AddDiskCommand] (EE-ManagedThreadFactory-engine-Thread-31181) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Running command: AddDiskCommand internal: true. Entities affected :  ID: 557421e0-d5f8-4caa-a84c-d1df24cb5a08 Type: VMAction group CONFIGURE_VM_STORAGE with role type USER,  ID: 588d888c-ff14-4191-a5bc-a38f65dc29e9 Type: StorageAction group CREATE_DISK with role type USER
2023-02-27 11:09:19,619-05 INFO  [org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand] (EE-ManagedThreadFactory-engine-Thread-31181) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Running command: AddImageFromScratchCommand internal: true. Entities affected :  ID: 588d888c-ff14-4191-a5bc-a38f65dc29e9 Type: Storage
2023-02-27 11:09:19,636-05 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateVolumeVDSCommand] (EE-ManagedThreadFactory-engine-Thread-31181) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] START, CreateVolumeVDSCommand( CreateVolumeVDSCommandParameters:{storagePoolId='26a41fba-0334-4b87-9b87-4ac6e86d32f7', ignoreFailoverLimit='false', storageDomainId='588d888c-ff14-4191-a5bc-a38f65dc29e9', imageGroupId='096444a2-0f21-4ea0-b4c3-3a4604d232bf', imageSizeInBytes='161061273600', volumeFormat='COW', newImageId='213d572b-c37f-484e-ad50-b513ae68d455', imageType='Sparse', newImageDescription='{"DiskAlias":"vmdisk1","DiskDescription":""}', imageInitialSizeInBytes='117837135872', imageId='00000000-0000-0000-0000-000000000000', sourceImageGroupId='00000000-0000-0000-0000-000000000000', shouldAddBitmaps='false', legal='true', sequenceNumber='1', bitmap='null'}), log id: 50719fb2
2023-02-27 11:09:19,673-05 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateVolumeVDSCommand] (EE-ManagedThreadFactory-engine-Thread-31181) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] FINISH, CreateVolumeVDSCommand, return: 213d572b-c37f-484e-ad50-b513ae68d455, log id: 50719fb2
2023-02-27 11:09:19,678-05 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-31181) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command '9850b22f-13fd-4c17-afef-83483140d670'
2023-02-27 11:09:19,678-05 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (EE-ManagedThreadFactory-engine-Thread-31181) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] CommandMultiAsyncTasks::attachTask: Attaching task 'b168c512-9335-405d-b239-05b6e663aaa5' to command '9850b22f-13fd-4c17-afef-83483140d670'.
2023-02-27 11:09:19,689-05 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (EE-ManagedThreadFactory-engine-Thread-31181) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Adding task 'b168c512-9335-405d-b239-05b6e663aaa5' (Parent Command 'AddImageFromScratch', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters'), polling hasn't started yet..
2023-02-27 11:09:19,697-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engine-Thread-31181) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] BaseAsyncTask::startPollingTask: Starting to poll task 'b168c512-9335-405d-b239-05b6e663aaa5'.
2023-02-27 11:09:19,718-05 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-31181) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] EVENT_ID: ADD_DISK_INTERNAL(2,036), Add-Disk operation of 'vmdisk1' was initiated by the system.
2023-02-27 11:09:19,800-05 INFO  [org.ovirt.engine.core.bll.storage.disk.AddDiskCommand] (EE-ManagedThreadFactory-engine-Thread-31181) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Running command: AddDiskCommand internal: true. Entities affected :  ID: 557421e0-d5f8-4caa-a84c-d1df24cb5a08 Type: VMAction group CONFIGURE_VM_STORAGE with role type USER,  ID: 588d888c-ff14-4191-a5bc-a38f65dc29e9 Type: StorageAction group CREATE_DISK with role type USER
2023-02-27 11:09:19,813-05 INFO  [org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand] (EE-ManagedThreadFactory-engine-Thread-31181) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Running command: AddImageFromScratchCommand internal: true. Entities affected :  ID: 588d888c-ff14-4191-a5bc-a38f65dc29e9 Type: Storage
2023-02-27 11:09:19,822-05 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateVolumeVDSCommand] (EE-ManagedThreadFactory-engine-Thread-31181) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] START, CreateVolumeVDSCommand( CreateVolumeVDSCommandParameters:{storagePoolId='26a41fba-0334-4b87-9b87-4ac6e86d32f7', ignoreFailoverLimit='false', storageDomainId='588d888c-ff14-4191-a5bc-a38f65dc29e9', imageGroupId='7ae5ded5-d0c8-41f8-ab30-401aef4a0e93', imageSizeInBytes='161061273600', volumeFormat='COW', newImageId='482e7dc2-faf9-480b-bbf9-2ece1a12cc5a', imageType='Sparse', newImageDescription='{"DiskAlias":"vmdisk2","DiskDescription":""}', imageInitialSizeInBytes='148116078592', imageId='00000000-0000-0000-0000-000000000000', sourceImageGroupId='00000000-0000-0000-0000-000000000000', shouldAddBitmaps='false', legal='true', sequenceNumber='1', bitmap='null'}), log id: 7ca68b15
2023-02-27 11:09:19,846-05 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.CreateVolumeVDSCommand] (EE-ManagedThreadFactory-engine-Thread-31181) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] FINISH, CreateVolumeVDSCommand, return: 482e7dc2-faf9-480b-bbf9-2ece1a12cc5a, log id: 7ca68b15
2023-02-27 11:09:19,850-05 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-31181) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 'c75cb919-ba4b-481c-8dcd-733573abe129'
2023-02-27 11:09:19,850-05 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (EE-ManagedThreadFactory-engine-Thread-31181) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] CommandMultiAsyncTasks::attachTask: Attaching task '9c06a6b6-d2e4-4fe6-a370-d871fc312a16' to command 'c75cb919-ba4b-481c-8dcd-733573abe129'.
2023-02-27 11:09:19,861-05 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (EE-ManagedThreadFactory-engine-Thread-31181) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Adding task '9c06a6b6-d2e4-4fe6-a370-d871fc312a16' (Parent Command 'AddImageFromScratch', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters'), polling hasn't started yet..
2023-02-27 11:09:19,867-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engine-Thread-31181) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] BaseAsyncTask::startPollingTask: Starting to poll task '9c06a6b6-d2e4-4fe6-a370-d871fc312a16'.
2023-02-27 11:09:19,888-05 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-31181) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] EVENT_ID: ADD_DISK_INTERNAL(2,036), Add-Disk operation of 'vmdisk2' was initiated by the system.
2023-02-27 11:09:19,890-05 INFO  [org.ovirt.engine.core.bll.storage.disk.AddDiskCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-91) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Command 'AddDisk' (id: 'bd6a7f06-1aeb-407a-8dbc-837280be9fb6') waiting on child command id: 'c75cb919-ba4b-481c-8dcd-733573abe129' type:'AddImageFromScratch' to complete
2023-02-27 11:09:19,891-05 INFO  [org.ovirt.engine.core.bll.storage.disk.AddDiskCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-91) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Command 'AddDisk' (id: 'd0158085-44c8-4dae-8108-6704c16154f7') waiting on child command id: '9850b22f-13fd-4c17-afef-83483140d670' type:'AddImageFromScratch' to complete
2023-02-27 11:09:19,909-05 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-31181) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] EVENT_ID: IMPORTEXPORT_STARTING_IMPORT_VM(1,165), Starting to import Vm Implementacion_02-2 to Data Center dc_rcloud, Cluster cl_rcloud
2023-02-27 11:09:21,857-05 INFO  [org.ovirt.engine.core.bll.provider.network.SyncNetworkProviderCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-46) [7346ec67] Lock Acquired to object 'EngineLock:{exclusiveLocks='[0eddd27f-e2d0-4e5f-9fbb-00fd7409c165=PROVIDER]', sharedLocks=''}'
2023-02-27 11:09:21,873-05 INFO  [org.ovirt.engine.core.bll.provider.network.SyncNetworkProviderCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-46) [7346ec67] Running command: SyncNetworkProviderCommand internal: true.
2023-02-27 11:09:21,877-05 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-46) [7346ec67] EVENT_ID: PROVIDER_SYNCHRONIZATION_STARTED(223), Provider ovirt-provider-ovn synchronization started.
2023-02-27 11:09:21,893-05 INFO  [org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-100) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Command 'ImportVmFromOva' (id: 'f94e159b-a638-4f81-8af0-8b1fb8653170') waiting on child command id: 'd0158085-44c8-4dae-8108-6704c16154f7' type:'AddDisk' to complete
2023-02-27 11:09:21,894-05 INFO  [org.ovirt.engine.core.bll.storage.disk.AddDiskCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-100) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Command 'AddDisk' (id: 'bd6a7f06-1aeb-407a-8dbc-837280be9fb6') waiting on child command id: 'c75cb919-ba4b-481c-8dcd-733573abe129' type:'AddImageFromScratch' to complete
2023-02-27 11:09:21,894-05 INFO  [org.ovirt.engine.core.bll.storage.disk.AddDiskCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-100) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Command 'AddDisk' (id: 'd0158085-44c8-4dae-8108-6704c16154f7') waiting on child command id: '9850b22f-13fd-4c17-afef-83483140d670' type:'AddImageFromScratch' to complete
2023-02-27 11:09:22,029-05 INFO  [org.ovirt.engine.core.sso.service.ExternalOIDCService] (default task-38) [] User admin@ovirt@internalkeycloak-authz with profile [internalsso] successfully logged into external OP with scopes: ovirt-app-api ovirt-ext=token-info:authz-search ovirt-ext=token-info:public-authz-search ovirt-ext=token-info:validate ovirt-ext=token:password-access
2023-02-27 11:09:22,142-05 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-46) [7346ec67] EVENT_ID: PROVIDER_SYNCHRONIZATION_ENDED(224), Provider ovirt-provider-ovn synchronization ended.
2023-02-27 11:09:22,143-05 INFO  [org.ovirt.engine.core.bll.provider.network.SyncNetworkProviderCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-46) [7346ec67] Lock freed to object 'EngineLock:{exclusiveLocks='[0eddd27f-e2d0-4e5f-9fbb-00fd7409c165=PROVIDER]', sharedLocks=''}'
2023-02-27 11:09:25,899-05 INFO  [org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-94) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Command 'ImportVmFromOva' (id: 'f94e159b-a638-4f81-8af0-8b1fb8653170') waiting on child command id: 'd0158085-44c8-4dae-8108-6704c16154f7' type:'AddDisk' to complete
2023-02-27 11:09:25,899-05 INFO  [org.ovirt.engine.core.bll.storage.disk.AddDiskCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-94) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Command 'AddDisk' (id: 'bd6a7f06-1aeb-407a-8dbc-837280be9fb6') waiting on child command id: 'c75cb919-ba4b-481c-8dcd-733573abe129' type:'AddImageFromScratch' to complete
2023-02-27 11:09:25,900-05 INFO  [org.ovirt.engine.core.bll.storage.disk.AddDiskCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-94) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Command 'AddDisk' (id: 'd0158085-44c8-4dae-8108-6704c16154f7') waiting on child command id: '9850b22f-13fd-4c17-afef-83483140d670' type:'AddImageFromScratch' to complete
2023-02-27 11:09:26,075-05 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-20) [] Polling and updating Async Tasks: 2 tasks, 2 tasks to poll now
2023-02-27 11:09:26,082-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-20) [] SPMAsyncTask::PollTask: Polling task '9c06a6b6-d2e4-4fe6-a370-d871fc312a16' (Parent Command 'AddImageFromScratch', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') returned status 'finished', result 'success'.
2023-02-27 11:09:26,088-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-20) [] BaseAsyncTask::onTaskEndSuccess: Task '9c06a6b6-d2e4-4fe6-a370-d871fc312a16' (Parent Command 'AddImageFromScratch', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') ended successfully.
2023-02-27 11:09:26,090-05 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-20) [] CommandAsyncTask::endActionIfNecessary: All tasks of command 'c75cb919-ba4b-481c-8dcd-733573abe129' has ended -> executing 'endAction'
2023-02-27 11:09:26,090-05 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-20) [] CommandAsyncTask::endAction: Ending action for '1' tasks (command ID: 'c75cb919-ba4b-481c-8dcd-733573abe129'): calling endAction '.
2023-02-27 11:09:26,091-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-20) [] SPMAsyncTask::PollTask: Polling task 'b168c512-9335-405d-b239-05b6e663aaa5' (Parent Command 'AddImageFromScratch', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') returned status 'finished', result 'success'.
2023-02-27 11:09:26,091-05 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-31184) [] CommandAsyncTask::endCommandAction [within thread] context: Attempting to endAction 'AddImageFromScratch',
2023-02-27 11:09:26,095-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-20) [] BaseAsyncTask::onTaskEndSuccess: Task 'b168c512-9335-405d-b239-05b6e663aaa5' (Parent Command 'AddImageFromScratch', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters') ended successfully.
2023-02-27 11:09:26,096-05 INFO  [org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand] (EE-ManagedThreadFactory-engine-Thread-31184) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Command [id=c75cb919-ba4b-481c-8dcd-733573abe129]: Updating status to 'SUCCEEDED', The command end method logic will be executed by one of its parent commands.
2023-02-27 11:09:26,096-05 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-31184) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type 'AddImageFromScratch' completed, handling the result.
2023-02-27 11:09:26,096-05 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-31184) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type 'AddImageFromScratch' succeeded, clearing tasks.
2023-02-27 11:09:26,096-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engine-Thread-31184) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] SPMAsyncTask::ClearAsyncTask: Attempting to clear task '9c06a6b6-d2e4-4fe6-a370-d871fc312a16'
2023-02-27 11:09:26,096-05 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-20) [] CommandAsyncTask::endActionIfNecessary: All tasks of command '9850b22f-13fd-4c17-afef-83483140d670' has ended -> executing 'endAction'
2023-02-27 11:09:26,096-05 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-31184) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] START, SPMClearTaskVDSCommand( SPMTaskGuidBaseVDSCommandParameters:{storagePoolId='26a41fba-0334-4b87-9b87-4ac6e86d32f7', ignoreFailoverLimit='false', taskId='9c06a6b6-d2e4-4fe6-a370-d871fc312a16'}), log id: 5fe37e69
2023-02-27 11:09:26,096-05 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-20) [] CommandAsyncTask::endAction: Ending action for '1' tasks (command ID: '9850b22f-13fd-4c17-afef-83483140d670'): calling endAction '.
2023-02-27 11:09:26,097-05 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-31185) [] CommandAsyncTask::endCommandAction [within thread] context: Attempting to endAction 'AddImageFromScratch',
2023-02-27 11:09:26,097-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-31184) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] START, HSMClearTaskVDSCommand(HostName = ovirtnodo01.redsiscloud.loc, HSMTaskGuidBaseVDSCommandParameters:{hostId='2b47d08d-16f6-4d5b-bdcd-8c8ac6f30629', taskId='9c06a6b6-d2e4-4fe6-a370-d871fc312a16'}), log id: 3673d24
2023-02-27 11:09:26,099-05 INFO  [org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand] (EE-ManagedThreadFactory-engine-Thread-31185) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Command [id=9850b22f-13fd-4c17-afef-83483140d670]: Updating status to 'SUCCEEDED', The command end method logic will be executed by one of its parent commands.
2023-02-27 11:09:26,099-05 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-31185) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type 'AddImageFromScratch' completed, handling the result.
2023-02-27 11:09:26,099-05 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-31185) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] CommandAsyncTask::HandleEndActionResult [within thread]: endAction for action type 'AddImageFromScratch' succeeded, clearing tasks.
2023-02-27 11:09:26,099-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engine-Thread-31185) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] SPMAsyncTask::ClearAsyncTask: Attempting to clear task 'b168c512-9335-405d-b239-05b6e663aaa5'
2023-02-27 11:09:26,100-05 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-31185) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] START, SPMClearTaskVDSCommand( SPMTaskGuidBaseVDSCommandParameters:{storagePoolId='26a41fba-0334-4b87-9b87-4ac6e86d32f7', ignoreFailoverLimit='false', taskId='b168c512-9335-405d-b239-05b6e663aaa5'}), log id: 20d1c555
2023-02-27 11:09:26,105-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-31184) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] FINISH, HSMClearTaskVDSCommand, return: , log id: 3673d24
2023-02-27 11:09:26,105-05 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-31184) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] FINISH, SPMClearTaskVDSCommand, return: , log id: 5fe37e69
2023-02-27 11:09:26,106-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-31185) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] START, HSMClearTaskVDSCommand(HostName = ovirtnodo01.redsiscloud.loc, HSMTaskGuidBaseVDSCommandParameters:{hostId='2b47d08d-16f6-4d5b-bdcd-8c8ac6f30629', taskId='b168c512-9335-405d-b239-05b6e663aaa5'}), log id: 162b7940
2023-02-27 11:09:26,107-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engine-Thread-31184) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] BaseAsyncTask::removeTaskFromDB: Removed task '9c06a6b6-d2e4-4fe6-a370-d871fc312a16' from DataBase
2023-02-27 11:09:26,107-05 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-31184) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity 'c75cb919-ba4b-481c-8dcd-733573abe129'
2023-02-27 11:09:26,117-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-31185) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] FINISH, HSMClearTaskVDSCommand, return: , log id: 162b7940
2023-02-27 11:09:26,118-05 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (EE-ManagedThreadFactory-engine-Thread-31185) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] FINISH, SPMClearTaskVDSCommand, return: , log id: 20d1c555
2023-02-27 11:09:26,120-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedThreadFactory-engine-Thread-31185) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] BaseAsyncTask::removeTaskFromDB: Removed task 'b168c512-9335-405d-b239-05b6e663aaa5' from DataBase
2023-02-27 11:09:26,120-05 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedThreadFactory-engine-Thread-31185) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] CommandAsyncTask::HandleEndActionResult [within thread]: Removing CommandMultiAsyncTasks object for entity '9850b22f-13fd-4c17-afef-83483140d670'
2023-02-27 11:09:33,905-05 INFO  [org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-78) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Command 'ImportVmFromOva' (id: 'f94e159b-a638-4f81-8af0-8b1fb8653170') waiting on child command id: 'd0158085-44c8-4dae-8108-6704c16154f7' type:'AddDisk' to complete
2023-02-27 11:09:33,906-05 INFO  [org.ovirt.engine.core.bll.storage.disk.AddDiskCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-78) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Getting volume info for image '7ae5ded5-d0c8-41f8-ab30-401aef4a0e93/482e7dc2-faf9-480b-bbf9-2ece1a12cc5a'
2023-02-27 11:09:33,918-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-78) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] START, GetVolumeInfoVDSCommand(HostName = ovirtnodo01.redsiscloud.loc, GetVolumeInfoVDSCommandParameters:{hostId='2b47d08d-16f6-4d5b-bdcd-8c8ac6f30629', storagePoolId='26a41fba-0334-4b87-9b87-4ac6e86d32f7', storageDomainId='588d888c-ff14-4191-a5bc-a38f65dc29e9', imageGroupId='7ae5ded5-d0c8-41f8-ab30-401aef4a0e93', imageId='482e7dc2-faf9-480b-bbf9-2ece1a12cc5a'}), log id: 576590a3
2023-02-27 11:09:34,065-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-78) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] FINISH, GetVolumeInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage.DiskImage@e712ba11, log id: 576590a3
2023-02-27 11:09:34,065-05 INFO  [org.ovirt.engine.core.bll.storage.disk.AddDiskCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-78) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Command 'AddDisk' id: 'bd6a7f06-1aeb-407a-8dbc-837280be9fb6' child commands '[c75cb919-ba4b-481c-8dcd-733573abe129]' executions were completed, status 'SUCCEEDED'
2023-02-27 11:09:34,070-05 INFO  [org.ovirt.engine.core.bll.storage.disk.AddDiskCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-78) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Getting volume info for image '096444a2-0f21-4ea0-b4c3-3a4604d232bf/213d572b-c37f-484e-ad50-b513ae68d455'
2023-02-27 11:09:34,083-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-78) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] START, GetVolumeInfoVDSCommand(HostName = ovirtnodo01.redsiscloud.loc, GetVolumeInfoVDSCommandParameters:{hostId='2b47d08d-16f6-4d5b-bdcd-8c8ac6f30629', storagePoolId='26a41fba-0334-4b87-9b87-4ac6e86d32f7', storageDomainId='588d888c-ff14-4191-a5bc-a38f65dc29e9', imageGroupId='096444a2-0f21-4ea0-b4c3-3a4604d232bf', imageId='213d572b-c37f-484e-ad50-b513ae68d455'}), log id: 7cc26313
2023-02-27 11:09:34,108-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-78) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] FINISH, GetVolumeInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage.DiskImage@ed015f5c, log id: 7cc26313
2023-02-27 11:09:34,108-05 INFO  [org.ovirt.engine.core.bll.storage.disk.AddDiskCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-78) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Command 'AddDisk' id: 'd0158085-44c8-4dae-8108-6704c16154f7' child commands '[9850b22f-13fd-4c17-afef-83483140d670]' executions were completed, status 'SUCCEEDED'
2023-02-27 11:09:35,113-05 INFO  [org.ovirt.engine.core.bll.storage.disk.AddDiskCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Ending command 'org.ovirt.engine.core.bll.storage.disk.AddDiskCommand' successfully.
2023-02-27 11:09:35,119-05 INFO  [org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Ending command 'org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand' successfully.
2023-02-27 11:09:35,131-05 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] START, GetImageInfoVDSCommand( GetImageInfoVDSCommandParameters:{storagePoolId='26a41fba-0334-4b87-9b87-4ac6e86d32f7', ignoreFailoverLimit='false', storageDomainId='588d888c-ff14-4191-a5bc-a38f65dc29e9', imageGroupId='7ae5ded5-d0c8-41f8-ab30-401aef4a0e93', imageId='482e7dc2-faf9-480b-bbf9-2ece1a12cc5a'}), log id: 57b964f4
2023-02-27 11:09:35,132-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] START, GetVolumeInfoVDSCommand(HostName = ovirtnodo01.redsiscloud.loc, GetVolumeInfoVDSCommandParameters:{hostId='2b47d08d-16f6-4d5b-bdcd-8c8ac6f30629', storagePoolId='26a41fba-0334-4b87-9b87-4ac6e86d32f7', storageDomainId='588d888c-ff14-4191-a5bc-a38f65dc29e9', imageGroupId='7ae5ded5-d0c8-41f8-ab30-401aef4a0e93', imageId='482e7dc2-faf9-480b-bbf9-2ece1a12cc5a'}), log id: 598fc554
2023-02-27 11:09:35,159-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] FINISH, GetVolumeInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage.DiskImage@e712ba11, log id: 598fc554
2023-02-27 11:09:35,159-05 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage.DiskImage@e712ba11, log id: 57b964f4
2023-02-27 11:09:35,221-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] START, PrepareImageVDSCommand(HostName = ovirtnodo01.redsiscloud.loc, PrepareImageVDSCommandParameters:{hostId='2b47d08d-16f6-4d5b-bdcd-8c8ac6f30629'}), log id: 673ef63a
2023-02-27 11:09:35,661-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] FINISH, PrepareImageVDSCommand, return: PrepareImageReturn:{status='Status [code=0, message=Done]'}, log id: 673ef63a
2023-02-27 11:09:35,662-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetQemuImageInfoVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] START, GetQemuImageInfoVDSCommand(HostName = ovirtnodo01.redsiscloud.loc, GetVolumeInfoVDSCommandParameters:{hostId='2b47d08d-16f6-4d5b-bdcd-8c8ac6f30629', storagePoolId='26a41fba-0334-4b87-9b87-4ac6e86d32f7', storageDomainId='588d888c-ff14-4191-a5bc-a38f65dc29e9', imageGroupId='7ae5ded5-d0c8-41f8-ab30-401aef4a0e93', imageId='482e7dc2-faf9-480b-bbf9-2ece1a12cc5a'}), log id: 75196914
2023-02-27 11:09:35,682-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetQemuImageInfoVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] FINISH, GetQemuImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage.QemuImageInfo@203d7f68, log id: 75196914
2023-02-27 11:09:35,683-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.TeardownImageVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] START, TeardownImageVDSCommand(HostName = ovirtnodo01.redsiscloud.loc, ImageActionsVDSCommandParameters:{hostId='2b47d08d-16f6-4d5b-bdcd-8c8ac6f30629'}), log id: 41ad864
2023-02-27 11:09:35,935-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.TeardownImageVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] FINISH, TeardownImageVDSCommand, return: StatusReturn:{status='Status [code=0, message=Done]'}, log id: 41ad864
2023-02-27 11:09:36,003-05 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [] EVENT_ID: USER_ADD_DISK_TO_VM_FINISHED_SUCCESS(97), The disk vmdisk2 was successfully added to VM Implementacion_02-2.
2023-02-27 11:09:36,005-05 INFO  [org.ovirt.engine.core.bll.storage.disk.AddDiskCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Ending command 'org.ovirt.engine.core.bll.storage.disk.AddDiskCommand' successfully.
2023-02-27 11:09:36,009-05 INFO  [org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Ending command 'org.ovirt.engine.core.bll.storage.disk.image.AddImageFromScratchCommand' successfully.
2023-02-27 11:09:36,010-05 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] START, GetImageInfoVDSCommand( GetImageInfoVDSCommandParameters:{storagePoolId='26a41fba-0334-4b87-9b87-4ac6e86d32f7', ignoreFailoverLimit='false', storageDomainId='588d888c-ff14-4191-a5bc-a38f65dc29e9', imageGroupId='096444a2-0f21-4ea0-b4c3-3a4604d232bf', imageId='213d572b-c37f-484e-ad50-b513ae68d455'}), log id: 1393bd54
2023-02-27 11:09:36,011-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] START, GetVolumeInfoVDSCommand(HostName = ovirtnodo01.redsiscloud.loc, GetVolumeInfoVDSCommandParameters:{hostId='2b47d08d-16f6-4d5b-bdcd-8c8ac6f30629', storagePoolId='26a41fba-0334-4b87-9b87-4ac6e86d32f7', storageDomainId='588d888c-ff14-4191-a5bc-a38f65dc29e9', imageGroupId='096444a2-0f21-4ea0-b4c3-3a4604d232bf', imageId='213d572b-c37f-484e-ad50-b513ae68d455'}), log id: 3d6a9c56
2023-02-27 11:09:36,038-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetVolumeInfoVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] FINISH, GetVolumeInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage.DiskImage@ed015f5c, log id: 3d6a9c56
2023-02-27 11:09:36,039-05 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage.DiskImage@ed015f5c, log id: 1393bd54
2023-02-27 11:09:36,052-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] START, PrepareImageVDSCommand(HostName = ovirtnodo01.redsiscloud.loc, PrepareImageVDSCommandParameters:{hostId='2b47d08d-16f6-4d5b-bdcd-8c8ac6f30629'}), log id: 700e12bc
2023-02-27 11:09:36,473-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] FINISH, PrepareImageVDSCommand, return: PrepareImageReturn:{status='Status [code=0, message=Done]'}, log id: 700e12bc
2023-02-27 11:09:36,474-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetQemuImageInfoVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] START, GetQemuImageInfoVDSCommand(HostName = ovirtnodo01.redsiscloud.loc, GetVolumeInfoVDSCommandParameters:{hostId='2b47d08d-16f6-4d5b-bdcd-8c8ac6f30629', storagePoolId='26a41fba-0334-4b87-9b87-4ac6e86d32f7', storageDomainId='588d888c-ff14-4191-a5bc-a38f65dc29e9', imageGroupId='096444a2-0f21-4ea0-b4c3-3a4604d232bf', imageId='213d572b-c37f-484e-ad50-b513ae68d455'}), log id: 4ac0f47b
2023-02-27 11:09:36,494-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetQemuImageInfoVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] FINISH, GetQemuImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage.QemuImageInfo@44b063f5, log id: 4ac0f47b
2023-02-27 11:09:36,495-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.TeardownImageVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] START, TeardownImageVDSCommand(HostName = ovirtnodo01.redsiscloud.loc, ImageActionsVDSCommandParameters:{hostId='2b47d08d-16f6-4d5b-bdcd-8c8ac6f30629'}), log id: 5615381f
2023-02-27 11:09:36,727-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.TeardownImageVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] FINISH, TeardownImageVDSCommand, return: StatusReturn:{status='Status [code=0, message=Done]'}, log id: 5615381f
2023-02-27 11:09:36,750-05 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-54) [] EVENT_ID: USER_ADD_DISK_TO_VM_FINISHED_SUCCESS(97), The disk vmdisk1 was successfully added to VM Implementacion_02-2.
2023-02-27 11:09:37,798-05 INFO  [org.ovirt.engine.core.bll.exportimport.ConvertOvaCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-51) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Running command: ConvertOvaCommand internal: true. Entities affected :  ID: 557421e0-d5f8-4caa-a84c-d1df24cb5a08 Type: VM
2023-02-27 11:09:37,829-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-51) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] START, PrepareImageVDSCommand(HostName = ovirtnodo01.redsiscloud.loc, PrepareImageVDSCommandParameters:{hostId='2b47d08d-16f6-4d5b-bdcd-8c8ac6f30629'}), log id: 649bcef9
2023-02-27 11:09:38,286-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.PrepareImageVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-51) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] FINISH, PrepareImageVDSCommand, return: PrepareImageReturn:{status='Status [code=0, message=Done]'}, log id: 649bcef9
2023-02-27 11:09:38,288-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConvertOvaVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-51) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] START, ConvertOvaVDSCommand(HostName = ovirtnodo01.redsiscloud.loc, ConvertOvaVDSParameters:{hostId='2b47d08d-16f6-4d5b-bdcd-8c8ac6f30629', ovaPath='/root/w2_ovfs/ovf/Implementacion_02-2', vmName='Implementacion_02-2', storageDomainId='588d888c-ff14-4191-a5bc-a38f65dc29e9', storagePoolId='26a41fba-0334-4b87-9b87-4ac6e86d32f7', virtioIsoPath='/rhev/data-center/mnt/blockSD/7f20ff07-e000-462e-9471-7efe882687c6/images/8e4c49b1-5078-4c89-9ccc-237b95b5dce4/5d21a9de-5a4d-40e9-93e0-989174dcb912', Disk0='096444a2-0f21-4ea0-b4c3-3a4604d232bf', Disk1='7ae5ded5-d0c8-41f8-ab30-401aef4a0e93'}), log id: 780d2b61
2023-02-27 11:09:38,326-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConvertOvaVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-51) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] FINISH, ConvertOvaVDSCommand, return: 557421e0-d5f8-4caa-a84c-d1df24cb5a08, log id: 780d2b61
2023-02-27 11:09:40,344-05 INFO  [org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-35) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Command 'ImportVmFromOva' (id: 'f94e159b-a638-4f81-8af0-8b1fb8653170') waiting on child command id: '02556fe8-58c7-438f-8fd1-29a0df677b51' type:'ConvertOva' to complete
2023-02-27 11:09:41,345-05 INFO  [org.ovirt.engine.core.bll.exportimport.ConvertVmCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-59) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Conversion of VM from external environment failed: Job '557421e0-d5f8-4caa-a84c-d1df24cb5a08' process failed exit-code: 1
2023-02-27 11:09:42,353-05 ERROR [org.ovirt.engine.core.bll.exportimport.ConvertOvaCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-18) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Ending command 'org.ovirt.engine.core.bll.exportimport.ConvertOvaCommand' with failure.
2023-02-27 11:09:42,354-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DeleteV2VJobVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-18) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] START, DeleteV2VJobVDSCommand(HostName = ovirtnodo01.redsiscloud.loc, VdsAndVmIDVDSParametersBase:{hostId='2b47d08d-16f6-4d5b-bdcd-8c8ac6f30629', vmId='557421e0-d5f8-4caa-a84c-d1df24cb5a08'}), log id: 65e6b22b
2023-02-27 11:09:42,360-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.DeleteV2VJobVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-18) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] FINISH, DeleteV2VJobVDSCommand, return: , log id: 65e6b22b
2023-02-27 11:09:42,374-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.TeardownImageVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-18) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] START, TeardownImageVDSCommand(HostName = ovirtnodo01.redsiscloud.loc, ImageActionsVDSCommandParameters:{hostId='2b47d08d-16f6-4d5b-bdcd-8c8ac6f30629'}), log id: 5cc711f5
2023-02-27 11:09:42,608-05 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.TeardownImageVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-18) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] FINISH, TeardownImageVDSCommand, return: StatusReturn:{status='Status [code=0, message=Done]'}, log id: 5cc711f5
2023-02-27 11:09:42,622-05 INFO  [org.ovirt.engine.core.bll.SerialChildCommandsExecutionCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-18) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Command 'ImportVmFromOva' id: 'f94e159b-a638-4f81-8af0-8b1fb8653170' child commands '[d0158085-44c8-4dae-8108-6704c16154f7, bd6a7f06-1aeb-407a-8dbc-837280be9fb6, 02556fe8-58c7-438f-8fd1-29a0df677b51]' executions were completed, status 'FAILED'
2023-02-27 11:09:43,639-05 ERROR [org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [90ee1c89-6cfb-4632-837b-781d5a32ed4c] Ending command 'org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand' with failure.
2023-02-27 11:09:43,661-05 INFO  [org.ovirt.engine.core.bll.storage.disk.image.RemoveAllVmImagesCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] Running command: RemoveAllVmImagesCommand internal: true. Entities affected :  ID: 557421e0-d5f8-4caa-a84c-d1df24cb5a08 Type: VM
2023-02-27 11:09:43,684-05 INFO  [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] Running command: RemoveImageCommand internal: true. Entities affected :  ID: 00000000-0000-0000-0000-000000000000 Type: Storage
2023-02-27 11:09:43,693-05 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] START, DeleteImageGroupVDSCommand( DeleteImageGroupVDSCommandParameters:{storagePoolId='26a41fba-0334-4b87-9b87-4ac6e86d32f7', ignoreFailoverLimit='false', storageDomainId='588d888c-ff14-4191-a5bc-a38f65dc29e9', imageGroupId='096444a2-0f21-4ea0-b4c3-3a4604d232bf', postZeros='false', discard='true', forceDelete='false'}), log id: 6ae771f2
2023-02-27 11:09:43,938-05 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] FINISH, DeleteImageGroupVDSCommand, return: , log id: 6ae771f2
2023-02-27 11:09:43,940-05 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command '1fc4fb29-5034-49f2-a991-9eb10a4f3f63'
2023-02-27 11:09:43,940-05 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] CommandMultiAsyncTasks::attachTask: Attaching task '002baef1-b642-49b4-9eea-065c311d57b1' to command '1fc4fb29-5034-49f2-a991-9eb10a4f3f63'.
2023-02-27 11:09:43,949-05 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] Adding task '002baef1-b642-49b4-9eea-065c311d57b1' (Parent Command 'RemoveImage', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters'), polling hasn't started yet..
2023-02-27 11:09:43,953-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] BaseAsyncTask::startPollingTask: Starting to poll task '002baef1-b642-49b4-9eea-065c311d57b1'.
2023-02-27 11:09:43,954-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] BaseAsyncTask::startPollingTask: Starting to poll task '002baef1-b642-49b4-9eea-065c311d57b1'.
2023-02-27 11:09:43,972-05 INFO  [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] Running command: RemoveImageCommand internal: true. Entities affected :  ID: 00000000-0000-0000-0000-000000000000 Type: Storage
2023-02-27 11:09:43,980-05 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] START, DeleteImageGroupVDSCommand( DeleteImageGroupVDSCommandParameters:{storagePoolId='26a41fba-0334-4b87-9b87-4ac6e86d32f7', ignoreFailoverLimit='false', storageDomainId='588d888c-ff14-4191-a5bc-a38f65dc29e9', imageGroupId='7ae5ded5-d0c8-41f8-ab30-401aef4a0e93', postZeros='false', discard='true', forceDelete='false'}), log id: 3f49936
2023-02-27 11:09:44,317-05 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.DeleteImageGroupVDSCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] FINISH, DeleteImageGroupVDSCommand, return: , log id: 3f49936
2023-02-27 11:09:44,319-05 INFO  [org.ovirt.engine.core.bll.tasks.CommandAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] CommandAsyncTask::Adding CommandMultiAsyncTasks object for command 'c2f34732-9878-4cbf-bca3-1511ce145e34'
2023-02-27 11:09:44,319-05 INFO  [org.ovirt.engine.core.bll.CommandMultiAsyncTasks] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] CommandMultiAsyncTasks::attachTask: Attaching task '3679c4b7-aea9-4fbd-804a-20f479aed410' to command 'c2f34732-9878-4cbf-bca3-1511ce145e34'.
2023-02-27 11:09:44,342-05 INFO  [org.ovirt.engine.core.bll.tasks.AsyncTaskManager] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] Adding task '3679c4b7-aea9-4fbd-804a-20f479aed410' (Parent Command 'RemoveImage', Parameters Type 'org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters'), polling hasn't started yet..
2023-02-27 11:09:44,352-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] BaseAsyncTask::startPollingTask: Starting to poll task '3679c4b7-aea9-4fbd-804a-20f479aed410'.
2023-02-27 11:09:44,352-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] BaseAsyncTask::startPollingTask: Starting to poll task '3679c4b7-aea9-4fbd-804a-20f479aed410'.
2023-02-27 11:09:44,371-05 INFO  [org.ovirt.engine.core.bll.CommandCompensator] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] Command [id=f94e159b-a638-4f81-8af0-8b1fb8653170]: Compensating NEW_ENTITY_ID of org.ovirt.engine.core.common.businessentities.network.VmNetworkStatistics; snapshot: 56850e5f-79f5-4414-8381-62ea2f81bf94.
2023-02-27 11:09:44,372-05 INFO  [org.ovirt.engine.core.bll.CommandCompensator] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] Command [id=f94e159b-a638-4f81-8af0-8b1fb8653170]: Compensating NEW_ENTITY_ID of org.ovirt.engine.core.common.businessentities.network.VmNetworkInterface; snapshot: 56850e5f-79f5-4414-8381-62ea2f81bf94.
2023-02-27 11:09:44,373-05 INFO  [org.ovirt.engine.core.bll.CommandCompensator] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] Command [id=f94e159b-a638-4f81-8af0-8b1fb8653170]: Compensating TRANSIENT_ENTITY of org.ovirt.engine.core.common.businessentities.ReleaseMacsTransientCompensation; snapshot: org.ovirt.engine.core.common.businessentities.ReleaseMacsTransientCompensation@2a96e51.
2023-02-27 11:09:44,374-05 INFO  [org.ovirt.engine.core.bll.CommandCompensator] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] Command [id=f94e159b-a638-4f81-8af0-8b1fb8653170]: Compensating NEW_ENTITY_ID of org.ovirt.engine.core.common.businessentities.VmStatistics; snapshot: 557421e0-d5f8-4caa-a84c-d1df24cb5a08.
2023-02-27 11:09:44,375-05 INFO  [org.ovirt.engine.core.bll.CommandCompensator] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] Command [id=f94e159b-a638-4f81-8af0-8b1fb8653170]: Compensating NEW_ENTITY_ID of org.ovirt.engine.core.common.businessentities.VmDynamic; snapshot: 557421e0-d5f8-4caa-a84c-d1df24cb5a08.
2023-02-27 11:09:44,375-05 INFO  [org.ovirt.engine.core.bll.CommandCompensator] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] Command [id=f94e159b-a638-4f81-8af0-8b1fb8653170]: Compensating NEW_ENTITY_ID of org.ovirt.engine.core.common.businessentities.Snapshot; snapshot: f3661ecd-0965-4099-87fa-b341fb934628.
2023-02-27 11:09:44,377-05 INFO  [org.ovirt.engine.core.bll.CommandCompensator] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] Command [id=f94e159b-a638-4f81-8af0-8b1fb8653170]: Compensating NEW_ENTITY_ID of org.ovirt.engine.core.common.businessentities.VmStatic; snapshot: 557421e0-d5f8-4caa-a84c-d1df24cb5a08.
2023-02-27 11:09:44,385-05 INFO  [org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] Lock freed to object 'EngineLock:{exclusiveLocks='[557421e0-d5f8-4caa-a84c-d1df24cb5a08=VM, 8e4c49b1-5078-4c89-9ccc-237b95b5dce4=DISK, Implementacion_02-2=VM_NAME]', sharedLocks=''}'
2023-02-27 11:09:44,409-05 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] EVENT_ID: IMPORTEXPORT_IMPORT_VM_FAILED(1,153), Failed to import Vm Implementacion_02-2 to Data Center dc_rcloud, Cluster cl_rcloud
2023-02-27 11:09:45,435-05 INFO  [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-50) [17e97345] Waiting on remove image command to complete the task '3679c4b7-aea9-4fbd-804a-20f479aed410'
2023-02-27 11:09:45,437-05 INFO  [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-50) [17e97345] Waiting on remove image command to complete the task '002baef1-b642-49b4-9eea-065c311d57b1'
2023-02-27 11:09:45,439-05 INFO  [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-50) [17e97345] Command 'RemoveAllVmImages' (id: 'e8fcae23-dbaa-4ac8-8bd0-251aad0e9a04') waiting on child command id: '1fc4fb29-5034-49f2-a991-9eb10a4f3f63' type:'RemoveImage' to complete
2023-02-27 11:09:46,097-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-100) [] Task id '002baef1-b642-49b4-9eea-065c311d57b1' is in pre-polling  period and should not be polled. Pre-polling period is 60000 millis.
2023-02-27 11:09:46,097-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-100) [] Task id '3679c4b7-aea9-4fbd-804a-20f479aed410' is in pre-polling  period and should not be polled. Pre-polling period is 60000 millis.
2023-02-27 11:09:47,443-05 INFO  [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-76) [17e97345] Waiting on remove image command to complete the task '3679c4b7-aea9-4fbd-804a-20f479aed410'
2023-02-27 11:09:47,445-05 INFO  [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-76) [17e97345] Waiting on remove image command to complete the task '002baef1-b642-49b4-9eea-065c311d57b1'
2023-02-27 11:09:47,447-05 INFO  [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-76) [17e97345] Command 'RemoveAllVmImages' (id: 'e8fcae23-dbaa-4ac8-8bd0-251aad0e9a04') waiting on child command id: '1fc4fb29-5034-49f2-a991-9eb10a4f3f63' type:'RemoveImage' to complete
2023-02-27 11:09:51,451-05 INFO  [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-63) [17e97345] Waiting on remove image command to complete the task '3679c4b7-aea9-4fbd-804a-20f479aed410'
2023-02-27 11:09:51,453-05 INFO  [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-63) [17e97345] Waiting on remove image command to complete the task '002baef1-b642-49b4-9eea-065c311d57b1'
2023-02-27 11:09:51,455-05 INFO  [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-63) [17e97345] Command 'RemoveAllVmImages' (id: 'e8fcae23-dbaa-4ac8-8bd0-251aad0e9a04') waiting on child command id: '1fc4fb29-5034-49f2-a991-9eb10a4f3f63' type:'RemoveImage' to complete
2023-02-27 11:09:56,098-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-71) [] Task id '002baef1-b642-49b4-9eea-065c311d57b1' is in pre-polling  period and should not be polled. Pre-polling period is 60000 millis.
2023-02-27 11:09:56,098-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-71) [] Task id '3679c4b7-aea9-4fbd-804a-20f479aed410' is in pre-polling  period and should not be polled. Pre-polling period is 60000 millis.
2023-02-27 11:09:59,460-05 INFO  [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-11) [17e97345] Waiting on remove image command to complete the task '3679c4b7-aea9-4fbd-804a-20f479aed410'
2023-02-27 11:09:59,463-05 INFO  [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-11) [17e97345] Waiting on remove image command to complete the task '002baef1-b642-49b4-9eea-065c311d57b1'
2023-02-27 11:09:59,465-05 INFO  [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-11) [17e97345] Command 'RemoveAllVmImages' (id: 'e8fcae23-dbaa-4ac8-8bd0-251aad0e9a04') waiting on child command id: '1fc4fb29-5034-49f2-a991-9eb10a4f3f63' type:'RemoveImage' to complete
2023-02-27 11:10:06,098-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-59) [] Task id '002baef1-b642-49b4-9eea-065c311d57b1' is in pre-polling  period and should not be polled. Pre-polling period is 60000 millis.
2023-02-27 11:10:06,098-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-59) [] Task id '3679c4b7-aea9-4fbd-804a-20f479aed410' is in pre-polling  period and should not be polled. Pre-polling period is 60000 millis.
2023-02-27 11:10:08,215-05 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-38) [16fe44b7-87c1-4419-a1f1-7a7b1b0c2584] EVENT_ID: ANSIBLE_RUNNER_EVENT_NOTIFICATION(559), Query OVA info. Run import yaml on py3.
2023-02-27 11:10:08,222-05 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-38) [16fe44b7-87c1-4419-a1f1-7a7b1b0c2584] EVENT_ID: ANSIBLE_RUNNER_EVENT_NOTIFICATION(559), Query OVA info. Set facts.
2023-02-27 11:10:08,229-05 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-38) [16fe44b7-87c1-4419-a1f1-7a7b1b0c2584] EVENT_ID: ANSIBLE_RUNNER_EVENT_NOTIFICATION(559), Query OVA info. Create temporary directory.
2023-02-27 11:10:09,470-05 INFO  [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] Waiting on remove image command to complete the task '3679c4b7-aea9-4fbd-804a-20f479aed410'
2023-02-27 11:10:09,472-05 INFO  [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] Waiting on remove image command to complete the task '002baef1-b642-49b4-9eea-065c311d57b1'
2023-02-27 11:10:09,474-05 INFO  [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-29) [17e97345] Command 'RemoveAllVmImages' (id: 'e8fcae23-dbaa-4ac8-8bd0-251aad0e9a04') waiting on child command id: '1fc4fb29-5034-49f2-a991-9eb10a4f3f63' type:'RemoveImage' to complete
2023-02-27 11:10:11,240-05 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-38) [16fe44b7-87c1-4419-a1f1-7a7b1b0c2584] EVENT_ID: ANSIBLE_RUNNER_EVENT_NOTIFICATION(559), Query OVA info. Copy query_ova.py to temp directory.
2023-02-27 11:10:16,099-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-22) [] Task id '002baef1-b642-49b4-9eea-065c311d57b1' is in pre-polling  period and should not be polled. Pre-polling period is 60000 millis.
2023-02-27 11:10:16,099-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-22) [] Task id '3679c4b7-aea9-4fbd-804a-20f479aed410' is in pre-polling  period and should not be polled. Pre-polling period is 60000 millis.
2023-02-27 11:10:19,479-05 INFO  [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-17) [17e97345] Waiting on remove image command to complete the task '3679c4b7-aea9-4fbd-804a-20f479aed410'
2023-02-27 11:10:19,482-05 INFO  [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-17) [17e97345] Waiting on remove image command to complete the task '002baef1-b642-49b4-9eea-065c311d57b1'
2023-02-27 11:10:19,484-05 INFO  [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-17) [17e97345] Command 'RemoveAllVmImages' (id: 'e8fcae23-dbaa-4ac8-8bd0-251aad0e9a04') waiting on child command id: '1fc4fb29-5034-49f2-a991-9eb10a4f3f63' type:'RemoveImage' to complete
2023-02-27 11:10:26,099-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-83) [] Task id '002baef1-b642-49b4-9eea-065c311d57b1' is in pre-polling  period and should not be polled. Pre-polling period is 60000 millis.
2023-02-27 11:10:26,099-05 INFO  [org.ovirt.engine.core.bll.tasks.SPMAsyncTask] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-83) [] Task id '3679c4b7-aea9-4fbd-804a-20f479aed410' is in pre-polling  period and should not be polled. Pre-polling period is 60000 millis.
2023-02-27 11:10:26,255-05 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-38) [16fe44b7-87c1-4419-a1f1-7a7b1b0c2584] EVENT_ID: ANSIBLE_RUNNER_EVENT_NOTIFICATION(559), Query OVA info. Run query script.
2023-02-27 11:10:26,264-05 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-38) [16fe44b7-87c1-4419-a1f1-7a7b1b0c2584] EVENT_ID: ANSIBLE_RUNNER_EVENT_NOTIFICATION(559), Query OVA info. Remove temp directory.
2023-02-27 11:10:29,273-05 WARN  [org.ovirt.engine.core.utils.ovf.OvfReader] (default task-38) [16fe44b7-87c1-4419-a1f1-7a7b1b0c2584] could not retrieve volume id of file1 from ovf, generating new guid
2023-02-27 11:10:29,274-05 WARN  [org.ovirt.engine.core.utils.ovf.OvfReader] (default task-38) [16fe44b7-87c1-4419-a1f1-7a7b1b0c2584] could not retrieve disk id of vmdisk2 from ovf, generating new guid
2023-02-27 11:10:29,274-05 WARN  [org.ovirt.engine.core.utils.ovf.OvfReader] (default task-38) [16fe44b7-87c1-4419-a1f1-7a7b1b0c2584] didn't find disk provisioned size thus allocating the virtual size
2023-02-27 11:10:29,498-05 INFO  [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-95) [17e97345] Waiting on remove image command to complete the task '3679c4b7-aea9-4fbd-804a-20f479aed410'
2023-02-27 11:10:29,500-05 INFO  [org.ovirt.engine.core.bll.storage.disk.image.RemoveImageCommandCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-95) [17e97345] Waiting on remove image command to complete the task '002baef1-b642-49b4-9eea-065c311d57b1'
2023-02-27 11:10:29,502-05 INFO  [org.ovirt.engine.core.bll.ConcurrentChildCommandsExecutionCallback] (EE-ManagedScheduledExecutorService-engineScheduledThreadPool-Thread-95) [17e97345] Command 'RemoveAllVmImages' (id: 'e8fcae23-dbaa-4ac8-8bd0-251aad0e9a04') waiting on child command id: '1fc4fb29-5034-49f2-a991-9eb10a4f3f63' type:'RemoveImage' to complete

any suggestions for me??

Importing VM from OVA/OVF that was exported from VSphere fails ConvertOvaCommand - ImportVmFromOvaCommand

wcordero8＠gmail.com

wcordero8＠gmail.com

tags

participants (1)