All of lore.kernel.org
 help / color / mirror / Atom feed
* about attaching rbd volume from instance on KVM
@ 2012-02-04  4:48 Masuko Tomoya
  2012-02-06  8:06 ` Christian Brunner
  0 siblings, 1 reply; 7+ messages in thread
From: Masuko Tomoya @ 2012-02-04  4:48 UTC (permalink / raw)
  To: ceph-devel

Hi, all.

I'm trying to attach rbd volume from instance on KVM.
But I have problem.
Could you help me ?

---
I tried to attach rbd volume on ceph01 to instance on compute1 with
virsh command.

root@compute1:~# virsh attach-device test-ub16 /root/testvolume.xml
error: Failed to attach device from /root/testvolume.xml
error: cannot resolve symlink rbd/testvolume: No such file or directory

/var/log/messages
Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: error :
qemuMonitorTextAddDevice:2417 : operation failed: adding
virtio-blk-pci,bus=pci.0,addr=0x9,drive=drive-virtio-disk4,id=virtio-disk4
device failed: Device needs media, but drive is empty#015#012Device
'virtio-blk-pci' could not be initialized#015#012
Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: warning :
qemuDomainAttachPciDiskDevice:188 : qemuMonitorAddDevice failed on
file=rbd:rbd/testvolume,if=none,id=drive-virtio-disk4,format=raw
(virtio-blk-pci,bus=pci.0,addr=0x9,drive=drive-virtio-disk4,id=virtio-disk4)
Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: error :
virSecurityDACRestoreSecurityFileLabel:143 : cannot resolve symlink
rbd/testvolume: No such file or directory
Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: warning :
qemuDomainAttachPciDiskDevice:229 : Unable to restore security label
on rbd/testvolume

there is no log in /var/log/ceph/mon.0.log of host ceph01.
---


My environment is below.
*There are two servers. All server are ubuntu 10.10 x86_64.
*ceph01: single server configured ceph.(version: 0.41-1maverick)
*compute1: kvm hypervisor
 -librados2 and librbd1 packages are installed.
 (version: 0.41-1maverick)
 -qemu-kvm is 0.14.0-rc1. I built qemu with rbd enable.
 the output of run 'qemu-img' show 'rbd' at supported formats field.
 (I built qemu reffering this page.
 http://ceph.newdream.net/wiki/QEMU-RBD)
 -apparmor is disable.
 -libvirt is 0.8.8

====
 -there is ceph.conf on compute1.
root@compute1:~# ls -l /etc/ceph/
total 20
-rw-r--r-- 1 root root 508 2012-02-03 14:38 ceph.conf
-rw------- 1 root root  63 2012-02-03 17:04 keyring.admin
-rw------- 1 root root  63 2012-02-03 14:38 keyring.bin
-rw------- 1 root root  56 2012-02-03 14:38 keyring.mds.0
-rw------- 1 root root  56 2012-02-03 14:38 keyring.osd.0

=====
 -contents of ceph.conf is below.
root@compute1:~# cat /etc/ceph/ceph.conf
[global]
       auth supported = cephx
       keyring = /etc/ceph/keyring.bin
[mon]
       mon data = /data/data/mon$id
       debug ms = 1
[mon.0]
       host = ceph01
       mon addr = 10.68.119.191:6789
[mds]
       keyring = /etc/ceph/keyring.$name
[mds.0]
       host = ceph01
[osd]
       keyring = /etc/ceph/keyring.$name
       osd data = /data/osd$id
       osd journal = /data/osd$id/journal
       osd journal size = 512
       osd class tmp = /var/lib/ceph/tmp
       debug osd = 20
       debug ms = 1
       debug filestore = 20
[osd.0]
       host = ceph01
       btrfs devs = /dev/sdb1

===
*conten of keyring.admin is below
root@compute1:~# cat /etc/ceph/keyring.admin
[client.admin]
       key = AQDFeCxPyBlNIRAAxS1DcRHpMXRpcjY/GNMwYg==


===
*output of run 'ceph auth list'
root@ceph01:/etc/ceph# ceph auth list
2012-02-03 20:34:59.507451 mon <- [auth,list]
2012-02-03 20:34:59.508785 mon.0 -> 'installed auth entries:
mon.
       key: AQDFeCxPiK04IxAAslDBNkrOGKWxcbCh2iysqg==
mds.0
       key: AQDFeCxPsJ+LGhAAJ3/rmkAtGXSv/eHh0yXgww==
       caps: [mds] allow
       caps: [mon] allow rwx
       caps: [osd] allow *
osd.0
       key: AQDFeCxPoEK+ExAAecD7+tWgpIRoZx2AT7Jwbg==
       caps: [mon] allow rwx
       caps: [osd] allow *
client.admin
       key: AQDFeCxPyBlNIRAAxS1DcRHpMXRpcjY/GNMwYg==
       caps: [mds] allow
       caps: [mon] allow *
       caps: [osd] allow *
' (0)

====
*xml file is below.
root@compute1:~# cat /root/testvolume.xml
<disk type='network' device='disk'>
 <driver name='qemu' type='raw'/>
 <source protocol='rbd' name='rbd/testvolume'>
   <host name='10.68.119.191' port='6789'/>
 </source>
 <target dev='vde' bus='virtio'/>
</disk>

====
*testvolume is on rados pools.
root@compute1:~# qemu-img info rbd:rbd/testvolume
image: rbd:rbd/testvolume
file format: raw
virtual size: 1.0G (1073741824 bytes)
disk size: unavailable


Waiting for reply,

Tomoya.

^ permalink raw reply	[flat|nested] 7+ messages in thread

* Re: about attaching rbd volume from instance on KVM
  2012-02-04  4:48 about attaching rbd volume from instance on KVM Masuko Tomoya
@ 2012-02-06  8:06 ` Christian Brunner
  2012-02-08 23:42   ` Masuko Tomoya
  0 siblings, 1 reply; 7+ messages in thread
From: Christian Brunner @ 2012-02-06  8:06 UTC (permalink / raw)
  To: Masuko Tomoya; +Cc: ceph-devel

Libvirt is trying to set security lables even for network shares. This
will not work. I think this is fixed in newer libvirt versions. For
older versions you can try this patch:

http://www.redhat.com/archives/libvir-list/2011-May/msg01446.html

Regards,
Christian

2012/2/4 Masuko Tomoya <tomoya.masuko@gmail.com>:
> Hi, all.
>
> I'm trying to attach rbd volume from instance on KVM.
> But I have problem.
> Could you help me ?
>
> ---
> I tried to attach rbd volume on ceph01 to instance on compute1 with
> virsh command.
>
> root@compute1:~# virsh attach-device test-ub16 /root/testvolume.xml
> error: Failed to attach device from /root/testvolume.xml
> error: cannot resolve symlink rbd/testvolume: No such file or directory
>
> /var/log/messages
> Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: error :
> qemuMonitorTextAddDevice:2417 : operation failed: adding
> virtio-blk-pci,bus=pci.0,addr=0x9,drive=drive-virtio-disk4,id=virtio-disk4
> device failed: Device needs media, but drive is empty#015#012Device
> 'virtio-blk-pci' could not be initialized#015#012
> Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: warning :
> qemuDomainAttachPciDiskDevice:188 : qemuMonitorAddDevice failed on
> file=rbd:rbd/testvolume,if=none,id=drive-virtio-disk4,format=raw
> (virtio-blk-pci,bus=pci.0,addr=0x9,drive=drive-virtio-disk4,id=virtio-disk4)
> Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: error :
> virSecurityDACRestoreSecurityFileLabel:143 : cannot resolve symlink
> rbd/testvolume: No such file or directory
> Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: warning :
> qemuDomainAttachPciDiskDevice:229 : Unable to restore security label
> on rbd/testvolume
>
> there is no log in /var/log/ceph/mon.0.log of host ceph01.
> ---
>
>
> My environment is below.
> *There are two servers. All server are ubuntu 10.10 x86_64.
> *ceph01: single server configured ceph.(version: 0.41-1maverick)
> *compute1: kvm hypervisor
>  -librados2 and librbd1 packages are installed.
>  (version: 0.41-1maverick)
>  -qemu-kvm is 0.14.0-rc1. I built qemu with rbd enable.
>  the output of run 'qemu-img' show 'rbd' at supported formats field.
>  (I built qemu reffering this page.
>  http://ceph.newdream.net/wiki/QEMU-RBD)
>  -apparmor is disable.
>  -libvirt is 0.8.8
>
> ====
>  -there is ceph.conf on compute1.
> root@compute1:~# ls -l /etc/ceph/
> total 20
> -rw-r--r-- 1 root root 508 2012-02-03 14:38 ceph.conf
> -rw------- 1 root root  63 2012-02-03 17:04 keyring.admin
> -rw------- 1 root root  63 2012-02-03 14:38 keyring.bin
> -rw------- 1 root root  56 2012-02-03 14:38 keyring.mds.0
> -rw------- 1 root root  56 2012-02-03 14:38 keyring.osd.0
>
> =====
>  -contents of ceph.conf is below.
> root@compute1:~# cat /etc/ceph/ceph.conf
> [global]
>       auth supported = cephx
>       keyring = /etc/ceph/keyring.bin
> [mon]
>       mon data = /data/data/mon$id
>       debug ms = 1
> [mon.0]
>       host = ceph01
>       mon addr = 10.68.119.191:6789
> [mds]
>       keyring = /etc/ceph/keyring.$name
> [mds.0]
>       host = ceph01
> [osd]
>       keyring = /etc/ceph/keyring.$name
>       osd data = /data/osd$id
>       osd journal = /data/osd$id/journal
>       osd journal size = 512
>       osd class tmp = /var/lib/ceph/tmp
>       debug osd = 20
>       debug ms = 1
>       debug filestore = 20
> [osd.0]
>       host = ceph01
>       btrfs devs = /dev/sdb1
>
> ===
> *conten of keyring.admin is below
> root@compute1:~# cat /etc/ceph/keyring.admin
> [client.admin]
>       key = AQDFeCxPyBlNIRAAxS1DcRHpMXRpcjY/GNMwYg==
>
>
> ===
> *output of run 'ceph auth list'
> root@ceph01:/etc/ceph# ceph auth list
> 2012-02-03 20:34:59.507451 mon <- [auth,list]
> 2012-02-03 20:34:59.508785 mon.0 -> 'installed auth entries:
> mon.
>       key: AQDFeCxPiK04IxAAslDBNkrOGKWxcbCh2iysqg==
> mds.0
>       key: AQDFeCxPsJ+LGhAAJ3/rmkAtGXSv/eHh0yXgww==
>       caps: [mds] allow
>       caps: [mon] allow rwx
>       caps: [osd] allow *
> osd.0
>       key: AQDFeCxPoEK+ExAAecD7+tWgpIRoZx2AT7Jwbg==
>       caps: [mon] allow rwx
>       caps: [osd] allow *
> client.admin
>       key: AQDFeCxPyBlNIRAAxS1DcRHpMXRpcjY/GNMwYg==
>       caps: [mds] allow
>       caps: [mon] allow *
>       caps: [osd] allow *
> ' (0)
>
> ====
> *xml file is below.
> root@compute1:~# cat /root/testvolume.xml
> <disk type='network' device='disk'>
>  <driver name='qemu' type='raw'/>
>  <source protocol='rbd' name='rbd/testvolume'>
>   <host name='10.68.119.191' port='6789'/>
>  </source>
>  <target dev='vde' bus='virtio'/>
> </disk>
>
> ====
> *testvolume is on rados pools.
> root@compute1:~# qemu-img info rbd:rbd/testvolume
> image: rbd:rbd/testvolume
> file format: raw
> virtual size: 1.0G (1073741824 bytes)
> disk size: unavailable
>
>
> Waiting for reply,
>
> Tomoya.
> --
> To unsubscribe from this list: send the line "unsubscribe ceph-devel" in
> the body of a message to majordomo@vger.kernel.org
> More majordomo info at  http://vger.kernel.org/majordomo-info.html
--
To unsubscribe from this list: send the line "unsubscribe ceph-devel" in
the body of a message to majordomo@vger.kernel.org
More majordomo info at  http://vger.kernel.org/majordomo-info.html

^ permalink raw reply	[flat|nested] 7+ messages in thread

* Re: about attaching rbd volume from instance on KVM
  2012-02-06  8:06 ` Christian Brunner
@ 2012-02-08 23:42   ` Masuko Tomoya
  2012-02-09  2:10     ` Josh Durgin
  0 siblings, 1 reply; 7+ messages in thread
From: Masuko Tomoya @ 2012-02-08 23:42 UTC (permalink / raw)
  To: christian; +Cc: ceph-devel

Hi Christian,

Thank you for reply !

Could I ask a version of libvirt which is your patch applied ?

