[ovirt-users] Re: When creating a virtual machine with a template, there are times when disk cannot be recognized

Wednesday, 4 July 2018

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(a)163.com&gt; 写道：

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-...
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-...
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(a)163.com&gt; ：

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(a)redhat.com&gt; 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(a)163.com&gt; 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(a)ovirt.org
To unsubscribe send an email to users-leave(a)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/XHW44X37UKS...

【网易自营|30天无忧退货】爱上书写：施华洛世奇制造商星空原色水晶笔，限时仅29元>>    

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

[ovirt-users] Re: When creating a virtual machine with a template, there are times when disk cannot be recognized