When creating a virtual machine with a template, there are times when disk cannot be recognized

Hi: I use environment is ovirt4.2.0, first, I created a template, the template has already installed in the operating system, and then I use this template to create a virtual machine, sometimes can't find the disk happens, particular case is shown in figure: At this point, the virtual machine attaches disk again and cannot be recognized. However, the disk above is identifiable to other virtual machines, and the contents of disk still exist I would like to ask what causes this problem.

Hi, Can you please attach full vdsm and engine logs? *Regards,* *Shani Leviim* On Fri, Jun 29, 2018 at 1:41 PM, Pym <pym0914@163.com> wrote:
Hi:
I use environment is ovirt4.2.0, first, I created a template, the template has already installed in the operating system, and then I use this template to create a virtual machine, sometimes can't find the disk happens, particular case is shown in figure:
At this point, the virtual machine attaches disk again and cannot be recognized. However, the disk above is identifiable to other virtual machines, and the contents of disk still exist
I would like to ask what causes this problem.
_______________________________________________ Users mailing list -- users@ovirt.org To unsubscribe send an email to users-leave@ovirt.org Privacy Statement: https://www.ovirt.org/site/privacy-policy/ oVirt Code of Conduct: https://www.ovirt.org/community/about/community- guidelines/ List Archives: https://lists.ovirt.org/archives/list/users@ovirt.org/ message/XHW44X37UKSCCL5Q675AC3CAQN4Z44MF/

Hi, #vdsm.log 2018-07-02 19:22:02,316+0800 INFO (libvirt/events) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') CPU running: onResume (vm:5742) 2018-07-02 19:22:02,586+0800 INFO (vm/0febbdb6) [root] /usr/libexec/vdsm/hooks/after_vm_start/50_openstacknet: rc=0 err= (hooks:109) 2018-07-02 19:22:02,807+0800 INFO (vm/0febbdb6) [root] /usr/libexec/vdsm/hooks/after_vm_start/openstacknet_utils.py: rc=0 err= (hooks:109) 2018-07-02 19:22:02,824+0800 WARN (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Found unknown drive: {'alias': 'ide0-1-0', 'readonly': 'True', 'iface': 'ide', 'name': 'hdc', 'address': {'bus': '1', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'cdrom', 'path': '/rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111/CentOS-7-x86_64-Minimal-1804.iso', 'type': 'disk', 'bootOrder': '2'} (storage:217) 2018-07-02 19:22:02,824+0800 WARN (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Found unknown drive: {'alias': 'scsi0-0-0', 'readonly': 'False', 'iface': 'ide', 'name': 'sda', 'address': {'bus': '0', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'disk', 'path': '/rhev/data-center/mnt/192.168.100.59:_exports_vmstorage/461517fd-986a-4b39-9b84-5fd54ef06967/images/1ea736ad-164c-4ac2-a6ce-9b8e6eceefde/b7f8140f-3a34-40fd-9fec-878df3d159a4', 'type': 'disk', 'bootOrder': '1'} (storage:217) 2018-07-02 19:22:02,825+0800 WARN (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Found unknown drive: {'alias': 'scsi0-0-1', 'readonly': 'False', 'iface': 'ide', 'name': 'sdb', 'address': {'bus': '0', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '1'}, 'device': 'disk', 'path': '/rhev/data-center/mnt/192.168.100.241:_exports_vmstorage/c5a790c2-2885-4220-b803-35e4a40ecef6/images/183b3ace-64e1-49cf-a214-b5462f989171/36ea0af6-b681-4923-bf70-6b51717f372d', 'type': 'disk'} (storage:217) 2018-07-02 19:22:02,825+0800 WARN (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Found unknown drive: {'alias': 'scsi0-0-2', 'readonly': 'False', 'iface': 'ide', 'name': 'sdc', 'address': {'bus': '0', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '2'}, 'device': 'disk', 'path': '/rhev/data-center/mnt/192.168.100.241:_exports_vmstorage/c5a790c2-2885-4220-b803-35e4a40ecef6/images/5866d66c-0756-4544-af6a-95f5aed9312b/1751cd16-fd89-4441-b847-45169689c9a7', 'type': 'disk'} (storage:217) 2018-07-02 19:22:02,840+0800 INFO (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Starting connection (guestagent:244) 2018-07-02 19:22:02,841+0800 ERROR (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Failed to connect to guest agent channel (vm:2415) Traceback (most recent call last): File "/usr/lib/python2.7/site-packages/vdsm/virt/vm.py", line 2413, in _vmDependentInit self.guestAgent.start() File "/usr/lib/python2.7/site-packages/vdsm/virt/guestagent.py", line 245, in start self._prepare_socket() File "/usr/lib/python2.7/site-packages/vdsm/virt/guestagent.py", line 287, in _prepare_socket supervdsm.getProxy().prepareVmChannel(self._socketName) File "/usr/lib/python2.7/site-packages/vdsm/supervdsm.py", line 53, in __call__ return callMethod() File "/usr/lib/python2.7/site-packages/vdsm/supervdsm.py", line 51, in <lambda> **kwargs) File "<string>", line 2, in prepareVmChannel File "/usr/lib64/python2.7/multiprocessing/managers.py", line 773, in _callmethod raise convert_to_error(kind, result) OSError: [Errno 2] No such file or directory: '/var/lib/libvirt/qemu/channels/0febbdb6-9b90-4052-8169-4f6792d99d1e.com.redhat.rhevm.vdsm' 2018-07-02 19:22:02,843+0800 INFO (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') CPU running: domain initialization (vm:5742) 2018-07-02 19:22:02,910+0800 INFO (jsonrpc/6) [api.host] START dumpxmls(vmList=[u'0febbdb6-9b90-4052-8169-4f6792d99d1e']) from=::ffff:192.168.100.59,60612 (api:46) 2018-07-02 19:22:09,292+0800 ERROR (periodic/39) [virt.vmstats] VM metrics collection failed (vmstats:260) Traceback (most recent call last): File "/usr/lib/python2.7/site-packages/vdsm/virt/vmstats.py", line 217, in send_metrics diskinfo['readOps'] KeyError: 'readOps' 2018-07-02 19:22:09,813+0800 INFO (jsonrpc/2) [api.host] START getStats() from=::ffff:192.168.100.59,60612 (api:46) 2018-07-02 19:22:09,816+0800 INFO (jsonrpc/2) [vdsm.api] START repoStats(domains=()) from=::ffff:192.168.100.59,60612, task_id=36236da1-3586-4324-a842-71aafb37d124 (api:46) 2018-07-02 19:22:09,816+0800 INFO (jsonrpc/2) [vdsm.api] FINISH repoStats return={u'6ff100c0-d08d-4504-832d-53876bf97349': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000776933', 'lastCheck': '6.6', 'valid': True}, u'5a50d04c-4d39-4249-8fc6-24df9bd096b0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000358834', 'lastCheck': '4.1', 'valid': True}, u'c5a790c2-2885-4220-b803-35e4a40ecef6': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000328361', 'lastCheck': '3.3', 'valid': True}, u'461517fd-986a-4b39-9b84-5fd54ef06967': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000412804', 'lastCheck': '5.7', 'valid': True}} from=::ffff:192.168.100.59,60612, task_id=36236da1-3586-4324-a842-71aafb37d124 (api:52) 2018-07-02 19:22:09,831+0800 INFO (periodic/39) [vdsm.api] START repoStats(domains=()) from=internal, task_id=b4dcd456-df92-4270-92be-d73d7e7127f9 (api:46) 2018-07-02 19:22:09,832+0800 INFO (periodic/39) [vdsm.api] FINISH repoStats return={u'6ff100c0-d08d-4504-832d-53876bf97349': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000776933', 'lastCheck': '6.6', 'valid': True}, u'5a50d04c-4d39-4249-8fc6-24df9bd096b0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000358834', 'lastCheck': '4.1', 'valid': True}, u'c5a790c2-2885-4220-b803-35e4a40ecef6': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000328361', 'lastCheck': '3.3', 'valid': True}, u'461517fd-986a-4b39-9b84-5fd54ef06967': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000412804', 'lastCheck': '5.7', 'valid': True}} from=internal, task_id=b4dcd456-df92-4270-92be-d73d7e7127f9 (api:52) #engine.log 2018-07-02 19:22:07,907+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (default task-41) [2f069134-d730-4ca5-93b7-3925a06234da] Lock Acquired to object 'EngineLock:{exclusiveLocks='[0febbdb6-9b90-4052-8169-4f6792d99d1e=VM]', sharedLocks=''}' 2018-07-02 19:22:07,935+08 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (default task-41) [2f069134-d730-4ca5-93b7-3925a06234da] START, IsVmDuringInitiatingVDSCommand( IsVmDuringInitiatingVDSCommandParameters:{vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}), log id: 703a024f 2018-07-02 19:22:07,936+08 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (default task-41) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 703a024f 2018-07-02 19:22:07,998+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Running command: RunVmCommand internal: false. Entities affected : ID: 0febbdb6-9b90-4052-8169-4f6792d99d1e Type: VMAction group RUN_VM with role type USER 2018-07-02 19:22:08,061+08 INFO [org.ovirt.engine.core.bll.scheduling.policyunits.PreferredHostsWeightPolicyUnit] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Penalizing host 'storage3' because it is not preferred. 2018-07-02 19:22:08,061+08 INFO [org.ovirt.engine.core.bll.scheduling.policyunits.PreferredHostsWeightPolicyUnit] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Penalizing host 'storage2' because it is not preferred. 2018-07-02 19:22:08,073+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Running VM with attached cd 'CentOS-7-x86_64-Minimal-1804.iso' 2018-07-02 19:22:08,075+08 INFO [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] START, UpdateVmDynamicDataVDSCommand( UpdateVmDynamicDataVDSCommandParameters:{hostId='null', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e', vmDynamic='org.ovirt.engine.core.common.businessentities.VmDynamic@540c1d26'}), log id: 11987cd 2018-07-02 19:22:08,076+08 INFO [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, UpdateVmDynamicDataVDSCommand, log id: 11987cd 2018-07-02 19:22:08,079+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] START, IsoPrefixVDSCommand(HostName = tchypnode1.ecr.com, VdsAndPoolIDVDSParametersBase:{hostId='7902d7f8-348e-426e-a3c3-399d08b0eb1e', storagePoolId='5b0f6949-02d6-00bf-008b-000000000167'}), log id: 3d66a9a0 2018-07-02 19:22:08,079+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, IsoPrefixVDSCommand, return: /rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111, log id: 3d66a9a0 2018-07-02 19:22:08,079+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] START, IsoPrefixVDSCommand(HostName = tchypnode1.ecr.com, VdsAndPoolIDVDSParametersBase:{hostId='7902d7f8-348e-426e-a3c3-399d08b0eb1e', storagePoolId='5b0f6949-02d6-00bf-008b-000000000167'}), log id: 3d66a9a0 2018-07-02 19:22:08,079+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, IsoPrefixVDSCommand, return: /rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111, log id: 3d66a9a0 2018-07-02 19:22:08,087+08 INFO [org.ovirt.engine.core.vdsbroker.CreateVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] START, CreateVDSCommand( CreateVDSCommandParameters:{hostId='7902d7f8-348e-426e-a3c3-399d08b0eb1e', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e', vm='VM [badvm-avodev-controller]'}), log id: 54fa1fca 2018-07-02 19:22:08,090+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] START, CreateBrokerVDSCommand(HostName = tchypnode1.ecr.com, CreateVDSCommandParameters:{hostId='7902d7f8-348e-426e-a3c3-399d08b0eb1e', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e', vm='VM [badvm-avodev-controller]'}), log id: 54926511 2018-07-02 19:22:08,096+08 INFO [org.ovirt.engine.core.vdsbroker.builder.vminfo.VmInfoBuilderImpl] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Bootable disk '1ea736ad-164c-4ac2-a6ce-9b8e6eceefde' set to index '0' 2018-07-02 19:22:08,130+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] VM <?xml version="1.0" encoding="UTF-8"?><domain type="kvm" xmlns:ovirt-tune="http://ovirt.org/vm/tune/1.0" xmlns:ovirt-vm="http://ovirt.org/vm/1.0"> <name>badvm-avodev-controller</name> <uuid>0febbdb6-9b90-4052-8169-4f6792d99d1e</uuid> <memory>8388608</memory> <currentMemory>8388608</currentMemory> <maxMemory slots="16">33554432</maxMemory> <vcpu current="4">16</vcpu> <sysinfo type="smbios"> <system> <entry name="manufacturer">oVirt</entry> <entry name="product">OS-NAME:</entry> <entry name="version">OS-VERSION:</entry> <entry name="serial">HOST-SERIAL:</entry> <entry name="uuid">0febbdb6-9b90-4052-8169-4f6792d99d1e</entry> </system> </sysinfo> <clock offset="variable" adjustment="0"> <timer name="rtc" tickpolicy="catchup"/> <timer name="pit" tickpolicy="delay"/> <timer name="hpet" present="no"/> </clock> <features> <acpi/> </features> <cpu match="exact"> <model>Nehalem</model> <topology cores="1" threads="1" sockets="16"/> <numa> <cell cpus="0,1,2,3" memory="8388608"/> <numa> <cell cpus="0,1,2,3" memory="8388608"/> </numa> </cpu> <cputune/> <devices> <input type="tablet" bus="usb"/> <channel type="unix"> <target type="virtio" name="ovirt-guest-agent.0"/> <source mode="bind" path="/var/lib/libvirt/qemu/channels/0febbdb6-9b90-4052-8169-4f6792d99d1e.ovirt-guest-agent.0"/> </channel> <channel type="unix"> <target type="virtio" name="org.qemu.guest_agent.0"/> <source mode="bind" path="/var/lib/libvirt/qemu/channels/0febbdb6-9b90-4052-8169-4f6792d99d1e.org.qemu.guest_agent.0"/> </channel> <controller type="usb" model="piix3-uhci" index="0"> <address bus="0x00" domain="0x0000" function="0x2" slot="0x01" type="pci"/> </controller> <memballoon model="virtio"> <stats period="5"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x06" type="pci"/> </memballoon> <controller type="ide"> <address bus="0x00" domain="0x0000" function="0x1" slot="0x01" type="pci"/> </controller> <controller type="scsi" model="virtio-scsi" index="0"/> <controller type="scsi" index="0"> <address bus="0x00" domain="0x0000" function="0x0" slot="0x05" type="pci"/> </controller> <graphics type="vnc" port="-1" autoport="yes" passwd="*****" passwdValidTo="1970-01-01T00:00:01" keymap="en-us"> <listen type="network" network="vdsm-ovirtmgmt"/> <graphics type="vnc" port="-1" autoport="yes" passwd="*****" passwdValidTo="1970-01-01T00:00:01" keymap="en-us"> <listen type="network" network="vdsm-ovirtmgmt"/> </graphics> <video> <model type="vga" vram="16384" heads="1"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x02" type="pci"/> </video> <rng model="virtio"> <backend model="random">/dev/urandom</backend> </rng> <controller type="virtio-serial" index="0" ports="16"> <address bus="0x00" domain="0x0000" function="0x0" slot="0x04" type="pci"/> </controller> <interface type="bridge"> <model type="virtio"/> <link state="up"/> <source bridge="ovirtmgmt"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x03" type="pci"/> <mac address="00:1a:4a:16:01:f5"/> <filterref filter="vdsm-no-mac-spoofing"/> <bandwidth/> </interface> <interface type="bridge"> <model type="virtio"/> <link state="up"/> <source bridge="ovirtmgmt"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x07" type="pci"/> <mac address="00:1a:4a:16:01:f6"/> <filterref filter="vdsm-no-mac-spoofing"/> <bandwidth/> </interface> <bandwidth/> </interface> <disk type="file" device="cdrom" snapshot="no"> <driver name="qemu" type="raw" error_policy="report"/> <source file="/rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111/CentOS-7-x86_64-Minimal-1804.iso" startupPolicy="optional"/> <target dev="hdc" bus="ide"/> <readonly/> <address bus="1" controller="0" unit="0" type="drive" target="0"/> <boot order="2"/> </disk> <disk snapshot="no" type="file" device="disk"> <target dev="sda" bus="scsi"/> <source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/461517fd-986a-4b39-9b84-5fd54ef06967/images/1ea736ad-164c-4ac2-a6ce-9b8e6eceefde/b7f8140f-3a34-40fd-9fec-878df3d159a4"/> <driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"/> <address bus="0" controller="0" unit="0" type="drive" target="0"/> <boot order="1"/> <serial>1ea736ad-164c-4ac2-a6ce-9b8e6eceefde</serial> </disk> <disk snapshot="no" type="file" device="disk"> <target dev="sdb" bus="scsi"/> <source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/183b3ace-64e1-49cf-a214-b5462f989171/36ea0af6-b681-4923-bf70-6b51717f372d"/> <driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"/> <address bus="0" controller="0" unit="1" type="drive" target="0"/> <serial>183b3ace-64e1-49cf-a214-b5462f989171</serial> </disk> <disk snapshot="no" type="file" device="disk"> <target dev="sdc" bus="scsi"/> <disk snapshot="no" type="file" device="disk"> <target dev="sdc" bus="scsi"/> <source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/5866d66c-0756-4544-af6a-95f5aed9312b/1751cd16-fd89-4441-b847-45169689c9a7"/> <driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"/> <address bus="0" controller="0" unit="2" type="drive" target="0"/> <serial>5866d66c-0756-4544-af6a-95f5aed9312b</serial> </disk> </devices> <pm> <suspend-to-disk enabled="no"/> <suspend-to-mem enabled="no"/> </pm> <os> <type arch="x86_64" machine="pc-i440fx-2.5">hvm</type> <smbios mode="sysinfo"/> </os> <metadata> <ovirt-tune:qos/> <ovirt-vm:vm> <minGuaranteedMemoryMb type="int">4096</minGuaranteedMemoryMb> <clusterVersion>4.2</clusterVersion> <ovirt-vm:custom/> <ovirt-vm:device mac_address="00:1a:4a:16:01:f6"> <ovirt-vm:custom/> </ovirt-vm:device> <ovirt-vm:device mac_address="00:1a:4a:16:01:f5"> <ovirt-vm:custom/> </ovirt-vm:device> <ovirt-vm:device devtype="disk" name="sdb"> <ovirt-vm:imageID>183b3ace-64e1-49cf-a214-b5462f989171</ovirt-vm:imageID> <ovirt-vm:device devtype="disk" name="sdb"> <ovirt-vm:imageID>183b3ace-64e1-49cf-a214-b5462f989171</ovirt-vm:imageID> <ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID> <ovirt-vm:volumeID>36ea0af6-b681-4923-bf70-6b51717f372d</ovirt-vm:volumeID> <ovirt-vm:domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ovirt-vm:domainID> </ovirt-vm:device> <ovirt-vm:device devtype="disk" name="sda"> <ovirt-vm:imageID>1ea736ad-164c-4ac2-a6ce-9b8e6eceefde</ovirt-vm:imageID> <ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID> <ovirt-vm:volumeID>b7f8140f-3a34-40fd-9fec-878df3d159a4</ovirt-vm:volumeID> <ovirt-vm:domainID>461517fd-986a-4b39-9b84-5fd54ef06967</ovirt-vm:domainID> </ovirt-vm:device> <ovirt-vm:device devtype="disk" name="sdc"> <ovirt-vm:imageID>5866d66c-0756-4544-af6a-95f5aed9312b</ovirt-vm:imageID> <ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID> <ovirt-vm:volumeID>1751cd16-fd89-4441-b847-45169689c9a7</ovirt-vm:volumeID> <ovirt-vm:domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ovirt-vm:domainID> </ovirt-vm:device> <launchPaused>false</launchPaused> <resumeBehavior>auto_resume</resumeBehavior> </ovirt-vm:vm> </metadata> </domain> 2018-07-02 19:22:08,143+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, CreateBrokerVDSCommand, log id: 54926511 2018-07-02 19:22:08,144+08 INFO [org.ovirt.engine.core.vdsbroker.CreateVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, CreateVDSCommand, return: WaitForLaunch, log id: 54fa1fca 2018-07-02 19:22:08,145+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Lock freed to object 'EngineLock:{exclusiveLocks='[0febbdb6-9b90-4052-8169-4f6792d99d1e=VM]', sharedLocks=''}' At 2018-07-02 14:19:23, "Shani Leviim" <sleviim@redhat.com> wrote: Hi, Can you please attach full vdsm and engine logs? Regards, Shani Leviim On Fri, Jun 29, 2018 at 1:41 PM, Pym <pym0914@163.com> wrote: Hi: I use environment is ovirt4.2.0, first, I created a template, the template has already installed in the operating system, and then I use this template to create a virtual machine, sometimes can't find the disk happens, particular case is shown in figure: At this point, the virtual machine attaches disk again and cannot be recognized. However, the disk above is identifiable to other virtual machines, and the contents of disk still exist I would like to ask what causes this problem. _______________________________________________ Users mailing list -- users@ovirt.org To unsubscribe send an email to users-leave@ovirt.org Privacy Statement: https://www.ovirt.org/site/privacy-policy/ oVirt Code of Conduct: https://www.ovirt.org/community/about/community-guidelines/ List Archives: https://lists.ovirt.org/archives/list/users@ovirt.org/message/XHW44X37UKSCCL...

