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.log2018-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 _vmDependentInitself.guestAgent.start()File "/usr/lib/python2.7/site-packages/vdsm/virt/guestagent.py", line 245, in startself._prepare_socket()File "/usr/lib/python2.7/site-packages/vdsm/virt/guestagent.py", line 287, in _prepare_socketsupervdsm.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 prepareVmChannelFile "/usr/lib64/python2.7/multiprocessing/managers.py", line 773, in _callmethodraise 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_metricsdiskinfo['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.log2018-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: 703a024f2018-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: 703a024f2018-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 USER2018-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: 11987cd2018-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: 11987cd2018-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: 3d66a9a02018-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: 3d66a9a02018-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: 3d66a9a02018-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: 3d66a9a02018-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: 54fa1fca2018-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: 549265112018-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: 549265112018-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: 54fa1fca2018-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 LeviimOn 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 existI 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/ XHW44X37UKSCCL5Q675AC3CAQN4Z44 MF/