- Users - oVirt List Archives

vm disk stuck on "paused by system"
by andreas_nikiforou＠hotmail.com 09 Mar '23

09 Mar '23

Hi, i was working on my own backup application (web front end for ovirtsdk) and i somehow managed to get my Vm disks, stuck on status: "paused by system". I have tried to stop the backup and finalize the backup manually, but nothing works. ovirtsdk4.Error: Fault reason is "Operation Failed". Fault detail is "[Cannot backup VM: Disk is locked. Please try again later.]". HTTP response code is 409. ovirtsdk4.Error: Fault reason is "Operation Failed". Fault detail is "[Cannot stop VM backup. There is an active image transfer for VM backup]". HTTP response code is 409. The API is showing that a backup exists and is in state: <phase>ready</phase>, the backup cannot be transferd. I i try and take a full new backup of the VM, all i get is more snapshots stuck on "paused by system" i cannot delete the VM or the disk. Using lastest version of oVirt Software Version:4.5.4-1.el8

2 5

oVirt 4.4 hosted engine deploy fails - repository issues
by lars.stolpe＠bvg.de 09 Mar '23

09 Mar '23

Hi, I want to upgrade oVirt 4.3 to oVirt 4.4. Thus i have to reinstall one node to EL8 an deploy the engine with restore. i get this error message at deploy: [ INFO ] TASK [ovirt.ovirt.engine_setup : Install oVirt Engine package] [ ERROR ] fatal: [localhost -> 192.168.2.143]: FAILED! => {"changed": false, "msg": "Failed to download metadata for repo 'ovirt-4.4-centos-ceph-pacific': Cannot prepare internal mirrorlist: Curl error (56): Failure when receiving data from the peer for http://mirrorlist.centos.org/?release=8-stream&arch=x86_64&repo=storage-cep… [Recv failure: Connection reset by peer]", "rc": 1, "results": []} Since i do use our satellite server, this URL is not included in the repositories i provided. A repository named 'ovirt-4.4-centos-ceph-pacific' is deinitely provided and available. How do i get the deploy to use the correct repositories? I hope someone can help me out, best regards

3 16

Q: VNC Certificate Issue with Windows Server 2022 VM
by Andrei Verovski 07 Mar '23

07 Mar '23

Hi, I have strange issue with certificate trying to connect to Windows Server 2022 VM with Compute -> Virtual machines -> Console: Unable to connect to graphic server …console.vv, the certificate is not trusted. Same issue with Firefox and Chrome. Anyone knows how to fix this? Thanks in advance. Andrei

2 2

After install Ovirt Engine 4.5.4-1.el8 and click button Administration Portal I get Internal Server Error
by staybox＠gmail.com 06 Mar '23

06 Mar '23

Hello! I installed Ovirt Engine 4.5.4-1.el8 and then I click button Administation Portal I get Internal Server Error. My postgresql-jdbc version was 42.2.14-1.el8 when I get error, then I upgraded to 42.5.4-1.rhel8 but I see same error. I need your help.

3 2

Ovirt provider OVN
by Vladislav S 02 Mar '23

02 Mar '23

Good afternoon, Since installing ovirt, there have been problems with OVN Provider. But we didn't do it, and we didn't have to. Now we need to create virtual networks, but nothing works for us. Please help with thoughts. I updated the Ovirt OVN Provider certificate, previously there was a problem with them after 2 years. LOGS AND information see link below https://lists.ovirt.org/archives/list/users@ovirt.org/thread/TUNTKKQCDRXEFR…

2 2

Problem with update 4.4.3
by Vladislav S 02 Mar '23

02 Mar '23

Good afternoon, I have version 4.4.3 and want to upgrade to at least 4.4.10 but I am facing this error: LOGS AND information see link below https://lists.ovirt.org/archives/list/users@ovirt.org/thread/LDH6K6ICRDDQ3Y…

2 2

Very long reboot times of "RH" hosts with oVirt installed
by Peter H 02 Mar '23

02 Mar '23

I was just wondering if anyone else is experiencing the following issue. On both real physical machines and on VM when I have installed a RH derivative like CentOS, CentOS Stream, Rocky Linux or Alma Linux the reboot times are initially normal. After installation of oVirt and all the dependencies the reboot (shutdown) increases to anywhere between 3 and 5 minutes. During shutdown a blinking cursor is visible in the upper left corner. If sitting at the console E.g. for

2 2

Importing Windows VM from OVA/OVF that was exported from VSphere fails
by wcordero8＠gmail.com 28 Feb '23

28 Feb '23