Hi, The last mail engine did not have enough log content, this email adds There is an ERROR in the log. How to deal with this problem #engine.log 2018-07-03 11:28:40,493+08 INFO [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer] (EE-ManagedThreadFactory-engineScheduled-Thread-58) [] VM '56b28418-9dcf-4d46-87fe-0dfef05bb141' was discovered as 'Down' on VDS '7902d7f8-348e-426e-a3c3-399d08b0eb1e'(tchypnode1.ecr.com) 2018-07-03 11:28:40,511+08 INFO [org.ovirt.engine.core.bll.AddUnmanagedVmsCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-58) [55d6983c] Running command: AddUnmanagedVmsCommand internal: true. 2018-07-03 11:28:40,514+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-58) [55d6983c] START, FullListVDSCommand(HostName = tchypnode1.ecr.com, FullListVDSCommandParameters:{hostId='7902d7f8-348e-426e-a3c3-399d08b0eb1e', vmIds='[56b28418-9dcf-4d46-87fe-0dfef05bb141]'}), log id: 7f6022f8 2018-07-03 11:28:40,521+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-58) [55d6983c] FINISH, FullListVDSCommand, return: [{status=Down, guestFQDN=, kvmEnable=true, xml=<?xml version="1.0" encoding="UTF-8"?><domain type="kvm" xmlns:ovirt-tune="http://ovirt.org/vm/tune/1.0" xmlns:ovirt-vm="http://ovirt.org/vm/1.0"><name>avodev-controller</name><uuid>56b28418-9dcf-4d46-87fe-0dfef05bb141</uuid><memory>8388608</memory><currentMemory>8388608</currentMemory><maxMemory slots="16">33554432</maxMemory><vcpu current="4">16</vcpu><sysinfo type="smbios"><system><entry name="manufacturer">oVirt</entry><entry name="product">OS-NAME:</entry><entry name="version">OS-VERSION:</entry><entry name="serial">HOST-SERIAL:</entry><entry name="uuid">56b28418-9dcf-4d46-87fe-0dfef05bb141</entry></system></sysinfo><clock offset="variable" adjustment="0"><timer name="rtc" tickpolicy="catchup"></timer><timer name="pit" tickpolicy="delay"></timer><timer name="hpet" present="no"></timer></clock><features><acpi></acpi></features><cpu match="exact"><model>Nehalem</model><topology cores="1" threads="1" sockets="16"></topology><numa><cell cpus="0,1,2,3" memory="8388608"></cell></numa></cpu><cputune></cputune><devices><input type="tablet" bus="usb"></input><channel type="unix"><target type="virtio" name="ovirt-guest-agent.0"></target><source mode="bind" path="/var/lib/libvirt/qemu/channels/56b28418-9dcf-4d46-87fe-0dfef05bb141.ovirt-guest-agent.0"></source></channel><channel type="unix"><target type="virtio" name="org.qemu.guest_agent.0"></target><source mode="bind" path="/var/lib/libvirt/qemu/channels/56b28418-9dcf-4d46-87fe-0dfef05bb141.org.qemu.guest_agent.0"></source></channel><controller type="scsi" model="virtio-scsi" index="0"></controller><controller type="usb" model="piix3-uhci" index="0"><address bus="0x00" domain="0x0000" function="0x2" slot="0x01" type="pci"></address></controller><controller type="virtio-serial" index="0" ports="16"><address bus="0x00" domain="0x0000" function="0x0" slot="0x04" type="pci"></address></controller><graphics type="vnc" port="-1" autoport="yes" passwd="*****" passwdValidTo="1970-01-01T00:00:01" keymap="en-us"><listen type="network" network="vdsm-ovirtmgmt"></listen></graphics><controller type="ide"><address bus="0x00" domain="0x0000" function="0x1" slot="0x01" type="pci"></address></controller><rng model="virtio"><backend model="random">/dev/urandom</backend></rng><memballoon model="virtio"><stats period="5"></stats><address bus="0x00" domain="0x0000" function="0x0" slot="0x06" type="pci"></address></memballoon><video><model type="vga" vram="16384" heads="1"></model></video><interface type="bridge"><model type="virtio"></model><link state="up"></link><source bridge="ovirtmgmt"></source><address bus="0x00" domain="0x0000" function="0x0" slot="0x03" type="pci"></address><mac address="00:1a:4a:16:01:da"></mac><filterref filter="vdsm-no-mac-spoofing"></filterref><bandwidth></bandwidth></interface><interface type="bridge"><model type="virtio"></model><link state="up"></link><source bridge="ovirtmgmt"></source><address bus="0x00" domain="0x0000" function="0x0" slot="0x07" type="pci"></address><mac address="00:1a:4a:16:01:db"></mac><filterref filter="vdsm-no-mac-spoofing"></filterref><bandwidth></bandwidth></interface><disk type="file" device="cdrom" snapshot="no"><driver name="qemu" type="raw" error_policy="report"></driver><source file="/rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111/CentOS-7-x86_64-Minimal-1804.iso" startupPolicy="optional"></source><target dev="hdc" bus="ide"></target><readonly></readonly><address bus="1" controller="0" unit="0" type="drive" target="0"></address><boot order="2"></boot></disk><disk snapshot="no" type="file" device="disk"><target dev="sda" bus="scsi"></target><source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/461517fd-986a-4b39-9b84-5fd54ef06967/images/8e17d226-94d0-42f8-ae29-9c795db7e522/3b4e2ee9-ba96-4b4d-8392-94e2736e6dab"></source><driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"></driver><address bus="0" controller="0" unit="0" type="drive" target="0"></address><boot order="1"></boot><serial>8e17d226-94d0-42f8-ae29-9c795db7e522</serial></disk><disk snapshot="no" type="file" device="disk"><target dev="sdb" bus="scsi"></target><source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/d0a00b64-3676-4783-a7af-9bb53940b03e/a88be564-84f8-4232-9ba6-91be4625a469"></source><driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"></driver><address bus="0" controller="0" unit="1" type="drive" target="0"></address><serial>d0a00b64-3676-4783-a7af-9bb53940b03e</serial></disk><disk snapshot="no" type="file" device="disk"><target dev="sdc" bus="scsi"></target><source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/03352bba-9d9f-4fca-ac35-f4aa7767e0b6/26a2e47d-2b10-454a-83f4-3c3c96d27f72"></source><driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"></driver><address bus="0" controller="0" unit="2" type="drive" target="0"></address><serial>03352bba-9d9f-4fca-ac35-f4aa7767e0b6</serial></disk></devices><pm><suspend-to-disk enabled="no"></suspend-to-disk><suspend-to-mem enabled="no"></suspend-to-mem></pm><os><type arch="x86_64" machine="pc-i440fx-2.5">hvm</type><smbios mode="sysinfo"></smbios></os><metadata><ovirt-tune:qos></ovirt-tune:qos><ovirt-vm:vm><minGuaranteedMemoryMb type="int">4096</minGuaranteedMemoryMb><clusterVersion>4.2</clusterVersion><ovirt-vm:custom></ovirt-vm:custom><ovirt-vm:device mac_address="00:1a:4a:16:01:db"><ovirt-vm:custom></ovirt-vm:custom></ovirt-vm:device><ovirt-vm:device mac_address="00:1a:4a:16:01:da"><ovirt-vm:custom></ovirt-vm:custom></ovirt-vm:device><ovirt-vm:device devtype="disk" name="sdb"><ovirt-vm:imageID>d0a00b64-3676-4783-a7af-9bb53940b03e</ovirt-vm:imageID><ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID><ovirt-vm:volumeID>a88be564-84f8-4232-9ba6-91be4625a469</ovirt-vm:volumeID><ovirt-vm:domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ovirt-vm:domainID></ovirt-vm:device><ovirt-vm:device devtype="disk" name="sda"><ovirt-vm:imageID>8e17d226-94d0-42f8-ae29-9c795db7e522</ovirt-vm:imageID><ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID><ovirt-vm:volumeID>3b4e2ee9-ba96-4b4d-8392-94e2736e6dab</ovirt-vm:volumeID><ovirt-vm:domainID>461517fd-986a-4b39-9b84-5fd54ef06967</ovirt-vm:domainID></ovirt-vm:device><ovirt-vm:device devtype="disk" name="sdc"><ovirt-vm:imageID>03352bba-9d9f-4fca-ac35-f4aa7767e0b6</ovirt-vm:imageID><ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID><ovirt-vm:volumeID>26a2e47d-2b10-454a-83f4-3c3c96d27f72</ovirt-vm:volumeID><ovirt-vm:domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ovirt-vm:domainID></ovirt-vm:device><launchPaused>false</launchPaused><resumeBehavior>auto_resume</resumeBehavior></ovirt-vm:vm></metadata></domain>, enableGuestEvents=true, vmId=56b28418-9dcf-4d46-87fe-0dfef05bb141, devices=[Ljava.lang.Object;@1a054604, guestDiskMapping={}, username=Unknown, vmType=kvm, smp=1, arch=x86_64, clientIp=, statusTime=7232123440, vmName=nNone, guestIPs=}], log id: 7f6022f8 2018-07-03 11:28:40,522+08 ERROR [org.ovirt.engine.core.bll.AddUnmanagedVmsCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-58) [55d6983c] Command 'org.ovirt.engine.core.bll.AddUnmanagedVmsCommand' failed: null 2018-07-03 11:28:40,522+08 ERROR [org.ovirt.engine.core.bll.AddUnmanagedVmsCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-58) [55d6983c] Exception: java.lang.NumberFormatException: null at java.lang.Integer.parseInt(Integer.java:542) [rt.jar:1.8.0_161] at java.lang.Integer.parseInt(Integer.java:615) [rt.jar:1.8.0_161] at org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerObjectsBuilder.parseIntVdsProperty(VdsBrokerObjectsBuilder.java:777) [vdsbroker.jar:] at org.ovirt.engine.core.bll.AddUnmanagedVmsCommand.convertVm(AddUnmanagedVmsCommand.java:121) [bll.jar:] at org.ovirt.engine.core.bll.AddUnmanagedVmsCommand.executeCommand(AddUnmanagedVmsCommand.java:97) [bll.jar:] at org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1132) [bll.jar:] at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1272) [bll.jar:] at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1914) [bll.jar:] at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInSuppressed(TransactionSupport.java:164) [utils.jar:] at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:103) [utils.jar:] at org.ovirt.engine.core.bll.CommandBase.execute(CommandBase.java:1332) [bll.jar:] at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:406) [bll.jar:] at org.ovirt.engine.core.bll.executor.DefaultBackendActionExecutor.execute(DefaultBackendActionExecutor.java:13) [bll.jar:] at org.ovirt.engine.core.bll.Backend.runAction(Backend.java:468) [bll.jar:] at org.ovirt.engine.core.bll.Backend.runActionImpl(Backend.java:450) [bll.jar:] at org.ovirt.engine.core.bll.Backend.runInternalAction(Backend.java:656) [bll.jar:] at sun.reflect.GeneratedMethodAccessor452.invoke(Unknown Source) [:1.8.0_161] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_161] at java.lang.reflect.Method.invoke(Method.java:498) [rt.jar:1.8.0_161] at org.jboss.as.ee.component.ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptor.java:52) at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:509) at org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.delegateInterception(Jsr299BindingsInterceptor.java:78) at org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.doMethodInterception(Jsr299BindingsInterceptor.java:88) at org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.processInvocation(Jsr299BindingsInterceptor.java:101) at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63) at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.as.ejb3.component.invocationmetrics.ExecutionTimeInterceptor.processInvocation(ExecutionTimeInterceptor.java:43) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.as.ee.concurrent.ConcurrentContextInterceptor.processInvocation(ConcurrentContextInterceptor.java:45) [wildfly-ee-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:40) at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:53) at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:52) at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.as.ejb3.component.singleton.SingletonComponentInstanceAssociationInterceptor.processInvocation(SingletonComponentInstanceAssociationInterceptor.java:53) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:264) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:379) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:244) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:509) at org.jboss.weld.ejb.AbstractEJBRequestScopeActivationInterceptor.aroundInvoke(AbstractEJBRequestScopeActivationInterceptor.java:73) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final] at org.jboss.as.weld.ejb.EjbRequestScopeActivationInterceptor.processInvocation(EjbRequestScopeActivationInterceptor.java:89) at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.as.ejb3.component.invocationmetrics.WaitTimeInterceptor.processInvocation(WaitTimeInterceptor.java:47) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.as.ejb3.security.SecurityContextInterceptor.processInvocation(SecurityContextInterceptor.java:100) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.as.ejb3.deployment.processors.StartupAwaitInterceptor.processInvocation(StartupAwaitInterceptor.java:22) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.as.ejb3.component.interceptors.ShutDownInterceptorFactory$1.processInvocation(ShutDownInterceptorFactory.java:64) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:67) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50) at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.invocation.ContextClassLoaderInterceptor.processInvocation(ContextClassLoaderInterceptor.java:60) at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.invocation.InterceptorContext.run(InterceptorContext.java:438) at org.wildfly.security.manager.WildFlySecurityManager.doChecked(WildFlySecurityManager.java:609) at org.jboss.invocation.AccessCheckingInterceptor.processInvocation(AccessCheckingInterceptor.java:57) at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:53) at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:198) at org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:185) at org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:81) at org.ovirt.engine.core.bll.interfaces.BackendInternal$$$view3.runInternalAction(Unknown Source) [bll.jar:] at sun.reflect.GeneratedMethodAccessor451.invoke(Unknown Source) [:1.8.0_161] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_161] at java.lang.reflect.Method.invoke(Method.java:498) [rt.jar:1.8.0_161] at org.jboss.weld.util.reflection.Reflections.invokeAndUnwrap(Reflections.java:433) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final] at org.jboss.weld.bean.proxy.EnterpriseBeanProxyMethodHandler.invoke(EnterpriseBeanProxyMethodHandler.java:127) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final] at org.jboss.weld.bean.proxy.EnterpriseTargetBeanInstance.invoke(EnterpriseTargetBeanInstance.java:56) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final] at org.jboss.weld.bean.proxy.InjectionPointPropagatingEnterpriseTargetBeanInstance.invoke(InjectionPointPropagatingEnterpriseTargetBeanInstance.java:67) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final] at org.jboss.weld.bean.proxy.ProxyMethodHandler.invoke(ProxyMethodHandler.java:100) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final] at org.ovirt.engine.core.bll.BackendCommandObjectsHandler$BackendInternal$BackendLocal$2049259618$Proxy$_$$_Weld$EnterpriseProxy$.runInternalAction(Unknown Source) [bll.jar:] at org.ovirt.engine.core.bll.VdsEventListener.addUnmanagedVms(VdsEventListener.java:473) [bll.jar:] at org.ovirt.engine.core.vdsbroker.monitoring.VmsMonitoring.addUnmanagedVms(VmsMonitoring.java:326) [vdsbroker.jar:] at org.ovirt.engine.core.vdsbroker.monitoring.VmsMonitoring.perform(VmsMonitoring.java:96) [vdsbroker.jar:] at org.ovirt.engine.core.vdsbroker.monitoring.PollVmStatsRefresher.poll(PollVmStatsRefresher.java:43) [vdsbroker.jar:] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [rt.jar:1.8.0_161] at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) [rt.jar:1.8.0_161] at org.glassfish.enterprise.concurrent.internal.ManagedScheduledThreadPoolExecutor$ManagedScheduledFutureTask.access$201(ManagedScheduledThreadPoolExecutor.java:383) [javax.enterprise.concurrent-1.0.jar:] at org.glassfish.enterprise.concurrent.internal.ManagedScheduledThreadPoolExecutor$ManagedScheduledFutureTask.run(ManagedScheduledThreadPoolExecutor.java:534) [javax.enterprise.concurrent-1.0.jar:] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [rt.jar:1.8.0_161] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [rt.jar:1.8.0_161] at java.lang.Thread.run(Thread.java:748) [rt.jar:1.8.0_161] at org.glassfish.enterprise.concurrent.ManagedThreadFactoryImpl$ManagedThread.run(ManagedThreadFactoryImpl.java:250) [javax.enterprise.concurrent-1.0.jar:] at org.jboss.as.ee.concurrent.service.ElytronManagedThreadFactory$ElytronManagedThread.run(ElytronManagedThreadFactory.java:78) 2018-07-03 11:28:41,840+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (default task-65) [a2075a4a-458f-4224-820a-c3acb8d776fa] Lock Acquired to object 'EngineLock:{exclusiveLocks='[0febbdb6-9b90-4052-8169-4f6792d99d1e=VM]', sharedLocks=''}' 2018-07-03 11:28:41,871+08 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (default task-65) [a2075a4a-458f-4224-820a-c3acb8d776fa] START, IsVmDuringInitiatingVDSCommand( IsVmDuringInitiatingVDSCommandParameters:{vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}), log id: 18b64302 2018-07-03 11:28:41,871+08 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (default task-65) [a2075a4a-458f-4224-820a-c3acb8d776fa] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 18b64302 2018-07-03 11:28:41,939+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] Running command: RunVmCommand internal: false. Entities affected : ID: 0febbdb6-9b90-4052-8169-4f6792d99d1e Type: VMAction group RUN_VM with role type USER 2018-07-03 11:28:41,995+08 INFO [org.ovirt.engine.core.bll.scheduling.policyunits.PreferredHostsWeightPolicyUnit] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] Penalizing host 'tchypnode2.ecr.com' because it is not preferred. 2018-07-03 11:28:41,995+08 INFO [org.ovirt.engine.core.bll.scheduling.policyunits.PreferredHostsWeightPolicyUnit] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] Penalizing host 'tchypnode1.ecr.com' because it is not preferred. 2018-07-03 11:28:41,995+08 INFO [org.ovirt.engine.core.bll.scheduling.policyunits.PreferredHostsWeightPolicyUnit] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] Penalizing host 'storage2' because it is not preferred. 2018-07-03 11:28:42,004+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] Running VM with attached cd 'CentOS-7-x86_64-Minimal-1804.iso' 2018-07-03 11:28:42,007+08 INFO [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] START, UpdateVmDynamicDataVDSCommand( UpdateVmDynamicDataVDSCommandParameters:{hostId='null', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e', vmDynamic='org.ovirt.engine.core.common.businessentities.VmDynamic@3746b80a'}), log id: 33de8399 2018-07-03 11:28:42,008+08 INFO [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] FINISH, UpdateVmDynamicDataVDSCommand, log id: 33de8399 2018-07-03 11:28:42,011+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] START, IsoPrefixVDSCommand(HostName = storage3, VdsAndPoolIDVDSParametersBase:{hostId='862f44c5-3529-4ef1-a2a6-2910d5b93460', storagePoolId='5b0f6949-02d6-00bf-008b-000000000167'}), log id: 39168ed2 2018-07-03 11:28:42,011+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] FINISH, IsoPrefixVDSCommand, return: /rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111, log id: 39168ed2 2018-07-03 11:28:42,017+08 INFO [org.ovirt.engine.core.vdsbroker.CreateVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] START, CreateVDSCommand( CreateVDSCommandParameters:{hostId='862f44c5-3529-4ef1-a2a6-2910d5b93460', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e', vm='VM [badvm-avodev-controller]'}), log id: 72322ec5 2018-07-03 11:28:42,020+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] START, CreateBrokerVDSCommand(HostName = storage3, CreateVDSCommandParameters:{hostId='862f44c5-3529-4ef1-a2a6-2910d5b93460', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e', vm='VM [badvm-avodev-controller]'}), log id: 68b50b27 2018-07-03 11:28:42,024+08 INFO [org.ovirt.engine.core.vdsbroker.builder.vminfo.VmInfoBuilderImpl] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] Bootable disk '1ea736ad-164c-4ac2-a6ce-9b8e6eceefde' set to index '0' 2018-07-03 11:28:42,042+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] VM {memGuaranteedSize=4096, smpThreadsPerCore=1, cpuType=Nehalem, vmId=0febbdb6-9b90-4052-8169-4f6792d99d1e, acpiEnable=true, tabletEnable=true, vmType=kvm, smp=4, smpCoresPerSocket=1, emulatedMachine=pc-i440fx-2.5, smartcardEnable=false, guestNumaNodes=[{memory=8192, cpus=0,1,2,3, nodeIndex=0}], transparentHugePages=true, displayNetwork=ovirtmgmt, vmName=badvm-avodev-controller, maxVCpus=16, kvmEnable=true, devices=[{address={bus=0x00, domain=0x0000, function=0x0, slot=0x02, type=pci}, type=video, specParams={vram=16384}, device=vga, deviceId=a50622b4-d3d4-4319-ae6d-0c66848321ef}, {type=graphics, specParams={keyMap=en-us}, device=vnc, deviceId=8dd702a4-f289-4bd1-a991-74b9c7224568}, {iface=ide, shared=false, path=/rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111/CentOS-7-x86_64-Minimal-1804.iso, address={bus=1, controller=0, unit=0, type=drive, target=0}, readonly=true, bootOrder=2, index=2, type=disk, specParams={path=CentOS-7-x86_64-Minimal-1804.iso}, device=cdrom, deviceId=89f6751c-9b33-4171-9dfa-e3a513ae7729}, {discard=false, shared=false, address={bus=0, controller=0, unit=0, type=drive, target=0}, imageID=1ea736ad-164c-4ac2-a6ce-9b8e6eceefde, format=cow, index=0, optional=false, type=disk, deviceId=1ea736ad-164c-4ac2-a6ce-9b8e6eceefde, domainID=461517fd-986a-4b39-9b84-5fd54ef06967, propagateErrors=off, iface=scsi, readonly=false, bootOrder=1, poolID=5b0f6949-02d6-00bf-008b-000000000167, volumeID=b7f8140f-3a34-40fd-9fec-878df3d159a4, diskType=file, specParams={}, device=disk}, {discard=false, shared=false, address={bus=0, controller=0, unit=1, type=drive, target=0}, imageID=183b3ace-64e1-49cf-a214-b5462f989171, format=cow, optional=false, type=disk, deviceId=183b3ace-64e1-49cf-a214-b5462f989171, domainID=c5a790c2-2885-4220-b803-35e4a40ecef6, propagateErrors=off, iface=scsi, readonly=false, poolID=5b0f6949-02d6-00bf-008b-000000000167, volumeID=36ea0af6-b681-4923-bf70-6b51717f372d, diskType=file, specParams={}, device=disk}, {discard=false, shared=false, address={bus=0, controller=0, unit=2, type=drive, target=0}, imageID=5866d66c-0756-4544-af6a-95f5aed9312b, format=cow, optional=false, type=disk, deviceId=5866d66c-0756-4544-af6a-95f5aed9312b, domainID=c5a790c2-2885-4220-b803-35e4a40ecef6, propagateErrors=off, iface=scsi, readonly=false, poolID=5b0f6949-02d6-00bf-008b-000000000167, volumeID=1751cd16-fd89-4441-b847-45169689c9a7, diskType=file, specParams={}, device=disk}, {filter=vdsm-no-mac-spoofing, nicModel=pv, address={bus=0x00, domain=0x0000, function=0x0, slot=0x03, type=pci}, filterParameters=[], type=interface, specParams={inbound={}, outbound={}}, device=bridge, linkActive=true, deviceId=59cdfb1f-ef08-4fee-a99b-27ad6a27c14e, macAddr=00:1a:4a:16:01:f5, network=ovirtmgmt}, {filter=vdsm-no-mac-spoofing, nicModel=pv, address={bus=0x00, domain=0x0000, function=0x0, slot=0x07, type=pci}, filterParameters=[], type=interface, specParams={inbound={}, outbound={}}, device=bridge, linkActive=true, deviceId=d3bcaa77-ef63-4371-a59f-8e9d444c9cec, macAddr=00:1a:4a:16:01:f6, network=ovirtmgmt}, {address={bus=0x00, domain=0x0000, function=0x2, slot=0x01, type=pci}, index=0, model=piix3-uhci, type=controller, specParams={}, device=usb, deviceId=05c84b2b-2f7a-4dbf-a4f4-89572562bdca}, {address={bus=0x00, domain=0x0000, function=0x0, slot=0x06, type=pci}, type=balloon, specParams={model=virtio}, device=memballoon, deviceId=0a8c9c29-575c-4bcd-b983-7c7d1b5bf020}, {index=0, model=virtio-scsi, type=controller, specParams={}, device=scsi, deviceId=cc5b8141-51ed-4a8b-9cf4-a703fa9a3dd1}, {address={bus=0x00, domain=0x0000, function=0x0, slot=0x04, type=pci}, type=controller, specParams={}, device=virtio-serial, deviceId=d2049d59-412f-4518-9e0e-22c3ead63438}, {model=virtio, type=rng, specParams={source=urandom}, device=virtio, deviceId=befbd382-3e77-4773-a3bb-766b660a4248}], custom={device_17eb478e-16dd-4d17-9f90-d978a28cc2b9device_50c094be-df54-477f-a0e2-f2ce36a2132fdevice_04a84b3e-b86b-454e-96bc-2d3151a315f6=VmDevice:{id='VmDeviceId:{deviceId='04a84b3e-b86b-454e-96bc-2d3151a315f6', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}', device='unix', type='CHANNEL', specParams='[]', address='{type=virtio-serial, bus=0, controller=0, port=1}', managed='false', plugged='true', readOnly='false', deviceAlias='channel0', customProperties='[]', snapshotId='null', logicalName='null', hostDevice=''}, device_17eb478e-16dd-4d17-9f90-d978a28cc2b9device_50c094be-df54-477f-a0e2-f2ce36a2132f=VmDevice:{id='VmDeviceId:{deviceId='50c094be-df54-477f-a0e2-f2ce36a2132f', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}', device='scsi', type='CONTROLLER', specParams='[index=0]', address='{type=pci, slot=0x05, bus=0x00, domain=0x0000, function=0x0}', managed='false', plugged='true', readOnly='false', deviceAlias='scsi0', customProperties='[]', snapshotId='null', logicalName='null', hostDevice=''}, device_17eb478e-16dd-4d17-9f90-d978a28cc2b9device_50c094be-df54-477f-a0e2-f2ce36a2132fdevice_04a84b3e-b86b-454e-96bc-2d3151a315f6device_0f1f725b-ec13-4ce4-9dfb-8882aa0b28e7=VmDevice:{id='VmDeviceId:{deviceId='0f1f725b-ec13-4ce4-9dfb-8882aa0b28e7', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}', device='unix', type='CHANNEL', specParams='[]', address='{type=virtio-serial, bus=0, controller=0, port=2}', managed='false', plugged='true', readOnly='false', deviceAlias='channel1', customProperties='[]', snapshotId='null', logicalName='null', hostDevice=''}, device_17eb478e-16dd-4d17-9f90-d978a28cc2b9=VmDevice:{id='VmDeviceId:{deviceId='17eb478e-16dd-4d17-9f90-d978a28cc2b9', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}', device='ide', type='CONTROLLER', specParams='[]', address='{type=pci, slot=0x01, bus=0x00, domain=0x0000, function=0x1}', managed='false', plugged='true', readOnly='false', deviceAlias='ide', customProperties='[]', snapshotId='null', logicalName='null', hostDevice=''}}, timeOffset=0, nice=0, maxMemSize=32768, maxMemSlots=16, bootMenuEnable=false, memSize=8192, agentChannelName=ovirt-guest-agent.0} 2018-07-03 11:28:42,056+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] VM <?xml version="1.0" encoding="UTF-8"?><domain type="kvm" xmlns:ovirt-tune="http://ovirt.org/vm/tune/1.0" xmlns:ovirt-vm="http://ovirt.org/vm/1.0"> <name>badvm-avodev-controller</name> <uuid>0febbdb6-9b90-4052-8169-4f6792d99d1e</uuid> <memory>8388608</memory> <currentMemory>8388608</currentMemory> <maxMemory slots="16">33554432</maxMemory> <vcpu current="4">16</vcpu> <sysinfo type="smbios"> <system> <entry name="manufacturer">oVirt</entry> <entry name="product">OS-NAME:</entry> <entry name="version">OS-VERSION:</entry> <entry name="serial">HOST-SERIAL:</entry> <entry name="uuid">0febbdb6-9b90-4052-8169-4f6792d99d1e</entry> </system> </sysinfo> <clock offset="variable" adjustment="0"> <timer name="rtc" tickpolicy="catchup"/> <timer name="pit" tickpolicy="delay"/> <timer name="hpet" present="no"/> </clock> <features> <acpi/> </features> <cpu match="exact"> <model>Nehalem</model> <topology cores="1" threads="1" sockets="16"/> <numa> <cell cpus="0,1,2,3" memory="8388608"/> </numa> </cpu> <cputune/> <devices> <input type="tablet" bus="usb"/> <channel type="unix"> <target type="virtio" name="ovirt-guest-agent.0"/> <source mode="bind" path="/var/lib/libvirt/qemu/channels/0febbdb6-9b90-4052-8169-4f6792d99d1e.ovirt-guest-agent.0"/> </channel> <channel type="unix"> <target type="virtio" name="org.qemu.guest_agent.0"/> <source mode="bind" path="/var/lib/libvirt/qemu/channels/0febbdb6-9b90-4052-8169-4f6792d99d1e.org.qemu.guest_agent.0"/> </channel> <controller type="usb" model="piix3-uhci" index="0"> <address bus="0x00" domain="0x0000" function="0x2" slot="0x01" type="pci"/> </controller> <memballoon model="virtio"> <stats period="5"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x06" type="pci"/> </memballoon> <controller type="ide"> <address bus="0x00" domain="0x0000" function="0x1" slot="0x01" type="pci"/> </controller> <controller type="scsi" index="0"> <address bus="0x00" domain="0x0000" function="0x0" slot="0x05" type="pci"/> </controller> <graphics type="vnc" port="-1" autoport="yes" passwd="*****" passwdValidTo="1970-01-01T00:00:01" keymap="en-us"> <listen type="network" network="vdsm-ovirtmgmt"/> </graphics> <video> <model type="vga" vram="16384" heads="1"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x02" type="pci"/> </video> <rng model="virtio"> <backend model="random">/dev/urandom</backend> </rng> <controller type="virtio-serial" index="0" ports="16"> <address bus="0x00" domain="0x0000" function="0x0" slot="0x04" type="pci"/> </controller> <interface type="bridge"> <model type="virtio"/> <link state="up"/> <source bridge="ovirtmgmt"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x03" type="pci"/> <mac address="00:1a:4a:16:01:f5"/> <filterref filter="vdsm-no-mac-spoofing"/> <bandwidth/> </interface> <interface type="bridge"> <model type="virtio"/> <link state="up"/> <source bridge="ovirtmgmt"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x07" type="pci"/> <mac address="00:1a:4a:16:01:f6"/> <filterref filter="vdsm-no-mac-spoofing"/> <bandwidth/> </interface> <disk type="file" device="cdrom" snapshot="no"> <driver name="qemu" type="raw" error_policy="report"/> <source file="/rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111/CentOS-7-x86_64-Minimal-1804.iso" startupPolicy="optional"/> <target dev="hdc" bus="ide"/> <readonly/> <address bus="1" controller="0" unit="0" type="drive" target="0"/> <boot order="2"/> </disk> <disk snapshot="no" type="file" device="disk"> <target dev="sda" bus="scsi"/> <source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/461517fd-986a-4b39-9b84-5fd54ef06967/images/1ea736ad-164c-4ac2-a6ce-9b8e6eceefde/b7f8140f-3a34-40fd-9fec-878df3d159a4"/> <driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"/> <address bus="0" controller="0" unit="0" type="drive" target="0"/> <boot order="1"/> <serial>1ea736ad-164c-4ac2-a6ce-9b8e6eceefde</serial> </disk> <disk snapshot="no" type="file" device="disk"> <target dev="sdb" bus="scsi"/> <source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/183b3ace-64e1-49cf-a214-b5462f989171/36ea0af6-b681-4923-bf70-6b51717f372d"/> <driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"/> <address bus="0" controller="0" unit="1" type="drive" target="0"/> <serial>183b3ace-64e1-49cf-a214-b5462f989171</serial> </disk> <disk snapshot="no" type="file" device="disk"> <target dev="sdc" bus="scsi"/> <source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/5866d66c-0756-4544-af6a-95f5aed9312b/1751cd16-fd89-4441-b847-45169689c9a7"/> <driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"/> <address bus="0" controller="0" unit="2" type="drive" target="0"/> <serial>5866d66c-0756-4544-af6a-95f5aed9312b</serial> </disk> </devices> <pm> <suspend-to-disk enabled="no"/> <suspend-to-mem enabled="no"/> </pm> <os> <type arch="x86_64" machine="pc-i440fx-2.5">hvm</type> <smbios mode="sysinfo"/> </os> <metadata> <ovirt-tune:qos/> <ovirt-vm:vm> <minGuaranteedMemoryMb type="int">4096</minGuaranteedMemoryMb> <clusterVersion>4.2</clusterVersion> <ovirt-vm:custom/> <ovirt-vm:device mac_address="00:1a:4a:16:01:f6"> <ovirt-vm:custom/> </ovirt-vm:device> <ovirt-vm:device mac_address="00:1a:4a:16:01:f5"> <ovirt-vm:custom/> </ovirt-vm:device> <ovirt-vm:device devtype="disk" name="sdb"> <ovirt-vm:imageID>183b3ace-64e1-49cf-a214-b5462f989171</ovirt-vm:imageID> <ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID> <ovirt-vm:volumeID>36ea0af6-b681-4923-bf70-6b51717f372d</ovirt-vm:volumeID> <ovirt-vm:domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ovirt-vm:domainID> </ovirt-vm:device> <ovirt-vm:device devtype="disk" name="sda"> <ovirt-vm:imageID>1ea736ad-164c-4ac2-a6ce-9b8e6eceefde</ovirt-vm:imageID> <ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID> <ovirt-vm:volumeID>b7f8140f-3a34-40fd-9fec-878df3d159a4</ovirt-vm:volumeID> <ovirt-vm:domainID>461517fd-986a-4b39-9b84-5fd54ef06967</ovirt-vm:domainID> </ovirt-vm:device> <ovirt-vm:device devtype="disk" name="sdc"> <ovirt-vm:imageID>5866d66c-0756-4544-af6a-95f5aed9312b</ovirt-vm:imageID> <ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID> <ovirt-vm:volumeID>1751cd16-fd89-4441-b847-45169689c9a7</ovirt-vm:volumeID> <ovirt-vm:domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ovirt-vm:domainID> </ovirt-vm:device> <launchPaused>false</launchPaused> <resumeBehavior>auto_resume</resumeBehavior> </ovirt-vm:vm> </metadata> </domain> 2018-07-03 11:28:42,072+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] FINISH, CreateBrokerVDSCommand, log id: 68b50b27 2018-07-03 11:28:42,074+08 INFO [org.ovirt.engine.core.vdsbroker.CreateVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] FINISH, CreateVDSCommand, return: WaitForLaunch, log id: 72322ec5 2018-07-03 11:28:42,074+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] Lock freed to object 'EngineLock:{exclusiveLocks='[0febbdb6-9b90-4052-8169-4f6792d99d1e=VM]', sharedLocks=''}' 2018-07-03 11:28:42,079+08 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] EVENT_ID: USER_STARTED_VM(153), VM badvm-avodev-controller was started by admin@internal-authz (Host: storage3). 2018-07-03 11:28:43,803+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DumpXmlsVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-72) [] START, DumpXmlsVDSCommand(HostName = storage3, Params:{hostId='862f44c5-3529-4ef1-a2a6-2910d5b93460', vmIds='[0febbdb6-9b90-4052-8169-4f6792d99d1e]'}), log id: 39084aef 2018-07-03 11:28:43,835+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DumpXmlsVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-72) [] FINISH, DumpXmlsVDSCommand, return: [{vmId=0febbdb6-9b90-4052-8169-4f6792d99d1e, devices=[Ljava.util.Map;@749e3a2d}], log id: 39084aef 2018-07-03 11:28:43,837+08 ERROR [org.ovirt.engine.core.vdsbroker.monitoring.VmDevicesMonitoring] (EE-ManagedThreadFactory-engineScheduled-Thread-72) [] VM '0febbdb6-9b90-4052-8169-4f6792d99d1e' managed non pluggable device was removed unexpectedly from libvirt: 'VmDevice:{id='VmDeviceId:{deviceId='cc5b8141-51ed-4a8b-9cf4-a703fa9a3dd1', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}', device='virtio-scsi', type='CONTROLLER', specParams='[]', address='', managed='true', plugged='false', readOnly='false', deviceAlias='', customProperties='[]', snapshotId='null', logicalName='null', hostDevice='null'}' 2018-07-03 11:28:44,132+08 INFO [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer] (ForkJoinPool-1-worker-9) [] VM '0febbdb6-9b90-4052-8169-4f6792d99d1e'(badvm-avodev-controller) moved from 'WaitForLaunch' --> 'PoweringUp' 2018-07-03 11:28:44,143+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DumpXmlsVDSCommand] (ForkJoinPool-1-worker-9) [] START, DumpXmlsVDSCommand(HostName = storage3, Params:{hostId='862f44c5-3529-4ef1-a2a6-2910d5b93460', vmIds='[0febbdb6-9b90-4052-8169-4f6792d99d1e]'}), log id: 47bab6eb 2018-07-03 11:28:44,184+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DumpXmlsVDSCommand] (ForkJoinPool-1-worker-9) [] FINISH, DumpXmlsVDSCommand, return: [{vmId=0febbdb6-9b90-4052-8169-4f6792d99d1e, devices=[Ljava.util.Map;@5a5f0670}], log id: 47bab6eb 2018-07-03 11:28:44,185+08 ERROR [org.ovirt.engine.core.vdsbroker.monitoring.VmDevicesMonitoring] (ForkJoinPool-1-worker-9) [] VM '0febbdb6-9b90-4052-8169-4f6792d99d1e' managed non pluggable device was removed unexpectedly from libvirt: 'VmDevice:{id='VmDeviceId:{deviceId='cc5b8141-51ed-4a8b-9cf4-a703fa9a3dd1', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}', device='virtio-scsi', type='CONTROLLER', specParams='[]', address='', managed='true', plugged='false', readOnly='false', deviceAlias='', customProperties='[]', snapshotId='null', logicalName='null', hostDevice='null'}' 2018-07-03 11:28:53,460+08 INFO [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer] (EE-ManagedThreadFactory-engineScheduled-Thread-91) [] VM '56b28418-9dcf-4d46-87fe-0dfef05bb141' was discovered as 'Down' on VDS 'a24d7172-1c3c-44ab-8ef3-9f863aeb4a87'(storage2) 2018-07-03 11:28:53,477+08 INFO [org.ovirt.engine.core.bll.AddUnmanagedVmsCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-91) [609d6b57] Running command: AddUnmanagedVmsCommand internal: true. 2018-07-03 11:28:53,480+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-91) [609d6b57] START, FullListVDSCommand(HostName = storage2, FullListVDSCommandParameters:{hostId='a24d7172-1c3c-44ab-8ef3-9f863aeb4a87', vmIds='[56b28418-9dcf-4d46-87fe-0dfef05bb141]'}), log id: 7ff29962 2018-07-03 11:28:53,486+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-91) [609d6b57] FINISH, FullListVDSCommand, return: [{status=Down, guestFQDN=, kvmEnable=true, xml=<?xml version="1.0" encoding="UTF-8"?><domain type="kvm" xmlns:ovirt-tune="http://ovirt.org/vm/tune/1.0" xmlns:ovirt-vm="http://ovirt.org/vm/1.0"><name>avodev-controller</name><uuid>56b28418-9dcf-4d46-87fe-0dfef05bb141</uuid><memory>8388608</memory><currentMemory>8388608</currentMemory><maxMemory slots="16">33554432</maxMemory><vcpu current="4">16</vcpu><sysinfo type="smbios"><system><entry name="manufacturer">oVirt</entry><entry name="product">OS-NAME:</entry><entry name="version">OS-VERSION:</entry><entry name="serial">HOST-SERIAL:</entry><entry name="uuid">56b28418-9dcf-4d46-87fe-0dfef05bb141</entry></system></sysinfo><clock offset="variable" adjustment="0"><timer name="rtc" tickpolicy="catchup"></timer><timer name="pit" tickpolicy="delay"></timer><timer name="hpet" present="no"></timer></clock><features><acpi></acpi></features><cpu match="exact"><model>Nehalem</model><topology cores="1" threads="1" sockets="16"></topology><numa><cell cpus="0,1,2,3" memory="8388608"></cell></numa></cpu><cputune></cputune><devices><input type="tablet" bus="usb"></input><channel type="unix"><target type="virtio" name="ovirt-guest-agent.0"></target><source mode="bind" path="/var/lib/libvirt/qemu/channels/56b28418-9dcf-4d46-87fe-0dfef05bb141.ovirt-guest-agent.0"></source></channel><channel type="unix"><target type="virtio" name="org.qemu.guest_agent.0"></target><source mode="bind" path="/var/lib/libvirt/qemu/channels/56b28418-9dcf-4d46-87fe-0dfef05bb141.org.qemu.guest_agent.0"></source></channel><controller type="scsi" model="virtio-scsi" index="0"></controller><controller type="usb" model="piix3-uhci" index="0"><address bus="0x00" domain="0x0000" function="0x2" slot="0x01" type="pci"></address></controller><controller type="virtio-serial" index="0" ports="16"><address bus="0x00" domain="0x0000" function="0x0" slot="0x04" type="pci"></address></controller><graphics type="vnc" port="-1" autoport="yes" passwd="*****" passwdValidTo="1970-01-01T00:00:01" keymap="en-us"><listen type="network" network="vdsm-ovirtmgmt"></listen></graphics><controller type="ide"><address bus="0x00" domain="0x0000" function="0x1" slot="0x01" type="pci"></address></controller><rng model="virtio"><backend model="random">/dev/urandom</backend></rng><memballoon model="virtio"><stats period="5"></stats><address bus="0x00" domain="0x0000" function="0x0" slot="0x06" type="pci"></address></memballoon><video><model type="vga" vram="16384" heads="1"></model></video><interface type="bridge"><model type="virtio"></model><link state="up"></link><source bridge="ovirtmgmt"></source><address bus="0x00" domain="0x0000" function="0x0" slot="0x03" type="pci"></address><mac address="00:1a:4a:16:01:da"></mac><filterref filter="vdsm-no-mac-spoofing"></filterref><bandwidth></bandwidth></interface><interface type="bridge"><model type="virtio"></model><link state="up"></link><source bridge="ovirtmgmt"></source><address bus="0x00" domain="0x0000" function="0x0" slot="0x07" type="pci"></address><mac address="00:1a:4a:16:01:db"></mac><filterref filter="vdsm-no-mac-spoofing"></filterref><bandwidth></bandwidth></interface><disk type="file" device="cdrom" snapshot="no"><driver name="qemu" type="raw" error_policy="report"></driver><source file="/rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111/CentOS-7-x86_64-Minimal-1804.iso" startupPolicy="optional"></source><target dev="hdc" bus="ide"></target><readonly></readonly><address bus="1" controller="0" unit="0" type="drive" target="0"></address><boot order="2"></boot></disk><disk snapshot="no" type="file" device="disk"><target dev="sda" bus="scsi"></target><source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/461517fd-986a-4b39-9b84-5fd54ef06967/images/8e17d226-94d0-42f8-ae29-9c795db7e522/3b4e2ee9-ba96-4b4d-8392-94e2736e6dab"></source><driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"></driver><address bus="0" controller="0" unit="0" type="drive" target="0"></address><boot order="1"></boot><serial>8e17d226-94d0-42f8-ae29-9c795db7e522</serial></disk><disk snapshot="no" type="file" device="disk"><target dev="sdb" bus="scsi"></target><source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/d0a00b64-3676-4783-a7af-9bb53940b03e/a88be564-84f8-4232-9ba6-91be4625a469"></source><driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"></driver><address bus="0" controller="0" unit="1" type="drive" target="0"></address><serial>d0a00b64-3676-4783-a7af-9bb53940b03e</serial></disk><disk snapshot="no" type="file" device="disk"><target dev="sdc" bus="scsi"></target><source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/03352bba-9d9f-4fca-ac35-f4aa7767e0b6/26a2e47d-2b10-454a-83f4-3c3c96d27f72"></source><driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"></driver><address bus="0" controller="0" unit="2" type="drive" target="0"></address><serial>03352bba-9d9f-4fca-ac35-f4aa7767e0b6</serial></disk></devices><pm><suspend-to-disk enabled="no"></suspend-to-disk><suspend-to-mem enabled="no"></suspend-to-mem></pm><os><type arch="x86_64" machine="pc-i440fx-2.5">hvm</type><smbios mode="sysinfo"></smbios></os><metadata><ovirt-tune:qos></ovirt-tune:qos><ovirt-vm:vm><minGuaranteedMemoryMb type="int">4096</minGuaranteedMemoryMb><clusterVersion>4.2</clusterVersion><ovirt-vm:custom></ovirt-vm:custom><ovirt-vm:device mac_address="00:1a:4a:16:01:db"><ovirt-vm:custom></ovirt-vm:custom></ovirt-vm:device><ovirt-vm:device mac_address="00:1a:4a:16:01:da"><ovirt-vm:custom></ovirt-vm:custom></ovirt-vm:device><ovirt-vm:device devtype="disk" name="sdb"><ovirt-vm:imageID>d0a00b64-3676-4783-a7af-9bb53940b03e</ovirt-vm:imageID><ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID><ovirt-vm:volumeID>a88be564-84f8-4232-9ba6-91be4625a469</ovirt-vm:volumeID><ovirt-vm:domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ovirt-vm:domainID></ovirt-vm:device><ovirt-vm:device devtype="disk" name="sda"><ovirt-vm:imageID>8e17d226-94d0-42f8-ae29-9c795db7e522</ovirt-vm:imageID><ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID><ovirt-vm:volumeID>3b4e2ee9-ba96-4b4d-8392-94e2736e6dab</ovirt-vm:volumeID><ovirt-vm:domainID>461517fd-986a-4b39-9b84-5fd54ef06967</ovirt-vm:domainID></ovirt-vm:device><ovirt-vm:device devtype="disk" name="sdc"><ovirt-vm:imageID>03352bba-9d9f-4fca-ac35-f4aa7767e0b6</ovirt-vm:imageID><ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID><ovirt-vm:volumeID>26a2e47d-2b10-454a-83f4-3c3c96d27f72</ovirt-vm:volumeID><ovirt-vm:domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ovirt-vm:domainID></ovirt-vm:device><launchPaused>false</launchPaused><resumeBehavior>auto_resume</resumeBehavior></ovirt-vm:vm></metadata></domain>, enableGuestEvents=true, vmId=56b28418-9dcf-4d46-87fe-0dfef05bb141, devices=[Ljava.lang.Object;@671dc026, guestDiskMapping={}, username=Unknown, vmType=kvm, smp=1, arch=x86_64, clientIp=, statusTime=4814199690, vmName=nNone, guestIPs=}], log id: 7ff29962 2018-07-03 11:28:53,486+08 ERROR [org.ovirt.engine.core.bll.AddUnmanagedVmsCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-91) [609d6b57] Command 'org.ovirt.engine.core.bll.AddUnmanagedVmsCommand' failed: null 在 2018-07-03 12:10:06,"Pym" <pym0914@163.com> : Hi, #vdsm.log 2018-07-02 19:22:02,316+0800 INFO (libvirt/events) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') CPU running: onResume (vm:5742) 2018-07-02 19:22:02,586+0800 INFO (vm/0febbdb6) [root] /usr/libexec/vdsm/hooks/after_vm_start/50_openstacknet: rc=0 err= (hooks:109) 2018-07-02 19:22:02,807+0800 INFO (vm/0febbdb6) [root] /usr/libexec/vdsm/hooks/after_vm_start/openstacknet_utils.py: rc=0 err= (hooks:109) 2018-07-02 19:22:02,824+0800 WARN (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Found unknown drive: {'alias': 'ide0-1-0', 'readonly': 'True', 'iface': 'ide', 'name': 'hdc', 'address': {'bus': '1', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'cdrom', 'path': '/rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111/CentOS-7-x86_64-Minimal-1804.iso', 'type': 'disk', 'bootOrder': '2'} (storage:217) 2018-07-02 19:22:02,824+0800 WARN (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Found unknown drive: {'alias': 'scsi0-0-0', 'readonly': 'False', 'iface': 'ide', 'name': 'sda', 'address': {'bus': '0', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'disk', 'path': '/rhev/data-center/mnt/192.168.100.59:_exports_vmstorage/461517fd-986a-4b39-9b84-5fd54ef06967/images/1ea736ad-164c-4ac2-a6ce-9b8e6eceefde/b7f8140f-3a34-40fd-9fec-878df3d159a4', 'type': 'disk', 'bootOrder': '1'} (storage:217) 2018-07-02 19:22:02,825+0800 WARN (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Found unknown drive: {'alias': 'scsi0-0-1', 'readonly': 'False', 'iface': 'ide', 'name': 'sdb', 'address': {'bus': '0', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '1'}, 'device': 'disk', 'path': '/rhev/data-center/mnt/192.168.100.241:_exports_vmstorage/c5a790c2-2885-4220-b803-35e4a40ecef6/images/183b3ace-64e1-49cf-a214-b5462f989171/36ea0af6-b681-4923-bf70-6b51717f372d', 'type': 'disk'} (storage:217) 2018-07-02 19:22:02,825+0800 WARN (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Found unknown drive: {'alias': 'scsi0-0-2', 'readonly': 'False', 'iface': 'ide', 'name': 'sdc', 'address': {'bus': '0', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '2'}, 'device': 'disk', 'path': '/rhev/data-center/mnt/192.168.100.241:_exports_vmstorage/c5a790c2-2885-4220-b803-35e4a40ecef6/images/5866d66c-0756-4544-af6a-95f5aed9312b/1751cd16-fd89-4441-b847-45169689c9a7', 'type': 'disk'} (storage:217) 2018-07-02 19:22:02,840+0800 INFO (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Starting connection (guestagent:244) 2018-07-02 19:22:02,841+0800 ERROR (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Failed to connect to guest agent channel (vm:2415) Traceback (most recent call last): File "/usr/lib/python2.7/site-packages/vdsm/virt/vm.py", line 2413, in _vmDependentInit self.guestAgent.start() File "/usr/lib/python2.7/site-packages/vdsm/virt/guestagent.py", line 245, in start self._prepare_socket() File "/usr/lib/python2.7/site-packages/vdsm/virt/guestagent.py", line 287, in _prepare_socket supervdsm.getProxy().prepareVmChannel(self._socketName) File "/usr/lib/python2.7/site-packages/vdsm/supervdsm.py", line 53, in __call__ return callMethod() File "/usr/lib/python2.7/site-packages/vdsm/supervdsm.py", line 51, in <lambda> **kwargs) File "<string>", line 2, in prepareVmChannel File "/usr/lib64/python2.7/multiprocessing/managers.py", line 773, in _callmethod raise convert_to_error(kind, result) OSError: [Errno 2] No such file or directory: '/var/lib/libvirt/qemu/channels/0febbdb6-9b90-4052-8169-4f6792d99d1e.com.redhat.rhevm.vdsm' 2018-07-02 19:22:02,843+0800 INFO (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') CPU running: domain initialization (vm:5742) 2018-07-02 19:22:02,910+0800 INFO (jsonrpc/6) [api.host] START dumpxmls(vmList=[u'0febbdb6-9b90-4052-8169-4f6792d99d1e']) from=::ffff:192.168.100.59,60612 (api:46) 2018-07-02 19:22:09,292+0800 ERROR (periodic/39) [virt.vmstats] VM metrics collection failed (vmstats:260) Traceback (most recent call last): File "/usr/lib/python2.7/site-packages/vdsm/virt/vmstats.py", line 217, in send_metrics diskinfo['readOps'] KeyError: 'readOps' 2018-07-02 19:22:09,813+0800 INFO (jsonrpc/2) [api.host] START getStats() from=::ffff:192.168.100.59,60612 (api:46) 2018-07-02 19:22:09,816+0800 INFO (jsonrpc/2) [vdsm.api] START repoStats(domains=()) from=::ffff:192.168.100.59,60612, task_id=36236da1-3586-4324-a842-71aafb37d124 (api:46) 2018-07-02 19:22:09,816+0800 INFO (jsonrpc/2) [vdsm.api] FINISH repoStats return={u'6ff100c0-d08d-4504-832d-53876bf97349': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000776933', 'lastCheck': '6.6', 'valid': True}, u'5a50d04c-4d39-4249-8fc6-24df9bd096b0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000358834', 'lastCheck': '4.1', 'valid': True}, u'c5a790c2-2885-4220-b803-35e4a40ecef6': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000328361', 'lastCheck': '3.3', 'valid': True}, u'461517fd-986a-4b39-9b84-5fd54ef06967': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000412804', 'lastCheck': '5.7', 'valid': True}} from=::ffff:192.168.100.59,60612, task_id=36236da1-3586-4324-a842-71aafb37d124 (api:52) 2018-07-02 19:22:09,831+0800 INFO (periodic/39) [vdsm.api] START repoStats(domains=()) from=internal, task_id=b4dcd456-df92-4270-92be-d73d7e7127f9 (api:46) 2018-07-02 19:22:09,832+0800 INFO (periodic/39) [vdsm.api] FINISH repoStats return={u'6ff100c0-d08d-4504-832d-53876bf97349': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000776933', 'lastCheck': '6.6', 'valid': True}, u'5a50d04c-4d39-4249-8fc6-24df9bd096b0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000358834', 'lastCheck': '4.1', 'valid': True}, u'c5a790c2-2885-4220-b803-35e4a40ecef6': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000328361', 'lastCheck': '3.3', 'valid': True}, u'461517fd-986a-4b39-9b84-5fd54ef06967': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000412804', 'lastCheck': '5.7', 'valid': True}} from=internal, task_id=b4dcd456-df92-4270-92be-d73d7e7127f9 (api:52) #engine.log 2018-07-02 19:22:07,907+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (default task-41) [2f069134-d730-4ca5-93b7-3925a06234da] Lock Acquired to object 'EngineLock:{exclusiveLocks='[0febbdb6-9b90-4052-8169-4f6792d99d1e=VM]', sharedLocks=''}' 2018-07-02 19:22:07,935+08 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (default task-41) [2f069134-d730-4ca5-93b7-3925a06234da] START, IsVmDuringInitiatingVDSCommand( IsVmDuringInitiatingVDSCommandParameters:{vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}), log id: 703a024f 2018-07-02 19:22:07,936+08 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (default task-41) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 703a024f 2018-07-02 19:22:07,998+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Running command: RunVmCommand internal: false. Entities affected : ID: 0febbdb6-9b90-4052-8169-4f6792d99d1e Type: VMAction group RUN_VM with role type USER 2018-07-02 19:22:08,061+08 INFO [org.ovirt.engine.core.bll.scheduling.policyunits.PreferredHostsWeightPolicyUnit] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Penalizing host 'storage3' because it is not preferred. 2018-07-02 19:22:08,061+08 INFO [org.ovirt.engine.core.bll.scheduling.policyunits.PreferredHostsWeightPolicyUnit] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Penalizing host 'storage2' because it is not preferred. 2018-07-02 19:22:08,073+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Running VM with attached cd 'CentOS-7-x86_64-Minimal-1804.iso' 2018-07-02 19:22:08,075+08 INFO [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] START, UpdateVmDynamicDataVDSCommand( UpdateVmDynamicDataVDSCommandParameters:{hostId='null', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e', vmDynamic='org.ovirt.engine.core.common.businessentities.VmDynamic@540c1d26'}), log id: 11987cd 2018-07-02 19:22:08,076+08 INFO [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, UpdateVmDynamicDataVDSCommand, log id: 11987cd 2018-07-02 19:22:08,079+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] START, IsoPrefixVDSCommand(HostName = tchypnode1.ecr.com, VdsAndPoolIDVDSParametersBase:{hostId='7902d7f8-348e-426e-a3c3-399d08b0eb1e', storagePoolId='5b0f6949-02d6-00bf-008b-000000000167'}), log id: 3d66a9a0 2018-07-02 19:22:08,079+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, IsoPrefixVDSCommand, return: /rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111, log id: 3d66a9a0 2018-07-02 19:22:08,079+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] START, IsoPrefixVDSCommand(HostName = tchypnode1.ecr.com, VdsAndPoolIDVDSParametersBase:{hostId='7902d7f8-348e-426e-a3c3-399d08b0eb1e', storagePoolId='5b0f6949-02d6-00bf-008b-000000000167'}), log id: 3d66a9a0 2018-07-02 19:22:08,079+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, IsoPrefixVDSCommand, return: /rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111, log id: 3d66a9a0 2018-07-02 19:22:08,087+08 INFO [org.ovirt.engine.core.vdsbroker.CreateVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] START, CreateVDSCommand( CreateVDSCommandParameters:{hostId='7902d7f8-348e-426e-a3c3-399d08b0eb1e', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e', vm='VM [badvm-avodev-controller]'}), log id: 54fa1fca 2018-07-02 19:22:08,090+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] START, CreateBrokerVDSCommand(HostName = tchypnode1.ecr.com, CreateVDSCommandParameters:{hostId='7902d7f8-348e-426e-a3c3-399d08b0eb1e', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e', vm='VM [badvm-avodev-controller]'}), log id: 54926511 2018-07-02 19:22:08,096+08 INFO [org.ovirt.engine.core.vdsbroker.builder.vminfo.VmInfoBuilderImpl] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Bootable disk '1ea736ad-164c-4ac2-a6ce-9b8e6eceefde' set to index '0' 2018-07-02 19:22:08,130+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] VM <?xml version="1.0" encoding="UTF-8"?><domain type="kvm" xmlns:ovirt-tune="http://ovirt.org/vm/tune/1.0" xmlns:ovirt-vm="http://ovirt.org/vm/1.0"> <name>badvm-avodev-controller</name> <uuid>0febbdb6-9b90-4052-8169-4f6792d99d1e</uuid> <memory>8388608</memory> <currentMemory>8388608</currentMemory> <maxMemory slots="16">33554432</maxMemory> <vcpu current="4">16</vcpu> <sysinfo type="smbios"> <system> <entry name="manufacturer">oVirt</entry> <entry name="product">OS-NAME:</entry> <entry name="version">OS-VERSION:</entry> <entry name="serial">HOST-SERIAL:</entry> <entry name="uuid">0febbdb6-9b90-4052-8169-4f6792d99d1e</entry> </system> </sysinfo> <clock offset="variable" adjustment="0"> <timer name="rtc" tickpolicy="catchup"/> <timer name="pit" tickpolicy="delay"/> <timer name="hpet" present="no"/> </clock> <features> <acpi/> </features> <cpu match="exact"> <model>Nehalem</model> <topology cores="1" threads="1" sockets="16"/> <numa> <cell cpus="0,1,2,3" memory="8388608"/> <numa> <cell cpus="0,1,2,3" memory="8388608"/> </numa> </cpu> <cputune/> <devices> <input type="tablet" bus="usb"/> <channel type="unix"> <target type="virtio" name="ovirt-guest-agent.0"/> <source mode="bind" path="/var/lib/libvirt/qemu/channels/0febbdb6-9b90-4052-8169-4f6792d99d1e.ovirt-guest-agent.0"/> </channel> <channel type="unix"> <target type="virtio" name="org.qemu.guest_agent.0"/> <source mode="bind" path="/var/lib/libvirt/qemu/channels/0febbdb6-9b90-4052-8169-4f6792d99d1e.org.qemu.guest_agent.0"/> </channel> <controller type="usb" model="piix3-uhci" index="0"> <address bus="0x00" domain="0x0000" function="0x2" slot="0x01" type="pci"/> </controller> <memballoon model="virtio"> <stats period="5"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x06" type="pci"/> </memballoon> <controller type="ide"> <address bus="0x00" domain="0x0000" function="0x1" slot="0x01" type="pci"/> </controller> <controller type="scsi" model="virtio-scsi" index="0"/> <controller type="scsi" index="0"> <address bus="0x00" domain="0x0000" function="0x0" slot="0x05" type="pci"/> </controller> <graphics type="vnc" port="-1" autoport="yes" passwd="*****" passwdValidTo="1970-01-01T00:00:01" keymap="en-us"> <listen type="network" network="vdsm-ovirtmgmt"/> <graphics type="vnc" port="-1" autoport="yes" passwd="*****" passwdValidTo="1970-01-01T00:00:01" keymap="en-us"> <listen type="network" network="vdsm-ovirtmgmt"/> </graphics> <video> <model type="vga" vram="16384" heads="1"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x02" type="pci"/> </video> <rng model="virtio"> <backend model="random">/dev/urandom</backend> </rng> <controller type="virtio-serial" index="0" ports="16"> <address bus="0x00" domain="0x0000" function="0x0" slot="0x04" type="pci"/> </controller> <interface type="bridge"> <model type="virtio"/> <link state="up"/> <source bridge="ovirtmgmt"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x03" type="pci"/> <mac address="00:1a:4a:16:01:f5"/> <filterref filter="vdsm-no-mac-spoofing"/> <bandwidth/> </interface> <interface type="bridge"> <model type="virtio"/> <link state="up"/> <source bridge="ovirtmgmt"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x07" type="pci"/> <mac address="00:1a:4a:16:01:f6"/> <filterref filter="vdsm-no-mac-spoofing"/> <bandwidth/> </interface> <bandwidth/> </interface> <disk type="file" device="cdrom" snapshot="no"> <driver name="qemu" type="raw" error_policy="report"/> <source file="/rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111/CentOS-7-x86_64-Minimal-1804.iso" startupPolicy="optional"/> <target dev="hdc" bus="ide"/> <readonly/> <address bus="1" controller="0" unit="0" type="drive" target="0"/> <boot order="2"/> </disk> <disk snapshot="no" type="file" device="disk"> <target dev="sda" bus="scsi"/> <source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/461517fd-986a-4b39-9b84-5fd54ef06967/images/1ea736ad-164c-4ac2-a6ce-9b8e6eceefde/b7f8140f-3a34-40fd-9fec-878df3d159a4"/> <driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"/> <address bus="0" controller="0" unit="0" type="drive" target="0"/> <boot order="1"/> <serial>1ea736ad-164c-4ac2-a6ce-9b8e6eceefde</serial> </disk> <disk snapshot="no" type="file" device="disk"> <target dev="sdb" bus="scsi"/> <source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/183b3ace-64e1-49cf-a214-b5462f989171/36ea0af6-b681-4923-bf70-6b51717f372d"/> <driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"/> <address bus="0" controller="0" unit="1" type="drive" target="0"/> <serial>183b3ace-64e1-49cf-a214-b5462f989171</serial> </disk> <disk snapshot="no" type="file" device="disk"> <target dev="sdc" bus="scsi"/> <disk snapshot="no" type="file" device="disk"> <target dev="sdc" bus="scsi"/> <source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/5866d66c-0756-4544-af6a-95f5aed9312b/1751cd16-fd89-4441-b847-45169689c9a7"/> <driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"/> <address bus="0" controller="0" unit="2" type="drive" target="0"/> <serial>5866d66c-0756-4544-af6a-95f5aed9312b</serial> </disk> </devices> <pm> <suspend-to-disk enabled="no"/> <suspend-to-mem enabled="no"/> </pm> <os> <type arch="x86_64" machine="pc-i440fx-2.5">hvm</type> <smbios mode="sysinfo"/> </os> <metadata> <ovirt-tune:qos/> <ovirt-vm:vm> <minGuaranteedMemoryMb type="int">4096</minGuaranteedMemoryMb> <clusterVersion>4.2</clusterVersion> <ovirt-vm:custom/> <ovirt-vm:device mac_address="00:1a:4a:16:01:f6"> <ovirt-vm:custom/> </ovirt-vm:device> <ovirt-vm:device mac_address="00:1a:4a:16:01:f5"> <ovirt-vm:custom/> </ovirt-vm:device> <ovirt-vm:device devtype="disk" name="sdb"> <ovirt-vm:imageID>183b3ace-64e1-49cf-a214-b5462f989171</ovirt-vm:imageID> <ovirt-vm:device devtype="disk" name="sdb"> <ovirt-vm:imageID>183b3ace-64e1-49cf-a214-b5462f989171</ovirt-vm:imageID> <ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID> <ovirt-vm:volumeID>36ea0af6-b681-4923-bf70-6b51717f372d</ovirt-vm:volumeID> <ovirt-vm:domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ovirt-vm:domainID> </ovirt-vm:device> <ovirt-vm:device devtype="disk" name="sda"> <ovirt-vm:imageID>1ea736ad-164c-4ac2-a6ce-9b8e6eceefde</ovirt-vm:imageID> <ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID> <ovirt-vm:volumeID>b7f8140f-3a34-40fd-9fec-878df3d159a4</ovirt-vm:volumeID> <ovirt-vm:domainID>461517fd-986a-4b39-9b84-5fd54ef06967</ovirt-vm:domainID> </ovirt-vm:device> <ovirt-vm:device devtype="disk" name="sdc"> <ovirt-vm:imageID>5866d66c-0756-4544-af6a-95f5aed9312b</ovirt-vm:imageID> <ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID> <ovirt-vm:volumeID>1751cd16-fd89-4441-b847-45169689c9a7</ovirt-vm:volumeID> <ovirt-vm:domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ovirt-vm:domainID> </ovirt-vm:device> <launchPaused>false</launchPaused> <resumeBehavior>auto_resume</resumeBehavior> </ovirt-vm:vm> </metadata> </domain> 2018-07-02 19:22:08,143+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, CreateBrokerVDSCommand, log id: 54926511 2018-07-02 19:22:08,144+08 INFO [org.ovirt.engine.core.vdsbroker.CreateVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, CreateVDSCommand, return: WaitForLaunch, log id: 54fa1fca 2018-07-02 19:22:08,145+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Lock freed to object 'EngineLock:{exclusiveLocks='[0febbdb6-9b90-4052-8169-4f6792d99d1e=VM]', sharedLocks=''}' At 2018-07-02 14:19:23, "Shani Leviim" <sleviim@redhat.com> wrote: Hi, Can you please attach full vdsm and engine logs? Regards, Shani Leviim On Fri, Jun 29, 2018 at 1:41 PM, Pym <pym0914@163.com> wrote: Hi: I use environment is ovirt4.2.0, first, I created a template, the template has already installed in the operating system, and then I use this template to create a virtual machine, sometimes can't find the disk happens, particular case is shown in figure: At this point, the virtual machine attaches disk again and cannot be recognized. However, the disk above is identifiable to other virtual machines, and the contents of disk still exist I would like to ask what causes this problem. _______________________________________________ Users mailing list -- users@ovirt.org To unsubscribe send an email to users-leave@ovirt.org Privacy Statement: https://www.ovirt.org/site/privacy-policy/ oVirt Code of Conduct: https://www.ovirt.org/community/about/community-guidelines/ List Archives: https://lists.ovirt.org/archives/list/users@ovirt.org/message/XHW44X37UKSCCL...

