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)
...