Attachment 622766 Details for Bug 863696 – libvirtd.log with debug log 1

libvirtd.log with debug log 1

libvirtd.log (text/plain), 176.24 KB, created by Kashyap Chamarthy on 2012-10-06 17:10:10 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Kashyap Chamarthy

Created: 2012-10-06 17:10:10 UTC

Size: 176.24 KB

patch

obsolete

>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 0
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=3 events=1
>2012-10-06 16:41:37.486+0000: 23344: debug : virNetSocketNew:147 : localAddr=0x7fffd2843790 remoteAddr=0x7fffd2843820 fd=21 errfd=-1 pid=0
>2012-10-06 16:41:37.486+0000: 23344: debug : virObjectNew:110 : OBJECT_NEW: obj=0x1a31a50 classname=virNetSocket
>2012-10-06 16:41:37.486+0000: 23344: debug : virNetSocketNew:204 : RPC_SOCKET_NEW: sock=0x1a31a50 fd=21 errfd=-1 pid=0 localAddr=127.0.0.1;0, remoteAddr=127.0.0.1;0
>2012-10-06 16:41:37.486+0000: 23344: debug : virNetServerClientNew:403 : sock=0x1a31a50 auth=2 tls=(nil)
>2012-10-06 16:41:37.486+0000: 23344: debug : virObjectNew:110 : OBJECT_NEW: obj=0x1a31ca0 classname=virNetServerClient
>2012-10-06 16:41:37.486+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31a50
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollAddTimeout:220 : Used 0 timeout slots, adding at least 10 more
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollAddTimeout:243 : EVENT_POLL_ADD_TIMEOUT: timer=1 frequency=-1 cb=0x7f25f716aa30 opaque=0x1a31ca0 ff=(nil)
>2012-10-06 16:41:37.486+0000: 23344: debug : virNetMessageNew:45 : msg=0x1a31d80 tracked=1
>2012-10-06 16:41:37.486+0000: 23344: debug : virNetServerClientNewInternal:382 : RPC_SERVER_CLIENT_NEW: client=0x1a31ca0 sock=0x1a31a50
>2012-10-06 16:41:37.486+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a31d80 tx=(nil)
>2012-10-06 16:41:37.486+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-10-06 16:41:37.486+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31ca0
>2012-10-06 16:41:37.486+0000: 23344: debug : virNetServerClientRegisterEvent:199 : Registering client event callback 1
>2012-10-06 16:41:37.486+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31a50
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollAddHandle:136 : EVENT_POLL_ADD_HANDLE: watch=12 fd=21 events=1 cb=0x7f25f71717c0 opaque=0x1a31a50 ff=0x7f25f7171850
>2012-10-06 16:41:37.486+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31ca0
>2012-10-06 16:41:37.486+0000: 23344: debug : virKeepAliveNew:212 : client=0x1a31ca0, interval=5, count=5
>2012-10-06 16:41:37.486+0000: 23344: debug : virObjectNew:110 : OBJECT_NEW: obj=0x1a324c0 classname=virKeepAlive
>2012-10-06 16:41:37.486+0000: 23344: debug : virKeepAliveNew:236 : RPC_KEEPALIVE_NEW: ka=0x1a324c0 client=0x1a31ca0
>2012-10-06 16:41:37.486+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31ca0
>2012-10-06 16:41:37.486+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0
>2012-10-06 16:41:37.486+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31a50
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:37.486+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1
>2012-10-06 16:41:37.487+0000: 23344: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2012-10-06 16:41:37.487+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a31d80 tx=(nil)
>2012-10-06 16:41:37.487+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976
>2012-10-06 16:41:37.487+0000: 23344: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x1a31ca0 len=28 prog=536903814 vers=1 proc=66 type=0 status=0 serial=0
>2012-10-06 16:41:37.487+0000: 23344: debug : virKeepAliveCheckMessage:384 : ka=0x1a324c0, client=0x1a31ca0, msg=0x1a31d80
>2012-10-06 16:41:37.487+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31ca0
>2012-10-06 16:41:37.487+0000: 23344: debug : virNetServerDispatchNewMessage:218 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a31d80
>2012-10-06 16:41:37.487+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a23da0
>2012-10-06 16:41:37.487+0000: 23344: debug : virNetMessageNew:45 : msg=0x1a274f0 tracked=1
>2012-10-06 16:41:37.487+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a274f0 tx=(nil)
>2012-10-06 16:41:37.487+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2012-10-06 16:41:37.487+0000: 23350: debug : virNetServerHandleJob:189 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a31d80 prog=0x1a23da0
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976
>2012-10-06 16:41:37.487+0000: 23350: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=0 proc=66
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:37.487+0000: 23350: debug : remoteDispatchAuthListHelper:18 : server=0x1a0ff10 client=0x1a31ca0 msg=0x1a31d80 rerr=0x7f25e624bc70 args=0x7f25d8104b50 ret=0x7f25d80cdbd0
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:37.487+0000: 23350: info : remoteDispatchAuthList:2372 : Bypass polkit auth for privileged client pid:23504,uid:0
>2012-10-06 16:41:37.487+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:37.488+0000: 23350: debug : virNetMessageEncodePayload:358 : Encode length as 36
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:37.488+0000: 23350: debug : virNetServerClientSendMessageLocked:1116 : msg=0x1a31d80 proc=66 len=36 offset=0
>2012-10-06 16:41:37.488+0000: 23350: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1a31ca0 len=36 prog=536903814 vers=1 proc=66 type=1 status=0 serial=0
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:37.488+0000: 23350: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a274f0 tx=0x1a31d80
>2012-10-06 16:41:37.488+0000: 23350: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2012-10-06 16:41:37.488+0000: 23350: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1
>2012-10-06 16:41:37.488+0000: 23350: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-10-06 16:41:37.488+0000: 23350: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a23da0
>2012-10-06 16:41:37.488+0000: 23350: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=5 d=0
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:37.488+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2
>2012-10-06 16:41:37.489+0000: 23344: debug : virNetMessageFree:73 : msg=0x1a31d80 nfds=0 cb=(nil)
>2012-10-06 16:41:37.489+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a274f0 tx=(nil)
>2012-10-06 16:41:37.489+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1
>2012-10-06 16:41:37.489+0000: 23344: debug : virNetMessageDecodeLength:152 : Got length, now need 40 total (36 more)
>2012-10-06 16:41:37.489+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a274f0 tx=(nil)
>2012-10-06 16:41:37.489+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2012-10-06 16:41:37.489+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976
>2012-10-06 16:41:37.490+0000: 23344: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x1a31ca0 len=40 prog=536903814 vers=1 proc=1 type=0 status=0 serial=1
>2012-10-06 16:41:37.490+0000: 23344: debug : virKeepAliveCheckMessage:384 : ka=0x1a324c0, client=0x1a31ca0, msg=0x1a274f0
>2012-10-06 16:41:37.490+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31ca0
>2012-10-06 16:41:37.490+0000: 23344: debug : virNetServerDispatchNewMessage:218 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a274f0
>2012-10-06 16:41:37.490+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a23da0
>2012-10-06 16:41:37.490+0000: 23344: debug : virNetMessageNew:45 : msg=0x1a26a70 tracked=1
>2012-10-06 16:41:37.490+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a26a70 tx=(nil)
>2012-10-06 16:41:37.490+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976
>2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.490+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0
>2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:37.490+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1
>2012-10-06 16:41:37.490+0000: 23346: debug : virNetServerHandleJob:189 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a274f0 prog=0x1a23da0
>2012-10-06 16:41:37.490+0000: 23346: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=1 proc=1
>2012-10-06 16:41:37.490+0000: 23346: debug : remoteDispatchOpenHelper:11127 : server=0x1a0ff10 client=0x1a31ca0 msg=0x1a274f0 rerr=0x7f25e824fc70 args=0x7f25d0002480 ret=0x7f25d00023a0
>2012-10-06 16:41:37.490+0000: 23346: debug : remoteDispatchOpen:707 : priv=0x1a32130 conn=(nil)
>2012-10-06 16:41:37.490+0000: 23346: debug : virConnectOpen:1331 : name=
>2012-10-06 16:41:37.490+0000: 23346: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f25d00025f0 classname=virConnect
>2012-10-06 16:41:37.491+0000: 23346: debug : virConnectGetConfigFile:953 : Loading config file '/etc/libvirt/libvirt.conf'
>2012-10-06 16:41:37.491+0000: 23346: debug : virFileClose:72 : Closed fd 23
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1131 : no name, allowing driver auto-select
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1174 : trying driver 0 (Test) ...
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1180 : driver 0 Test returned DECLINED
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1174 : trying driver 1 (OPENVZ) ...
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1180 : driver 1 OPENVZ returned DECLINED
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1174 : trying driver 2 (VMWARE) ...
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1180 : driver 2 VMWARE returned DECLINED
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1174 : trying driver 3 (PHYP) ...
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1180 : driver 3 PHYP returned DECLINED
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1174 : trying driver 4 (VBOX) ...
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1180 : driver 4 VBOX returned DECLINED
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1174 : trying driver 5 (ESX) ...
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1180 : driver 5 ESX returned DECLINED
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1174 : trying driver 6 (Hyper-V) ...
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1180 : driver 6 Hyper-V returned DECLINED
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1174 : trying driver 7 (remote) ...
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1180 : driver 7 remote returned DECLINED
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1174 : trying driver 8 (Xen) ...
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1180 : driver 8 Xen returned DECLINED
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1174 : trying driver 9 (xenlight) ...
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1180 : driver 9 xenlight returned DECLINED
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1174 : trying driver 10 (QEMU) ...
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1180 : driver 10 QEMU returned SUCCESS
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1202 : network driver 0 Test returned DECLINED
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1202 : network driver 1 VBOX returned DECLINED
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1202 : network driver 2 ESX returned DECLINED
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1202 : network driver 3 Hyper-V returned DECLINED
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1202 : network driver 4 remote returned DECLINED
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1202 : network driver 5 Network returned SUCCESS
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1217 : interface driver 0 Test returned DECLINED
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1217 : interface driver 1 PHYP returned DECLINED
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1217 : interface driver 2 ESX returned DECLINED
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1217 : interface driver 3 Hyper-V returned DECLINED
>2012-10-06 16:41:37.491+0000: 23346: debug : do_open:1217 : interface driver 4 remote returned DECLINED
>2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1217 : interface driver 5 Interface returned SUCCESS
>2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1233 : storage driver 0 Test returned DECLINED
>2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1233 : storage driver 1 PHYP returned DECLINED
>2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1233 : storage driver 2 VBOX returned DECLINED
>2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1233 : storage driver 3 ESX returned DECLINED
>2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1233 : storage driver 4 Hyper-V returned DECLINED
>2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1233 : storage driver 5 remote returned DECLINED
>2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1233 : storage driver 6 storage returned SUCCESS
>2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1249 : node driver 0 Test returned DECLINED
>2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1249 : node driver 1 ESX returned DECLINED
>2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1249 : node driver 2 Hyper-V returned DECLINED
>2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1249 : node driver 3 remote returned DECLINED
>2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1249 : node driver 4 udevDeviceMonitor returned SUCCESS
>2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1265 : secret driver 0 Test returned DECLINED
>2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1265 : secret driver 1 ESX returned DECLINED
>2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1265 : secret driver 2 Hyper-V returned DECLINED
>2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1265 : secret driver 3 remote returned DECLINED
>2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1265 : secret driver 4 secret returned SUCCESS
>2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1281 : nwfilter driver 0 Test returned DECLINED
>2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1281 : nwfilter driver 1 ESX returned DECLINED
>2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1281 : nwfilter driver 2 Hyper-V returned DECLINED
>2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1281 : nwfilter driver 3 remote returned DECLINED
>2012-10-06 16:41:37.501+0000: 23346: debug : do_open:1281 : nwfilter driver 4 nwfilter returned SUCCESS
>2012-10-06 16:41:37.501+0000: 23346: debug : virNetMessageEncodePayload:358 : Encode length as 28
>2012-10-06 16:41:37.502+0000: 23346: debug : virNetServerClientSendMessageLocked:1116 : msg=0x1a274f0 proc=1 len=28 offset=0
>2012-10-06 16:41:37.502+0000: 23346: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1a31ca0 len=28 prog=536903814 vers=1 proc=1 type=1 status=0 serial=1
>2012-10-06 16:41:37.502+0000: 23346: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a26a70 tx=0x1a274f0
>2012-10-06 16:41:37.502+0000: 23346: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2012-10-06 16:41:37.502+0000: 23346: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3
>2012-10-06 16:41:37.502+0000: 23346: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-10-06 16:41:37.502+0000: 23346: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a23da0
>2012-10-06 16:41:37.502+0000: 23346: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=5 d=0
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2
>2012-10-06 16:41:37.502+0000: 23344: debug : virNetMessageFree:73 : msg=0x1a274f0 nfds=0 cb=(nil)
>2012-10-06 16:41:37.502+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a26a70 tx=(nil)
>2012-10-06 16:41:37.502+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:37.502+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1
>2012-10-06 16:41:37.503+0000: 23344: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2012-10-06 16:41:37.503+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a26a70 tx=(nil)
>2012-10-06 16:41:37.503+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976
>2012-10-06 16:41:37.503+0000: 23344: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x1a31ca0 len=28 prog=536903814 vers=1 proc=110 type=0 status=0 serial=2
>2012-10-06 16:41:37.503+0000: 23344: debug : virKeepAliveCheckMessage:384 : ka=0x1a324c0, client=0x1a31ca0, msg=0x1a26a70
>2012-10-06 16:41:37.503+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31ca0
>2012-10-06 16:41:37.503+0000: 23344: debug : virNetServerDispatchNewMessage:218 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a26a70
>2012-10-06 16:41:37.503+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a23da0
>2012-10-06 16:41:37.503+0000: 23344: debug : virNetMessageNew:45 : msg=0x1a32550 tracked=1
>2012-10-06 16:41:37.503+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a32550 tx=(nil)
>2012-10-06 16:41:37.503+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:37.503+0000: 23347: debug : virNetServerHandleJob:189 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a26a70 prog=0x1a23da0
>2012-10-06 16:41:37.503+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1
>2012-10-06 16:41:37.504+0000: 23347: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=2 proc=110
>2012-10-06 16:41:37.504+0000: 23347: debug : remoteDispatchGetURIHelper:7297 : server=0x1a0ff10 client=0x1a31ca0 msg=0x1a26a70 rerr=0x7f25e7a4ec70 args=0x7f25d40008c0 ret=0x7f25d40008e0
>2012-10-06 16:41:37.504+0000: 23347: debug : virConnectGetURI:1700 : conn=0x7f25d00025f0
>2012-10-06 16:41:37.504+0000: 23347: debug : virNetMessageEncodePayload:358 : Encode length as 48
>2012-10-06 16:41:37.504+0000: 23347: debug : virNetServerClientSendMessageLocked:1116 : msg=0x1a26a70 proc=110 len=48 offset=0
>2012-10-06 16:41:37.504+0000: 23347: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1a31ca0 len=48 prog=536903814 vers=1 proc=110 type=1 status=0 serial=2
>2012-10-06 16:41:37.504+0000: 23347: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a32550 tx=0x1a26a70
>2012-10-06 16:41:37.504+0000: 23347: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2012-10-06 16:41:37.504+0000: 23347: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3
>2012-10-06 16:41:37.504+0000: 23347: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-10-06 16:41:37.504+0000: 23347: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a23da0
>2012-10-06 16:41:37.504+0000: 23347: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=5 d=0
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2
>2012-10-06 16:41:37.504+0000: 23344: debug : virNetMessageFree:73 : msg=0x1a26a70 nfds=0 cb=(nil)
>2012-10-06 16:41:37.504+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a32550 tx=(nil)
>2012-10-06 16:41:37.504+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.504+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1
>2012-10-06 16:41:37.505+0000: 23344: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2012-10-06 16:41:37.505+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a32550 tx=(nil)
>2012-10-06 16:41:37.505+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976
>2012-10-06 16:41:37.505+0000: 23344: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x1a31ca0 len=28 prog=536903814 vers=1 proc=7 type=0 status=0 serial=3
>2012-10-06 16:41:37.505+0000: 23344: debug : virKeepAliveCheckMessage:384 : ka=0x1a324c0, client=0x1a31ca0, msg=0x1a32550
>2012-10-06 16:41:37.505+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31ca0
>2012-10-06 16:41:37.505+0000: 23344: debug : virNetServerDispatchNewMessage:218 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a32550
>2012-10-06 16:41:37.505+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a23da0
>2012-10-06 16:41:37.505+0000: 23344: debug : virNetMessageNew:45 : msg=0x1a325c0 tracked=1
>2012-10-06 16:41:37.505+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a325c0 tx=(nil)
>2012-10-06 16:41:37.505+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:37.505+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:37.506+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:37.506+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:37.506+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0
>2012-10-06 16:41:37.506+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:37.506+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:37.506+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1
>2012-10-06 16:41:37.506+0000: 23348: debug : virNetServerHandleJob:189 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a32550 prog=0x1a23da0
>2012-10-06 16:41:37.506+0000: 23348: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=7
>2012-10-06 16:41:37.506+0000: 23348: debug : remoteDispatchGetCapabilitiesHelper:6997 : server=0x1a0ff10 client=0x1a31ca0 msg=0x1a32550 rerr=0x7f25e724dc70 args=0x7f25c8000950 ret=0x7f25c80008c0
>2012-10-06 16:41:37.506+0000: 23348: debug : virConnectGetCapabilities:6418 : conn=0x7f25d00025f0
>2012-10-06 16:41:37.512+0000: 23348: debug : cpuNodeData:236 : arch=x86_64
>2012-10-06 16:41:37.512+0000: 23348: debug : cpuDecode:141 : cpu=0x7f25c8000b10, data=0x7f25c8000ca0, nmodels=0, preferred=(null)
>2012-10-06 16:41:37.517+0000: 23348: debug : x86Decode:1362 : CPU vendor AMD of model Opteron_G4 differs from Intel; ignoring
>2012-10-06 16:41:37.517+0000: 23348: debug : x86Decode:1362 : CPU vendor AMD of model Opteron_G3 differs from Intel; ignoring
>2012-10-06 16:41:37.517+0000: 23348: debug : x86Decode:1362 : CPU vendor AMD of model Opteron_G2 differs from Intel; ignoring
>2012-10-06 16:41:37.517+0000: 23348: debug : x86Decode:1362 : CPU vendor AMD of model Opteron_G1 differs from Intel; ignoring
>2012-10-06 16:41:37.517+0000: 23348: debug : x86Decode:1362 : CPU vendor AMD of model phenom differs from Intel; ignoring
>2012-10-06 16:41:37.517+0000: 23348: debug : x86Decode:1362 : CPU vendor AMD of model athlon differs from Intel; ignoring
>2012-10-06 16:41:37.518+0000: 23348: debug : cpuDataFree:212 : arch=x86_64, data=0x7f25c8000ca0
>2012-10-06 16:41:37.518+0000: 23348: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d8107640 for /usr/bin/qemu-system-i386
>2012-10-06 16:41:37.518+0000: 23348: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d8107640
>2012-10-06 16:41:37.518+0000: 23348: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d8107160 for /usr/bin/qemu-kvm
>2012-10-06 16:41:37.518+0000: 23348: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d8107160
>2012-10-06 16:41:37.518+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d8107640
>2012-10-06 16:41:37.518+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d8107160
>2012-10-06 16:41:37.519+0000: 23348: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d8019270 for /usr/bin/qemu-system-x86_64
>2012-10-06 16:41:37.519+0000: 23348: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d8019270
>2012-10-06 16:41:37.519+0000: 23348: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d8107160 for /usr/bin/qemu-kvm
>2012-10-06 16:41:37.519+0000: 23348: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d8107160
>2012-10-06 16:41:37.519+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d8019270
>2012-10-06 16:41:37.519+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d8107160
>2012-10-06 16:41:37.519+0000: 23348: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d80a10c0 for /usr/bin/qemu-system-arm
>2012-10-06 16:41:37.519+0000: 23348: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d80a10c0
>2012-10-06 16:41:37.519+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d80a10c0
>2012-10-06 16:41:37.519+0000: 23348: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d80a1d60 for /usr/bin/qemu-system-mips
>2012-10-06 16:41:37.519+0000: 23348: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d80a1d60
>2012-10-06 16:41:37.519+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d80a1d60
>2012-10-06 16:41:37.520+0000: 23348: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d8105ab0 for /usr/bin/qemu-system-mipsel
>2012-10-06 16:41:37.520+0000: 23348: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d8105ab0
>2012-10-06 16:41:37.520+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d8105ab0
>2012-10-06 16:41:37.520+0000: 23348: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d8106840 for /usr/bin/qemu-system-sparc
>2012-10-06 16:41:37.520+0000: 23348: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d8106840
>2012-10-06 16:41:37.520+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d8106840
>2012-10-06 16:41:37.520+0000: 23348: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d8106f80 for /usr/bin/qemu-system-ppc
>2012-10-06 16:41:37.521+0000: 23348: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d8106f80
>2012-10-06 16:41:37.521+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d8106f80
>2012-10-06 16:41:37.521+0000: 23348: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d80acdc0 for /usr/bin/qemu-system-ppc64
>2012-10-06 16:41:37.521+0000: 23348: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d80acdc0
>2012-10-06 16:41:37.521+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d80acdc0
>2012-10-06 16:41:37.521+0000: 23348: debug : qemuCreateCapabilities:434 : Initialized caps for security driver "selinux" with DOI "0"
>2012-10-06 16:41:37.521+0000: 23348: debug : qemuCreateCapabilities:434 : Initialized caps for security driver "dac" with DOI "0"
>2012-10-06 16:41:37.521+0000: 23348: debug : virConnectGetCapabilities:6433 : conn=0x7f25d00025f0 ret=<capabilities>
>
>  <host>
>    <uuid>401da052-c681-b601-f35c-00215e6fb9f4</uuid>
>    <cpu>
>      <arch>x86_64</arch>
>      <model>Penryn</model>
>      <vendor>Intel</vendor>
>      <topology sockets='1' cores='4' threads='1'/>
>      <feature name='osxsave'/>
>      <feature name='xsave'/>
>      <feature name='dca'/>
>      <feature name='pdcm'/>
>      <feature name='xtpr'/>
>      <feature name='tm2'/>
>      <feature name='vmx'/>
>      <feature name='ds_cpl'/>
>      <feature name='monitor'/>
>      <feature name='dtes64'/>
>      <feature name='pbe'/>
>      <feature name='tm'/>
>      <feature name='ht'/>
>      <feature name='ss'/>
>      <feature name='acpi'/>
>      <feature name='ds'/>
>      <feature name='vme'/>
>    </cpu>
>    <power_management>
>      <suspend_disk/>
>      <suspend_hybrid/>
>    </power_management>
>    <migration_features>
>      <live/>
>      <uri_transports>
>        <uri_transport>tcp</uri_transport>
>      </uri_transports>
>    </migration_features>
>    <topology>
>      <cells num='1'>
>        <cell id='0'>
>          <cpus num='4'>
>            <cpu id='0'/>
>            <cpu id='1'/>
>            <cpu id='2'/>
>            <cpu id='3'/>
>          </cpus>
>        </cell>
>      </cells>
>    </topology>
>    <secmodel>
>      <model>selinux</model>
>      <doi>0</doi>
>    </secmodel>
>    <secmodel>
>      <model>dac</model>
>      <doi>0</doi>
>    </secmodel>
>  </host>
>
>  <guest>
>    <os_type>hvm</os_type>
>    <arch name='i686'>
>      <wordsize>32</wordsize>
>      <emulator>/usr/bin/qemu-system-i386</emulator>
>      <machine>isapc</machine>
>      <machine>none</machine>
>      <machine canonical='pc-1.2'>pc</machine>
>      <machine>pc-1.2</machine>
>      <machine>pc-1.1</machine>
>      <machine>pc-1.0</machine>
>      <machine>pc-0.15</machine>
>      <machine>pc-0.14</machine>
>      <machine>pc-0.13</machine>
>      <machine>pc-0.12</machine>
>      <machine>pc-0.11</machine>
>      <machine>pc-0.10</machine>
>      <domain type='qemu'>
>      </domain>
>      <domain type='kvm'>
>        <emulator>/usr/bin/qemu-kvm</emulator>
>        <machine>isapc</machine>
>        <machine>none</machine>
>        <machine canonical='pc-1.2'>pc</machine>
>        <machine>pc-1.2</machine>
>        <machine>pc-1.1</machine>
>        <machine>pc-1.0</machine>
>        <machine>pc-0.15</machine>
>        <machine>pc-0.14</machine>
>        <machine>pc-0.13</machine>
>        <machine>pc-0.12</machine>
>        <machine>pc-0.11</machine>
>        <machine>pc-0.10</machine>
>      </domain>
>    </arch>
>    <features>
>      <deviceboot/>
>      <acpi default='on' toggle='yes'/>
>      <apic default='on' toggle='no'/>
>      <pae/>
>      <nonpae/>
>    </features>
>  </guest>
>
>  <guest>
>    <os_type>hvm</os_type>
>    <arch name='x86_64'>
>      <wordsize>64</wordsize>
>      <emulator>/usr/bin/qemu-system-x86_64</emulator>
>      <machine>isapc</machine>
>      <machine>none</machine>
>      <machine canonical='pc-1.2'>pc</machine>
>      <machine>pc-1.2</machine>
>      <machine>pc-1.1</machine>
>      <machine>pc-1.0</machine>
>      <machine>pc-0.15</machine>
>      <machine>pc-0.14</machine>
>      <machine>pc-0.13</machine>
>      <machine>pc-0.12</machine>
>      <machine>pc-0.11</machine>
>      <machine>pc-0.10</machine>
>      <domain type='qemu'>
>      </domain>
>      <domain type='kvm'>
>        <emulator>/usr/bin/qemu-kvm</emulator>
>        <machine>isapc</machine>
>        <machine>none</machine>
>        <machine canonical='pc-1.2'>pc</machine>
>        <machine>pc-1.2</machine>
>        <machine>pc-1.1</machine>
>        <machine>pc-1.0</machine>
>        <machine>pc-0.15</machine>
>        <machine>pc-0.14</machine>
>        <machine>pc-0.13</machine>
>        <machine>pc-0.12</machine>
>        <machine>pc-0.11</machine>
>        <machine>pc-0.10</machine>
>      </domain>
>    </arch>
>    <features>
>      <cpuselection/>
>      <deviceboot/>
>      <acpi default='on' toggle='yes'/>
>      <apic default='on' toggle='no'/>
>    </features>
>  </guest>
>
>  <guest>
>    <os_type>hvm</os_type>
>    <arch name='arm'>
>      <wordsize>64</wordsize>
>      <emulator>/usr/bin/qemu-system-arm</emulator>
>      <machine>z2</machine>
>      <machine>none</machine>
>      <machine>collie</machine>
>      <machine>nuri</machine>
>      <machine>smdkc210</machine>
>      <machine>connex</machine>
>      <machine>verdex</machine>
>      <machine>highbank</machine>
>      <machine>integratorcp</machine>
>      <machine>kzm</machine>
>      <machine>mainstone</machine>
>      <machine>musicpal</machine>
>      <machine>n800</machine>
>      <machine>n810</machine>
>      <machine>sx1</machine>
>      <machine>sx1-v1</machine>
>      <machine>cheetah</machine>
>      <machine>realview-eb</machine>
>      <machine>realview-eb-mpcore</machine>
>      <machine>realview-pb-a8</machine>
>      <machine>realview-pbx-a9</machine>
>      <machine>akita</machine>
>      <machine>spitz</machine>
>      <machine>borzoi</machine>
>      <machine>terrier</machine>
>      <machine>lm3s811evb</machine>
>      <machine>lm3s6965evb</machine>
>      <machine>tosa</machine>
>      <machine>versatilepb</machine>
>      <machine>versatileab</machine>
>      <machine>vexpress-a9</machine>
>      <machine>vexpress-a15</machine>
>      <machine>xilinx-zynq-a9</machine>
>      <domain type='qemu'>
>      </domain>
>      <domain type='kvm'>
>        <emulator>/usr/bin/qemu-system-arm</emulator>
>      </domain>
>    </arch>
>    <features>
>      <deviceboot/>
>    </features>
>  </guest>
>
>  <guest>
>    <os_type>hvm</os_type>
>    <arch name='mips'>
>      <wordsize>32</wordsize>
>      <emulator>/usr/bin/qemu-system-mips</emulator>
>      <machine>mips</machine>
>      <machine>none</machine>
>      <machine>magnum</machine>
>      <machine>pica61</machine>
>      <machine>malta</machine>
>      <machine>mipssim</machine>
>      <domain type='qemu'>
>      </domain>
>      <domain type='kvm'>
>        <emulator>/usr/bin/qemu-system-mips</emulator>
>      </domain>
>    </arch>
>    <features>
>      <deviceboot/>
>    </features>
>  </guest>
>
>  <guest>
>    <os_type>hvm</os_type>
>    <arch name='mipsel'>
>      <wordsize>32</wordsize>
>      <emulator>/usr/bin/qemu-system-mipsel</emulator>
>      <machine>mips</machine>
>      <machine>none</machine>
>      <machine>magnum</machine>
>      <machine>pica61</machine>
>      <machine>malta</machine>
>      <machine>mipssim</machine>
>      <domain type='qemu'>
>      </domain>
>      <domain type='kvm'>
>        <emulator>/usr/bin/qemu-system-mipsel</emulator>
>      </domain>
>    </arch>
>    <features>
>      <deviceboot/>
>    </features>
>  </guest>
>
>  <guest>
>    <os_type>hvm</os_type>
>    <arch name='sparc'>
>      <wordsize>32</wordsize>
>      <emulator>/usr/bin/qemu-system-sparc</emulator>
>      <machine>SS-2</machine>
>      <machine>none</machine>
>      <machine>leon3_generic</machine>
>      <machine>SS-5</machine>
>      <machine>SS-10</machine>
>      <machine>SS-600MP</machine>
>      <machine>SS-20</machine>
>      <machine>Voyager</machine>
>      <machine>LX</machine>
>      <machine>SS-4</machine>
>      <machine>SPARCClassic</machine>
>      <machine>SPARCbook</machine>
>      <machine>SS-1000</machine>
>      <machine>SS-2000</machine>
>      <domain type='qemu'>
>      </domain>
>      <domain type='kvm'>
>        <emulator>/usr/bin/qemu-system-sparc</emulator>
>      </domain>
>    </arch>
>  </guest>
>
>  <guest>
>    <os_type>hvm</os_type>
>    <arch name='ppc'>
>      <wordsize>32</wordsize>
>      <emulator>/usr/bin/qemu-system-ppc</emulator>
>      <machine>mpc8544ds</machine>
>      <machine>none</machine>
>      <machine>ref405ep</machine>
>      <machine>taihu</machine>
>      <machine>bamboo</machine>
>      <machine>mac99</machine>
>      <machine>g3beige</machine>
>      <machine>prep</machine>
>      <machine>virtex-ml507</machine>
>      <machine>ppce500</machine>
>      <domain type='qemu'>
>      </domain>
>      <domain type='kvm'>
>        <emulator>/usr/bin/qemu-system-ppc</emulator>
>      </domain>
>    </arch>
>    <features>
>      <deviceboot/>
>    </features>
>  </guest>
>
>  <guest>
>    <os_type>hvm</os_type>
>    <arch name='ppc64'>
>      <wordsize>64</wordsize>
>      <emulator>/usr/bin/qemu-system-ppc64</emulator>
>      <machine>mpc8544ds</machine>
>      <machine>none</machine>
>      <machine>ref405ep</machine>
>      <machine>taihu</machine>
>      <machine>bamboo</machine>
>      <machine>mac99</machine>
>      <machine>g3beige</machine>
>      <machine>prep</machine>
>      <machine>pseries</machine>
>      <machine>virtex-ml507</machine>
>      <machine>ppce500</machine>
>      <domain type='qemu'>
>      </domain>
>      <domain type='kvm'>
>        <emulator>/usr/bin/qemu-system-ppc64</emulator>
>      </domain>
>    </arch>
>    <features>
>      <cpuselection/>
>      <deviceboot/>
>    </features>
>  </guest>
>
></capabilities>
>
>2012-10-06 16:41:37.521+0000: 23348: debug : virNetMessageEncodePayload:358 : Encode length as 8776
>2012-10-06 16:41:37.521+0000: 23348: debug : virNetServerClientSendMessageLocked:1116 : msg=0x1a32550 proc=7 len=8776 offset=0
>2012-10-06 16:41:37.521+0000: 23348: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1a31ca0 len=8776 prog=536903814 vers=1 proc=7 type=1 status=0 serial=3
>2012-10-06 16:41:37.521+0000: 23348: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a325c0 tx=0x1a32550
>2012-10-06 16:41:37.521+0000: 23348: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2012-10-06 16:41:37.521+0000: 23348: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3
>2012-10-06 16:41:37.521+0000: 23348: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-10-06 16:41:37.522+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a23da0
>2012-10-06 16:41:37.522+0000: 23348: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=5 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2
>2012-10-06 16:41:37.522+0000: 23344: debug : virNetMessageFree:73 : msg=0x1a32550 nfds=0 cb=(nil)
>2012-10-06 16:41:37.522+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a325c0 tx=(nil)
>2012-10-06 16:41:37.522+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:37.522+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1
>2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2012-10-06 16:41:38.237+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1
>2012-10-06 16:41:38.239+0000: 23344: debug : virNetMessageDecodeLength:152 : Got length, now need 1832 total (1828 more)
>2012-10-06 16:41:38.239+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a325c0 tx=(nil)
>2012-10-06 16:41:38.239+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976
>2012-10-06 16:41:38.239+0000: 23344: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x1a31ca0 len=1832 prog=536903814 vers=1 proc=10 type=0 status=0 serial=4
>2012-10-06 16:41:38.239+0000: 23344: debug : virKeepAliveCheckMessage:384 : ka=0x1a324c0, client=0x1a31ca0, msg=0x1a325c0
>2012-10-06 16:41:38.239+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31ca0
>2012-10-06 16:41:38.239+0000: 23344: debug : virNetServerDispatchNewMessage:218 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a325c0
>2012-10-06 16:41:38.239+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a23da0
>2012-10-06 16:41:38.239+0000: 23344: debug : virNetMessageNew:45 : msg=0x1a32450 tracked=1
>2012-10-06 16:41:38.239+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a32450 tx=(nil)
>2012-10-06 16:41:38.239+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:38.239+0000: 23349: debug : virNetServerHandleJob:189 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a325c0 prog=0x1a23da0
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:38.239+0000: 23349: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=10
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:38.239+0000: 23349: debug : remoteDispatchDomainCreateXMLHelper:1151 : server=0x1a0ff10 client=0x1a31ca0 msg=0x1a325c0 rerr=0x7f25e6a4cc70 args=0x7f25cc000950 ret=0x7f25cc0008c0
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:38.239+0000: 23349: debug : virDomainCreateXML:1937 : conn=0x7f25d00025f0, xmlDesc=<?xml version="1.0"?>
><domain type="kvm" xmlns:qemu="http://libvirt.org/schemas/domain/qemu/1.0">
>  <name>guestfs-b95l0l3zykx0219j</name>
>  <memory unit="MiB">500</memory>
>  <currentMemory unit="MiB">500</currentMemory>
>  <cpu model="host-model"/>
>  <vcpu>1</vcpu>
>  <clock offset="utc"/>
>  <os>
>    <type>hvm</type>
>    <kernel>/var/tmp/.guestfs-0/kernel.23504</kernel>
>    <initrd>/var/tmp/.guestfs-0/initrd.23504</initrd>
>    <cmdline>panic=1 console=ttyS0 udevtimeout=600 no_timer_check acpi=off printk.time=1 cgroup_disable=memory root=/dev/sdb selinux=0  TERM=screen </cmdline>
>  </os>
>  <on_reboot>destroy</on_reboot>
>  <devices>
>    <controller type="scsi" index="0" model="virtio-scsi"/>
>    <disk device="disk" type="file">
>      <source file="/export/vmimgs2/f17-base.qcow2"/>
>      <target dev="sda" bus="scsi"/>
>      <driver name="qemu" type="qcow2"/>
>      <address type="drive" controller="0" bus="0" target="0" unit="0"/>
>    </disk>
>    <disk type="file" device="disk">
>      <source file="/var/tmp/.guestfs-0/root.23504"/>
>      <target dev="sdb" bus="scsi"/>
>      <driver name="qemu" type="raw" cache="unsafe"/>
>      <address type="drive" controller="0" bus="0" target="1" unit="0"/>
>      <shareable/>
>    </disk>
>    <serial type="unix">
>      <source mode="connect" path="/tmp/libguestfsBKM1WI/console.sock"/>
>      <target port="0"/>
>    </serial>
>    <channel type="unix">
>      <source mode="connect" path="/tmp/libguestfsBKM1WI/guestfsd.sock"/>
>      <target type="virtio" name="org.libguestfs.channel.0"/>
>    </channel>
>  </devices>
>  <qemu:commandline>
>    <qemu:arg value="-set"/>
>    <qemu:arg value="drive.drive-scsi0-0-0-0.snapshot=on"/>
>    <qemu:arg value="-set"/>
>    <qemu:arg value="drive.drive-scsi0-0-1-0.snapshot=on"/>
>  </qemu:commandline>
></domain>
>, flags=2
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:38.239+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1
>2012-10-06 16:41:38.240+0000: 23349: debug : virFileClose:72 : Closed fd 25
>2012-10-06 16:41:38.240+0000: 23349: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d8107160 for /usr/bin/qemu-kvm
>2012-10-06 16:41:38.240+0000: 23349: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d8107160
>2012-10-06 16:41:38.240+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.0
>2012-10-06 16:41:38.240+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.1
>2012-10-06 16:41:38.240+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.2
>2012-10-06 16:41:38.240+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.3
>2012-10-06 16:41:38.240+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.4
>2012-10-06 16:41:38.240+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.5
>2012-10-06 16:41:38.240+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.6
>2012-10-06 16:41:38.240+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.7
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.0
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.1
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.2
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.3
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.4
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.5
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.6
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.7
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.0
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.1
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.2
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.3
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.4
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.5
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.6
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.7
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressGetNextSlot:1322 : PCI addr 0:0:2.0 already in use
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressGetNextSlot:1327 : Found free PCI addr 0:0:3.0
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:3.0
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:3.1
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:3.2
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:3.3
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:3.4
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:3.5
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:3.6
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:3.7
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressGetNextSlot:1327 : Found free PCI addr 0:0:4.0
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:4.0
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:4.1
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:4.2
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:4.3
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:4.4
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:4.5
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:4.6
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:4.7
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressGetNextSlot:1327 : Found free PCI addr 0:0:5.0
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:5.0
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:5.1
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:5.2
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:5.3
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:5.4
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:5.5
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:5.6
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:5.7
>2012-10-06 16:41:38.241+0000: 23349: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f25cc0018a0 classname=virDomainObj
>2012-10-06 16:41:38.241+0000: 23349: debug : virDomainObjNew:1782 : obj=0x7f25cc0018a0
>2012-10-06 16:41:38.241+0000: 23349: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25cc0018a0
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuDomainObjBeginJobInternal:807 : Starting job: modify (async=none)
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuProcessStart:3346 : Beginning VM startup process
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuProcessStart:3358 : Setting current domain def as transient
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuProcessStart:3384 : Preparing host devices
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuProcessStart:3388 : Preparing chr devices
>2012-10-06 16:41:38.241+0000: 23349: debug : qemuProcessStart:3397 : Generating domain security label (if required)
>2012-10-06 16:41:38.241+0000: 23349: debug : virSecuritySELinuxGenSecurityLabel:483 : label=QEMU
>2012-10-06 16:41:38.241+0000: 23349: debug : virSecuritySELinuxGenSecurityLabel:505 : type=2
>2012-10-06 16:41:38.243+0000: 23349: debug : virSecuritySELinuxMCSFind:202 : Using sensitivity level 's0' cat min 0 max 1023 range 1024
>2012-10-06 16:41:38.243+0000: 23349: debug : virSecuritySELinuxMCSFind:207 : Try cat s0:c340,c535
>2012-10-06 16:41:38.243+0000: 23349: debug : virSecuritySELinuxMCSFind:233 : Found context 's0:c340,c535'
>2012-10-06 16:41:38.243+0000: 23349: debug : virSecuritySELinuxGenNewContext:253 : basecontext=system_u:system_r:svirt_t:s0 mcs=s0:c340,c535 isObjectContext=0
>2012-10-06 16:41:38.243+0000: 23349: debug : virSecuritySELinuxGenNewContext:266 : process=system_u:system_r:virtd_t:s0-s0:c0.c1023
>2012-10-06 16:41:38.243+0000: 23349: debug : virSecuritySELinuxGenNewContext:307 : Generated context 'system_u:system_r:svirt_t:s0:c340,c535'
>2012-10-06 16:41:38.243+0000: 23349: debug : virSecuritySELinuxGenNewContext:253 : basecontext=system_u:object_r:svirt_image_t:s0 mcs=s0:c340,c535 isObjectContext=1
>2012-10-06 16:41:38.243+0000: 23349: debug : virSecuritySELinuxGenNewContext:266 : process=system_u:system_r:virtd_t:s0-s0:c0.c1023
>2012-10-06 16:41:38.243+0000: 23349: debug : virSecuritySELinuxGenNewContext:307 : Generated context 'system_u:object_r:svirt_image_t:s0:c340,c535'
>2012-10-06 16:41:38.243+0000: 23349: debug : virSecuritySELinuxGenSecurityLabel:591 : model=selinux label=system_u:system_r:svirt_t:s0:c340,c535 imagelabel=system_u:object_r:svirt_image_t:s0:c340,c535 baselabel=(null)
>2012-10-06 16:41:38.245+0000: 23349: debug : qemuProcessStart:3406 : Ensuring no historical cgroup is lying around
>2012-10-06 16:41:38.245+0000: 23349: debug : virCgroupNew:617 : New group /libvirt/qemu/guestfs-b95l0l3zykx0219j
>2012-10-06 16:41:38.246+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu,cpuacct in /system/libvirtd.service
>2012-10-06 16:41:38.246+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpu,cpuacct in /system/libvirtd.service
>2012-10-06 16:41:38.246+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 2:cpuset at /sys/fs/cgroup/cpuset in 
>2012-10-06 16:41:38.246+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 3:memory at /sys/fs/cgroup/memory in 
>2012-10-06 16:41:38.246+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 4:devices at /sys/fs/cgroup/devices in 
>2012-10-06 16:41:38.246+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 5:freezer at /sys/fs/cgroup/freezer in 
>2012-10-06 16:41:38.246+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 6:blkio at /sys/fs/cgroup/blkio in 
>2012-10-06 16:41:38.246+0000: 23349: debug : virCgroupMakeGroup:535 : Make group /libvirt/qemu/guestfs-b95l0l3zykx0219j
>2012-10-06 16:41:38.246+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/cpu,cpuacct/system/libvirtd.service/libvirt/qemu/guestfs-b95l0l3zykx0219j/
>2012-10-06 16:41:38.247+0000: 23349: debug : qemuProcessStart:3479 : Creating domain log file
>2012-10-06 16:41:38.247+0000: 23349: debug : qemuProcessStart:3484 : Checking for KVM availability
>2012-10-06 16:41:38.247+0000: 23349: debug : qemuProcessStart:3494 : Determining emulator version
>2012-10-06 16:41:38.247+0000: 23349: debug : qemuCapsCacheLookup:2474 : Returning caps 0x7f25d8107160 for /usr/bin/qemu-kvm
>2012-10-06 16:41:38.247+0000: 23349: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25d8107160
>2012-10-06 16:41:38.247+0000: 23349: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f25cc0042c0 classname=qemuCaps
>2012-10-06 16:41:38.247+0000: 23349: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d8107160
>2012-10-06 16:41:38.247+0000: 23349: debug : qemuProcessStart:3503 : Checking for CDROM and floppy presence
>2012-10-06 16:41:38.247+0000: 23349: debug : qemuProcessStart:3527 : Setting up domain cgroup (if required)
>2012-10-06 16:41:38.247+0000: 23349: debug : virCgroupNew:617 : New group /libvirt/qemu/guestfs-b95l0l3zykx0219j
>2012-10-06 16:41:38.248+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu,cpuacct in /system/libvirtd.service
>2012-10-06 16:41:38.248+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpu,cpuacct in /system/libvirtd.service
>2012-10-06 16:41:38.248+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 2:cpuset at /sys/fs/cgroup/cpuset in 
>2012-10-06 16:41:38.248+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 3:memory at /sys/fs/cgroup/memory in 
>2012-10-06 16:41:38.248+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 4:devices at /sys/fs/cgroup/devices in 
>2012-10-06 16:41:38.248+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 5:freezer at /sys/fs/cgroup/freezer in 
>2012-10-06 16:41:38.248+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 6:blkio at /sys/fs/cgroup/blkio in 
>2012-10-06 16:41:38.248+0000: 23349: debug : virCgroupMakeGroup:535 : Make group /libvirt/qemu/guestfs-b95l0l3zykx0219j
>2012-10-06 16:41:38.248+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/cpu,cpuacct/system/libvirtd.service/libvirt/qemu/guestfs-b95l0l3zykx0219j/
>2012-10-06 16:41:38.250+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/cpu,cpuacct/system/libvirtd.service/libvirt/qemu/guestfs-b95l0l3zykx0219j/
>2012-10-06 16:41:38.250+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/cpuset/libvirt/qemu/guestfs-b95l0l3zykx0219j/
>2012-10-06 16:41:38.251+0000: 23349: debug : virCgroupCpuSetInherit:469 : Setting up inheritance /libvirt/qemu -> /libvirt/qemu/guestfs-b95l0l3zykx0219j
>2012-10-06 16:41:38.251+0000: 23349: debug : virCgroupGetValueStr:361 : Get value /sys/fs/cgroup/cpuset/libvirt/qemu/cpuset.cpus
>2012-10-06 16:41:38.251+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.251+0000: 23349: debug : virCgroupCpuSetInherit:482 : Inherit cpuset.cpus = 0-3
>2012-10-06 16:41:38.251+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/cpuset/libvirt/qemu/guestfs-b95l0l3zykx0219j/cpuset.cpus' to '0-3'
>2012-10-06 16:41:38.251+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.251+0000: 23349: debug : virCgroupGetValueStr:361 : Get value /sys/fs/cgroup/cpuset/libvirt/qemu/cpuset.mems
>2012-10-06 16:41:38.252+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.252+0000: 23349: debug : virCgroupCpuSetInherit:482 : Inherit cpuset.mems = 0
>2012-10-06 16:41:38.252+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/cpuset/libvirt/qemu/guestfs-b95l0l3zykx0219j/cpuset.mems' to '0'
>2012-10-06 16:41:38.252+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.252+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/memory/libvirt/qemu/guestfs-b95l0l3zykx0219j/
>2012-10-06 16:41:38.253+0000: 23349: debug : virCgroupGetValueStr:361 : Get value /sys/fs/cgroup/memory/libvirt/qemu/guestfs-b95l0l3zykx0219j/memory.use_hierarchy
>2012-10-06 16:41:38.253+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.254+0000: 23349: debug : virCgroupSetMemoryUseHierarchy:517 : Setting up /libvirt/qemu/guestfs-b95l0l3zykx0219j/memory.use_hierarchy
>2012-10-06 16:41:38.254+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/memory/libvirt/qemu/guestfs-b95l0l3zykx0219j/memory.use_hierarchy' to '1'
>2012-10-06 16:41:38.254+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.254+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/
>2012-10-06 16:41:38.254+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/freezer/libvirt/qemu/guestfs-b95l0l3zykx0219j/
>2012-10-06 16:41:38.255+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/blkio/libvirt/qemu/guestfs-b95l0l3zykx0219j/
>2012-10-06 16:41:38.256+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/devices.deny' to 'a'
>2012-10-06 16:41:38.256+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.256+0000: 23349: debug : qemuSetupDiskPathAllow:70 : Process path /export/vmimgs2/f17-base.qcow2 for disk
>2012-10-06 16:41:38.257+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.257+0000: 23349: debug : qemuSetupDiskPathAllow:70 : Process path /var/tmp/.guestfs-0/root.23504 for disk
>2012-10-06 16:41:38.258+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.258+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/devices.allow' to 'c 136:* rw'
>2012-10-06 16:41:38.258+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.258+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/devices.allow' to 'c 1:3 rw'
>2012-10-06 16:41:38.258+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.258+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/devices.allow' to 'c 1:7 rw'
>2012-10-06 16:41:38.259+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.259+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/devices.allow' to 'c 1:5 rw'
>2012-10-06 16:41:38.259+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.259+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/devices.allow' to 'c 1:8 rw'
>2012-10-06 16:41:38.259+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.260+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/devices.allow' to 'c 1:9 rw'
>2012-10-06 16:41:38.260+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.260+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/devices.allow' to 'c 5:2 rw'
>2012-10-06 16:41:38.260+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.260+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/devices.allow' to 'c 10:232 rw'
>2012-10-06 16:41:38.260+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.261+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/devices.allow' to 'c 254:0 rw'
>2012-10-06 16:41:38.262+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.262+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/devices.allow' to 'c 10:228 rw'
>2012-10-06 16:41:38.262+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.262+0000: 23349: debug : virCgroupSetValueStr:331 : Set value '/sys/fs/cgroup/memory/libvirt/qemu/guestfs-b95l0l3zykx0219j/memory.limit_in_bytes' to '744488960'
>2012-10-06 16:41:38.262+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.262+0000: 23349: debug : qemuProcessStart:3536 : Preparing monitor state
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuProcessStart:3572 : Assigning domain PCI addresses
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:993 : Remembering PCI addr 0:0:3.0
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:3.1 (multifunction=off for function 0)
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:3.2 (multifunction=off for function 0)
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:3.3 (multifunction=off for function 0)
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:3.4 (multifunction=off for function 0)
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:3.5 (multifunction=off for function 0)
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:3.6 (multifunction=off for function 0)
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:3.7 (multifunction=off for function 0)
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:993 : Remembering PCI addr 0:0:1.2
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:993 : Remembering PCI addr 0:0:4.0
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:4.1 (multifunction=off for function 0)
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:4.2 (multifunction=off for function 0)
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:4.3 (multifunction=off for function 0)
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:4.4 (multifunction=off for function 0)
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:4.5 (multifunction=off for function 0)
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:4.6 (multifunction=off for function 0)
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:4.7 (multifunction=off for function 0)
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:993 : Remembering PCI addr 0:0:5.0
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:5.1 (multifunction=off for function 0)
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:5.2 (multifunction=off for function 0)
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:5.3 (multifunction=off for function 0)
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:5.4 (multifunction=off for function 0)
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:5.5 (multifunction=off for function 0)
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:5.6 (multifunction=off for function 0)
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuCollectPCIAddress:1019 : Remembering PCI addr 0:0:5.7 (multifunction=off for function 0)
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.0
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.1
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.2
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.3
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.4
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.5
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.6
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:0.7
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.0
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.1
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.3
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.4
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.5
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.6
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:1.7
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.0
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.1
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.2
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.3
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.4
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.5
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.6
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuDomainPCIAddressReserveAddr:1152 : Reserving PCI addr 0:0:2.7
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuProcessStart:3577 : Building emulator command line
>2012-10-06 16:41:38.263+0000: 23349: debug : qemuBuildCommandLine:4403 : conn=0x7f25d00025f0 driver=0x7f25d808f590 def=0x7f25cc00abf0 mon=0x7f25cc00baf0 json=1 caps=0x7f25cc0042c0 migrateFrom=(null) migrateFD=-1 snapshot=(nil) vmop=0
>2012-10-06 16:41:38.263+0000: 23349: debug : cpuDataFree:212 : arch=x86_64, data=(nil)
>2012-10-06 16:41:38.267+0000: 23349: warning : qemuDomainObjTaint:1364 : Domain id=8 name='guestfs-b95l0l3zykx0219j' uuid=b1d5e6b8-94d7-67df-b5b8-2a67e0c39d13 is tainted: custom-argv
>2012-10-06 16:41:38.267+0000: 23349: debug : qemuProcessStart:3622 : Clear emulator capabilities: 1
>2012-10-06 16:41:38.267+0000: 23349: debug : virCommandRequireHandshake:2362 : Transfer handshake wait=27 notify=28, keep handshake wait=26 notify=29
>2012-10-06 16:41:38.268+0000: 23349: debug : virCommandRunAsync:2200 : About to run LC_ALL=C PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin /usr/bin/qemu-kvm -name guestfs-b95l0l3zykx0219j -S -M isapc -enable-kvm -m 500 -smp 1,sockets=1,cores=1,threads=1 -uuid b1d5e6b8-94d7-67df-b5b8-2a67e0c39d13 -nographic -no-user-config -nodefaults -chardev socket,id=charmonitor,path=/var/lib/libvirt/qemu/guestfs-b95l0l3zykx0219j.monitor,server,nowait -mon chardev=charmonitor,id=monitor,mode=control -rtc base=utc -no-reboot -no-shutdown -no-acpi -kernel /var/tmp/.guestfs-0/kernel.23504 -initrd /var/tmp/.guestfs-0/initrd.23504 -append 'panic=1 console=ttyS0 udevtimeout=600 no_timer_check acpi=off printk.time=1 cgroup_disable=memory root=/dev/sdb selinux=0  TERM=screen ' -device virtio-scsi-pci,id=scsi0,bus=pci.0,addr=0x3 -device piix3-usb-uhci,id=usb,bus=pci.0,addr=0x1.0x2 -device virtio-serial-pci,id=virtio-serial0,bus=pci.0,addr=0x4 -drive file=/export/vmimgs2/f17-base.qcow2,if=none,id=drive-scsi0-0-0-0,format=qcow2 -device scsi-hd,bus=scsi0.0,channel=0,scsi-id=0,lun=0,drive=drive-scsi0-0-0-0,id=scsi0-0-0-0,bootindex=1 -drive file=/var/tmp/.guestfs-0/root.23504,if=none,id=drive-scsi0-0-1-0,format=raw,cache=unsafe -device scsi-hd,bus=scsi0.0,channel=0,scsi-id=1,lun=0,drive=drive-scsi0-0-1-0,id=scsi0-0-1-0 -chardev socket,id=charserial0,path=/tmp/libguestfsBKM1WI/console.sock -device isa-serial,chardev=charserial0,id=serial0 -chardev socket,id=charchannel0,path=/tmp/libguestfsBKM1WI/guestfsd.sock -device virtserialport,bus=virtio-serial0.0,nr=1,chardev=charchannel0,id=channel0,name=org.libguestfs.channel.0 -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x5 -set drive.drive-scsi0-0-0-0.snapshot=on -set drive.drive-scsi0-0-1-0.snapshot=on
>2012-10-06 16:41:38.279+0000: 23349: debug : virFileClose:72 : Closed fd 30
>2012-10-06 16:41:38.279+0000: 23349: debug : virCommandRunAsync:2218 : Command result 0, with PID 23512
>2012-10-06 16:41:38.279+0000: 23349: debug : virFileClose:72 : Closed fd 27
>2012-10-06 16:41:38.279+0000: 23349: debug : virFileClose:72 : Closed fd 28
>2012-10-06 16:41:38.303+0000: 23349: debug : virCommandRun:2016 : Result status 0, stdout: '(null)' stderr: '(null)'
>2012-10-06 16:41:38.303+0000: 23349: debug : virFileClose:72 : Closed fd 27
>2012-10-06 16:41:38.303+0000: 23349: debug : qemuProcessStart:3669 : Writing early domain status to disk
>2012-10-06 16:41:38.303+0000: 23349: debug : virFileClose:72 : Closed fd 27
>2012-10-06 16:41:38.303+0000: 23349: debug : qemuProcessStart:3674 : Waiting for handshake from child
>2012-10-06 16:41:38.303+0000: 23349: debug : virCommandHandshakeWait:2395 : Wait for handshake on 26
>2012-10-06 16:41:38.362+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.362+0000: 23349: debug : qemuProcessStart:3679 : Setting domain security labels
>2012-10-06 16:41:38.362+0000: 23349: info : virSecuritySELinuxSetFileconHelper:754 : Setting SELinux context on '/export/vmimgs2/f17-base.qcow2' to 'system_u:object_r:svirt_image_t:s0:c340,c535'
>2012-10-06 16:41:38.364+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.364+0000: 23349: info : virSecuritySELinuxSetFileconHelper:754 : Setting SELinux context on '/var/tmp/.guestfs-0/root.23504' to 'system_u:object_r:svirt_image_t:s0'
>2012-10-06 16:41:38.364+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.365+0000: 23349: info : virSecuritySELinuxSetFileconHelper:754 : Setting SELinux context on '/tmp/libguestfsBKM1WI/console.sock' to 'system_u:object_r:svirt_image_t:s0:c340,c535'
>2012-10-06 16:41:38.366+0000: 23349: info : virSecuritySELinuxSetFileconHelper:754 : Setting SELinux context on '/tmp/libguestfsBKM1WI/guestfsd.sock' to 'system_u:object_r:svirt_image_t:s0:c340,c535'
>2012-10-06 16:41:38.366+0000: 23349: info : virSecuritySELinuxSetFileconHelper:754 : Setting SELinux context on '/var/tmp/.guestfs-0/kernel.23504' to 'system_u:object_r:virt_content_t:s0'
>2012-10-06 16:41:38.367+0000: 23349: info : virSecuritySELinuxSetFileconHelper:754 : Setting SELinux context on '/var/tmp/.guestfs-0/initrd.23504' to 'system_u:object_r:virt_content_t:s0'
>2012-10-06 16:41:38.367+0000: 23349: error : virGetUserID:2535 : Failed to find user record for name '107': Success
>2012-10-06 16:41:38.367+0000: 23349: error : virGetGroupID:2584 : Failed to find group record for name '107': Success
>2012-10-06 16:41:38.368+0000: 23349: error : virGetUserID:2535 : Failed to find user record for name '107': Success
>2012-10-06 16:41:38.368+0000: 23349: error : virGetGroupID:2584 : Failed to find group record for name '107': Success
>2012-10-06 16:41:38.368+0000: 23349: info : virSecurityDACSetOwnership:300 : Setting DAC user and group on '/export/vmimgs2/f17-base.qcow2' to '107:107'
>2012-10-06 16:41:38.368+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.368+0000: 23349: error : virGetUserID:2535 : Failed to find user record for name '107': Success
>2012-10-06 16:41:38.368+0000: 23349: error : virGetGroupID:2584 : Failed to find group record for name '107': Success
>2012-10-06 16:41:38.368+0000: 23349: error : virGetUserID:2535 : Failed to find user record for name '107': Success
>2012-10-06 16:41:38.369+0000: 23349: error : virGetGroupID:2584 : Failed to find group record for name '107': Success
>2012-10-06 16:41:38.369+0000: 23349: info : virSecurityDACSetOwnership:300 : Setting DAC user and group on '/var/tmp/.guestfs-0/root.23504' to '107:107'
>2012-10-06 16:41:38.369+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.369+0000: 23349: error : virGetUserID:2535 : Failed to find user record for name '107': Success
>2012-10-06 16:41:38.370+0000: 23349: error : virGetGroupID:2584 : Failed to find group record for name '107': Success
>2012-10-06 16:41:38.370+0000: 23349: error : virGetUserID:2535 : Failed to find user record for name '107': Success
>2012-10-06 16:41:38.370+0000: 23349: error : virGetGroupID:2584 : Failed to find group record for name '107': Success
>2012-10-06 16:41:38.370+0000: 23349: error : virGetUserID:2535 : Failed to find user record for name '107': Success
>2012-10-06 16:41:38.370+0000: 23349: error : virGetGroupID:2584 : Failed to find group record for name '107': Success
>2012-10-06 16:41:38.370+0000: 23349: info : virSecurityDACSetOwnership:300 : Setting DAC user and group on '/var/tmp/.guestfs-0/kernel.23504' to '107:107'
>2012-10-06 16:41:38.370+0000: 23349: info : virSecurityDACSetOwnership:300 : Setting DAC user and group on '/var/tmp/.guestfs-0/initrd.23504' to '107:107'
>2012-10-06 16:41:38.370+0000: 23349: debug : qemuProcessStart:3708 : Labelling done, completing handshake to child
>2012-10-06 16:41:38.370+0000: 23349: debug : virCommandHandshakeNotify:2462 : Notify handshake on 29
>2012-10-06 16:41:38.371+0000: 23349: debug : virFileClose:72 : Closed fd 29
>2012-10-06 16:41:38.371+0000: 23349: debug : qemuProcessStart:3712 : Handshake complete, child running
>2012-10-06 16:41:38.371+0000: 23349: debug : qemuProcessStart:3723 : Waiting for monitor to show up
>2012-10-06 16:41:38.371+0000: 23349: debug : qemuProcessWaitForMonitor:1603 : Connect monitor to 0x7f25cc0018a0 'guestfs-b95l0l3zykx0219j'
>2012-10-06 16:41:38.371+0000: 23349: debug : virSecuritySELinuxSetSecurityDaemonSocketLabel:1639 : Setting VM guestfs-b95l0l3zykx0219j socket context system_u:system_r:virtd_t:s0:c340,c535
>2012-10-06 16:41:38.372+0000: 23349: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25cc0018a0
>2012-10-06 16:41:38.576+0000: 23349: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f25cc003fb0 classname=qemuMonitor
>2012-10-06 16:41:38.576+0000: 23349: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-10-06 16:41:38.576+0000: 23349: debug : virEventPollAddHandle:136 : EVENT_POLL_ADD_HANDLE: watch=13 fd=27 events=13 cb=0x7f25df548030 opaque=0x7f25cc003fb0 ff=0x40a5a0
>2012-10-06 16:41:38.576+0000: 23349: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25cc003fb0
>2012-10-06 16:41:38.576+0000: 23349: debug : qemuMonitorOpenInternal:765 : QEMU_MONITOR_NEW: mon=0x7f25cc003fb0 refs=2 fd=27
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:38.576+0000: 23349: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25cc003fb0
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:38.576+0000: 23349: debug : qemuMonitorSetCapabilities:1142 : mon=0x7f25cc003fb0
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:38.576+0000: 23349: debug : virJSONValueToString:1068 : object=0x7f25cc002e20
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2012-10-06 16:41:38.576+0000: 23349: debug : virJSONValueToStringOne:999 : object=0x7f25cc002e20 type=0 gen=0x7f25cc0056a0
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2012-10-06 16:41:38.576+0000: 23349: debug : virJSONValueToStringOne:999 : object=0x7f25cc003c90 type=2 gen=0x7f25cc0056a0
>2012-10-06 16:41:38.576+0000: 23349: debug : virJSONValueToStringOne:999 : object=0x7f25cc004330 type=2 gen=0x7f25cc0056a0
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:38.576+0000: 23349: debug : virJSONValueToString:1102 : result={"execute":"qmp_capabilities","id":"libvirt-1"}
>2012-10-06 16:41:38.576+0000: 23349: debug : qemuMonitorJSONCommandWithFd:257 : Send command '{"execute":"qmp_capabilities","id":"libvirt-1"}' for write with FD -1
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:38.576+0000: 23349: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=13
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:38.576+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=27 e=25 d=0
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=-1
>2012-10-06 16:41:38.577+0000: 23349: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-10-06 16:41:38.577+0000: 23349: debug : qemuMonitorSend:904 : QEMU_MONITOR_SEND_MSG: mon=0x7f25cc003fb0 msg={"execute":"qmp_capabilities","id":"libvirt-1"}
> fd=-1
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollDispatchHandles:469 : i=12 w=13
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=27 e=25 d=0
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:38.577+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=-1
>2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:469 : i=12 w=13
>2012-10-06 16:41:38.672+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=13 events=13
>2012-10-06 16:41:38.672+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25cc003fb0
>2012-10-06 16:41:38.672+0000: 23344: error : qemuMonitorIORead:514 : Unable to read from monitor: Connection reset by peer
>2012-10-06 16:41:38.672+0000: 23344: debug : qemuMonitorIO:647 : Error on monitor Unable to read from monitor: Connection reset by peer
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=12
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976
>2012-10-06 16:41:38.673+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25cc003fb0
>2012-10-06 16:41:38.673+0000: 23344: debug : qemuMonitorIO:681 : Triggering error callback
>2012-10-06 16:41:38.673+0000: 23349: debug : qemuMonitorSend:916 : Send command resulted in error Unable to read from monitor: Connection reset by peer
>2012-10-06 16:41:38.673+0000: 23344: debug : qemuProcessHandleMonitorError:342 : Received error on 0x7f25cc0018a0 'guestfs-b95l0l3zykx0219j'
>2012-10-06 16:41:38.673+0000: 23349: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=12
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:38.673+0000: 23349: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 -284886976
>2012-10-06 16:41:38.673+0000: 23349: debug : qemuMonitorJSONCommandWithFd:262 : Receive command reply ret=-1 rxObject=(nil)
>2012-10-06 16:41:38.673+0000: 23349: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25cc003fb0
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0
>2012-10-06 16:41:38.673+0000: 23349: debug : virFileClose:72 : Closed fd 26
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=27 e=24 d=0
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=-1
>2012-10-06 16:41:38.673+0000: 23349: debug : virFileClose:72 : Closed fd 25
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-10-06 16:41:38.673+0000: 23349: debug : qemuProcessStop:3918 : Shutting down VM 'guestfs-b95l0l3zykx0219j' pid=23513 flags=0
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:469 : i=12 w=13
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=13 events=8
>2012-10-06 16:41:38.673+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25cc003fb0
>2012-10-06 16:41:38.673+0000: 23344: debug : qemuMonitorIO:647 : Error on monitor Unable to read from monitor: Connection reset by peer
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=12
>2012-10-06 16:41:38.673+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976
>2012-10-06 16:41:38.673+0000: 23349: debug : virFileClose:72 : Closed fd 25
>2012-10-06 16:41:38.673+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25cc003fb0
>2012-10-06 16:41:38.674+0000: 23344: debug : qemuMonitorIO:670 : Triggering EOF callback
>2012-10-06 16:41:38.674+0000: 23344: debug : qemuProcessHandleMonitorEOF:283 : Received EOF on 0x7f25cc0018a0 'guestfs-b95l0l3zykx0219j'
>2012-10-06 16:41:38.673+0000: 23349: debug : qemuMonitorClose:838 : QEMU_MONITOR_CLOSE: mon=0x7f25cc003fb0 refs=2
>2012-10-06 16:41:38.674+0000: 23349: debug : virEventPollRemoveHandle:175 : EVENT_POLL_REMOVE_HANDLE: watch=13
>2012-10-06 16:41:38.674+0000: 23349: debug : virEventPollRemoveHandle:188 : mark delete 12 27
>2012-10-06 16:41:38.674+0000: 23349: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-10-06 16:41:38.674+0000: 23349: debug : virFileClose:72 : Closed fd 27
>2012-10-06 16:41:38.674+0000: 23349: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25cc003fb0
>2012-10-06 16:41:38.674+0000: 23349: debug : qemuProcessKill:3867 : vm=guestfs-b95l0l3zykx0219j pid=23513 flags=5
>2012-10-06 16:41:38.674+0000: 23349: debug : virProcessKillPainfully:253 : vpid=23513 force=1
>2012-10-06 16:41:38.874+0000: 23349: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f25cc0018a0
>2012-10-06 16:41:38.874+0000: 23344: debug : qemuProcessHandleMonitorEOF:296 : Domain 0x7f25cc0018a0 is not active, ignoring EOF
>2012-10-06 16:41:38.874+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:38.874+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2012-10-06 16:41:38.874+0000: 23344: debug : virEventPollCleanupHandles:562 : EVENT_POLL_PURGE_HANDLE: watch=13
>2012-10-06 16:41:38.874+0000: 23349: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25cc0018a0
>2012-10-06 16:41:38.874+0000: 23349: debug : qemuDomainCleanupRun:2006 : driver=0x7f25d808f590, vm=guestfs-b95l0l3zykx0219j
>2012-10-06 16:41:38.874+0000: 23349: debug : qemuProcessAutoDestroyRemove:4389 : vm=guestfs-b95l0l3zykx0219j
>2012-10-06 16:41:38.874+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25cc003fb0
>2012-10-06 16:41:38.875+0000: 23344: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f25cc003fb0
>2012-10-06 16:41:38.874+0000: 23349: debug : qemuDriverCloseCallbackUnset:661 : vm=guestfs-b95l0l3zykx0219j, uuid=b1d5e6b8-94d7-67df-b5b8-2a67e0c39d13, cb=0x7f25df53d5b0
>2012-10-06 16:41:38.875+0000: 23344: debug : qemuMonitorDispose:247 : mon=0x7f25cc003fb0
>2012-10-06 16:41:38.875+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25cc0018a0
>2012-10-06 16:41:38.875+0000: 23349: debug : virSecuritySELinuxRestoreSecurityAllLabel:1408 : Restoring security label on guestfs-b95l0l3zykx0219j
>2012-10-06 16:41:38.875+0000: 23349: info : virSecuritySELinuxRestoreSecurityFileLabel:884 : Restoring SELinux context on '/export/vmimgs2/f17-base.qcow2'
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:38.875+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1
>2012-10-06 16:41:38.894+0000: 23349: info : virSecuritySELinuxSetFileconHelper:754 : Setting SELinux context on '/export/vmimgs2/f17-base.qcow2' to 'system_u:object_r:usr_t:s0'
>2012-10-06 16:41:38.895+0000: 23349: info : virSecuritySELinuxRestoreSecurityFileLabel:884 : Restoring SELinux context on '/tmp/libguestfsBKM1WI/console.sock'
>2012-10-06 16:41:38.911+0000: 23349: warning : virSecuritySELinuxRestoreSecurityFileLabel:899 : cannot lookup default selinux label for /tmp/libguestfsBKM1WI/console.sock
>2012-10-06 16:41:38.911+0000: 23349: info : virSecuritySELinuxRestoreSecurityFileLabel:884 : Restoring SELinux context on '/tmp/libguestfsBKM1WI/guestfsd.sock'
>2012-10-06 16:41:38.926+0000: 23349: warning : virSecuritySELinuxRestoreSecurityFileLabel:899 : cannot lookup default selinux label for /tmp/libguestfsBKM1WI/guestfsd.sock
>2012-10-06 16:41:38.926+0000: 23349: info : virSecuritySELinuxRestoreSecurityFileLabel:884 : Restoring SELinux context on '/var/tmp/.guestfs-0/kernel.23504'
>2012-10-06 16:41:38.943+0000: 23349: warning : virSecuritySELinuxRestoreSecurityFileLabel:899 : cannot lookup default selinux label for /var/tmp/.guestfs-0/kernel.23504
>2012-10-06 16:41:38.943+0000: 23349: info : virSecuritySELinuxRestoreSecurityFileLabel:884 : Restoring SELinux context on '/var/tmp/.guestfs-0/initrd.23504'
>2012-10-06 16:41:38.959+0000: 23349: warning : virSecuritySELinuxRestoreSecurityFileLabel:899 : cannot lookup default selinux label for /var/tmp/.guestfs-0/initrd.23504
>2012-10-06 16:41:38.959+0000: 23349: debug : virSecurityDACRestoreSecurityAllLabel:754 : Restoring security label on guestfs-b95l0l3zykx0219j migrated=0
>2012-10-06 16:41:38.959+0000: 23349: info : virSecurityDACRestoreSecurityFileLabel:344 : Restoring DAC user and group on '/export/vmimgs2/f17-base.qcow2'
>2012-10-06 16:41:38.959+0000: 23349: info : virSecurityDACSetOwnership:300 : Setting DAC user and group on '/export/vmimgs2/f17-base.qcow2' to '0:0'
>2012-10-06 16:41:38.959+0000: 23349: info : virSecurityDACRestoreSecurityFileLabel:344 : Restoring DAC user and group on '/var/tmp/.guestfs-0/kernel.23504'
>2012-10-06 16:41:38.959+0000: 23349: info : virSecurityDACSetOwnership:300 : Setting DAC user and group on '/var/tmp/.guestfs-0/kernel.23504' to '0:0'
>2012-10-06 16:41:38.959+0000: 23349: info : virSecurityDACRestoreSecurityFileLabel:344 : Restoring DAC user and group on '/var/tmp/.guestfs-0/initrd.23504'
>2012-10-06 16:41:38.959+0000: 23349: info : virSecurityDACSetOwnership:300 : Setting DAC user and group on '/var/tmp/.guestfs-0/initrd.23504' to '0:0'
>2012-10-06 16:41:38.959+0000: 23349: debug : virCgroupNew:617 : New group /libvirt/qemu/guestfs-b95l0l3zykx0219j
>2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 0:cpu at /sys/fs/cgroup/cpu,cpuacct in /system/libvirtd.service
>2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 1:cpuacct at /sys/fs/cgroup/cpu,cpuacct in /system/libvirtd.service
>2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 2:cpuset at /sys/fs/cgroup/cpuset in 
>2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 3:memory at /sys/fs/cgroup/memory in 
>2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 4:devices at /sys/fs/cgroup/devices in 
>2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 5:freezer at /sys/fs/cgroup/freezer in 
>2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupDetect:273 : Detected mount/mapping 6:blkio at /sys/fs/cgroup/blkio in 
>2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupMakeGroup:535 : Make group /libvirt/qemu/guestfs-b95l0l3zykx0219j
>2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/cpu,cpuacct/system/libvirtd.service/libvirt/qemu/guestfs-b95l0l3zykx0219j/
>2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/cpu,cpuacct/system/libvirtd.service/libvirt/qemu/guestfs-b95l0l3zykx0219j/
>2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/cpuset/libvirt/qemu/guestfs-b95l0l3zykx0219j/
>2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/memory/libvirt/qemu/guestfs-b95l0l3zykx0219j/
>2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/
>2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/freezer/libvirt/qemu/guestfs-b95l0l3zykx0219j/
>2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupMakeGroup:560 : Make controller /sys/fs/cgroup/blkio/libvirt/qemu/guestfs-b95l0l3zykx0219j/
>2012-10-06 16:41:38.961+0000: 23349: debug : virCgroupRemove:772 : Removing cgroup /sys/fs/cgroup/cpu,cpuacct/system/libvirtd.service/libvirt/qemu/guestfs-b95l0l3zykx0219j/ and all child cgroups
>2012-10-06 16:41:38.962+0000: 23349: debug : virCgroupRemoveRecursively:727 : Removing cgroup /sys/fs/cgroup/cpu,cpuacct/system/libvirtd.service/libvirt/qemu/guestfs-b95l0l3zykx0219j/
>2012-10-06 16:41:38.971+0000: 23349: debug : virCgroupRemove:772 : Removing cgroup /sys/fs/cgroup/cpu,cpuacct/system/libvirtd.service/libvirt/qemu/guestfs-b95l0l3zykx0219j/ and all child cgroups
>2012-10-06 16:41:38.971+0000: 23349: debug : virCgroupRemove:772 : Removing cgroup /sys/fs/cgroup/cpuset/libvirt/qemu/guestfs-b95l0l3zykx0219j/ and all child cgroups
>2012-10-06 16:41:38.972+0000: 23349: debug : virCgroupRemoveRecursively:727 : Removing cgroup /sys/fs/cgroup/cpuset/libvirt/qemu/guestfs-b95l0l3zykx0219j/
>2012-10-06 16:41:38.979+0000: 23349: debug : virCgroupRemove:772 : Removing cgroup /sys/fs/cgroup/memory/libvirt/qemu/guestfs-b95l0l3zykx0219j/ and all child cgroups
>2012-10-06 16:41:38.980+0000: 23349: debug : virCgroupRemoveRecursively:727 : Removing cgroup /sys/fs/cgroup/memory/libvirt/qemu/guestfs-b95l0l3zykx0219j/
>2012-10-06 16:41:38.995+0000: 23349: debug : virCgroupRemove:772 : Removing cgroup /sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/ and all child cgroups
>2012-10-06 16:41:38.995+0000: 23349: debug : virCgroupRemoveRecursively:727 : Removing cgroup /sys/fs/cgroup/devices/libvirt/qemu/guestfs-b95l0l3zykx0219j/
>2012-10-06 16:41:39.003+0000: 23349: debug : virCgroupRemove:772 : Removing cgroup /sys/fs/cgroup/freezer/libvirt/qemu/guestfs-b95l0l3zykx0219j/ and all child cgroups
>2012-10-06 16:41:39.003+0000: 23349: debug : virCgroupRemoveRecursively:727 : Removing cgroup /sys/fs/cgroup/freezer/libvirt/qemu/guestfs-b95l0l3zykx0219j/
>2012-10-06 16:41:39.011+0000: 23349: debug : virCgroupRemove:772 : Removing cgroup /sys/fs/cgroup/blkio/libvirt/qemu/guestfs-b95l0l3zykx0219j/ and all child cgroups
>2012-10-06 16:41:39.011+0000: 23349: debug : virCgroupRemoveRecursively:727 : Removing cgroup /sys/fs/cgroup/blkio/libvirt/qemu/guestfs-b95l0l3zykx0219j/
>2012-10-06 16:41:39.020+0000: 23349: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25cc0042c0
>2012-10-06 16:41:39.020+0000: 23349: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f25cc0042c0
>2012-10-06 16:41:39.020+0000: 23349: debug : qemuDomainObjEndJob:936 : Stopping job: modify (async=none)
>2012-10-06 16:41:39.020+0000: 23349: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25cc0018a0
>2012-10-06 16:41:39.020+0000: 23349: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25cc0018a0
>2012-10-06 16:41:39.020+0000: 23349: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f25cc0018a0
>2012-10-06 16:41:39.020+0000: 23349: debug : virDomainObjDispose:1736 : obj=0x7f25cc0018a0
>2012-10-06 16:41:39.020+0000: 23349: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d8107160
>2012-10-06 16:41:39.020+0000: 23349: debug : virNetServerProgramSendError:150 : prog=536903814 ver=1 proc=10 type=1 serial=4 msg=0x1a325c0 rerr=0x7f25e6a4cc70
>2012-10-06 16:41:39.020+0000: 23349: debug : virNetMessageEncodePayload:358 : Encode length as 200
>2012-10-06 16:41:39.020+0000: 23349: debug : virNetServerClientSendMessageLocked:1116 : msg=0x1a325c0 proc=10 len=200 offset=0
>2012-10-06 16:41:39.020+0000: 23349: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1a31ca0 len=200 prog=536903814 vers=1 proc=10 type=1 status=1 serial=4
>2012-10-06 16:41:39.021+0000: 23349: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a32450 tx=0x1a325c0
>2012-10-06 16:41:39.021+0000: 23349: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2012-10-06 16:41:39.021+0000: 23349: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3
>2012-10-06 16:41:39.021+0000: 23349: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-10-06 16:41:39.021+0000: 23349: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a23da0
>2012-10-06 16:41:39.021+0000: 23349: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=5 d=0
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2
>2012-10-06 16:41:39.021+0000: 23344: debug : virNetMessageFree:73 : msg=0x1a325c0 nfds=0 cb=(nil)
>2012-10-06 16:41:39.021+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a32450 tx=(nil)
>2012-10-06 16:41:39.021+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:39.021+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0
>2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1
>2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1
>2012-10-06 16:41:39.022+0000: 23344: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2012-10-06 16:41:39.022+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a32450 tx=(nil)
>2012-10-06 16:41:39.022+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2012-10-06 16:41:39.022+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976
>2012-10-06 16:41:39.022+0000: 23344: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x1a31ca0 len=28 prog=536903814 vers=1 proc=2 type=0 status=0 serial=5
>2012-10-06 16:41:39.022+0000: 23344: debug : virKeepAliveCheckMessage:384 : ka=0x1a324c0, client=0x1a31ca0, msg=0x1a32450
>2012-10-06 16:41:39.022+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31ca0
>2012-10-06 16:41:39.022+0000: 23344: debug : virNetServerDispatchNewMessage:218 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a32450
>2012-10-06 16:41:39.022+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a23da0
>2012-10-06 16:41:39.023+0000: 23344: debug : virNetMessageNew:45 : msg=0x1a31930 tracked=1
>2012-10-06 16:41:39.023+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a31930 tx=(nil)
>2012-10-06 16:41:39.023+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:39.023+0000: 23345: debug : virNetServerHandleJob:189 : server=0x1a0ff10 client=0x1a31ca0 message=0x1a32450 prog=0x1a23da0
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:39.023+0000: 23345: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=5 proc=2
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=1 d=0
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:39.023+0000: 23345: debug : remoteDispatchCloseHelper:124 : server=0x1a0ff10 client=0x1a31ca0 msg=0x1a32450 rerr=0x7f25e8a50c70 args=0x7f25c0000950 ret=0x7f25c00008c0
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:39.023+0000: 23345: debug : virNetMessageEncodePayload:358 : Encode length as 28
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1
>2012-10-06 16:41:39.023+0000: 23345: debug : virNetServerClientSendMessageLocked:1116 : msg=0x1a32450 proc=2 len=28 offset=0
>2012-10-06 16:41:39.023+0000: 23345: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1a31ca0 len=28 prog=536903814 vers=1 proc=2 type=1 status=0 serial=5
>2012-10-06 16:41:39.023+0000: 23345: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a31930 tx=0x1a32450
>2012-10-06 16:41:39.023+0000: 23345: debug : virNetServerClientCalculateHandleMode:183 : mode=2
>2012-10-06 16:41:39.023+0000: 23345: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=2
>2012-10-06 16:41:39.023+0000: 23345: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-10-06 16:41:39.023+0000: 23345: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a23da0
>2012-10-06 16:41:39.023+0000: 23345: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:39.023+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=21 e=4 d=0
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=-1
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2
>2012-10-06 16:41:39.024+0000: 23344: debug : virNetMessageFree:73 : msg=0x1a32450 nfds=0 cb=(nil)
>2012-10-06 16:41:39.024+0000: 23344: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1a31930 tx=(nil)
>2012-10-06 16:41:39.024+0000: 23344: debug : virNetServerClientCalculateHandleMode:183 : mode=0
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=0
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:39.024+0000: 23344: debug : virNetServerClientClose:620 : client=0x1a31ca0
>2012-10-06 16:41:39.024+0000: 23344: debug : virKeepAliveStop:320 : RPC_KEEPALIVE_STOP: ka=0x1a324c0 client=0x1a31ca0
>2012-10-06 16:41:39.024+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31ca0
>2012-10-06 16:41:39.024+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a324c0
>2012-10-06 16:41:39.024+0000: 23344: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x1a324c0
>2012-10-06 16:41:39.024+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0
>2012-10-06 16:41:39.024+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0
>2012-10-06 16:41:39.024+0000: 23344: debug : virObjectRef:168 : OBJECT_REF: obj=0x1a31ca0
>2012-10-06 16:41:39.024+0000: 23344: debug : daemonRemoveAllClientStreams:480 : stream=(nil)
>2012-10-06 16:41:39.024+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollRemoveHandle:175 : EVENT_POLL_REMOVE_HANDLE: watch=12
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollRemoveHandle:188 : mark delete 11 21
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 -284886976
>2012-10-06 16:41:39.024+0000: 23344: debug : virNetMessageFree:73 : msg=0x1a31930 nfds=0 cb=(nil)
>2012-10-06 16:41:39.024+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31a50
>2012-10-06 16:41:39.024+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-10-06 16:41:39.024+0000: 23344: debug : virEventPollCleanupHandles:562 : EVENT_POLL_PURGE_HANDLE: watch=12
>2012-10-06 16:41:39.024+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31ca0
>2012-10-06 16:41:39.024+0000: 23344: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x1a31ca0
>2012-10-06 16:41:39.024+0000: 23344: debug : virConnectClose:1449 : conn=0x7f25d00025f0
>2012-10-06 16:41:39.024+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f25d00025f0
>2012-10-06 16:41:39.024+0000: 23344: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f25d00025f0
>2012-10-06 16:41:39.026+0000: 23344: debug : qemuDriverCloseCallbackRunAll:744 : conn=0x7f25d00025f0
>2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollRemoveTimeout:288 : EVENT_POLL_REMOVE_TIMEOUT: timer=1
>2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -284886976
>2012-10-06 16:41:39.026+0000: 23344: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1a31a50
>2012-10-06 16:41:39.026+0000: 23344: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x1a31a50
>2012-10-06 16:41:39.026+0000: 23344: debug : virNetSocketDispose:889 : sock=0x1a31a50 fd=21
>2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollRemoveHandle:175 : EVENT_POLL_REMOVE_HANDLE: watch=12
>2012-10-06 16:41:39.026+0000: 23344: debug : virFileClose:72 : Closed fd 21
>2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
>2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
>2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=11 e=1 d=0
>2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=12 e=1 d=0
>2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=15 e=1 d=0
>2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=13 e=1 d=0
>2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=0 d=0
>2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=25 d=0
>2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=20 e=1 d=0
>2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-10-06 16:41:39.026+0000: 23344: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=-1

Actions: View

Attachments on bug 863696: 622766