Hi, I saw the vm device interface of the problematic virtual machine in the web interface as follows: 在 2018-07-03 13:48:08,"Pym" <pym0914@163.com> 写道: Hi, The last mail engine did not have enough log content, this email adds There is an ERROR in the log. How to deal with this problem #engine.log 2018-07-03 11:28:40,493+08 INFO [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer] (EE-ManagedThreadFactory-engineScheduled-Thread-58) [] VM '56b28418-9dcf-4d46-87fe-0dfef05bb141' was discovered as 'Down' on VDS '7902d7f8-348e-426e-a3c3-399d08b0eb1e'(tchypnode1.ecr.com) 2018-07-03 11:28:40,511+08 INFO [org.ovirt.engine.core.bll.AddUnmanagedVmsCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-58) [55d6983c] Running command: AddUnmanagedVmsCommand internal: true. 2018-07-03 11:28:40,514+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-58) [55d6983c] START, FullListVDSCommand(HostName = tchypnode1.ecr.com, FullListVDSCommandParameters:{hostId='7902d7f8-348e-426e-a3c3-399d08b0eb1e', vmIds='[56b28418-9dcf-4d46-87fe-0dfef05bb141]'}), log id: 7f6022f8 2018-07-03 11:28:40,521+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-58) [55d6983c] FINISH, FullListVDSCommand, return: [{status=Down, guestFQDN=, kvmEnable=true, xml=<?xml version="1.0" encoding="UTF-8"?><domain type="kvm" xmlns:ovirt-tune="http://ovirt.org/vm/tune/1.0" xmlns:ovirt-vm="http://ovirt.org/vm/1.0"><name>avodev-controller</name><uuid>56b28418-9dcf-4d46-87fe-0dfef05bb141</uuid><memory>8388608</memory><currentMemory>8388608</currentMemory><maxMemory slots="16">33554432</maxMemory><vcpu current="4">16</vcpu><sysinfo type="smbios"><system><entry name="manufacturer">oVirt</entry><entry name="product">OS-NAME:</entry><entry name="version">OS-VERSION:</entry><entry name="serial">HOST-SERIAL:</entry><entry name="uuid">56b28418-9dcf-4d46-87fe-0dfef05bb141</entry></system></sysinfo><clock offset="variable" adjustment="0"><timer name="rtc" tickpolicy="catchup"></timer><timer name="pit" tickpolicy="delay"></timer><timer name="hpet" present="no"></timer></clock><features><acpi></acpi></features><cpu match="exact"><model>Nehalem</model><topology cores="1" threads="1" sockets="16"></topology><numa><cell cpus="0,1,2,3" memory="8388608"></cell></numa></cpu><cputune></cputune><devices><input type="tablet" bus="usb"></input><channel type="unix"><target type="virtio" name="ovirt-guest-agent.0"></target><source mode="bind" path="/var/lib/libvirt/qemu/channels/56b28418-9dcf-4d46-87fe-0dfef05bb141.ovirt-guest-agent.0"></source></channel><channel type="unix"><target type="virtio" name="org.qemu.guest_agent.0"></target><source mode="bind" path="/var/lib/libvirt/qemu/channels/56b28418-9dcf-4d46-87fe-0dfef05bb141.org.qemu.guest_agent.0"></source></channel><controller type="scsi" model="virtio-scsi" index="0"></controller><controller type="usb" model="piix3-uhci" index="0"><address bus="0x00" domain="0x0000" function="0x2" slot="0x01" type="pci"></address></controller><controller type="virtio-serial" index="0" ports="16"><address bus="0x00" domain="0x0000" function="0x0" slot="0x04" type="pci"></address></controller><graphics type="vnc" port="-1" autoport="yes" passwd="*****" passwdValidTo="1970-01-01T00:00:01" keymap="en-us"><listen type="network" network="vdsm-ovirtmgmt"></listen></graphics><controller type="ide"><address bus="0x00" domain="0x0000" function="0x1" slot="0x01" type="pci"></address></controller><rng model="virtio"><backend model="random">/dev/urandom</backend></rng><memballoon model="virtio"><stats period="5"></stats><address bus="0x00" domain="0x0000" function="0x0" slot="0x06" type="pci"></address></memballoon><video><model type="vga" vram="16384" heads="1"></model></video><interface type="bridge"><model type="virtio"></model><link state="up"></link><source bridge="ovirtmgmt"></source><address bus="0x00" domain="0x0000" function="0x0" slot="0x03" type="pci"></address><mac address="00:1a:4a:16:01:da"></mac><filterref filter="vdsm-no-mac-spoofing"></filterref><bandwidth></bandwidth></interface><interface type="bridge"><model type="virtio"></model><link state="up"></link><source bridge="ovirtmgmt"></source><address bus="0x00" domain="0x0000" function="0x0" slot="0x07" type="pci"></address><mac address="00:1a:4a:16:01:db"></mac><filterref filter="vdsm-no-mac-spoofing"></filterref><bandwidth></bandwidth></interface><disk type="file" device="cdrom" snapshot="no"><driver name="qemu" type="raw" error_policy="report"></driver><source file="/rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111/CentOS-7-x86_64-Minimal-1804.iso" startupPolicy="optional"></source><target dev="hdc" bus="ide"></target><readonly></readonly><address bus="1" controller="0" unit="0" type="drive" target="0"></address><boot order="2"></boot></disk><disk snapshot="no" type="file" device="disk"><target dev="sda" bus="scsi"></target><source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/461517fd-986a-4b39-9b84-5fd54ef06967/images/8e17d226-94d0-42f8-ae29-9c795db7e522/3b4e2ee9-ba96-4b4d-8392-94e2736e6dab"></source><driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"></driver><address bus="0" controller="0" unit="0" type="drive" target="0"></address><boot order="1"></boot><serial>8e17d226-94d0-42f8-ae29-9c795db7e522</serial></disk><disk snapshot="no" type="file" device="disk"><target dev="sdb" bus="scsi"></target><source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/d0a00b64-3676-4783-a7af-9bb53940b03e/a88be564-84f8-4232-9ba6-91be4625a469"></source><driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"></driver><address bus="0" controller="0" unit="1" type="drive" target="0"></address><serial>d0a00b64-3676-4783-a7af-9bb53940b03e</serial></disk><disk snapshot="no" type="file" device="disk"><target dev="sdc" bus="scsi"></target><source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/03352bba-9d9f-4fca-ac35-f4aa7767e0b6/26a2e47d-2b10-454a-83f4-3c3c96d27f72"></source><driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"></driver><address bus="0" controller="0" unit="2" type="drive" target="0"></address><serial>03352bba-9d9f-4fca-ac35-f4aa7767e0b6</serial></disk></devices><pm><suspend-to-disk enabled="no"></suspend-to-disk><suspend-to-mem enabled="no"></suspend-to-mem></pm><os><type arch="x86_64" machine="pc-i440fx-2.5">hvm</type><smbios mode="sysinfo"></smbios></os><metadata><ovirt-tune:qos></ovirt-tune:qos><ovirt-vm:vm><minGuaranteedMemoryMb type="int">4096</minGuaranteedMemoryMb><clusterVersion>4.2</clusterVersion><ovirt-vm:custom></ovirt-vm:custom><ovirt-vm:device mac_address="00:1a:4a:16:01:db"><ovirt-vm:custom></ovirt-vm:custom></ovirt-vm:device><ovirt-vm:device mac_address="00:1a:4a:16:01:da"><ovirt-vm:custom></ovirt-vm:custom></ovirt-vm:device><ovirt-vm:device devtype="disk" name="sdb"><ovirt-vm:imageID>d0a00b64-3676-4783-a7af-9bb53940b03e</ovirt-vm:imageID><ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID><ovirt-vm:volumeID>a88be564-84f8-4232-9ba6-91be4625a469</ovirt-vm:volumeID><ovirt-vm:domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ovirt-vm:domainID></ovirt-vm:device><ovirt-vm:device devtype="disk" name="sda"><ovirt-vm:imageID>8e17d226-94d0-42f8-ae29-9c795db7e522</ovirt-vm:imageID><ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID><ovirt-vm:volumeID>3b4e2ee9-ba96-4b4d-8392-94e2736e6dab</ovirt-vm:volumeID><ovirt-vm:domainID>461517fd-986a-4b39-9b84-5fd54ef06967</ovirt-vm:domainID></ovirt-vm:device><ovirt-vm:device devtype="disk" name="sdc"><ovirt-vm:imageID>03352bba-9d9f-4fca-ac35-f4aa7767e0b6</ovirt-vm:imageID><ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID><ovirt-vm:volumeID>26a2e47d-2b10-454a-83f4-3c3c96d27f72</ovirt-vm:volumeID><ovirt-vm:domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ovirt-vm:domainID></ovirt-vm:device><launchPaused>false</launchPaused><resumeBehavior>auto_resume</resumeBehavior></ovirt-vm:vm></metadata></domain>, enableGuestEvents=true, vmId=56b28418-9dcf-4d46-87fe-0dfef05bb141, devices=[Ljava.lang.Object;@1a054604, guestDiskMapping={}, username=Unknown, vmType=kvm, smp=1, arch=x86_64, clientIp=, statusTime=7232123440, vmName=nNone, guestIPs=}], log id: 7f6022f8 2018-07-03 11:28:40,522+08 ERROR [org.ovirt.engine.core.bll.AddUnmanagedVmsCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-58) [55d6983c] Command 'org.ovirt.engine.core.bll.AddUnmanagedVmsCommand' failed: null 2018-07-03 11:28:40,522+08 ERROR [org.ovirt.engine.core.bll.AddUnmanagedVmsCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-58) [55d6983c] Exception: java.lang.NumberFormatException: null at java.lang.Integer.parseInt(Integer.java:542) [rt.jar:1.8.0_161] at java.lang.Integer.parseInt(Integer.java:615) [rt.jar:1.8.0_161] at org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerObjectsBuilder.parseIntVdsProperty(VdsBrokerObjectsBuilder.java:777) [vdsbroker.jar:] at org.ovirt.engine.core.bll.AddUnmanagedVmsCommand.convertVm(AddUnmanagedVmsCommand.java:121) [bll.jar:] at org.ovirt.engine.core.bll.AddUnmanagedVmsCommand.executeCommand(AddUnmanagedVmsCommand.java:97) [bll.jar:] at org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1132) [bll.jar:] at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1272) [bll.jar:] at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1914) [bll.jar:] at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInSuppressed(TransactionSupport.java:164) [utils.jar:] at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:103) [utils.jar:] at org.ovirt.engine.core.bll.CommandBase.execute(CommandBase.java:1332) [bll.jar:] at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:406) [bll.jar:] at org.ovirt.engine.core.bll.executor.DefaultBackendActionExecutor.execute(DefaultBackendActionExecutor.java:13) [bll.jar:] at org.ovirt.engine.core.bll.Backend.runAction(Backend.java:468) [bll.jar:] at org.ovirt.engine.core.bll.Backend.runActionImpl(Backend.java:450) [bll.jar:] at org.ovirt.engine.core.bll.Backend.runInternalAction(Backend.java:656) [bll.jar:] at sun.reflect.GeneratedMethodAccessor452.invoke(Unknown Source) [:1.8.0_161] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_161] at java.lang.reflect.Method.invoke(Method.java:498) [rt.jar:1.8.0_161] at org.jboss.as.ee.component.ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptor.java:52) at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:509) at org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.delegateInterception(Jsr299BindingsInterceptor.java:78) at org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.doMethodInterception(Jsr299BindingsInterceptor.java:88) at org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor.processInvocation(Jsr299BindingsInterceptor.java:101) at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63) at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.as.ejb3.component.invocationmetrics.ExecutionTimeInterceptor.processInvocation(ExecutionTimeInterceptor.java:43) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.as.ee.concurrent.ConcurrentContextInterceptor.processInvocation(ConcurrentContextInterceptor.java:45) [wildfly-ee-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:40) at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:53) at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:52) at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.as.ejb3.component.singleton.SingletonComponentInstanceAssociationInterceptor.processInvocation(SingletonComponentInstanceAssociationInterceptor.java:53) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:264) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:379) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:244) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:509) at org.jboss.weld.ejb.AbstractEJBRequestScopeActivationInterceptor.aroundInvoke(AbstractEJBRequestScopeActivationInterceptor.java:73) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final] at org.jboss.as.weld.ejb.EjbRequestScopeActivationInterceptor.processInvocation(EjbRequestScopeActivationInterceptor.java:89) at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.as.ejb3.component.invocationmetrics.WaitTimeInterceptor.processInvocation(WaitTimeInterceptor.java:47) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.as.ejb3.security.SecurityContextInterceptor.processInvocation(SecurityContextInterceptor.java:100) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.as.ejb3.deployment.processors.StartupAwaitInterceptor.processInvocation(StartupAwaitInterceptor.java:22) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.as.ejb3.component.interceptors.ShutDownInterceptorFactory$1.processInvocation(ShutDownInterceptorFactory.java:64) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:67) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50) at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.invocation.ContextClassLoaderInterceptor.processInvocation(ContextClassLoaderInterceptor.java:60) at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.invocation.InterceptorContext.run(InterceptorContext.java:438) at org.wildfly.security.manager.WildFlySecurityManager.doChecked(WildFlySecurityManager.java:609) at org.jboss.invocation.AccessCheckingInterceptor.processInvocation(AccessCheckingInterceptor.java:57) at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:422) at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:53) at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:198) at org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:185) at org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:81) at org.ovirt.engine.core.bll.interfaces.BackendInternal$$$view3.runInternalAction(Unknown Source) [bll.jar:] at sun.reflect.GeneratedMethodAccessor451.invoke(Unknown Source) [:1.8.0_161] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_161] at java.lang.reflect.Method.invoke(Method.java:498) [rt.jar:1.8.0_161] at org.jboss.weld.util.reflection.Reflections.invokeAndUnwrap(Reflections.java:433) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final] at org.jboss.weld.bean.proxy.EnterpriseBeanProxyMethodHandler.invoke(EnterpriseBeanProxyMethodHandler.java:127) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final] at org.jboss.weld.bean.proxy.EnterpriseTargetBeanInstance.invoke(EnterpriseTargetBeanInstance.java:56) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final] at org.jboss.weld.bean.proxy.InjectionPointPropagatingEnterpriseTargetBeanInstance.invoke(InjectionPointPropagatingEnterpriseTargetBeanInstance.java:67) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final] at org.jboss.weld.bean.proxy.ProxyMethodHandler.invoke(ProxyMethodHandler.java:100) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final] at org.ovirt.engine.core.bll.BackendCommandObjectsHandler$BackendInternal$BackendLocal$2049259618$Proxy$_$$_Weld$EnterpriseProxy$.runInternalAction(Unknown Source) [bll.jar:] at org.ovirt.engine.core.bll.VdsEventListener.addUnmanagedVms(VdsEventListener.java:473) [bll.jar:] at org.ovirt.engine.core.vdsbroker.monitoring.VmsMonitoring.addUnmanagedVms(VmsMonitoring.java:326) [vdsbroker.jar:] at org.ovirt.engine.core.vdsbroker.monitoring.VmsMonitoring.perform(VmsMonitoring.java:96) [vdsbroker.jar:] at org.ovirt.engine.core.vdsbroker.monitoring.PollVmStatsRefresher.poll(PollVmStatsRefresher.java:43) [vdsbroker.jar:] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [rt.jar:1.8.0_161] at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) [rt.jar:1.8.0_161] at org.glassfish.enterprise.concurrent.internal.ManagedScheduledThreadPoolExecutor$ManagedScheduledFutureTask.access$201(ManagedScheduledThreadPoolExecutor.java:383) [javax.enterprise.concurrent-1.0.jar:] at org.glassfish.enterprise.concurrent.internal.ManagedScheduledThreadPoolExecutor$ManagedScheduledFutureTask.run(ManagedScheduledThreadPoolExecutor.java:534) [javax.enterprise.concurrent-1.0.jar:] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [rt.jar:1.8.0_161] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [rt.jar:1.8.0_161] at java.lang.Thread.run(Thread.java:748) [rt.jar:1.8.0_161] at org.glassfish.enterprise.concurrent.ManagedThreadFactoryImpl$ManagedThread.run(ManagedThreadFactoryImpl.java:250) [javax.enterprise.concurrent-1.0.jar:] at org.jboss.as.ee.concurrent.service.ElytronManagedThreadFactory$ElytronManagedThread.run(ElytronManagedThreadFactory.java:78) 2018-07-03 11:28:41,840+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (default task-65) [a2075a4a-458f-4224-820a-c3acb8d776fa] Lock Acquired to object 'EngineLock:{exclusiveLocks='[0febbdb6-9b90-4052-8169-4f6792d99d1e=VM]', sharedLocks=''}' 2018-07-03 11:28:41,871+08 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (default task-65) [a2075a4a-458f-4224-820a-c3acb8d776fa] START, IsVmDuringInitiatingVDSCommand( IsVmDuringInitiatingVDSCommandParameters:{vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}), log id: 18b64302 2018-07-03 11:28:41,871+08 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (default task-65) [a2075a4a-458f-4224-820a-c3acb8d776fa] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 18b64302 2018-07-03 11:28:41,939+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] Running command: RunVmCommand internal: false. Entities affected : ID: 0febbdb6-9b90-4052-8169-4f6792d99d1e Type: VMAction group RUN_VM with role type USER 2018-07-03 11:28:41,995+08 INFO [org.ovirt.engine.core.bll.scheduling.policyunits.PreferredHostsWeightPolicyUnit] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] Penalizing host 'tchypnode2.ecr.com' because it is not preferred. 2018-07-03 11:28:41,995+08 INFO [org.ovirt.engine.core.bll.scheduling.policyunits.PreferredHostsWeightPolicyUnit] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] Penalizing host 'tchypnode1.ecr.com' because it is not preferred. 2018-07-03 11:28:41,995+08 INFO [org.ovirt.engine.core.bll.scheduling.policyunits.PreferredHostsWeightPolicyUnit] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] Penalizing host 'storage2' because it is not preferred. 2018-07-03 11:28:42,004+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] Running VM with attached cd 'CentOS-7-x86_64-Minimal-1804.iso' 2018-07-03 11:28:42,007+08 INFO [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] START, UpdateVmDynamicDataVDSCommand( UpdateVmDynamicDataVDSCommandParameters:{hostId='null', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e', vmDynamic='org.ovirt.engine.core.common.businessentities.VmDynamic@3746b80a'}), log id: 33de8399 2018-07-03 11:28:42,008+08 INFO [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] FINISH, UpdateVmDynamicDataVDSCommand, log id: 33de8399 2018-07-03 11:28:42,011+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] START, IsoPrefixVDSCommand(HostName = storage3, VdsAndPoolIDVDSParametersBase:{hostId='862f44c5-3529-4ef1-a2a6-2910d5b93460', storagePoolId='5b0f6949-02d6-00bf-008b-000000000167'}), log id: 39168ed2 2018-07-03 11:28:42,011+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] FINISH, IsoPrefixVDSCommand, return: /rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111, log id: 39168ed2 2018-07-03 11:28:42,017+08 INFO [org.ovirt.engine.core.vdsbroker.CreateVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] START, CreateVDSCommand( CreateVDSCommandParameters:{hostId='862f44c5-3529-4ef1-a2a6-2910d5b93460', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e', vm='VM [badvm-avodev-controller]'}), log id: 72322ec5 2018-07-03 11:28:42,020+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] START, CreateBrokerVDSCommand(HostName = storage3, CreateVDSCommandParameters:{hostId='862f44c5-3529-4ef1-a2a6-2910d5b93460', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e', vm='VM [badvm-avodev-controller]'}), log id: 68b50b27 2018-07-03 11:28:42,024+08 INFO [org.ovirt.engine.core.vdsbroker.builder.vminfo.VmInfoBuilderImpl] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] Bootable disk '1ea736ad-164c-4ac2-a6ce-9b8e6eceefde' set to index '0' 2018-07-03 11:28:42,042+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] VM {memGuaranteedSize=4096, smpThreadsPerCore=1, cpuType=Nehalem, vmId=0febbdb6-9b90-4052-8169-4f6792d99d1e, acpiEnable=true, tabletEnable=true, vmType=kvm, smp=4, smpCoresPerSocket=1, emulatedMachine=pc-i440fx-2.5, smartcardEnable=false, guestNumaNodes=[{memory=8192, cpus=0,1,2,3, nodeIndex=0}], transparentHugePages=true, displayNetwork=ovirtmgmt, vmName=badvm-avodev-controller, maxVCpus=16, kvmEnable=true, devices=[{address={bus=0x00, domain=0x0000, function=0x0, slot=0x02, type=pci}, type=video, specParams={vram=16384}, device=vga, deviceId=a50622b4-d3d4-4319-ae6d-0c66848321ef}, {type=graphics, specParams={keyMap=en-us}, device=vnc, deviceId=8dd702a4-f289-4bd1-a991-74b9c7224568}, {iface=ide, shared=false, path=/rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111/CentOS-7-x86_64-Minimal-1804.iso, address={bus=1, controller=0, unit=0, type=drive, target=0}, readonly=true, bootOrder=2, index=2, type=disk, specParams={path=CentOS-7-x86_64-Minimal-1804.iso}, device=cdrom, deviceId=89f6751c-9b33-4171-9dfa-e3a513ae7729}, {discard=false, shared=false, address={bus=0, controller=0, unit=0, type=drive, target=0}, imageID=1ea736ad-164c-4ac2-a6ce-9b8e6eceefde, format=cow, index=0, optional=false, type=disk, deviceId=1ea736ad-164c-4ac2-a6ce-9b8e6eceefde, domainID=461517fd-986a-4b39-9b84-5fd54ef06967, propagateErrors=off, iface=scsi, readonly=false, bootOrder=1, poolID=5b0f6949-02d6-00bf-008b-000000000167, volumeID=b7f8140f-3a34-40fd-9fec-878df3d159a4, diskType=file, specParams={}, device=disk}, {discard=false, shared=false, address={bus=0, controller=0, unit=1, type=drive, target=0}, imageID=183b3ace-64e1-49cf-a214-b5462f989171, format=cow, optional=false, type=disk, deviceId=183b3ace-64e1-49cf-a214-b5462f989171, domainID=c5a790c2-2885-4220-b803-35e4a40ecef6, propagateErrors=off, iface=scsi, readonly=false, poolID=5b0f6949-02d6-00bf-008b-000000000167, volumeID=36ea0af6-b681-4923-bf70-6b51717f372d, diskType=file, specParams={}, device=disk}, {discard=false, shared=false, address={bus=0, controller=0, unit=2, type=drive, target=0}, imageID=5866d66c-0756-4544-af6a-95f5aed9312b, format=cow, optional=false, type=disk, deviceId=5866d66c-0756-4544-af6a-95f5aed9312b, domainID=c5a790c2-2885-4220-b803-35e4a40ecef6, propagateErrors=off, iface=scsi, readonly=false, poolID=5b0f6949-02d6-00bf-008b-000000000167, volumeID=1751cd16-fd89-4441-b847-45169689c9a7, diskType=file, specParams={}, device=disk}, {filter=vdsm-no-mac-spoofing, nicModel=pv, address={bus=0x00, domain=0x0000, function=0x0, slot=0x03, type=pci}, filterParameters=[], type=interface, specParams={inbound={}, outbound={}}, device=bridge, linkActive=true, deviceId=59cdfb1f-ef08-4fee-a99b-27ad6a27c14e, macAddr=00:1a:4a:16:01:f5, network=ovirtmgmt}, {filter=vdsm-no-mac-spoofing, nicModel=pv, address={bus=0x00, domain=0x0000, function=0x0, slot=0x07, type=pci}, filterParameters=[], type=interface, specParams={inbound={}, outbound={}}, device=bridge, linkActive=true, deviceId=d3bcaa77-ef63-4371-a59f-8e9d444c9cec, macAddr=00:1a:4a:16:01:f6, network=ovirtmgmt}, {address={bus=0x00, domain=0x0000, function=0x2, slot=0x01, type=pci}, index=0, model=piix3-uhci, type=controller, specParams={}, device=usb, deviceId=05c84b2b-2f7a-4dbf-a4f4-89572562bdca}, {address={bus=0x00, domain=0x0000, function=0x0, slot=0x06, type=pci}, type=balloon, specParams={model=virtio}, device=memballoon, deviceId=0a8c9c29-575c-4bcd-b983-7c7d1b5bf020}, {index=0, model=virtio-scsi, type=controller, specParams={}, device=scsi, deviceId=cc5b8141-51ed-4a8b-9cf4-a703fa9a3dd1}, {address={bus=0x00, domain=0x0000, function=0x0, slot=0x04, type=pci}, type=controller, specParams={}, device=virtio-serial, deviceId=d2049d59-412f-4518-9e0e-22c3ead63438}, {model=virtio, type=rng, specParams={source=urandom}, device=virtio, deviceId=befbd382-3e77-4773-a3bb-766b660a4248}], custom={device_17eb478e-16dd-4d17-9f90-d978a28cc2b9device_50c094be-df54-477f-a0e2-f2ce36a2132fdevice_04a84b3e-b86b-454e-96bc-2d3151a315f6=VmDevice:{id='VmDeviceId:{deviceId='04a84b3e-b86b-454e-96bc-2d3151a315f6', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}', device='unix', type='CHANNEL', specParams='[]', address='{type=virtio-serial, bus=0, controller=0, port=1}', managed='false', plugged='true', readOnly='false', deviceAlias='channel0', customProperties='[]', snapshotId='null', logicalName='null', hostDevice=''}, device_17eb478e-16dd-4d17-9f90-d978a28cc2b9device_50c094be-df54-477f-a0e2-f2ce36a2132f=VmDevice:{id='VmDeviceId:{deviceId='50c094be-df54-477f-a0e2-f2ce36a2132f', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}', device='scsi', type='CONTROLLER', specParams='[index=0]', address='{type=pci, slot=0x05, bus=0x00, domain=0x0000, function=0x0}', managed='false', plugged='true', readOnly='false', deviceAlias='scsi0', customProperties='[]', snapshotId='null', logicalName='null', hostDevice=''}, device_17eb478e-16dd-4d17-9f90-d978a28cc2b9device_50c094be-df54-477f-a0e2-f2ce36a2132fdevice_04a84b3e-b86b-454e-96bc-2d3151a315f6device_0f1f725b-ec13-4ce4-9dfb-8882aa0b28e7=VmDevice:{id='VmDeviceId:{deviceId='0f1f725b-ec13-4ce4-9dfb-8882aa0b28e7', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}', device='unix', type='CHANNEL', specParams='[]', address='{type=virtio-serial, bus=0, controller=0, port=2}', managed='false', plugged='true', readOnly='false', deviceAlias='channel1', customProperties='[]', snapshotId='null', logicalName='null', hostDevice=''}, device_17eb478e-16dd-4d17-9f90-d978a28cc2b9=VmDevice:{id='VmDeviceId:{deviceId='17eb478e-16dd-4d17-9f90-d978a28cc2b9', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}', device='ide', type='CONTROLLER', specParams='[]', address='{type=pci, slot=0x01, bus=0x00, domain=0x0000, function=0x1}', managed='false', plugged='true', readOnly='false', deviceAlias='ide', customProperties='[]', snapshotId='null', logicalName='null', hostDevice=''}}, timeOffset=0, nice=0, maxMemSize=32768, maxMemSlots=16, bootMenuEnable=false, memSize=8192, agentChannelName=ovirt-guest-agent.0} 2018-07-03 11:28:42,056+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] VM <?xml version="1.0" encoding="UTF-8"?><domain type="kvm" xmlns:ovirt-tune="http://ovirt.org/vm/tune/1.0" xmlns:ovirt-vm="http://ovirt.org/vm/1.0"> <name>badvm-avodev-controller</name> <uuid>0febbdb6-9b90-4052-8169-4f6792d99d1e</uuid> <memory>8388608</memory> <currentMemory>8388608</currentMemory> <maxMemory slots="16">33554432</maxMemory> <vcpu current="4">16</vcpu> <sysinfo type="smbios"> <system> <entry name="manufacturer">oVirt</entry> <entry name="product">OS-NAME:</entry> <entry name="version">OS-VERSION:</entry> <entry name="serial">HOST-SERIAL:</entry> <entry name="uuid">0febbdb6-9b90-4052-8169-4f6792d99d1e</entry> </system> </sysinfo> <clock offset="variable" adjustment="0"> <timer name="rtc" tickpolicy="catchup"/> <timer name="pit" tickpolicy="delay"/> <timer name="hpet" present="no"/> </clock> <features> <acpi/> </features> <cpu match="exact"> <model>Nehalem</model> <topology cores="1" threads="1" sockets="16"/> <numa> <cell cpus="0,1,2,3" memory="8388608"/> </numa> </cpu> <cputune/> <devices> <input type="tablet" bus="usb"/> <channel type="unix"> <target type="virtio" name="ovirt-guest-agent.0"/> <source mode="bind" path="/var/lib/libvirt/qemu/channels/0febbdb6-9b90-4052-8169-4f6792d99d1e.ovirt-guest-agent.0"/> </channel> <channel type="unix"> <target type="virtio" name="org.qemu.guest_agent.0"/> <source mode="bind" path="/var/lib/libvirt/qemu/channels/0febbdb6-9b90-4052-8169-4f6792d99d1e.org.qemu.guest_agent.0"/> </channel> <controller type="usb" model="piix3-uhci" index="0"> <address bus="0x00" domain="0x0000" function="0x2" slot="0x01" type="pci"/> </controller> <memballoon model="virtio"> <stats period="5"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x06" type="pci"/> </memballoon> <controller type="ide"> <address bus="0x00" domain="0x0000" function="0x1" slot="0x01" type="pci"/> </controller> <controller type="scsi" index="0"> <address bus="0x00" domain="0x0000" function="0x0" slot="0x05" type="pci"/> </controller> <graphics type="vnc" port="-1" autoport="yes" passwd="*****" passwdValidTo="1970-01-01T00:00:01" keymap="en-us"> <listen type="network" network="vdsm-ovirtmgmt"/> </graphics> <video> <model type="vga" vram="16384" heads="1"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x02" type="pci"/> </video> <rng model="virtio"> <backend model="random">/dev/urandom</backend> </rng> <controller type="virtio-serial" index="0" ports="16"> <address bus="0x00" domain="0x0000" function="0x0" slot="0x04" type="pci"/> </controller> <interface type="bridge"> <model type="virtio"/> <link state="up"/> <source bridge="ovirtmgmt"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x03" type="pci"/> <mac address="00:1a:4a:16:01:f5"/> <filterref filter="vdsm-no-mac-spoofing"/> <bandwidth/> </interface> <interface type="bridge"> <model type="virtio"/> <link state="up"/> <source bridge="ovirtmgmt"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x07" type="pci"/> <mac address="00:1a:4a:16:01:f6"/> <filterref filter="vdsm-no-mac-spoofing"/> <bandwidth/> </interface> <disk type="file" device="cdrom" snapshot="no"> <driver name="qemu" type="raw" error_policy="report"/> <source file="/rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111/CentOS-7-x86_64-Minimal-1804.iso" startupPolicy="optional"/> <target dev="hdc" bus="ide"/> <readonly/> <address bus="1" controller="0" unit="0" type="drive" target="0"/> <boot order="2"/> </disk> <disk snapshot="no" type="file" device="disk"> <target dev="sda" bus="scsi"/> <source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/461517fd-986a-4b39-9b84-5fd54ef06967/images/1ea736ad-164c-4ac2-a6ce-9b8e6eceefde/b7f8140f-3a34-40fd-9fec-878df3d159a4"/> <driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"/> <address bus="0" controller="0" unit="0" type="drive" target="0"/> <boot order="1"/> <serial>1ea736ad-164c-4ac2-a6ce-9b8e6eceefde</serial> </disk> <disk snapshot="no" type="file" device="disk"> <target dev="sdb" bus="scsi"/> <source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/183b3ace-64e1-49cf-a214-b5462f989171/36ea0af6-b681-4923-bf70-6b51717f372d"/> <driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"/> <address bus="0" controller="0" unit="1" type="drive" target="0"/> <serial>183b3ace-64e1-49cf-a214-b5462f989171</serial> </disk> <disk snapshot="no" type="file" device="disk"> <target dev="sdc" bus="scsi"/> <source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/5866d66c-0756-4544-af6a-95f5aed9312b/1751cd16-fd89-4441-b847-45169689c9a7"/> <driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"/> <address bus="0" controller="0" unit="2" type="drive" target="0"/> <serial>5866d66c-0756-4544-af6a-95f5aed9312b</serial> </disk> </devices> <pm> <suspend-to-disk enabled="no"/> <suspend-to-mem enabled="no"/> </pm> <os> <type arch="x86_64" machine="pc-i440fx-2.5">hvm</type> <smbios mode="sysinfo"/> </os> <metadata> <ovirt-tune:qos/> <ovirt-vm:vm> <minGuaranteedMemoryMb type="int">4096</minGuaranteedMemoryMb> <clusterVersion>4.2</clusterVersion> <ovirt-vm:custom/> <ovirt-vm:device mac_address="00:1a:4a:16:01:f6"> <ovirt-vm:custom/> </ovirt-vm:device> <ovirt-vm:device mac_address="00:1a:4a:16:01:f5"> <ovirt-vm:custom/> </ovirt-vm:device> <ovirt-vm:device devtype="disk" name="sdb"> <ovirt-vm:imageID>183b3ace-64e1-49cf-a214-b5462f989171</ovirt-vm:imageID> <ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID> <ovirt-vm:volumeID>36ea0af6-b681-4923-bf70-6b51717f372d</ovirt-vm:volumeID> <ovirt-vm:domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ovirt-vm:domainID> </ovirt-vm:device> <ovirt-vm:device devtype="disk" name="sda"> <ovirt-vm:imageID>1ea736ad-164c-4ac2-a6ce-9b8e6eceefde</ovirt-vm:imageID> <ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID> <ovirt-vm:volumeID>b7f8140f-3a34-40fd-9fec-878df3d159a4</ovirt-vm:volumeID> <ovirt-vm:domainID>461517fd-986a-4b39-9b84-5fd54ef06967</ovirt-vm:domainID> </ovirt-vm:device> <ovirt-vm:device devtype="disk" name="sdc"> <ovirt-vm:imageID>5866d66c-0756-4544-af6a-95f5aed9312b</ovirt-vm:imageID> <ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID> <ovirt-vm:volumeID>1751cd16-fd89-4441-b847-45169689c9a7</ovirt-vm:volumeID> <ovirt-vm:domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ovirt-vm:domainID> </ovirt-vm:device> <launchPaused>false</launchPaused> <resumeBehavior>auto_resume</resumeBehavior> </ovirt-vm:vm> </metadata> </domain> 2018-07-03 11:28:42,072+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] FINISH, CreateBrokerVDSCommand, log id: 68b50b27 2018-07-03 11:28:42,074+08 INFO [org.ovirt.engine.core.vdsbroker.CreateVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] FINISH, CreateVDSCommand, return: WaitForLaunch, log id: 72322ec5 2018-07-03 11:28:42,074+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] Lock freed to object 'EngineLock:{exclusiveLocks='[0febbdb6-9b90-4052-8169-4f6792d99d1e=VM]', sharedLocks=''}' 2018-07-03 11:28:42,079+08 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] EVENT_ID: USER_STARTED_VM(153), VM badvm-avodev-controller was started by admin@internal-authz (Host: storage3). 2018-07-03 11:28:43,803+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DumpXmlsVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-72) [] START, DumpXmlsVDSCommand(HostName = storage3, Params:{hostId='862f44c5-3529-4ef1-a2a6-2910d5b93460', vmIds='[0febbdb6-9b90-4052-8169-4f6792d99d1e]'}), log id: 39084aef 2018-07-03 11:28:43,835+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DumpXmlsVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-72) [] FINISH, DumpXmlsVDSCommand, return: [{vmId=0febbdb6-9b90-4052-8169-4f6792d99d1e, devices=[Ljava.util.Map;@749e3a2d}], log id: 39084aef 2018-07-03 11:28:43,837+08 ERROR [org.ovirt.engine.core.vdsbroker.monitoring.VmDevicesMonitoring] (EE-ManagedThreadFactory-engineScheduled-Thread-72) [] VM '0febbdb6-9b90-4052-8169-4f6792d99d1e' managed non pluggable device was removed unexpectedly from libvirt: 'VmDevice:{id='VmDeviceId:{deviceId='cc5b8141-51ed-4a8b-9cf4-a703fa9a3dd1', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}', device='virtio-scsi', type='CONTROLLER', specParams='[]', address='', managed='true', plugged='false', readOnly='false', deviceAlias='', customProperties='[]', snapshotId='null', logicalName='null', hostDevice='null'}' 2018-07-03 11:28:44,132+08 INFO [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer] (ForkJoinPool-1-worker-9) [] VM '0febbdb6-9b90-4052-8169-4f6792d99d1e'(badvm-avodev-controller) moved from 'WaitForLaunch' --> 'PoweringUp' 2018-07-03 11:28:44,143+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DumpXmlsVDSCommand] (ForkJoinPool-1-worker-9) [] START, DumpXmlsVDSCommand(HostName = storage3, Params:{hostId='862f44c5-3529-4ef1-a2a6-2910d5b93460', vmIds='[0febbdb6-9b90-4052-8169-4f6792d99d1e]'}), log id: 47bab6eb 2018-07-03 11:28:44,184+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DumpXmlsVDSCommand] (ForkJoinPool-1-worker-9) [] FINISH, DumpXmlsVDSCommand, return: [{vmId=0febbdb6-9b90-4052-8169-4f6792d99d1e, devices=[Ljava.util.Map;@5a5f0670}], log id: 47bab6eb 2018-07-03 11:28:44,185+08 ERROR [org.ovirt.engine.core.vdsbroker.monitoring.VmDevicesMonitoring] (ForkJoinPool-1-worker-9) [] VM '0febbdb6-9b90-4052-8169-4f6792d99d1e' managed non pluggable device was removed unexpectedly from libvirt: 'VmDevice:{id='VmDeviceId:{deviceId='cc5b8141-51ed-4a8b-9cf4-a703fa9a3dd1', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}', device='virtio-scsi', type='CONTROLLER', specParams='[]', address='', managed='true', plugged='false', readOnly='false', deviceAlias='', customProperties='[]', snapshotId='null', logicalName='null', hostDevice='null'}' 2018-07-03 11:28:53,460+08 INFO [org.ovirt.engine.core.vdsbroker.monitoring.VmAnalyzer] (EE-ManagedThreadFactory-engineScheduled-Thread-91) [] VM '56b28418-9dcf-4d46-87fe-0dfef05bb141' was discovered as 'Down' on VDS 'a24d7172-1c3c-44ab-8ef3-9f863aeb4a87'(storage2) 2018-07-03 11:28:53,477+08 INFO [org.ovirt.engine.core.bll.AddUnmanagedVmsCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-91) [609d6b57] Running command: AddUnmanagedVmsCommand internal: true. 2018-07-03 11:28:53,480+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-91) [609d6b57] START, FullListVDSCommand(HostName = storage2, FullListVDSCommandParameters:{hostId='a24d7172-1c3c-44ab-8ef3-9f863aeb4a87', vmIds='[56b28418-9dcf-4d46-87fe-0dfef05bb141]'}), log id: 7ff29962 2018-07-03 11:28:53,486+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-91) [609d6b57] FINISH, FullListVDSCommand, return: [{status=Down, guestFQDN=, kvmEnable=true, xml=<?xml version="1.0" encoding="UTF-8"?><domain type="kvm" xmlns:ovirt-tune="http://ovirt.org/vm/tune/1.0" xmlns:ovirt-vm="http://ovirt.org/vm/1.0"><name>avodev-controller</name><uuid>56b28418-9dcf-4d46-87fe-0dfef05bb141</uuid><memory>8388608</memory><currentMemory>8388608</currentMemory><maxMemory slots="16">33554432</maxMemory><vcpu current="4">16</vcpu><sysinfo type="smbios"><system><entry name="manufacturer">oVirt</entry><entry name="product">OS-NAME:</entry><entry name="version">OS-VERSION:</entry><entry name="serial">HOST-SERIAL:</entry><entry name="uuid">56b28418-9dcf-4d46-87fe-0dfef05bb141</entry></system></sysinfo><clock offset="variable" adjustment="0"><timer name="rtc" tickpolicy="catchup"></timer><timer name="pit" tickpolicy="delay"></timer><timer name="hpet" present="no"></timer></clock><features><acpi></acpi></features><cpu match="exact"><model>Nehalem</model><topology cores="1" threads="1" sockets="16"></topology><numa><cell cpus="0,1,2,3" memory="8388608"></cell></numa></cpu><cputune></cputune><devices><input type="tablet" bus="usb"></input><channel type="unix"><target type="virtio" name="ovirt-guest-agent.0"></target><source mode="bind" path="/var/lib/libvirt/qemu/channels/56b28418-9dcf-4d46-87fe-0dfef05bb141.ovirt-guest-agent.0"></source></channel><channel type="unix"><target type="virtio" name="org.qemu.guest_agent.0"></target><source mode="bind" path="/var/lib/libvirt/qemu/channels/56b28418-9dcf-4d46-87fe-0dfef05bb141.org.qemu.guest_agent.0"></source></channel><controller type="scsi" model="virtio-scsi" index="0"></controller><controller type="usb" model="piix3-uhci" index="0"><address bus="0x00" domain="0x0000" function="0x2" slot="0x01" type="pci"></address></controller><controller type="virtio-serial" index="0" ports="16"><address bus="0x00" domain="0x0000" function="0x0" slot="0x04" type="pci"></address></controller><graphics type="vnc" port="-1" autoport="yes" passwd="*****" passwdValidTo="1970-01-01T00:00:01" keymap="en-us"><listen type="network" network="vdsm-ovirtmgmt"></listen></graphics><controller type="ide"><address bus="0x00" domain="0x0000" function="0x1" slot="0x01" type="pci"></address></controller><rng model="virtio"><backend model="random">/dev/urandom</backend></rng><memballoon model="virtio"><stats period="5"></stats><address bus="0x00" domain="0x0000" function="0x0" slot="0x06" type="pci"></address></memballoon><video><model type="vga" vram="16384" heads="1"></model></video><interface type="bridge"><model type="virtio"></model><link state="up"></link><source bridge="ovirtmgmt"></source><address bus="0x00" domain="0x0000" function="0x0" slot="0x03" type="pci"></address><mac address="00:1a:4a:16:01:da"></mac><filterref filter="vdsm-no-mac-spoofing"></filterref><bandwidth></bandwidth></interface><interface type="bridge"><model type="virtio"></model><link state="up"></link><source bridge="ovirtmgmt"></source><address bus="0x00" domain="0x0000" function="0x0" slot="0x07" type="pci"></address><mac address="00:1a:4a:16:01:db"></mac><filterref filter="vdsm-no-mac-spoofing"></filterref><bandwidth></bandwidth></interface><disk type="file" device="cdrom" snapshot="no"><driver name="qemu" type="raw" error_policy="report"></driver><source file="/rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111/CentOS-7-x86_64-Minimal-1804.iso" startupPolicy="optional"></source><target dev="hdc" bus="ide"></target><readonly></readonly><address bus="1" controller="0" unit="0" type="drive" target="0"></address><boot order="2"></boot></disk><disk snapshot="no" type="file" device="disk"><target dev="sda" bus="scsi"></target><source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/461517fd-986a-4b39-9b84-5fd54ef06967/images/8e17d226-94d0-42f8-ae29-9c795db7e522/3b4e2ee9-ba96-4b4d-8392-94e2736e6dab"></source><driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"></driver><address bus="0" controller="0" unit="0" type="drive" target="0"></address><boot order="1"></boot><serial>8e17d226-94d0-42f8-ae29-9c795db7e522</serial></disk><disk snapshot="no" type="file" device="disk"><target dev="sdb" bus="scsi"></target><source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/d0a00b64-3676-4783-a7af-9bb53940b03e/a88be564-84f8-4232-9ba6-91be4625a469"></source><driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"></driver><address bus="0" controller="0" unit="1" type="drive" target="0"></address><serial>d0a00b64-3676-4783-a7af-9bb53940b03e</serial></disk><disk snapshot="no" type="file" device="disk"><target dev="sdc" bus="scsi"></target><source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/03352bba-9d9f-4fca-ac35-f4aa7767e0b6/26a2e47d-2b10-454a-83f4-3c3c96d27f72"></source><driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"></driver><address bus="0" controller="0" unit="2" type="drive" target="0"></address><serial>03352bba-9d9f-4fca-ac35-f4aa7767e0b6</serial></disk></devices><pm><suspend-to-disk enabled="no"></suspend-to-disk><suspend-to-mem enabled="no"></suspend-to-mem></pm><os><type arch="x86_64" machine="pc-i440fx-2.5">hvm</type><smbios mode="sysinfo"></smbios></os><metadata><ovirt-tune:qos></ovirt-tune:qos><ovirt-vm:vm><minGuaranteedMemoryMb type="int">4096</minGuaranteedMemoryMb><clusterVersion>4.2</clusterVersion><ovirt-vm:custom></ovirt-vm:custom><ovirt-vm:device mac_address="00:1a:4a:16:01:db"><ovirt-vm:custom></ovirt-vm:custom></ovirt-vm:device><ovirt-vm:device mac_address="00:1a:4a:16:01:da"><ovirt-vm:custom></ovirt-vm:custom></ovirt-vm:device><ovirt-vm:device devtype="disk" name="sdb"><ovirt-vm:imageID>d0a00b64-3676-4783-a7af-9bb53940b03e</ovirt-vm:imageID><ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID><ovirt-vm:volumeID>a88be564-84f8-4232-9ba6-91be4625a469</ovirt-vm:volumeID><ovirt-vm:domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ovirt-vm:domainID></ovirt-vm:device><ovirt-vm:device devtype="disk" name="sda"><ovirt-vm:imageID>8e17d226-94d0-42f8-ae29-9c795db7e522</ovirt-vm:imageID><ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID><ovirt-vm:volumeID>3b4e2ee9-ba96-4b4d-8392-94e2736e6dab</ovirt-vm:volumeID><ovirt-vm:domainID>461517fd-986a-4b39-9b84-5fd54ef06967</ovirt-vm:domainID></ovirt-vm:device><ovirt-vm:device devtype="disk" name="sdc"><ovirt-vm:imageID>03352bba-9d9f-4fca-ac35-f4aa7767e0b6</ovirt-vm:imageID><ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID><ovirt-vm:volumeID>26a2e47d-2b10-454a-83f4-3c3c96d27f72</ovirt-vm:volumeID><ovirt-vm:domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ovirt-vm:domainID></ovirt-vm:device><launchPaused>false</launchPaused><resumeBehavior>auto_resume</resumeBehavior></ovirt-vm:vm></metadata></domain>, enableGuestEvents=true, vmId=56b28418-9dcf-4d46-87fe-0dfef05bb141, devices=[Ljava.lang.Object;@671dc026, guestDiskMapping={}, username=Unknown, vmType=kvm, smp=1, arch=x86_64, clientIp=, statusTime=4814199690, vmName=nNone, guestIPs=}], log id: 7ff29962 2018-07-03 11:28:53,486+08 ERROR [org.ovirt.engine.core.bll.AddUnmanagedVmsCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-91) [609d6b57] Command 'org.ovirt.engine.core.bll.AddUnmanagedVmsCommand' failed: null 在 2018-07-03 12:10:06,"Pym" <pym0914@163.com> : Hi, #vdsm.log 2018-07-02 19:22:02,316+0800 INFO (libvirt/events) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') CPU running: onResume (vm:5742) 2018-07-02 19:22:02,586+0800 INFO (vm/0febbdb6) [root] /usr/libexec/vdsm/hooks/after_vm_start/50_openstacknet: rc=0 err= (hooks:109) 2018-07-02 19:22:02,807+0800 INFO (vm/0febbdb6) [root] /usr/libexec/vdsm/hooks/after_vm_start/openstacknet_utils.py: rc=0 err= (hooks:109) 2018-07-02 19:22:02,824+0800 WARN (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Found unknown drive: {'alias': 'ide0-1-0', 'readonly': 'True', 'iface': 'ide', 'name': 'hdc', 'address': {'bus': '1', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'cdrom', 'path': '/rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111/CentOS-7-x86_64-Minimal-1804.iso', 'type': 'disk', 'bootOrder': '2'} (storage:217) 2018-07-02 19:22:02,824+0800 WARN (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Found unknown drive: {'alias': 'scsi0-0-0', 'readonly': 'False', 'iface': 'ide', 'name': 'sda', 'address': {'bus': '0', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'disk', 'path': '/rhev/data-center/mnt/192.168.100.59:_exports_vmstorage/461517fd-986a-4b39-9b84-5fd54ef06967/images/1ea736ad-164c-4ac2-a6ce-9b8e6eceefde/b7f8140f-3a34-40fd-9fec-878df3d159a4', 'type': 'disk', 'bootOrder': '1'} (storage:217) 2018-07-02 19:22:02,825+0800 WARN (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Found unknown drive: {'alias': 'scsi0-0-1', 'readonly': 'False', 'iface': 'ide', 'name': 'sdb', 'address': {'bus': '0', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '1'}, 'device': 'disk', 'path': '/rhev/data-center/mnt/192.168.100.241:_exports_vmstorage/c5a790c2-2885-4220-b803-35e4a40ecef6/images/183b3ace-64e1-49cf-a214-b5462f989171/36ea0af6-b681-4923-bf70-6b51717f372d', 'type': 'disk'} (storage:217) 2018-07-02 19:22:02,825+0800 WARN (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Found unknown drive: {'alias': 'scsi0-0-2', 'readonly': 'False', 'iface': 'ide', 'name': 'sdc', 'address': {'bus': '0', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '2'}, 'device': 'disk', 'path': '/rhev/data-center/mnt/192.168.100.241:_exports_vmstorage/c5a790c2-2885-4220-b803-35e4a40ecef6/images/5866d66c-0756-4544-af6a-95f5aed9312b/1751cd16-fd89-4441-b847-45169689c9a7', 'type': 'disk'} (storage:217) 2018-07-02 19:22:02,840+0800 INFO (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Starting connection (guestagent:244) 2018-07-02 19:22:02,841+0800 ERROR (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Failed to connect to guest agent channel (vm:2415) Traceback (most recent call last): File "/usr/lib/python2.7/site-packages/vdsm/virt/vm.py", line 2413, in _vmDependentInit self.guestAgent.start() File "/usr/lib/python2.7/site-packages/vdsm/virt/guestagent.py", line 245, in start self._prepare_socket() File "/usr/lib/python2.7/site-packages/vdsm/virt/guestagent.py", line 287, in _prepare_socket supervdsm.getProxy().prepareVmChannel(self._socketName) File "/usr/lib/python2.7/site-packages/vdsm/supervdsm.py", line 53, in __call__ return callMethod() File "/usr/lib/python2.7/site-packages/vdsm/supervdsm.py", line 51, in <lambda> **kwargs) File "<string>", line 2, in prepareVmChannel File "/usr/lib64/python2.7/multiprocessing/managers.py", line 773, in _callmethod raise convert_to_error(kind, result) OSError: [Errno 2] No such file or directory: '/var/lib/libvirt/qemu/channels/0febbdb6-9b90-4052-8169-4f6792d99d1e.com.redhat.rhevm.vdsm' 2018-07-02 19:22:02,843+0800 INFO (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') CPU running: domain initialization (vm:5742) 2018-07-02 19:22:02,910+0800 INFO (jsonrpc/6) [api.host] START dumpxmls(vmList=[u'0febbdb6-9b90-4052-8169-4f6792d99d1e']) from=::ffff:192.168.100.59,60612 (api:46) 2018-07-02 19:22:09,292+0800 ERROR (periodic/39) [virt.vmstats] VM metrics collection failed (vmstats:260) Traceback (most recent call last): File "/usr/lib/python2.7/site-packages/vdsm/virt/vmstats.py", line 217, in send_metrics diskinfo['readOps'] KeyError: 'readOps' 2018-07-02 19:22:09,813+0800 INFO (jsonrpc/2) [api.host] START getStats() from=::ffff:192.168.100.59,60612 (api:46) 2018-07-02 19:22:09,816+0800 INFO (jsonrpc/2) [vdsm.api] START repoStats(domains=()) from=::ffff:192.168.100.59,60612, task_id=36236da1-3586-4324-a842-71aafb37d124 (api:46) 2018-07-02 19:22:09,816+0800 INFO (jsonrpc/2) [vdsm.api] FINISH repoStats return={u'6ff100c0-d08d-4504-832d-53876bf97349': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000776933', 'lastCheck': '6.6', 'valid': True}, u'5a50d04c-4d39-4249-8fc6-24df9bd096b0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000358834', 'lastCheck': '4.1', 'valid': True}, u'c5a790c2-2885-4220-b803-35e4a40ecef6': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000328361', 'lastCheck': '3.3', 'valid': True}, u'461517fd-986a-4b39-9b84-5fd54ef06967': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000412804', 'lastCheck': '5.7', 'valid': True}} from=::ffff:192.168.100.59,60612, task_id=36236da1-3586-4324-a842-71aafb37d124 (api:52) 2018-07-02 19:22:09,831+0800 INFO (periodic/39) [vdsm.api] START repoStats(domains=()) from=internal, task_id=b4dcd456-df92-4270-92be-d73d7e7127f9 (api:46) 2018-07-02 19:22:09,832+0800 INFO (periodic/39) [vdsm.api] FINISH repoStats return={u'6ff100c0-d08d-4504-832d-53876bf97349': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000776933', 'lastCheck': '6.6', 'valid': True}, u'5a50d04c-4d39-4249-8fc6-24df9bd096b0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000358834', 'lastCheck': '4.1', 'valid': True}, u'c5a790c2-2885-4220-b803-35e4a40ecef6': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000328361', 'lastCheck': '3.3', 'valid': True}, u'461517fd-986a-4b39-9b84-5fd54ef06967': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000412804', 'lastCheck': '5.7', 'valid': True}} from=internal, task_id=b4dcd456-df92-4270-92be-d73d7e7127f9 (api:52) #engine.log 2018-07-02 19:22:07,907+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (default task-41) [2f069134-d730-4ca5-93b7-3925a06234da] Lock Acquired to object 'EngineLock:{exclusiveLocks='[0febbdb6-9b90-4052-8169-4f6792d99d1e=VM]', sharedLocks=''}' 2018-07-02 19:22:07,935+08 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (default task-41) [2f069134-d730-4ca5-93b7-3925a06234da] START, IsVmDuringInitiatingVDSCommand( IsVmDuringInitiatingVDSCommandParameters:{vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}), log id: 703a024f 2018-07-02 19:22:07,936+08 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (default task-41) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 703a024f 2018-07-02 19:22:07,998+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Running command: RunVmCommand internal: false. Entities affected : ID: 0febbdb6-9b90-4052-8169-4f6792d99d1e Type: VMAction group RUN_VM with role type USER 2018-07-02 19:22:08,061+08 INFO [org.ovirt.engine.core.bll.scheduling.policyunits.PreferredHostsWeightPolicyUnit] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Penalizing host 'storage3' because it is not preferred. 2018-07-02 19:22:08,061+08 INFO [org.ovirt.engine.core.bll.scheduling.policyunits.PreferredHostsWeightPolicyUnit] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Penalizing host 'storage2' because it is not preferred. 2018-07-02 19:22:08,073+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Running VM with attached cd 'CentOS-7-x86_64-Minimal-1804.iso' 2018-07-02 19:22:08,075+08 INFO [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] START, UpdateVmDynamicDataVDSCommand( UpdateVmDynamicDataVDSCommandParameters:{hostId='null', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e', vmDynamic='org.ovirt.engine.core.common.businessentities.VmDynamic@540c1d26'}), log id: 11987cd 2018-07-02 19:22:08,076+08 INFO [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, UpdateVmDynamicDataVDSCommand, log id: 11987cd 2018-07-02 19:22:08,079+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] START, IsoPrefixVDSCommand(HostName = tchypnode1.ecr.com, VdsAndPoolIDVDSParametersBase:{hostId='7902d7f8-348e-426e-a3c3-399d08b0eb1e', storagePoolId='5b0f6949-02d6-00bf-008b-000000000167'}), log id: 3d66a9a0 2018-07-02 19:22:08,079+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, IsoPrefixVDSCommand, return: /rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111, log id: 3d66a9a0 2018-07-02 19:22:08,079+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] START, IsoPrefixVDSCommand(HostName = tchypnode1.ecr.com, VdsAndPoolIDVDSParametersBase:{hostId='7902d7f8-348e-426e-a3c3-399d08b0eb1e', storagePoolId='5b0f6949-02d6-00bf-008b-000000000167'}), log id: 3d66a9a0 2018-07-02 19:22:08,079+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.IsoPrefixVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, IsoPrefixVDSCommand, return: /rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111, log id: 3d66a9a0 2018-07-02 19:22:08,087+08 INFO [org.ovirt.engine.core.vdsbroker.CreateVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] START, CreateVDSCommand( CreateVDSCommandParameters:{hostId='7902d7f8-348e-426e-a3c3-399d08b0eb1e', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e', vm='VM [badvm-avodev-controller]'}), log id: 54fa1fca 2018-07-02 19:22:08,090+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] START, CreateBrokerVDSCommand(HostName = tchypnode1.ecr.com, CreateVDSCommandParameters:{hostId='7902d7f8-348e-426e-a3c3-399d08b0eb1e', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e', vm='VM [badvm-avodev-controller]'}), log id: 54926511 2018-07-02 19:22:08,096+08 INFO [org.ovirt.engine.core.vdsbroker.builder.vminfo.VmInfoBuilderImpl] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Bootable disk '1ea736ad-164c-4ac2-a6ce-9b8e6eceefde' set to index '0' 2018-07-02 19:22:08,130+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] VM <?xml version="1.0" encoding="UTF-8"?><domain type="kvm" xmlns:ovirt-tune="http://ovirt.org/vm/tune/1.0" xmlns:ovirt-vm="http://ovirt.org/vm/1.0"> <name>badvm-avodev-controller</name> <uuid>0febbdb6-9b90-4052-8169-4f6792d99d1e</uuid> <memory>8388608</memory> <currentMemory>8388608</currentMemory> <maxMemory slots="16">33554432</maxMemory> <vcpu current="4">16</vcpu> <sysinfo type="smbios"> <system> <entry name="manufacturer">oVirt</entry> <entry name="product">OS-NAME:</entry> <entry name="version">OS-VERSION:</entry> <entry name="serial">HOST-SERIAL:</entry> <entry name="uuid">0febbdb6-9b90-4052-8169-4f6792d99d1e</entry> </system> </sysinfo> <clock offset="variable" adjustment="0"> <timer name="rtc" tickpolicy="catchup"/> <timer name="pit" tickpolicy="delay"/> <timer name="hpet" present="no"/> </clock> <features> <acpi/> </features> <cpu match="exact"> <model>Nehalem</model> <topology cores="1" threads="1" sockets="16"/> <numa> <cell cpus="0,1,2,3" memory="8388608"/> <numa> <cell cpus="0,1,2,3" memory="8388608"/> </numa> </cpu> <cputune/> <devices> <input type="tablet" bus="usb"/> <channel type="unix"> <target type="virtio" name="ovirt-guest-agent.0"/> <source mode="bind" path="/var/lib/libvirt/qemu/channels/0febbdb6-9b90-4052-8169-4f6792d99d1e.ovirt-guest-agent.0"/> </channel> <channel type="unix"> <target type="virtio" name="org.qemu.guest_agent.0"/> <source mode="bind" path="/var/lib/libvirt/qemu/channels/0febbdb6-9b90-4052-8169-4f6792d99d1e.org.qemu.guest_agent.0"/> </channel> <controller type="usb" model="piix3-uhci" index="0"> <address bus="0x00" domain="0x0000" function="0x2" slot="0x01" type="pci"/> </controller> <memballoon model="virtio"> <stats period="5"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x06" type="pci"/> </memballoon> <controller type="ide"> <address bus="0x00" domain="0x0000" function="0x1" slot="0x01" type="pci"/> </controller> <controller type="scsi" model="virtio-scsi" index="0"/> <controller type="scsi" index="0"> <address bus="0x00" domain="0x0000" function="0x0" slot="0x05" type="pci"/> </controller> <graphics type="vnc" port="-1" autoport="yes" passwd="*****" passwdValidTo="1970-01-01T00:00:01" keymap="en-us"> <listen type="network" network="vdsm-ovirtmgmt"/> <graphics type="vnc" port="-1" autoport="yes" passwd="*****" passwdValidTo="1970-01-01T00:00:01" keymap="en-us"> <listen type="network" network="vdsm-ovirtmgmt"/> </graphics> <video> <model type="vga" vram="16384" heads="1"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x02" type="pci"/> </video> <rng model="virtio"> <backend model="random">/dev/urandom</backend> </rng> <controller type="virtio-serial" index="0" ports="16"> <address bus="0x00" domain="0x0000" function="0x0" slot="0x04" type="pci"/> </controller> <interface type="bridge"> <model type="virtio"/> <link state="up"/> <source bridge="ovirtmgmt"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x03" type="pci"/> <mac address="00:1a:4a:16:01:f5"/> <filterref filter="vdsm-no-mac-spoofing"/> <bandwidth/> </interface> <interface type="bridge"> <model type="virtio"/> <link state="up"/> <source bridge="ovirtmgmt"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x07" type="pci"/> <mac address="00:1a:4a:16:01:f6"/> <filterref filter="vdsm-no-mac-spoofing"/> <bandwidth/> </interface> <bandwidth/> </interface> <disk type="file" device="cdrom" snapshot="no"> <driver name="qemu" type="raw" error_policy="report"/> <source file="/rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111/CentOS-7-x86_64-Minimal-1804.iso" startupPolicy="optional"/> <target dev="hdc" bus="ide"/> <readonly/> <address bus="1" controller="0" unit="0" type="drive" target="0"/> <boot order="2"/> </disk> <disk snapshot="no" type="file" device="disk"> <target dev="sda" bus="scsi"/> <source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/461517fd-986a-4b39-9b84-5fd54ef06967/images/1ea736ad-164c-4ac2-a6ce-9b8e6eceefde/b7f8140f-3a34-40fd-9fec-878df3d159a4"/> <driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"/> <address bus="0" controller="0" unit="0" type="drive" target="0"/> <boot order="1"/> <serial>1ea736ad-164c-4ac2-a6ce-9b8e6eceefde</serial> </disk> <disk snapshot="no" type="file" device="disk"> <target dev="sdb" bus="scsi"/> <source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/183b3ace-64e1-49cf-a214-b5462f989171/36ea0af6-b681-4923-bf70-6b51717f372d"/> <driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"/> <address bus="0" controller="0" unit="1" type="drive" target="0"/> <serial>183b3ace-64e1-49cf-a214-b5462f989171</serial> </disk> <disk snapshot="no" type="file" device="disk"> <target dev="sdc" bus="scsi"/> <disk snapshot="no" type="file" device="disk"> <target dev="sdc" bus="scsi"/> <source file="/rhev/data-center/5b0f6949-02d6-00bf-008b-000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/5866d66c-0756-4544-af6a-95f5aed9312b/1751cd16-fd89-4441-b847-45169689c9a7"/> <driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"/> <address bus="0" controller="0" unit="2" type="drive" target="0"/> <serial>5866d66c-0756-4544-af6a-95f5aed9312b</serial> </disk> </devices> <pm> <suspend-to-disk enabled="no"/> <suspend-to-mem enabled="no"/> </pm> <os> <type arch="x86_64" machine="pc-i440fx-2.5">hvm</type> <smbios mode="sysinfo"/> </os> <metadata> <ovirt-tune:qos/> <ovirt-vm:vm> <minGuaranteedMemoryMb type="int">4096</minGuaranteedMemoryMb> <clusterVersion>4.2</clusterVersion> <ovirt-vm:custom/> <ovirt-vm:device mac_address="00:1a:4a:16:01:f6"> <ovirt-vm:custom/> </ovirt-vm:device> <ovirt-vm:device mac_address="00:1a:4a:16:01:f5"> <ovirt-vm:custom/> </ovirt-vm:device> <ovirt-vm:device devtype="disk" name="sdb"> <ovirt-vm:imageID>183b3ace-64e1-49cf-a214-b5462f989171</ovirt-vm:imageID> <ovirt-vm:device devtype="disk" name="sdb"> <ovirt-vm:imageID>183b3ace-64e1-49cf-a214-b5462f989171</ovirt-vm:imageID> <ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID> <ovirt-vm:volumeID>36ea0af6-b681-4923-bf70-6b51717f372d</ovirt-vm:volumeID> <ovirt-vm:domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ovirt-vm:domainID> </ovirt-vm:device> <ovirt-vm:device devtype="disk" name="sda"> <ovirt-vm:imageID>1ea736ad-164c-4ac2-a6ce-9b8e6eceefde</ovirt-vm:imageID> <ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID> <ovirt-vm:volumeID>b7f8140f-3a34-40fd-9fec-878df3d159a4</ovirt-vm:volumeID> <ovirt-vm:domainID>461517fd-986a-4b39-9b84-5fd54ef06967</ovirt-vm:domainID> </ovirt-vm:device> <ovirt-vm:device devtype="disk" name="sdc"> <ovirt-vm:imageID>5866d66c-0756-4544-af6a-95f5aed9312b</ovirt-vm:imageID> <ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ovirt-vm:poolID> <ovirt-vm:volumeID>1751cd16-fd89-4441-b847-45169689c9a7</ovirt-vm:volumeID> <ovirt-vm:domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ovirt-vm:domainID> </ovirt-vm:device> <launchPaused>false</launchPaused> <resumeBehavior>auto_resume</resumeBehavior> </ovirt-vm:vm> </metadata> </domain> 2018-07-02 19:22:08,143+08 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, CreateBrokerVDSCommand, log id: 54926511 2018-07-02 19:22:08,144+08 INFO [org.ovirt.engine.core.vdsbroker.CreateVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, CreateVDSCommand, return: WaitForLaunch, log id: 54fa1fca 2018-07-02 19:22:08,145+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Lock freed to object 'EngineLock:{exclusiveLocks='[0febbdb6-9b90-4052-8169-4f6792d99d1e=VM]', sharedLocks=''}' At 2018-07-02 14:19:23, "Shani Leviim" <sleviim@redhat.com> wrote: Hi, Can you please attach full vdsm and engine logs? Regards, Shani Leviim On Fri, Jun 29, 2018 at 1:41 PM, Pym <pym0914@163.com> wrote: Hi: I use environment is ovirt4.2.0, first, I created a template, the template has already installed in the operating system, and then I use this template to create a virtual machine, sometimes can't find the disk happens, particular case is shown in figure: At this point, the virtual machine attaches disk again and cannot be recognized. However, the disk above is identifiable to other virtual machines, and the contents of disk still exist I would like to ask what causes this problem. _______________________________________________ Users mailing list -- users@ovirt.org To unsubscribe send an email to users-leave@ovirt.org Privacy Statement: https://www.ovirt.org/site/privacy-policy/ oVirt Code of Conduct: https://www.ovirt.org/community/about/community-guidelines/ List Archives: https://lists.ovirt.org/archives/list/users@ovirt.org/message/XHW44X37UKSCCL... 【网易自营|30天无忧退货】爱上书写:施华洛世奇制造商星空原色水晶笔,限时仅29元>>

