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@xxxxxxxxxxxxx>: > 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@xxxxxxxxxxxxxx>: >>> >>> 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@xxxxxxxxx>: >>>> >>>> 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@xxxxxxxxxxxxxxx >>>> 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@xxxxxxxxxxxxxxx More majordomo info at http://vger.kernel.org/majordomo-info.html
