6:10 p.m.
Hi, All. We have a new oVirt 4.1.1 cluster up with the OVS switch type. Everything seems
to be working great, except for live migration.
I believe the red flag in vdsm.log on the source is:
Cannot get interface MTU on 'vdsmbr_QwORbsw2': No such device (migration:287)
Which results from vdsm assigning an arbitrary bridge name to each ovs bridge.
Please see below for more details on the bridges and excerpts from the logs. Any help
would be greatly appreciated.
Many thanks,
Devin
SOURCE OVS BRIDGES:
# ovs-vsctl show
6d96d9a5-e30d-455b-90c7-9e9632574695
Bridge "vdsmbr_QwORbsw2"
Port "vdsmbr_QwORbsw2"
Interface "vdsmbr_QwORbsw2"
type: internal
Port "vnet0"
Interface "vnet0"
Port classepublic
Interface classepublic
type: internal
Port "ens1f0"
Interface "ens1f0"
Bridge "vdsmbr_9P7ZYKWn"
Port ovirtmgmt
Interface ovirtmgmt
type: internal
Port "ens1f1"
Interface "ens1f1"
Port "vdsmbr_9P7ZYKWn"
Interface "vdsmbr_9P7ZYKWn"
type: internal
ovs_version: "2.7.0"
DESTINATION OVS BRIDGES:
# ovs-vsctl show
f66d765d-712a-4c81-b18e-da1acc9cfdde
Bridge "vdsmbr_vdpp0dOd"
Port "vdsmbr_vdpp0dOd"
Interface "vdsmbr_vdpp0dOd"
type: internal
Port "ens1f0"
Interface "ens1f0"
Port classepublic
Interface classepublic
type: internal
Bridge "vdsmbr_3sEwEKd1"
Port "vdsmbr_3sEwEKd1"
Interface "vdsmbr_3sEwEKd1"
type: internal
Port "ens1f1"
Interface "ens1f1"
Port ovirtmgmt
Interface ovirtmgmt
type: internal
ovs_version: "2.7.0"
SOURCE VDSM LOG:
...
2017-03-27 10:57:02,567-0400 INFO (jsonrpc/1) [vdsm.api] START migrate
args=(<virt.vm.Vm object at 0x3410810>, {u'incomingLimit': 2,
u'src': u'192.168.55.84', u'dstqemu': u'192.168.55.81',
u'autoConverge': u'false', u'tunneled': u'false',
u'enableGuestEvents': False, u'dst':
u'lnxvirt01-p55.classe.cornell.edu:54321', u'vmId':
u'cf9c5dbf-3924-47c6-b323-22ac90a1f682', u'abortOnError': u'true',
u'outgoingLimit': 2, u'compressed': u'false',
u'maxBandwidth': 5000, u'method': u'online', 'mode':
'remote'}) kwargs={} (api:37)
2017-03-27 10:57:02,570-0400 INFO (jsonrpc/1) [vdsm.api] FINISH migrate
return={'status': {'message': 'Migration in progress',
'code': 0}, 'progress': 0} (api:43)
2017-03-27 10:57:02,570-0400 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call VM.migrate
succeeded in 0.01 seconds (__init__:515)
2017-03-27 10:57:03,028-0400 INFO (migsrc/cf9c5dbf) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Creation of destination VM took: 0
seconds (migration:455)
2017-03-27 10:57:03,028-0400 INFO (migsrc/cf9c5dbf) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') starting migration to
qemu+tls://lnxvirt01-p55.classe.cornell.edu/system with miguri tcp://192.168.55.81
(migration:480)
2017-03-27 10:57:03,224-0400 ERROR (migsrc/cf9c5dbf) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Cannot get interface MTU on
'vdsmbr_QwORbsw2': No such device (migration:287)
2017-03-27 10:57:03,322-0400 ERROR (migsrc/cf9c5dbf) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Failed to migrate (migration:429)
Traceback (most recent call last):
File "/usr/lib/python2.7/site-packages/vdsm/virt/migration.py", line 411, in
run
self._startUnderlyingMigration(time.time())
File "/usr/lib/python2.7/site-packages/vdsm/virt/migration.py", line 489, in
_startUnderlyingMigration
self._perform_with_downtime_thread(duri, muri)
File "/usr/lib/python2.7/site-packages/vdsm/virt/migration.py", line 555, in
_perform_with_downtime_thread
self._perform_migration(duri, muri)
File "/usr/lib/python2.7/site-packages/vdsm/virt/migration.py", line 528, in
_perform_migration
self._vm._dom.migrateToURI3(duri, params, flags)
File "/usr/lib/python2.7/site-packages/vdsm/virt/virdomain.py", line 69, in f
ret = attr(*args, **kwargs)
File "/usr/lib/python2.7/site-packages/vdsm/libvirtconnection.py", line 123,
in wrapper
ret = f(*args, **kwargs)
File "/usr/lib/python2.7/site-packages/vdsm/utils.py", line 941, in wrapper
return func(inst, *args, **kwargs)
File "/usr/lib64/python2.7/site-packages/libvirt.py", line 1939, in
migrateToURI3
if ret == -1: raise libvirtError ('virDomainMigrateToURI3() failed',
dom=self)
libvirtError: Cannot get interface MTU on 'vdsmbr_QwORbsw2': No such device
2017-03-27 10:57:03,435-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::1:33716 (protocoldetector:72)
2017-03-27 10:57:03,452-0400 INFO (Reactor thread) [ProtocolDetector.Detector] Detected
protocol stomp from ::1:33716 (protocoldetector:127)
2017-03-27 10:57:03,452-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
2017-03-27 10:57:03,456-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
2017-03-27 10:57:03,589-0400 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.01 seconds (__init__:515)
2017-03-27 10:57:03,592-0400 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call
VM.getStats failed (error 1) in 0.00 seconds (__init__:515)
2017-03-27 10:57:04,494-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::1:33718 (protocoldetector:72)
2017-03-27 10:57:04,500-0400 INFO (Reactor thread) [ProtocolDetector.Detector] Detected
protocol stomp from ::1:33718 (protocoldetector:127)
2017-03-27 10:57:04,500-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
2017-03-27 10:57:04,501-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
2017-03-27 10:57:04,559-0400 INFO (periodic/2) [dispatcher] Run and protect:
repoStats(options=None) (logUtils:51)
2017-03-27 10:57:04,559-0400 INFO (periodic/2) [dispatcher] Run and protect: repoStats,
Return response: {u'016ceee8-9117-4e8a-b611-f58f6763a098': {'code': 0,
'actual': True, 'version': 4, 'acquired': True, 'delay':
'0.000262909', 'lastCheck': '5.3', 'valid': True},
u'2438f819-e7f5-4bb1-ad0d-5349fa371e6e': {'code': 0, 'actual':
True, 'version': 0, 'acquired': True, 'delay':
'0.000342019', 'lastCheck': '5.3', 'valid': True},
u'48d4f45d-0bdd-4f4a-90b6-35efe2da935a': {'code': 0, 'actual':
True, 'version': 4, 'acquired': True, 'delay':
'0.000239611', 'lastCheck': '0.6', 'valid': True}}
(logUtils:54)
2017-03-27 10:57:04,669-0400 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.01 seconds (__init__:515)
2017-03-27 10:57:04,684-0400 INFO (jsonrpc/0) [dispatcher] Run and protect:
repoStats(options=None) (logUtils:51)
2017-03-27 10:57:04,684-0400 INFO (jsonrpc/0) [dispatcher] Run and protect: repoStats,
Return response: {u'016ceee8-9117-4e8a-b611-f58f6763a098': {'code': 0,
'actual': True, 'version': 4, 'acquired': True, 'delay':
'0.000262909', 'lastCheck': '5.4', 'valid': True},
u'2438f819-e7f5-4bb1-ad0d-5349fa371e6e': {'code': 0, 'actual':
True, 'version': 0, 'acquired': True, 'delay':
'0.000342019', 'lastCheck': '5.4', 'valid': True},
u'48d4f45d-0bdd-4f4a-90b6-35efe2da935a': {'code': 0, 'actual':
True, 'version': 4, 'acquired': True, 'delay':
'0.000239611', 'lastCheck': '0.7', 'valid': True}}
(logUtils:54)
2017-03-27 10:57:04,770-0400 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call
Host.getStats succeeded in 0.09 seconds (__init__:515)
...
DESTINATION VDSM LOG:
...
2017-03-27 10:57:01,515-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
2017-03-27 10:57:01,652-0400 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.00 seconds (__init__:515)
2017-03-27 10:57:02,572-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::ffff:192.168.55.84:44582 (protocoldetector:72)
2017-03-27 10:57:02,577-0400 INFO (Reactor thread) [ProtocolDetector.Detector] Detected
protocol stomp from ::ffff:192.168.55.84:44582 (protocoldetector:127)
2017-03-27 10:57:02,578-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
2017-03-27 10:57:02,579-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
2017-03-27 10:57:02,748-0400 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.ping
succeeded in 0.00 seconds (__init__:515)
2017-03-27 10:57:02,774-0400 INFO (jsonrpc/4) [vdsm.api] START __init__
args=(<virt.vm.Vm object at 0x4132e50>, <clientIF.clientIF object at
0x7f453c01e650>, {u'guestFQDN': u'lnx16.classe.cornell.edu',
u'acpiEnable': u'true', u'emulatedMachine':
u'pc-i440fx-rhel7.3.0', u'afterMigrationStatus': u'',
u'enableGuestEvents': False, u'vmId':
u'cf9c5dbf-3924-47c6-b323-22ac90a1f682', u'elapsedTimeOffset':
1321.2906959056854, u'guestDiskMapping':
{u'0QEMU_QEMU_HARDDISK_21d18729-279f-435b-9': {u'name':
u'/dev/sda'}, u'QEMU_DVD-ROM_QM00003': {u'name':
u'/dev/sr0'}}, u'transparentHugePages': u'true',
u'timeOffset': u'0', u'cpuType': u'Haswell-noTSX',
u'custom': {u'device_9d15a0c8-a122-43b9-89c2-3ad530ebc985':
u"VmDevice:{id='VmDeviceId:{deviceId='9d15a0c8-a122-43b9-89c2-3ad530ebc985',
vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682'}', device='ide',
type='CONTROLLER', bootOrder='0', specParams='[]',
address='{slot=0x01, bus=0x00, domain=0x0000, type=pci, function=0x1}',
managed='false', plugged='true', readOnly='false',
deviceAlias='ide', customProperties='[]', snapshotId='null',
logicalName='null', hostDevice='null'}",
u'device_9d15a0c8-a122-43b9-89c2-3ad530ebc985device_0bbb6377-22db-4d04-a501-64de81bab622device_758565a5-4378-4412-8ec1-2df7bb231c14device_7ead63f7-b119-434d-a2f7-081fd06229e4':
u"VmDevice:{id='VmDeviceId:{deviceId='7ead63f7-b119-434d-a2f7-081fd06229e4',
vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682'}', device='spicevmc',
type='CHANNEL', bootOrder='0', specParams='[]',
address='{bus=0, controller=0, type=virtio-serial, port=3}',
managed='false', plugged='true', readOnly='false',
deviceAlias='channel2', customProperties='[]', snapshotId='null',
logicalName='null', hostDevice='null'}",
u'device_9d15a0c8-a122-43b9-89c2-3ad530ebc985device_0bbb6377-22db-4d04-a501-64de81bab622':
u"VmDevice:{id='VmDeviceId:{deviceId='0bbb6377-22db-4d04-a501-64de81bab622',
vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682'}', device='unix',
type='CHANNEL', bootOrder='0', specParams='[]',
address='{bus=0, controller=0, type=virtio-serial, port=2}',
managed='false', plugged='true', readOnly='false',
deviceAlias='channel1', customProperties='[]', snapshotId='null',
logicalName='null', hostDevice='null'}",
u'device_9d15a0c8-a122-43b9-89c2-3ad530ebc985device_0bbb6377-22db-4d04-a501-64de81bab622device_758565a5-4378-4412-8ec1-2df7bb231c14':
u"VmDevice:{id='VmDeviceId:{deviceId='758565a5-4378-4412-8ec1-2df7bb231c14',
vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682'}', device='unix',
type='CHANNEL', bootOrder='0', specParams='[]',
address='{bus=0, controller=0, type=virtio-serial, port=1}',
managed='false', plugged='true', readOnly='false',
deviceAlias='channel0', customProperties='[]', snapshotId='null',
logicalName='null', hostDevice='null'}"}, u'pauseCode':
u'NOERR', u'migrationDest': u'libvirt', u'guestNumaNodes':
[{u'nodeIndex': 0, u'cpus': u'0', u'memory':
u'1024'}], u'display': u'qxl', u'smp': u'1',
u'vmType': u'kvm', u'_srcDomXML': u'<domain
type=\'kvm\' id=\'4\'>\n <name>lnx16</name>\n
<uuid>cf9c5dbf-3924-47c6-b323-22ac90a1f682</uuid>\n <metadata
xmlns:ovirt="http://ovirt.org/vm/tune/1.0">\n <ovirt:qos/>\n
</metadata>\n <maxMemory slots=\'16\'
unit=\'KiB\'>4194304</maxMemory>\n <memory
unit=\'KiB\'>1048576</memory>\n <currentMemory
unit=\'KiB\'>1048576</currentMemory>\n <vcpu
placement=\'static\' current=\'1\'>16</vcpu>\n <cputune>\n
<shares>1020</shares>\n </cputune>\n <resource>\n
<partition>/machine</partition>\n </resource>\n <sysinfo
type=\'smbios\'>\n <system>\n <entry
name=\'manufacturer\'>oVirt</entry>\n <entry
name=\'product\'>oVirt Node</entry>\n <entry
name=\'version\'>7.3-4.sl7</entry>\n <entry
name=\'serial\'>38C15485-D270-11E6-8752-0090FAEDD7C8</entry>\n
<entry name=\'uuid\'>cf9c5dbf-3924-47c6-b323-22ac90a1f682</entry>\n
</system>\n </sysinfo>\n <os>\n <type arch=\'x86_64\'
machine=\'pc-i440fx-rhel7.3.0\'>hvm</type>\n <smbios
mode=\'sysinfo\'/>\n </os>\n <features>\n <acpi/>\n
</features>\n <cpu mode=\'custom\' match=\'exact\'>\n
<model fallback=\'allow\'>Haswell-noTSX</model>\n <topology
sockets=\'16\' cores=\'1\' threads=\'1\'/>\n <numa>\n
<cell id=\'0\' cpus=\'0\' memory=\'1048576\'
unit=\'KiB\'/>\n </numa>\n </cpu>\n <clock
offset=\'variable\' adjustment=\'0\' basis=\'utc\'>\n
<timer name=\'rtc\' tickpolicy=\'catchup\'/>\n <timer
name=\'pit\' tickpolicy=\'delay\'/>\n <timer
name=\'hpet\' present=\'no\'/>\n </clock>\n
<on_poweroff>destroy</on_poweroff>\n
<on_reboot>restart</on_reboot>\n <on_crash>destroy</on_crash>\n
<devices>\n <emulator>/usr/libexec/qemu-kvm</emulator>\n <disk
type=\'file\' device=\'cdrom\'>\n <driver name=\'qemu\'
type=\'raw\'/>\n <source startupPolicy=\'optional\'/>\n
<backingStore/>\n <target dev=\'hdc\' bus=\'ide\'/>\n
<readonly/>\n <alias name=\'ide0-1-0\'/>\n <address
type=\'drive\' controller=\'0\' bus=\'1\' target=\'0\'
unit=\'0\'/>\n </disk>\n <disk type=\'block\'
device=\'disk\' snapshot=\'no\'>\n <driver
name=\'qemu\' type=\'raw\' cache=\'none\'
error_policy=\'stop\' io=\'native\'/>\n <source
dev=\'/rhev/data-center/00000001-0001-0001-0001-000000000311/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49b2-af40-6178023b3794\'/>\n
<backingStore/>\n <target dev=\'sda\'
bus=\'scsi\'/>\n
<serial>21d18729-279f-435b-90ab-a2995f041bc6</serial>\n <boot
order=\'1\'/>\n <alias name=\'scsi0-0-0-0\'/>\n
<address type=\'drive\' controller=\'0\' bus=\'0\'
target=\'0\' unit=\'0\'/>\n </disk>\n <controller
type=\'usb\' index=\'0\' model=\'piix3-uhci\'>\n <alias
name=\'usb\'/>\n <address type=\'pci\'
domain=\'0x0000\' bus=\'0x00\' slot=\'0x01\'
function=\'0x2\'/>\n </controller>\n <controller
type=\'scsi\' index=\'0\' model=\'virtio-scsi\'>\n
<alias name=\'scsi0\'/>\n <address type=\'pci\'
domain=\'0x0000\' bus=\'0x00\' slot=\'0x04\'
function=\'0x0\'/>\n </controller>\n <controller
type=\'virtio-serial\' index=\'0\' ports=\'16\'>\n
<alias name=\'virtio-serial0\'/>\n <address type=\'pci\'
domain=\'0x0000\' bus=\'0x00\' slot=\'0x05\'
function=\'0x0\'/>\n </controller>\n <controller
type=\'pci\' index=\'0\' model=\'pci-root\'>\n <alias
name=\'pci.0\'/>\n </controller>\n <controller
type=\'ide\' index=\'0\'>\n <alias name=\'ide\'/>\n
<address type=\'pci\' domain=\'0x0000\' bus=\'0x00\'
slot=\'0x01\' function=\'0x1\'/>\n </controller>\n
<interface type=\'bridge\'>\n <mac
address=\'00:1a:4a:16:01:51\'/>\n <source
bridge=\'vdsmbr_QwORbsw2\'/>\n <virtualport
type=\'openvswitch\'>\n <parameters
interfaceid=\'3a24e764-32c8-4a2e-8dfd-02a776c3bef6\'/>\n
</virtualport>\n <target dev=\'vnet0\'/>\n <model
type=\'virtio\'/>\n <filterref
filter=\'vdsm-no-mac-spoofing\'/>\n <link state=\'up\'/>\n
<alias name=\'net0\'/>\n <address type=\'pci\'
domain=\'0x0000\' bus=\'0x00\' slot=\'0x03\'
function=\'0x0\'/>\n </interface>\n <channel
type=\'unix\'>\n <source mode=\'bind\'
path=\'/var/lib/libvirt/qemu/channels/cf9c5dbf-3924-47c6-b323-22ac90a1f682.com.redhat.rhevm.vdsm\'/>\n
<target type=\'virtio\' name=\'com.redhat.rhevm.vdsm\'
state=\'connected\'/>\n <alias name=\'channel0\'/>\n
<address type=\'virtio-serial\' controller=\'0\' bus=\'0\'
port=\'1\'/>\n </channel>\n <channel type=\'unix\'>\n
<source mode=\'bind\'
path=\'/var/lib/libvirt/qemu/channels/cf9c5dbf-3924-47c6-b323-22ac90a1f682.org.qemu.guest_agent.0\'/>\n
<target type=\'virtio\' name=\'org.qemu.guest_agent.0\'
state=\'connected\'/>\n <alias name=\'channel1\'/>\n
<address type=\'virtio-serial\' controller=\'0\' bus=\'0\'
port=\'2\'/>\n </channel>\n <channel
type=\'spicevmc\'>\n <target type=\'virtio\'
name=\'com.redhat.spice.0\' state=\'connected\'/>\n <alias
name=\'channel2\'/>\n <address type=\'virtio-serial\'
controller=\'0\' bus=\'0\' port=\'3\'/>\n </channel>\n
<input type=\'mouse\' bus=\'ps2\'>\n <alias
name=\'input0\'/>\n </input>\n <input type=\'keyboard\'
bus=\'ps2\'>\n <alias name=\'input1\'/>\n
</input>\n <graphics type=\'spice\' tlsPort=\'5900\'
autoport=\'yes\' listen=\'128.84.46.164\' defaultMode=\'secure\'
passwdValidTo=\'2017-03-27T14:37:09\'>\n <listen
type=\'address\' address=\'128.84.46.164\'/>\n <channel
name=\'main\' mode=\'secure\'/>\n <channel
name=\'display\' mode=\'secure\'/>\n <channel
name=\'inputs\' mode=\'secure\'/>\n <channel
name=\'cursor\' mode=\'secure\'/>\n <channel
name=\'playback\' mode=\'secure\'/>\n <channel
name=\'record\' mode=\'secure\'/>\n <channel
name=\'smartcard\' mode=\'secure\'/>\n <channel
name=\'usbredir\' mode=\'secure\'/>\n </graphics>\n
<video>\n <model type=\'qxl\' ram=\'65536\'
vram=\'8192\' vgamem=\'16384\' heads=\'1\'
primary=\'yes\'/>\n <alias name=\'video0\'/>\n
<address type=\'pci\' domain=\'0x0000\' bus=\'0x00\'
slot=\'0x02\' function=\'0x0\'/>\n </video>\n
<memballoon model=\'virtio\'>\n <alias
name=\'balloon0\'/>\n <address type=\'pci\'
domain=\'0x0000\' bus=\'0x00\' slot=\'0x06\'
function=\'0x0\'/>\n </memballoon>\n <rng
model=\'virtio\'>\n <backend
model=\'random\'>/dev/urandom</backend>\n <alias
name=\'rng0\'/>\n <address type=\'pci\'
domain=\'0x0000\' bus=\'0x00\' slot=\'0x07\'
function=\'0x0\'/>\n </rng>\n </devices>\n <seclabel
type=\'none\' model=\'none\'/>\n <seclabel type=\'dynamic\'
model=\'dac\' relabel=\'yes\'>\n
<label>+265:+107</label>\n <imagelabel>+265:+107</imagelabel>\n
</seclabel>\n</domain>\n', u'memSize': 1024,
u'smpCoresPerSocket': u'1', u'vmName': u'lnx16',
u'nice': u'0', u'username': u'(unknown)',
u'maxMemSize': 4096, u'bootMenuEnable': u'false',
u'smpThreadsPerCore': u'1', u'smartcardEnable': u'false',
u'clientIp': u'', u'guestAgentAPIVersion': 3,
u'kvmEnable': u'true', u'pitReinjection': u'false',
u'displayNetwork': u'classepublic', u'devices':
[{u'target': 1048576, u'alias': u'balloon0',
u'specParams': {u'model': u'virtio'}, u'deviceId':
u'ee3ebcbf-08d9-481e-907c-efe32b8ee869', u'address': {u'function':
u'0x0', u'bus': u'0x00', u'domain': u'0x0000',
u'type': u'pci', u'slot': u'0x06'}, u'device':
u'memballoon', u'type': u'balloon'}, {u'alias':
u'rng0', u'specParams': {u'source': u'urandom'},
u'deviceId': u'0c522e52-a4c9-4bd9-a367-7b6fddedc931', u'address':
{u'slot': u'0x07', u'bus': u'0x00', u'domain':
u'0x0000', u'type': u'pci', u'function': u'0x0'},
u'device': u'virtio', u'model': u'virtio',
u'type': u'rng'}, {u'index': u'0', u'alias':
u'usb', u'specParams': {}, u'deviceId':
u'3d4cbbda-4aa6-424e-95c6-bfae37e466b7', u'address': {u'slot':
u'0x01', u'bus': u'0x00', u'domain': u'0x0000',
u'type': u'pci', u'function': u'0x2'}, u'device':
u'usb', u'model': u'piix3-uhci', u'type':
u'controller'}, {u'index': u'0', u'alias':
u'scsi0', u'specParams': {}, u'deviceId':
u'0db369d1-81d8-4534-8437-105b851670c5', u'address': {u'slot':
u'0x04', u'bus': u'0x00', u'domain': u'0x0000',
u'type': u'pci', u'function': u'0x0'}, u'device':
u'scsi', u'model': u'virtio-scsi', u'type':
u'controller'}, {u'alias': u'virtio-serial0',
u'specParams': {}, u'deviceId':
u'553f9e1c-d811-4cb6-82bc-d567cd8e50bd', u'address': {u'slot':
u'0x05', u'bus': u'0x00', u'domain': u'0x0000',
u'type': u'pci', u'function': u'0x0'}, u'device':
u'virtio-serial', u'type': u'controller'}, {u'alias':
u'video0', u'specParams': {u'vram': u'8192',
u'vgamem': u'16384', u'heads': u'1', u'ram':
u'65536'}, u'deviceId': u'2ece7a34-22ec-49bc-b48c-df4660d13d71',
u'address': {u'slot': u'0x02', u'bus': u'0x00',
u'domain': u'0x0000', u'type': u'pci',
u'function': u'0x0'}, u'device': u'qxl', u'type':
u'video'}, {u'device': u'spice', u'specParams':
{u'fileTransferEnable': u'true', u'spiceSecureChannels':
u'smain,sinputs,scursor,splayback,srecord,sdisplay,ssmartcard,susbredir',
u'displayNetwork': u'classepublic', u'displayIp':
u'128.84.46.164', u'copyPasteEnable': u'true'}, u'type':
u'graphics', u'deviceId': u'a7076b55-ddbb-4932-aca4-c567d9576c54',
u'tlsPort': u'5900'}, {u'nicModel': u'pv',
u'macAddr': u'00:1a:4a:16:01:51', u'linkActive': True,
u'network': u'classepublic', u'alias': u'net0',
u'filter': u'vdsm-no-mac-spoofing', u'specParams':
{u'inbound': {}, u'outbound': {}}, u'deviceId':
u'5110646a-b5d4-4b62-8188-b18cec8c15df', u'address': {u'slot':
u'0x03', u'bus': u'0x00', u'domain': u'0x0000',
u'type': u'pci', u'function': u'0x0'}, u'device':
u'bridge', u'type': u'interface', u'name':
u'vnet0'}, {u'index': u'2', u'iface': u'ide',
u'name': u'hdc', u'alias': u'ide0-1-0',
u'specParams': {u'path': u''}, u'readonly':
u'True', u'deviceId': u'75deba88-b39d-478c-83d3-cc535b43bb28',
u'address': {u'bus': u'1', u'controller': u'0',
u'type': u'drive', u'target': u'0', u'unit':
u'0'}, u'device': u'cdrom', u'shared': u'false',
u'path': u'', u'type': u'disk'}, {u'poolID':
u'00000001-0001-0001-0001-000000000311', u'volumeInfo':
{u'domainID': u'016ceee8-9117-4e8a-b611-f58f6763a098', u'volType':
u'path', u'leaseOffset': 111149056, u'volumeID':
u'67b12700-5faf-49b2-af40-6178023b3794', u'leasePath':
u'/dev/016ceee8-9117-4e8a-b611-f58f6763a098/leases', u'imageID':
u'21d18729-279f-435b-90ab-a2995f041bc6', u'path':
u'/rhev/data-center/mnt/blockSD/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49b2-af40-6178023b3794'},
u'index': 0, u'iface': u'scsi', u'apparentsize':
u'107374182400', u'alias': u'scsi0-0-0-0', u'imageID':
u'21d18729-279f-435b-90ab-a2995f041bc6', u'readonly': u'False',
u'shared': u'false', u'truesize': u'107374182400',
u'type': u'disk', u'domainID':
u'016ceee8-9117-4e8a-b611-f58f6763a098', u'reqsize': u'0',
u'format': u'raw', u'deviceId':
u'21d18729-279f-435b-90ab-a2995f041bc6', u'address': {u'bus':
u'0', u'controller': u'0', u'type': u'drive',
u'target': u'0', u'unit': u'0'}, u'device':
u'disk', u'path':
u'/rhev/data-center/00000001-0001-0001-0001-000000000311/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49b2-af40-6178023b3794',
u'propagateErrors': u'off', u'optional': u'false',
u'name': u'sda', u'bootOrder': u'1', u'volumeID':
u'67b12700-5faf-49b2-af40-6178023b3794', u'specParams': {},
u'discard': False, u'volumeChain': [{u'domainID':
u'016ceee8-9117-4e8a-b611-f58f6763a098', u'volType': u'path',
u'leaseOffset': 111149056, u'volumeID':
u'67b12700-5faf-49b2-af40-6178023b3794', u'leasePath':
u'/dev/016ceee8-9117-4e8a-b611-f58f6763a098/leases', u'imageID':
u'21d18729-279f-435b-90ab-a2995f041bc6', u'path':
u'/rhev/data-center/mnt/blockSD/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49b2-af40-6178023b3794'}]},
{u'device': u'ide', u'alias': u'ide', u'type':
u'controller', u'address': {u'slot': u'0x01',
u'bus': u'0x00', u'domain': u'0x0000', u'type':
u'pci', u'function': u'0x1'}}, {u'device':
u'unix', u'alias': u'channel0', u'type':
u'channel', u'address': {u'bus': u'0',
u'controller': u'0', u'type': u'virtio-serial',
u'port': u'1'}}, {u'device': u'unix', u'alias':
u'channel1', u'type': u'channel', u'address':
{u'bus': u'0', u'controller': u'0', u'type':
u'virtio-serial', u'port': u'2'}}, {u'device':
u'spicevmc', u'alias': u'channel2', u'type':
u'channel', u'address': {u'bus': u'0',
u'controller': u'0', u'type': u'virtio-serial',
u'port': u'3'}}], u'memGuaranteedSize': 1024, u'status':
u'Up', u'maxVCpus': u'16', u'guestIPs': u'128.84.46.16
192.168.122.1', u'statusTime': u'4528256170', u'maxMemSlots':
16}, False) kwargs={} (api:37)
2017-03-27 10:57:02,776-0400 INFO (jsonrpc/4) [vdsm.api] FINISH __init__ return=None
(api:43)
2017-03-27 10:57:02,777-0400 INFO (vm/cf9c5dbf) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') VM wrapper has started (vm:1915)
2017-03-27 10:57:02,778-0400 INFO (vm/cf9c5dbf) [dispatcher] Run and protect:
getVolumeSize(sdUUID=u'016ceee8-9117-4e8a-b611-f58f6763a098',
spUUID=u'00000001-0001-0001-0001-000000000311',
imgUUID=u'21d18729-279f-435b-90ab-a2995f041bc6',
volUUID=u'67b12700-5faf-49b2-af40-6178023b3794', options=None) (logUtils:51)
2017-03-27 10:57:02,809-0400 INFO (vm/cf9c5dbf) [dispatcher] Run and protect:
getVolumeSize, Return response: {'truesize': '107374182400',
'apparentsize': '107374182400'} (logUtils:54)
2017-03-27 10:57:02,809-0400 INFO (vm/cf9c5dbf) [vds] prepared volume path:
(clientIF:374)
2017-03-27 10:57:02,810-0400 INFO (vm/cf9c5dbf) [dispatcher] Run and protect:
prepareImage(sdUUID=u'016ceee8-9117-4e8a-b611-f58f6763a098',
spUUID=u'00000001-0001-0001-0001-000000000311',
imgUUID=u'21d18729-279f-435b-90ab-a2995f041bc6',
leafUUID=u'67b12700-5faf-49b2-af40-6178023b3794', allowIllegal=False)
(logUtils:51)
2017-03-27 10:57:02,859-0400 INFO (vm/cf9c5dbf) [storage.LVM] Activating lvs:
vg=016ceee8-9117-4e8a-b611-f58f6763a098
lvs=['67b12700-5faf-49b2-af40-6178023b3794'] (lvm:1289)
2017-03-27 10:57:02,947-0400 INFO (vm/cf9c5dbf) [storage.LVM] Refreshing lvs:
vg=016ceee8-9117-4e8a-b611-f58f6763a098 lvs=['leases'] (lvm:1285)
2017-03-27 10:57:02,998-0400 INFO (vm/cf9c5dbf) [dispatcher] Run and protect:
prepareImage, Return response: {'info': {'domainID':
u'016ceee8-9117-4e8a-b611-f58f6763a098', 'volType': 'path',
'leaseOffset': 111149056, 'path':
u'/rhev/data-center/mnt/blockSD/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49b2-af40-6178023b3794',
'volumeID': '67b12700-5faf-49b2-af40-6178023b3794', 'leasePath':
'/dev/016ceee8-9117-4e8a-b611-f58f6763a098/leases', 'imageID':
u'21d18729-279f-435b-90ab-a2995f041bc6'}, 'path':
u'/rhev/data-center/00000001-0001-0001-0001-000000000311/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49b2-af40-6178023b3794',
'imgVolumesInfo': [{'domainID':
u'016ceee8-9117-4e8a-b611-f58f6763a098', 'volType': 'path',
'leaseOffset': 111149056, 'path':
u'/rhev/data-center/mnt/blockSD/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49b2-af40-6178023b3794',
'volumeID': '67b12700-5faf-49b2-af40-6178023b3794', 'leasePath':
'/dev/016ceee8-9117-4e8a-b611-f58f6763a098/leases', 'imageID':
u'21d18729-279f-435b-90ab-a2995f041bc6'}]} (logUtils:54)
2017-03-27 10:57:02,998-0400 INFO (vm/cf9c5dbf) [vds] prepared volume path:
/rhev/data-center/00000001-0001-0001-0001-000000000311/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49b2-af40-6178023b3794
(clientIF:374)
2017-03-27 10:57:03,026-0400 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call
VM.migrationCreate succeeded in 0.27 seconds (__init__:515)
2017-03-27 10:57:03,225-0400 INFO (jsonrpc/7) [vdsm.api] START destroy
args=(<virt.vm.Vm object at 0x4132e50>, 1) kwargs={} (api:37)
2017-03-27 10:57:03,225-0400 INFO (jsonrpc/7) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Release VM resources (vm:4199)
2017-03-27 10:57:03,226-0400 INFO (jsonrpc/7) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Stopping connection
(guestagent:430)
2017-03-27 10:57:03,227-0400 INFO (jsonrpc/7) [dispatcher] Run and protect:
teardownImage(sdUUID=u'016ceee8-9117-4e8a-b611-f58f6763a098',
spUUID=u'00000001-0001-0001-0001-000000000311',
imgUUID=u'21d18729-279f-435b-90ab-a2995f041bc6', volUUID=None) (logUtils:51)
2017-03-27 10:57:03,237-0400 INFO (vm/cf9c5dbf) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Changed state to Down: VM destroyed
during the startup (code=10) (vm:1207)
2017-03-27 10:57:03,238-0400 INFO (vm/cf9c5dbf) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Stopping connection
(guestagent:430)
2017-03-27 10:57:03,260-0400 INFO (jsonrpc/7) [storage.LVM] Deactivating lvs:
vg=016ceee8-9117-4e8a-b611-f58f6763a098
lvs=['67b12700-5faf-49b2-af40-6178023b3794'] (lvm:1297)
2017-03-27 10:57:03,311-0400 INFO (jsonrpc/7) [dispatcher] Run and protect:
teardownImage, Return response: None (logUtils:54)
2017-03-27 10:57:03,312-0400 INFO (jsonrpc/7) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Stopping connection
(guestagent:430)
2017-03-27 10:57:03,312-0400 WARN (jsonrpc/7) [root] File:
/var/lib/libvirt/qemu/channels/cf9c5dbf-3924-47c6-b323-22ac90a1f682.com.redhat.rhevm.vdsm
already removed (utils:120)
2017-03-27 10:57:03,312-0400 WARN (jsonrpc/7) [root] File:
/var/lib/libvirt/qemu/channels/cf9c5dbf-3924-47c6-b323-22ac90a1f682.org.qemu.guest_agent.0
already removed (utils:120)
2017-03-27 10:57:03,313-0400 WARN (jsonrpc/7) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') timestamp already removed from stats
cache (vm:1729)
2017-03-27 10:57:03,313-0400 INFO (jsonrpc/7) [dispatcher] Run and protect:
inappropriateDevices(thiefId=u'cf9c5dbf-3924-47c6-b323-22ac90a1f682')
(logUtils:51)
2017-03-27 10:57:03,314-0400 INFO (jsonrpc/7) [dispatcher] Run and protect:
inappropriateDevices, Return response: None (logUtils:54)
2017-03-27 10:57:03,314-0400 INFO (jsonrpc/7) [vdsm.api] FINISH destroy
return={'status': {'message': 'Done', 'code': 0}}
(api:43)
2017-03-27 10:57:03,315-0400 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call VM.destroy
succeeded in 0.09 seconds (__init__:515)
2017-03-27 10:57:03,937-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::1:53024 (protocoldetector:72)
2017-03-27 10:57:03,943-0400 INFO (Reactor thread) [ProtocolDetector.Detector] Detected
protocol stomp from ::1:53024 (protocoldetector:127)
2017-03-27 10:57:03,944-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
2017-03-27 10:57:03,945-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
2017-03-27 10:57:04,084-0400 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.00 seconds (__init__:515)
2017-03-27 10:57:04,086-0400 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call
VM.getStats failed (error 1) in 0.00 seconds (__init__:515)
2017-03-27 10:57:04,767-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::1:53026 (protocoldetector:72)
2017-03-27 10:57:04,772-0400 INFO (Reactor thread) [ProtocolDetector.Detector] Detected
protocol stomp from ::1:53026 (protocoldetector:127)
2017-03-27 10:57:04,773-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
2017-03-27 10:57:04,774-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
2017-03-27 10:57:04,913-0400 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.00 seconds (__init__:515)
2017-03-27 10:57:05,126-0400 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call
Host.getCapabilities succeeded in 0.22 seconds (__init__:515)
2017-03-27 10:57:06,040-0400 INFO (periodic/0) [dispatcher] Run and protect:
repoStats(options=None) (logUtils:51)
2017-03-27 10:57:06,040-0400 INFO (periodic/0) [dispatcher] Run and protect: repoStats,
Return response: {u'016ceee8-9117-4e8a-b611-f58f6763a098': {'code': 0,
'actual': True, 'version': 4, 'acquired': True, 'delay':
'0.000426872', 'lastCheck': '4.6', 'valid': True},
u'2438f819-e7f5-4bb1-ad0d-5349fa371e6e': {'code': 0, 'actual':
True, 'version': 0, 'acquired': True, 'delay':
'0.000387272', 'lastCheck': '4.5', 'valid': True},
u'48d4f45d-0bdd-4f4a-90b6-35efe2da935a': {'code': 0, 'actual':
True, 'version': 4, 'acquired': True, 'delay':
'0.000353399', 'lastCheck': '0.5', 'valid': True}}
(logUtils:54)
2017-03-27 10:57:08,170-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::1:53028 (protocoldetector:72)
2017-03-27 10:57:08,176-0400 INFO (Reactor thread) [ProtocolDetector.Detector] Detected
protocol stomp from ::1:53028 (protocoldetector:127)
2017-03-27 10:57:08,176-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
2017-03-27 10:57:08,177-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
2017-03-27 10:57:08,321-0400 INFO (jsonrpc/6) [dispatcher] Run and protect:
repoStats(options=None) (logUtils:51)
2017-03-27 10:57:08,322-0400 INFO (jsonrpc/6) [dispatcher] Run and protect: repoStats,
Return response: {u'016ceee8-9117-4e8a-b611-f58f6763a098': {'code': 0,
'actual': True, 'version': 4, 'acquired': True, 'delay':
'0.000426872', 'lastCheck': '2.1', 'valid': True},
u'2438f819-e7f5-4bb1-ad0d-5349fa371e6e': {'code': 0, 'actual':
True, 'version': 0, 'acquired': True, 'delay':
'0.000387272', 'lastCheck': '1.2', 'valid': True},
u'48d4f45d-0bdd-4f4a-90b6-35efe2da935a': {'code': 0, 'actual':
True, 'version': 4, 'acquired': True, 'delay':
'0.000353399', 'lastCheck': '2.8', 'valid': True}}
(logUtils:54)
2017-03-27 10:57:08,324-0400 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.00 seconds (__init__:515)
2017-03-27 10:57:08,327-0400 INFO (jsonrpc/4) [dispatcher] Run and protect:
repoStats(options=None) (logUtils:51)
2017-03-27 10:57:08,328-0400 INFO (jsonrpc/4) [dispatcher] Run and protect: repoStats,
Return response: {u'016ceee8-9117-4e8a-b611-f58f6763a098': {'code': 0,
'actual': True, 'version': 4, 'acquired': True, 'delay':
'0.000426872', 'lastCheck': '2.1', 'valid': True},
u'2438f819-e7f5-4bb1-ad0d-5349fa371e6e': {'code': 0, 'actual':
True, 'version': 0, 'acquired': True, 'delay':
'0.000387272', 'lastCheck': '1.2', 'valid': True},
u'48d4f45d-0bdd-4f4a-90b6-35efe2da935a': {'code': 0, 'actual':
True, 'version': 4, 'acquired': True, 'delay':
'0.000353399', 'lastCheck': '2.8', 'valid': True}}
(logUtils:54)
2017-03-27 10:57:08,619-0400 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call
Host.getStats succeeded in 0.29 seconds (__init__:515)
2017-03-27 10:57:08,625-0400 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call
Host.getStats succeeded in 0.30 seconds (__init__:515)
2017-03-27 10:57:08,848-0400 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call
Host.getAllVmStats succeeded in 0.00 seconds (__init__:515)
2017-03-27 10:57:09,370-0400 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call
Host.getAllVmStats succeeded in 0.00 seconds (__init__:515)
2017-03-27 10:57:10,927-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::1:53030 (protocoldetector:72)
2017-03-27 10:57:10,932-0400 INFO (Reactor thread) [ProtocolDetector.Detector] Detected
protocol stomp from ::1:53030 (protocoldetector:127)
2017-03-27 10:57:10,932-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
2017-03-27 10:57:10,933-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
2017-03-27 10:57:11,077-0400 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.00 seconds (__init__:515)
2017-03-27 10:57:11,080-0400 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call
VM.getStats failed (error 1) in 0.00 seconds (__init__:515)
2017-03-27 10:57:13,802-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::1:53034 (protocoldetector:72)
2017-03-27 10:57:13,808-0400 INFO (Reactor thread) [ProtocolDetector.Detector] Detected
protocol stomp from ::1:53034 (protocoldetector:127)
2017-03-27 10:57:13,809-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
2017-03-27 10:57:13,810-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
2017-03-27 10:57:14,015-0400 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.00 seconds (__init__:515)
2017-03-27 10:57:14,019-0400 INFO (jsonrpc/5) [dispatcher] Run and protect:
getImagesList(sdUUID=u'48d4f45d-0bdd-4f4a-90b6-35efe2da935a', options=None)
(logUtils:51)
2017-03-27 10:57:14,050-0400 INFO (jsonrpc/5) [dispatcher] Run and protect:
getImagesList, Return response: {'imageslist':
['a1d7f855-40d4-4f07-bf25-625284682b40',
'c61297c5-367b-4c98-a44b-de0318848a24',
'c2fc0c7c-596e-4011-8471-7f67408ea633',
'b2bb3396-a8fb-4ed7-89c7-4cc7d22aa26a',
'6d594f05-c2c2-4ef0-849f-28da46b8f87e',
'5fc548e6-51a8-44ee-a14b-92e0e924d7b8']} (logUtils:54)
2017-03-27 10:57:14,051-0400 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call
StorageDomain.getImages succeeded in 0.03 seconds (__init__:515)
2017-03-27 10:57:14,083-0400 INFO (jsonrpc/4) [dispatcher] Run and protect:
getVolumesList(sdUUID=u'48d4f45d-0bdd-4f4a-90b6-35efe2da935a',
spUUID=u'00000000-0000-0000-0000-000000000000',
imgUUID=u'a1d7f855-40d4-4f07-bf25-625284682b40', options=None) (logUtils:51)
...
6:17 p.m.
Just incase anyone else runs into this, you need to set
"migration_ovs_hook_enabled=True" in vdsm.conf. It seems the vdsm.conf created
by "hosted-engine --deploy" did not list all of the options, so I overlooked
this one.
Thanks for all the help,
Devin
On Mar 27, 2017, at 11:10 AM, Devin A. Bougie <devin.bougie(a)cornell.edu> wrote:
Hi, All. We have a new oVirt 4.1.1 cluster up with the OVS switch
type. Everything seems to be working great, except for live migration.
I believe the red flag in vdsm.log on the source is:
Cannot get interface MTU on 'vdsmbr_QwORbsw2': No such device (migration:287)
Which results from vdsm assigning an arbitrary bridge name to each ovs bridge.
Please see below for more details on the bridges and excerpts from the logs. Any help
would be greatly appreciated.
Many thanks,
Devin
SOURCE OVS BRIDGES:
# ovs-vsctl show
6d96d9a5-e30d-455b-90c7-9e9632574695
Bridge "vdsmbr_QwORbsw2"
Port "vdsmbr_QwORbsw2"
Interface "vdsmbr_QwORbsw2"
type: internal
Port "vnet0"
Interface "vnet0"
Port classepublic
Interface classepublic
type: internal
Port "ens1f0"
Interface "ens1f0"
Bridge "vdsmbr_9P7ZYKWn"
Port ovirtmgmt
Interface ovirtmgmt
type: internal
Port "ens1f1"
Interface "ens1f1"
Port "vdsmbr_9P7ZYKWn"
Interface "vdsmbr_9P7ZYKWn"
type: internal
ovs_version: "2.7.0"
DESTINATION OVS BRIDGES:
# ovs-vsctl show
f66d765d-712a-4c81-b18e-da1acc9cfdde
Bridge "vdsmbr_vdpp0dOd"
Port "vdsmbr_vdpp0dOd"
Interface "vdsmbr_vdpp0dOd"
type: internal
Port "ens1f0"
Interface "ens1f0"
Port classepublic
Interface classepublic
type: internal
Bridge "vdsmbr_3sEwEKd1"
Port "vdsmbr_3sEwEKd1"
Interface "vdsmbr_3sEwEKd1"
type: internal
Port "ens1f1"
Interface "ens1f1"
Port ovirtmgmt
Interface ovirtmgmt
type: internal
ovs_version: "2.7.0"
SOURCE VDSM LOG:
...
2017-03-27 10:57:02,567-0400 INFO (jsonrpc/1) [vdsm.api] START migrate
args=(<virt.vm.Vm object at 0x3410810>, {u'incomingLimit': 2,
u'src': u'192.168.55.84', u'dstqemu': u'192.168.55.81',
u'autoConverge': u'false', u'tunneled': u'false',
u'enableGuestEvents': False, u'dst':
u'lnxvirt01-p55.classe.cornell.edu:54321', u'vmId':
u'cf9c5dbf-3924-47c6-b323-22ac90a1f682', u'abortOnError': u'true',
u'outgoingLimit': 2, u'compressed': u'false',
u'maxBandwidth': 5000, u'method': u'online', 'mode':
'remote'}) kwargs={} (api:37)
2017-03-27 10:57:02,570-0400 INFO (jsonrpc/1) [vdsm.api] FINISH migrate
return={'status': {'message': 'Migration in progress',
'code': 0}, 'progress': 0} (api:43)
2017-03-27 10:57:02,570-0400 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call
VM.migrate succeeded in 0.01 seconds (__init__:515)
2017-03-27 10:57:03,028-0400 INFO (migsrc/cf9c5dbf) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Creation of destination VM took: 0
seconds (migration:455)
2017-03-27 10:57:03,028-0400 INFO (migsrc/cf9c5dbf) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') starting migration to
qemu+tls://lnxvirt01-p55.classe.cornell.edu/system with miguri tcp://192.168.55.81
(migration:480)
2017-03-27 10:57:03,224-0400 ERROR (migsrc/cf9c5dbf) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Cannot get interface MTU on
'vdsmbr_QwORbsw2': No such device (migration:287)
2017-03-27 10:57:03,322-0400 ERROR (migsrc/cf9c5dbf) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Failed to migrate (migration:429)
Traceback (most recent call last):
File "/usr/lib/python2.7/site-packages/vdsm/virt/migration.py", line 411, in
run
self._startUnderlyingMigration(time.time())
File "/usr/lib/python2.7/site-packages/vdsm/virt/migration.py", line 489, in
_startUnderlyingMigration
self._perform_with_downtime_thread(duri, muri)
File "/usr/lib/python2.7/site-packages/vdsm/virt/migration.py", line 555, in
_perform_with_downtime_thread
self._perform_migration(duri, muri)
File "/usr/lib/python2.7/site-packages/vdsm/virt/migration.py", line 528, in
_perform_migration
self._vm._dom.migrateToURI3(duri, params, flags)
File "/usr/lib/python2.7/site-packages/vdsm/virt/virdomain.py", line 69, in f
ret = attr(*args, **kwargs)
File "/usr/lib/python2.7/site-packages/vdsm/libvirtconnection.py", line 123,
in wrapper
ret = f(*args, **kwargs)
File "/usr/lib/python2.7/site-packages/vdsm/utils.py", line 941, in wrapper
return func(inst, *args, **kwargs)
File "/usr/lib64/python2.7/site-packages/libvirt.py", line 1939, in
migrateToURI3
if ret == -1: raise libvirtError ('virDomainMigrateToURI3() failed',
dom=self)
libvirtError: Cannot get interface MTU on 'vdsmbr_QwORbsw2': No such device
2017-03-27 10:57:03,435-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::1:33716 (protocoldetector:72)
2017-03-27 10:57:03,452-0400 INFO (Reactor thread) [ProtocolDetector.Detector] Detected
protocol stomp from ::1:33716 (protocoldetector:127)
2017-03-27 10:57:03,452-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
2017-03-27 10:57:03,456-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
2017-03-27 10:57:03,589-0400 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.01 seconds (__init__:515)
2017-03-27 10:57:03,592-0400 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call
VM.getStats failed (error 1) in 0.00 seconds (__init__:515)
2017-03-27 10:57:04,494-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::1:33718 (protocoldetector:72)
2017-03-27 10:57:04,500-0400 INFO (Reactor thread) [ProtocolDetector.Detector] Detected
protocol stomp from ::1:33718 (protocoldetector:127)
2017-03-27 10:57:04,500-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
2017-03-27 10:57:04,501-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
2017-03-27 10:57:04,559-0400 INFO (periodic/2) [dispatcher] Run and protect:
repoStats(options=None) (logUtils:51)
2017-03-27 10:57:04,559-0400 INFO (periodic/2) [dispatcher] Run and protect: repoStats,
Return response: {u'016ceee8-9117-4e8a-b611-f58f6763a098': {'code': 0,
'actual': True, 'version': 4, 'acquired': True, 'delay':
'0.000262909', 'lastCheck': '5.3', 'valid': True},
u'2438f819-e7f5-4bb1-ad0d-5349fa371e6e': {'code': 0, 'actual':
True, 'version': 0, 'acquired': True, 'delay':
'0.000342019', 'lastCheck': '5.3', 'valid': True},
u'48d4f45d-0bdd-4f4a-90b6-35efe2da935a': {'code': 0, 'actual':
True, 'version': 4, 'acquired': True, 'delay':
'0.000239611', 'lastCheck': '0.6', 'valid': True}}
(logUtils:54)
2017-03-27 10:57:04,669-0400 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.01 seconds (__init__:515)
2017-03-27 10:57:04,684-0400 INFO (jsonrpc/0) [dispatcher] Run and protect:
repoStats(options=None) (logUtils:51)
2017-03-27 10:57:04,684-0400 INFO (jsonrpc/0) [dispatcher] Run and protect: repoStats,
Return response: {u'016ceee8-9117-4e8a-b611-f58f6763a098': {'code': 0,
'actual': True, 'version': 4, 'acquired': True, 'delay':
'0.000262909', 'lastCheck': '5.4', 'valid': True},
u'2438f819-e7f5-4bb1-ad0d-5349fa371e6e': {'code': 0, 'actual':
True, 'version': 0, 'acquired': True, 'delay':
'0.000342019', 'lastCheck': '5.4', 'valid': True},
u'48d4f45d-0bdd-4f4a-90b6-35efe2da935a': {'code': 0, 'actual':
True, 'version': 4, 'acquired': True, 'delay':
'0.000239611', 'lastCheck': '0.7', 'valid': True}}
(logUtils:54)
2017-03-27 10:57:04,770-0400 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call
Host.getStats succeeded in 0.09 seconds (__init__:515)
...
DESTINATION VDSM LOG:
...
2017-03-27 10:57:01,515-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
2017-03-27 10:57:01,652-0400 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.00 seconds (__init__:515)
2017-03-27 10:57:02,572-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::ffff:192.168.55.84:44582 (protocoldetector:72)
2017-03-27 10:57:02,577-0400 INFO (Reactor thread) [ProtocolDetector.Detector] Detected
protocol stomp from ::ffff:192.168.55.84:44582 (protocoldetector:127)
2017-03-27 10:57:02,578-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
2017-03-27 10:57:02,579-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
2017-03-27 10:57:02,748-0400 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call Host.ping
succeeded in 0.00 seconds (__init__:515)
2017-03-27 10:57:02,774-0400 INFO (jsonrpc/4) [vdsm.api] START __init__
args=(<virt.vm.Vm object at 0x4132e50>, <clientIF.clientIF object at
0x7f453c01e650>, {u'guestFQDN': u'lnx16.classe.cornell.edu',
u'acpiEnable': u'true', u'emulatedMachine':
u'pc-i440fx-rhel7.3.0', u'afterMigrationStatus': u'',
u'enableGuestEvents': False, u'vmId':
u'cf9c5dbf-3924-47c6-b323-22ac90a1f682', u'elapsedTimeOffset':
1321.2906959056854, u'guestDiskMapping':
{u'0QEMU_QEMU_HARDDISK_21d18729-279f-435b-9': {u'name':
u'/dev/sda'}, u'QEMU_DVD-ROM_QM00003': {u'name':
u'/dev/sr0'}}, u'transparentHugePages': u'true',
u'timeOffset': u'0', u'cpuType': u'Haswell-noTSX',
u'custom': {u'device_9d15a0c8-a122-43b9-89c2-3ad530ebc985':
u"VmDevice:{id='VmDeviceId:{deviceId='9d15a0c8-a122-43b9-89c2-3ad530ebc985',
vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682'}', device='ide',
type='CONTROLLER', bootOrder='0', specParams='[]',
address='{slot=0x01, bus=0x00, domain=0x0000, type=pci, function=0x1}',
managed='false', plug
ged='true', readOnly='false', deviceAlias='ide',
customProperties='[]', snapshotId='null', logicalName='null',
hostDevice='null'}",
u'device_9d15a0c8-a122-43b9-89c2-3ad530ebc985device_0bbb6377-22db-4d04-a501-64de81bab622device_758565a5-4378-4412-8ec1-2df7bb231c14device_7ead63f7-b119-434d-a2f7-081fd06229e4':
u"VmDevice:{id='VmDeviceId:{deviceId='7ead63f7-b119-434d-a2f7-081fd06229e4',
vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682'}', device='spicevmc',
type='CHANNEL', bootOrder='0', specParams='[]',
address='{bus=0, controller=0, type=virtio-serial, port=3}',
managed='false', plugged='true', readOnly='false',
deviceAlias='channel2', customProperties='[]', snapshotId='null',
logicalName='null', hostDevice='null'}",
u'device_9d15a0c8-a122-43b9-89c2-3ad530ebc985device_0bbb6377-22db-4d04-a501-64de81bab622':
u"VmDevice:{id='VmDeviceId:{deviceId='0bbb6377-22db-4d04-a501-64de81bab622',
vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682'}', device='unix',
type='CHANNEL', bootOrder='0', specParam
s='[]', address='{bus=0, controller=0, type=virtio-serial, port=2}',
managed='false', plugged='true', readOnly='false',
deviceAlias='channel1', customProperties='[]', snapshotId='null',
logicalName='null', hostDevice='null'}",
u'device_9d15a0c8-a122-43b9-89c2-3ad530ebc985device_0bbb6377-22db-4d04-a501-64de81bab622device_758565a5-4378-4412-8ec1-2df7bb231c14':
u"VmDevice:{id='VmDeviceId:{deviceId='758565a5-4378-4412-8ec1-2df7bb231c14',
vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682'}', device='unix',
type='CHANNEL', bootOrder='0', specParams='[]',
address='{bus=0, controller=0, type=virtio-serial, port=1}',
managed='false', plugged='true', readOnly='false',
deviceAlias='channel0', customProperties='[]', snapshotId='null',
logicalName='null', hostDevice='null'}"}, u'pauseCode':
u'NOERR', u'migrationDest': u'libvirt', u'guestNumaNodes':
[{u'nodeIndex': 0, u'cpus': u'0', u'memory':
u'1024'}], u'display': u'qxl', u'smp': u'1',
u'vmType': u'kvm', u'_srcDomXML': u'<domain
type=\'kvm\' i
d=\'4\'>\n <name>lnx16</name>\n
<uuid>cf9c5dbf-3924-47c6-b323-22ac90a1f682</uuid>\n <metadata
xmlns:ovirt="http://ovirt.org/vm/tune/1.0">\n <ovirt:qos/>\n
</metadata>\n <maxMemory slots=\'16\'
unit=\'KiB\'>4194304</maxMemory>\n <memory
unit=\'KiB\'>1048576</memory>\n <currentMemory
unit=\'KiB\'>1048576</currentMemory>\n <vcpu
placement=\'static\' current=\'1\'>16</vcpu>\n <cputune>\n
<shares>1020</shares>\n </cputune>\n <resource>\n
<partition>/machine</partition>\n </resource>\n <sysinfo
type=\'smbios\'>\n <system>\n <entry
name=\'manufacturer\'>oVirt</entry>\n <entry
name=\'product\'>oVirt Node</entry>\n <entry
name=\'version\'>7.3-4.sl7</entry>\n <entry
name=\'serial\'>38C15485-D270-11E6-8752-0090FAEDD7C8</entry>\n
<entry name=\'uuid\'>cf9c5dbf-3924-47c6-b323-22ac90a1f682</entry>\n
</system>\n </sysinfo>\n <os>\n <type arch=\'x86_64\'
machine=\'pc-i440fx-rhel7.3.0\'>hvm</type>\n <smbios
mode=\'sysinfo\'/>\n </o
s>\n <features>\n <acpi/>\n </features>\n <cpu
mode=\'custom\' match=\'exact\'>\n <model
fallback=\'allow\'>Haswell-noTSX</model>\n <topology
sockets=\'16\' cores=\'1\' threads=\'1\'/>\n <numa>\n
<cell id=\'0\' cpus=\'0\' memory=\'1048576\'
unit=\'KiB\'/>\n </numa>\n </cpu>\n <clock
offset=\'variable\' adjustment=\'0\' basis=\'utc\'>\n
<timer name=\'rtc\' tickpolicy=\'catchup\'/>\n <timer
name=\'pit\' tickpolicy=\'delay\'/>\n <timer
name=\'hpet\' present=\'no\'/>\n </clock>\n
<on_poweroff>destroy</on_poweroff>\n
<on_reboot>restart</on_reboot>\n <on_crash>destroy</on_crash>\n
<devices>\n <emulator>/usr/libexec/qemu-kvm</emulator>\n <disk
type=\'file\' device=\'cdrom\'>\n <driver name=\'qemu\'
type=\'raw\'/>\n <source startupPolicy=\'optional\'/>\n
<backingStore/>\n <target dev=\'hdc\' bus=\'ide\'/>\n
<readonly/>\n <alias name=\'ide0-1-0\'/>\n <address
type=\'drive\' controller=\'0\' bus=\'1\' targ
et=\'0\' unit=\'0\'/>\n </disk>\n <disk
type=\'block\' device=\'disk\' snapshot=\'no\'>\n
<driver name=\'qemu\' type=\'raw\' cache=\'none\'
error_policy=\'stop\' io=\'native\'/>\n <source
dev=\'/rhev/data-center/00000001-0001-0001-0001-000000000311/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49b2-af40-6178023b3794\'/>\n
<backingStore/>\n <target dev=\'sda\'
bus=\'scsi\'/>\n
<serial>21d18729-279f-435b-90ab-a2995f041bc6</serial>\n <boot
order=\'1\'/>\n <alias name=\'scsi0-0-0-0\'/>\n
<address type=\'drive\' controller=\'0\' bus=\'0\'
target=\'0\' unit=\'0\'/>\n </disk>\n <controller
type=\'usb\' index=\'0\' model=\'piix3-uhci\'>\n <alias
name=\'usb\'/>\n <address type=\'pci\'
domain=\'0x0000\' bus=\'0x00\' slot=\'0x01\'
function=\'0x2\'/>\n </controller>\n <controller
type=\'scsi\' index=\'0\' model=\'virtio-scsi\'>\n
<alias name=\'scsi0\'/>\n <addre
ss type=\'pci\' domain=\'0x0000\' bus=\'0x00\'
slot=\'0x04\' function=\'0x0\'/>\n </controller>\n
<controller type=\'virtio-serial\' index=\'0\'
ports=\'16\'>\n <alias name=\'virtio-serial0\'/>\n
<address type=\'pci\' domain=\'0x0000\' bus=\'0x00\'
slot=\'0x05\' function=\'0x0\'/>\n </controller>\n
<controller type=\'pci\' index=\'0\' model=\'pci-root\'>\n
<alias name=\'pci.0\'/>\n </controller>\n <controller
type=\'ide\' index=\'0\'>\n <alias name=\'ide\'/>\n
<address type=\'pci\' domain=\'0x0000\' bus=\'0x00\'
slot=\'0x01\' function=\'0x1\'/>\n </controller>\n
<interface type=\'bridge\'>\n <mac
address=\'00:1a:4a:16:01:51\'/>\n <source
bridge=\'vdsmbr_QwORbsw2\'/>\n <virtualport
type=\'openvswitch\'>\n <parameters
interfaceid=\'3a24e764-32c8-4a2e-8dfd-02a776c3bef6\'/>\n
</virtualport>\n <target dev=\'vnet0\'/>\n <model
type=\'virtio\'/>\n <filterref
filter=\'vdsm-no-mac-spoofing\'/>\
n <link state=\'up\'/>\n <alias name=\'net0\'/>\n
<address type=\'pci\' domain=\'0x0000\' bus=\'0x00\'
slot=\'0x03\' function=\'0x0\'/>\n </interface>\n
<channel type=\'unix\'>\n <source mode=\'bind\'
path=\'/var/lib/libvirt/qemu/channels/cf9c5dbf-3924-47c6-b323-22ac90a1f682.com.redhat.rhevm.vdsm\'/>\n
<target type=\'virtio\' name=\'com.redhat.rhevm.vdsm\'
state=\'connected\'/>\n <alias name=\'channel0\'/>\n
<address type=\'virtio-serial\' controller=\'0\' bus=\'0\'
port=\'1\'/>\n </channel>\n <channel type=\'unix\'>\n
<source mode=\'bind\'
path=\'/var/lib/libvirt/qemu/channels/cf9c5dbf-3924-47c6-b323-22ac90a1f682.org.qemu.guest_agent.0\'/>\n
<target type=\'virtio\' name=\'org.qemu.guest_agent.0\'
state=\'connected\'/>\n <alias name=\'channel1\'/>\n
<address type=\'virtio-serial\' controller=\'0\' bus=\'0\'
port=\'2\'/>\n </channel>\n <channel
type=\'spicevmc\'>\n <target type=\'virtio\' name=\'com
.redhat.spice.0\' state=\'connected\'/>\n <alias
name=\'channel2\'/>\n <address type=\'virtio-serial\'
controller=\'0\' bus=\'0\' port=\'3\'/>\n </channel>\n
<input type=\'mouse\' bus=\'ps2\'>\n <alias
name=\'input0\'/>\n </input>\n <input type=\'keyboard\'
bus=\'ps2\'>\n <alias name=\'input1\'/>\n
</input>\n <graphics type=\'spice\' tlsPort=\'5900\'
autoport=\'yes\' listen=\'128.84.46.164\' defaultMode=\'secure\'
passwdValidTo=\'2017-03-27T14:37:09\'>\n <listen
type=\'address\' address=\'128.84.46.164\'/>\n <channel
name=\'main\' mode=\'secure\'/>\n <channel
name=\'display\' mode=\'secure\'/>\n <channel
name=\'inputs\' mode=\'secure\'/>\n <channel
name=\'cursor\' mode=\'secure\'/>\n <channel
name=\'playback\' mode=\'secure\'/>\n <channel
name=\'record\' mode=\'secure\'/>\n <channel
name=\'smartcard\' mode=\'secure\'/>\n <channel
name=\'usbredir\' mode=\'secure\'/>\n </graphics>\n
<video
> \n <model type=\'qxl\' ram=\'65536\' vram=\'8192\'
vgamem=\'16384\' heads=\'1\' primary=\'yes\'/>\n <alias
name=\'video0\'/>\n <address type=\'pci\'
domain=\'0x0000\' bus=\'0x00\' slot=\'0x02\'
function=\'0x0\'/>\n </video>\n <memballoon
model=\'virtio\'>\n <alias name=\'balloon0\'/>\n
<address type=\'pci\' domain=\'0x0000\' bus=\'0x00\'
slot=\'0x06\' function=\'0x0\'/>\n </memballoon>\n <rng
model=\'virtio\'>\n <backend
model=\'random\'>/dev/urandom</backend>\n <alias
name=\'rng0\'/>\n <address type=\'pci\'
domain=\'0x0000\' bus=\'0x00\' slot=\'0x07\'
function=\'0x0\'/>\n </rng>\n </devices>\n <seclabel
type=\'none\' model=\'none\'/>\n <seclabel type=\'dynamic\'
model=\'dac\' relabel=\'yes\'>\n
<label>+265:+107</label>\n <imagelabel>+265:+107</imagelabel>\n
</seclabel>\n</domain>\n', u'memSize': 1024,
u'smpCoresPerSocket': u'1', u'vmName': u'lnx16',
u'nice': u'0', u'username': u'(unknown)',
u'maxMemSize': 409
6, u'bootMenuEnable': u'false', u'smpThreadsPerCore':
u'1', u'smartcardEnable': u'false', u'clientIp':
u'', u'guestAgentAPIVersion': 3, u'kvmEnable': u'true',
u'pitReinjection': u'false', u'displayNetwork':
u'classepublic', u'devices': [{u'target': 1048576,
u'alias': u'balloon0', u'specParams': {u'model':
u'virtio'}, u'deviceId': u'ee3ebcbf-08d9-481e-907c-efe32b8ee869',
u'address': {u'function': u'0x0', u'bus': u'0x00',
u'domain': u'0x0000', u'type': u'pci', u'slot':
u'0x06'}, u'device': u'memballoon', u'type':
u'balloon'}, {u'alias': u'rng0', u'specParams':
{u'source': u'urandom'}, u'deviceId':
u'0c522e52-a4c9-4bd9-a367-7b6fddedc931', u'address': {u'slot':
u'0x07', u'bus': u'0x00', u'domain': u'0x0000',
u'type': u'pci', u'function': u'0x0'}, u'device':
u'virtio', u'model': u'virtio', u'type': u'rng'},
{u'index': u'0', u'alias': u'usb', u'specParams':
{}, u'deviceId': u'3d4cbbda-4aa6-424e-95c6-bfae37e466b7',
u'address': {u'slot': u'0x01', u'bus': u'0x00',
u'domain': u'0x0000
', u'type': u'pci', u'function': u'0x2'},
u'device': u'usb', u'model': u'piix3-uhci',
u'type': u'controller'}, {u'index': u'0',
u'alias': u'scsi0', u'specParams': {}, u'deviceId':
u'0db369d1-81d8-4534-8437-105b851670c5', u'address': {u'slot':
u'0x04', u'bus': u'0x00', u'domain': u'0x0000',
u'type': u'pci', u'function': u'0x0'}, u'device':
u'scsi', u'model': u'virtio-scsi', u'type':
u'controller'}, {u'alias': u'virtio-serial0',
u'specParams': {}, u'deviceId':
u'553f9e1c-d811-4cb6-82bc-d567cd8e50bd', u'address': {u'slot':
u'0x05', u'bus': u'0x00', u'domain': u'0x0000',
u'type': u'pci', u'function': u'0x0'}, u'device':
u'virtio-serial', u'type': u'controller'}, {u'alias':
u'video0', u'specParams': {u'vram': u'8192',
u'vgamem': u'16384', u'heads': u'1', u'ram':
u'65536'}, u'deviceId': u'2ece7a34-22ec-49bc-b48c-df4660d13d71',
u'address': {u'slot': u'0x02', u'bus': u'0x00',
u'domain': u'0x0000', u'type': u'pci',
u'function': u'0x0'}, u'device': u'qxl', u'type':
u'video'}, {u'devic
e': u'spice', u'specParams': {u'fileTransferEnable':
u'true', u'spiceSecureChannels':
u'smain,sinputs,scursor,splayback,srecord,sdisplay,ssmartcard,susbredir',
u'displayNetwork': u'classepublic', u'displayIp':
u'128.84.46.164', u'copyPasteEnable': u'true'}, u'type':
u'graphics', u'deviceId': u'a7076b55-ddbb-4932-aca4-c567d9576c54',
u'tlsPort': u'5900'}, {u'nicModel': u'pv',
u'macAddr': u'00:1a:4a:16:01:51', u'linkActive': True,
u'network': u'classepublic', u'alias': u'net0',
u'filter': u'vdsm-no-mac-spoofing', u'specParams':
{u'inbound': {}, u'outbound': {}}, u'deviceId':
u'5110646a-b5d4-4b62-8188-b18cec8c15df', u'address': {u'slot':
u'0x03', u'bus': u'0x00', u'domain': u'0x0000',
u'type': u'pci', u'function': u'0x0'}, u'device':
u'bridge', u'type': u'interface', u'name':
u'vnet0'}, {u'index': u'2', u'iface': u'ide',
u'name': u'hdc', u'alias': u'ide0-1-0',
u'specParams': {u'path': u''}, u'readonly':
u'True', u'deviceId': u'75deba88-b39d-478c-83d3-cc535b43bb28',
u'address': {
u'bus': u'1', u'controller': u'0', u'type':
u'drive', u'target': u'0', u'unit': u'0'},
u'device': u'cdrom', u'shared': u'false', u'path':
u'', u'type': u'disk'}, {u'poolID':
u'00000001-0001-0001-0001-000000000311', u'volumeInfo':
{u'domainID': u'016ceee8-9117-4e8a-b611-f58f6763a098', u'volType':
u'path', u'leaseOffset': 111149056, u'volumeID':
u'67b12700-5faf-49b2-af40-6178023b3794', u'leasePath':
u'/dev/016ceee8-9117-4e8a-b611-f58f6763a098/leases', u'imageID':
u'21d18729-279f-435b-90ab-a2995f041bc6', u'path':
u'/rhev/data-center/mnt/blockSD/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49b2-af40-6178023b3794'},
u'index': 0, u'iface': u'scsi', u'apparentsize':
u'107374182400', u'alias': u'scsi0-0-0-0', u'imageID':
u'21d18729-279f-435b-90ab-a2995f041bc6', u'readonly': u'False',
u'shared': u'false', u'truesize': u'107374182400',
u'type': u'disk', u'domainID':
u'016ceee8-9117-4e8a-b611-f58f6763a098', u'reqsize': u'0',
u'format':
u'raw', u'deviceId': u'21d18729-279f-435b-90ab-a2995f041bc6',
u'address': {u'bus': u'0', u'controller': u'0',
u'type': u'drive', u'target': u'0', u'unit':
u'0'}, u'device': u'disk', u'path':
u'/rhev/data-center/00000001-0001-0001-0001-000000000311/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49b2-af40-6178023b3794',
u'propagateErrors': u'off', u'optional': u'false',
u'name': u'sda', u'bootOrder': u'1', u'volumeID':
u'67b12700-5faf-49b2-af40-6178023b3794', u'specParams': {},
u'discard': False, u'volumeChain': [{u'domainID':
u'016ceee8-9117-4e8a-b611-f58f6763a098', u'volType': u'path',
u'leaseOffset': 111149056, u'volumeID':
u'67b12700-5faf-49b2-af40-6178023b3794', u'leasePath':
u'/dev/016ceee8-9117-4e8a-b611-f58f6763a098/leases', u'imageID':
u'21d18729-279f-435b-90ab-a2995f041bc6', u'path':
u'/rhev/data-center/mnt/blockSD/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49b2-af40-6
178023b3794'}]}, {u'device': u'ide', u'alias':
u'ide', u'type': u'controller', u'address':
{u'slot': u'0x01', u'bus': u'0x00', u'domain':
u'0x0000', u'type': u'pci', u'function': u'0x1'}},
{u'device': u'unix', u'alias': u'channel0',
u'type': u'channel', u'address': {u'bus': u'0',
u'controller': u'0', u'type': u'virtio-serial',
u'port': u'1'}}, {u'device': u'unix', u'alias':
u'channel1', u'type': u'channel', u'address':
{u'bus': u'0', u'controller': u'0', u'type':
u'virtio-serial', u'port': u'2'}}, {u'device':
u'spicevmc', u'alias': u'channel2', u'type':
u'channel', u'address': {u'bus': u'0',
u'controller': u'0', u'type': u'virtio-serial',
u'port': u'3'}}], u'memGuaranteedSize': 1024, u'status':
u'Up', u'maxVCpus': u'16', u'guestIPs': u'128.84.46.16
192.168.122.1', u'statusTime': u'4528256170', u'maxMemSlots':
16}, False) kwargs={} (api:37)
2017-03-27 10:57:02,776-0400 INFO (jsonrpc/4) [vdsm.api] FINISH __init__ return=None
(api:43)
2017-03-27 10:57:02,777-0400 INFO (vm/cf9c5dbf) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') VM wrapper has started (vm:1915)
2017-03-27 10:57:02,778-0400 INFO (vm/cf9c5dbf) [dispatcher] Run and protect:
getVolumeSize(sdUUID=u'016ceee8-9117-4e8a-b611-f58f6763a098',
spUUID=u'00000001-0001-0001-0001-000000000311',
imgUUID=u'21d18729-279f-435b-90ab-a2995f041bc6',
volUUID=u'67b12700-5faf-49b2-af40-6178023b3794', options=None) (logUtils:51)
2017-03-27 10:57:02,809-0400 INFO (vm/cf9c5dbf) [dispatcher] Run and protect:
getVolumeSize, Return response: {'truesize': '107374182400',
'apparentsize': '107374182400'} (logUtils:54)
2017-03-27 10:57:02,809-0400 INFO (vm/cf9c5dbf) [vds] prepared volume path:
(clientIF:374)
2017-03-27 10:57:02,810-0400 INFO (vm/cf9c5dbf) [dispatcher] Run and protect:
prepareImage(sdUUID=u'016ceee8-9117-4e8a-b611-f58f6763a098',
spUUID=u'00000001-0001-0001-0001-000000000311',
imgUUID=u'21d18729-279f-435b-90ab-a2995f041bc6',
leafUUID=u'67b12700-5faf-49b2-af40-6178023b3794', allowIllegal=False)
(logUtils:51)
2017-03-27 10:57:02,859-0400 INFO (vm/cf9c5dbf) [storage.LVM] Activating lvs:
vg=016ceee8-9117-4e8a-b611-f58f6763a098
lvs=['67b12700-5faf-49b2-af40-6178023b3794'] (lvm:1289)
2017-03-27 10:57:02,947-0400 INFO (vm/cf9c5dbf) [storage.LVM] Refreshing lvs:
vg=016ceee8-9117-4e8a-b611-f58f6763a098 lvs=['leases'] (lvm:1285)
2017-03-27 10:57:02,998-0400 INFO (vm/cf9c5dbf) [dispatcher] Run and protect:
prepareImage, Return response: {'info': {'domainID':
u'016ceee8-9117-4e8a-b611-f58f6763a098', 'volType': 'path',
'leaseOffset': 111149056, 'path':
u'/rhev/data-center/mnt/blockSD/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49b2-af40-6178023b3794',
'volumeID': '67b12700-5faf-49b2-af40-6178023b3794', 'leasePath':
'/dev/016ceee8-9117-4e8a-b611-f58f6763a098/leases', 'imageID':
u'21d18729-279f-435b-90ab-a2995f041bc6'}, 'path':
u'/rhev/data-center/00000001-0001-0001-0001-000000000311/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49b2-af40-6178023b3794',
'imgVolumesInfo': [{'domainID':
u'016ceee8-9117-4e8a-b611-f58f6763a098', 'volType': 'path',
'leaseOffset': 111149056, 'path':
u'/rhev/data-center/mnt/blockSD/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49b2
-af40-6178023b3794', 'volumeID':
'67b12700-5faf-49b2-af40-6178023b3794', 'leasePath':
'/dev/016ceee8-9117-4e8a-b611-f58f6763a098/leases', 'imageID':
u'21d18729-279f-435b-90ab-a2995f041bc6'}]} (logUtils:54)
2017-03-27 10:57:02,998-0400 INFO (vm/cf9c5dbf) [vds] prepared volume path:
/rhev/data-center/00000001-0001-0001-0001-000000000311/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49b2-af40-6178023b3794
(clientIF:374)
2017-03-27 10:57:03,026-0400 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call
VM.migrationCreate succeeded in 0.27 seconds (__init__:515)
2017-03-27 10:57:03,225-0400 INFO (jsonrpc/7) [vdsm.api] START destroy
args=(<virt.vm.Vm object at 0x4132e50>, 1) kwargs={} (api:37)
2017-03-27 10:57:03,225-0400 INFO (jsonrpc/7) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Release VM resources (vm:4199)
2017-03-27 10:57:03,226-0400 INFO (jsonrpc/7) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Stopping connection
(guestagent:430)
2017-03-27 10:57:03,227-0400 INFO (jsonrpc/7) [dispatcher] Run and protect:
teardownImage(sdUUID=u'016ceee8-9117-4e8a-b611-f58f6763a098',
spUUID=u'00000001-0001-0001-0001-000000000311',
imgUUID=u'21d18729-279f-435b-90ab-a2995f041bc6', volUUID=None) (logUtils:51)
2017-03-27 10:57:03,237-0400 INFO (vm/cf9c5dbf) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Changed state to Down: VM destroyed
during the startup (code=10) (vm:1207)
2017-03-27 10:57:03,238-0400 INFO (vm/cf9c5dbf) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Stopping connection
(guestagent:430)
2017-03-27 10:57:03,260-0400 INFO (jsonrpc/7) [storage.LVM] Deactivating lvs:
vg=016ceee8-9117-4e8a-b611-f58f6763a098
lvs=['67b12700-5faf-49b2-af40-6178023b3794'] (lvm:1297)
2017-03-27 10:57:03,311-0400 INFO (jsonrpc/7) [dispatcher] Run and protect:
teardownImage, Return response: None (logUtils:54)
2017-03-27 10:57:03,312-0400 INFO (jsonrpc/7) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Stopping connection
(guestagent:430)
2017-03-27 10:57:03,312-0400 WARN (jsonrpc/7) [root] File:
/var/lib/libvirt/qemu/channels/cf9c5dbf-3924-47c6-b323-22ac90a1f682.com.redhat.rhevm.vdsm
already removed (utils:120)
2017-03-27 10:57:03,312-0400 WARN (jsonrpc/7) [root] File:
/var/lib/libvirt/qemu/channels/cf9c5dbf-3924-47c6-b323-22ac90a1f682.org.qemu.guest_agent.0
already removed (utils:120)
2017-03-27 10:57:03,313-0400 WARN (jsonrpc/7) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') timestamp already removed from stats
cache (vm:1729)
2017-03-27 10:57:03,313-0400 INFO (jsonrpc/7) [dispatcher] Run and protect:
inappropriateDevices(thiefId=u'cf9c5dbf-3924-47c6-b323-22ac90a1f682')
(logUtils:51)
2017-03-27 10:57:03,314-0400 INFO (jsonrpc/7) [dispatcher] Run and protect:
inappropriateDevices, Return response: None (logUtils:54)
2017-03-27 10:57:03,314-0400 INFO (jsonrpc/7) [vdsm.api] FINISH destroy
return={'status': {'message': 'Done', 'code': 0}}
(api:43)
2017-03-27 10:57:03,315-0400 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call
VM.destroy succeeded in 0.09 seconds (__init__:515)
2017-03-27 10:57:03,937-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::1:53024 (protocoldetector:72)
2017-03-27 10:57:03,943-0400 INFO (Reactor thread) [ProtocolDetector.Detector] Detected
protocol stomp from ::1:53024 (protocoldetector:127)
2017-03-27 10:57:03,944-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
2017-03-27 10:57:03,945-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
2017-03-27 10:57:04,084-0400 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.00 seconds (__init__:515)
2017-03-27 10:57:04,086-0400 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call
VM.getStats failed (error 1) in 0.00 seconds (__init__:515)
2017-03-27 10:57:04,767-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::1:53026 (protocoldetector:72)
2017-03-27 10:57:04,772-0400 INFO (Reactor thread) [ProtocolDetector.Detector] Detected
protocol stomp from ::1:53026 (protocoldetector:127)
2017-03-27 10:57:04,773-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
2017-03-27 10:57:04,774-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
2017-03-27 10:57:04,913-0400 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.00 seconds (__init__:515)
2017-03-27 10:57:05,126-0400 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call
Host.getCapabilities succeeded in 0.22 seconds (__init__:515)
2017-03-27 10:57:06,040-0400 INFO (periodic/0) [dispatcher] Run and protect:
repoStats(options=None) (logUtils:51)
2017-03-27 10:57:06,040-0400 INFO (periodic/0) [dispatcher] Run and protect: repoStats,
Return response: {u'016ceee8-9117-4e8a-b611-f58f6763a098': {'code': 0,
'actual': True, 'version': 4, 'acquired': True, 'delay':
'0.000426872', 'lastCheck': '4.6', 'valid': True},
u'2438f819-e7f5-4bb1-ad0d-5349fa371e6e': {'code': 0, 'actual':
True, 'version': 0, 'acquired': True, 'delay':
'0.000387272', 'lastCheck': '4.5', 'valid': True},
u'48d4f45d-0bdd-4f4a-90b6-35efe2da935a': {'code': 0, 'actual':
True, 'version': 4, 'acquired': True, 'delay':
'0.000353399', 'lastCheck': '0.5', 'valid': True}}
(logUtils:54)
2017-03-27 10:57:08,170-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::1:53028 (protocoldetector:72)
2017-03-27 10:57:08,176-0400 INFO (Reactor thread) [ProtocolDetector.Detector] Detected
protocol stomp from ::1:53028 (protocoldetector:127)
2017-03-27 10:57:08,176-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
2017-03-27 10:57:08,177-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
2017-03-27 10:57:08,321-0400 INFO (jsonrpc/6) [dispatcher] Run and protect:
repoStats(options=None) (logUtils:51)
2017-03-27 10:57:08,322-0400 INFO (jsonrpc/6) [dispatcher] Run and protect: repoStats,
Return response: {u'016ceee8-9117-4e8a-b611-f58f6763a098': {'code': 0,
'actual': True, 'version': 4, 'acquired': True, 'delay':
'0.000426872', 'lastCheck': '2.1', 'valid': True},
u'2438f819-e7f5-4bb1-ad0d-5349fa371e6e': {'code': 0, 'actual':
True, 'version': 0, 'acquired': True, 'delay':
'0.000387272', 'lastCheck': '1.2', 'valid': True},
u'48d4f45d-0bdd-4f4a-90b6-35efe2da935a': {'code': 0, 'actual':
True, 'version': 4, 'acquired': True, 'delay':
'0.000353399', 'lastCheck': '2.8', 'valid': True}}
(logUtils:54)
2017-03-27 10:57:08,324-0400 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.00 seconds (__init__:515)
2017-03-27 10:57:08,327-0400 INFO (jsonrpc/4) [dispatcher] Run and protect:
repoStats(options=None) (logUtils:51)
2017-03-27 10:57:08,328-0400 INFO (jsonrpc/4) [dispatcher] Run and protect: repoStats,
Return response: {u'016ceee8-9117-4e8a-b611-f58f6763a098': {'code': 0,
'actual': True, 'version': 4, 'acquired': True, 'delay':
'0.000426872', 'lastCheck': '2.1', 'valid': True},
u'2438f819-e7f5-4bb1-ad0d-5349fa371e6e': {'code': 0, 'actual':
True, 'version': 0, 'acquired': True, 'delay':
'0.000387272', 'lastCheck': '1.2', 'valid': True},
u'48d4f45d-0bdd-4f4a-90b6-35efe2da935a': {'code': 0, 'actual':
True, 'version': 4, 'acquired': True, 'delay':
'0.000353399', 'lastCheck': '2.8', 'valid': True}}
(logUtils:54)
2017-03-27 10:57:08,619-0400 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call
Host.getStats succeeded in 0.29 seconds (__init__:515)
2017-03-27 10:57:08,625-0400 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call
Host.getStats succeeded in 0.30 seconds (__init__:515)
2017-03-27 10:57:08,848-0400 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call
Host.getAllVmStats succeeded in 0.00 seconds (__init__:515)
2017-03-27 10:57:09,370-0400 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call
Host.getAllVmStats succeeded in 0.00 seconds (__init__:515)
2017-03-27 10:57:10,927-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::1:53030 (protocoldetector:72)
2017-03-27 10:57:10,932-0400 INFO (Reactor thread) [ProtocolDetector.Detector] Detected
protocol stomp from ::1:53030 (protocoldetector:127)
2017-03-27 10:57:10,932-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
2017-03-27 10:57:10,933-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
2017-03-27 10:57:11,077-0400 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.00 seconds (__init__:515)
2017-03-27 10:57:11,080-0400 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call
VM.getStats failed (error 1) in 0.00 seconds (__init__:515)
2017-03-27 10:57:13,802-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::1:53034 (protocoldetector:72)
2017-03-27 10:57:13,808-0400 INFO (Reactor thread) [ProtocolDetector.Detector] Detected
protocol stomp from ::1:53034 (protocoldetector:127)
2017-03-27 10:57:13,809-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
2017-03-27 10:57:13,810-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
2017-03-27 10:57:14,015-0400 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.00 seconds (__init__:515)
2017-03-27 10:57:14,019-0400 INFO (jsonrpc/5) [dispatcher] Run and protect:
getImagesList(sdUUID=u'48d4f45d-0bdd-4f4a-90b6-35efe2da935a', options=None)
(logUtils:51)
2017-03-27 10:57:14,050-0400 INFO (jsonrpc/5) [dispatcher] Run and protect:
getImagesList, Return response: {'imageslist':
['a1d7f855-40d4-4f07-bf25-625284682b40',
'c61297c5-367b-4c98-a44b-de0318848a24',
'c2fc0c7c-596e-4011-8471-7f67408ea633',
'b2bb3396-a8fb-4ed7-89c7-4cc7d22aa26a',
'6d594f05-c2c2-4ef0-849f-28da46b8f87e',
'5fc548e6-51a8-44ee-a14b-92e0e924d7b8']} (logUtils:54)
2017-03-27 10:57:14,051-0400 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call
StorageDomain.getImages succeeded in 0.03 seconds (__init__:515)
2017-03-27 10:57:14,083-0400 INFO (jsonrpc/4) [dispatcher] Run and protect:
getVolumesList(sdUUID=u'48d4f45d-0bdd-4f4a-90b6-35efe2da935a',
spUUID=u'00000000-0000-0000-0000-000000000000',
imgUUID=u'a1d7f855-40d4-4f07-bf25-625284682b40', options=None) (logUtils:51)
...
_______________________________________________
Users mailing list
Users(a)ovirt.org
http://lists.ovirt.org/mailman/listinfo/users
9:35 p.m.
Thanks for following up on this. We need to put a little more effort on
Bug 1400952 - [RFE] Resolve listen IP for graphics attached to Open
vSwitch network
so that the hook is no longer needed.
Please let us know how oVirt+OvS is working for you!
On Wed, Mar 29, 2017 at 6:17 PM, Devin A. Bougie
<devin.bougie(a)cornell.edu> wrote:
Just incase anyone else runs into this, you need to set
"migration_ovs_hook_enabled=True" in vdsm.conf. It seems the vdsm.conf created
by "hosted-engine --deploy" did not list all of the options, so I overlooked
this one.
Thanks for all the help,
Devin
On Mar 27, 2017, at 11:10 AM, Devin A. Bougie <devin.bougie(a)cornell.edu> wrote:
> Hi, All. We have a new oVirt 4.1.1 cluster up with the OVS switch type. Everything
seems to be working great, except for live migration.
>
> I believe the red flag in vdsm.log on the source is:
> Cannot get interface MTU on 'vdsmbr_QwORbsw2': No such device
(migration:287)
>
> Which results from vdsm assigning an arbitrary bridge name to each ovs bridge.
>
> Please see below for more details on the bridges and excerpts from the logs. Any
help would be greatly appreciated.
>
> Many thanks,
> Devin
>
> SOURCE OVS BRIDGES:
> # ovs-vsctl show
> 6d96d9a5-e30d-455b-90c7-9e9632574695
> Bridge "vdsmbr_QwORbsw2"
> Port "vdsmbr_QwORbsw2"
> Interface "vdsmbr_QwORbsw2"
> type: internal
> Port "vnet0"
> Interface "vnet0"
> Port classepublic
> Interface classepublic
> type: internal
> Port "ens1f0"
> Interface "ens1f0"
> Bridge "vdsmbr_9P7ZYKWn"
> Port ovirtmgmt
> Interface ovirtmgmt
> type: internal
> Port "ens1f1"
> Interface "ens1f1"
> Port "vdsmbr_9P7ZYKWn"
> Interface "vdsmbr_9P7ZYKWn"
> type: internal
> ovs_version: "2.7.0"
>
> DESTINATION OVS BRIDGES:
> # ovs-vsctl show
> f66d765d-712a-4c81-b18e-da1acc9cfdde
> Bridge "vdsmbr_vdpp0dOd"
> Port "vdsmbr_vdpp0dOd"
> Interface "vdsmbr_vdpp0dOd"
> type: internal
> Port "ens1f0"
> Interface "ens1f0"
> Port classepublic
> Interface classepublic
> type: internal
> Bridge "vdsmbr_3sEwEKd1"
> Port "vdsmbr_3sEwEKd1"
> Interface "vdsmbr_3sEwEKd1"
> type: internal
> Port "ens1f1"
> Interface "ens1f1"
> Port ovirtmgmt
> Interface ovirtmgmt
> type: internal
> ovs_version: "2.7.0"
>
>
> SOURCE VDSM LOG:
> ...
> 2017-03-27 10:57:02,567-0400 INFO (jsonrpc/1) [vdsm.api] START migrate
args=(<virt.vm.Vm object at 0x3410810>, {u'incomingLimit': 2,
u'src': u'192.168.55.84', u'dstqemu': u'192.168.55.81',
u'autoConverge': u'false', u'tunneled': u'false',
u'enableGuestEvents': False, u'dst':
u'lnxvirt01-p55.classe.cornell.edu:54321', u'vmId':
u'cf9c5dbf-3924-47c6-b323-22ac90a1f682', u'abortOnError': u'true',
u'outgoingLimit': 2, u'compressed': u'false',
u'maxBandwidth': 5000, u'method': u'online', 'mode':
'remote'}) kwargs={} (api:37)
> 2017-03-27 10:57:02,570-0400 INFO (jsonrpc/1) [vdsm.api] FINISH migrate
return={'status': {'message': 'Migration in progress',
'code': 0}, 'progress': 0} (api:43)
> 2017-03-27 10:57:02,570-0400 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call
VM.migrate succeeded in 0.01 seconds (__init__:515)
> 2017-03-27 10:57:03,028-0400 INFO (migsrc/cf9c5dbf) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Creation of destination VM took: 0
seconds (migration:455)
> 2017-03-27 10:57:03,028-0400 INFO (migsrc/cf9c5dbf) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') starting migration to
qemu+tls://lnxvirt01-p55.classe.cornell.edu/system with miguri tcp://192.168.55.81
(migration:480)
> 2017-03-27 10:57:03,224-0400 ERROR (migsrc/cf9c5dbf) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Cannot get interface MTU on
'vdsmbr_QwORbsw2': No such device (migration:287)
> 2017-03-27 10:57:03,322-0400 ERROR (migsrc/cf9c5dbf) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Failed to migrate (migration:429)
> Traceback (most recent call last):
> File "/usr/lib/python2.7/site-packages/vdsm/virt/migration.py", line 411,
in run
> self._startUnderlyingMigration(time.time())
> File "/usr/lib/python2.7/site-packages/vdsm/virt/migration.py", line 489,
in _startUnderlyingMigration
> self._perform_with_downtime_thread(duri, muri)
> File "/usr/lib/python2.7/site-packages/vdsm/virt/migration.py", line 555,
in _perform_with_downtime_thread
> self._perform_migration(duri, muri)
> File "/usr/lib/python2.7/site-packages/vdsm/virt/migration.py", line 528,
in _perform_migration
> self._vm._dom.migrateToURI3(duri, params, flags)
> File "/usr/lib/python2.7/site-packages/vdsm/virt/virdomain.py", line 69,
in f
> ret = attr(*args, **kwargs)
> File "/usr/lib/python2.7/site-packages/vdsm/libvirtconnection.py", line
123, in wrapper
> ret = f(*args, **kwargs)
> File "/usr/lib/python2.7/site-packages/vdsm/utils.py", line 941, in
wrapper
> return func(inst, *args, **kwargs)
> File "/usr/lib64/python2.7/site-packages/libvirt.py", line 1939, in
migrateToURI3
> if ret == -1: raise libvirtError ('virDomainMigrateToURI3() failed',
dom=self)
> libvirtError: Cannot get interface MTU on 'vdsmbr_QwORbsw2': No such device
> 2017-03-27 10:57:03,435-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::1:33716 (protocoldetector:72)
> 2017-03-27 10:57:03,452-0400 INFO (Reactor thread) [ProtocolDetector.Detector]
Detected protocol stomp from ::1:33716 (protocoldetector:127)
> 2017-03-27 10:57:03,452-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
> 2017-03-27 10:57:03,456-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
> 2017-03-27 10:57:03,589-0400 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.01 seconds (__init__:515)
> 2017-03-27 10:57:03,592-0400 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call
VM.getStats failed (error 1) in 0.00 seconds (__init__:515)
> 2017-03-27 10:57:04,494-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::1:33718 (protocoldetector:72)
> 2017-03-27 10:57:04,500-0400 INFO (Reactor thread) [ProtocolDetector.Detector]
Detected protocol stomp from ::1:33718 (protocoldetector:127)
> 2017-03-27 10:57:04,500-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
> 2017-03-27 10:57:04,501-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
> 2017-03-27 10:57:04,559-0400 INFO (periodic/2) [dispatcher] Run and protect:
repoStats(options=None) (logUtils:51)
> 2017-03-27 10:57:04,559-0400 INFO (periodic/2) [dispatcher] Run and protect:
repoStats, Return response: {u'016ceee8-9117-4e8a-b611-f58f6763a098':
{'code': 0, 'actual': True, 'version': 4, 'acquired':
True, 'delay': '0.000262909', 'lastCheck': '5.3',
'valid': True}, u'2438f819-e7f5-4bb1-ad0d-5349fa371e6e': {'code':
0, 'actual': True, 'version': 0, 'acquired': True,
'delay': '0.000342019', 'lastCheck': '5.3',
'valid': True}, u'48d4f45d-0bdd-4f4a-90b6-35efe2da935a': {'code':
0, 'actual': True, 'version': 4, 'acquired': True,
'delay': '0.000239611', 'lastCheck': '0.6',
'valid': True}} (logUtils:54)
> 2017-03-27 10:57:04,669-0400 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.01 seconds (__init__:515)
> 2017-03-27 10:57:04,684-0400 INFO (jsonrpc/0) [dispatcher] Run and protect:
repoStats(options=None) (logUtils:51)
> 2017-03-27 10:57:04,684-0400 INFO (jsonrpc/0) [dispatcher] Run and protect:
repoStats, Return response: {u'016ceee8-9117-4e8a-b611-f58f6763a098':
{'code': 0, 'actual': True, 'version': 4, 'acquired':
True, 'delay': '0.000262909', 'lastCheck': '5.4',
'valid': True}, u'2438f819-e7f5-4bb1-ad0d-5349fa371e6e': {'code':
0, 'actual': True, 'version': 0, 'acquired': True,
'delay': '0.000342019', 'lastCheck': '5.4',
'valid': True}, u'48d4f45d-0bdd-4f4a-90b6-35efe2da935a': {'code':
0, 'actual': True, 'version': 4, 'acquired': True,
'delay': '0.000239611', 'lastCheck': '0.7',
'valid': True}} (logUtils:54)
> 2017-03-27 10:57:04,770-0400 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call
Host.getStats succeeded in 0.09 seconds (__init__:515)
> ...
>
> DESTINATION VDSM LOG:
> ...
> 2017-03-27 10:57:01,515-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
> 2017-03-27 10:57:01,652-0400 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.00 seconds (__init__:515)
> 2017-03-27 10:57:02,572-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::ffff:192.168.55.84:44582 (protocoldetector:72)
> 2017-03-27 10:57:02,577-0400 INFO (Reactor thread) [ProtocolDetector.Detector]
Detected protocol stomp from ::ffff:192.168.55.84:44582 (protocoldetector:127)
> 2017-03-27 10:57:02,578-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
> 2017-03-27 10:57:02,579-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
> 2017-03-27 10:57:02,748-0400 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call
Host.ping succeeded in 0.00 seconds (__init__:515)
> 2017-03-27 10:57:02,774-0400 INFO (jsonrpc/4) [vdsm.api] START __init__
args=(<virt.vm.Vm object at 0x4132e50>, <clientIF.clientIF object at
0x7f453c01e650>, {u'guestFQDN': u'lnx16.classe.cornell.edu',
u'acpiEnable': u'true', u'emulatedMachine':
u'pc-i440fx-rhel7.3.0', u'afterMigrationStatus': u'',
u'enableGuestEvents': False, u'vmId':
u'cf9c5dbf-3924-47c6-b323-22ac90a1f682', u'elapsedTimeOffset':
1321.2906959056854, u'guestDiskMapping':
{u'0QEMU_QEMU_HARDDISK_21d18729-279f-435b-9': {u'name':
u'/dev/sda'}, u'QEMU_DVD-ROM_QM00003': {u'name':
u'/dev/sr0'}}, u'transparentHugePages': u'true',
u'timeOffset': u'0', u'cpuType': u'Haswell-noTSX',
u'custom': {u'device_9d15a0c8-a122-43b9-89c2-3ad530ebc985':
u"VmDevice:{id='VmDeviceId:{deviceId='9d15a0c8-a122-43b9-89c2-3ad530ebc985',
vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682'}', device='ide',
type='CONTROLLER', bootOrder='0', specParams='[]',
address='{slot=0x01, bus=0x00, domain=0x0000, type=pci, function=0x1}',
managed='false', pl
ug
> ged='true', readOnly='false', deviceAlias='ide',
customProperties='[]', snapshotId='null', logicalName='null',
hostDevice='null'}",
u'device_9d15a0c8-a122-43b9-89c2-3ad530ebc985device_0bbb6377-22db-4d04-a501-64de81bab622device_758565a5-4378-4412-8ec1-2df7bb231c14device_7ead63f7-b119-434d-a2f7-081fd06229e4':
u"VmDevice:{id='VmDeviceId:{deviceId='7ead63f7-b119-434d-a2f7-081fd06229e4',
vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682'}', device='spicevmc',
type='CHANNEL', bootOrder='0', specParams='[]',
address='{bus=0, controller=0, type=virtio-serial, port=3}',
managed='false', plugged='true', readOnly='false',
deviceAlias='channel2', customProperties='[]', snapshotId='null',
logicalName='null', hostDevice='null'}",
u'device_9d15a0c8-a122-43b9-89c2-3ad530ebc985device_0bbb6377-22db-4d04-a501-64de81bab622':
u"VmDevice:{id='VmDeviceId:{deviceId='0bbb6377-22db-4d04-a501-64de81bab622',
vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682'}', device='unix',
type='CHANNEL', bootOrder='0', specPara
m
> s='[]', address='{bus=0, controller=0, type=virtio-serial, port=2}',
managed='false', plugged='true', readOnly='false',
deviceAlias='channel1', customProperties='[]', snapshotId='null',
logicalName='null', hostDevice='null'}",
u'device_9d15a0c8-a122-43b9-89c2-3ad530ebc985device_0bbb6377-22db-4d04-a501-64de81bab622device_758565a5-4378-4412-8ec1-2df7bb231c14':
u"VmDevice:{id='VmDeviceId:{deviceId='758565a5-4378-4412-8ec1-2df7bb231c14',
vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682'}', device='unix',
type='CHANNEL', bootOrder='0', specParams='[]',
address='{bus=0, controller=0, type=virtio-serial, port=1}',
managed='false', plugged='true', readOnly='false',
deviceAlias='channel0', customProperties='[]', snapshotId='null',
logicalName='null', hostDevice='null'}"}, u'pauseCode':
u'NOERR', u'migrationDest': u'libvirt', u'guestNumaNodes':
[{u'nodeIndex': 0, u'cpus': u'0', u'memory':
u'1024'}], u'display': u'qxl', u'smp': u'1',
u'vmType': u'kvm', u'_srcDomXML': u'<domain
type=\'kvm\'
i
> d=\'4\'>\n <name>lnx16</name>\n
<uuid>cf9c5dbf-3924-47c6-b323-22ac90a1f682</uuid>\n <metadata
xmlns:ovirt="http://ovirt.org/vm/tune/1.0">\n <ovirt:qos/>\n
</metadata>\n <maxMemory slots=\'16\'
unit=\'KiB\'>4194304</maxMemory>\n <memory
unit=\'KiB\'>1048576</memory>\n <currentMemory
unit=\'KiB\'>1048576</currentMemory>\n <vcpu
placement=\'static\' current=\'1\'>16</vcpu>\n <cputune>\n
<shares>1020</shares>\n </cputune>\n <resource>\n
<partition>/machine</partition>\n </resource>\n <sysinfo
type=\'smbios\'>\n <system>\n <entry
name=\'manufacturer\'>oVirt</entry>\n <entry
name=\'product\'>oVirt Node</entry>\n <entry
name=\'version\'>7.3-4.sl7</entry>\n <entry
name=\'serial\'>38C15485-D270-11E6-8752-0090FAEDD7C8</entry>\n
<entry name=\'uuid\'>cf9c5dbf-3924-47c6-b323-22ac90a1f682</entry>\n
</system>\n </sysinfo>\n <os>\n <type arch=\'x86_64\'
machine=\'pc-i440fx-rhel7.3.0\'>hvm</type>\n <smbios
mode=\'sysinfo\'/>\n </
o
> s>\n <features>\n <acpi/>\n </features>\n <cpu
mode=\'custom\' match=\'exact\'>\n <model
fallback=\'allow\'>Haswell-noTSX</model>\n <topology
sockets=\'16\' cores=\'1\' threads=\'1\'/>\n <numa>\n
<cell id=\'0\' cpus=\'0\' memory=\'1048576\'
unit=\'KiB\'/>\n </numa>\n </cpu>\n <clock
offset=\'variable\' adjustment=\'0\' basis=\'utc\'>\n
<timer name=\'rtc\' tickpolicy=\'catchup\'/>\n <timer
name=\'pit\' tickpolicy=\'delay\'/>\n <timer
name=\'hpet\' present=\'no\'/>\n </clock>\n
<on_poweroff>destroy</on_poweroff>\n
<on_reboot>restart</on_reboot>\n <on_crash>destroy</on_crash>\n
<devices>\n <emulator>/usr/libexec/qemu-kvm</emulator>\n <disk
type=\'file\' device=\'cdrom\'>\n <driver name=\'qemu\'
type=\'raw\'/>\n <source startupPolicy=\'optional\'/>\n
<backingStore/>\n <target dev=\'hdc\' bus=\'ide\'/>\n
<readonly/>\n <alias name=\'ide0-1-0\'/>\n <address
type=\'drive\' controller=\'0\' bus=\'1\' tar
g
> et=\'0\' unit=\'0\'/>\n </disk>\n <disk
type=\'block\' device=\'disk\' snapshot=\'no\'>\n
<driver name=\'qemu\' type=\'raw\' cache=\'none\'
error_policy=\'stop\' io=\'native\'/>\n <source
dev=\'/rhev/data-center/00000001-0001-0001-0001-000000000311/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49b2-af40-6178023b3794\'/>\n
<backingStore/>\n <target dev=\'sda\'
bus=\'scsi\'/>\n
<serial>21d18729-279f-435b-90ab-a2995f041bc6</serial>\n <boot
order=\'1\'/>\n <alias name=\'scsi0-0-0-0\'/>\n
<address type=\'drive\' controller=\'0\' bus=\'0\'
target=\'0\' unit=\'0\'/>\n </disk>\n <controller
type=\'usb\' index=\'0\' model=\'piix3-uhci\'>\n <alias
name=\'usb\'/>\n <address type=\'pci\'
domain=\'0x0000\' bus=\'0x00\' slot=\'0x01\'
function=\'0x2\'/>\n </controller>\n <controller
type=\'scsi\' index=\'0\' model=\'virtio-scsi\'>\n
<alias name=\'scsi0\'/>\n <addr
e
> ss type=\'pci\' domain=\'0x0000\' bus=\'0x00\'
slot=\'0x04\' function=\'0x0\'/>\n </controller>\n
<controller type=\'virtio-serial\' index=\'0\'
ports=\'16\'>\n <alias name=\'virtio-serial0\'/>\n
<address type=\'pci\' domain=\'0x0000\' bus=\'0x00\'
slot=\'0x05\' function=\'0x0\'/>\n </controller>\n
<controller type=\'pci\' index=\'0\' model=\'pci-root\'>\n
<alias name=\'pci.0\'/>\n </controller>\n <controller
type=\'ide\' index=\'0\'>\n <alias name=\'ide\'/>\n
<address type=\'pci\' domain=\'0x0000\' bus=\'0x00\'
slot=\'0x01\' function=\'0x1\'/>\n </controller>\n
<interface type=\'bridge\'>\n <mac
address=\'00:1a:4a:16:01:51\'/>\n <source
bridge=\'vdsmbr_QwORbsw2\'/>\n <virtualport
type=\'openvswitch\'>\n <parameters
interfaceid=\'3a24e764-32c8-4a2e-8dfd-02a776c3bef6\'/>\n
</virtualport>\n <target dev=\'vnet0\'/>\n <model
type=\'virtio\'/>\n <filterref
filter=\'vdsm-no-mac-spoofing\'/>
\
> n <link state=\'up\'/>\n <alias
name=\'net0\'/>\n <address type=\'pci\'
domain=\'0x0000\' bus=\'0x00\' slot=\'0x03\'
function=\'0x0\'/>\n </interface>\n <channel
type=\'unix\'>\n <source mode=\'bind\'
path=\'/var/lib/libvirt/qemu/channels/cf9c5dbf-3924-47c6-b323-22ac90a1f682.com.redhat.rhevm.vdsm\'/>\n
<target type=\'virtio\' name=\'com.redhat.rhevm.vdsm\'
state=\'connected\'/>\n <alias name=\'channel0\'/>\n
<address type=\'virtio-serial\' controller=\'0\' bus=\'0\'
port=\'1\'/>\n </channel>\n <channel type=\'unix\'>\n
<source mode=\'bind\'
path=\'/var/lib/libvirt/qemu/channels/cf9c5dbf-3924-47c6-b323-22ac90a1f682.org.qemu.guest_agent.0\'/>\n
<target type=\'virtio\' name=\'org.qemu.guest_agent.0\'
state=\'connected\'/>\n <alias name=\'channel1\'/>\n
<address type=\'virtio-serial\' controller=\'0\' bus=\'0\'
port=\'2\'/>\n </channel>\n <channel
type=\'spicevmc\'>\n <target type=\'virtio\' name=\'co
m
> .redhat.spice.0\' state=\'connected\'/>\n <alias
name=\'channel2\'/>\n <address type=\'virtio-serial\'
controller=\'0\' bus=\'0\' port=\'3\'/>\n </channel>\n
<input type=\'mouse\' bus=\'ps2\'>\n <alias
name=\'input0\'/>\n </input>\n <input type=\'keyboard\'
bus=\'ps2\'>\n <alias name=\'input1\'/>\n
</input>\n <graphics type=\'spice\' tlsPort=\'5900\'
autoport=\'yes\' listen=\'128.84.46.164\' defaultMode=\'secure\'
passwdValidTo=\'2017-03-27T14:37:09\'>\n <listen
type=\'address\' address=\'128.84.46.164\'/>\n <channel
name=\'main\' mode=\'secure\'/>\n <channel
name=\'display\' mode=\'secure\'/>\n <channel
name=\'inputs\' mode=\'secure\'/>\n <channel
name=\'cursor\' mode=\'secure\'/>\n <channel
name=\'playback\' mode=\'secure\'/>\n <channel
name=\'record\' mode=\'secure\'/>\n <channel
name=\'smartcard\' mode=\'secure\'/>\n <channel
name=\'usbredir\' mode=\'secure\'/>\n </graphics>\n
<vide
o
>> \n <model type=\'qxl\' ram=\'65536\'
vram=\'8192\' vgamem=\'16384\' heads=\'1\'
primary=\'yes\'/>\n <alias name=\'video0\'/>\n
<address type=\'pci\' domain=\'0x0000\' bus=\'0x00\'
slot=\'0x02\' function=\'0x0\'/>\n </video>\n
<memballoon model=\'virtio\'>\n <alias
name=\'balloon0\'/>\n <address type=\'pci\'
domain=\'0x0000\' bus=\'0x00\' slot=\'0x06\'
function=\'0x0\'/>\n </memballoon>\n <rng
model=\'virtio\'>\n <backend
model=\'random\'>/dev/urandom</backend>\n <alias
name=\'rng0\'/>\n <address type=\'pci\'
domain=\'0x0000\' bus=\'0x00\' slot=\'0x07\'
function=\'0x0\'/>\n </rng>\n </devices>\n <seclabel
type=\'none\' model=\'none\'/>\n <seclabel type=\'dynamic\'
model=\'dac\' relabel=\'yes\'>\n
<label>+265:+107</label>\n <imagelabel>+265:+107</imagelabel>\n
</seclabel>\n</domain>\n', u'memSize': 1024,
u'smpCoresPerSocket': u'1', u'vmName': u'lnx16',
u'nice': u'0', u'username': u'(unknown)',
u'maxMemSize': 40
9
> 6, u'bootMenuEnable': u'false', u'smpThreadsPerCore':
u'1', u'smartcardEnable': u'false', u'clientIp':
u'', u'guestAgentAPIVersion': 3, u'kvmEnable': u'true',
u'pitReinjection': u'false', u'displayNetwork':
u'classepublic', u'devices': [{u'target': 1048576,
u'alias': u'balloon0', u'specParams': {u'model':
u'virtio'}, u'deviceId': u'ee3ebcbf-08d9-481e-907c-efe32b8ee869',
u'address': {u'function': u'0x0', u'bus': u'0x00',
u'domain': u'0x0000', u'type': u'pci', u'slot':
u'0x06'}, u'device': u'memballoon', u'type':
u'balloon'}, {u'alias': u'rng0', u'specParams':
{u'source': u'urandom'}, u'deviceId':
u'0c522e52-a4c9-4bd9-a367-7b6fddedc931', u'address': {u'slot':
u'0x07', u'bus': u'0x00', u'domain': u'0x0000',
u'type': u'pci', u'function': u'0x0'}, u'device':
u'virtio', u'model': u'virtio', u'type': u'rng'},
{u'index': u'0', u'alias': u'usb', u'specParams':
{}, u'deviceId': u'3d4cbbda-4aa6-424e-95c6-bfae37e466b7',
u'address': {u'slot': u'0x01', u'bus': u'0x00',
u'domain': u'0x000
0
> ', u'type': u'pci', u'function': u'0x2'},
u'device': u'usb', u'model': u'piix3-uhci',
u'type': u'controller'}, {u'index': u'0',
u'alias': u'scsi0', u'specParams': {}, u'deviceId':
u'0db369d1-81d8-4534-8437-105b851670c5', u'address': {u'slot':
u'0x04', u'bus': u'0x00', u'domain': u'0x0000',
u'type': u'pci', u'function': u'0x0'}, u'device':
u'scsi', u'model': u'virtio-scsi', u'type':
u'controller'}, {u'alias': u'virtio-serial0',
u'specParams': {}, u'deviceId':
u'553f9e1c-d811-4cb6-82bc-d567cd8e50bd', u'address': {u'slot':
u'0x05', u'bus': u'0x00', u'domain': u'0x0000',
u'type': u'pci', u'function': u'0x0'}, u'device':
u'virtio-serial', u'type': u'controller'}, {u'alias':
u'video0', u'specParams': {u'vram': u'8192',
u'vgamem': u'16384', u'heads': u'1', u'ram':
u'65536'}, u'deviceId': u'2ece7a34-22ec-49bc-b48c-df4660d13d71',
u'address': {u'slot': u'0x02', u'bus': u'0x00',
u'domain': u'0x0000', u'type': u'pci',
u'function': u'0x0'}, u'device': u'qxl', u'type':
u'video'}, {u'devi
c
> e': u'spice', u'specParams': {u'fileTransferEnable':
u'true', u'spiceSecureChannels':
u'smain,sinputs,scursor,splayback,srecord,sdisplay,ssmartcard,susbredir',
u'displayNetwork': u'classepublic', u'displayIp':
u'128.84.46.164', u'copyPasteEnable': u'true'}, u'type':
u'graphics', u'deviceId': u'a7076b55-ddbb-4932-aca4-c567d9576c54',
u'tlsPort': u'5900'}, {u'nicModel': u'pv',
u'macAddr': u'00:1a:4a:16:01:51', u'linkActive': True,
u'network': u'classepublic', u'alias': u'net0',
u'filter': u'vdsm-no-mac-spoofing', u'specParams':
{u'inbound': {}, u'outbound': {}}, u'deviceId':
u'5110646a-b5d4-4b62-8188-b18cec8c15df', u'address': {u'slot':
u'0x03', u'bus': u'0x00', u'domain': u'0x0000',
u'type': u'pci', u'function': u'0x0'}, u'device':
u'bridge', u'type': u'interface', u'name':
u'vnet0'}, {u'index': u'2', u'iface': u'ide',
u'name': u'hdc', u'alias': u'ide0-1-0',
u'specParams': {u'path': u''}, u'readonly':
u'True', u'deviceId': u'75deba88-b39d-478c-83d3-cc535b43bb28',
u'address':
{
> u'bus': u'1', u'controller': u'0', u'type':
u'drive', u'target': u'0', u'unit': u'0'},
u'device': u'cdrom', u'shared': u'false', u'path':
u'', u'type': u'disk'}, {u'poolID':
u'00000001-0001-0001-0001-000000000311', u'volumeInfo':
{u'domainID': u'016ceee8-9117-4e8a-b611-f58f6763a098', u'volType':
u'path', u'leaseOffset': 111149056, u'volumeID':
u'67b12700-5faf-49b2-af40-6178023b3794', u'leasePath':
u'/dev/016ceee8-9117-4e8a-b611-f58f6763a098/leases', u'imageID':
u'21d18729-279f-435b-90ab-a2995f041bc6', u'path':
u'/rhev/data-center/mnt/blockSD/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49b2-af40-6178023b3794'},
u'index': 0, u'iface': u'scsi', u'apparentsize':
u'107374182400', u'alias': u'scsi0-0-0-0', u'imageID':
u'21d18729-279f-435b-90ab-a2995f041bc6', u'readonly': u'False',
u'shared': u'false', u'truesize': u'107374182400',
u'type': u'disk', u'domainID':
u'016ceee8-9117-4e8a-b611-f58f6763a098', u'reqsize': u'0',
u'format':
> u'raw', u'deviceId': u'21d18729-279f-435b-90ab-a2995f041bc6',
u'address': {u'bus': u'0', u'controller': u'0',
u'type': u'drive', u'target': u'0', u'unit':
u'0'}, u'device': u'disk', u'path':
u'/rhev/data-center/00000001-0001-0001-0001-000000000311/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49b2-af40-6178023b3794',
u'propagateErrors': u'off', u'optional': u'false',
u'name': u'sda', u'bootOrder': u'1', u'volumeID':
u'67b12700-5faf-49b2-af40-6178023b3794', u'specParams': {},
u'discard': False, u'volumeChain': [{u'domainID':
u'016ceee8-9117-4e8a-b611-f58f6763a098', u'volType': u'path',
u'leaseOffset': 111149056, u'volumeID':
u'67b12700-5faf-49b2-af40-6178023b3794', u'leasePath':
u'/dev/016ceee8-9117-4e8a-b611-f58f6763a098/leases', u'imageID':
u'21d18729-279f-435b-90ab-a2995f041bc6', u'path':
u'/rhev/data-center/mnt/blockSD/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49b2-af40-
6
> 178023b3794'}]}, {u'device': u'ide', u'alias':
u'ide', u'type': u'controller', u'address':
{u'slot': u'0x01', u'bus': u'0x00', u'domain':
u'0x0000', u'type': u'pci', u'function': u'0x1'}},
{u'device': u'unix', u'alias': u'channel0',
u'type': u'channel', u'address': {u'bus': u'0',
u'controller': u'0', u'type': u'virtio-serial',
u'port': u'1'}}, {u'device': u'unix', u'alias':
u'channel1', u'type': u'channel', u'address':
{u'bus': u'0', u'controller': u'0', u'type':
u'virtio-serial', u'port': u'2'}}, {u'device':
u'spicevmc', u'alias': u'channel2', u'type':
u'channel', u'address': {u'bus': u'0',
u'controller': u'0', u'type': u'virtio-serial',
u'port': u'3'}}], u'memGuaranteedSize': 1024, u'status':
u'Up', u'maxVCpus': u'16', u'guestIPs': u'128.84.46.16
192.168.122.1', u'statusTime': u'4528256170', u'maxMemSlots':
16}, False) kwargs={} (api:37)
> 2017-03-27 10:57:02,776-0400 INFO (jsonrpc/4) [vdsm.api] FINISH __init__ return=None
(api:43)
> 2017-03-27 10:57:02,777-0400 INFO (vm/cf9c5dbf) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') VM wrapper has started (vm:1915)
> 2017-03-27 10:57:02,778-0400 INFO (vm/cf9c5dbf) [dispatcher] Run and protect:
getVolumeSize(sdUUID=u'016ceee8-9117-4e8a-b611-f58f6763a098',
spUUID=u'00000001-0001-0001-0001-000000000311',
imgUUID=u'21d18729-279f-435b-90ab-a2995f041bc6',
volUUID=u'67b12700-5faf-49b2-af40-6178023b3794', options=None) (logUtils:51)
> 2017-03-27 10:57:02,809-0400 INFO (vm/cf9c5dbf) [dispatcher] Run and protect:
getVolumeSize, Return response: {'truesize': '107374182400',
'apparentsize': '107374182400'} (logUtils:54)
> 2017-03-27 10:57:02,809-0400 INFO (vm/cf9c5dbf) [vds] prepared volume path:
(clientIF:374)
> 2017-03-27 10:57:02,810-0400 INFO (vm/cf9c5dbf) [dispatcher] Run and protect:
prepareImage(sdUUID=u'016ceee8-9117-4e8a-b611-f58f6763a098',
spUUID=u'00000001-0001-0001-0001-000000000311',
imgUUID=u'21d18729-279f-435b-90ab-a2995f041bc6',
leafUUID=u'67b12700-5faf-49b2-af40-6178023b3794', allowIllegal=False)
(logUtils:51)
> 2017-03-27 10:57:02,859-0400 INFO (vm/cf9c5dbf) [storage.LVM] Activating lvs:
vg=016ceee8-9117-4e8a-b611-f58f6763a098
lvs=['67b12700-5faf-49b2-af40-6178023b3794'] (lvm:1289)
> 2017-03-27 10:57:02,947-0400 INFO (vm/cf9c5dbf) [storage.LVM] Refreshing lvs:
vg=016ceee8-9117-4e8a-b611-f58f6763a098 lvs=['leases'] (lvm:1285)
> 2017-03-27 10:57:02,998-0400 INFO (vm/cf9c5dbf) [dispatcher] Run and protect:
prepareImage, Return response: {'info': {'domainID':
u'016ceee8-9117-4e8a-b611-f58f6763a098', 'volType': 'path',
'leaseOffset': 111149056, 'path':
u'/rhev/data-center/mnt/blockSD/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49b2-af40-6178023b3794',
'volumeID': '67b12700-5faf-49b2-af40-6178023b3794', 'leasePath':
'/dev/016ceee8-9117-4e8a-b611-f58f6763a098/leases', 'imageID':
u'21d18729-279f-435b-90ab-a2995f041bc6'}, 'path':
u'/rhev/data-center/00000001-0001-0001-0001-000000000311/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49b2-af40-6178023b3794',
'imgVolumesInfo': [{'domainID':
u'016ceee8-9117-4e8a-b611-f58f6763a098', 'volType': 'path',
'leaseOffset': 111149056, 'path':
u'/rhev/data-center/mnt/blockSD/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49
b2
> -af40-6178023b3794', 'volumeID':
'67b12700-5faf-49b2-af40-6178023b3794', 'leasePath':
'/dev/016ceee8-9117-4e8a-b611-f58f6763a098/leases', 'imageID':
u'21d18729-279f-435b-90ab-a2995f041bc6'}]} (logUtils:54)
> 2017-03-27 10:57:02,998-0400 INFO (vm/cf9c5dbf) [vds] prepared volume path:
/rhev/data-center/00000001-0001-0001-0001-000000000311/016ceee8-9117-4e8a-b611-f58f6763a098/images/21d18729-279f-435b-90ab-a2995f041bc6/67b12700-5faf-49b2-af40-6178023b3794
(clientIF:374)
> 2017-03-27 10:57:03,026-0400 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call
VM.migrationCreate succeeded in 0.27 seconds (__init__:515)
> 2017-03-27 10:57:03,225-0400 INFO (jsonrpc/7) [vdsm.api] START destroy
args=(<virt.vm.Vm object at 0x4132e50>, 1) kwargs={} (api:37)
> 2017-03-27 10:57:03,225-0400 INFO (jsonrpc/7) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Release VM resources (vm:4199)
> 2017-03-27 10:57:03,226-0400 INFO (jsonrpc/7) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Stopping connection
(guestagent:430)
> 2017-03-27 10:57:03,227-0400 INFO (jsonrpc/7) [dispatcher] Run and protect:
teardownImage(sdUUID=u'016ceee8-9117-4e8a-b611-f58f6763a098',
spUUID=u'00000001-0001-0001-0001-000000000311',
imgUUID=u'21d18729-279f-435b-90ab-a2995f041bc6', volUUID=None) (logUtils:51)
> 2017-03-27 10:57:03,237-0400 INFO (vm/cf9c5dbf) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Changed state to Down: VM destroyed
during the startup (code=10) (vm:1207)
> 2017-03-27 10:57:03,238-0400 INFO (vm/cf9c5dbf) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Stopping connection
(guestagent:430)
> 2017-03-27 10:57:03,260-0400 INFO (jsonrpc/7) [storage.LVM] Deactivating lvs:
vg=016ceee8-9117-4e8a-b611-f58f6763a098
lvs=['67b12700-5faf-49b2-af40-6178023b3794'] (lvm:1297)
> 2017-03-27 10:57:03,311-0400 INFO (jsonrpc/7) [dispatcher] Run and protect:
teardownImage, Return response: None (logUtils:54)
> 2017-03-27 10:57:03,312-0400 INFO (jsonrpc/7) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') Stopping connection
(guestagent:430)
> 2017-03-27 10:57:03,312-0400 WARN (jsonrpc/7) [root] File:
/var/lib/libvirt/qemu/channels/cf9c5dbf-3924-47c6-b323-22ac90a1f682.com.redhat.rhevm.vdsm
already removed (utils:120)
> 2017-03-27 10:57:03,312-0400 WARN (jsonrpc/7) [root] File:
/var/lib/libvirt/qemu/channels/cf9c5dbf-3924-47c6-b323-22ac90a1f682.org.qemu.guest_agent.0
already removed (utils:120)
> 2017-03-27 10:57:03,313-0400 WARN (jsonrpc/7) [virt.vm]
(vmId='cf9c5dbf-3924-47c6-b323-22ac90a1f682') timestamp already removed from stats
cache (vm:1729)
> 2017-03-27 10:57:03,313-0400 INFO (jsonrpc/7) [dispatcher] Run and protect:
inappropriateDevices(thiefId=u'cf9c5dbf-3924-47c6-b323-22ac90a1f682')
(logUtils:51)
> 2017-03-27 10:57:03,314-0400 INFO (jsonrpc/7) [dispatcher] Run and protect:
inappropriateDevices, Return response: None (logUtils:54)
> 2017-03-27 10:57:03,314-0400 INFO (jsonrpc/7) [vdsm.api] FINISH destroy
return={'status': {'message': 'Done', 'code': 0}}
(api:43)
> 2017-03-27 10:57:03,315-0400 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call
VM.destroy succeeded in 0.09 seconds (__init__:515)
> 2017-03-27 10:57:03,937-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::1:53024 (protocoldetector:72)
> 2017-03-27 10:57:03,943-0400 INFO (Reactor thread) [ProtocolDetector.Detector]
Detected protocol stomp from ::1:53024 (protocoldetector:127)
> 2017-03-27 10:57:03,944-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
> 2017-03-27 10:57:03,945-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
> 2017-03-27 10:57:04,084-0400 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.00 seconds (__init__:515)
> 2017-03-27 10:57:04,086-0400 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call
VM.getStats failed (error 1) in 0.00 seconds (__init__:515)
> 2017-03-27 10:57:04,767-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::1:53026 (protocoldetector:72)
> 2017-03-27 10:57:04,772-0400 INFO (Reactor thread) [ProtocolDetector.Detector]
Detected protocol stomp from ::1:53026 (protocoldetector:127)
> 2017-03-27 10:57:04,773-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
> 2017-03-27 10:57:04,774-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
> 2017-03-27 10:57:04,913-0400 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.00 seconds (__init__:515)
> 2017-03-27 10:57:05,126-0400 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call
Host.getCapabilities succeeded in 0.22 seconds (__init__:515)
> 2017-03-27 10:57:06,040-0400 INFO (periodic/0) [dispatcher] Run and protect:
repoStats(options=None) (logUtils:51)
> 2017-03-27 10:57:06,040-0400 INFO (periodic/0) [dispatcher] Run and protect:
repoStats, Return response: {u'016ceee8-9117-4e8a-b611-f58f6763a098':
{'code': 0, 'actual': True, 'version': 4, 'acquired':
True, 'delay': '0.000426872', 'lastCheck': '4.6',
'valid': True}, u'2438f819-e7f5-4bb1-ad0d-5349fa371e6e': {'code':
0, 'actual': True, 'version': 0, 'acquired': True,
'delay': '0.000387272', 'lastCheck': '4.5',
'valid': True}, u'48d4f45d-0bdd-4f4a-90b6-35efe2da935a': {'code':
0, 'actual': True, 'version': 4, 'acquired': True,
'delay': '0.000353399', 'lastCheck': '0.5',
'valid': True}} (logUtils:54)
> 2017-03-27 10:57:08,170-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::1:53028 (protocoldetector:72)
> 2017-03-27 10:57:08,176-0400 INFO (Reactor thread) [ProtocolDetector.Detector]
Detected protocol stomp from ::1:53028 (protocoldetector:127)
> 2017-03-27 10:57:08,176-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
> 2017-03-27 10:57:08,177-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
> 2017-03-27 10:57:08,321-0400 INFO (jsonrpc/6) [dispatcher] Run and protect:
repoStats(options=None) (logUtils:51)
> 2017-03-27 10:57:08,322-0400 INFO (jsonrpc/6) [dispatcher] Run and protect:
repoStats, Return response: {u'016ceee8-9117-4e8a-b611-f58f6763a098':
{'code': 0, 'actual': True, 'version': 4, 'acquired':
True, 'delay': '0.000426872', 'lastCheck': '2.1',
'valid': True}, u'2438f819-e7f5-4bb1-ad0d-5349fa371e6e': {'code':
0, 'actual': True, 'version': 0, 'acquired': True,
'delay': '0.000387272', 'lastCheck': '1.2',
'valid': True}, u'48d4f45d-0bdd-4f4a-90b6-35efe2da935a': {'code':
0, 'actual': True, 'version': 4, 'acquired': True,
'delay': '0.000353399', 'lastCheck': '2.8',
'valid': True}} (logUtils:54)
> 2017-03-27 10:57:08,324-0400 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.00 seconds (__init__:515)
> 2017-03-27 10:57:08,327-0400 INFO (jsonrpc/4) [dispatcher] Run and protect:
repoStats(options=None) (logUtils:51)
> 2017-03-27 10:57:08,328-0400 INFO (jsonrpc/4) [dispatcher] Run and protect:
repoStats, Return response: {u'016ceee8-9117-4e8a-b611-f58f6763a098':
{'code': 0, 'actual': True, 'version': 4, 'acquired':
True, 'delay': '0.000426872', 'lastCheck': '2.1',
'valid': True}, u'2438f819-e7f5-4bb1-ad0d-5349fa371e6e': {'code':
0, 'actual': True, 'version': 0, 'acquired': True,
'delay': '0.000387272', 'lastCheck': '1.2',
'valid': True}, u'48d4f45d-0bdd-4f4a-90b6-35efe2da935a': {'code':
0, 'actual': True, 'version': 4, 'acquired': True,
'delay': '0.000353399', 'lastCheck': '2.8',
'valid': True}} (logUtils:54)
> 2017-03-27 10:57:08,619-0400 INFO (jsonrpc/4) [jsonrpc.JsonRpcServer] RPC call
Host.getStats succeeded in 0.29 seconds (__init__:515)
> 2017-03-27 10:57:08,625-0400 INFO (jsonrpc/6) [jsonrpc.JsonRpcServer] RPC call
Host.getStats succeeded in 0.30 seconds (__init__:515)
> 2017-03-27 10:57:08,848-0400 INFO (jsonrpc/7) [jsonrpc.JsonRpcServer] RPC call
Host.getAllVmStats succeeded in 0.00 seconds (__init__:515)
> 2017-03-27 10:57:09,370-0400 INFO (jsonrpc/2) [jsonrpc.JsonRpcServer] RPC call
Host.getAllVmStats succeeded in 0.00 seconds (__init__:515)
> 2017-03-27 10:57:10,927-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::1:53030 (protocoldetector:72)
> 2017-03-27 10:57:10,932-0400 INFO (Reactor thread) [ProtocolDetector.Detector]
Detected protocol stomp from ::1:53030 (protocoldetector:127)
> 2017-03-27 10:57:10,932-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
> 2017-03-27 10:57:10,933-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
> 2017-03-27 10:57:11,077-0400 INFO (jsonrpc/0) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.00 seconds (__init__:515)
> 2017-03-27 10:57:11,080-0400 INFO (jsonrpc/3) [jsonrpc.JsonRpcServer] RPC call
VM.getStats failed (error 1) in 0.00 seconds (__init__:515)
> 2017-03-27 10:57:13,802-0400 INFO (Reactor thread) [ProtocolDetector.AcceptorImpl]
Accepted connection from ::1:53034 (protocoldetector:72)
> 2017-03-27 10:57:13,808-0400 INFO (Reactor thread) [ProtocolDetector.Detector]
Detected protocol stomp from ::1:53034 (protocoldetector:127)
> 2017-03-27 10:57:13,809-0400 INFO (Reactor thread) [Broker.StompAdapter] Processing
CONNECT request (stompreactor:102)
> 2017-03-27 10:57:13,810-0400 INFO (JsonRpc (StompReactor)) [Broker.StompAdapter]
Subscribe command received (stompreactor:129)
> 2017-03-27 10:57:14,015-0400 INFO (jsonrpc/1) [jsonrpc.JsonRpcServer] RPC call
Host.getHardwareInfo succeeded in 0.00 seconds (__init__:515)
> 2017-03-27 10:57:14,019-0400 INFO (jsonrpc/5) [dispatcher] Run and protect:
getImagesList(sdUUID=u'48d4f45d-0bdd-4f4a-90b6-35efe2da935a', options=None)
(logUtils:51)
> 2017-03-27 10:57:14,050-0400 INFO (jsonrpc/5) [dispatcher] Run and protect:
getImagesList, Return response: {'imageslist':
['a1d7f855-40d4-4f07-bf25-625284682b40',
'c61297c5-367b-4c98-a44b-de0318848a24',
'c2fc0c7c-596e-4011-8471-7f67408ea633',
'b2bb3396-a8fb-4ed7-89c7-4cc7d22aa26a',
'6d594f05-c2c2-4ef0-849f-28da46b8f87e',
'5fc548e6-51a8-44ee-a14b-92e0e924d7b8']} (logUtils:54)
> 2017-03-27 10:57:14,051-0400 INFO (jsonrpc/5) [jsonrpc.JsonRpcServer] RPC call
StorageDomain.getImages succeeded in 0.03 seconds (__init__:515)
> 2017-03-27 10:57:14,083-0400 INFO (jsonrpc/4) [dispatcher] Run and protect:
getVolumesList(sdUUID=u'48d4f45d-0bdd-4f4a-90b6-35efe2da935a',
spUUID=u'00000000-0000-0000-0000-000000000000',
imgUUID=u'a1d7f855-40d4-4f07-bf25-625284682b40', options=None) (logUtils:51)
> ...
> _______________________________________________
> Users mailing list
> Users(a)ovirt.org
> http://lists.ovirt.org/mailman/listinfo/users
_______________________________________________
Users mailing list
Users(a)ovirt.org
http://lists.ovirt.org/mailman/listinfo/users
2795
days inactive
2797
days old
2 comments
2 participants
participants (2)
-
Dan Kenigsberg -
Devin A. Bougie