Hi Pym, Thanks for the engine logs, I saw a similar bug which was fixed recently in ovirt 4.2.2 (see [1]). Is it possible you can upgrade your version to 4.2.2 and check if that fixed your issue? [1] https://bugzilla.redhat.com/1545862 Regards, Maor On Wed, Jul 4, 2018 at 12:14 PM, Pym <pym0914@163.com> wrote:
Hi,
I saw the vm device interface of the problematic virtual machine in the web interface as follows:
在 2018-07-03 13:48:08,"Pym" <pym0914@163.com> 写道:
Hi,
The last mail engine did not have enough log content, this email adds There is an ERROR in the log. How to deal with this problem
#engine.log 2018-07-03 11:28:40,493+08 INFO [org.ovirt.engine.core. vdsbroker.monitoring.VmAnalyzer] (EE-ManagedThreadFactory-engineScheduled-Thread-58) [] VM '56b28418-9dcf-4d46-87fe-0dfef05bb141' was discovered as 'Down' on VDS '7902d7f8-348e-426e-a3c3-399d08b0eb1e'(tchypnode1.ecr.com) 2018-07-03 11:28:40,511+08 INFO [org.ovirt.engine.core.bll.AddUnmanagedVmsCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-58) [55d6983c] Running command: AddUnmanagedVmsCommand internal: true. 2018-07-03 11:28:40,514+08 INFO [org.ovirt.engine.core. vdsbroker.vdsbroker.FullListVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-58) [55d6983c] START, FullListVDSCommand(HostName = tchypnode1.ecr.com, FullListVDSCommandParameters:{hostId='7902d7f8-348e-426e-a3c3-399d08b0eb1e', vmIds='[56b28418-9dcf-4d46-87fe-0dfef05bb141]'}), log id: 7f6022f8 2018-07-03 11:28:40,521+08 INFO [org.ovirt.engine.core. vdsbroker.vdsbroker.FullListVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-58) [55d6983c] FINISH, FullListVDSCommand, return: [{status=Down, guestFQDN=, kvmEnable=true, xml=<?xml version="1.0" encoding="UTF-8"?><domain type="kvm" xmlns:ovirt-tune="http://ovirt.org/vm/tune/1.0" xmlns:ovirt-vm="http://ovirt.org/vm/1.0"><name>avodev- controller</name><uuid>56b28418-9dcf-4d46-87fe-0dfef05bb141</uuid><memory> 8388608</memory><currentMemory>8388608</currentMemory><maxMemory slots="16">33554432</maxMemory><vcpu current="4">16</vcpu><sysinfo type="smbios"><system><entry name="manufacturer">oVirt</entry><entry name="product">OS-NAME:</entry><entry name="version">OS-VERSION:</entry><entry name="serial">HOST-SERIAL:</entry><entry name="uuid">56b28418-9dcf- 4d46-87fe-0dfef05bb141</entry></system></sysinfo><clock offset="variable" adjustment="0"><timer name="rtc" tickpolicy="catchup"></timer><timer name="pit" tickpolicy="delay"></timer><timer name="hpet" present="no"></timer></clock><features><acpi></acpi></features><cpu match="exact"><model>Nehalem</model><topology cores="1" threads="1" sockets="16"></topology><numa><cell cpus="0,1,2,3" memory="8388608"></cell></numa></cpu><cputune></cputune><devices><input type="tablet" bus="usb"></input><channel type="unix"><target type="virtio" name="ovirt-guest-agent.0"></target><source mode="bind" path="/var/lib/libvirt/qemu/channels/56b28418-9dcf-4d46- 87fe-0dfef05bb141.ovirt-guest-agent.0"></source></channel><channel type="unix"><target type="virtio" name="org.qemu.guest_agent.0"></target><source mode="bind" path="/var/lib/libvirt/qemu/channels/56b28418-9dcf-4d46- 87fe-0dfef05bb141.org.qemu.guest_agent.0"></source></channel><controller type="scsi" model="virtio-scsi" index="0"></controller><controller type="usb" model="piix3-uhci" index="0"><address bus="0x00" domain="0x0000" function="0x2" slot="0x01" type="pci"></address></controller><controller type="virtio-serial" index="0" ports="16"><address bus="0x00" domain="0x0000" function="0x0" slot="0x04" type="pci"></address></controller><graphics type="vnc" port="-1" autoport="yes" passwd="*****" passwdValidTo="1970-01-01T00:00:01" keymap="en-us"><listen type="network" network="vdsm-ovirtmgmt"></listen></graphics><controller type="ide"><address bus="0x00" domain="0x0000" function="0x1" slot="0x01" type="pci"></address></controller><rng model="virtio"><backend model="random">/dev/urandom</backend></rng><memballoon model="virtio"><stats period="5"></stats><address bus="0x00" domain="0x0000" function="0x0" slot="0x06" type="pci"></address></memballoon><video><model type="vga" vram="16384" heads="1"></model></video><interface type="bridge"><model type="virtio"></model><link state="up"></link><source bridge="ovirtmgmt"></source><address bus="0x00" domain="0x0000" function="0x0" slot="0x03" type="pci"></address><mac address="00:1a:4a:16:01:da"></mac><filterref filter="vdsm-no-mac-spoofing"></filterref><bandwidth></ bandwidth></interface><interface type="bridge"><model type="virtio"></model><link state="up"></link><source bridge="ovirtmgmt"></source><address bus="0x00" domain="0x0000" function="0x0" slot="0x07" type="pci"></address><mac address="00:1a:4a:16:01:db"></mac><filterref filter="vdsm-no-mac-spoofing"></filterref><bandwidth></bandwidth></interface><disk type="file" device="cdrom" snapshot="no"><driver name="qemu" type="raw" error_policy="report"></driver><source file="/rhev/data-center/mnt/ 192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d- 53876bf97349/images/11111111-1111-1111-1111-111111111111/ CentOS-7-x86_64-Minimal-1804.iso" startupPolicy="optional"></source><target dev="hdc" bus="ide"></target><readonly></readonly><address bus="1" controller="0" unit="0" type="drive" target="0"></address><boot order="2"></boot></disk><disk snapshot="no" type="file" device="disk"><target dev="sda" bus="scsi"></target><source file="/rhev/data-center/5b0f6949-02d6-00bf-008b- 000000000167/461517fd-986a-4b39-9b84-5fd54ef06967/images/ 8e17d226-94d0-42f8-ae29-9c795db7e522/3b4e2ee9-ba96- 4b4d-8392-94e2736e6dab"></source><driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"></driver><address bus="0" controller="0" unit="0" type="drive" target="0"></address><boot order="1"></boot><serial>8e17d226-94d0-42f8-ae29- 9c795db7e522</serial></disk><disk snapshot="no" type="file" device="disk"><target dev="sdb" bus="scsi"></target><source file="/rhev/data-center/5b0f6949-02d6-00bf-008b- 000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/ d0a00b64-3676-4783-a7af-9bb53940b03e/a88be564-84f8- 4232-9ba6-91be4625a469"></source><driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"></driver><address bus="0" controller="0" unit="1" type="drive" target="0"></address><serial> d0a00b64-3676-4783-a7af-9bb53940b03e</serial></disk><disk snapshot="no" type="file" device="disk"><target dev="sdc" bus="scsi"></target><source file="/rhev/data-center/5b0f6949-02d6-00bf-008b- 000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/ 03352bba-9d9f-4fca-ac35-f4aa7767e0b6/26a2e47d-2b10- 454a-83f4-3c3c96d27f72"></source><driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"></driver><address bus="0" controller="0" unit="2" type="drive" target="0"></address><serial> 03352bba-9d9f-4fca-ac35-f4aa7767e0b6</serial></disk></devices><pm><suspend-to-disk enabled="no"></suspend-to-disk><suspend-to-mem enabled="no"></suspend-to-mem></pm><os><type arch="x86_64" machine="pc-i440fx-2.5">hvm</type><smbios mode="sysinfo"></smbios></os>< metadata><ovirt-tune:qos></ovirt-tune:qos><ovirt-vm:vm><minGuaranteedMemoryMb type="int">4096</minGuaranteedMemoryMb><clusterVersion>4.2</ clusterVersion><ovirt-vm:custom></ovirt-vm:custom><ovirt-vm:device mac_address="00:1a:4a:16:01:db"><ovirt-vm:custom></ovirt- vm:custom></ovirt-vm:device><ovirt-vm:device mac_address="00:1a:4a:16:01: da"><ovirt-vm:custom></ovirt-vm:custom></ovirt-vm:device><ovirt-vm:device devtype="disk" name="sdb"><ovirt-vm:imageID>d0a00b64-3676-4783-a7af- 9bb53940b03e</ovirt-vm:imageID><ovirt-vm:poolID>5b0f6949-02d6-00bf-008b- 000000000167</ovirt-vm:poolID><ovirt-vm:volumeID>a88be564- 84f8-4232-9ba6-91be4625a469</ovirt-vm:volumeID><ovirt-vm: domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ovirt-vm: domainID></ovirt-vm:device><ovirt-vm:device devtype="disk" name="sda"><ovirt-vm:imageID>8e17d226-94d0-42f8-ae29- 9c795db7e522</ovirt-vm:imageID><ovirt-vm:poolID>5b0f6949-02d6-00bf-008b- 000000000167</ovirt-vm:poolID><ovirt-vm:volumeID>3b4e2ee9- ba96-4b4d-8392-94e2736e6dab</ovirt-vm:volumeID><ovirt-vm: domainID>461517fd-986a-4b39-9b84-5fd54ef06967</ovirt-vm: domainID></ovirt-vm:device><ovirt-vm:device devtype="disk" name="sdc"><ovirt-vm:imageID>03352bba-9d9f-4fca-ac35- f4aa7767e0b6</ovirt-vm:imageID><ovirt-vm:poolID>5b0f6949-02d6-00bf-008b- 000000000167</ovirt-vm:poolID><ovirt-vm:volumeID>26a2e47d- 2b10-454a-83f4-3c3c96d27f72</ovirt-vm:volumeID><ovirt-vm: domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ovirt-vm: domainID></ovirt-vm:device><launchPaused>false</ launchPaused><resumeBehavior>auto_resume</resumeBehavior></ ovirt-vm:vm></metadata></domain>, enableGuestEvents=true, vmId=56b28418-9dcf-4d46-87fe-0dfef05bb141, devices=[Ljava.lang.Object;@1a054604, guestDiskMapping={}, username=Unknown, vmType=kvm, smp=1, arch=x86_64, clientIp=, statusTime=7232123440, vmName=nNone, guestIPs=}], log id: 7f6022f8 2018-07-03 11:28:40,522+08 ERROR [org.ovirt.engine.core.bll.AddUnmanagedVmsCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-58) [55d6983c] Command 'org.ovirt.engine.core.bll.AddUnmanagedVmsCommand' failed: null 2018-07-03 11:28:40,522+08 ERROR [org.ovirt.engine.core.bll.AddUnmanagedVmsCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-58) [55d6983c] Exception: java.lang.NumberFormatException: null at java.lang.Integer.parseInt(Integer.java:542) [rt.jar:1.8.0_161] at java.lang.Integer.parseInt(Integer.java:615) [rt.jar:1.8.0_161] at org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerObjectsBuilder. parseIntVdsProperty(VdsBrokerObjectsBuilder.java:777) [vdsbroker.jar:] at org.ovirt.engine.core.bll.AddUnmanagedVmsCommand.convertVm( AddUnmanagedVmsCommand.java:121) [bll.jar:] at org.ovirt.engine.core.bll.AddUnmanagedVmsCommand.executeCommand( AddUnmanagedVmsCommand.java:97) [bll.jar:] at org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1132) [bll.jar:] at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1272) [bll.jar:] at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1914) [bll.jar:] at org.ovirt.engine.core.utils.transaction.TransactionSupport. executeInSuppressed(TransactionSupport.java:164) [utils.jar:] at org.ovirt.engine.core.utils.transaction.TransactionSupport. executeInScope(TransactionSupport.java:103) [utils.jar:] at org.ovirt.engine.core.bll.CommandBase.execute(CommandBase.java:1332) [bll.jar:] at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:406) [bll.jar:] at org.ovirt.engine.core.bll.executor.DefaultBackendActionExecutor. execute(DefaultBackendActionExecutor.java:13) [bll.jar:] at org.ovirt.engine.core.bll.Backend.runAction(Backend.java:468) [bll.jar:] at org.ovirt.engine.core.bll.Backend.runActionImpl(Backend.java:450) [bll.jar:] at org.ovirt.engine.core.bll.Backend.runInternalAction(Backend.java:656) [bll.jar:] at sun.reflect.GeneratedMethodAccessor452.invoke(Unknown Source) [:1.8.0_161] at sun.reflect.DelegatingMethodAccessorImpl.invoke( DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_161] at java.lang.reflect.Method.invoke(Method.java:498) [rt.jar:1.8.0_161] at org.jboss.as.ee.component.ManagedReferenceMethodIntercep tor.processInvocation(ManagedReferenceMethodInterceptor.java:52) at org.jboss.invocation.InterceptorContext.proceed( InterceptorContext.java:422) at org.jboss.invocation.InterceptorContext$Invocation. proceed(InterceptorContext.java:509) at org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor. delegateInterception(Jsr299BindingsInterceptor.java:78) at org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor. doMethodInterception(Jsr299BindingsInterceptor.java:88) at org.jboss.as.weld.interceptors.Jsr299BindingsInterceptor. processInvocation(Jsr299BindingsInterceptor.java:101) at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1. processInvocation(UserInterceptorFactory.java:63) at org.jboss.invocation.InterceptorContext.proceed( InterceptorContext.java:422) at org.jboss.as.ejb3.component.invocationmetrics.ExecutionTimeInterceptor. processInvocation(ExecutionTimeInterceptor.java:43) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed( InterceptorContext.java:422) at org.jboss.as.ee.concurrent.ConcurrentContextInterceptor. processInvocation(ConcurrentContextInterceptor.java:45) [wildfly-ee-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed( InterceptorContext.java:422) at org.jboss.invocation.InitialInterceptor.processInvocation( InitialInterceptor.java:40) at org.jboss.invocation.InterceptorContext.proceed( InterceptorContext.java:422) at org.jboss.invocation.ChainedInterceptor.processInvocation( ChainedInterceptor.java:53) at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor .processInvocation(ComponentDispatcherInterceptor.java:52) at org.jboss.invocation.InterceptorContext.proceed( InterceptorContext.java:422) at org.jboss.as.ejb3.component.singleton.SingletonComponentInstanceAsso ciationInterceptor.processInvocation(SingletonComponentInstanceAssociationInterceptor.java:53) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed( InterceptorContext.java:422) at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInNoTx(CMTTxInterceptor.java:264) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.as.ejb3.tx.CMTTxInterceptor.supports(CMTTxInterceptor.java:379) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:244) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed( InterceptorContext.java:422) at org.jboss.invocation.InterceptorContext$Invocation. proceed(InterceptorContext.java:509) at org.jboss.weld.ejb.AbstractEJBRequestScopeActivat ionInterceptor.aroundInvoke(AbstractEJBRequestScopeActivationInterceptor.java:73) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final] at org.jboss.as.weld.ejb.EjbRequestScopeActivationInter ceptor.processInvocation(EjbRequestScopeActivationInterceptor.java:89) at org.jboss.invocation.InterceptorContext.proceed( InterceptorContext.java:422) at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterc eptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed( InterceptorContext.java:422) at org.jboss.as.ejb3.component.invocationmetrics.WaitTimeInterceptor. processInvocation(WaitTimeInterceptor.java:47) [wildfly-ejb3-11.0.0.Final. jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed( InterceptorContext.java:422) at org.jboss.as.ejb3.security.SecurityContextInterceptor. processInvocation(SecurityContextInterceptor.java:100) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed( InterceptorContext.java:422) at org.jboss.as.ejb3.deployment.processors.StartupAwaitInterceptor. processInvocation(StartupAwaitInterceptor.java:22) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed( InterceptorContext.java:422) at org.jboss.as.ejb3.component.interceptors.ShutDownInterceptorFactory$1. processInvocation(ShutDownInterceptorFactory.java:64) [wildfly-ejb3-11.0.0.Final.jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed( InterceptorContext.java:422) at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor. processInvocation(LoggingInterceptor.java:67) [wildfly-ejb3-11.0.0.Final. jar:11.0.0.Final] at org.jboss.invocation.InterceptorContext.proceed( InterceptorContext.java:422) at org.jboss.as.ee.component.NamespaceContextInterceptor. processInvocation(NamespaceContextInterceptor.java:50) at org.jboss.invocation.InterceptorContext.proceed( InterceptorContext.java:422) at org.jboss.invocation.ContextClassLoaderInterceptor.processInvocation( ContextClassLoaderInterceptor.java:60) at org.jboss.invocation.InterceptorContext.proceed( InterceptorContext.java:422) at org.jboss.invocation.InterceptorContext.run( InterceptorContext.java:438) at org.wildfly.security.manager.WildFlySecurityManager.doChecked( WildFlySecurityManager.java:609) at org.jboss.invocation.AccessCheckingInterceptor.processInvocation( AccessCheckingInterceptor.java:57) at org.jboss.invocation.InterceptorContext.proceed( InterceptorContext.java:422) at org.jboss.invocation.ChainedInterceptor.processInvocation( ChainedInterceptor.java:53) at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:198) at org.jboss.as.ee.component.ViewDescription$1.processInvocation( ViewDescription.java:185) at org.jboss.as.ee.component.ProxyInvocationHandler.invoke( ProxyInvocationHandler.java:81) at org.ovirt.engine.core.bll.interfaces.BackendInternal$$$ view3.runInternalAction(Unknown Source) [bll.jar:] at sun.reflect.GeneratedMethodAccessor451.invoke(Unknown Source) [:1.8.0_161] at sun.reflect.DelegatingMethodAccessorImpl.invoke( DelegatingMethodAccessorImpl.java:43) [rt.jar:1.8.0_161] at java.lang.reflect.Method.invoke(Method.java:498) [rt.jar:1.8.0_161] at org.jboss.weld.util.reflection.Reflections.invokeAndUnwrap(Reflections.java:433) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final] at org.jboss.weld.bean.proxy.EnterpriseBeanProxyMethodHandler.invoke( EnterpriseBeanProxyMethodHandler.java:127) [weld-core-impl-2.4.3.Final. jar:2.4.3.Final] at org.jboss.weld.bean.proxy.EnterpriseTargetBeanInstance.invoke( EnterpriseTargetBeanInstance.java:56) [weld-core-impl-2.4.3.Final. jar:2.4.3.Final] at org.jboss.weld.bean.proxy.InjectionPointPropagatingEnter priseTargetBeanInstance.invoke(InjectionPointPropagatingEnter priseTargetBeanInstance.java:67) [weld-core-impl-2.4.3.Final. jar:2.4.3.Final] at org.jboss.weld.bean.proxy.ProxyMethodHandler.invoke(ProxyMethodHandler.java:100) [weld-core-impl-2.4.3.Final.jar:2.4.3.Final] at org.ovirt.engine.core.bll.BackendCommandObjectsHandler$ BackendInternal$BackendLocal$2049259618$Proxy$_$$_Weld$EnterpriseProxy$.runInternalAction(Unknown Source) [bll.jar:] at org.ovirt.engine.core.bll.VdsEventListener.addUnmanagedVms(VdsEventListener.java:473) [bll.jar:] at org.ovirt.engine.core.vdsbroker.monitoring. VmsMonitoring.addUnmanagedVms(VmsMonitoring.java:326) [vdsbroker.jar:] at org.ovirt.engine.core.vdsbroker.monitoring.VmsMonitoring.perform(VmsMonitoring.java:96) [vdsbroker.jar:] at org.ovirt.engine.core.vdsbroker.monitoring.PollVmStatsRefresher.poll(PollVmStatsRefresher.java:43) [vdsbroker.jar:] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [rt.jar:1.8.0_161] at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308) [rt.jar:1.8.0_161] at org.glassfish.enterprise.concurrent.internal. ManagedScheduledThreadPoolExecutor$ManagedScheduledFutureTask.access$201( ManagedScheduledThreadPoolExecutor.java:383) [javax.enterprise.concurrent- 1.0.jar:] at org.glassfish.enterprise.concurrent.internal. ManagedScheduledThreadPoolExecutor$ManagedScheduledFutureTask.run( ManagedScheduledThreadPoolExecutor.java:534) [javax.enterprise.concurrent- 1.0.jar:] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [rt.jar:1.8.0_161] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [rt.jar:1.8.0_161] at java.lang.Thread.run(Thread.java:748) [rt.jar:1.8.0_161] at org.glassfish.enterprise.concurrent.ManagedThreadFactoryImpl$ ManagedThread.run(ManagedThreadFactoryImpl.java:250) [javax.enterprise.concurrent-1.0.jar:] at org.jboss.as.ee.concurrent.service.ElytronManagedThreadFactory$ ElytronManagedThread.run(ElytronManagedThreadFactory.java:78)
2018-07-03 11:28:41,840+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (default task-65) [a2075a4a-458f-4224-820a-c3acb8d776fa] Lock Acquired to object 'EngineLock:{exclusiveLocks='[0febbdb6-9b90-4052-8169-4f6792d99d1e=VM]', sharedLocks=''}' 2018-07-03 11:28:41,871+08 INFO [org.ovirt.engine.core.vdsbroker. IsVmDuringInitiatingVDSCommand] (default task-65) [a2075a4a-458f-4224-820a-c3acb8d776fa] START, IsVmDuringInitiatingVDSCommand( IsVmDuringInitiatingVDSCommand Parameters:{vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}), log id: 18b64302 2018-07-03 11:28:41,871+08 INFO [org.ovirt.engine.core.vdsbroker. IsVmDuringInitiatingVDSCommand] (default task-65) [a2075a4a-458f-4224-820a-c3acb8d776fa] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 18b64302 2018-07-03 11:28:41,939+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] Running command: RunVmCommand internal: false. Entities affected : ID: 0febbdb6-9b90-4052-8169-4f6792d99d1e Type: VMAction group RUN_VM with role type USER 2018-07-03 11:28:41,995+08 INFO [org.ovirt.engine.core.bll. scheduling.policyunits.PreferredHostsWeightPolicyUnit] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] Penalizing host 'tchypnode2.ecr.com' because it is not preferred. 2018-07-03 11:28:41,995+08 INFO [org.ovirt.engine.core.bll. scheduling.policyunits.PreferredHostsWeightPolicyUnit] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] Penalizing host 'tchypnode1.ecr.com' because it is not preferred. 2018-07-03 11:28:41,995+08 INFO [org.ovirt.engine.core.bll. scheduling.policyunits.PreferredHostsWeightPolicyUnit] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] Penalizing host 'storage2' because it is not preferred. 2018-07-03 11:28:42,004+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] Running VM with attached cd 'CentOS-7-x86_64-Minimal-1804.iso' 2018-07-03 11:28:42,007+08 INFO [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] START, UpdateVmDynamicDataVDSCommand( UpdateVmDynamicDataVDSCommandParameters:{hostId='null', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e', vmDynamic='org.ovirt.engine. core.common.businessentities.VmDynamic@3746b80a'}), log id: 33de8399 2018-07-03 11:28:42,008+08 INFO [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] FINISH, UpdateVmDynamicDataVDSCommand, log id: 33de8399 2018-07-03 11:28:42,011+08 INFO [org.ovirt.engine.core. vdsbroker.vdsbroker.IsoPrefixVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] START, IsoPrefixVDSCommand(HostName = storage3, VdsAndPoolIDVDSParametersBase: {hostId='862f44c5-3529-4ef1-a2a6-2910d5b93460', storagePoolId='5b0f6949-02d6-00bf-008b-000000000167'}), log id: 39168ed2 2018-07-03 11:28:42,011+08 INFO [org.ovirt.engine.core. vdsbroker.vdsbroker.IsoPrefixVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] FINISH, IsoPrefixVDSCommand, return: /rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0- d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111, log id: 39168ed2 2018-07-03 11:28:42,017+08 INFO [org.ovirt.engine.core.vdsbroker.CreateVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] START, CreateVDSCommand( CreateVDSCommandParameters:{ hostId='862f44c5-3529-4ef1-a2a6-2910d5b93460', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e', vm='VM [badvm-avodev-controller]'}), log id: 72322ec5 2018-07-03 11:28:42,020+08 INFO [org.ovirt.engine.core. vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] START, CreateBrokerVDSCommand(HostName = storage3, CreateVDSCommandParameters:{hostId='862f44c5-3529-4ef1-a2a6-2910d5b93460', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e', vm='VM [badvm-avodev-controller]'}), log id: 68b50b27 2018-07-03 11:28:42,024+08 INFO [org.ovirt.engine.core. vdsbroker.builder.vminfo.VmInfoBuilderImpl] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] Bootable disk '1ea736ad-164c-4ac2-a6ce-9b8e6eceefde' set to index '0' 2018-07-03 11:28:42,042+08 INFO [org.ovirt.engine.core. vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] VM {memGuaranteedSize=4096, smpThreadsPerCore=1, cpuType=Nehalem, vmId=0febbdb6-9b90-4052-8169-4f6792d99d1e, acpiEnable=true, tabletEnable=true, vmType=kvm, smp=4, smpCoresPerSocket=1, emulatedMachine=pc-i440fx-2.5, smartcardEnable=false, guestNumaNodes=[{memory=8192, cpus=0,1,2,3, nodeIndex=0}], transparentHugePages=true, displayNetwork=ovirtmgmt, vmName=badvm-avodev-controller, maxVCpus=16, kvmEnable=true, devices=[{address={bus=0x00, domain=0x0000, function=0x0, slot=0x02, type=pci}, type=video, specParams={vram=16384}, device=vga, deviceId=a50622b4-d3d4-4319-ae6d-0c66848321ef}, {type=graphics, specParams={keyMap=en-us}, device=vnc, deviceId=8dd702a4-f289-4bd1-a991-74b9c7224568}, {iface=ide, shared=false, path=/rhev/data-center/mnt/192.168.100.59:_exports_iso/ 6ff100c0-d08d-4504-832d-53876bf97349/images/11111111- 1111-1111-1111-111111111111/CentOS-7-x86_64-Minimal-1804.iso, address={bus=1, controller=0, unit=0, type=drive, target=0}, readonly=true, bootOrder=2, index=2, type=disk, specParams={path=CentOS-7-x86_64-Minimal-1804.iso}, device=cdrom, deviceId=89f6751c-9b33-4171-9dfa-e3a513ae7729}, {discard=false, shared=false, address={bus=0, controller=0, unit=0, type=drive, target=0}, imageID=1ea736ad-164c-4ac2-a6ce-9b8e6eceefde, format=cow, index=0, optional=false, type=disk, deviceId=1ea736ad-164c-4ac2-a6ce-9b8e6eceefde, domainID=461517fd-986a-4b39-9b84-5fd54ef06967, propagateErrors=off, iface=scsi, readonly=false, bootOrder=1, poolID=5b0f6949-02d6-00bf-008b-000000000167, volumeID=b7f8140f-3a34-40fd-9fec-878df3d159a4, diskType=file, specParams={}, device=disk}, {discard=false, shared=false, address={bus=0, controller=0, unit=1, type=drive, target=0}, imageID=183b3ace-64e1-49cf-a214-b5462f989171, format=cow, optional=false, type=disk, deviceId=183b3ace-64e1-49cf-a214-b5462f989171, domainID=c5a790c2-2885-4220-b803-35e4a40ecef6, propagateErrors=off, iface=scsi, readonly=false, poolID=5b0f6949-02d6-00bf-008b-000000000167, volumeID=36ea0af6-b681-4923-bf70-6b51717f372d, diskType=file, specParams={}, device=disk}, {discard=false, shared=false, address={bus=0, controller=0, unit=2, type=drive, target=0}, imageID=5866d66c-0756-4544-af6a-95f5aed9312b, format=cow, optional=false, type=disk, deviceId=5866d66c-0756-4544-af6a-95f5aed9312b, domainID=c5a790c2-2885-4220-b803-35e4a40ecef6, propagateErrors=off, iface=scsi, readonly=false, poolID=5b0f6949-02d6-00bf-008b-000000000167, volumeID=1751cd16-fd89-4441-b847-45169689c9a7, diskType=file, specParams={}, device=disk}, {filter=vdsm-no-mac-spoofing, nicModel=pv, address={bus=0x00, domain=0x0000, function=0x0, slot=0x03, type=pci}, filterParameters=[], type=interface, specParams={inbound={}, outbound={}}, device=bridge, linkActive=true, deviceId=59cdfb1f-ef08-4fee-a99b-27ad6a27c14e, macAddr=00:1a:4a:16:01:f5, network=ovirtmgmt}, {filter=vdsm-no-mac-spoofing, nicModel=pv, address={bus=0x00, domain=0x0000, function=0x0, slot=0x07, type=pci}, filterParameters=[], type=interface, specParams={inbound={}, outbound={}}, device=bridge, linkActive=true, deviceId=d3bcaa77-ef63-4371-a59f-8e9d444c9cec, macAddr=00:1a:4a:16:01:f6, network=ovirtmgmt}, {address={bus=0x00, domain=0x0000, function=0x2, slot=0x01, type=pci}, index=0, model=piix3-uhci, type=controller, specParams={}, device=usb, deviceId=05c84b2b-2f7a-4dbf-a4f4-89572562bdca}, {address={bus=0x00, domain=0x0000, function=0x0, slot=0x06, type=pci}, type=balloon, specParams={model=virtio}, device=memballoon, deviceId=0a8c9c29-575c-4bcd-b983-7c7d1b5bf020}, {index=0, model=virtio-scsi, type=controller, specParams={}, device=scsi, deviceId=cc5b8141-51ed-4a8b-9cf4-a703fa9a3dd1}, {address={bus=0x00, domain=0x0000, function=0x0, slot=0x04, type=pci}, type=controller, specParams={}, device=virtio-serial, deviceId=d2049d59-412f-4518-9e0e-22c3ead63438}, {model=virtio, type=rng, specParams={source=urandom}, device=virtio, deviceId=befbd382-3e77-4773-a3bb-766b660a4248}], custom={device_17eb478e-16dd-4d17-9f90-d978a28cc2b9device_ 50c094be-df54-477f-a0e2-f2ce36a2132fdevice_04a84b3e- b86b-454e-96bc-2d3151a315f6=VmDevice:{id='VmDeviceId:{ deviceId='04a84b3e-b86b-454e-96bc-2d3151a315f6', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}', device='unix', type='CHANNEL', specParams='[]', address='{type=virtio-serial, bus=0, controller=0, port=1}', managed='false', plugged='true', readOnly='false', deviceAlias='channel0', customProperties='[]', snapshotId='null', logicalName='null', hostDevice=''}, device_17eb478e-16dd-4d17- 9f90-d978a28cc2b9device_50c094be-df54-477f-a0e2- f2ce36a2132f=VmDevice:{id='VmDeviceId:{deviceId='50c094be-df54-477f-a0e2-f2ce36a2132f', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}', device='scsi', type='CONTROLLER', specParams='[index=0]', address='{type=pci, slot=0x05, bus=0x00, domain=0x0000, function=0x0}', managed='false', plugged='true', readOnly='false', deviceAlias='scsi0', customProperties='[]', snapshotId='null', logicalName='null', hostDevice=''}, device_17eb478e-16dd-4d17-9f90-d978a28cc2b9device_50c094be-df54-477f-a0e2- f2ce36a2132fdevice_04a84b3e-b86b-454e-96bc-2d3151a315f6device_0f1f725b- ec13-4ce4-9dfb-8882aa0b28e7=VmDevice:{id='VmDeviceId:{ deviceId='0f1f725b-ec13-4ce4-9dfb-8882aa0b28e7', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}', device='unix', type='CHANNEL', specParams='[]', address='{type=virtio-serial, bus=0, controller=0, port=2}', managed='false', plugged='true', readOnly='false', deviceAlias='channel1', customProperties='[]', snapshotId='null', logicalName='null', hostDevice=''}, device_17eb478e-16dd-4d17- 9f90-d978a28cc2b9=VmDevice:{id='VmDeviceId:{deviceId=' 17eb478e-16dd-4d17-9f90-d978a28cc2b9', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}', device='ide', type='CONTROLLER', specParams='[]', address='{type=pci, slot=0x01, bus=0x00, domain=0x0000, function=0x1}', managed='false', plugged='true', readOnly='false', deviceAlias='ide', customProperties='[]', snapshotId='null', logicalName='null', hostDevice=''}}, timeOffset=0, nice=0, maxMemSize=32768, maxMemSlots=16, bootMenuEnable=false, memSize=8192, agentChannelName=ovirt-guest-agent.0} 2018-07-03 11:28:42,056+08 INFO [org.ovirt.engine.core. vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] VM <?xml version="1.0" encoding="UTF-8"?><domain type="kvm" xmlns:ovirt-tune="http:// ovirt.org/vm/tune/1.0" xmlns:ovirt-vm="http://ovirt.org/vm/1.0"> <name>badvm-avodev-controller</name> <uuid>0febbdb6-9b90-4052-8169-4f6792d99d1e</uuid> <memory>8388608</memory> <currentMemory>8388608</currentMemory> <maxMemory slots="16">33554432</maxMemory> <vcpu current="4">16</vcpu> <sysinfo type="smbios"> <system> <entry name="manufacturer">oVirt</entry> <entry name="product">OS-NAME:</entry> <entry name="version">OS-VERSION:</entry> <entry name="serial">HOST-SERIAL:</entry> <entry name="uuid">0febbdb6-9b90-4052-8169-4f6792d99d1e</entry> </system> </sysinfo> <clock offset="variable" adjustment="0"> <timer name="rtc" tickpolicy="catchup"/> <timer name="pit" tickpolicy="delay"/> <timer name="hpet" present="no"/> </clock> <features> <acpi/> </features> <cpu match="exact"> <model>Nehalem</model> <topology cores="1" threads="1" sockets="16"/> <numa> <cell cpus="0,1,2,3" memory="8388608"/> </numa> </cpu> <cputune/> <devices> <input type="tablet" bus="usb"/> <channel type="unix"> <target type="virtio" name="ovirt-guest-agent.0"/> <source mode="bind" path="/var/lib/libvirt/qemu/ channels/0febbdb6-9b90-4052-8169-4f6792d99d1e.ovirt-guest-agent.0"/> </channel> <channel type="unix"> <target type="virtio" name="org.qemu.guest_agent.0"/> <source mode="bind" path="/var/lib/libvirt/qemu/ channels/0febbdb6-9b90-4052-8169-4f6792d99d1e.org.qemu.guest_agent.0"/> </channel> <controller type="usb" model="piix3-uhci" index="0"> <address bus="0x00" domain="0x0000" function="0x2" slot="0x01" type="pci"/> </controller> <memballoon model="virtio"> <stats period="5"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x06" type="pci"/> </memballoon> <controller type="ide"> <address bus="0x00" domain="0x0000" function="0x1" slot="0x01" type="pci"/> </controller> <controller type="scsi" index="0"> <address bus="0x00" domain="0x0000" function="0x0" slot="0x05" type="pci"/> </controller> <graphics type="vnc" port="-1" autoport="yes" passwd="*****" passwdValidTo="1970-01-01T00:00:01" keymap="en-us"> <listen type="network" network="vdsm-ovirtmgmt"/> </graphics> <video> <model type="vga" vram="16384" heads="1"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x02" type="pci"/> </video> <rng model="virtio"> <backend model="random">/dev/urandom</backend> </rng> <controller type="virtio-serial" index="0" ports="16"> <address bus="0x00" domain="0x0000" function="0x0" slot="0x04" type="pci"/> </controller> <interface type="bridge"> <model type="virtio"/> <link state="up"/> <source bridge="ovirtmgmt"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x03" type="pci"/> <mac address="00:1a:4a:16:01:f5"/> <filterref filter="vdsm-no-mac-spoofing"/> <bandwidth/> </interface> <interface type="bridge"> <model type="virtio"/> <link state="up"/> <source bridge="ovirtmgmt"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x07" type="pci"/> <mac address="00:1a:4a:16:01:f6"/> <filterref filter="vdsm-no-mac-spoofing"/> <bandwidth/> </interface> <disk type="file" device="cdrom" snapshot="no"> <driver name="qemu" type="raw" error_policy="report"/> <source file="/rhev/data-center/mnt/192.168.100.59:_exports_iso/ 6ff100c0-d08d-4504-832d-53876bf97349/images/11111111- 1111-1111-1111-111111111111/CentOS-7-x86_64-Minimal-1804.iso" startupPolicy="optional"/> <target dev="hdc" bus="ide"/> <readonly/> <address bus="1" controller="0" unit="0" type="drive" target="0"/> <boot order="2"/> </disk> <disk snapshot="no" type="file" device="disk"> <target dev="sda" bus="scsi"/> <source file="/rhev/data-center/5b0f6949-02d6-00bf-008b- 000000000167/461517fd-986a-4b39-9b84-5fd54ef06967/images/ 1ea736ad-164c-4ac2-a6ce-9b8e6eceefde/b7f8140f-3a34- 40fd-9fec-878df3d159a4"/> <driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"/> <address bus="0" controller="0" unit="0" type="drive" target="0"/> <boot order="1"/> <serial>1ea736ad-164c-4ac2-a6ce-9b8e6eceefde</serial> </disk> <disk snapshot="no" type="file" device="disk"> <target dev="sdb" bus="scsi"/> <source file="/rhev/data-center/5b0f6949-02d6-00bf-008b- 000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/ 183b3ace-64e1-49cf-a214-b5462f989171/36ea0af6-b681- 4923-bf70-6b51717f372d"/> <driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"/> <address bus="0" controller="0" unit="1" type="drive" target="0"/> <serial>183b3ace-64e1-49cf-a214-b5462f989171</serial> </disk> <disk snapshot="no" type="file" device="disk"> <target dev="sdc" bus="scsi"/> <source file="/rhev/data-center/5b0f6949-02d6-00bf-008b- 000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/ 5866d66c-0756-4544-af6a-95f5aed9312b/1751cd16-fd89- 4441-b847-45169689c9a7"/> <driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"/> <address bus="0" controller="0" unit="2" type="drive" target="0"/> <serial>5866d66c-0756-4544-af6a-95f5aed9312b</serial> </disk> </devices> <pm> <suspend-to-disk enabled="no"/> <suspend-to-mem enabled="no"/> </pm> <os> <type arch="x86_64" machine="pc-i440fx-2.5">hvm</type> <smbios mode="sysinfo"/> </os> <metadata> <ovirt-tune:qos/> <ovirt-vm:vm> <minGuaranteedMemoryMb type="int">4096</minGuaranteedMemoryMb> <clusterVersion>4.2</clusterVersion> <ovirt-vm:custom/> <ovirt-vm:device mac_address="00:1a:4a:16:01:f6"> <ovirt-vm:custom/> </ovirt-vm:device> <ovirt-vm:device mac_address="00:1a:4a:16:01:f5"> <ovirt-vm:custom/> </ovirt-vm:device> <ovirt-vm:device devtype="disk" name="sdb"> <ovirt-vm:imageID>183b3ace-64e1-49cf-a214-b5462f989171</ ovirt-vm:imageID> <ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ ovirt-vm:poolID> <ovirt-vm:volumeID>36ea0af6-b681-4923-bf70-6b51717f372d</ ovirt-vm:volumeID> <ovirt-vm:domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ ovirt-vm:domainID> </ovirt-vm:device> <ovirt-vm:device devtype="disk" name="sda"> <ovirt-vm:imageID>1ea736ad-164c-4ac2-a6ce-9b8e6eceefde</ ovirt-vm:imageID> <ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ ovirt-vm:poolID> <ovirt-vm:volumeID>b7f8140f-3a34-40fd-9fec-878df3d159a4</ ovirt-vm:volumeID> <ovirt-vm:domainID>461517fd-986a-4b39-9b84-5fd54ef06967</ ovirt-vm:domainID> </ovirt-vm:device> <ovirt-vm:device devtype="disk" name="sdc"> <ovirt-vm:imageID>5866d66c-0756-4544-af6a-95f5aed9312b</ ovirt-vm:imageID> <ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ ovirt-vm:poolID> <ovirt-vm:volumeID>1751cd16-fd89-4441-b847-45169689c9a7</ ovirt-vm:volumeID> <ovirt-vm:domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ ovirt-vm:domainID> </ovirt-vm:device> <launchPaused>false</launchPaused> <resumeBehavior>auto_resume</resumeBehavior> </ovirt-vm:vm> </metadata> </domain>
2018-07-03 11:28:42,072+08 INFO [org.ovirt.engine.core. vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] FINISH, CreateBrokerVDSCommand, log id: 68b50b27 2018-07-03 11:28:42,074+08 INFO [org.ovirt.engine.core.vdsbroker.CreateVDSCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] FINISH, CreateVDSCommand, return: WaitForLaunch, log id: 72322ec5 2018-07-03 11:28:42,074+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] Lock freed to object 'EngineLock:{exclusiveLocks='[ 0febbdb6-9b90-4052-8169-4f6792d99d1e=VM]', sharedLocks=''}' 2018-07-03 11:28:42,079+08 INFO [org.ovirt.engine.core.dal. dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-67220) [a2075a4a-458f-4224-820a-c3acb8d776fa] EVENT_ID: USER_STARTED_VM(153), VM badvm-avodev-controller was started by admin@internal-authz (Host: storage3). 2018-07-03 11:28:43,803+08 INFO [org.ovirt.engine.core. vdsbroker.vdsbroker.DumpXmlsVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-72) [] START, DumpXmlsVDSCommand(HostName = storage3, Params:{hostId='862f44c5-3529-4ef1-a2a6-2910d5b93460', vmIds='[0febbdb6-9b90-4052-8169-4f6792d99d1e]'}), log id: 39084aef 2018-07-03 11:28:43,835+08 INFO [org.ovirt.engine.core. vdsbroker.vdsbroker.DumpXmlsVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-72) [] FINISH, DumpXmlsVDSCommand, return: [{vmId=0febbdb6-9b90-4052-8169-4f6792d99d1e, devices=[Ljava.util.Map;@749e3a2d}], log id: 39084aef 2018-07-03 11:28:43,837+08 ERROR [org.ovirt.engine.core. vdsbroker.monitoring.VmDevicesMonitoring] (EE-ManagedThreadFactory-engineScheduled-Thread-72) [] VM '0febbdb6-9b90-4052-8169-4f6792d99d1e' managed non pluggable device was removed unexpectedly from libvirt: 'VmDevice:{id='VmDeviceId:{ deviceId='cc5b8141-51ed-4a8b-9cf4-a703fa9a3dd1', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}', device='virtio-scsi', type='CONTROLLER', specParams='[]', address='', managed='true', plugged='false', readOnly='false', deviceAlias='', customProperties='[]', snapshotId='null', logicalName='null', hostDevice='null'}' 2018-07-03 11:28:44,132+08 INFO [org.ovirt.engine.core. vdsbroker.monitoring.VmAnalyzer] (ForkJoinPool-1-worker-9) [] VM '0febbdb6-9b90-4052-8169-4f6792d99d1e'(badvm-avodev-controller) moved from 'WaitForLaunch' --> 'PoweringUp' 2018-07-03 11:28:44,143+08 INFO [org.ovirt.engine.core. vdsbroker.vdsbroker.DumpXmlsVDSCommand] (ForkJoinPool-1-worker-9) [] START, DumpXmlsVDSCommand(HostName = storage3, Params:{hostId='862f44c5-3529-4ef1-a2a6-2910d5b93460', vmIds='[0febbdb6-9b90-4052-8169-4f6792d99d1e]'}), log id: 47bab6eb 2018-07-03 11:28:44,184+08 INFO [org.ovirt.engine.core. vdsbroker.vdsbroker.DumpXmlsVDSCommand] (ForkJoinPool-1-worker-9) [] FINISH, DumpXmlsVDSCommand, return: [{vmId=0febbdb6-9b90-4052-8169-4f6792d99d1e, devices=[Ljava.util.Map;@5a5f0670}], log id: 47bab6eb 2018-07-03 11:28:44,185+08 ERROR [org.ovirt.engine.core. vdsbroker.monitoring.VmDevicesMonitoring] (ForkJoinPool-1-worker-9) [] VM '0febbdb6-9b90-4052-8169-4f6792d99d1e' managed non pluggable device was removed unexpectedly from libvirt: 'VmDevice:{id='VmDeviceId:{ deviceId='cc5b8141-51ed-4a8b-9cf4-a703fa9a3dd1', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}', device='virtio-scsi', type='CONTROLLER', specParams='[]', address='', managed='true', plugged='false', readOnly='false', deviceAlias='', customProperties='[]', snapshotId='null', logicalName='null', hostDevice='null'}' 2018-07-03 11:28:53,460+08 INFO [org.ovirt.engine.core. vdsbroker.monitoring.VmAnalyzer] (EE-ManagedThreadFactory-engineScheduled-Thread-91) [] VM '56b28418-9dcf-4d46-87fe-0dfef05bb141' was discovered as 'Down' on VDS 'a24d7172-1c3c-44ab-8ef3-9f863aeb4a87'(storage2) 2018-07-03 11:28:53,477+08 INFO [org.ovirt.engine.core.bll.AddUnmanagedVmsCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-91) [609d6b57] Running command: AddUnmanagedVmsCommand internal: true. 2018-07-03 11:28:53,480+08 INFO [org.ovirt.engine.core. vdsbroker.vdsbroker.FullListVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-91) [609d6b57] START, FullListVDSCommand(HostName = storage2, FullListVDSCommandParameters:{hostId='a24d7172-1c3c-44ab-8ef3-9f863aeb4a87', vmIds='[56b28418-9dcf-4d46-87fe-0dfef05bb141]'}), log id: 7ff29962 2018-07-03 11:28:53,486+08 INFO [org.ovirt.engine.core. vdsbroker.vdsbroker.FullListVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-91) [609d6b57] FINISH, FullListVDSCommand, return: [{status=Down, guestFQDN=, kvmEnable=true, xml=<?xml version="1.0" encoding="UTF-8"?><domain type="kvm" xmlns:ovirt-tune="http://ovirt.org/vm/tune/1.0" xmlns:ovirt-vm="http://ovirt.org/vm/1.0"><name>avodev- controller</name><uuid>56b28418-9dcf-4d46-87fe-0dfef05bb141</uuid><memory> 8388608</memory><currentMemory>8388608</currentMemory><maxMemory slots="16">33554432</maxMemory><vcpu current="4">16</vcpu><sysinfo type="smbios"><system><entry name="manufacturer">oVirt</entry><entry name="product">OS-NAME:</entry><entry name="version">OS-VERSION:</entry><entry name="serial">HOST-SERIAL:</entry><entry name="uuid">56b28418-9dcf- 4d46-87fe-0dfef05bb141</entry></system></sysinfo><clock offset="variable" adjustment="0"><timer name="rtc" tickpolicy="catchup"></timer><timer name="pit" tickpolicy="delay"></timer><timer name="hpet" present="no"></timer></clock><features><acpi></acpi></features><cpu match="exact"><model>Nehalem</model><topology cores="1" threads="1" sockets="16"></topology><numa><cell cpus="0,1,2,3" memory="8388608"></cell></numa></cpu><cputune></cputune><devices><input type="tablet" bus="usb"></input><channel type="unix"><target type="virtio" name="ovirt-guest-agent.0"></target><source mode="bind" path="/var/lib/libvirt/qemu/channels/56b28418-9dcf-4d46- 87fe-0dfef05bb141.ovirt-guest-agent.0"></source></channel><channel type="unix"><target type="virtio" name="org.qemu.guest_agent.0"></target><source mode="bind" path="/var/lib/libvirt/qemu/channels/56b28418-9dcf-4d46- 87fe-0dfef05bb141.org.qemu.guest_agent.0"></source></channel><controller type="scsi" model="virtio-scsi" index="0"></controller><controller type="usb" model="piix3-uhci" index="0"><address bus="0x00" domain="0x0000" function="0x2" slot="0x01" type="pci"></address></controller><controller type="virtio-serial" index="0" ports="16"><address bus="0x00" domain="0x0000" function="0x0" slot="0x04" type="pci"></address></controller><graphics type="vnc" port="-1" autoport="yes" passwd="*****" passwdValidTo="1970-01-01T00:00:01" keymap="en-us"><listen type="network" network="vdsm-ovirtmgmt"></listen></graphics><controller type="ide"><address bus="0x00" domain="0x0000" function="0x1" slot="0x01" type="pci"></address></controller><rng model="virtio"><backend model="random">/dev/urandom</backend></rng><memballoon model="virtio"><stats period="5"></stats><address bus="0x00" domain="0x0000" function="0x0" slot="0x06" type="pci"></address></memballoon><video><model type="vga" vram="16384" heads="1"></model></video><interface type="bridge"><model type="virtio"></model><link state="up"></link><source bridge="ovirtmgmt"></source><address bus="0x00" domain="0x0000" function="0x0" slot="0x03" type="pci"></address><mac address="00:1a:4a:16:01:da"></mac><filterref filter="vdsm-no-mac-spoofing"></filterref><bandwidth></ bandwidth></interface><interface type="bridge"><model type="virtio"></model><link state="up"></link><source bridge="ovirtmgmt"></source><address bus="0x00" domain="0x0000" function="0x0" slot="0x07" type="pci"></address><mac address="00:1a:4a:16:01:db"></mac><filterref filter="vdsm-no-mac-spoofing"></filterref><bandwidth></bandwidth></interface><disk type="file" device="cdrom" snapshot="no"><driver name="qemu" type="raw" error_policy="report"></driver><source file="/rhev/data-center/mnt/ 192.168.100.59:_exports_iso/6ff100c0-d08d-4504-832d- 53876bf97349/images/11111111-1111-1111-1111-111111111111/ CentOS-7-x86_64-Minimal-1804.iso" startupPolicy="optional"></source><target dev="hdc" bus="ide"></target><readonly></readonly><address bus="1" controller="0" unit="0" type="drive" target="0"></address><boot order="2"></boot></disk><disk snapshot="no" type="file" device="disk"><target dev="sda" bus="scsi"></target><source file="/rhev/data-center/5b0f6949-02d6-00bf-008b- 000000000167/461517fd-986a-4b39-9b84-5fd54ef06967/images/ 8e17d226-94d0-42f8-ae29-9c795db7e522/3b4e2ee9-ba96- 4b4d-8392-94e2736e6dab"></source><driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"></driver><address bus="0" controller="0" unit="0" type="drive" target="0"></address><boot order="1"></boot><serial>8e17d226-94d0-42f8-ae29- 9c795db7e522</serial></disk><disk snapshot="no" type="file" device="disk"><target dev="sdb" bus="scsi"></target><source file="/rhev/data-center/5b0f6949-02d6-00bf-008b- 000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/ d0a00b64-3676-4783-a7af-9bb53940b03e/a88be564-84f8- 4232-9ba6-91be4625a469"></source><driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"></driver><address bus="0" controller="0" unit="1" type="drive" target="0"></address><serial> d0a00b64-3676-4783-a7af-9bb53940b03e</serial></disk><disk snapshot="no" type="file" device="disk"><target dev="sdc" bus="scsi"></target><source file="/rhev/data-center/5b0f6949-02d6-00bf-008b- 000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/ 03352bba-9d9f-4fca-ac35-f4aa7767e0b6/26a2e47d-2b10- 454a-83f4-3c3c96d27f72"></source><driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"></driver><address bus="0" controller="0" unit="2" type="drive" target="0"></address><serial> 03352bba-9d9f-4fca-ac35-f4aa7767e0b6</serial></disk></devices><pm><suspend-to-disk enabled="no"></suspend-to-disk><suspend-to-mem enabled="no"></suspend-to-mem></pm><os><type arch="x86_64" machine="pc-i440fx-2.5">hvm</type><smbios mode="sysinfo"></smbios></os>< metadata><ovirt-tune:qos></ovirt-tune:qos><ovirt-vm:vm><minGuaranteedMemoryMb type="int">4096</minGuaranteedMemoryMb><clusterVersion>4.2</ clusterVersion><ovirt-vm:custom></ovirt-vm:custom><ovirt-vm:device mac_address="00:1a:4a:16:01:db"><ovirt-vm:custom></ovirt- vm:custom></ovirt-vm:device><ovirt-vm:device mac_address="00:1a:4a:16:01: da"><ovirt-vm:custom></ovirt-vm:custom></ovirt-vm:device><ovirt-vm:device devtype="disk" name="sdb"><ovirt-vm:imageID>d0a00b64-3676-4783-a7af- 9bb53940b03e</ovirt-vm:imageID><ovirt-vm:poolID>5b0f6949-02d6-00bf-008b- 000000000167</ovirt-vm:poolID><ovirt-vm:volumeID>a88be564- 84f8-4232-9ba6-91be4625a469</ovirt-vm:volumeID><ovirt-vm: domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ovirt-vm: domainID></ovirt-vm:device><ovirt-vm:device devtype="disk" name="sda"><ovirt-vm:imageID>8e17d226-94d0-42f8-ae29- 9c795db7e522</ovirt-vm:imageID><ovirt-vm:poolID>5b0f6949-02d6-00bf-008b- 000000000167</ovirt-vm:poolID><ovirt-vm:volumeID>3b4e2ee9- ba96-4b4d-8392-94e2736e6dab</ovirt-vm:volumeID><ovirt-vm: domainID>461517fd-986a-4b39-9b84-5fd54ef06967</ovirt-vm: domainID></ovirt-vm:device><ovirt-vm:device devtype="disk" name="sdc"><ovirt-vm:imageID>03352bba-9d9f-4fca-ac35- f4aa7767e0b6</ovirt-vm:imageID><ovirt-vm:poolID>5b0f6949-02d6-00bf-008b- 000000000167</ovirt-vm:poolID><ovirt-vm:volumeID>26a2e47d- 2b10-454a-83f4-3c3c96d27f72</ovirt-vm:volumeID><ovirt-vm: domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ovirt-vm: domainID></ovirt-vm:device><launchPaused>false</ launchPaused><resumeBehavior>auto_resume</resumeBehavior></ ovirt-vm:vm></metadata></domain>, enableGuestEvents=true, vmId=56b28418-9dcf-4d46-87fe-0dfef05bb141, devices=[Ljava.lang.Object;@671dc026, guestDiskMapping={}, username=Unknown, vmType=kvm, smp=1, arch=x86_64, clientIp=, statusTime=4814199690, vmName=nNone, guestIPs=}], log id: 7ff29962 2018-07-03 11:28:53,486+08 ERROR [org.ovirt.engine.core.bll.AddUnmanagedVmsCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-91) [609d6b57] Command 'org.ovirt.engine.core.bll.AddUnmanagedVmsCommand' failed: null
在 2018-07-03 12:10:06,"Pym" <pym0914@163.com> :
Hi,
#vdsm.log 2018-07-02 19:22:02,316+0800 INFO (libvirt/events) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') CPU running: onResume (vm:5742) 2018-07-02 19:22:02,586+0800 INFO (vm/0febbdb6) [root] /usr/libexec/vdsm/hooks/after_vm_start/50_openstacknet: rc=0 err= (hooks:109) 2018-07-02 19:22:02,807+0800 INFO (vm/0febbdb6) [root] /usr/libexec/vdsm/hooks/after_vm_start/openstacknet_utils.py: rc=0 err= (hooks:109) 2018-07-02 19:22:02,824+0800 WARN (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Found unknown drive: {'alias': 'ide0-1-0', 'readonly': 'True', 'iface': 'ide', 'name': 'hdc', 'address': {'bus': '1', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'cdrom', 'path': '/rhev/data-center/mnt/192. 168.100.59:_exports_iso/6ff100c0-d08d-4504-832d- 53876bf97349/images/11111111-1111-1111-1111-111111111111/ CentOS-7-x86_64-Minimal-1804.iso', 'type': 'disk', 'bootOrder': '2'} (storage:217) 2018-07-02 19:22:02,824+0800 WARN (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Found unknown drive: {'alias': 'scsi0-0-0', 'readonly': 'False', 'iface': 'ide', 'name': 'sda', 'address': {'bus': '0', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '0'}, 'device': 'disk', 'path': '/rhev/data-center/mnt/192. 168.100.59:_exports_vmstorage/461517fd-986a-4b39-9b84- 5fd54ef06967/images/1ea736ad-164c-4ac2-a6ce-9b8e6eceefde/ b7f8140f-3a34-40fd-9fec-878df3d159a4', 'type': 'disk', 'bootOrder': '1'} (storage:217) 2018-07-02 19:22:02,825+0800 WARN (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Found unknown drive: {'alias': 'scsi0-0-1', 'readonly': 'False', 'iface': 'ide', 'name': 'sdb', 'address': {'bus': '0', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '1'}, 'device': 'disk', 'path': '/rhev/data-center/mnt/192. 168.100.241:_exports_vmstorage/c5a790c2-2885-4220- b803-35e4a40ecef6/images/183b3ace-64e1-49cf-a214- b5462f989171/36ea0af6-b681-4923-bf70-6b51717f372d', 'type': 'disk'} (storage:217) 2018-07-02 19:22:02,825+0800 WARN (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Found unknown drive: {'alias': 'scsi0-0-2', 'readonly': 'False', 'iface': 'ide', 'name': 'sdc', 'address': {'bus': '0', 'controller': '0', 'type': 'drive', 'target': '0', 'unit': '2'}, 'device': 'disk', 'path': '/rhev/data-center/mnt/192. 168.100.241:_exports_vmstorage/c5a790c2-2885-4220- b803-35e4a40ecef6/images/5866d66c-0756-4544-af6a- 95f5aed9312b/1751cd16-fd89-4441-b847-45169689c9a7', 'type': 'disk'} (storage:217) 2018-07-02 19:22:02,840+0800 INFO (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Starting connection (guestagent:244) 2018-07-02 19:22:02,841+0800 ERROR (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') Failed to connect to guest agent channel (vm:2415) Traceback (most recent call last): File "/usr/lib/python2.7/site-packages/vdsm/virt/vm.py", line 2413, in _vmDependentInit self.guestAgent.start() File "/usr/lib/python2.7/site-packages/vdsm/virt/guestagent.py", line 245, in start self._prepare_socket() File "/usr/lib/python2.7/site-packages/vdsm/virt/guestagent.py", line 287, in _prepare_socket supervdsm.getProxy().prepareVmChannel(self._socketName) File "/usr/lib/python2.7/site-packages/vdsm/supervdsm.py", line 53, in __call__ return callMethod() File "/usr/lib/python2.7/site-packages/vdsm/supervdsm.py", line 51, in <lambda> **kwargs) File "<string>", line 2, in prepareVmChannel File "/usr/lib64/python2.7/multiprocessing/managers.py", line 773, in _callmethod raise convert_to_error(kind, result) OSError: [Errno 2] No such file or directory: '/var/lib/libvirt/qemu/ channels/0febbdb6-9b90-4052-8169-4f6792d99d1e.com.redhat.rhevm.vdsm' 2018-07-02 19:22:02,843+0800 INFO (vm/0febbdb6) [virt.vm] (vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e') CPU running: domain initialization (vm:5742) 2018-07-02 19:22:02,910+0800 INFO (jsonrpc/6) [api.host] START dumpxmls(vmList=[u'0febbdb6-9b90-4052-8169-4f6792d99d1e']) from=::ffff:192.168.100.59,60612 (api:46) 2018-07-02 19:22:09,292+0800 ERROR (periodic/39) [virt.vmstats] VM metrics collection failed (vmstats:260) Traceback (most recent call last): File "/usr/lib/python2.7/site-packages/vdsm/virt/vmstats.py", line 217, in send_metrics diskinfo['readOps'] KeyError: 'readOps' 2018-07-02 19:22:09,813+0800 INFO (jsonrpc/2) [api.host] START getStats() from=::ffff:192.168.100.59,60612 (api:46) 2018-07-02 19:22:09,816+0800 INFO (jsonrpc/2) [vdsm.api] START repoStats(domains=()) from=::ffff:192.168.100.59,60612, task_id=36236da1-3586-4324-a842-71aafb37d124 (api:46) 2018-07-02 19:22:09,816+0800 INFO (jsonrpc/2) [vdsm.api] FINISH repoStats return={u'6ff100c0-d08d-4504-832d-53876bf97349': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000776933', 'lastCheck': '6.6', 'valid': True}, u'5a50d04c-4d39-4249-8fc6-24df9bd096b0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000358834', 'lastCheck': '4.1', 'valid': True}, u'c5a790c2-2885-4220-b803-35e4a40ecef6': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000328361', 'lastCheck': '3.3', 'valid': True}, u'461517fd-986a-4b39-9b84-5fd54ef06967': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000412804', 'lastCheck': '5.7', 'valid': True}} from=::ffff:192.168.100.59,60612, task_id=36236da1-3586-4324-a842-71aafb37d124 (api:52) 2018-07-02 19:22:09,831+0800 INFO (periodic/39) [vdsm.api] START repoStats(domains=()) from=internal, task_id=b4dcd456-df92-4270-92be-d73d7e7127f9 (api:46) 2018-07-02 19:22:09,832+0800 INFO (periodic/39) [vdsm.api] FINISH repoStats return={u'6ff100c0-d08d-4504-832d-53876bf97349': {'code': 0, 'actual': True, 'version': 0, 'acquired': True, 'delay': '0.000776933', 'lastCheck': '6.6', 'valid': True}, u'5a50d04c-4d39-4249-8fc6-24df9bd096b0': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000358834', 'lastCheck': '4.1', 'valid': True}, u'c5a790c2-2885-4220-b803-35e4a40ecef6': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000328361', 'lastCheck': '3.3', 'valid': True}, u'461517fd-986a-4b39-9b84-5fd54ef06967': {'code': 0, 'actual': True, 'version': 4, 'acquired': True, 'delay': '0.000412804', 'lastCheck': '5.7', 'valid': True}} from=internal, task_id=b4dcd456-df92-4270-92be-d73d7e7127f9 (api:52)
#engine.log 2018-07-02 19:22:07,907+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (default task-41) [2f069134-d730-4ca5-93b7-3925a06234da] Lock Acquired to object 'EngineLock:{exclusiveLocks='[0febbdb6-9b90-4052-8169-4f6792d99d1e=VM]', sharedLocks=''}' 2018-07-02 19:22:07,935+08 INFO [org.ovirt.engine.core.vdsbroker. IsVmDuringInitiatingVDSCommand] (default task-41) [2f069134-d730-4ca5-93b7-3925a06234da] START, IsVmDuringInitiatingVDSCommand( IsVmDuringInitiatingVDSCommand Parameters:{vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e'}), log id: 703a024f 2018-07-02 19:22:07,936+08 INFO [org.ovirt.engine.core.vdsbroker. IsVmDuringInitiatingVDSCommand] (default task-41) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 703a024f 2018-07-02 19:22:07,998+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Running command: RunVmCommand internal: false. Entities affected : ID: 0febbdb6-9b90-4052-8169-4f6792d99d1e Type: VMAction group RUN_VM with role type USER 2018-07-02 19:22:08,061+08 INFO [org.ovirt.engine.core.bll. scheduling.policyunits.PreferredHostsWeightPolicyUnit] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Penalizing host 'storage3' because it is not preferred. 2018-07-02 19:22:08,061+08 INFO [org.ovirt.engine.core.bll. scheduling.policyunits.PreferredHostsWeightPolicyUnit] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Penalizing host 'storage2' because it is not preferred. 2018-07-02 19:22:08,073+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Running VM with attached cd 'CentOS-7-x86_64-Minimal-1804.iso' 2018-07-02 19:22:08,075+08 INFO [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] START, UpdateVmDynamicDataVDSCommand( UpdateVmDynamicDataVDSCommandParameters:{hostId='null', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e', vmDynamic='org.ovirt.engine. core.common.businessentities.VmDynamic@540c1d26'}), log id: 11987cd 2018-07-02 19:22:08,076+08 INFO [org.ovirt.engine.core.vdsbroker.UpdateVmDynamicDataVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, UpdateVmDynamicDataVDSCommand, log id: 11987cd 2018-07-02 19:22:08,079+08 INFO [org.ovirt.engine.core. vdsbroker.vdsbroker.IsoPrefixVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] START, IsoPrefixVDSCommand(HostName = tchypnode1.ecr.com, VdsAndPoolIDVDSParametersBase:{hostId='7902d7f8-348e-426e-a3c3-399d08b0eb1e', storagePoolId='5b0f6949-02d6-00bf-008b-000000000167'}), log id: 3d66a9a0 2018-07-02 19:22:08,079+08 INFO [org.ovirt.engine.core. vdsbroker.vdsbroker.IsoPrefixVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, IsoPrefixVDSCommand, return: /rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0- d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111, log id: 3d66a9a0 2018-07-02 19:22:08,079+08 INFO [org.ovirt.engine.core. vdsbroker.vdsbroker.IsoPrefixVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] START, IsoPrefixVDSCommand(HostName = tchypnode1.ecr.com, VdsAndPoolIDVDSParametersBase:{hostId='7902d7f8-348e-426e-a3c3-399d08b0eb1e', storagePoolId='5b0f6949-02d6-00bf-008b-000000000167'}), log id: 3d66a9a0 2018-07-02 19:22:08,079+08 INFO [org.ovirt.engine.core. vdsbroker.vdsbroker.IsoPrefixVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, IsoPrefixVDSCommand, return: /rhev/data-center/mnt/192.168.100.59:_exports_iso/6ff100c0- d08d-4504-832d-53876bf97349/images/11111111-1111-1111-1111-111111111111, log id: 3d66a9a0 2018-07-02 19:22:08,087+08 INFO [org.ovirt.engine.core.vdsbroker.CreateVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] START, CreateVDSCommand( CreateVDSCommandParameters:{ hostId='7902d7f8-348e-426e-a3c3-399d08b0eb1e', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e', vm='VM [badvm-avodev-controller]'}), log id: 54fa1fca 2018-07-02 19:22:08,090+08 INFO [org.ovirt.engine.core. vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] START, CreateBrokerVDSCommand(HostName = tchypnode1.ecr.com, CreateVDSCommandParameters:{ hostId='7902d7f8-348e-426e-a3c3-399d08b0eb1e', vmId='0febbdb6-9b90-4052-8169-4f6792d99d1e', vm='VM [badvm-avodev-controller]'}), log id: 54926511 2018-07-02 19:22:08,096+08 INFO [org.ovirt.engine.core. vdsbroker.builder.vminfo.VmInfoBuilderImpl] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Bootable disk '1ea736ad-164c-4ac2-a6ce-9b8e6eceefde' set to index '0' 2018-07-02 19:22:08,130+08 INFO [org.ovirt.engine.core. vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] VM <?xml version="1.0" encoding="UTF-8"?><domain type="kvm" xmlns:ovirt-tune="http:// ovirt.org/vm/tune/1.0" xmlns:ovirt-vm="http://ovirt.org/vm/1.0"> <name>badvm-avodev-controller</name> <uuid>0febbdb6-9b90-4052-8169-4f6792d99d1e</uuid> <memory>8388608</memory> <currentMemory>8388608</currentMemory> <maxMemory slots="16">33554432</maxMemory> <vcpu current="4">16</vcpu> <sysinfo type="smbios"> <system> <entry name="manufacturer">oVirt</entry> <entry name="product">OS-NAME:</entry> <entry name="version">OS-VERSION:</entry> <entry name="serial">HOST-SERIAL:</entry> <entry name="uuid">0febbdb6-9b90-4052-8169-4f6792d99d1e</entry> </system> </sysinfo> <clock offset="variable" adjustment="0"> <timer name="rtc" tickpolicy="catchup"/> <timer name="pit" tickpolicy="delay"/> <timer name="hpet" present="no"/> </clock> <features> <acpi/> </features> <cpu match="exact"> <model>Nehalem</model> <topology cores="1" threads="1" sockets="16"/> <numa> <cell cpus="0,1,2,3" memory="8388608"/> <numa> <cell cpus="0,1,2,3" memory="8388608"/> </numa> </cpu> <cputune/> <devices> <input type="tablet" bus="usb"/> <channel type="unix"> <target type="virtio" name="ovirt-guest-agent.0"/> <source mode="bind" path="/var/lib/libvirt/qemu/ channels/0febbdb6-9b90-4052-8169-4f6792d99d1e.ovirt-guest-agent.0"/> </channel> <channel type="unix"> <target type="virtio" name="org.qemu.guest_agent.0"/> <source mode="bind" path="/var/lib/libvirt/qemu/ channels/0febbdb6-9b90-4052-8169-4f6792d99d1e.org.qemu.guest_agent.0"/> </channel> <controller type="usb" model="piix3-uhci" index="0"> <address bus="0x00" domain="0x0000" function="0x2" slot="0x01" type="pci"/> </controller> <memballoon model="virtio"> <stats period="5"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x06" type="pci"/> </memballoon> <controller type="ide"> <address bus="0x00" domain="0x0000" function="0x1" slot="0x01" type="pci"/> </controller> <controller type="scsi" model="virtio-scsi" index="0"/> <controller type="scsi" index="0"> <address bus="0x00" domain="0x0000" function="0x0" slot="0x05" type="pci"/> </controller> <graphics type="vnc" port="-1" autoport="yes" passwd="*****" passwdValidTo="1970-01-01T00:00:01" keymap="en-us"> <listen type="network" network="vdsm-ovirtmgmt"/> <graphics type="vnc" port="-1" autoport="yes" passwd="*****" passwdValidTo="1970-01-01T00:00:01" keymap="en-us"> <listen type="network" network="vdsm-ovirtmgmt"/> </graphics> <video> <model type="vga" vram="16384" heads="1"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x02" type="pci"/> </video> <rng model="virtio"> <backend model="random">/dev/urandom</backend> </rng> <controller type="virtio-serial" index="0" ports="16"> <address bus="0x00" domain="0x0000" function="0x0" slot="0x04" type="pci"/> </controller> <interface type="bridge"> <model type="virtio"/> <link state="up"/> <source bridge="ovirtmgmt"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x03" type="pci"/> <mac address="00:1a:4a:16:01:f5"/> <filterref filter="vdsm-no-mac-spoofing"/> <bandwidth/> </interface> <interface type="bridge"> <model type="virtio"/> <link state="up"/> <source bridge="ovirtmgmt"/> <address bus="0x00" domain="0x0000" function="0x0" slot="0x07" type="pci"/> <mac address="00:1a:4a:16:01:f6"/> <filterref filter="vdsm-no-mac-spoofing"/> <bandwidth/> </interface> <bandwidth/> </interface> <disk type="file" device="cdrom" snapshot="no"> <driver name="qemu" type="raw" error_policy="report"/> <source file="/rhev/data-center/mnt/192.168.100.59:_exports_iso/ 6ff100c0-d08d-4504-832d-53876bf97349/images/11111111- 1111-1111-1111-111111111111/CentOS-7-x86_64-Minimal-1804.iso" startupPolicy="optional"/> <target dev="hdc" bus="ide"/> <readonly/> <address bus="1" controller="0" unit="0" type="drive" target="0"/> <boot order="2"/> </disk> <disk snapshot="no" type="file" device="disk"> <target dev="sda" bus="scsi"/> <source file="/rhev/data-center/5b0f6949-02d6-00bf-008b- 000000000167/461517fd-986a-4b39-9b84-5fd54ef06967/images/ 1ea736ad-164c-4ac2-a6ce-9b8e6eceefde/b7f8140f-3a34- 40fd-9fec-878df3d159a4"/> <driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"/> <address bus="0" controller="0" unit="0" type="drive" target="0"/> <boot order="1"/> <serial>1ea736ad-164c-4ac2-a6ce-9b8e6eceefde</serial> </disk> <disk snapshot="no" type="file" device="disk"> <target dev="sdb" bus="scsi"/> <source file="/rhev/data-center/5b0f6949-02d6-00bf-008b- 000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/ 183b3ace-64e1-49cf-a214-b5462f989171/36ea0af6-b681- 4923-bf70-6b51717f372d"/> <driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"/> <address bus="0" controller="0" unit="1" type="drive" target="0"/> <serial>183b3ace-64e1-49cf-a214-b5462f989171</serial> </disk> <disk snapshot="no" type="file" device="disk"> <target dev="sdc" bus="scsi"/> <disk snapshot="no" type="file" device="disk"> <target dev="sdc" bus="scsi"/> <source file="/rhev/data-center/5b0f6949-02d6-00bf-008b- 000000000167/c5a790c2-2885-4220-b803-35e4a40ecef6/images/ 5866d66c-0756-4544-af6a-95f5aed9312b/1751cd16-fd89- 4441-b847-45169689c9a7"/> <driver name="qemu" io="threads" type="qcow2" error_policy="stop" cache="none"/> <address bus="0" controller="0" unit="2" type="drive" target="0"/> <serial>5866d66c-0756-4544-af6a-95f5aed9312b</serial> </disk> </devices> <pm> <suspend-to-disk enabled="no"/> <suspend-to-mem enabled="no"/> </pm> <os> <type arch="x86_64" machine="pc-i440fx-2.5">hvm</type> <smbios mode="sysinfo"/> </os> <metadata> <ovirt-tune:qos/> <ovirt-vm:vm> <minGuaranteedMemoryMb type="int">4096</minGuaranteedMemoryMb> <clusterVersion>4.2</clusterVersion> <ovirt-vm:custom/> <ovirt-vm:device mac_address="00:1a:4a:16:01:f6"> <ovirt-vm:custom/> </ovirt-vm:device> <ovirt-vm:device mac_address="00:1a:4a:16:01:f5"> <ovirt-vm:custom/> </ovirt-vm:device> <ovirt-vm:device devtype="disk" name="sdb"> <ovirt-vm:imageID>183b3ace-64e1-49cf-a214-b5462f989171</ ovirt-vm:imageID> <ovirt-vm:device devtype="disk" name="sdb"> <ovirt-vm:imageID>183b3ace-64e1-49cf-a214-b5462f989171</ ovirt-vm:imageID> <ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ ovirt-vm:poolID> <ovirt-vm:volumeID>36ea0af6-b681-4923-bf70-6b51717f372d</ ovirt-vm:volumeID> <ovirt-vm:domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ ovirt-vm:domainID> </ovirt-vm:device> <ovirt-vm:device devtype="disk" name="sda"> <ovirt-vm:imageID>1ea736ad-164c-4ac2-a6ce-9b8e6eceefde</ ovirt-vm:imageID> <ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ ovirt-vm:poolID> <ovirt-vm:volumeID>b7f8140f-3a34-40fd-9fec-878df3d159a4</ ovirt-vm:volumeID> <ovirt-vm:domainID>461517fd-986a-4b39-9b84-5fd54ef06967</ ovirt-vm:domainID> </ovirt-vm:device> <ovirt-vm:device devtype="disk" name="sdc"> <ovirt-vm:imageID>5866d66c-0756-4544-af6a-95f5aed9312b</ ovirt-vm:imageID> <ovirt-vm:poolID>5b0f6949-02d6-00bf-008b-000000000167</ ovirt-vm:poolID> <ovirt-vm:volumeID>1751cd16-fd89-4441-b847-45169689c9a7</ ovirt-vm:volumeID> <ovirt-vm:domainID>c5a790c2-2885-4220-b803-35e4a40ecef6</ ovirt-vm:domainID> </ovirt-vm:device> <launchPaused>false</launchPaused> <resumeBehavior>auto_resume</resumeBehavior> </ovirt-vm:vm> </metadata> </domain>
2018-07-02 19:22:08,143+08 INFO [org.ovirt.engine.core. vdsbroker.vdsbroker.CreateBrokerVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, CreateBrokerVDSCommand, log id: 54926511 2018-07-02 19:22:08,144+08 INFO [org.ovirt.engine.core.vdsbroker.CreateVDSCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] FINISH, CreateVDSCommand, return: WaitForLaunch, log id: 54fa1fca 2018-07-02 19:22:08,145+08 INFO [org.ovirt.engine.core.bll.RunVmCommand] (EE-ManagedThreadFactory-engine-Thread-23066) [2f069134-d730-4ca5-93b7-3925a06234da] Lock freed to object 'EngineLock:{exclusiveLocks='[ 0febbdb6-9b90-4052-8169-4f6792d99d1e=VM]', sharedLocks=''}'
At 2018-07-02 14:19:23, "Shani Leviim" <sleviim@redhat.com> wrote:
Hi, Can you please attach full vdsm and engine logs?
*Regards,*
*Shani Leviim*
On Fri, Jun 29, 2018 at 1:41 PM, Pym <pym0914@163.com> wrote:
Hi:
I use environment is ovirt4.2.0, first, I created a template, the template has already installed in the operating system, and then I use this template to create a virtual machine, sometimes can't find the disk happens, particular case is shown in figure:
At this point, the virtual machine attaches disk again and cannot be recognized. However, the disk above is identifiable to other virtual machines, and the contents of disk still exist
I would like to ask what causes this problem.
_______________________________________________ Users mailing list -- users@ovirt.org To unsubscribe send an email to users-leave@ovirt.org Privacy Statement: https://www.ovirt.org/site/privacy-policy/ oVirt Code of Conduct: https://www.ovirt.org/communit y/about/community-guidelines/ List Archives: https://lists.ovirt.org/archiv es/list/users@ovirt.org/message/XHW44X37UKSCCL5Q675AC3CAQN4Z44MF/
【网易自营|30天无忧退货】爱上书写:施华洛世奇制造商星空原色水晶笔,限时仅29元>> <http://you.163.com/item/detail?id=1092001&from=web_gg_mail_jiaobiao_7>
_______________________________________________ Users mailing list -- users@ovirt.org To unsubscribe send an email to users-leave@ovirt.org Privacy Statement: https://www.ovirt.org/site/privacy-policy/ oVirt Code of Conduct: https://www.ovirt.org/community/about/community- guidelines/ List Archives: https://lists.ovirt.org/archives/list/users@ovirt.org/ message/V7SYMRPDFC6XNEY7DCZQZ4NF65BY3QAJ/
participants (3)
-
Maor Lipchuk
-
Pym
-
Shani Leviim