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