I'm trying it with libvirt0.9.8-2ubuntu9, but there is same error.
(I'm using blow package:
https://launchpad.net/ubuntu/precise/+source/libvirt/0.9.8-2ubuntu9)

root@compute04:~# dpkg -l | grep libvirt-bin
ii  libvirt-bin                            0.9.8-2ubuntu9
            programs for the libvirt library

/var/log/libvirtd/libvirt.log
2012-02-08 23:33:53.800+0000: 4663: error :
qemuMonitorTextAddDrive:2828 : operation failed: open disk image file
failed
2012-02-08 23:33:53.800+0000: 4663: error :
virSecurityDACRestoreSecurityFileLabel:143 : cannot resolve symlink
rbd/testvolume: No such file or directory
2012-02-08 23:33:54.212+0000: 4663: warning :
qemuDomainAttachPciDiskDevice:287 : Unable to restore security label
on rbd/testvolume

2012/2/6 Christian Brunner <christian@brunner-muc.de>:
> Libvirt is trying to set security lables even for network shares. This
> will not work. I think this is fixed in newer libvirt versions. For
> older versions you can try this patch:
>
> http://www.redhat.com/archives/libvir-list/2011-May/msg01446.html
>
> Regards,
> Christian
>
> 2012/2/4 Masuko Tomoya <tomoya.masuko@gmail.com>:
>> Hi, all.
>>
>> I'm trying to attach rbd volume from instance on KVM.
>> But I have problem.
>> Could you help me ?
>>
>> ---
>> I tried to attach rbd volume on ceph01 to instance on compute1 with
>> virsh command.
>>
>> root@compute1:~# virsh attach-device test-ub16 /root/testvolume.xml
>> error: Failed to attach device from /root/testvolume.xml
>> error: cannot resolve symlink rbd/testvolume: No such file or directory
>>
>> /var/log/messages
>> Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: error :
>> qemuMonitorTextAddDevice:2417 : operation failed: adding
>> virtio-blk-pci,bus=pci.0,addr=0x9,drive=drive-virtio-disk4,id=virtio-disk4
>> device failed: Device needs media, but drive is empty#015#012Device
>> 'virtio-blk-pci' could not be initialized#015#012
>> Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: warning :
>> qemuDomainAttachPciDiskDevice:188 : qemuMonitorAddDevice failed on
>> file=rbd:rbd/testvolume,if=none,id=drive-virtio-disk4,format=raw
>> (virtio-blk-pci,bus=pci.0,addr=0x9,drive=drive-virtio-disk4,id=virtio-disk4)
>> Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: error :
>> virSecurityDACRestoreSecurityFileLabel:143 : cannot resolve symlink
>> rbd/testvolume: No such file or directory
>> Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: warning :
>> qemuDomainAttachPciDiskDevice:229 : Unable to restore security label
>> on rbd/testvolume
>>
>> there is no log in /var/log/ceph/mon.0.log of host ceph01.
>> ---
>>
>>
>> My environment is below.
>> *There are two servers. All server are ubuntu 10.10 x86_64.
>> *ceph01: single server configured ceph.(version: 0.41-1maverick)
>> *compute1: kvm hypervisor
>>  -librados2 and librbd1 packages are installed.
>>  (version: 0.41-1maverick)
>>  -qemu-kvm is 0.14.0-rc1. I built qemu with rbd enable.
>>  the output of run 'qemu-img' show 'rbd' at supported formats field.
>>  (I built qemu reffering this page.
>>  http://ceph.newdream.net/wiki/QEMU-RBD)
>>  -apparmor is disable.
>>  -libvirt is 0.8.8
>>
>> ====
>>  -there is ceph.conf on compute1.
>> root@compute1:~# ls -l /etc/ceph/
>> total 20
>> -rw-r--r-- 1 root root 508 2012-02-03 14:38 ceph.conf
>> -rw------- 1 root root  63 2012-02-03 17:04 keyring.admin
>> -rw------- 1 root root  63 2012-02-03 14:38 keyring.bin
>> -rw------- 1 root root  56 2012-02-03 14:38 keyring.mds.0
>> -rw------- 1 root root  56 2012-02-03 14:38 keyring.osd.0
>>
>> =====
>>  -contents of ceph.conf is below.
>> root@compute1:~# cat /etc/ceph/ceph.conf
>> [global]
>>       auth supported = cephx
>>       keyring = /etc/ceph/keyring.bin
>> [mon]
>>       mon data = /data/data/mon$id
>>       debug ms = 1
>> [mon.0]
>>       host = ceph01
>>       mon addr = 10.68.119.191:6789
>> [mds]
>>       keyring = /etc/ceph/keyring.$name
>> [mds.0]
>>       host = ceph01
>> [osd]
>>       keyring = /etc/ceph/keyring.$name
>>       osd data = /data/osd$id
>>       osd journal = /data/osd$id/journal
>>       osd journal size = 512
>>       osd class tmp = /var/lib/ceph/tmp
>>       debug osd = 20
>>       debug ms = 1
>>       debug filestore = 20
>> [osd.0]
>>       host = ceph01
>>       btrfs devs = /dev/sdb1
>>
>> ===
>> *conten of keyring.admin is below
>> root@compute1:~# cat /etc/ceph/keyring.admin
>> [client.admin]
>>       key = AQDFeCxPyBlNIRAAxS1DcRHpMXRpcjY/GNMwYg==
>>
>>
>> ===
>> *output of run 'ceph auth list'
>> root@ceph01:/etc/ceph# ceph auth list
>> 2012-02-03 20:34:59.507451 mon <- [auth,list]
>> 2012-02-03 20:34:59.508785 mon.0 -> 'installed auth entries:
>> mon.
>>       key: AQDFeCxPiK04IxAAslDBNkrOGKWxcbCh2iysqg==
>> mds.0
>>       key: AQDFeCxPsJ+LGhAAJ3/rmkAtGXSv/eHh0yXgww==
>>       caps: [mds] allow
>>       caps: [mon] allow rwx
>>       caps: [osd] allow *
>> osd.0
>>       key: AQDFeCxPoEK+ExAAecD7+tWgpIRoZx2AT7Jwbg==
>>       caps: [mon] allow rwx
>>       caps: [osd] allow *
>> client.admin
>>       key: AQDFeCxPyBlNIRAAxS1DcRHpMXRpcjY/GNMwYg==
>>       caps: [mds] allow
>>       caps: [mon] allow *
>>       caps: [osd] allow *
>> ' (0)
>>
>> ====
>> *xml file is below.
>> root@compute1:~# cat /root/testvolume.xml
>> <disk type='network' device='disk'>
>>  <driver name='qemu' type='raw'/>
>>  <source protocol='rbd' name='rbd/testvolume'>
>>   <host name='10.68.119.191' port='6789'/>
>>  </source>
>>  <target dev='vde' bus='virtio'/>
>> </disk>
>>
>> ====
>> *testvolume is on rados pools.
>> root@compute1:~# qemu-img info rbd:rbd/testvolume
>> image: rbd:rbd/testvolume
>> file format: raw
>> virtual size: 1.0G (1073741824 bytes)
>> disk size: unavailable
>>
>>
>> Waiting for reply,
>>
>> Tomoya.
>> --
>> To unsubscribe from this list: send the line "unsubscribe ceph-devel" in
>> the body of a message to majordomo@vger.kernel.org
>> More majordomo info at  http://vger.kernel.org/majordomo-info.html
--
To unsubscribe from this list: send the line "unsubscribe ceph-devel" in
the body of a message to majordomo@vger.kernel.org
More majordomo info at  http://vger.kernel.org/majordomo-info.html

^ permalink raw reply	[flat|nested] 7+ messages in thread

* Re: about attaching rbd volume from instance on KVM
  2012-02-08 23:42   ` Masuko Tomoya
@ 2012-02-09  2:10     ` Josh Durgin
  2012-02-16  3:01       ` Masuko Tomoya
  0 siblings, 1 reply; 7+ messages in thread
From: Josh Durgin @ 2012-02-09  2:10 UTC (permalink / raw)
  To: Masuko Tomoya; +Cc: christian, ceph-devel

On 02/08/2012 03:42 PM, Masuko Tomoya wrote:
> Hi Christian,
>
> Thank you for reply !
>
> Could I ask a version of libvirt which is your patch applied ?
>
> I'm trying it with libvirt0.9.8-2ubuntu9, but there is same error.
> (I'm using blow package:
> https://launchpad.net/ubuntu/precise/+source/libvirt/0.9.8-2ubuntu9)
>
> root@compute04:~# dpkg -l | grep libvirt-bin
> ii  libvirt-bin                            0.9.8-2ubuntu9
>              programs for the libvirt library
>
> /var/log/libvirtd/libvirt.log
> 2012-02-08 23:33:53.800+0000: 4663: error :
> qemuMonitorTextAddDrive:2828 : operation failed: open disk image file
> failed
> 2012-02-08 23:33:53.800+0000: 4663: error :
> virSecurityDACRestoreSecurityFileLabel:143 : cannot resolve symlink
> rbd/testvolume: No such file or directory
> 2012-02-08 23:33:54.212+0000: 4663: warning :
> qemuDomainAttachPciDiskDevice:287 : Unable to restore security label
> on rbd/testvolume

libvirt 0.9.9 fixes this (the applied patch is 
http://www.redhat.com/archives/libvir-list/2011-December/msg00506.html 
if you're curious).

>
> 2012/2/6 Christian Brunner<christian@brunner-muc.de>:
>> Libvirt is trying to set security lables even for network shares. This
>> will not work. I think this is fixed in newer libvirt versions. For
>> older versions you can try this patch:
>>
>> http://www.redhat.com/archives/libvir-list/2011-May/msg01446.html
>>
>> Regards,
>> Christian
>>
>> 2012/2/4 Masuko Tomoya<tomoya.masuko@gmail.com>:
>>> Hi, all.
>>>
>>> I'm trying to attach rbd volume from instance on KVM.
>>> But I have problem.
>>> Could you help me ?
>>>
>>> ---
>>> I tried to attach rbd volume on ceph01 to instance on compute1 with
>>> virsh command.
>>>
>>> root@compute1:~# virsh attach-device test-ub16 /root/testvolume.xml
>>> error: Failed to attach device from /root/testvolume.xml
>>> error: cannot resolve symlink rbd/testvolume: No such file or directory
>>>
>>> /var/log/messages
>>> Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: error :
>>> qemuMonitorTextAddDevice:2417 : operation failed: adding
>>> virtio-blk-pci,bus=pci.0,addr=0x9,drive=drive-virtio-disk4,id=virtio-disk4
>>> device failed: Device needs media, but drive is empty#015#012Device
>>> 'virtio-blk-pci' could not be initialized#015#012
>>> Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: warning :
>>> qemuDomainAttachPciDiskDevice:188 : qemuMonitorAddDevice failed on
>>> file=rbd:rbd/testvolume,if=none,id=drive-virtio-disk4,format=raw
>>> (virtio-blk-pci,bus=pci.0,addr=0x9,drive=drive-virtio-disk4,id=virtio-disk4)
>>> Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: error :
>>> virSecurityDACRestoreSecurityFileLabel:143 : cannot resolve symlink
>>> rbd/testvolume: No such file or directory
>>> Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: warning :
>>> qemuDomainAttachPciDiskDevice:229 : Unable to restore security label
>>> on rbd/testvolume
>>>
>>> there is no log in /var/log/ceph/mon.0.log of host ceph01.
>>> ---
>>>
>>>
>>> My environment is below.
>>> *There are two servers. All server are ubuntu 10.10 x86_64.
>>> *ceph01: single server configured ceph.(version: 0.41-1maverick)
>>> *compute1: kvm hypervisor
>>>   -librados2 and librbd1 packages are installed.
>>>   (version: 0.41-1maverick)
>>>   -qemu-kvm is 0.14.0-rc1. I built qemu with rbd enable.
>>>   the output of run 'qemu-img' show 'rbd' at supported formats field.
>>>   (I built qemu reffering this page.
>>>   http://ceph.newdream.net/wiki/QEMU-RBD)
>>>   -apparmor is disable.
>>>   -libvirt is 0.8.8
>>>
>>> ====
>>>   -there is ceph.conf on compute1.
>>> root@compute1:~# ls -l /etc/ceph/
>>> total 20
>>> -rw-r--r-- 1 root root 508 2012-02-03 14:38 ceph.conf
>>> -rw------- 1 root root  63 2012-02-03 17:04 keyring.admin
>>> -rw------- 1 root root  63 2012-02-03 14:38 keyring.bin
>>> -rw------- 1 root root  56 2012-02-03 14:38 keyring.mds.0
>>> -rw------- 1 root root  56 2012-02-03 14:38 keyring.osd.0
>>>
>>> =====
>>>   -contents of ceph.conf is below.
>>> root@compute1:~# cat /etc/ceph/ceph.conf
>>> [global]
>>>        auth supported = cephx
>>>        keyring = /etc/ceph/keyring.bin
>>> [mon]
>>>        mon data = /data/data/mon$id
>>>        debug ms = 1
>>> [mon.0]
>>>        host = ceph01
>>>        mon addr = 10.68.119.191:6789
>>> [mds]
>>>        keyring = /etc/ceph/keyring.$name
>>> [mds.0]
>>>        host = ceph01
>>> [osd]
>>>        keyring = /etc/ceph/keyring.$name
>>>        osd data = /data/osd$id
>>>        osd journal = /data/osd$id/journal
>>>        osd journal size = 512
>>>        osd class tmp = /var/lib/ceph/tmp
>>>        debug osd = 20
>>>        debug ms = 1
>>>        debug filestore = 20
>>> [osd.0]
>>>        host = ceph01
>>>        btrfs devs = /dev/sdb1
>>>
>>> ===
>>> *conten of keyring.admin is below
>>> root@compute1:~# cat /etc/ceph/keyring.admin
>>> [client.admin]
>>>        key = AQDFeCxPyBlNIRAAxS1DcRHpMXRpcjY/GNMwYg==
>>>
>>>
>>> ===
>>> *output of run 'ceph auth list'
>>> root@ceph01:/etc/ceph# ceph auth list
>>> 2012-02-03 20:34:59.507451 mon<- [auth,list]
>>> 2012-02-03 20:34:59.508785 mon.0 ->  'installed auth entries:
>>> mon.
>>>        key: AQDFeCxPiK04IxAAslDBNkrOGKWxcbCh2iysqg==
>>> mds.0
>>>        key: AQDFeCxPsJ+LGhAAJ3/rmkAtGXSv/eHh0yXgww==
>>>        caps: [mds] allow
>>>        caps: [mon] allow rwx
>>>        caps: [osd] allow *
>>> osd.0
>>>        key: AQDFeCxPoEK+ExAAecD7+tWgpIRoZx2AT7Jwbg==
>>>        caps: [mon] allow rwx
>>>        caps: [osd] allow *
>>> client.admin
>>>        key: AQDFeCxPyBlNIRAAxS1DcRHpMXRpcjY/GNMwYg==
>>>        caps: [mds] allow
>>>        caps: [mon] allow *
>>>        caps: [osd] allow *
>>> ' (0)
>>>
>>> ====
>>> *xml file is below.
>>> root@compute1:~# cat /root/testvolume.xml
>>> <disk type='network' device='disk'>
>>>   <driver name='qemu' type='raw'/>
>>>   <source protocol='rbd' name='rbd/testvolume'>
>>>    <host name='10.68.119.191' port='6789'/>
>>>   </source>
>>>   <target dev='vde' bus='virtio'/>
>>> </disk>
>>>
>>> ====
>>> *testvolume is on rados pools.
>>> root@compute1:~# qemu-img info rbd:rbd/testvolume
>>> image: rbd:rbd/testvolume
>>> file format: raw
>>> virtual size: 1.0G (1073741824 bytes)
>>> disk size: unavailable
>>>
>>>
>>> Waiting for reply,
>>>
>>> Tomoya.
>>> --
>>> To unsubscribe from this list: send the line "unsubscribe ceph-devel" in
>>> the body of a message to majordomo@vger.kernel.org
>>> More majordomo info at  http://vger.kernel.org/majordomo-info.html

^ permalink raw reply	[flat|nested] 7+ messages in thread

* Re: about attaching rbd volume from instance on KVM
  2012-02-09  2:10     ` Josh Durgin
@ 2012-02-16  3:01       ` Masuko Tomoya
  2012-02-18  2:49         ` Josh Durgin
  0 siblings, 1 reply; 7+ messages in thread
From: Masuko Tomoya @ 2012-02-16  3:01 UTC (permalink / raw)
  To: Josh Durgin; +Cc: christian, ceph-devel

Hi Josh,

Thank you for your reply !

>libvirt 0.9.9 fixes this (the applied patch is http://www.redhat.com/archives/libvir-list/2011-December/msg00506.html if you're curious).
Finally I applied the patch to libvirt 0.9.8 and
I fixed it.

But I have another issue.
When I tried attach rbd volume to VM on KVM, error occured.

--
root@compute04:~# virsh attach-device instance-00000027 testvolume4.xml
error: Failed to attach device from testvolume4.xml
error: operation failed: open disk image file failed
--

Could you advice to me ?

*testvolume4.xml is below.
<disk type='network' device='disk'>
  <driver name='qemu' type='raw'/>
  <source protocol='rbd' name='rbd/testvolume3'>
    <host name='10.2.100.56' port='6789'/>
  </source>
  <target dev='vdm' bus='virtio'/>
</disk>

* I use this qemu-kvm with built rbd option on.
root@compute04:/usr/bin# /usr/bin/qemu-system-x86_64 -version
QEMU emulator version 1.0 (qemu-kvm-1.0), Copyright (c) 2003-2008
Fabrice Bellard

*libvirtd.log is below
2012-02-15 05:30:24.669+0000: 14034: debug :
remoteDispatchDomainAttachDeviceHelper:297 : server=0x1dad7f0
client=0x1e78fd0 msg=0x7fedd0003490 rerr=0x7feddeb1fc70 args=0x1e6f270
ret=0x1e6f210
2012-02-15 05:30:24.669+0000: 14032: debug : virEventPollRunOnce:619 :
EVENT_POLL_RUN: nhandles=9 imeout=5000
2012-02-15 05:30:24.669+0000: 14034: debug :
virDomainAttachDevice:8542 : dom=0x1e6f230, (VM:
name=instance-00000027, uuid=51846781-0d41-44a4-9da7-902c7c394f53),
xml=<disk type='network' device='disk'>
  <driver name='qemu' type='raw'/>
  <source protocol='rbd' name='rbd/testvolume3'>
    <host name='10.2.100.56' port='6789'/>
  </source>
  <target dev='vdm' bus='virtio'/>
</disk>

2012-02-15 05:30:24.669+0000: 14034: debug : virDomainObjRef:1508 :
obj=0x1dcd410 refs=3
2012-02-15 05:30:24.669+0000: 14034: debug :
qemuDomainObjBeginJobInternal:766 : Starting job: modify (async=none)
2012-02-15 05:30:24.669+0000: 14034: debug : virCgroupNew:602 : New
group /libvirt/qemu/instance-00000027
2012-02-15 05:30:24.670+0000: 14034: debug : virCgroupDetect:261 :
Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu in
2012-02-15 05:30:24.670+0000: 14034: debug : virCgroupDetect:261 :
Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpuacct in
2012-02-15 05:30:24.670+0000: 14034: debug : virCgroupDetect:261 :
Detected mount/mapping 2:cpuset at /sys/fs/cgroup/cpuset in
2012-02-15 05:30:24.670+0000: 14034: debug : virCgroupDetect:261 :
Detected mount/mapping 3:memory at /sys/fs/cgroup/memory in
2012-02-15 05:30:24.670+0000: 14034: debug : virCgroupDetect:261 :
Detected mount/mapping 4:devices at /sys/fs/cgroup/devices in
2012-02-15 05:30:24.670+0000: 14034: debug : virCgroupDetect:261 :
Detected mount/mapping 5:freezer at /sys/fs/cgroup/freezer in
2012-02-15 05:30:24.670+0000: 14034: debug : virCgroupDetect:261 :
Detected mount/mapping 6:blkio at /sys/fs/cgroup/blkio in
2012-02-15 05:30:24.670+0000: 14034: debug : virCgroupMakeGroup:523 :
Make group /libvirt/qemu/instance-00000027
2012-02-15 05:30:24.670+0000: 14034: debug : virCgroupMakeGroup:545 :
Make controller /sys/fs/cgroup/cpu/libvirt/qemu/instance-00000027/
2012-02-15 05:30:24.670+0000: 14034: debug : virCgroupMakeGroup:545 :
Make controller /sys/fs/cgroup/cpuacct/libvirt/qemu/instance-00000027/
2012-02-15 05:30:24.670+0000: 14034: debug : virCgroupMakeGroup:545 :
Make controller /sys/fs/cgroup/cpuset/libvirt/qemu/instance-00000027/
2012-02-15 05:30:24.670+0000: 14034: debug : virCgroupMakeGroup:545 :
Make controller /sys/fs/cgroup/memory/libvirt/qemu/instance-00000027/
2012-02-15 05:30:24.670+0000: 14034: debug : virCgroupMakeGroup:545 :
Make controller /sys/fs/cgroup/devices/libvirt/qemu/instance-00000027/
2012-02-15 05:30:24.670+0000: 14034: debug : virCgroupMakeGroup:545 :
Make controller /sys/fs/cgroup/freezer/libvirt/qemu/instance-00000027/
2012-02-15 05:30:24.670+0000: 14034: debug : virCgroupMakeGroup:545 :
Make controller /sys/fs/cgroup/blkio/libvirt/qemu/instance-00000027/
2012-02-15 05:30:24.670+0000: 14034: debug :
virDomainLockManagerNew:123 : plugin=0x1dbb650 dom=0x1dcd410
withResources=0
2012-02-15 05:30:24.670+0000: 14034: debug : virLockManagerNew:291 :
plugin=0x1dbb650 type=0 nparams=4 params=0x7feddeb1f8c0 flags=0
2012-02-15 05:30:24.670+0000: 14034: debug :
virLockManagerLogParams:98 :   key=uuid type=uuid
value=51846781-0d41-44a4-9da7-902c7c394f53
2012-02-15 05:30:24.670+0000: 14034: debug :
virLockManagerLogParams:94 :   key=name type=string
value=instance-00000027
2012-02-15 05:30:24.670+0000: 14034: debug :
virLockManagerLogParams:82 :   key=id type=uint value=6
2012-02-15 05:30:24.670+0000: 14034: debug :
virLockManagerLogParams:82 :   key=pid type=uint value=31613
2012-02-15 05:30:24.670+0000: 14034: debug : virLockManagerAcquire:337
: lock=0x1ebec30 state='(null)' flags=0 fd=(nil)
2012-02-15 05:30:24.670+0000: 14034: debug : virLockManagerFree:374 :
lock=0x1ebec30
2012-02-15 05:30:24.670+0000: 14034: debug :
qemuDomainPCIAddressSetNextAddr:1053 : PCI addr 0:0:2.0 already in use
2012-02-15 05:30:24.670+0000: 14034: debug :
qemuDomainPCIAddressSetNextAddr:1053 : PCI addr 0:0:3.0 already in use
2012-02-15 05:30:24.670+0000: 14034: debug :
qemuDomainPCIAddressSetNextAddr:1053 : PCI addr 0:0:4.0 already in use
2012-02-15 05:30:24.670+0000: 14034: debug :
qemuDomainPCIAddressSetNextAddr:1053 : PCI addr 0:0:5.0 already in use
2012-02-15 05:30:24.670+0000: 14034: debug :
qemuDomainPCIAddressSetNextAddr:1053 : PCI addr 0:0:6.0 already in use
2012-02-15 05:30:24.670+0000: 14034: debug :
qemuDomainPCIAddressSetNextAddr:1058 : Allocating PCI addr 0:0:7.0
2012-02-15 05:30:24.670+0000: 14034: debug :
qemuDomainPCIAddressReserveAddr:882 : Reserving PCI addr 0:0:7.0
2012-02-15 05:30:24.670+0000: 14034: debug :
qemuDomainPCIAddressReserveAddr:882 : Reserving PCI addr 0:0:7.1
2012-02-15 05:30:24.670+0000: 14034: debug :
qemuDomainPCIAddressReserveAddr:882 : Reserving PCI addr 0:0:7.2
2012-02-15 05:30:24.670+0000: 14034: debug :
qemuDomainPCIAddressReserveAddr:882 : Reserving PCI addr 0:0:7.3
2012-02-15 05:30:24.670+0000: 14034: debug :
qemuDomainPCIAddressReserveAddr:882 : Reserving PCI addr 0:0:7.4
2012-02-15 05:30:24.670+0000: 14034: debug :
qemuDomainPCIAddressReserveAddr:882 : Reserving PCI addr 0:0:7.5
2012-02-15 05:30:24.670+0000: 14034: debug :
qemuDomainPCIAddressReserveAddr:882 : Reserving PCI addr 0:0:7.6
2012-02-15 05:30:24.670+0000: 14034: debug :
qemuDomainPCIAddressReserveAddr:882 : Reserving PCI addr 0:0:7.7
2012-02-15 05:30:24.670+0000: 14034: debug : qemuMonitorRef:187 :
QEMU_MONITOR_REF: mon=0x1dcdf70 refs=3
2012-02-15 05:30:24.670+0000: 14034: debug : qemuMonitorAddDrive:2382
: mon=0x1dcdf70
drive=file=rbd:rbd/testvolume3:mon_host=10.2.100.56\:6789,if=none,id=drive-virtio-disk12,format=raw
2012-02-15 05:30:24.670+0000: 14034: debug : virJSONValueToString:1037
: object=0x1dd5fb0
2012-02-15 05:30:24.670+0000: 14034: debug :
virJSONValueToStringOne:969 : object=0x1dd5fb0 type=0 gen=0x1e6e9e0
2012-02-15 05:30:24.670+0000: 14034: debug :
virJSONValueToStringOne:969 : object=0x1dc9ed0 type=2 gen=0x1e6e9e0
2012-02-15 05:30:24.670+0000: 14034: debug :
virJSONValueToStringOne:969 : object=0x1e4f370 type=0 gen=0x1e6e9e0
2012-02-15 05:30:24.670+0000: 14034: debug :
virJSONValueToStringOne:969 : object=0x1ebf6e0 type=2 gen=0x1e6e9e0
2012-02-15 05:30:24.670+0000: 14034: debug :
virJSONValueToStringOne:969 : object=0x1e79960 type=2 gen=0x1e6e9e0
2012-02-15 05:30:24.670+0000: 14034: debug :
virJSONValueToStringOne:969 : object=0x1ebf4b0 type=2 gen=0x1e6e9e0
2012-02-15 05:30:24.670+0000: 14034: debug : virJSONValueToString:1071
: result={"execute":"drive_add","arguments":{"pci_addr":"dummy","opts":"file=rbd:rbd/testvolume3:mon_host=10.2.100.56\\:6789,if=none,id=drive-virtio-disk12,format=raw"},"id":"libvirt-7"}
2012-02-15 05:30:24.670+0000: 14034: debug :
qemuMonitorJSONCommandWithFd:228 : Send command
'{"execute":"drive_add","arguments":{"pci_addr":"dummy","opts":"file=rbd:rbd/testvolume3:mon_host=10.2.100.56\\:6789,if=none,id=drive-virtio-disk12,format=raw"},"id":"libvirt-7"}'
for write with FD -1
2012-02-15 05:30:24.670+0000: 14034: debug :
virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=23
events=15
2012-02-15 05:30:24.670+0000: 14034: debug :
virEventPollInterruptLocked:706 : Interrupting
2012-02-15 05:30:24.670+0000: 14034: debug : qemuMonitorSend:788 :
QEMU_MONITOR_SEND_MSG: mon=0x1dcdf70
msg={"execute":"drive_add","arguments":{"pci_addr":"dummy","opts":"file=rbd:rbd/testvolume3:mon_host=10.2.100.56\\:6789,if=none,id=drive-virtio-disk12,format=raw"},"id":"libvirt-7"}
 fd=-1
2012-02-15 05:30:24.670+0000: 14032: debug : virEventPollRunOnce:630 :
Poll got 1 event(s)
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchTimeouts:415 : Dispatch 7
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchHandles:460 : Dispatch 9
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=0 w=1
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=1
events=1
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=1 w=2
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=2 w=3
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=3 w=4
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=4 w=5
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=5 w=6
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=6 w=8
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=7 w=23
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=8 w=24
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventRunDefaultImpl:244 : running default event implementation
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=6 w=8, f=16 e=1 d=0
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=7 w=23, f=19 e=29 d=0
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=8 w=24, f=15 e=1 d=0
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollCalculateTimeout:325 : Calculate expiry of 7 timers
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollCalculateTimeout:331 : Got a timeout scheduled for
1329283829669
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollCalculateTimeout:351 : Timeout at 1329283829669 due in
4999 ms
2012-02-15 05:30:24.670+0000: 14032: debug : virEventPollRunOnce:619 :
EVENT_POLL_RUN: nhandles=9 imeout=4999
2012-02-15 05:30:24.670+0000: 14032: debug : virEventPollRunOnce:630 :
Poll got 1 event(s)
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchTimeouts:415 : Dispatch 7
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchHandles:460 : Dispatch 9
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=0 w=1
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=1 w=2
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=2 w=3
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=3 w=4
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=4 w=5
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=5 w=6
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=6 w=8
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=7 w=23
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=23
events=2
2012-02-15 05:30:24.670+0000: 14032: debug : qemuMonitorRef:187 :
QEMU_MONITOR_REF: mon=0x1dcdf70 refs=4
2012-02-15 05:30:24.670+0000: 14032: debug : qemuMonitorIOWrite:418 :
QEMU_MONITOR_IO_WRITE: mon=0x1dcdf70
buf={"execute":"drive_add","arguments":{"pci_addr":"dummy","opts":"file=rbd:rbd/testvolume3:mon_host=10.2.100.56\\:6789,if=none,id=drive-virtio-disk12,format=raw"},"id":"libvirt-7"}
 len=179 ret=179 errno=11
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=23
events=13
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollInterruptLocked:702 : Skip interrupt, 1 -472389568
2012-02-15 05:30:24.670+0000: 14032: debug : qemuMonitorUnref:196 :
QEMU_MONITOR_UNREF: mon=0x1dcdf70 refs=3
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=8 w=24
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventRunDefaultImpl:244 : running default event implementation
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=6 w=8, f=16 e=1 d=0
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=7 w=23, f=19 e=25 d=0
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=8 w=24, f=15 e=1 d=0
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollCalculateTimeout:325 : Calculate expiry of 7 timers
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollCalculateTimeout:331 : Got a timeout scheduled for
1329283829669
2012-02-15 05:30:24.670+0000: 14032: debug :
virEventPollCalculateTimeout:351 : Timeout at 1329283829669 due in
4999 ms
2012-02-15 05:30:24.670+0000: 14032: debug : virEventPollRunOnce:619 :
EVENT_POLL_RUN: nhandles=9 imeout=4999
2012-02-15 05:30:24.671+0000: 14032: debug : virEventPollRunOnce:630 :
Poll got 1 event(s)
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchTimeouts:415 : Dispatch 7
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchHandles:460 : Dispatch 9
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=0 w=1
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=1 w=2
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=2 w=3
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=3 w=4
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=4 w=5
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=5 w=6
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=6 w=8
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=7 w=23
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=23
events=1
2012-02-15 05:30:24.671+0000: 14032: debug : qemuMonitorRef:187 :
QEMU_MONITOR_REF: mon=0x1dcdf70 refs=4
2012-02-15 05:30:24.671+0000: 14032: debug : qemuMonitorIOProcess:313
: QEMU_MONITOR_IO_PROCESS: mon=0x1dcdf70 buf={"id": "libvirt-7",
"error": {"class": "CommandNotFound", "desc": "The command drive_add
has not been found", "data": {"name": "drive_add"}}}
 len=143
2012-02-15 05:30:24.671+0000: 14032: debug :
qemuMonitorJSONIOProcessLine:115 : Line [{"id": "libvirt-7", "error":
{"class": "CommandNotFound", "desc": "The command drive_add has not
been found", "data": {"name": "drive_add"}}}]
2012-02-15 05:30:24.671+0000: 14032: debug :
virJSONValueFromString:914 : string={"id": "libvirt-7", "error":
{"class": "CommandNotFound", "desc": "The command drive_add has not
been found", "data": {"name": "drive_add"}}}
2012-02-15 05:30:24.671+0000: 14032: debug :
virJSONParserHandleStartMap:791 : parser=0x7fffc5c35aa0
2012-02-15 05:30:24.671+0000: 14032: debug :
virJSONParserHandleMapKey:772 : parser=0x7fffc5c35aa0
key=0x7fedd0002252
2012-02-15 05:30:24.671+0000: 14032: debug :
virJSONParserHandleString:752 : parser=0x7fffc5c35aa0
str=0x7fedd0002258
2012-02-15 05:30:24.671+0000: 14032: debug :
virJSONParserHandleMapKey:772 : parser=0x7fffc5c35aa0
key=0x7fedd0002265
2012-02-15 05:30:24.671+0000: 14032: debug :
virJSONParserHandleStartMap:791 : parser=0x7fffc5c35aa0
2012-02-15 05:30:24.671+0000: 14032: debug :
virJSONParserHandleMapKey:772 : parser=0x7fffc5c35aa0
key=0x7fedd000226f
2012-02-15 05:30:24.671+0000: 14032: debug :
virJSONParserHandleString:752 : parser=0x7fffc5c35aa0
str=0x7fedd0002278
2012-02-15 05:30:24.671+0000: 14032: debug :
virJSONParserHandleMapKey:772 : parser=0x7fffc5c35aa0
key=0x7fedd000228b
2012-02-15 05:30:24.671+0000: 14032: debug :
virJSONParserHandleString:752 : parser=0x7fffc5c35aa0
str=0x7fedd0002293
2012-02-15 05:30:24.671+0000: 14032: debug :
virJSONParserHandleMapKey:772 : parser=0x7fffc5c35aa0
key=0x7fedd00022bf
2012-02-15 05:30:24.671+0000: 14032: debug :
virJSONParserHandleStartMap:791 : parser=0x7fffc5c35aa0
2012-02-15 05:30:24.671+0000: 14032: debug :
virJSONParserHandleMapKey:772 : parser=0x7fffc5c35aa0
key=0x7fedd00022c8
2012-02-15 05:30:24.671+0000: 14032: debug :
virJSONParserHandleString:752 : parser=0x7fffc5c35aa0
str=0x7fedd00022d0
2012-02-15 05:30:24.671+0000: 14032: debug :
virJSONParserHandleEndMap:820 : parser=0x7fffc5c35aa0
2012-02-15 05:30:24.671+0000: 14032: debug :
virJSONParserHandleEndMap:820 : parser=0x7fffc5c35aa0
2012-02-15 05:30:24.671+0000: 14032: debug :
virJSONParserHandleEndMap:820 : parser=0x7fffc5c35aa0
2012-02-15 05:30:24.671+0000: 14032: debug :
virJSONValueFromString:958 : result=0x7fedd0000bc0
2012-02-15 05:30:24.671+0000: 14032: debug :
qemuMonitorJSONIOProcessLine:135 : QEMU_MONITOR_RECV_REPLY:
mon=0x1dcdf70 reply={"id": "libvirt-7", "error": {"class":
"CommandNotFound", "desc": "The command drive_add has not been found",
"data": {"name": "drive_add"}}}
2012-02-15 05:30:24.671+0000: 14032: debug :
qemuMonitorJSONIOProcess:186 : Total used 143 bytes out of 143
available in buffer
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=23
events=13
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollInterruptLocked:702 : Skip interrupt, 1 -472389568
2012-02-15 05:30:24.671+0000: 14032: debug : qemuMonitorUnref:196 :
QEMU_MONITOR_UNREF: mon=0x1dcdf70 refs=3
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=8 w=24
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventRunDefaultImpl:244 : running default event implementation
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0
2012-02-15 05:30:24.671+0000: 14034: debug :
virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=23
events=13
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=6 w=8, f=16 e=1 d=0
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=7 w=23, f=19 e=25 d=0
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=8 w=24, f=15 e=1 d=0
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollCalculateTimeout:325 : Calculate expiry of 7 timers
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollCalculateTimeout:331 : Got a timeout scheduled for
1329283829669
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollCalculateTimeout:351 : Timeout at 1329283829669 due in
4998 ms
2012-02-15 05:30:24.671+0000: 14032: debug : virEventPollRunOnce:619 :
EVENT_POLL_RUN: nhandles=9 imeout=4998
2012-02-15 05:30:24.671+0000: 14034: debug :
virEventPollInterruptLocked:706 : Interrupting
2012-02-15 05:30:24.671+0000: 14034: debug :
qemuMonitorJSONCommandWithFd:233 : Receive command reply ret=0
rxObject=0x7fedd0000bc0
2012-02-15 05:30:24.671+0000: 14032: debug : virEventPollRunOnce:630 :
Poll got 1 event(s)
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchTimeouts:415 : Dispatch 7
2012-02-15 05:30:24.671+0000: 14034: debug :
qemuMonitorJSONAddDrive:2818 : drive_add command not found, trying HMP
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchHandles:460 : Dispatch 9
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=0 w=1
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=1
events=1
2012-02-15 05:30:24.671+0000: 14034: debug : virJSONValueToString:1037
: object=0x1ebd520
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=1 w=2
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=2 w=3
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=3 w=4
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=4 w=5
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=5 w=6
2012-02-15 05:30:24.671+0000: 14034: debug :
virJSONValueToStringOne:969 : object=0x1ebd520 type=0 gen=0x1ebd6f0
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=6 w=8
2012-02-15 05:30:24.671+0000: 14034: debug :
virJSONValueToStringOne:969 : object=0x1e4e7a0 type=2 gen=0x1ebd6f0
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=7 w=23
2012-02-15 05:30:24.671+0000: 14034: debug :
virJSONValueToStringOne:969 : object=0x1ebf600 type=0 gen=0x1ebd6f0
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=8 w=24
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.671+0000: 14034: debug :
virJSONValueToStringOne:969 : object=0x1dd5e50 type=2 gen=0x1ebd6f0
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.671+0000: 14034: debug :
virJSONValueToStringOne:969 : object=0x1e543f0 type=2 gen=0x1ebd6f0
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventRunDefaultImpl:244 : running default event implementation
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.671+0000: 14034: debug : virJSONValueToString:1071
: result={"execute":"human-monitor-command","arguments":{"command-line":"drive_add
dummy file=rbd:rbd/testvolume3:mon_host=10.2.100.56\\\\:6789,if=none,id=drive-virtio-disk12,format=raw"},"id":"libvirt-8"}
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.671+0000: 14034: debug :
qemuMonitorJSONCommandWithFd:228 : Send command
'{"execute":"human-monitor-command","arguments":{"command-line":"drive_add
dummy file=rbd:rbd/testvolume3:mon_host=10.2.100.56\\\\:6789,if=none,id=drive-virtio-disk12,format=raw"},"id":"libvirt-8"}'
for write with FD -1
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0
2012-02-15 05:30:24.671+0000: 14034: debug :
virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=23
events=15
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=6 w=8, f=16 e=1 d=0
2012-02-15 05:30:24.671+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=7 w=23, f=19 e=25 d=0
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=8 w=24, f=15 e=1 d=0
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollCalculateTimeout:325 : Calculate expiry of 7 timers
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollCalculateTimeout:331 : Got a timeout scheduled for
1329283829669
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollCalculateTimeout:351 : Timeout at 1329283829669 due in
4997 ms
2012-02-15 05:30:24.672+0000: 14032: debug : virEventPollRunOnce:619 :
EVENT_POLL_RUN: nhandles=9 imeout=4997
2012-02-15 05:30:24.672+0000: 14034: debug :
virEventPollInterruptLocked:706 : Interrupting
2012-02-15 05:30:24.672+0000: 14034: debug : qemuMonitorSend:788 :
QEMU_MONITOR_SEND_MSG: mon=0x1dcdf70
msg={"execute":"human-monitor-command","arguments":{"command-line":"drive_add
dummy file=rbd:rbd/testvolume3:mon_host=10.2.100.56\\\\:6789,if=none,id=drive-virtio-disk12,format=raw"},"id":"libvirt-8"}
 fd=-1
2012-02-15 05:30:24.672+0000: 14032: debug : virEventPollRunOnce:630 :
Poll got 1 event(s)
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchTimeouts:415 : Dispatch 7
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchHandles:460 : Dispatch 9
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=0 w=1
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=1
events=1
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=1 w=2
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=2 w=3
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=3 w=4
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=4 w=5
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=5 w=6
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=6 w=8
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=7 w=23
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=8 w=24
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventRunDefaultImpl:244 : running default event implementation
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=6 w=8, f=16 e=1 d=0
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=7 w=23, f=19 e=29 d=0
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=8 w=24, f=15 e=1 d=0
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollCalculateTimeout:325 : Calculate expiry of 7 timers
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollCalculateTimeout:331 : Got a timeout scheduled for
1329283829669
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollCalculateTimeout:351 : Timeout at 1329283829669 due in
4997 ms
2012-02-15 05:30:24.672+0000: 14032: debug : virEventPollRunOnce:619 :
EVENT_POLL_RUN: nhandles=9 imeout=4997
2012-02-15 05:30:24.672+0000: 14032: debug : virEventPollRunOnce:630 :
Poll got 1 event(s)
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchTimeouts:415 : Dispatch 7
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchHandles:460 : Dispatch 9
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=0 w=1
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=1 w=2
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=2 w=3
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=3 w=4
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=4 w=5
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=5 w=6
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=6 w=8
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=7 w=23
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=23
events=2
2012-02-15 05:30:24.672+0000: 14032: debug : qemuMonitorRef:187 :
QEMU_MONITOR_REF: mon=0x1dcdf70 refs=4
2012-02-15 05:30:24.672+0000: 14032: debug : qemuMonitorIOWrite:418 :
QEMU_MONITOR_IO_WRITE: mon=0x1dcdf70
buf={"execute":"human-monitor-command","arguments":{"command-line":"drive_add
dummy file=rbd:rbd/testvolume3:mon_host=10.2.100.56\\\\:6789,if=none,id=drive-virtio-disk12,format=raw"},"id":"libvirt-8"}
 len=198 ret=198 errno=11
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=23
events=13
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollInterruptLocked:702 : Skip interrupt, 1 -472389568
2012-02-15 05:30:24.672+0000: 14032: debug : qemuMonitorUnref:196 :
QEMU_MONITOR_UNREF: mon=0x1dcdf70 refs=3
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=8 w=24
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventRunDefaultImpl:244 : running default event implementation
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=6 w=8, f=16 e=1 d=0
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=7 w=23, f=19 e=25 d=0
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=8 w=24, f=15 e=1 d=0
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollCalculateTimeout:325 : Calculate expiry of 7 timers
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollCalculateTimeout:331 : Got a timeout scheduled for
1329283829669
2012-02-15 05:30:24.672+0000: 14032: debug :
virEventPollCalculateTimeout:351 : Timeout at 1329283829669 due in
4997 ms
2012-02-15 05:30:24.672+0000: 14032: debug : virEventPollRunOnce:619 :
EVENT_POLL_RUN: nhandles=9 imeout=4997
2012-02-15 05:30:24.673+0000: 14032: debug : virEventPollRunOnce:630 :
Poll got 1 event(s)
2012-02-15 05:30:24.673+0000: 14032: debug :
virEventPollDispatchTimeouts:415 : Dispatch 7
2012-02-15 05:30:24.673+0000: 14032: debug :
virEventPollDispatchHandles:460 : Dispatch 9
2012-02-15 05:30:24.673+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=0 w=1
2012-02-15 05:30:24.673+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=1 w=2
2012-02-15 05:30:24.673+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=2 w=3
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=3 w=4
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=4 w=5
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=5 w=6
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=6 w=8
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=7 w=23
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=23
events=1
2012-02-15 05:30:24.674+0000: 14032: debug : qemuMonitorRef:187 :
QEMU_MONITOR_REF: mon=0x1dcdf70 refs=4
2012-02-15 05:30:24.674+0000: 14032: debug : qemuMonitorIOProcess:313
: QEMU_MONITOR_IO_PROCESS: mon=0x1dcdf70 buf={"return": "conf option
6789 has no value\r\nerror setting config options\r\ncould not open
disk image rbd:rbd/testvolume3:mon_host=10.2.100.56\\\\:6789: Invalid
argument\r\n", "id": "libvirt-8"}
 len=197
2012-02-15 05:30:24.674+0000: 14032: debug :
qemuMonitorJSONIOProcessLine:115 : Line [{"return": "conf option 6789
has no value\r\nerror setting config options\r\ncould not open disk
image rbd:rbd/testvolume3:mon_host=10.2.100.56\\\\:6789: Invalid
argument\r\n", "id": "libvirt-8"}]
2012-02-15 05:30:24.674+0000: 14032: debug :
virJSONValueFromString:914 : string={"return": "conf option 6789 has
no value\r\nerror setting config options\r\ncould not open disk image
rbd:rbd/testvolume3:mon_host=10.2.100.56\\\\:6789: Invalid
argument\r\n", "id": "libvirt-8"}
2012-02-15 05:30:24.674+0000: 14032: debug :
virJSONParserHandleStartMap:791 : parser=0x7fffc5c35aa0
2012-02-15 05:30:24.674+0000: 14032: debug :
virJSONParserHandleMapKey:772 : parser=0x7fffc5c35aa0
key=0x7fedd00026f2
2012-02-15 05:30:24.674+0000: 14032: debug :
virJSONParserHandleString:752 : parser=0x7fffc5c35aa0
str=0x7fedd0083e80
2012-02-15 05:30:24.674+0000: 14032: debug :
virJSONParserHandleMapKey:772 : parser=0x7fffc5c35aa0
key=0x7fedd00027a2
2012-02-15 05:30:24.674+0000: 14032: debug :
virJSONParserHandleString:752 : parser=0x7fffc5c35aa0
str=0x7fedd00027a8
2012-02-15 05:30:24.674+0000: 14032: debug :
virJSONParserHandleEndMap:820 : parser=0x7fffc5c35aa0
2012-02-15 05:30:24.674+0000: 14032: debug :
virJSONValueFromString:958 : result=0x7fedd0000b10
2012-02-15 05:30:24.674+0000: 14032: debug :
qemuMonitorJSONIOProcessLine:135 : QEMU_MONITOR_RECV_REPLY:
mon=0x1dcdf70 reply={"return": "conf option 6789 has no value\r\nerror
setting config options\r\ncould not open disk image
rbd:rbd/testvolume3:mon_host=10.2.100.56\\\\:6789: Invalid
argument\r\n", "id": "libvirt-8"}
2012-02-15 05:30:24.674+0000: 14032: debug :
qemuMonitorJSONIOProcess:186 : Total used 197 bytes out of 197
available in buffer
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=23
events=13
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollInterruptLocked:702 : Skip interrupt, 1 -472389568
2012-02-15 05:30:24.674+0000: 14032: debug : qemuMonitorUnref:196 :
QEMU_MONITOR_UNREF: mon=0x1dcdf70 refs=3
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=8 w=24
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventRunDefaultImpl:244 : running default event implementation
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
2012-02-15 05:30:24.674+0000: 14034: debug :
virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=23
events=13
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=6 w=8, f=16 e=1 d=0
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=7 w=23, f=19 e=25 d=0
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=8 w=24, f=15 e=1 d=0
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollCalculateTimeout:325 : Calculate expiry of 7 timers
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollCalculateTimeout:331 : Got a timeout scheduled for
1329283829669
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollCalculateTimeout:351 : Timeout at 1329283829669 due in
4995 ms
2012-02-15 05:30:24.674+0000: 14032: debug : virEventPollRunOnce:619 :
EVENT_POLL_RUN: nhandles=9 imeout=4995
2012-02-15 05:30:24.674+0000: 14034: debug :
virEventPollInterruptLocked:706 : Interrupting
2012-02-15 05:30:24.674+0000: 14034: debug :
qemuMonitorJSONCommandWithFd:233 : Receive command reply ret=0
rxObject=0x7fedd0000b10
2012-02-15 05:30:24.674+0000: 14032: debug : virEventPollRunOnce:630 :
Poll got 1 event(s)
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollDispatchTimeouts:415 : Dispatch 7
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollDispatchHandles:460 : Dispatch 9
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=0 w=1
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=1
events=1
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=1 w=2
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=2 w=3
2012-02-15 05:30:24.674+0000: 14034: error :
qemuMonitorTextAddDrive:2828 : operation failed: open disk image file
failed
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=3 w=4
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=4 w=5
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=5 w=6
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=6 w=8
2012-02-15 05:30:24.674+0000: 14034: debug : qemuMonitorUnref:196 :
QEMU_MONITOR_UNREF: mon=0x1dcdf70 refs=2
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=7 w=23
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=8 w=24
2012-02-15 05:30:24.674+0000: 14034: debug :
virSecurityDACRestoreSecurityImageLabelInt:219 : Skipping image label
restore on rbd/testvolume3 because FS is of type network
2012-02-15 05:30:24.674+0000: 14034: debug :
virDomainLockManagerNew:123 : plugin=0x1dbb650 dom=0x1dcd410
withResources=0
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.674+0000: 14034: debug : virLockManagerNew:291 :
plugin=0x1dbb650 type=0 nparams=4 params=0x7feddeb1f8c0 flags=0
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventRunDefaultImpl:244 : running default event implementation
2012-02-15 05:30:24.674+0000: 14034: debug :
virLockManagerLogParams:98 :   key=uuid type=uuid
value=51846781-0d41-44a4-9da7-902c7c394f53
2012-02-15 05:30:24.674+0000: 14034: debug :
virLockManagerLogParams:94 :   key=name type=string
value=instance-00000027
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.674+0000: 14034: debug :
virLockManagerLogParams:82 :   key=id type=uint value=6
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.674+0000: 14034: debug :
virLockManagerLogParams:82 :   key=pid type=uint value=31613
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
2012-02-15 05:30:24.674+0000: 14034: debug : virLockManagerRelease:352
: lock=0x1ebf210 state=(nil) flags=0
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
2012-02-15 05:30:24.674+0000: 14034: debug : virLockManagerFree:374 :
lock=0x1ebf210
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0
2012-02-15 05:30:24.674+0000: 14034: debug : qemuDomainObjEndJob:883 :
Stopping job: modify (async=none)
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=6 w=8, f=16 e=1 d=0
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=7 w=23, f=19 e=25 d=0
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=8 w=24, f=15 e=1 d=0
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollCalculateTimeout:325 : Calculate expiry of 7 timers
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollCalculateTimeout:331 : Got a timeout scheduled for
1329283829669
2012-02-15 05:30:24.674+0000: 14032: debug :
virEventPollCalculateTimeout:351 : Timeout at 1329283829669 due in
4995 ms
2012-02-15 05:30:24.674+0000: 14032: debug : virEventPollRunOnce:619 :
EVENT_POLL_RUN: nhandles=9 imeout=4995
2012-02-15 05:30:24.674+0000: 14034: debug : virDomainObjUnref:1515 :
obj=0x1dcd410 refs=2
2012-02-15 05:30:24.674+0000: 14034: debug : virDomainFree:2292 :
dom=0x1e6f230, (VM: name=instance-00000027,
uuid=51846781-0d41-44a4-9da7-902c7c394f53)
2012-02-15 05:30:24.674+0000: 14034: debug : virUnrefDomain:276 :
unref domain 0x1e6f230 instance-00000027 1
2012-02-15 05:30:24.674+0000: 14034: debug : virReleaseDomain:238 :
release domain 0x1e6f230 instance-00000027
51846781-0d41-44a4-9da7-902c7c394f53
2012-02-15 05:30:24.674+0000: 14034: debug : virReleaseDomain:246 :
unref connection 0x7fedd0043570 2
2012-02-15 05:30:24.674+0000: 14034: debug :
virNetServerProgramSendError:135 : prog=536903814 ver=1 proc=8 type=1
serial=4 msg=0x7fedd0003490 rerr=0x7feddeb1fc70
2012-02-15 05:30:24.674+0000: 14034: debug :
virNetMessageEncodePayload:351 : Encode length as 180
2012-02-15 05:30:24.674+0000: 14034: debug :
virNetServerClientSendMessage:1106 : msg=0x7fedd0003490 proc=8 len=180
offset=0
2012-02-15 05:30:24.674+0000: 14034: debug :
virNetServerClientSendMessage:1116 : RPC_SERVER_CLIENT_MSG_TX_QUEUE:
client=0x1e78fd0 len=180 prog=536903814 vers=1 proc=8 type=1 status=1
serial=4
2012-02-15 05:30:24.674+0000: 14034: debug :
virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1,
rx=0x7fedd0043e10 tx=0x7fedd0003490
2012-02-15 05:30:24.675+0000: 14034: debug :
virNetServerClientCalculateHandleMode:167 : mode=3
2012-02-15 05:30:24.675+0000: 14034: debug :
virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=24
events=3
2012-02-15 05:30:24.675+0000: 14034: debug :
virEventPollInterruptLocked:706 : Interrupting
2012-02-15 05:30:24.675+0000: 14034: debug :
virNetServerProgramFree:527 : prog=0x1db8300 refs=3
2012-02-15 05:30:24.675+0000: 14034: debug :
virNetServerClientFree:591 : RPC_SERVER_CLIENT_FREE: client=0x1e78fd0
refs=4
2012-02-15 05:30:24.675+0000: 14032: debug : virEventPollRunOnce:630 :
Poll got 1 event(s)
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchTimeouts:415 : Dispatch 7
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:460 : Dispatch 9
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=0 w=1
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=1
events=1
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=1 w=2
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=2 w=3
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=3 w=4
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=4 w=5
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=5 w=6
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=6 w=8
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=7 w=23
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=8 w=24
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventRunDefaultImpl:244 : running default event implementation
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=6 w=8, f=16 e=1 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=7 w=23, f=19 e=25 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=8 w=24, f=15 e=5 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollCalculateTimeout:325 : Calculate expiry of 7 timers
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollCalculateTimeout:331 : Got a timeout scheduled for
1329283829669
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollCalculateTimeout:351 : Timeout at 1329283829669 due in
4994 ms
2012-02-15 05:30:24.675+0000: 14032: debug : virEventPollRunOnce:619 :
EVENT_POLL_RUN: nhandles=9 imeout=4994
2012-02-15 05:30:24.675+0000: 14032: debug : virEventPollRunOnce:630 :
Poll got 1 event(s)
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchTimeouts:415 : Dispatch 7
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:460 : Dispatch 9
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=0 w=1
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=1 w=2
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=2 w=3
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=3 w=4
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=4 w=5
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=5 w=6
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=6 w=8
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=7 w=23
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=8 w=24
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=24
events=2
2012-02-15 05:30:24.675+0000: 14032: debug : virNetMessageFree:75 :
msg=0x7fedd0003490 nfds=0 cb=(nil)
2012-02-15 05:30:24.675+0000: 14032: debug :
virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1,
rx=0x7fedd0043e10 tx=(nil)
2012-02-15 05:30:24.675+0000: 14032: debug :
virNetServerClientCalculateHandleMode:167 : mode=1
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=24
events=1
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollInterruptLocked:702 : Skip interrupt, 1 -472389568
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventRunDefaultImpl:244 : running default event implementation
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=6 w=8, f=16 e=1 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=7 w=23, f=19 e=25 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=8 w=24, f=15 e=1 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollCalculateTimeout:325 : Calculate expiry of 7 timers
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollCalculateTimeout:331 : Got a timeout scheduled for
1329283829669
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollCalculateTimeout:351 : Timeout at 1329283829669 due in
4994 ms
2012-02-15 05:30:24.675+0000: 14032: debug : virEventPollRunOnce:619 :
EVENT_POLL_RUN: nhandles=9 imeout=4994
2012-02-15 05:30:24.675+0000: 14032: debug : virEventPollRunOnce:630 :
Poll got 1 event(s)
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchTimeouts:415 : Dispatch 7
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:460 : Dispatch 9
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=0 w=1
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=1 w=2
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=2 w=3
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=3 w=4
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=4 w=5
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=5 w=6
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=6 w=8
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=7 w=23
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=8 w=24
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=24
events=1
2012-02-15 05:30:24.675+0000: 14032: debug :
virNetMessageDecodeLength:149 : Got length, now need 28 total (24
more)
2012-02-15 05:30:24.675+0000: 14032: debug :
virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1,
rx=0x7fedd0043e10 tx=(nil)
2012-02-15 05:30:24.675+0000: 14032: debug :
virNetServerClientCalculateHandleMode:167 : mode=1
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=24
events=1
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollInterruptLocked:702 : Skip interrupt, 1 -472389568
2012-02-15 05:30:24.675+0000: 14032: debug :
virNetServerClientDispatchRead:886 : RPC_SERVER_CLIENT_MSG_RX:
client=0x1e78fd0 len=28 prog=536903814 vers=1 proc=2 type=0 status=0
serial=5
2012-02-15 05:30:24.675+0000: 14032: debug :
virKeepAliveCheckMessage:408 : ka=0x1ebf3e0, client=0x1e78fd0,
msg=0x7fedd0043e10
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollUpdateTimeout:259 : EVENT_POLL_UPDATE_TIMEOUT: timer=59
frequency=5000
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollInterruptLocked:702 : Skip interrupt, 1 -472389568
2012-02-15 05:30:24.675+0000: 14032: debug :
virNetServerDispatchNewMessage:199 : server=0x1dad7f0 client=0x1e78fd0
message=0x7fedd0043e10
2012-02-15 05:30:24.675+0000: 14032: debug : virNetServerProgramRef:87
: prog=0x1db8300 refs=3
2012-02-15 05:30:24.675+0000: 14036: debug : virNetServerHandleJob:138
: server=0x1dad7f0 client=0x1e78fd0 message=0x7fedd0043e10
prog=0x1db8300
2012-02-15 05:30:24.675+0000: 14036: debug :
virNetServerProgramDispatch:269 : prog=536903814 ver=1 type=0 status=0
serial=5 proc=2
2012-02-15 05:30:24.675+0000: 14032: debug : virNetMessageNew:48 :
msg=0x7fedd0003490 tracked=1
2012-02-15 05:30:24.675+0000: 14032: debug :
virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1,
rx=0x7fedd0003490 tx=(nil)
2012-02-15 05:30:24.675+0000: 14032: debug :
virNetServerClientCalculateHandleMode:167 : mode=1
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=24
events=1
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollInterruptLocked:702 : Skip interrupt, 1 -472389568
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventRunDefaultImpl:244 : running default event implementation
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=6 w=8, f=16 e=1 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=7 w=23, f=19 e=25 d=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=8 w=24, f=15 e=1 d=0
2012-02-15 05:30:24.675+0000: 14036: debug :
remoteDispatchCloseHelper:124 : server=0x1dad7f0 client=0x1e78fd0
msg=0x7fedd0043e10 rerr=0x7fedddb1dc70 args=0x1ebe3c0 ret=0x1e6f210
2012-02-15 05:30:24.675+0000: 14036: debug :
virNetMessageEncodePayload:351 : Encode length as 28
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollCalculateTimeout:325 : Calculate expiry of 7 timers
2012-02-15 05:30:24.675+0000: 14036: debug :
virNetServerClientSendMessage:1106 : msg=0x7fedd0043e10 proc=2 len=28
offset=0
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollCalculateTimeout:331 : Got a timeout scheduled for
1329283829675
2012-02-15 05:30:24.675+0000: 14036: debug :
virNetServerClientSendMessage:1116 : RPC_SERVER_CLIENT_MSG_TX_QUEUE:
client=0x1e78fd0 len=28 prog=536903814 vers=1 proc=2 type=1 status=0
serial=5
2012-02-15 05:30:24.675+0000: 14036: debug :
virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1,
rx=0x7fedd0003490 tx=0x7fedd0043e10
2012-02-15 05:30:24.675+0000: 14036: debug :
virNetServerClientCalculateHandleMode:167 : mode=3
2012-02-15 05:30:24.675+0000: 14036: debug :
virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=24
events=3
2012-02-15 05:30:24.675+0000: 14032: debug :
virEventPollCalculateTimeout:351 : Timeout at 1329283829675 due in
5000 ms
2012-02-15 05:30:24.676+0000: 14032: debug : virEventPollRunOnce:619 :
EVENT_POLL_RUN: nhandles=9 imeout=5000
2012-02-15 05:30:24.676+0000: 14036: debug :
virEventPollInterruptLocked:706 : Interrupting
2012-02-15 05:30:24.676+0000: 14036: debug :
virNetServerProgramFree:527 : prog=0x1db8300 refs=3
2012-02-15 05:30:24.676+0000: 14036: debug :
virNetServerClientFree:591 : RPC_SERVER_CLIENT_FREE: client=0x1e78fd0
refs=4
2012-02-15 05:30:24.676+0000: 14032: debug : virEventPollRunOnce:630 :
Poll got 1 event(s)
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchTimeouts:415 : Dispatch 7
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchHandles:460 : Dispatch 9
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=0 w=1
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=1
events=1
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=1 w=2
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=2 w=3
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=3 w=4
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=4 w=5
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=5 w=6
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=6 w=8
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=7 w=23
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=8 w=24
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventRunDefaultImpl:244 : running default event implementation
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=6 w=8, f=16 e=1 d=0
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=7 w=23, f=19 e=25 d=0
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=8 w=24, f=15 e=5 d=0
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollCalculateTimeout:325 : Calculate expiry of 7 timers
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollCalculateTimeout:331 : Got a timeout scheduled for
1329283829675
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollCalculateTimeout:351 : Timeout at 1329283829675 due in
4999 ms
2012-02-15 05:30:24.676+0000: 14032: debug : virEventPollRunOnce:619 :
EVENT_POLL_RUN: nhandles=9 imeout=4999
2012-02-15 05:30:24.676+0000: 14032: debug : virEventPollRunOnce:630 :
Poll got 1 event(s)
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchTimeouts:415 : Dispatch 7
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchHandles:460 : Dispatch 9
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=0 w=1
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=1 w=2
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=2 w=3
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=3 w=4
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=4 w=5
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=5 w=6
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=6 w=8
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=7 w=23
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchHandles:474 : i=8 w=24
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=24
events=2
2012-02-15 05:30:24.676+0000: 14032: debug : virNetMessageFree:75 :
msg=0x7fedd0043e10 nfds=0 cb=(nil)
2012-02-15 05:30:24.676+0000: 14032: debug :
virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1,
rx=0x7fedd0003490 tx=(nil)
2012-02-15 05:30:24.676+0000: 14032: debug :
virNetServerClientCalculateHandleMode:167 : mode=1
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=24
events=1
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollInterruptLocked:702 : Skip interrupt, 1 -472389568
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.676+0000: 14032: debug :
virNetServerClientClose:632 : client=0x1e78fd0 refs=3
2012-02-15 05:30:24.676+0000: 14032: debug : virKeepAliveStop:382 :
RPC_KEEPALIVE_STOP: ka=0x1ebf3e0 client=0x1e78fd0
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollRemoveTimeout:293 : EVENT_POLL_REMOVE_TIMEOUT: timer=59
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollInterruptLocked:702 : Skip interrupt, 0 -472389568
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollRemoveTimeout:293 : EVENT_POLL_REMOVE_TIMEOUT: timer=58
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollInterruptLocked:702 : Skip interrupt, 0 -472389568
2012-02-15 05:30:24.676+0000: 14032: debug : virKeepAliveFree:304 :
RPC_KEEPALIVE_FREE: ka=0x1ebf3e0 client=0x1e78fd0 refs=3
2012-02-15 05:30:24.676+0000: 14032: debug :
daemonRemoveAllClientStreams:493 : stream=(nil)
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollRemoveHandle:180 : EVENT_POLL_REMOVE_HANDLE: watch=24
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollRemoveHandle:193 : mark delete 8 15
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollInterruptLocked:702 : Skip interrupt, 0 -472389568
2012-02-15 05:30:24.676+0000: 14032: debug : virNetMessageFree:75 :
msg=0x7fedd0003490 nfds=0 cb=(nil)
2012-02-15 05:30:24.676+0000: 14032: debug : virNetSocketFree:722 :
RPC_SOCKET_FREE: sock=0x1e6e3f0 refs=2
2012-02-15 05:30:24.676+0000: 14032: debug :
virNetServerClientFree:591 : RPC_SERVER_CLIENT_FREE: client=0x1e78fd0
refs=3
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventRunDefaultImpl:244 : running default event implementation
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollCleanupTimeouts:506 : Cleanup 7
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollCleanupTimeouts:519 : EVENT_POLL_PURGE_TIMEOUT: timer=58
2012-02-15 05:30:24.676+0000: 14032: debug : virKeepAliveFree:304 :
RPC_KEEPALIVE_FREE: ka=0x1ebf3e0 client=0x1e78fd0 refs=2
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollCleanupTimeouts:519 : EVENT_POLL_PURGE_TIMEOUT: timer=59
2012-02-15 05:30:24.676+0000: 14032: debug : virKeepAliveFree:304 :
RPC_KEEPALIVE_FREE: ka=0x1ebf3e0 client=0x1e78fd0 refs=1
2012-02-15 05:30:24.676+0000: 14032: debug :
virNetServerClientFree:591 : RPC_SERVER_CLIENT_FREE: client=0x1e78fd0
refs=2
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollCleanupHandles:554 : Cleanup 9
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollCleanupHandles:567 : EVENT_POLL_PURGE_HANDLE: watch=24
2012-02-15 05:30:24.676+0000: 14032: debug :
virNetServerClientFree:591 : RPC_SERVER_CLIENT_FREE: client=0x1e78fd0
refs=1
2012-02-15 05:30:24.676+0000: 14032: debug : virConnectClose:1462 :
conn=0x7fedd0043570
2012-02-15 05:30:24.676+0000: 14032: debug : virUnrefConnect:145 :
unref connection 0x7fedd0043570 1
2012-02-15 05:30:24.676+0000: 14032: debug : virReleaseConnect:94 :
release connection 0x7fedd0043570
2012-02-15 05:30:24.676+0000: 14032: debug :
qemuProcessAutoDestroyRun:3823 : conn=0x7fedd0043570
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollRemoveTimeout:293 : EVENT_POLL_REMOVE_TIMEOUT: timer=57
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollInterruptLocked:702 : Skip interrupt, 1 -472389568
2012-02-15 05:30:24.676+0000: 14032: debug : virNetSocketFree:722 :
RPC_SOCKET_FREE: sock=0x1e6e3f0 refs=1
2012-02-15 05:30:24.676+0000: 14032: debug : virNetSocketFree:730 :
sock=0x1e6e3f0 fd=15
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollRemoveHandle:180 : EVENT_POLL_REMOVE_HANDLE: watch=24
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=11 e=1 d=0
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=12 e=1 d=0
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=14 e=1 d=0
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=6 w=8, f=16 e=1 d=0
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollMakePollFDs:383 : Prepare n=7 w=23, f=19 e=25 d=0
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollCalculateTimeout:325 : Calculate expiry of 5 timers
2012-02-15 05:30:24.676+0000: 14032: debug :
virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2012-02-15 05:30:24.676+0000: 14032: debug : virEventPollRunOnce:619 :
EVENT_POLL_RUN: nhandles=8 imeout=-1


Waiting for reply,

Tomoya.

2012/2/9 Josh Durgin <josh.durgin@dreamhost.com>:
> On 02/08/2012 03:42 PM, Masuko Tomoya wrote:
>>
>> Hi Christian,
>>
>> Thank you for reply !
>>
>> Could I ask a version of libvirt which is your patch applied ?
>>
>> I'm trying it with libvirt0.9.8-2ubuntu9, but there is same error.
>> (I'm using blow package:
>> https://launchpad.net/ubuntu/precise/+source/libvirt/0.9.8-2ubuntu9)
>>
>> root@compute04:~# dpkg -l | grep libvirt-bin
>> ii  libvirt-bin                            0.9.8-2ubuntu9
>>             programs for the libvirt library
>>
>> /var/log/libvirtd/libvirt.log
>> 2012-02-08 23:33:53.800+0000: 4663: error :
>> qemuMonitorTextAddDrive:2828 : operation failed: open disk image file
>> failed
>> 2012-02-08 23:33:53.800+0000: 4663: error :
>> virSecurityDACRestoreSecurityFileLabel:143 : cannot resolve symlink
>> rbd/testvolume: No such file or directory
>> 2012-02-08 23:33:54.212+0000: 4663: warning :
>> qemuDomainAttachPciDiskDevice:287 : Unable to restore security label
>> on rbd/testvolume
>
>
> libvirt 0.9.9 fixes this (the applied patch is
> http://www.redhat.com/archives/libvir-list/2011-December/msg00506.html if
> you're curious).
>
>
>>
>> 2012/2/6 Christian Brunner<christian@brunner-muc.de>:
>>>
>>> Libvirt is trying to set security lables even for network shares. This
>>> will not work. I think this is fixed in newer libvirt versions. For
>>> older versions you can try this patch:
>>>
>>> http://www.redhat.com/archives/libvir-list/2011-May/msg01446.html
>>>
>>> Regards,
>>> Christian
>>>
>>> 2012/2/4 Masuko Tomoya<tomoya.masuko@gmail.com>:
>>>>
>>>> Hi, all.
>>>>
>>>> I'm trying to attach rbd volume from instance on KVM.
>>>> But I have problem.
>>>> Could you help me ?
>>>>
>>>> ---
>>>> I tried to attach rbd volume on ceph01 to instance on compute1 with
>>>> virsh command.
>>>>
>>>> root@compute1:~# virsh attach-device test-ub16 /root/testvolume.xml
>>>> error: Failed to attach device from /root/testvolume.xml
>>>> error: cannot resolve symlink rbd/testvolume: No such file or directory
>>>>
>>>> /var/log/messages
>>>> Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: error :
>>>> qemuMonitorTextAddDevice:2417 : operation failed: adding
>>>>
>>>> virtio-blk-pci,bus=pci.0,addr=0x9,drive=drive-virtio-disk4,id=virtio-disk4
>>>> device failed: Device needs media, but drive is empty#015#012Device
>>>> 'virtio-blk-pci' could not be initialized#015#012
>>>> Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: warning :
>>>> qemuDomainAttachPciDiskDevice:188 : qemuMonitorAddDevice failed on
>>>> file=rbd:rbd/testvolume,if=none,id=drive-virtio-disk4,format=raw
>>>>
>>>> (virtio-blk-pci,bus=pci.0,addr=0x9,drive=drive-virtio-disk4,id=virtio-disk4)
>>>> Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: error :
>>>> virSecurityDACRestoreSecurityFileLabel:143 : cannot resolve symlink
>>>> rbd/testvolume: No such file or directory
>>>> Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: warning :
>>>> qemuDomainAttachPciDiskDevice:229 : Unable to restore security label
>>>> on rbd/testvolume
>>>>
>>>> there is no log in /var/log/ceph/mon.0.log of host ceph01.
>>>> ---
>>>>
>>>>
>>>> My environment is below.
>>>> *There are two servers. All server are ubuntu 10.10 x86_64.
>>>> *ceph01: single server configured ceph.(version: 0.41-1maverick)
>>>> *compute1: kvm hypervisor
>>>>  -librados2 and librbd1 packages are installed.
>>>>  (version: 0.41-1maverick)
>>>>  -qemu-kvm is 0.14.0-rc1. I built qemu with rbd enable.
>>>>  the output of run 'qemu-img' show 'rbd' at supported formats field.
>>>>  (I built qemu reffering this page.
>>>>  http://ceph.newdream.net/wiki/QEMU-RBD)
>>>>  -apparmor is disable.
>>>>  -libvirt is 0.8.8
>>>>
>>>> ====
>>>>  -there is ceph.conf on compute1.
>>>> root@compute1:~# ls -l /etc/ceph/
>>>> total 20
>>>> -rw-r--r-- 1 root root 508 2012-02-03 14:38 ceph.conf
>>>> -rw------- 1 root root  63 2012-02-03 17:04 keyring.admin
>>>> -rw------- 1 root root  63 2012-02-03 14:38 keyring.bin
>>>> -rw------- 1 root root  56 2012-02-03 14:38 keyring.mds.0
>>>> -rw------- 1 root root  56 2012-02-03 14:38 keyring.osd.0
>>>>
>>>> =====
>>>>  -contents of ceph.conf is below.
>>>> root@compute1:~# cat /etc/ceph/ceph.conf
>>>> [global]
>>>>       auth supported = cephx
>>>>       keyring = /etc/ceph/keyring.bin
>>>> [mon]
>>>>       mon data = /data/data/mon$id
>>>>       debug ms = 1
>>>> [mon.0]
>>>>       host = ceph01
>>>>       mon addr = 10.68.119.191:6789
>>>> [mds]
>>>>       keyring = /etc/ceph/keyring.$name
>>>> [mds.0]
>>>>       host = ceph01
>>>> [osd]
>>>>       keyring = /etc/ceph/keyring.$name
>>>>       osd data = /data/osd$id
>>>>       osd journal = /data/osd$id/journal
>>>>       osd journal size = 512
>>>>       osd class tmp = /var/lib/ceph/tmp
>>>>       debug osd = 20
>>>>       debug ms = 1
>>>>       debug filestore = 20
>>>> [osd.0]
>>>>       host = ceph01
>>>>       btrfs devs = /dev/sdb1
>>>>
>>>> ===
>>>> *conten of keyring.admin is below
>>>> root@compute1:~# cat /etc/ceph/keyring.admin
>>>> [client.admin]
>>>>       key = AQDFeCxPyBlNIRAAxS1DcRHpMXRpcjY/GNMwYg==
>>>>
>>>>
>>>> ===
>>>> *output of run 'ceph auth list'
>>>> root@ceph01:/etc/ceph# ceph auth list
>>>> 2012-02-03 20:34:59.507451 mon<- [auth,list]
>>>> 2012-02-03 20:34:59.508785 mon.0 ->  'installed auth entries:
>>>> mon.
>>>>       key: AQDFeCxPiK04IxAAslDBNkrOGKWxcbCh2iysqg==
>>>> mds.0
>>>>       key: AQDFeCxPsJ+LGhAAJ3/rmkAtGXSv/eHh0yXgww==
>>>>       caps: [mds] allow
>>>>       caps: [mon] allow rwx
>>>>       caps: [osd] allow *
>>>> osd.0
>>>>       key: AQDFeCxPoEK+ExAAecD7+tWgpIRoZx2AT7Jwbg==
>>>>       caps: [mon] allow rwx
>>>>       caps: [osd] allow *
>>>> client.admin
>>>>       key: AQDFeCxPyBlNIRAAxS1DcRHpMXRpcjY/GNMwYg==
>>>>       caps: [mds] allow
>>>>       caps: [mon] allow *
>>>>       caps: [osd] allow *
>>>> ' (0)
>>>>
>>>> ====
>>>> *xml file is below.
>>>> root@compute1:~# cat /root/testvolume.xml
>>>> <disk type='network' device='disk'>
>>>>  <driver name='qemu' type='raw'/>
>>>>  <source protocol='rbd' name='rbd/testvolume'>
>>>>   <host name='10.68.119.191' port='6789'/>
>>>>  </source>
>>>>  <target dev='vde' bus='virtio'/>
>>>> </disk>
>>>>
>>>> ====
>>>> *testvolume is on rados pools.
>>>> root@compute1:~# qemu-img info rbd:rbd/testvolume
>>>> image: rbd:rbd/testvolume
>>>> file format: raw
>>>> virtual size: 1.0G (1073741824 bytes)
>>>> disk size: unavailable
>>>>
>>>>
>>>> Waiting for reply,
>>>>
>>>> Tomoya.
>>>> --
>>>> To unsubscribe from this list: send the line "unsubscribe ceph-devel" in
>>>> the body of a message to majordomo@vger.kernel.org
>>>> More majordomo info at  http://vger.kernel.org/majordomo-info.html
--
To unsubscribe from this list: send the line "unsubscribe ceph-devel" in
the body of a message to majordomo@vger.kernel.org
More majordomo info at  http://vger.kernel.org/majordomo-info.html

^ permalink raw reply	[flat|nested] 7+ messages in thread

* Re: about attaching rbd volume from instance on KVM
  2012-02-16  3:01       ` Masuko Tomoya
@ 2012-02-18  2:49         ` Josh Durgin
       [not found]           ` <CAM2zNoNunP2AK43ZWeSUjKXoEeTd5VYeK-=DVTjTX-neR7whqQ@mail.gmail.com>
  0 siblings, 1 reply; 7+ messages in thread
From: Josh Durgin @ 2012-02-18  2:49 UTC (permalink / raw)
  To: Masuko Tomoya; +Cc: christian, ceph-devel

On 02/15/2012 07:01 PM, Masuko Tomoya wrote:
> Hi Josh,
>
> Thank you for your reply !
>
>> libvirt 0.9.9 fixes this (the applied patch is http://www.redhat.com/archives/libvir-list/2011-December/msg00506.html if you're curious).
> Finally I applied the patch to libvirt 0.9.8 and
> I fixed it.
>
> But I have another issue.
> When I tried attach rbd volume to VM on KVM, error occured.
>
> --
> root@compute04:~# virsh attach-device instance-00000027 testvolume4.xml
> error: Failed to attach device from testvolume4.xml
> error: operation failed: open disk image file failed
> --
>
> Could you advice to me ?
>
> *testvolume4.xml is below.
> <disk type='network' device='disk'>
>    <driver name='qemu' type='raw'/>
>    <source protocol='rbd' name='rbd/testvolume3'>
>      <host name='10.2.100.56' port='6789'/>
>    </source>
>    <target dev='vdm' bus='virtio'/>
> </disk>
>
> * I use this qemu-kvm with built rbd option on.
> root@compute04:/usr/bin# /usr/bin/qemu-system-x86_64 -version
> QEMU emulator version 1.0 (qemu-kvm-1.0), Copyright (c) 2003-2008
> Fabrice Bellard
>
> *libvirtd.log is below
> 2012-02-15 05:30:24.669+0000: 14034: debug :
> remoteDispatchDomainAttachDeviceHelper:297 : server=0x1dad7f0
> client=0x1e78fd0 msg=0x7fedd0003490 rerr=0x7feddeb1fc70 args=0x1e6f270
> ret=0x1e6f210
> 2012-02-15 05:30:24.669+0000: 14032: debug : virEventPollRunOnce:619 :
> EVENT_POLL_RUN: nhandles=9 imeout=5000
> 2012-02-15 05:30:24.669+0000: 14034: debug :
> virDomainAttachDevice:8542 : dom=0x1e6f230, (VM:
> name=instance-00000027, uuid=51846781-0d41-44a4-9da7-902c7c394f53),
> xml=<disk type='network' device='disk'>
>    <driver name='qemu' type='raw'/>
>    <source protocol='rbd' name='rbd/testvolume3'>
>      <host name='10.2.100.56' port='6789'/>
>    </source>
>    <target dev='vdm' bus='virtio'/>
> </disk>
>

Your xml looks fine, but libvirt has a problem:

> buf={"execute":"human-monitor-command","arguments":{"command-line":"drive_add
> dummy file=rbd:rbd/testvolume3:mon_host=10.2.100.56\\\\:6789,if=none,id=drive-virtio-disk12,format=raw"},"id":"libvirt-8"}
>   len=198 ret=198 errno=11

It looks like "10.2.100.56:6789" has been escaped twice. This makes qemu 
interpret the configuration as mon_host = 10.2.100.56\\, and then, since 
the colon is unescaped, 6789 is read as a separate option:

> 2012-02-15 05:30:24.674+0000: 14032: debug : qemuMonitorIOProcess:313
> : QEMU_MONITOR_IO_PROCESS: mon=0x1dcdf70 buf={"return": "conf option
> 6789 has no value\r\nerror setting config options\r\ncould not open
> disk image rbd:rbd/testvolume3:mon_host=10.2.100.56\\\\:6789: Invalid
> argument\r\n", "id": "libvirt-8"}
>   len=197
>

I'll look into the right way to solve this next week, but if you want a 
workaround, you can apply this patch to libvirt:

diff --git a/src/qemu/qemu_monitor_text.c b/src/qemu/qemu_monitor_text.c
index 2c68be8..3530ff4 100644
--- a/src/qemu/qemu_monitor_text.c
+++ b/src/qemu/qemu_monitor_text.c
@@ -2508,20 +2508,13 @@ int qemuMonitorTextAttachDrive(qemuMonitorPtr mon,
      char *cmd = NULL;
      char *reply = NULL;
      int ret = -1;
-    char *safe_str;
      int tryOldSyntax = 0;

-    safe_str = qemuMonitorEscapeArg(drivestr);
-    if (!safe_str) {
-        virReportOOMError();
-        return -1;
-    }
-
  try_command:
      if (virAsprintf(&cmd, "drive_add %s%.2x:%.2x:%.2x %s",
                      (tryOldSyntax ? "" : "pci_addr="),
                      controllerAddr->domain, controllerAddr->bus,
-                    controllerAddr->slot, safe_str) < 0) {
+                    controllerAddr->slot, drivestr) < 0) {
          virReportOOMError();
          goto cleanup;
      }
@@ -2555,7 +2548,6 @@ try_command:
  cleanup:
      VIR_FREE(cmd);
      VIR_FREE(reply);
-    VIR_FREE(safe_str);
      return ret;
  }

>
> Waiting for reply,
>
> Tomoya.
>
> 2012/2/9 Josh Durgin<josh.durgin@dreamhost.com>:
>> On 02/08/2012 03:42 PM, Masuko Tomoya wrote:
>>>
>>> Hi Christian,
>>>
>>> Thank you for reply !
>>>
>>> Could I ask a version of libvirt which is your patch applied ?
>>>
>>> I'm trying it with libvirt0.9.8-2ubuntu9, but there is same error.
>>> (I'm using blow package:
>>> https://launchpad.net/ubuntu/precise/+source/libvirt/0.9.8-2ubuntu9)
>>>
>>> root@compute04:~# dpkg -l | grep libvirt-bin
>>> ii  libvirt-bin                            0.9.8-2ubuntu9
>>>              programs for the libvirt library
>>>
>>> /var/log/libvirtd/libvirt.log
>>> 2012-02-08 23:33:53.800+0000: 4663: error :
>>> qemuMonitorTextAddDrive:2828 : operation failed: open disk image file
>>> failed
>>> 2012-02-08 23:33:53.800+0000: 4663: error :
>>> virSecurityDACRestoreSecurityFileLabel:143 : cannot resolve symlink
>>> rbd/testvolume: No such file or directory
>>> 2012-02-08 23:33:54.212+0000: 4663: warning :
>>> qemuDomainAttachPciDiskDevice:287 : Unable to restore security label
>>> on rbd/testvolume
>>
>>
>> libvirt 0.9.9 fixes this (the applied patch is
>> http://www.redhat.com/archives/libvir-list/2011-December/msg00506.html if
>> you're curious).
>>
>>
>>>
>>> 2012/2/6 Christian Brunner<christian@brunner-muc.de>:
>>>>
>>>> Libvirt is trying to set security lables even for network shares. This
>>>> will not work. I think this is fixed in newer libvirt versions. For
>>>> older versions you can try this patch:
>>>>
>>>> http://www.redhat.com/archives/libvir-list/2011-May/msg01446.html
>>>>
>>>> Regards,
>>>> Christian
>>>>
>>>> 2012/2/4 Masuko Tomoya<tomoya.masuko@gmail.com>:
>>>>>
>>>>> Hi, all.
>>>>>
>>>>> I'm trying to attach rbd volume from instance on KVM.
>>>>> But I have problem.
>>>>> Could you help me ?
>>>>>
>>>>> ---
>>>>> I tried to attach rbd volume on ceph01 to instance on compute1 with
>>>>> virsh command.
>>>>>
>>>>> root@compute1:~# virsh attach-device test-ub16 /root/testvolume.xml
>>>>> error: Failed to attach device from /root/testvolume.xml
>>>>> error: cannot resolve symlink rbd/testvolume: No such file or directory
>>>>>
>>>>> /var/log/messages
>>>>> Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: error :
>>>>> qemuMonitorTextAddDevice:2417 : operation failed: adding
>>>>>
>>>>> virtio-blk-pci,bus=pci.0,addr=0x9,drive=drive-virtio-disk4,id=virtio-disk4
>>>>> device failed: Device needs media, but drive is empty#015#012Device
>>>>> 'virtio-blk-pci' could not be initialized#015#012
>>>>> Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: warning :
>>>>> qemuDomainAttachPciDiskDevice:188 : qemuMonitorAddDevice failed on
>>>>> file=rbd:rbd/testvolume,if=none,id=drive-virtio-disk4,format=raw
>>>>>
>>>>> (virtio-blk-pci,bus=pci.0,addr=0x9,drive=drive-virtio-disk4,id=virtio-disk4)
>>>>> Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: error :
>>>>> virSecurityDACRestoreSecurityFileLabel:143 : cannot resolve symlink
>>>>> rbd/testvolume: No such file or directory
>>>>> Feb  3 20:14:48 compute1 libvirtd: 20:14:48.717: 3234: warning :
>>>>> qemuDomainAttachPciDiskDevice:229 : Unable to restore security label
>>>>> on rbd/testvolume
>>>>>
>>>>> there is no log in /var/log/ceph/mon.0.log of host ceph01.
>>>>> ---
>>>>>
>>>>>
>>>>> My environment is below.
>>>>> *There are two servers. All server are ubuntu 10.10 x86_64.
>>>>> *ceph01: single server configured ceph.(version: 0.41-1maverick)
>>>>> *compute1: kvm hypervisor
>>>>>   -librados2 and librbd1 packages are installed.
>>>>>   (version: 0.41-1maverick)
>>>>>   -qemu-kvm is 0.14.0-rc1. I built qemu with rbd enable.
>>>>>   the output of run 'qemu-img' show 'rbd' at supported formats field.
>>>>>   (I built qemu reffering this page.
>>>>>   http://ceph.newdream.net/wiki/QEMU-RBD)
>>>>>   -apparmor is disable.
>>>>>   -libvirt is 0.8.8
>>>>>
>>>>> ====
>>>>>   -there is ceph.conf on compute1.
>>>>> root@compute1:~# ls -l /etc/ceph/
>>>>> total 20
>>>>> -rw-r--r-- 1 root root 508 2012-02-03 14:38 ceph.conf
>>>>> -rw------- 1 root root  63 2012-02-03 17:04 keyring.admin
>>>>> -rw------- 1 root root  63 2012-02-03 14:38 keyring.bin
>>>>> -rw------- 1 root root  56 2012-02-03 14:38 keyring.mds.0
>>>>> -rw------- 1 root root  56 2012-02-03 14:38 keyring.osd.0
>>>>>
>>>>> =====
>>>>>   -contents of ceph.conf is below.
>>>>> root@compute1:~# cat /etc/ceph/ceph.conf
>>>>> [global]
>>>>>        auth supported = cephx
>>>>>        keyring = /etc/ceph/keyring.bin
>>>>> [mon]
>>>>>        mon data = /data/data/mon$id
>>>>>        debug ms = 1
>>>>> [mon.0]
>>>>>        host = ceph01
>>>>>        mon addr = 10.68.119.191:6789
>>>>> [mds]
>>>>>        keyring = /etc/ceph/keyring.$name
>>>>> [mds.0]
>>>>>        host = ceph01
>>>>> [osd]
>>>>>        keyring = /etc/ceph/keyring.$name
>>>>>        osd data = /data/osd$id
>>>>>        osd journal = /data/osd$id/journal
>>>>>        osd journal size = 512
>>>>>        osd class tmp = /var/lib/ceph/tmp
>>>>>        debug osd = 20
>>>>>        debug ms = 1
>>>>>        debug filestore = 20
>>>>> [osd.0]
>>>>>        host = ceph01
>>>>>        btrfs devs = /dev/sdb1
>>>>>
>>>>> ===
>>>>> *conten of keyring.admin is below
>>>>> root@compute1:~# cat /etc/ceph/keyring.admin
>>>>> [client.admin]
>>>>>        key = AQDFeCxPyBlNIRAAxS1DcRHpMXRpcjY/GNMwYg==
>>>>>
>>>>>
>>>>> ===
>>>>> *output of run 'ceph auth list'
>>>>> root@ceph01:/etc/ceph# ceph auth list
>>>>> 2012-02-03 20:34:59.507451 mon<- [auth,list]
>>>>> 2012-02-03 20:34:59.508785 mon.0 ->    'installed auth entries:
>>>>> mon.
>>>>>        key: AQDFeCxPiK04IxAAslDBNkrOGKWxcbCh2iysqg==
>>>>> mds.0
>>>>>        key: AQDFeCxPsJ+LGhAAJ3/rmkAtGXSv/eHh0yXgww==
>>>>>        caps: [mds] allow
>>>>>        caps: [mon] allow rwx
>>>>>        caps: [osd] allow *
>>>>> osd.0
>>>>>        key: AQDFeCxPoEK+ExAAecD7+tWgpIRoZx2AT7Jwbg==
>>>>>        caps: [mon] allow rwx
>>>>>        caps: [osd] allow *
>>>>> client.admin
>>>>>        key: AQDFeCxPyBlNIRAAxS1DcRHpMXRpcjY/GNMwYg==
>>>>>        caps: [mds] allow
>>>>>        caps: [mon] allow *
>>>>>        caps: [osd] allow *
>>>>> ' (0)
>>>>>
>>>>> ====
>>>>> *xml file is below.
>>>>> root@compute1:~# cat /root/testvolume.xml
>>>>> <disk type='network' device='disk'>
>>>>>   <driver name='qemu' type='raw'/>
>>>>>   <source protocol='rbd' name='rbd/testvolume'>
>>>>>    <host name='10.68.119.191' port='6789'/>
>>>>>   </source>
>>>>>   <target dev='vde' bus='virtio'/>
>>>>> </disk>
>>>>>
>>>>> ====
>>>>> *testvolume is on rados pools.
>>>>> root@compute1:~# qemu-img info rbd:rbd/testvolume
>>>>> image: rbd:rbd/testvolume
>>>>> file format: raw
>>>>> virtual size: 1.0G (1073741824 bytes)
>>>>> disk size: unavailable
>>>>>
>>>>>
>>>>> Waiting for reply,
>>>>>
>>>>> Tomoya.

^ permalink raw reply related	[flat|nested] 7+ messages in thread

* Re: about attaching rbd volume from instance on KVM
       [not found]             ` <4F4A7082.7020303@dreamhost.com>
@ 2012-02-28  0:18               ` Josh Durgin
  0 siblings, 0 replies; 7+ messages in thread
From: Josh Durgin @ 2012-02-28  0:18 UTC (permalink / raw)
  To: Masuko Tomoya; +Cc: christian, ceph-devel

On 02/26/2012 09:48 AM, Josh Durgin wrote:
> (resending since the list didn't receive it)
>
> On 02/20/2012 01:37 AM, Masuko Tomoya wrote:
>> Hi Josh,
>>
>> Thank you for reply.
>> Your advice is very helpful!!
>>
>> I applied your patch to libvirt 0.9.8 and rebuilt it.
>> But same error was occured.
>>
>> Could you advise me ?
>
> That workaround patched the wrong function. I sent a patch to libvirt
> that deals with the problem generically:
>
> https://www.redhat.com/archives/libvir-list/2012-February/msg01044.html
> (also at https://github.com/NewDreamNetwork/libvirt.git for easier access)
>
> Josh

This was merged as f27f616ff899732fe90ce1f0f4abb3887cea5e17 upstream.

^ permalink raw reply	[flat|nested] 7+ messages in thread

end of thread, other threads:[~2012-02-28  0:18 UTC | newest]

Thread overview: 7+ messages (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
2012-02-04  4:48 about attaching rbd volume from instance on KVM Masuko Tomoya
2012-02-06  8:06 ` Christian Brunner
2012-02-08 23:42   ` Masuko Tomoya
2012-02-09  2:10     ` Josh Durgin
2012-02-16  3:01       ` Masuko Tomoya
2012-02-18  2:49         ` Josh Durgin
     [not found]           ` <CAM2zNoNunP2AK43ZWeSUjKXoEeTd5VYeK-=DVTjTX-neR7whqQ@mail.gmail.com>
     [not found]             ` <4F4A7082.7020303@dreamhost.com>
2012-02-28  0:18               ` Josh Durgin

This is an external index of several public inboxes,
see mirroring instructions on how to clone and mirror
all data and code used by this external index.