Description of problem: when importing Windows VM from OVA/OVF import fails: Cannot import VM. Invalid time zone for given OS type. Attribute: vm.vmStatic Infrastructure: VMware ESXi, 7.0.3, 19193900 oVirt Version 4.5.4-1.el8 oVirt self-hosted engine Steps to Reproduce: 1.Export Windows VM Microsoft Windows Server 2019 (64-bit) to OVA/OVF from VSphere that have SA Pacific Standard Time timezone (UTC-05:00) Bogotá, Lima, Quito) with ovftool. 2. import the VM in oVirt import fails with: Cannot import VM. Invalid time zone for given OS type. Attribute: vm.vmStatic [org.ovirt.engine.core.bll.exportimport.ImportVmFromOvaCommand] (default task-2) [72bb230a-bc1c-41c6-b87f-3891764b9fdd] Validation of action 'ImportVmFromOva' failed for user Reasons: VAR__ACTION__IMPORT,VAR__TYPE__VM,ACTION_TYPE_FAILED_INVALID_TIMEZONE,$groups [Ljava.lang.Class;@746306ef,$message ACTION_TYPE_FAILED_INVALID_TIMEZONE,$payload [Ljava.lang.Class;@2d685ee5,ACTION_TYPE_FAILED_ATTRIBUTE_PATH,$path vm.vmStatic,$validatedValue # cat Implementacion_02-2.ovf <?xml version='1.0' encoding='UTF-8'?> <Envelope xmlns="http://schemas.dmtf.org/ovf/envelope/1" xmlns:ovf="http://schemas.dmtf.org/ovf/envelope/1" xmlns:vmw="http://www.vmware.com/schema/ovf" xmlns:rasd="http://schemas.dmtf.org/wbem/wscim/1/cim-schema/2/CIM_ResourceAllocationSet…" xmlns:vssd="http://schemas.dmtf.org/wbem/wscim/1/cim-schema/2/CIM_VirtualSystemSettingD…"> <References> <File ovf:id="file1" ovf:href="Implementacion_02-2-1.vmdk"/> <File ovf:id="file2" ovf:href="Implementacion_02-2-2.vmdk"/> <File ovf:id="file3" ovf:href="Implementacion_02-2-3.nvram" ovf:size="270840"/> </References> <DiskSection> <Info>List of the virtual disks</Info> <Disk ovf:capacityAllocationUnits="byte" ovf:format="http://www.vmware.com/interfaces/specifications/vmdk.html#streamOptimized" ovf:diskId="vmdisk1" ovf:capacity="161061273600" ovf:fileRef="file1"/> <Disk ovf:capacityAllocationUnits="byte" ovf:format="http://www.vmware.com/interfaces/specifications/vmdk.html#streamOptimized" ovf:diskId="vmdisk2" ovf:capacity="161061273600" ovf:fileRef="file2"/> </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 system</Info> <Name>Implementacion_02-2</Name> <OperatingSystemSection ovf:id="112" vmw:osType="windows2019srv_64Guest"> <Info>The operating system installed</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: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> <vmw:CoresPerSocket ovf:required="false">1</vmw:CoresPerSocket> </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>SCSI Controller</rasd:Description> <rasd:ElementName>SCSI Controller 1</rasd:ElementName> <rasd:InstanceID>3</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> <rasd:Address>0</rasd:Address> <rasd:Description>SATA Controller</rasd:Description> <rasd:ElementName>SATA Controller 1</rasd:ElementName> <rasd:InstanceID>4</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:Description>USB Controller (XHCI)</rasd:Description> <rasd:ElementName>USB 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:AddressOnParent>0</rasd:AddressOnParent> <rasd:ElementName>Hard Disk 1</rasd:ElementName> <rasd:HostResource>ovf:/disk/vmdisk1</rasd:HostResource> <rasd:InstanceID>6</rasd:InstanceID> <rasd:Parent>3</rasd:Parent> <rasd:ResourceType>17</rasd:ResourceType> </Item> <Item> <rasd:AddressOnParent>1</rasd:AddressOnParent> <rasd:ElementName>Hard Disk 2</rasd:ElementName> <rasd:HostResource>ovf:/disk/vmdisk2</rasd:HostResource> <rasd:InstanceID>7</rasd:InstanceID> <rasd:Parent>3</rasd:Parent> <rasd:ResourceType>17</rasd:ResourceType> </Item> <Item> <rasd:AddressOnParent>0</rasd:AddressOnParent> <rasd:AutomaticAllocation>false</rasd:AutomaticAllocation> <rasd:ElementName>CD/DVD Drive 1</rasd:ElementName> <rasd:InstanceID>8</rasd:InstanceID> <rasd:Parent>4</rasd:Parent> <rasd:ResourceSubType>vmware.cdrom.remoteatapi</rasd:ResourceSubType> <rasd:ResourceType>15</rasd:ResourceType> </Item> <Item> <rasd:AddressOnParent>0</rasd:AddressOnParent> <rasd:AutomaticAllocation>true</rasd:AutomaticAllocation> <rasd:Connection>DVPG_102</rasd:Connection> <rasd:ElementName>Network adapter 1</rasd:ElementName> <rasd:InstanceID>9</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="connectable.allowGuestControl" vmw:value="true"/> <vmw:Config ovf:required="false" vmw:key="wakeOnLanEnabled" vmw:value="true"/> <vmw:Config ovf:required="false" vmw:key="uptCompatibilityEnabled" vmw:value="true"/> </Item> <Item ovf:required="false"> <rasd:ElementName>Video card</rasd:ElementName> <rasd:InstanceID>10</rasd:InstanceID> <rasd:ResourceType>24</rasd:ResourceType> <vmw:Config ovf:required="false" vmw:key="enable3DSupport" vmw:value="false"/> <vmw:Config ovf:required="false" vmw:key="useAutoDetect" vmw:value="true"/> <vmw:Config ovf:required="false" vmw:key="graphicsMemorySizeInKB" vmw:value="262144"/> <vmw:Config ovf:required="false" vmw:key="use3dRenderer" vmw:value="automatic"/> <vmw:Config ovf:required="false" vmw:key="numDisplays" vmw:value="1"/> <vmw:Config ovf:required="false" vmw:key="videoRamSizeInKB" vmw:value="16384"/> </Item> <vmw:Config ovf:required="false" vmw:key="cpuHotAddEnabled" vmw:value="false"/> <vmw:Config ovf:required="false" vmw:key="sgxInfo.epcSize" vmw:value="0"/> <vmw:Config ovf:required="false" vmw:key="nestedHVEnabled" 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="cpuHotRemoveEnabled" vmw:value="false"/> <vmw:Config ovf:required="false" vmw:key="sgxInfo.flcMode" vmw:value="unlocked"/> <vmw:Config ovf:required="false" vmw:key="sevEnabled" vmw:value="false"/> <vmw:Config ovf:required="false" vmw:key="virtualICH7MPresent" vmw:value="false"/> <vmw:Config ovf:required="false" vmw:key="flags.vbsEnabled" vmw:value="false"/> <vmw:Config ovf:required="false" vmw:key="memoryHotAddEnabled" vmw:value="false"/> <vmw:Config ovf:required="false" vmw:key="bootOptions.efiSecureBootEnabled" vmw:value="true"/> <vmw:Config ovf:required="false" vmw:key="firmware" vmw:value="efi"/> <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> # cat /etc/ovirt-engine/timezones/00-defaults.properties | grep -ie bogota America/Bogota=SA Pacific Standard Time any suggestions for me??

2 4

Importing VM from OVA/OVF that was exported from VSphere fails ConvertOvaCommand - ImportVmFromOvaCommand
by wcordero8＠gmail.com 28 Feb '23

28 Feb '23

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"?>  <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_ResourceAllocationSet…" xmlns:vmw="http://www.vmware.com/schema/ovf" xmlns:vssd="http://schemas.dmtf.org/wbem/wscim/1/cim-schema/2/CIM_VirtualSystemSettingD…" 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??

1 1

Host Reboot Timeout of 10 Minutes
by Peter H 27 Feb '23

27 Feb '23

I'm working in a group that maintains a large oVirt setup based on 4.4.1 which works very well. We are afraid of upgrading and prefer setting up a new installation and gradually enlist the hosts one by one into the new installation. We have tried 4.4.10 and 4.5.1 - 4.5.4 based on CentOS Stream 8, Rocky 8, Alma Linux 9.1 with various problems. Worst was the problem that the rpm db ended up in a catch-22 state. Using Alma Linux 9.1 and current oVirt 4.5.4 seems promising as no rpm problems are present after installation. We have only one nuisance left which we have seen in all installation attempts we have made since 4.4.10. When rebooting a host it takes 10 minutes before it's activated again. In 4.4.1 the hosts are activated a few seconds after they have booted up. I have found the following in the engine log: 2023-01-24 23:01:57,564+01 INFO [org.ovirt.engine.core.bll.SshHostRebootCommand] (EE-ManagedThreadFactory-engine-Thread-1513) [2bb08d20] Waiting 600 seconds, for server to finish reboot process. Our ansible playbooks for deployment times out and we could increase the timeout but how come that this 10 minutes delay has been introduced? Does a config file exist where this timeout can be set to a lower value? BR Peter H.

2 2