8:53 p.m.
--Apple-Mail=_877DC8BA-BA64-4899-AC8F-C9588B685A8B
Content-Transfer-Encoding: quoted-printable
Content-Type: text/plain;
charset=windows-1252
So twice now under oVirt 3.3.2 I have added 2 virtio-scsi devices to a =
single virtual host. =20
After doing so, the VM would fail to boot due to duplicate disk ID. The =
first time I thought it a fluke, second time=92s a bug?
Fortunately they were empty data disks and I was able to get around the =
problem by deleting one and recreating it.
VDSM log:
Thread-32154::INFO::2014-01-08 =
11:54:39,717::clientIF::350::vds::(prepareVolumePath) prepared volume =
path: =
/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/imag=
es/e1886286-3d81-47d0-ae8d-77454e464078/1ef64ebd-6415-43d1-a34f-28f05cf6de=
a2
Thread-32154::DEBUG::2014-01-08 11:54:39,740::vm::2984::vm.Vm::(_run) =
vmId=3D`c2aff4cc-0de6-4342-a565-669b1825838c`::<?xml version=3D"1.0" =
encoding=3D"utf-8"?>
<domain type=3D"kvm">
<name>cobra</name>
<uuid>c2aff4cc-0de6-4342-a565-669b1825838c</uuid>
<memory>4194304</memory>
<currentMemory>4194304</currentMemory>
<vcpu>3</vcpu>
<memtune>
<min_guarantee>4194304</min_guarantee>
</memtune>
<devices>
<channel type=3D"unix">
<target name=3D"com.redhat.rhevm.vdsm" =
type=3D"virtio"/>
<source mode=3D"bind" =
path=3D"/var/lib/libvirt/qemu/channels/c2aff4cc-0de6-4342-a565-669b1825838=
c.com.redhat.rhevm.vdsm"/>
</channel>
<channel type=3D"unix">
<target name=3D"org.qemu.guest_agent.0" =
type=3D"virtio"/>
<source mode=3D"bind" =
path=3D"/var/lib/libvirt/qemu/channels/c2aff4cc-0de6-4342-a565-669b1825838=
c.org.qemu.guest_agent.0"/>
</channel>
<input bus=3D"ps2" type=3D"mouse"/>
<channel type=3D"spicevmc">
<target name=3D"com.redhat.spice.0" =
type=3D"virtio"/>
</channel>
<graphics autoport=3D"yes" keymap=3D"en-us"
listen=3D"0" =
passwd=3D"*****" passwdValidTo=3D"1970-01-01T00:00:01"
port=3D"-1" =
tlsPort=3D"-1" type=3D"spice">
<channel mode=3D"secure"
name=3D"main"/>
<channel mode=3D"secure"
name=3D"inputs"/>
<channel mode=3D"secure"
name=3D"cursor"/>
<channel mode=3D"secure"
name=3D"playback"/>
<channel mode=3D"secure"
name=3D"record"/>
<channel mode=3D"secure"
name=3D"display"/>
<channel mode=3D"secure"
name=3D"usbredir"/>
<channel mode=3D"secure"
name=3D"display"/>
<channel mode=3D"secure"
name=3D"usbredir"/>
<channel mode=3D"secure"
name=3D"smartcard"/>
</graphics>
<controller model=3D"virtio-scsi"
type=3D"scsi">
<address bus=3D"0x00" domain=3D"0x0000" =
function=3D"0x0" slot=3D"0x05" type=3D"pci"/>
</controller>
<video>
<address bus=3D"0x00" domain=3D"0x0000" =
function=3D"0x0" slot=3D"0x02" type=3D"pci"/>
<model heads=3D"1" type=3D"qxl"
vram=3D"32768"/>
</video>
<interface type=3D"bridge">
<address bus=3D"0x00" domain=3D"0x0000" =
function=3D"0x0" slot=3D"0x03" type=3D"pci"/>
<mac address=3D"00:1a:4a:5b:9f:02"/>
<model type=3D"virtio"/>
<source bridge=3D"ovirtmgmt"/>
<filterref filter=3D"vdsm-no-mac-spoofing"/>
<link state=3D"up"/>
</interface>
<disk device=3D"cdrom" snapshot=3D"no"
type=3D"file">
<address bus=3D"1" controller=3D"0"
target=3D"0" =
type=3D"drive" unit=3D"0"/>
<source file=3D""
startupPolicy=3D"optional"/>
<target bus=3D"ide" dev=3D"hdc"/>
<readonly/>
<serial/>
</disk>
<disk device=3D"disk" snapshot=3D"no"
type=3D"file">
<source =
file=3D"/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c=
97a/images/94a298cb-87a5-48cd-b78f-770582b50639/c4864816-7384-4d0e-927e-29=
ea36d5fa4a"/>
<target bus=3D"scsi" dev=3D"sda"/>
=
<serial>94a298cb-87a5-48cd-b78f-770582b50639</serial>
<boot order=3D"1"/>
<driver cache=3D"none"
error_policy=3D"stop" =
io=3D"threads" name=3D"qemu" type=3D"raw"/>
</disk>
<disk device=3D"disk" snapshot=3D"no"
type=3D"file">
<address bus=3D"0x00" domain=3D"0x0000" =
function=3D"0x0" slot=3D"0x07" type=3D"pci"/>
<source =
file=3D"/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c=
97a/images/8df43d38-c4c7-4711-bc87-55f35d1550e5/42786233-04c0-4a9f-b731-6e=
aa2ca4d40d"/>
<target bus=3D"virtio" dev=3D"vda"/>
=
<serial>8df43d38-c4c7-4711-bc87-55f35d1550e5</serial>
<driver cache=3D"none"
error_policy=3D"stop" =
io=3D"threads" name=3D"qemu" type=3D"raw"/>
</disk>
<disk device=3D"disk" snapshot=3D"no"
type=3D"file">
<address bus=3D"0" controller=3D"0"
target=3D"0" =
type=3D"drive" unit=3D"0"/>
<source =
file=3D"/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c=
97a/images/e1886286-3d81-47d0-ae8d-77454e464078/1ef64ebd-6415-43d1-a34f-28=
f05cf6dea2"/>
<target bus=3D"scsi" dev=3D"sdb"/>
=
<serial>e1886286-3d81-47d0-ae8d-77454e464078</serial>
<driver cache=3D"none"
error_policy=3D"stop" =
io=3D"threads" name=3D"qemu" type=3D"raw"/>
</disk>
<sound model=3D"ich6">
<address bus=3D"0x00" domain=3D"0x0000" =
function=3D"0x0" slot=3D"0x04" type=3D"pci"/>
</sound>
<memballoon model=3D"virtio"/>
</devices>
<os>
<type arch=3D"x86_64"
machine=3D"pc-1.0">hvm</type>
<smbios mode=3D"sysinfo"/>
</os>
<sysinfo type=3D"smbios">
<system>
<entry name=3D"manufacturer">oVirt</entry>
<entry name=3D"product">oVirt Node</entry>
<entry name=3D"version">19-5</entry>
<entry =
name=3D"serial">2061001F-C600-0006-E1BC-BCAEC518BA45</entry>
<entry =
name=3D"uuid">c2aff4cc-0de6-4342-a565-669b1825838c</entry>
</system>
</sysinfo>
<clock adjustment=3D"-21600" offset=3D"variable">
<timer name=3D"rtc" tickpolicy=3D"catchup"/>
</clock>
<features>
<acpi/>
</features>
<cpu match=3D"exact">
<model>Nehalem</model>
<topology cores=3D"1" sockets=3D"3"
threads=3D"1"/>
</cpu>
</domain>
Thread-32154::DEBUG::2014-01-08 =
11:54:40,218::libvirtconnection::108::libvirtconnection::(wrapper) =
Unknown libvirterror: ecode: 1 edom: 10 level: 2 message: internal error =
process exited while connecting to monitor: qemu-system-x86_64: -drive =
file=3D/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c9=
7a/images/e1886286-3d81-47d0-ae8d-77454e464078/1ef64ebd-6415-43d1-a34f-28f=
05cf6dea2,if=3Dnone,id=3Ddrive-scsi0-0-0-0,format=3Draw,serial=3De1886286-=
3d81-47d0-ae8d-77454e464078,cache=3Dnone,werror=3Dstop,rerror=3Dstop,aio=3D=
threads: Duplicate ID 'drive-scsi0-0-0-0' for drive
Thread-32154::DEBUG::2014-01-08 =
11:54:40,218::vm::2109::vm.Vm::(_startUnderlyingVm) =
vmId=3D`c2aff4cc-0de6-4342-a565-669b1825838c`::_ongoingCreations =
released
Thread-32154::ERROR::2014-01-08 =
11:54:40,218::vm::2135::vm.Vm::(_startUnderlyingVm) =
vmId=3D`c2aff4cc-0de6-4342-a565-669b1825838c`::The vm start process =
failed
Traceback (most recent call last):
File "/usr/share/vdsm/vm.py", line 2095, in _startUnderlyingVm
self._run()
File "/usr/share/vdsm/vm.py", line 3018, in _run
self._connection.createXML(domxml, flags),
File "/usr/lib64/python2.7/site-packages/vdsm/libvirtconnection.py", =
line 76, in wrapper
ret =3D f(*args, **kwargs)
File "/usr/lib64/python2.7/site-packages/libvirt.py", line 2805, in =
createXML
if ret is None:raise libvirtError('virDomainCreateXML() failed', =
conn=3Dself)
libvirtError: internal error process exited while connecting to monitor: =
qemu-system-x86_64: -drive =
file=3D/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c9=
7a/images/e1886286-3d81-47d0-ae8d-77454e464078/1ef64ebd-6415-43d1-a34f-28f=
05cf6dea2,if=3Dnone,id=3Ddrive-scsi0-0-0-0,format=3Draw,serial=3De1886286-=
3d81-47d0-ae8d-77454e464078,cache=3Dnone,werror=3Dstop,rerror=3Dstop,aio=3D=
threads: Duplicate ID 'drive-scsi0-0-0-0' for drive
Thread-32154::DEBUG::2014-01-08 =
11:54:40,223::vm::2577::vm.Vm::(setDownStatus) =
vmId=3D`c2aff4cc-0de6-4342-a565-669b1825838c`::Changed state to Down: =
internal error process exited while connecting to monitor: =
qemu-system-x86_64: -drive =
file=3D/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c9=
7a/images/e1886286-3d81-47d0-ae8d-77454e464078/1ef64ebd-6415-43d1-a34f-28f=
05cf6dea2,if=3Dnone,id=3Ddrive-scsi0-0-0-0,format=3Draw,serial=3De1886286-=
3d81-47d0-ae8d-77454e464078,cache=3Dnone,werror=3Dstop,rerror=3Dstop,aio=3D=
threads: Duplicate ID 'drive-scsi0-0-0-0' for drive
Thread-32158::WARNING::2014-01-08 =
11:54:42,185::clientIF::362::vds::(teardownVolumePath) Drive is not a =
vdsm image: VOLWM_CHUNK_MB:1024 VOLWM_CHUNK_REPLICATE_MULT:2 =
VOLWM_FREE_PCT:50 _blockDev:False _checkIoTuneCategories:<bound method =
Drive._checkIoTuneCategories of <vm.Drive object at 0x7f1150113a90>> =
_customize:<bound method Drive._customize of <vm.Drive object at =
0x7f1150113a90>> _deviceXML:<disk device=3D"cdrom"
snapshot=3D"no" =
type=3D"file"><address bus=3D"1" controller=3D"0"
target=3D"0" =
type=3D"drive" unit=3D"0"/><source file=3D"" =
startupPolicy=3D"optional"/><target bus=3D"ide" =
dev=3D"hdc"/><readonly/><serial></serial></disk>
_makeName:<bound method =
Drive._makeName of <vm.Drive object at 0x7f1150113a90>> =
_setExtSharedState:<bound method Drive._setExtSharedState of <vm.Drive =
object at 0x7f1150113a90>> _validateIoTuneParams:<bound method =
Drive._validateIoTuneParams of <vm.Drive object at 0x7f1150113a90>> =
address:{' controller': '0', ' target': '0',
'unit': '0', ' bus': '1', ' =
type': 'drive'} apparentsize:0 blockDev:False cache:none
conf:{'status': =
'Down', 'acpiEnable': 'true', 'emulatedMachine':
'pc-1.0', 'vmId': =
'c2aff4cc-0de6-4342-a565-669b1825838c', 'pid': '0',
'memGuaranteedSize': =
4096, 'timeOffset': '-21600', 'keyboardLayout': 'en-us',
'displayPort': =
'-1', 'displaySecurePort': '-1', 'spiceSslCipherSuite':
'DEFAULT', =
'cpuType': 'Nehalem', 'custom': =
{'device_142f948d-f916-4f42-bd28-cb4f0b8ebb40device_615c1466-850e-4362-a4f=
b-60df1aaee1e8device_861eb290-19bc-4ace-b2cb-85cbb2e0eb5adevice_94db7fa0-0=
71d-4181-bac6-826ecfca3dd8device_a2e6354f-4ad6-475f-bd18-754fcedf505f': =
'VmDevice {vmId=3Dc2aff4cc-0de6-4342-a565-669b1825838c, =
deviceId=3Da2e6354f-4ad6-475f-bd18-754fcedf505f, device=3Dunix, =
type=3DCHANNEL, bootOrder=3D0, specParams=3D{}, address=3D{port=3D2, =
bus=3D0, controller=3D0, type=3Dvirtio-serial}, managed=3Dfalse, =
plugged=3Dtrue, readOnly=3Dfalse, deviceAlias=3Dchannel1, =
customProperties=3D{}, snapshotId=3Dnull}', =
'device_142f948d-f916-4f42-bd28-cb4f0b8ebb40': 'VmDevice =
{vmId=3Dc2aff4cc-0de6-4342-a565-669b1825838c, =
deviceId=3D142f948d-f916-4f42-bd28-cb4f0b8ebb40, device=3Dvirtio-serial, =
type=3DCONTROLLER, bootOrder=3D0, specParams=3D{}, address=3D{bus=3D0x00, =
domain=3D0x0000, type=3Dpci, slot=3D0x06, function=3D0x0}, =
managed=3Dfalse, plugged=3Dtrue, readOnly=3Dfalse, =
deviceAlias=3Dvirtio-serial0, customProperties=3D{}, snapshotId=3Dnull}', =
'device_142f948d-f916-4f42-bd28-cb4f0b8ebb40device_615c1466-850e-4362-a4fb=
-60df1aaee1e8device_861eb290-19bc-4ace-b2cb-85cbb2e0eb5a': 'VmDevice =
{vmId=3Dc2aff4cc-0de6-4342-a565-669b1825838c, =
deviceId=3D861eb290-19bc-4ace-b2cb-85cbb2e0eb5a, device=3Dide, =
type=3DCONTROLLER, bootOrder=3D0, specParams=3D{}, address=3D{bus=3D0x00, =
domain=3D0x0000, type=3Dpci, slot=3D0x01, function=3D0x1}, =
managed=3Dfalse, plugged=3Dtrue, readOnly=3Dfalse, deviceAlias=3Dide0, =
customProperties=3D{}, snapshotId=3Dnull}', =
'device_142f948d-f916-4f42-bd28-cb4f0b8ebb40device_615c1466-850e-4362-a4fb=
-60df1aaee1e8device_861eb290-19bc-4ace-b2cb-85cbb2e0eb5adevice_94db7fa0-07=
1d-4181-bac6-826ecfca3dd8': 'VmDevice =
{vmId=3Dc2aff4cc-0de6-4342-a565-669b1825838c, =
deviceId=3D94db7fa0-071d-4181-bac6-826ecfca3dd8, device=3Dunix, =
type=3DCHANNEL, bootOrder=3D0, specParams=3D{}, address=3D{port=3D1, =
bus=3D0, controller=3D0, type=3Dvirtio-serial}, managed=3Dfalse, =
plugged=3Dtrue, readOnly=3Dfalse, deviceAlias=3Dchannel0, =
customProperties=3D{}, snapshotId=3Dnull}', =
'device_142f948d-f916-4f42-bd28-cb4f0b8ebb40device_615c1466-850e-4362-a4fb=
-60df1aaee1e8': 'VmDevice {vmId=3Dc2aff4cc-0de6-4342-a565-669b1825838c, =
deviceId=3D615c1466-850e-4362-a4fb-60df1aaee1e8, device=3Dspicevmc, =
type=3DCHANNEL, bootOrder=3D0, specParams=3D{}, address=3D{port=3D3, =
bus=3D0, controller=3D0, type=3Dvirtio-serial}, managed=3Dfalse, =
plugged=3Dtrue, readOnly=3Dfalse, deviceAlias=3Dchannel2, =
customProperties=3D{}, snapshotId=3Dnull}'}, 'clientIp': '',
'exitCode': =
1, 'nicModel': 'rtl8139,pv', 'smartcardEnable': 'false',
'kvmEnable': =
'true', 'exitMessage': "internal error process exited while
connecting =
to monitor: qemu-system-x86_64: -drive =
file=3D/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c9=
7a/images/e1886286-3d81-47d0-ae8d-77454e464078/1ef64ebd-6415-43d1-a34f-28f=
05cf6dea2,if=3Dnone,id=3Ddrive-scsi0-0-0-0,format=3Draw,serial=3De1886286-=
3d81-47d0-ae8d-77454e464078,cache=3Dnone,werror=3Dstop,rerror=3Dstop,aio=3D=
threads: Duplicate ID 'drive-scsi0-0-0-0' for drive\n", =
'transparentHugePages': 'true', 'devices': [{'specParams':
{}, =
'deviceId': 'db6166cb-e977-485e-8c82-fa48ca75e709', 'address':
{'bus': =
'0x00', ' slot': '0x05', ' domain': '0x0000',
' type': 'pci', ' =
function': '0x0'}, 'device': 'scsi', 'model':
'virtio-scsi', 'type': =
'controller'}, {'device': 'qxl', 'specParams':
{'vram': '32768', =
'heads': '1'}, 'type': 'video', 'deviceId': =
'8b0e3dbc-27c6-4eae-ba6b-201c3e1736aa', 'address': {'bus':
'0x00', ' =
slot': '0x02', ' domain': '0x0000', ' type':
'pci', ' function': =
'0x0'}}, {'nicModel': 'pv', 'macAddr':
'00:1a:4a:5b:9f:02', =
'linkActive': 'true', 'network': 'ovirtmgmt',
'filter': =
'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': =
'738c8ebe-b014-4d65-8c78-942aaf12bfb5', 'address': {'bus':
'0x00', ' =
slot': '0x03', ' domain': '0x0000', ' type':
'pci', ' function': '0x0'}, =
'device': 'bridge', 'type': 'interface'},
{'index': '2', 'iface': 'ide', =
'address': {' controller': '0', ' target': '0',
'unit': '0', ' bus': =
'1', ' type': 'drive'}, 'specParams': {'path':
''}, 'readonly': 'true', =
'deviceId': '5611019a-948e-41b3-8ffd-75790ebcdf84', 'path':
'', =
'device': 'cdrom', 'shared': 'false', 'type':
'disk'}, {'volumeInfo': =
{'domainID': 'f14f471e-0cce-414d-af57-779eeb88c97a', 'volType':
'path', =
'leaseOffset': 0, 'path': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/94a298cb-87a5-48cd-b78f-770582b50639/c4864816-7384-4d0e-927e-29ea36d5f=
a4a', 'volumeID': 'c4864816-7384-4d0e-927e-29ea36d5fa4a',
'leasePath': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/94a298cb-87a5-48cd-b78f-770582b50639/c4864816-7384-4d0e-927e-29ea36d5f=
a4a.lease', 'imageID': '94a298cb-87a5-48cd-b78f-770582b50639'},
'index': =
0, 'iface': 'scsi', 'apparentsize': '162135015424',
'imageID': =
'94a298cb-87a5-48cd-b78f-770582b50639', 'readonly': 'false',
'shared': =
'false', 'truesize': '107119386624', 'type':
'disk', 'domainID': =
'f14f471e-0cce-414d-af57-779eeb88c97a', 'reqsize': '0',
'format': 'raw', =
'deviceId': '94a298cb-87a5-48cd-b78f-770582b50639', 'poolID': =
'18f6234c-a9de-4fdf-bd9a-2bd90b9f33f9', 'device': 'disk',
'path': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/94a298cb-87a5-48cd-b78f-770582b50639/c4864816-7384-4d0e-927e-29ea36d5f=
a4a', 'propagateErrors': 'off', 'optional': 'false',
'bootOrder': '1', =
'volumeID': 'c4864816-7384-4d0e-927e-29ea36d5fa4a', 'specParams':
{}, =
'volumeChain': [{'domainID':
'f14f471e-0cce-414d-af57-779eeb88c97a', =
'volType': 'path', 'leaseOffset': 0, 'path': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/94a298cb-87a5-48cd-b78f-770582b50639/c4864816-7384-4d0e-927e-29ea36d5f=
a4a', 'volumeID': 'c4864816-7384-4d0e-927e-29ea36d5fa4a',
'leasePath': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/94a298cb-87a5-48cd-b78f-770582b50639/c4864816-7384-4d0e-927e-29ea36d5f=
a4a.lease', 'imageID': '94a298cb-87a5-48cd-b78f-770582b50639'}]}, =
{'address': {'bus': '0x00', ' slot': '0x07', '
domain': '0x0000', ' =
type': 'pci', ' function': '0x0'}, 'volumeInfo':
{'domainID': =
'f14f471e-0cce-414d-af57-779eeb88c97a', 'volType': 'path', =
'leaseOffset': 0, 'path': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/8df43d38-c4c7-4711-bc87-55f35d1550e5/42786233-04c0-4a9f-b731-6eaa2ca4d=
40d', 'volumeID': '42786233-04c0-4a9f-b731-6eaa2ca4d40d',
'leasePath': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/8df43d38-c4c7-4711-bc87-55f35d1550e5/42786233-04c0-4a9f-b731-6eaa2ca4d=
40d.lease', 'imageID': '8df43d38-c4c7-4711-bc87-55f35d1550e5'},
'index': =
'0', 'iface': 'virtio', 'apparentsize':
'1073741824', 'imageID': =
'8df43d38-c4c7-4711-bc87-55f35d1550e5', 'readonly': 'false',
'shared': =
'false', 'truesize': '0', 'type': 'disk',
'domainID': =
'f14f471e-0cce-414d-af57-779eeb88c97a', 'reqsize': '0',
'format': 'raw', =
'deviceId': '8df43d38-c4c7-4711-bc87-55f35d1550e5', 'poolID': =
'18f6234c-a9de-4fdf-bd9a-2bd90b9f33f9', 'device': 'disk',
'path': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/8df43d38-c4c7-4711-bc87-55f35d1550e5/42786233-04c0-4a9f-b731-6eaa2ca4d=
40d', 'propagateErrors': 'off', 'optional': 'false',
'volumeID': =
'42786233-04c0-4a9f-b731-6eaa2ca4d40d', 'specParams': {},
'volumeChain': =
[{'domainID': 'f14f471e-0cce-414d-af57-779eeb88c97a', 'volType':
'path', =
'leaseOffset': 0, 'path': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/8df43d38-c4c7-4711-bc87-55f35d1550e5/42786233-04c0-4a9f-b731-6eaa2ca4d=
40d', 'volumeID': '42786233-04c0-4a9f-b731-6eaa2ca4d40d',
'leasePath': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/8df43d38-c4c7-4711-bc87-55f35d1550e5/42786233-04c0-4a9f-b731-6eaa2ca4d=
40d.lease', 'imageID': '8df43d38-c4c7-4711-bc87-55f35d1550e5'}]}, =
{'address': {' controller': '0', ' target': '0',
'unit': '0', ' bus': =
'0', ' type': 'drive'}, 'volumeInfo': {'domainID':
=
'f14f471e-0cce-414d-af57-779eeb88c97a', 'volType': 'path', =
'leaseOffset': 0, 'path': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/e1886286-3d81-47d0-ae8d-77454e464078/1ef64ebd-6415-43d1-a34f-28f05cf6d=
ea2', 'volumeID': '1ef64ebd-6415-43d1-a34f-28f05cf6dea2',
'leasePath': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/e1886286-3d81-47d0-ae8d-77454e464078/1ef64ebd-6415-43d1-a34f-28f05cf6d=
ea2.lease', 'imageID': 'e1886286-3d81-47d0-ae8d-77454e464078'},
'index': =
'1', 'iface': 'scsi', 'apparentsize':
'1073741824', 'imageID': =
'e1886286-3d81-47d0-ae8d-77454e464078', 'readonly': 'false',
'shared': =
'false', 'truesize': '0', 'type': 'disk',
'domainID': =
'f14f471e-0cce-414d-af57-779eeb88c97a', 'reqsize': '0',
'format': 'raw', =
'deviceId': 'e1886286-3d81-47d0-ae8d-77454e464078', 'poolID': =
'18f6234c-a9de-4fdf-bd9a-2bd90b9f33f9', 'device': 'disk',
'path': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/e1886286-3d81-47d0-ae8d-77454e464078/1ef64ebd-6415-43d1-a34f-28f05cf6d=
ea2', 'propagateErrors': 'off', 'optional': 'false',
'volumeID': =
'1ef64ebd-6415-43d1-a34f-28f05cf6dea2', 'specParams': {},
'volumeChain': =
[{'domainID': 'f14f471e-0cce-414d-af57-779eeb88c97a', 'volType':
'path', =
'leaseOffset': 0, 'path': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/e1886286-3d81-47d0-ae8d-77454e464078/1ef64ebd-6415-43d1-a34f-28f05cf6d=
ea2', 'volumeID': '1ef64ebd-6415-43d1-a34f-28f05cf6dea2',
'leasePath': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/e1886286-3d81-47d0-ae8d-77454e464078/1ef64ebd-6415-43d1-a34f-28f05cf6d=
ea2.lease', 'imageID': 'e1886286-3d81-47d0-ae8d-77454e464078'}]}, =
{'device': 'ich6', 'specParams': {}, 'type':
'sound', 'deviceId': =
'a1e596e9-218f-46ba-9f32-b9c966e11d73', 'address': {'bus':
'0x00', ' =
slot': '0x04', ' domain': '0x0000', ' type':
'pci', ' function': =
'0x0'}}, {'device': 'memballoon', 'specParams':
{'model': 'virtio'}, =
'type': 'balloon', 'deviceId':
'5c04fd7e-7249-4e3a-b8eb-91cce72d5b60', =
'target': 4194304}], 'smp': '3', 'vmType': 'kvm',
'memSize': 4096, =
'displayIp': '0', 'spiceSecureChannels': =
'smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard', =
'smpCoresPerSocket': '1', 'vmName': 'cobra',
'display': 'qxl', 'nice': =
'0'} createXmlElem:<bound method Drive.createXmlElem of <vm.Drive object =
at 0x7f1150113a90>> device:cdrom =
deviceId:5611019a-948e-41b3-8ffd-75790ebcdf84 extSharedState:none =
getLeasesXML:<bound method Drive.getLeasesXML of <vm.Drive object at =
0x7f1150113a90>> getNextVolumeSize:<bound method Drive.getNextVolumeSize =
of <vm.Drive object at 0x7f1150113a90>> getXML:<bound method =
Drive.getXML of <vm.Drive object at 0x7f1150113a90>> =
hasVolumeLeases:False iface:ide index:2 =
isDiskReplicationInProgress:<bound method =
Drive.isDiskReplicationInProgress of <vm.Drive object at =
0x7f1150113a90>> isVdsmImage:<bound method Drive.isVdsmImage of =
<vm.Drive object at 0x7f1150113a90>> log:<logUtils.SimpleLogAdapter =
object at 0x7f111838af90> name:hdc networkDev:False path: readonly:true =
reqsize:0 serial: shared:false specParams:{'path': ''} truesize:0 =
type:disk volExtensionChunk:1024 watermarkLimit:536870912
Traceback (most recent call last):
File "/usr/share/vdsm/clientIF.py", line 356, in teardownVolumePath
res =3D self.irs.teardownImage(drive['domainID'],
File "/usr/share/vdsm/vm.py", line 1389, in __getitem__
raise KeyError(key)
KeyError: 'domainID'
Thread-32158::DEBUG::2014-01-08 =
11:54:42,186::task::579::TaskManager.Task::(_updateState) =
Task=3D`30d76cca-4645-4893-8d68-5cc68ba42dc3`::moving from state init -> =
state preparing
Thread-32158::INFO::2014-01-08 =
11:54:42,187::logUtils::44::dispatcher::(wrapper) Run and protect: =
teardownImage(sdUUID=3D'f14f471e-0cce-414d-af57-779eeb88c97a', =
spUUID=3D'18f6234c-a9de-4fdf-bd9a-2bd90b9f33f9', =
imgUUID=3D'94a298cb-87a5-48cd-b78f-770582b50639', volUUID=3DNone)
Thread-32158::DEBUG::2014-01-08 =
11:54:42,187::resourceManager::197::ResourceManager.Request::(__init__) =
ResName=3D`Storage.f14f471e-0cce-414d-af57-779eeb88c97a`ReqID=3D`533f2699-=
0684-4247-9d5f-a858ffe96fe9`::Request was made in =
'/usr/share/vdsm/storage/hsm.py' line '3283' at 'teardownImage'
Thread-32158::DEBUG::2014-01-08 =
11:54:42,187::resourceManager::541::ResourceManager::(registerResource) =
Trying to register resource =
'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' for lock type 'shared'
Thread-32158::DEBUG::2014-01-08 =
11:54:42,187::resourceManager::600::ResourceManager::(registerResource) =
Resource 'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' is free. Now =
locking as 'shared' (1 active user)
Thread-32158::DEBUG::2014-01-08 =
11:54:42,187::resourceManager::237::ResourceManager.Request::(grant) =
ResName=3D`Storage.f14f471e-0cce-414d-af57-779eeb88c97a`ReqID=3D`533f2699-=
0684-4247-9d5f-a858ffe96fe9`::Granted request
Thread-32158::DEBUG::2014-01-08 =
11:54:42,188::task::811::TaskManager.Task::(resourceAcquired) =
Task=3D`30d76cca-4645-4893-8d68-5cc68ba42dc3`::_resourcesAcquired: =
Storage.f14f471e-0cce-414d-af57-779eeb88c97a (shared)
Thread-32158::DEBUG::2014-01-08 =
11:54:42,188::task::974::TaskManager.Task::(_decref) =
Task=3D`30d76cca-4645-4893-8d68-5cc68ba42dc3`::ref 1 aborting False
Thread-32158::INFO::2014-01-08 =
11:54:42,188::logUtils::47::dispatcher::(wrapper) Run and protect: =
teardownImage, Return response: None
Thread-32158::DEBUG::2014-01-08 =
11:54:42,188::task::1168::TaskManager.Task::(prepare) =
Task=3D`30d76cca-4645-4893-8d68-5cc68ba42dc3`::finished: None
Thread-32158::DEBUG::2014-01-08 =
11:54:42,188::task::579::TaskManager.Task::(_updateState) =
Task=3D`30d76cca-4645-4893-8d68-5cc68ba42dc3`::moving from state =
preparing -> state finished
Thread-32158::DEBUG::2014-01-08 =
11:54:42,188::resourceManager::939::ResourceManager.Owner::(releaseAll) =
Owner.releaseAll requests {} resources =
{'Storage.f14f471e-0cce-414d-af57-779eeb88c97a': < ResourceRef =
'Storage.f14f471e-0cce-414d-af57-779eeb88c97a', isValid: 'True' obj: =
'None'>}
Thread-32158::DEBUG::2014-01-08 =
11:54:42,188::resourceManager::976::ResourceManager.Owner::(cancelAll) =
Owner.cancelAll requests {}
Thread-32158::DEBUG::2014-01-08 =
11:54:42,189::resourceManager::615::ResourceManager::(releaseResource) =
Trying to release resource =
'Storage.f14f471e-0cce-414d-af57-779eeb88c97a'
Thread-32158::DEBUG::2014-01-08 =
11:54:42,189::resourceManager::634::ResourceManager::(releaseResource) =
Released resource 'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' (0 =
active users)
Thread-32158::DEBUG::2014-01-08 =
11:54:42,189::resourceManager::640::ResourceManager::(releaseResource) =
Resource 'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' is free, finding =
out if anyone is waiting for it.
Thread-32158::DEBUG::2014-01-08 =
11:54:42,189::resourceManager::648::ResourceManager::(releaseResource) =
No one is waiting for resource =
'Storage.f14f471e-0cce-414d-af57-779eeb88c97a', Clearing records.
Thread-32158::DEBUG::2014-01-08 =
11:54:42,189::task::974::TaskManager.Task::(_decref) =
Task=3D`30d76cca-4645-4893-8d68-5cc68ba42dc3`::ref 0 aborting False
Thread-32158::DEBUG::2014-01-08 =
11:54:42,189::task::579::TaskManager.Task::(_updateState) =
Task=3D`4b2bde05-78f2-42b2-a5be-171377c6905e`::moving from state init -> =
state preparing
Thread-32158::INFO::2014-01-08 =
11:54:42,190::logUtils::44::dispatcher::(wrapper) Run and protect: =
teardownImage(sdUUID=3D'f14f471e-0cce-414d-af57-779eeb88c97a', =
spUUID=3D'18f6234c-a9de-4fdf-bd9a-2bd90b9f33f9', =
imgUUID=3D'8df43d38-c4c7-4711-bc87-55f35d1550e5', volUUID=3DNone)
Thread-32158::DEBUG::2014-01-08 =
11:54:42,190::resourceManager::197::ResourceManager.Request::(__init__) =
ResName=3D`Storage.f14f471e-0cce-414d-af57-779eeb88c97a`ReqID=3D`0907e117-=
ba60-4c9f-a190-ebb7e027b4c2`::Request was made in =
'/usr/share/vdsm/storage/hsm.py' line '3283' at 'teardownImage'
Thread-32158::DEBUG::2014-01-08 =
11:54:42,190::resourceManager::541::ResourceManager::(registerResource) =
Trying to register resource =
'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' for lock type 'shared'
Thread-32158::DEBUG::2014-01-08 =
11:54:42,190::resourceManager::600::ResourceManager::(registerResource) =
Resource 'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' is free. Now =
locking as 'shared' (1 active user)
Thread-32158::DEBUG::2014-01-08 =
11:54:42,190::resourceManager::237::ResourceManager.Request::(grant) =
ResName=3D`Storage.f14f471e-0cce-414d-af57-779eeb88c97a`ReqID=3D`0907e117-=
ba60-4c9f-a190-ebb7e027b4c2`::Granted request
Thread-32158::DEBUG::2014-01-08 =
11:54:42,190::task::811::TaskManager.Task::(resourceAcquired) =
Task=3D`4b2bde05-78f2-42b2-a5be-171377c6905e`::_resourcesAcquired: =
Storage.f14f471e-0cce-414d-af57-779eeb88c97a (shared)
Thread-32158::DEBUG::2014-01-08 =
11:54:42,191::task::974::TaskManager.Task::(_decref) =
Task=3D`4b2bde05-78f2-42b2-a5be-171377c6905e`::ref 1 aborting False
Thread-32158::INFO::2014-01-08 =
11:54:42,191::logUtils::47::dispatcher::(wrapper) Run and protect: =
teardownImage, Return response: None
Thread-32158::DEBUG::2014-01-08 =
11:54:42,191::task::1168::TaskManager.Task::(prepare) =
Task=3D`4b2bde05-78f2-42b2-a5be-171377c6905e`::finished: None
Thread-32158::DEBUG::2014-01-08 =
11:54:42,191::task::579::TaskManager.Task::(_updateState) =
Task=3D`4b2bde05-78f2-42b2-a5be-171377c6905e`::moving from state =
preparing -> state finished
Thread-32158::DEBUG::2014-01-08 =
11:54:42,191::resourceManager::939::ResourceManager.Owner::(releaseAll) =
Owner.releaseAll requests {} resources =
{'Storage.f14f471e-0cce-414d-af57-779eeb88c97a': < ResourceRef =
'Storage.f14f471e-0cce-414d-af57-779eeb88c97a', isValid: 'True' obj: =
'None'>}
Thread-32158::DEBUG::2014-01-08 =
11:54:42,191::resourceManager::976::ResourceManager.Owner::(cancelAll) =
Owner.cancelAll requests {}
Thread-32158::DEBUG::2014-01-08 =
11:54:42,191::resourceManager::615::ResourceManager::(releaseResource) =
Trying to release resource =
'Storage.f14f471e-0cce-414d-af57-779eeb88c97a'
Thread-32158::DEBUG::2014-01-08 =
11:54:42,192::resourceManager::634::ResourceManager::(releaseResource) =
Released resource 'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' (0 =
active users)
Thread-32158::DEBUG::2014-01-08 =
11:54:42,192::resourceManager::640::ResourceManager::(releaseResource) =
Resource 'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' is free, finding =
out if anyone is waiting for it.
Thread-32158::DEBUG::2014-01-08 =
11:54:42,192::resourceManager::648::ResourceManager::(releaseResource) =
No one is waiting for resource =
'Storage.f14f471e-0cce-414d-af57-779eeb88c97a', Clearing records.
Thread-32158::DEBUG::2014-01-08 =
11:54:42,192::task::974::TaskManager.Task::(_decref) =
Task=3D`4b2bde05-78f2-42b2-a5be-171377c6905e`::ref 0 aborting False
Thread-32158::DEBUG::2014-01-08 =
11:54:42,192::task::579::TaskManager.Task::(_updateState) =
Task=3D`75d1051f-b118-4af8-b9f1-504fcd1802c2`::moving from state init -> =
state preparing
Thread-32158::INFO::2014-01-08 =
11:54:42,192::logUtils::44::dispatcher::(wrapper) Run and protect: =
teardownImage(sdUUID=3D'f14f471e-0cce-414d-af57-779eeb88c97a', =
spUUID=3D'18f6234c-a9de-4fdf-bd9a-2bd90b9f33f9', =
imgUUID=3D'e1886286-3d81-47d0-ae8d-77454e464078', volUUID=3DNone)
Thread-32158::DEBUG::2014-01-08 =
11:54:42,193::resourceManager::197::ResourceManager.Request::(__init__) =
ResName=3D`Storage.f14f471e-0cce-414d-af57-779eeb88c97a`ReqID=3D`8908ad39-=
2555-4ef5-a6ae-49e8504de015`::Request was made in =
'/usr/share/vdsm/storage/hsm.py' line '3283' at 'teardownImage'
Thread-32158::DEBUG::2014-01-08 =
11:54:42,193::resourceManager::541::ResourceManager::(registerResource) =
Trying to register resource =
'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' for lock type 'shared'
Thread-32158::DEBUG::2014-01-08 =
11:54:42,196::resourceManager::600::ResourceManager::(registerResource) =
Resource 'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' is free. Now =
locking as 'shared' (1 active user)
Thread-32158::DEBUG::2014-01-08 =
11:54:42,196::resourceManager::237::ResourceManager.Request::(grant) =
ResName=3D`Storage.f14f471e-0cce-414d-af57-779eeb88c97a`ReqID=3D`8908ad39-=
2555-4ef5-a6ae-49e8504de015`::Granted request
Thread-32158::DEBUG::2014-01-08 =
11:54:42,196::task::811::TaskManager.Task::(resourceAcquired) =
Task=3D`75d1051f-b118-4af8-b9f1-504fcd1802c2`::_resourcesAcquired: =
Storage.f14f471e-0cce-414d-af57-779eeb88c97a (shared)
Thread-32158::DEBUG::2014-01-08 =
11:54:42,196::task::974::TaskManager.Task::(_decref) =
Task=3D`75d1051f-b118-4af8-b9f1-504fcd1802c2`::ref 1 aborting False
Thread-32158::INFO::2014-01-08 =
11:54:42,196::logUtils::47::dispatcher::(wrapper) Run and protect: =
teardownImage, Return response: None
Thread-32158::DEBUG::2014-01-08 =
11:54:42,196::task::1168::TaskManager.Task::(prepare) =
Task=3D`75d1051f-b118-4af8-b9f1-504fcd1802c2`::finished: None
Thread-32158::DEBUG::2014-01-08 =
11:54:42,197::task::579::TaskManager.Task::(_updateState) =
Task=3D`75d1051f-b118-4af8-b9f1-504fcd1802c2`::moving from state =
preparing -> state finished
Thread-32158::DEBUG::2014-01-08 =
11:54:42,197::resourceManager::939::ResourceManager.Owner::(releaseAll) =
Owner.releaseAll requests {} resources =
{'Storage.f14f471e-0cce-414d-af57-779eeb88c97a': < ResourceRef =
'Storage.f14f471e-0cce-414d-af57-779eeb88c97a', isValid: 'True' obj: =
'None'>}
Thread-32158::DEBUG::2014-01-08 =
11:54:42,197::resourceManager::976::ResourceManager.Owner::(cancelAll) =
Owner.cancelAll requests {}
Thread-32158::DEBUG::2014-01-08 =
11:54:42,197::resourceManager::615::ResourceManager::(releaseResource) =
Trying to release resource =
'Storage.f14f471e-0cce-414d-af57-779eeb88c97a'
Thread-32158::DEBUG::2014-01-08 =
11:54:42,197::resourceManager::634::ResourceManager::(releaseResource) =
Released resource 'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' (0 =
active users)
Thread-32158::DEBUG::2014-01-08 =
11:54:42,197::resourceManager::640::ResourceManager::(releaseResource) =
Resource 'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' is free, finding =
out if anyone is waiting for it.
--Apple-Mail=_877DC8BA-BA64-4899-AC8F-C9588B685A8B
Content-Transfer-Encoding: quoted-printable
Content-Type: text/html;
charset=windows-1252
<html><head><meta http-equiv=3D"Content-Type"
content=3D"text/html =
charset=3Dwindows-1252"></head><body style=3D"word-wrap: break-word;
=
-webkit-nbsp-mode: space; -webkit-line-break: after-white-space;">So =
twice now under oVirt 3.3.2 I have added 2 virtio-scsi devices to a =
single virtual host. <div><br></div><div>After doing so,
the VM =
would fail to boot due to duplicate disk ID. The first time I =
thought it a fluke, second time=92s a =
bug?</div><div><br></div><div>Fortunately they were empty
data disks and =
I was able to get around the problem by deleting one and recreating =
it.</div><div><br></div><div>VDSM
log:</div><div><br></div><div><div =
style=3D"margin: 0px; font-size: 11px; font-family: =
Menlo;">Thread-32154::INFO::2014-01-08 =
11:54:39,717::clientIF::350::vds::(prepareVolumePath) prepared volume =
path: =
/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/imag=
es/e1886286-3d81-47d0-ae8d-77454e464078/1ef64ebd-6415-43d1-a34f-28f05cf6de=
a2</div><div style=3D"margin: 0px; font-size: 11px; font-family: =
Menlo;">Thread-32154::DEBUG::2014-01-08 =
11:54:39,740::vm::2984::vm.Vm::(_run) =
vmId=3D`c2aff4cc-0de6-4342-a565-669b1825838c`::<?xml version=3D"1.0" =
encoding=3D"utf-8"?></div><div style=3D"margin: 0px;
font-size: 11px; =
font-family: Menlo;"><domain
type=3D"kvm"></div><div =
style=3D"margin: 0px; font-size: 11px; font-family: Menlo;"> =
<name>cobra</name></div><div =
style=3D"margin: 0px; font-size: 11px; font-family: Menlo;"> =
=
<uuid>c2aff4cc-0de6-4342-a565-669b1825838c</uuid></div><div
=
style=3D"margin: 0px; font-size: 11px; font-family: Menlo;"> =
<memory>4194304</memory></div><div =
style=3D"margin: 0px; font-size: 11px; font-family: Menlo;"> =
=
<currentMemory>4194304</currentMemory></div><div =
style=3D"margin: 0px; font-size: 11px; font-family: Menlo;"> =
<vcpu>3</vcpu></div><div style=3D"margin:=
0px; font-size: 11px; font-family: Menlo;">
=
<memtune></div><div style=3D"margin: 0px; font-size: 11px; =
font-family: Menlo;">
=
<min_guarantee>4194304</min_guarantee></div><div =
style=3D"margin: 0px; font-size: 11px; font-family: Menlo;"> =
</memtune></div><div
style=3D"margin: 0px; =
font-size: 11px; font-family: Menlo;">
=
<devices></div><div style=3D"margin: 0px; font-size: 11px; =
font-family: Menlo;">
=
<channel type=3D"unix"></div><div
style=3D"margin: 0px; =
font-size: 11px; font-family: Menlo;">
=
<target =
name=3D"com.redhat.rhevm.vdsm"
type=3D"virtio"/></div><div =
style=3D"margin: 0px; font-size: 11px; font-family: Menlo;"> =
=
<source mode=3D"bind" =
path=3D"/var/lib/libvirt/qemu/channels/c2aff4cc-0de6-4342-a565-669b1825838=
c.com.redhat.rhevm.vdsm"/></div><div style=3D"margin: 0px;
font-size: =
11px; font-family: Menlo;">
=
</channel></div><div style=3D"margin:
0px; =
font-size: 11px; font-family: Menlo;">
=
<channel
type=3D"unix"></div><div =
style=3D"margin: 0px; font-size: 11px; font-family: Menlo;"> =
=
<target name=3D"org.qemu.guest_agent.0" =
type=3D"virtio"/></div><div style=3D"margin: 0px;
font-size: 11px; =
font-family: Menlo;">
=
<source
mode=3D"bind" =
path=3D"/var/lib/libvirt/qemu/channels/c2aff4cc-0de6-4342-a565-669b1825838=
c.org.qemu.guest_agent.0"/></div><div style=3D"margin: 0px; =
font-size: 11px; font-family: Menlo;">
=
</channel></div><div
style=3D"margin: 0px; =
font-size: 11px; font-family: Menlo;">
=
<input bus=3D"ps2"
type=3D"mouse"/></div><div =
style=3D"margin: 0px; font-size: 11px; font-family: Menlo;"> =
<channel =
type=3D"spicevmc"></div><div style=3D"margin: 0px;
font-size: 11px; =
font-family: Menlo;">
=
<target
name=3D"com.redhat.spice.0" =
type=3D"virtio"/></div><div style=3D"margin: 0px;
font-size: 11px; =
font-family: Menlo;">
=
</channel></div><div style=3D"margin: 0px;
font-size: 11px; =
font-family: Menlo;">
=
<graphics autoport=3D"yes" keymap=3D"en-us"
listen=3D"0" =
passwd=3D"*****" passwdValidTo=3D"1970-01-01T00:00:01"
port=3D"-1" =
tlsPort=3D"-1" type=3D"spice"></div><div
style=3D"margin: 0px; =
font-size: 11px; font-family: Menlo;">
=
<channel =
mode=3D"secure" name=3D"main"/></div><div
style=3D"margin: 0px; =
font-size: 11px; font-family: Menlo;">
=
<channel =
mode=3D"secure" name=3D"inputs"/></div><div
style=3D"margin: 0px; =
font-size: 11px; font-family: Menlo;">
=
<channel =
mode=3D"secure" name=3D"cursor"/></div><div
style=3D"margin: 0px; =
font-size: 11px; font-family: Menlo;">
=
<channel =
mode=3D"secure" name=3D"playback"/></div><div
style=3D"margin: 0px; =
font-size: 11px; font-family: Menlo;">
=
<channel =
mode=3D"secure" name=3D"record"/></div><div
style=3D"margin: 0px; =
font-size: 11px; font-family: Menlo;">
=
<channel =
mode=3D"secure" name=3D"display"/></div><div
style=3D"margin: 0px; =
font-size: 11px; font-family: Menlo;">
=
<channel =
mode=3D"secure" name=3D"usbredir"/></div><div
style=3D"margin: 0px; =
font-size: 11px; font-family: Menlo; min-height: =
13px;"><br></div></div><div style=3D"margin: 0px;
font-size: 11px; =
font-family: Menlo; min-height: 13px;"><div style=3D"margin: =
0px;">
=
<channel mode=3D"secure" =
name=3D"display"/></div><div style=3D"margin:
0px;"> =
=
<channel mode=3D"secure"
name=3D"usbredir"/></div><div =
style=3D"margin: 0px;">
=
<channel
mode=3D"secure" =
name=3D"smartcard"/></div><div style=3D"margin:
0px;"> =
</graphics></div><div =
style=3D"margin: 0px;">
=
<controller model=3D"virtio-scsi"
type=3D"scsi"></div><div =
style=3D"margin: 0px;">
=
<address
bus=3D"0x00" =
domain=3D"0x0000" function=3D"0x0" slot=3D"0x05" =
type=3D"pci"/></div><div style=3D"margin:
0px;"> =
</controller></div><div =
style=3D"margin: 0px;">
=
<video></div><div style=3D"margin:
0px;"> =
=
<address bus=3D"0x00" domain=3D"0x0000"
function=3D"0x0" slot=3D"0x02" =
type=3D"pci"/></div><div style=3D"margin:
0px;"> =
<model =
heads=3D"1" type=3D"qxl"
vram=3D"32768"/></div><div style=3D"margin: =
0px;">
=
</video></div><div style=3D"margin: 0px;">
=
<interface =
type=3D"bridge"></div><div style=3D"margin:
0px;"> =
=
<address bus=3D"0x00" domain=3D"0x0000"
function=3D"0x0" slot=3D"0x03" =
type=3D"pci"/></div><div style=3D"margin:
0px;"> =
<mac =
address=3D"00:1a:4a:5b:9f:02"/></div><div style=3D"margin:
=
0px;">
=
<model
type=3D"virtio"/></div><div =
style=3D"margin: 0px;">
=
<source =
bridge=3D"ovirtmgmt"/></div><div style=3D"margin:
0px;"> =
=
<filterref filter=3D"vdsm-no-mac-spoofing"/></div><div =
style=3D"margin: 0px;">
=
<link
state=3D"up"/></div><div =
style=3D"margin: 0px;">
=
</interface></div><div style=3D"margin:
0px;"> =
<disk
device=3D"cdrom" =
snapshot=3D"no" type=3D"file"></div><div
style=3D"margin: =
0px;">
=
<address bus=3D"1"
controller=3D"0" target=3D"0" =
type=3D"drive" unit=3D"0"/></div><div
style=3D"margin: 0px;"> =
=
<source file=3D""
startupPolicy=3D"optional"/></div><div =
style=3D"margin: 0px;">
=
<target
bus=3D"ide" =
dev=3D"hdc"/></div><div style=3D"margin:
0px;"> =
=
<readonly/></div><div style=3D"margin:
0px;"> =
=
<serial/></div><div style=3D"margin: 0px;">
=
</disk></div><div =
style=3D"margin: 0px;">
=
<disk device=3D"disk" snapshot=3D"no" =
type=3D"file"></div><div style=3D"margin:
0px;"> =
=
<source =
file=3D"/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c=
97a/images/94a298cb-87a5-48cd-b78f-770582b50639/c4864816-7384-4d0e-927e-29=
ea36d5fa4a"/></div><div style=3D"margin:
0px;"> =
=
<target bus=3D"scsi" dev=3D"sda"/></div><div
style=3D"margin: =
0px;">
=
=
<serial>94a298cb-87a5-48cd-b78f-770582b50639</serial></div><di=
v style=3D"margin: 0px;">
=
<boot =
order=3D"1"/></div><div style=3D"margin:
0px;"> =
=
<driver cache=3D"none" error_policy=3D"stop"
io=3D"threads" =
name=3D"qemu" type=3D"raw"/></div><div
style=3D"margin: 0px;"> =
</disk></div><div =
style=3D"margin: 0px;">
=
<disk device=3D"disk" snapshot=3D"no" =
type=3D"file"></div><div style=3D"margin:
0px;"> =
=
<address bus=3D"0x00" domain=3D"0x0000"
function=3D"0x0" slot=3D"0x07" =
type=3D"pci"/></div><div style=3D"margin:
0px;"> =
=
<source =
file=3D"/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c=
97a/images/8df43d38-c4c7-4711-bc87-55f35d1550e5/42786233-04c0-4a9f-b731-6e=
aa2ca4d40d"/></div><div style=3D"margin:
0px;"> =
=
<target bus=3D"virtio" dev=3D"vda"/></div><div
style=3D"margin: =
0px;">
=
=
<serial>8df43d38-c4c7-4711-bc87-55f35d1550e5</serial></div><di=
v style=3D"margin: 0px;">
=
<driver
cache=3D"none" =
error_policy=3D"stop" io=3D"threads" name=3D"qemu" =
type=3D"raw"/></div><div style=3D"margin:
0px;"> =
</disk></div><div =
style=3D"margin: 0px;">
=
<disk device=3D"disk" snapshot=3D"no" =
type=3D"file"></div><div><br></div><div><div
style=3D"margin: =
0px;">
=
<address bus=3D"0"
controller=3D"0" =
target=3D"0" type=3D"drive"
unit=3D"0"/></div><div style=3D"margin: =
0px;">
=
<source =
file=3D"/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c=
97a/images/e1886286-3d81-47d0-ae8d-77454e464078/1ef64ebd-6415-43d1-a34f-28=
f05cf6dea2"/></div><div style=3D"margin:
0px;"> =
=
<target bus=3D"scsi" dev=3D"sdb"/></div><div
style=3D"margin: =
0px;">
=
=
<serial>e1886286-3d81-47d0-ae8d-77454e464078</serial></div><di=
v style=3D"margin: 0px;">
=
<driver
cache=3D"none" =
error_policy=3D"stop" io=3D"threads" name=3D"qemu" =
type=3D"raw"/></div><div style=3D"margin:
0px;"> =
</disk></div><div =
style=3D"margin: 0px;">
=
<sound model=3D"ich6"></div><div
style=3D"margin: =
0px;">
=
<address bus=3D"0x00"
domain=3D"0x0000" =
function=3D"0x0" slot=3D"0x04"
type=3D"pci"/></div><div =
style=3D"margin: 0px;">
=
</sound></div><div style=3D"margin:
0px;"> =
<memballoon =
model=3D"virtio"/></div><div style=3D"margin:
0px;"> =
</devices></div><div style=3D"margin:
0px;"> =
<os></div><div
style=3D"margin: 0px;"> =
<type
arch=3D"x86_64" =
machine=3D"pc-1.0">hvm</type></div><div
style=3D"margin: =
0px;">
<smbios =
mode=3D"sysinfo"/></div><div style=3D"margin:
0px;"> =
</os></div><div style=3D"margin:
0px;"> =
<sysinfo type=3D"smbios"></div><div
style=3D"margin: =
0px;">
=
<system></div><div style=3D"margin: 0px;">
=
<entry =
name=3D"manufacturer">oVirt</entry></div><div
style=3D"margin: =
0px;">
=
<entry name=3D"product">oVirt =
Node</entry></div><div style=3D"margin:
0px;"> =
<entry =
name=3D"version">19-5</entry></div><div
style=3D"margin: =
0px;">
=
<entry =
name=3D"serial">2061001F-C600-0006-E1BC-BCAEC518BA45</entry></div=
<div style=3D"margin: 0px;">
=
<entry =
name=3D"uuid">c2aff4cc-0de6-4342-a565-669b1825838c</entry></div><=
div style=3D"margin: 0px;">
=
</system></div><div style=3D"margin:
0px;"> =
</sysinfo></div><div
style=3D"margin: =
0px;"> <clock
adjustment=3D"-21600" =
offset=3D"variable"></div><div style=3D"margin:
0px;"> =
<timer
name=3D"rtc" =
tickpolicy=3D"catchup"/></div><div style=3D"margin:
0px;"> =
</clock></div><div
style=3D"margin: =
0px;">
<features></div><div =
style=3D"margin: 0px;">
=
<acpi/></div><div style=3D"margin:
0px;"> =
</features></div><div style=3D"margin:
0px;"> =
<cpu
match=3D"exact"></div><div =
style=3D"margin: 0px;">
=
<model>Nehalem</model></div><div
style=3D"margin: =
0px;">
=
<topology cores=3D"1" sockets=3D"3"
threads=3D"1"/></div><div =
style=3D"margin: 0px;"> =
</cpu></div><div style=3D"margin: =
0px;"></domain></div></div><div style=3D"margin:
0px;"><div =
style=3D"margin: 0px;">Thread-32154::DEBUG::2014-01-08 =
11:54:40,218::libvirtconnection::108::libvirtconnection::(wrapper) =
Unknown libvirterror: ecode: 1 edom: 10 level: 2 message: internal error =
process exited while connecting to monitor: qemu-system-x86_64: -drive =
file=3D/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c9=
7a/images/e1886286-3d81-47d0-ae8d-77454e464078/1ef64ebd-6415-43d1-a34f-28f=
05cf6dea2,if=3Dnone,id=3Ddrive-scsi0-0-0-0,format=3Draw,serial=3De1886286-=
3d81-47d0-ae8d-77454e464078,cache=3Dnone,werror=3Dstop,rerror=3Dstop,aio=3D=
threads: Duplicate ID 'drive-scsi0-0-0-0' for drive</div><div =
style=3D"margin: 0px; min-height: 13px;"><br></div><div
style=3D"margin: =
0px;">Thread-32154::DEBUG::2014-01-08 =
11:54:40,218::vm::2109::vm.Vm::(_startUnderlyingVm) =
vmId=3D`c2aff4cc-0de6-4342-a565-669b1825838c`::_ongoingCreations =
released</div><div style=3D"margin: =
0px;">Thread-32154::ERROR::2014-01-08 =
11:54:40,218::vm::2135::vm.Vm::(_startUnderlyingVm) =
vmId=3D`c2aff4cc-0de6-4342-a565-669b1825838c`::The vm start process =
failed</div><div style=3D"margin: 0px;">Traceback (most recent call
=
last):</div><div style=3D"margin: 0px;"> File =
"/usr/share/vdsm/vm.py", line 2095, in _startUnderlyingVm</div><div =
style=3D"margin: 0px;"> self._run()</div><div
=
style=3D"margin: 0px;"> File "/usr/share/vdsm/vm.py",
line 3018, =
in _run</div><div style=3D"margin: 0px;"> =
self._connection.createXML(domxml, flags),</div><div style=3D"margin: =
0px;"> File =
"/usr/lib64/python2.7/site-packages/vdsm/libvirtconnection.py", line 76, =
in wrapper</div><div style=3D"margin: 0px;">
ret =3D =
f(*args, **kwargs)</div><div style=3D"margin: 0px;"> File
=
"/usr/lib64/python2.7/site-packages/libvirt.py", line 2805, in =
createXML</div><div style=3D"margin: 0px;"> if
ret is =
None:raise libvirtError('virDomainCreateXML() failed', =
conn=3Dself)</div><div style=3D"margin: 0px;">libvirtError: internal
=
error process exited while connecting to monitor: qemu-system-x86_64: =
-drive =
file=3D/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c9=
7a/images/e1886286-3d81-47d0-ae8d-77454e464078/1ef64ebd-6415-43d1-a34f-28f=
05cf6dea2,if=3Dnone,id=3Ddrive-scsi0-0-0-0,format=3Draw,serial=3De1886286-=
3d81-47d0-ae8d-77454e464078,cache=3Dnone,werror=3Dstop,rerror=3Dstop,aio=3D=
threads: Duplicate ID 'drive-scsi0-0-0-0' for drive</div><div =
style=3D"margin: 0px; min-height: 13px;"><br></div><div
style=3D"margin: =
0px;">Thread-32154::DEBUG::2014-01-08 =
11:54:40,223::vm::2577::vm.Vm::(setDownStatus) =
vmId=3D`c2aff4cc-0de6-4342-a565-669b1825838c`::Changed state to Down: =
internal error process exited while connecting to monitor: =
qemu-system-x86_64: -drive =
file=3D/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c9=
7a/images/e1886286-3d81-47d0-ae8d-77454e464078/1ef64ebd-6415-43d1-a34f-28f=
05cf6dea2,if=3Dnone,id=3Ddrive-scsi0-0-0-0,format=3Draw,serial=3De1886286-=
3d81-47d0-ae8d-77454e464078,cache=3Dnone,werror=3Dstop,rerror=3Dstop,aio=3D=
threads: Duplicate ID 'drive-scsi0-0-0-0' for drive</div><div =
style=3D"margin: 0px; min-height: 13px;"><br></div><div
style=3D"margin: =
0px;"><div style=3D"margin:
0px;">Thread-32158::WARNING::2014-01-08 =
11:54:42,185::clientIF::362::vds::(teardownVolumePath) Drive is not a =
vdsm image: VOLWM_CHUNK_MB:1024 VOLWM_CHUNK_REPLICATE_MULT:2 =
VOLWM_FREE_PCT:50 _blockDev:False _checkIoTuneCategories:<bound =
method Drive._checkIoTuneCategories of <vm.Drive object at =
0x7f1150113a90>> _customize:<bound method Drive._customize of =
<vm.Drive object at 0x7f1150113a90>> _deviceXML:<disk =
device=3D"cdrom" snapshot=3D"no"
type=3D"file"><address =
bus=3D"1" controller=3D"0"
target=3D"0" type=3D"drive" =
unit=3D"0"/><source file=3D"" =
startupPolicy=3D"optional"/><target bus=3D"ide" =
dev=3D"hdc"/><readonly/><serial></serial></disk>=
; _makeName:<bound method Drive._makeName of <vm.Drive object at =
0x7f1150113a90>> _setExtSharedState:<bound method =
Drive._setExtSharedState of <vm.Drive object at =
0x7f1150113a90>> _validateIoTuneParams:<bound method =
Drive._validateIoTuneParams of <vm.Drive object at =
0x7f1150113a90>> address:{' controller': '0', '
target': '0', =
'unit': '0', ' bus': '1', ' type':
'drive'} apparentsize:0 =
blockDev:False cache:none conf:{'status': 'Down', 'acpiEnable':
'true', =
'emulatedMachine': 'pc-1.0', 'vmId': =
'c2aff4cc-0de6-4342-a565-669b1825838c', 'pid': '0',
'memGuaranteedSize': =
4096, 'timeOffset': '-21600', 'keyboardLayout': 'en-us',
'displayPort': =
'-1', 'displaySecurePort': '-1', 'spiceSslCipherSuite':
'DEFAULT', =
'cpuType': 'Nehalem', 'custom': =
{'device_142f948d-f916-4f42-bd28-cb4f0b8ebb40device_615c1466-850e-4362-a4f=
b-60df1aaee1e8device_861eb290-19bc-4ace-b2cb-85cbb2e0eb5adevice_94db7fa0-0=
71d-4181-bac6-826ecfca3dd8device_a2e6354f-4ad6-475f-bd18-754fcedf505f': =
'VmDevice {vmId=3Dc2aff4cc-0de6-4342-a565-669b1825838c, =
deviceId=3Da2e6354f-4ad6-475f-bd18-754fcedf505f, device=3Dunix, =
type=3DCHANNEL, bootOrder=3D0, specParams=3D{}, address=3D{port=3D2, =
bus=3D0, controller=3D0, type=3Dvirtio-serial}, managed=3Dfalse, =
plugged=3Dtrue, readOnly=3Dfalse, deviceAlias=3Dchannel1, =
customProperties=3D{}, snapshotId=3Dnull}', =
'device_142f948d-f916-4f42-bd28-cb4f0b8ebb40': 'VmDevice =
{vmId=3Dc2aff4cc-0de6-4342-a565-669b1825838c, =
deviceId=3D142f948d-f916-4f42-bd28-cb4f0b8ebb40, device=3Dvirtio-serial, =
type=3DCONTROLLER, bootOrder=3D0, specParams=3D{}, address=3D{bus=3D0x00, =
domain=3D0x0000, type=3Dpci, slot=3D0x06, function=3D0x0}, =
managed=3Dfalse, plugged=3Dtrue, readOnly=3Dfalse, =
deviceAlias=3Dvirtio-serial0, customProperties=3D{}, snapshotId=3Dnull}', =
'device_142f948d-f916-4f42-bd28-cb4f0b8ebb40device_615c1466-850e-4362-a4fb=
-60df1aaee1e8device_861eb290-19bc-4ace-b2cb-85cbb2e0eb5a': 'VmDevice =
{vmId=3Dc2aff4cc-0de6-4342-a565-669b1825838c, =
deviceId=3D861eb290-19bc-4ace-b2cb-85cbb2e0eb5a, device=3Dide, =
type=3DCONTROLLER, bootOrder=3D0, specParams=3D{}, address=3D{bus=3D0x00, =
domain=3D0x0000, type=3Dpci, slot=3D0x01, function=3D0x1}, =
managed=3Dfalse, plugged=3Dtrue, readOnly=3Dfalse, deviceAlias=3Dide0, =
customProperties=3D{}, snapshotId=3Dnull}', =
'device_142f948d-f916-4f42-bd28-cb4f0b8ebb40device_615c1466-850e-4362-a4fb=
-60df1aaee1e8device_861eb290-19bc-4ace-b2cb-85cbb2e0eb5adevice_94db7fa0-07=
1d-4181-bac6-826ecfca3dd8': 'VmDevice =
{vmId=3Dc2aff4cc-0de6-4342-a565-669b1825838c, =
deviceId=3D94db7fa0-071d-4181-bac6-826ecfca3dd8, device=3Dunix, =
type=3DCHANNEL, bootOrder=3D0, specParams=3D{}, address=3D{port=3D1, =
bus=3D0, controller=3D0, type=3Dvirtio-serial}, managed=3Dfalse, =
plugged=3Dtrue, readOnly=3Dfalse, deviceAlias=3Dchannel0, =
customProperties=3D{}, snapshotId=3Dnull}', =
'device_142f948d-f916-4f42-bd28-cb4f0b8ebb40device_615c1466-850e-4362-a4fb=
-60df1aaee1e8': 'VmDevice {vmId=3Dc2aff4cc-0de6-4342-a565-669b1825838c, =
deviceId=3D615c1466-850e-4362-a4fb-60df1aaee1e8, device=3Dspicevmc, =
type=3DCHANNEL, bootOrder=3D0, specParams=3D{}, address=3D{port=3D3, =
bus=3D0, controller=3D0, type=3Dvirtio-serial}, managed=3Dfalse, =
plugged=3Dtrue, readOnly=3Dfalse, deviceAlias=3Dchannel2, =
customProperties=3D{}, snapshotId=3Dnull}'}, 'clientIp': '',
'exitCode': =
1, 'nicModel': 'rtl8139,pv', 'smartcardEnable': 'false',
'kvmEnable': =
'true', 'exitMessage': "internal error process exited while
connecting =
to monitor: qemu-system-x86_64: -drive =
file=3D/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c9=
7a/images/e1886286-3d81-47d0-ae8d-77454e464078/1ef64ebd-6415-43d1-a34f-28f=
05cf6dea2,if=3Dnone,id=3Ddrive-scsi0-0-0-0,format=3Draw,serial=3De1886286-=
3d81-47d0-ae8d-77454e464078,cache=3Dnone,werror=3Dstop,rerror=3Dstop,aio=3D=
threads: Duplicate ID 'drive-scsi0-0-0-0' for drive\n", =
'transparentHugePages': 'true', 'devices': [{'specParams':
{}, =
'deviceId': 'db6166cb-e977-485e-8c82-fa48ca75e709', 'address':
{'bus': =
'0x00', ' slot': '0x05', ' domain': '0x0000',
' type': 'pci', ' =
function': '0x0'}, 'device': 'scsi', 'model':
'virtio-scsi', 'type': =
'controller'}, {'device': 'qxl', 'specParams':
{'vram': '32768', =
'heads': '1'}, 'type': 'video', 'deviceId': =
'8b0e3dbc-27c6-4eae-ba6b-201c3e1736aa', 'address': {'bus':
'0x00', ' =
slot': '0x02', ' domain': '0x0000', ' type':
'pci', ' function': =
'0x0'}}, {'nicModel': 'pv', 'macAddr':
'00:1a:4a:5b:9f:02', =
'linkActive': 'true', 'network': 'ovirtmgmt',
'filter': =
'vdsm-no-mac-spoofing', 'specParams': {}, 'deviceId': =
'738c8ebe-b014-4d65-8c78-942aaf12bfb5', 'address': {'bus':
'0x00', ' =
slot': '0x03', ' domain': '0x0000', ' type':
'pci', ' function': '0x0'}, =
'device': 'bridge', 'type': 'interface'},
{'index': '2', 'iface': 'ide', =
'address': {' controller': '0', ' target': '0',
'unit': '0', ' bus': =
'1', ' type': 'drive'}, 'specParams': {'path':
''}, 'readonly': 'true', =
'deviceId': '5611019a-948e-41b3-8ffd-75790ebcdf84', 'path':
'', =
'device': 'cdrom', 'shared': 'false', 'type':
'disk'}, {'volumeInfo': =
{'domainID': 'f14f471e-0cce-414d-af57-779eeb88c97a', 'volType':
'path', =
'leaseOffset': 0, 'path': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/94a298cb-87a5-48cd-b78f-770582b50639/c4864816-7384-4d0e-927e-29ea36d5f=
a4a', 'volumeID': 'c4864816-7384-4d0e-927e-29ea36d5fa4a',
'leasePath': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/94a298cb-87a5-48cd-b78f-770582b50639/c4864816-7384-4d0e-927e-29ea36d5f=
a4a.lease', 'imageID': '94a298cb-87a5-48cd-b78f-770582b50639'},
'index': =
0, 'iface': 'scsi', 'apparentsize': '162135015424',
'imageID': =
'94a298cb-87a5-48cd-b78f-770582b50639', 'readonly': 'false',
'shared': =
'false', 'truesize': '107119386624', 'type':
'disk', 'domainID': =
'f14f471e-0cce-414d-af57-779eeb88c97a', 'reqsize': '0',
'format': 'raw', =
'deviceId': '94a298cb-87a5-48cd-b78f-770582b50639', 'poolID': =
'18f6234c-a9de-4fdf-bd9a-2bd90b9f33f9', 'device': 'disk',
'path': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/94a298cb-87a5-48cd-b78f-770582b50639/c4864816-7384-4d0e-927e-29ea36d5f=
a4a', 'propagateErrors': 'off', 'optional': 'false',
'bootOrder': '1', =
'volumeID': 'c4864816-7384-4d0e-927e-29ea36d5fa4a', 'specParams':
{}, =
'volumeChain': [{'domainID':
'f14f471e-0cce-414d-af57-779eeb88c97a', =
'volType': 'path', 'leaseOffset': 0, 'path': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/94a298cb-87a5-48cd-b78f-770582b50639/c4864816-7384-4d0e-927e-29ea36d5f=
a4a', 'volumeID': 'c4864816-7384-4d0e-927e-29ea36d5fa4a',
'leasePath': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/94a298cb-87a5-48cd-b78f-770582b50639/c4864816-7384-4d0e-927e-29ea36d5f=
a4a.lease', 'imageID': '94a298cb-87a5-48cd-b78f-770582b50639'}]}, =
{'address': {'bus': '0x00', ' slot': '0x07', '
domain': '0x0000', ' =
type': 'pci', ' function': '0x0'}, 'volumeInfo':
{'domainID': =
'f14f471e-0cce-414d-af57-779eeb88c97a', 'volType': 'path', =
'leaseOffset': 0, 'path': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/8df43d38-c4c7-4711-bc87-55f35d1550e5/42786233-04c0-4a9f-b731-6eaa2ca4d=
40d', 'volumeID': '42786233-04c0-4a9f-b731-6eaa2ca4d40d',
'leasePath': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/8df43d38-c4c7-4711-bc87-55f35d1550e5/42786233-04c0-4a9f-b731-6eaa2ca4d=
40d.lease', 'imageID': '8df43d38-c4c7-4711-bc87-55f35d1550e5'},
'index': =
'0', 'iface': 'virtio', 'apparentsize':
'1073741824', 'imageID': =
'8df43d38-c4c7-4711-bc87-55f35d1550e5', 'readonly': 'false',
'shared': =
'false', 'truesize': '0', 'type': 'disk',
'domainID': =
'f14f471e-0cce-414d-af57-779eeb88c97a', 'reqsize': '0',
'format': 'raw', =
'deviceId': '8df43d38-c4c7-4711-bc87-55f35d1550e5', 'poolID': =
'18f6234c-a9de-4fdf-bd9a-2bd90b9f33f9', 'device': 'disk',
'path': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/8df43d38-c4c7-4711-bc87-55f35d1550e5/42786233-04c0-4a9f-b731-6eaa2ca4d=
40d', 'propagateErrors': 'off', 'optional': 'false',
'volumeID': =
'42786233-04c0-4a9f-b731-6eaa2ca4d40d', 'specParams': {},
'volumeChain': =
[{'domainID': 'f14f471e-0cce-414d-af57-779eeb88c97a', 'volType':
'path', =
'leaseOffset': 0, 'path': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/8df43d38-c4c7-4711-bc87-55f35d1550e5/42786233-04c0-4a9f-b731-6eaa2ca4d=
40d', 'volumeID': '42786233-04c0-4a9f-b731-6eaa2ca4d40d',
'leasePath': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/8df43d38-c4c7-4711-bc87-55f35d1550e5/42786233-04c0-4a9f-b731-6eaa2ca4d=
40d.lease', 'imageID': '8df43d38-c4c7-4711-bc87-55f35d1550e5'}]}, =
{'address': {' controller': '0', ' target': '0',
'unit': '0', ' bus': =
'0', ' type': 'drive'}, 'volumeInfo': {'domainID':
=
'f14f471e-0cce-414d-af57-779eeb88c97a', 'volType': 'path', =
'leaseOffset': 0, 'path': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/e1886286-3d81-47d0-ae8d-77454e464078/1ef64ebd-6415-43d1-a34f-28f05cf6d=
ea2', 'volumeID': '1ef64ebd-6415-43d1-a34f-28f05cf6dea2',
'leasePath': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/e1886286-3d81-47d0-ae8d-77454e464078/1ef64ebd-6415-43d1-a34f-28f05cf6d=
ea2.lease', 'imageID': 'e1886286-3d81-47d0-ae8d-77454e464078'},
'index': =
'1', 'iface': 'scsi', 'apparentsize':
'1073741824', 'imageID': =
'e1886286-3d81-47d0-ae8d-77454e464078', 'readonly': 'false',
'shared': =
'false', 'truesize': '0', 'type': 'disk',
'domainID': =
'f14f471e-0cce-414d-af57-779eeb88c97a', 'reqsize': '0',
'format': 'raw', =
'deviceId': 'e1886286-3d81-47d0-ae8d-77454e464078', 'poolID': =
'18f6234c-a9de-4fdf-bd9a-2bd90b9f33f9', 'device': 'disk',
'path': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/e1886286-3d81-47d0-ae8d-77454e464078/1ef64ebd-6415-43d1-a34f-28f05cf6d=
ea2', 'propagateErrors': 'off', 'optional': 'false',
'volumeID': =
'1ef64ebd-6415-43d1-a34f-28f05cf6dea2', 'specParams': {},
'volumeChain': =
[{'domainID': 'f14f471e-0cce-414d-af57-779eeb88c97a', 'volType':
'path', =
'leaseOffset': 0, 'path': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/e1886286-3d81-47d0-ae8d-77454e464078/1ef64ebd-6415-43d1-a34f-28f05cf6d=
ea2', 'volumeID': '1ef64ebd-6415-43d1-a34f-28f05cf6dea2',
'leasePath': =
'/rhev/data-center/mnt/_disk01_vm/f14f471e-0cce-414d-af57-779eeb88c97a/ima=
ges/e1886286-3d81-47d0-ae8d-77454e464078/1ef64ebd-6415-43d1-a34f-28f05cf6d=
ea2.lease', 'imageID': 'e1886286-3d81-47d0-ae8d-77454e464078'}]}, =
{'device': 'ich6', 'specParams': {}, 'type':
'sound', 'deviceId': =
'a1e596e9-218f-46ba-9f32-b9c966e11d73', 'address': {'bus':
'0x00', ' =
slot': '0x04', ' domain': '0x0000', ' type':
'pci', ' function': =
'0x0'}}, {'device': 'memballoon', 'specParams':
{'model': 'virtio'}, =
'type': 'balloon', 'deviceId':
'5c04fd7e-7249-4e3a-b8eb-91cce72d5b60', =
'target': 4194304}], 'smp': '3', 'vmType': 'kvm',
'memSize': 4096, =
'displayIp': '0', 'spiceSecureChannels': =
'smain,sinputs,scursor,splayback,srecord,sdisplay,susbredir,ssmartcard', =
'smpCoresPerSocket': '1', 'vmName': 'cobra',
'display': 'qxl', 'nice': =
'0'} createXmlElem:<bound method Drive.createXmlElem of <vm.Drive =
object at 0x7f1150113a90>> device:cdrom =
deviceId:5611019a-948e-41b3-8ffd-75790ebcdf84 extSharedState:none =
getLeasesXML:<bound method Drive.getLeasesXML of <vm.Drive object =
at 0x7f1150113a90>> getNextVolumeSize:<bound method =
Drive.getNextVolumeSize of <vm.Drive object at 0x7f1150113a90>> =
getXML:<bound method Drive.getXML of <vm.Drive object at =
0x7f1150113a90>> hasVolumeLeases:False iface:ide index:2 =
isDiskReplicationInProgress:<bound method =
Drive.isDiskReplicationInProgress of <vm.Drive object at =
0x7f1150113a90>> isVdsmImage:<bound method Drive.isVdsmImage of =
<vm.Drive object at 0x7f1150113a90>> =
log:<logUtils.SimpleLogAdapter object at 0x7f111838af90> name:hdc =
networkDev:False path: readonly:true reqsize:0 serial: shared:false =
specParams:{'path': ''} truesize:0 type:disk volExtensionChunk:1024 =
watermarkLimit:536870912</div><div style=3D"margin: 0px;">Traceback
=
(most recent call last):</div><div style=3D"margin: 0px;">
File =
"/usr/share/vdsm/clientIF.py", line 356, in
teardownVolumePath</div><div =
style=3D"margin: 0px;"> res =3D =
self.irs.teardownImage(drive['domainID'],</div><div style=3D"margin:
=
0px;"> File "/usr/share/vdsm/vm.py", line 1389, in =
__getitem__</div><div style=3D"margin: 0px;">
raise =
KeyError(key)</div><div style=3D"margin: 0px;">KeyError: =
'domainID'</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,186::task::579::TaskManager.Task::(_updateState) =
Task=3D`30d76cca-4645-4893-8d68-5cc68ba42dc3`::moving from state init =
-> state preparing</div><div><div style=3D"margin: =
0px;">Thread-32158::INFO::2014-01-08 =
11:54:42,187::logUtils::44::dispatcher::(wrapper) Run and protect: =
teardownImage(sdUUID=3D'f14f471e-0cce-414d-af57-779eeb88c97a', =
spUUID=3D'18f6234c-a9de-4fdf-bd9a-2bd90b9f33f9', =
imgUUID=3D'94a298cb-87a5-48cd-b78f-770582b50639', =
volUUID=3DNone)</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,187::resourceManager::197::ResourceManager.Request::(__init__) =
ResName=3D`Storage.f14f471e-0cce-414d-af57-779eeb88c97a`ReqID=3D`533f2699-=
0684-4247-9d5f-a858ffe96fe9`::Request was made in =
'/usr/share/vdsm/storage/hsm.py' line '3283' at =
'teardownImage'</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,187::resourceManager::541::ResourceManager::(registerResource) =
Trying to register resource =
'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' for lock type =
'shared'</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,187::resourceManager::600::ResourceManager::(registerResource) =
Resource 'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' is free. Now =
locking as 'shared' (1 active user)</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,187::resourceManager::237::ResourceManager.Request::(grant) =
ResName=3D`Storage.f14f471e-0cce-414d-af57-779eeb88c97a`ReqID=3D`533f2699-=
0684-4247-9d5f-a858ffe96fe9`::Granted request</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,188::task::811::TaskManager.Task::(resourceAcquired) =
Task=3D`30d76cca-4645-4893-8d68-5cc68ba42dc3`::_resourcesAcquired: =
Storage.f14f471e-0cce-414d-af57-779eeb88c97a (shared)</div><div =
style=3D"margin: 0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,188::task::974::TaskManager.Task::(_decref) =
Task=3D`30d76cca-4645-4893-8d68-5cc68ba42dc3`::ref 1 aborting =
False</div><div style=3D"margin:
0px;">Thread-32158::INFO::2014-01-08 =
11:54:42,188::logUtils::47::dispatcher::(wrapper) Run and protect: =
teardownImage, Return response: None</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,188::task::1168::TaskManager.Task::(prepare) =
Task=3D`30d76cca-4645-4893-8d68-5cc68ba42dc3`::finished: None</div><div =
style=3D"margin: 0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,188::task::579::TaskManager.Task::(_updateState) =
Task=3D`30d76cca-4645-4893-8d68-5cc68ba42dc3`::moving from state =
preparing -> state finished</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,188::resourceManager::939::ResourceManager.Owner::(releaseAll) =
Owner.releaseAll requests {} resources =
{'Storage.f14f471e-0cce-414d-af57-779eeb88c97a': < ResourceRef =
'Storage.f14f471e-0cce-414d-af57-779eeb88c97a', isValid: 'True' obj: =
'None'>}</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,188::resourceManager::976::ResourceManager.Owner::(cancelAll) =
Owner.cancelAll requests {}</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,189::resourceManager::615::ResourceManager::(releaseResource) =
Trying to release resource =
'Storage.f14f471e-0cce-414d-af57-779eeb88c97a'</div><div
style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,189::resourceManager::634::ResourceManager::(releaseResource) =
Released resource 'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' (0 =
active users)</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,189::resourceManager::640::ResourceManager::(releaseResource) =
Resource 'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' is free, finding =
out if anyone is waiting for it.</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,189::resourceManager::648::ResourceManager::(releaseResource) =
No one is waiting for resource =
'Storage.f14f471e-0cce-414d-af57-779eeb88c97a', Clearing =
records.</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,189::task::974::TaskManager.Task::(_decref) =
Task=3D`30d76cca-4645-4893-8d68-5cc68ba42dc3`::ref 0 aborting =
False</div><div style=3D"margin:
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,189::task::579::TaskManager.Task::(_updateState) =
Task=3D`4b2bde05-78f2-42b2-a5be-171377c6905e`::moving from state init =
-> state preparing</div><div style=3D"margin: =
0px;">Thread-32158::INFO::2014-01-08 =
11:54:42,190::logUtils::44::dispatcher::(wrapper) Run and protect: =
teardownImage(sdUUID=3D'f14f471e-0cce-414d-af57-779eeb88c97a', =
spUUID=3D'18f6234c-a9de-4fdf-bd9a-2bd90b9f33f9', =
imgUUID=3D'8df43d38-c4c7-4711-bc87-55f35d1550e5', =
volUUID=3DNone)</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,190::resourceManager::197::ResourceManager.Request::(__init__) =
ResName=3D`Storage.f14f471e-0cce-414d-af57-779eeb88c97a`ReqID=3D`0907e117-=
ba60-4c9f-a190-ebb7e027b4c2`::Request was made in =
'/usr/share/vdsm/storage/hsm.py' line '3283' at =
'teardownImage'</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,190::resourceManager::541::ResourceManager::(registerResource) =
Trying to register resource =
'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' for lock type =
'shared'</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,190::resourceManager::600::ResourceManager::(registerResource) =
Resource 'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' is free. Now =
locking as 'shared' (1 active user)</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,190::resourceManager::237::ResourceManager.Request::(grant) =
ResName=3D`Storage.f14f471e-0cce-414d-af57-779eeb88c97a`ReqID=3D`0907e117-=
ba60-4c9f-a190-ebb7e027b4c2`::Granted request</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,190::task::811::TaskManager.Task::(resourceAcquired) =
Task=3D`4b2bde05-78f2-42b2-a5be-171377c6905e`::_resourcesAcquired: =
Storage.f14f471e-0cce-414d-af57-779eeb88c97a (shared)</div><div =
style=3D"margin: 0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,191::task::974::TaskManager.Task::(_decref) =
Task=3D`4b2bde05-78f2-42b2-a5be-171377c6905e`::ref 1 aborting =
False</div><div style=3D"margin:
0px;">Thread-32158::INFO::2014-01-08 =
11:54:42,191::logUtils::47::dispatcher::(wrapper) Run and protect: =
teardownImage, Return response: None</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,191::task::1168::TaskManager.Task::(prepare) =
Task=3D`4b2bde05-78f2-42b2-a5be-171377c6905e`::finished: None</div><div =
style=3D"margin: 0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,191::task::579::TaskManager.Task::(_updateState) =
Task=3D`4b2bde05-78f2-42b2-a5be-171377c6905e`::moving from state =
preparing -> state finished</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,191::resourceManager::939::ResourceManager.Owner::(releaseAll) =
Owner.releaseAll requests {} resources =
{'Storage.f14f471e-0cce-414d-af57-779eeb88c97a': < ResourceRef =
'Storage.f14f471e-0cce-414d-af57-779eeb88c97a', isValid: 'True' obj: =
'None'>}</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,191::resourceManager::976::ResourceManager.Owner::(cancelAll) =
Owner.cancelAll requests {}</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,191::resourceManager::615::ResourceManager::(releaseResource) =
Trying to release resource =
'Storage.f14f471e-0cce-414d-af57-779eeb88c97a'</div><div
style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,192::resourceManager::634::ResourceManager::(releaseResource) =
Released resource 'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' (0 =
active users)</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,192::resourceManager::640::ResourceManager::(releaseResource) =
Resource 'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' is free, finding =
out if anyone is waiting for it.</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,192::resourceManager::648::ResourceManager::(releaseResource) =
No one is waiting for resource =
'Storage.f14f471e-0cce-414d-af57-779eeb88c97a', Clearing =
records.</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,192::task::974::TaskManager.Task::(_decref) =
Task=3D`4b2bde05-78f2-42b2-a5be-171377c6905e`::ref 0 aborting =
False</div><div style=3D"margin:
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,192::task::579::TaskManager.Task::(_updateState) =
Task=3D`75d1051f-b118-4af8-b9f1-504fcd1802c2`::moving from state init =
-> state preparing</div><div style=3D"margin: =
0px;">Thread-32158::INFO::2014-01-08 =
11:54:42,192::logUtils::44::dispatcher::(wrapper) Run and protect: =
teardownImage(sdUUID=3D'f14f471e-0cce-414d-af57-779eeb88c97a', =
spUUID=3D'18f6234c-a9de-4fdf-bd9a-2bd90b9f33f9', =
imgUUID=3D'e1886286-3d81-47d0-ae8d-77454e464078', =
volUUID=3DNone)</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,193::resourceManager::197::ResourceManager.Request::(__init__) =
ResName=3D`Storage.f14f471e-0cce-414d-af57-779eeb88c97a`ReqID=3D`8908ad39-=
2555-4ef5-a6ae-49e8504de015`::Request was made in =
'/usr/share/vdsm/storage/hsm.py' line '3283' at =
'teardownImage'</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,193::resourceManager::541::ResourceManager::(registerResource) =
Trying to register resource =
'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' for lock type =
'shared'</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,196::resourceManager::600::ResourceManager::(registerResource) =
Resource 'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' is free. Now =
locking as 'shared' (1 active user)</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,196::resourceManager::237::ResourceManager.Request::(grant) =
ResName=3D`Storage.f14f471e-0cce-414d-af57-779eeb88c97a`ReqID=3D`8908ad39-=
2555-4ef5-a6ae-49e8504de015`::Granted request</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,196::task::811::TaskManager.Task::(resourceAcquired) =
Task=3D`75d1051f-b118-4af8-b9f1-504fcd1802c2`::_resourcesAcquired: =
Storage.f14f471e-0cce-414d-af57-779eeb88c97a (shared)</div><div =
style=3D"margin: 0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,196::task::974::TaskManager.Task::(_decref) =
Task=3D`75d1051f-b118-4af8-b9f1-504fcd1802c2`::ref 1 aborting =
False</div><div style=3D"margin:
0px;">Thread-32158::INFO::2014-01-08 =
11:54:42,196::logUtils::47::dispatcher::(wrapper) Run and protect: =
teardownImage, Return response: None</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,196::task::1168::TaskManager.Task::(prepare) =
Task=3D`75d1051f-b118-4af8-b9f1-504fcd1802c2`::finished: None</div><div =
style=3D"margin: 0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,197::task::579::TaskManager.Task::(_updateState) =
Task=3D`75d1051f-b118-4af8-b9f1-504fcd1802c2`::moving from state =
preparing -> state finished</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,197::resourceManager::939::ResourceManager.Owner::(releaseAll) =
Owner.releaseAll requests {} resources =
{'Storage.f14f471e-0cce-414d-af57-779eeb88c97a': < ResourceRef =
'Storage.f14f471e-0cce-414d-af57-779eeb88c97a', isValid: 'True' obj: =
'None'>}</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,197::resourceManager::976::ResourceManager.Owner::(cancelAll) =
Owner.cancelAll requests {}</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,197::resourceManager::615::ResourceManager::(releaseResource) =
Trying to release resource =
'Storage.f14f471e-0cce-414d-af57-779eeb88c97a'</div><div
style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,197::resourceManager::634::ResourceManager::(releaseResource) =
Released resource 'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' (0 =
active users)</div><div style=3D"margin: =
0px;">Thread-32158::DEBUG::2014-01-08 =
11:54:42,197::resourceManager::640::ResourceManager::(releaseResource) =
Resource 'Storage.f14f471e-0cce-414d-af57-779eeb88c97a' is free, finding =
out if anyone is waiting for it.</div></div><div style=3D"margin: =
0px;"><br></div></div><div style=3D"margin:
0px;"><div style=3D"margin: =
0px;"><br></div></div></div><div><br></div></div></body></html>=
--Apple-Mail=_877DC8BA-BA64-4899-AC8F-C9588B685A8B--