Attachment 918142 Details for Bug 924853 – libvirtd log

libvirtd log

libvirtd.log (text/plain), 152.19 KB, created by Shanzhi Yu on 2014-07-15 11:05:52 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Shanzhi Yu

Created: 2014-07-15 11:05:52 UTC

Size: 152.19 KB

patch

obsolete

>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 0
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=3 events=1
>2014-07-15 11:05:26.994+0000: 19609: debug : virNetSocketNew:147 : localAddr=0x7fff27fbeb30 remoteAddr=0x7fff27fbeaa0 fd=18 errfd=-1 pid=0
>2014-07-15 11:05:26.994+0000: 19609: debug : virObjectNew:110 : OBJECT_NEW: obj=0x1178f60 classname=virNetSocket
>2014-07-15 11:05:26.994+0000: 19609: debug : virNetSocketNew:204 : RPC_SOCKET_NEW: sock=0x1178f60 fd=18 errfd=-1 pid=0 localAddr=127.0.0.1;0, remoteAddr=127.0.0.1;0
>2014-07-15 11:05:26.994+0000: 19609: debug : virNetServerClientNew:403 : sock=0x1178f60 auth=2 tls=(nil)
>2014-07-15 11:05:26.994+0000: 19609: debug : virObjectNew:110 : OBJECT_NEW: obj=0x11791a0 classname=virNetServerClient
>2014-07-15 11:05:26.994+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x1178f60
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollAddTimeout:220 : Used 0 timeout slots, adding at least 10 more
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollAddTimeout:243 : EVENT_POLL_ADD_TIMEOUT: timer=1 frequency=-1 cb=0x3517746f50 opaque=0x11791a0 ff=(nil)
>2014-07-15 11:05:26.994+0000: 19609: debug : virNetMessageNew:45 : msg=0x1179280 tracked=1
>2014-07-15 11:05:26.994+0000: 19609: debug : virNetServerClientNewInternal:382 : RPC_SERVER_CLIENT_NEW: client=0x11791a0 sock=0x1178f60
>2014-07-15 11:05:26.994+0000: 19609: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1179280 tx=(nil)
>2014-07-15 11:05:26.994+0000: 19609: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-15 11:05:26.994+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x11791a0
>2014-07-15 11:05:26.994+0000: 19609: debug : virNetServerClientRegisterEvent:199 : Registering client event callback 1
>2014-07-15 11:05:26.994+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x1178f60
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollAddHandle:136 : EVENT_POLL_ADD_HANDLE: watch=9 fd=18 events=1 cb=0x351774ac70 opaque=0x1178f60 ff=0x351774b060
>2014-07-15 11:05:26.994+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x11791a0
>2014-07-15 11:05:26.994+0000: 19609: debug : virKeepAliveNew:212 : client=0x11791a0, interval=5, count=5
>2014-07-15 11:05:26.994+0000: 19609: debug : virObjectNew:110 : OBJECT_NEW: obj=0x11737f0 classname=virKeepAlive
>2014-07-15 11:05:26.994+0000: 19609: debug : virKeepAliveNew:236 : RPC_KEEPALIVE_NEW: ka=0x11737f0 client=0x11791a0
>2014-07-15 11:05:26.994+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x11791a0
>2014-07-15 11:05:26.994+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11791a0
>2014-07-15 11:05:26.994+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1178f60
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=-1
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=9 events=1
>2014-07-15 11:05:26.994+0000: 19609: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2014-07-15 11:05:26.994+0000: 19609: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1179280 tx=(nil)
>2014-07-15 11:05:26.994+0000: 19609: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=1
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:26.994+0000: 19609: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x11791a0 len=28 prog=536903814 vers=1 proc=66 type=0 status=0 serial=0
>2014-07-15 11:05:26.994+0000: 19609: debug : virKeepAliveCheckMessage:384 : ka=0x11737f0, client=0x11791a0, msg=0x1179280
>2014-07-15 11:05:26.994+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x11791a0
>2014-07-15 11:05:26.994+0000: 19609: debug : virNetServerDispatchNewMessage:218 : server=0x116d1b0 client=0x11791a0 message=0x1179280
>2014-07-15 11:05:26.994+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x11767c0
>2014-07-15 11:05:26.994+0000: 19609: debug : virNetMessageNew:45 : msg=0x1174370 tracked=1
>2014-07-15 11:05:26.994+0000: 19609: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1174370 tx=(nil)
>2014-07-15 11:05:26.994+0000: 19609: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-15 11:05:26.994+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=1
>2014-07-15 11:05:26.994+0000: 19610: debug : virNetServerHandleJob:189 : server=0x116d1b0 client=0x11791a0 message=0x1179280 prog=0x11767c0
>2014-07-15 11:05:26.995+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:26.995+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-07-15 11:05:26.995+0000: 19610: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=0 proc=66
>2014-07-15 11:05:26.995+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:26.995+0000: 19610: debug : remoteDispatchAuthListHelper:18 : server=0x116d1b0 client=0x11791a0 msg=0x1179280 rerr=0x7f6659afcb80 args=0x7f66440024d0 ret=0x7f6644001c50
>2014-07-15 11:05:26.995+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:26.995+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-07-15 11:05:26.995+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:26.995+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:26.995+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:26.995+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:26.995+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:26.995+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:26.995+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:26.995+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:26.995+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:26.995+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2014-07-15 11:05:26.995+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2014-07-15 11:05:26.995+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2014-07-15 11:05:26.995+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=-1
>2014-07-15 11:05:27.003+0000: 19610: debug : virFileClose:72 : Closed fd 19
>2014-07-15 11:05:27.003+0000: 19610: info : remoteDispatchAuthList:2432 : Bypass polkit auth for privileged client pid:19708,uid:0
>2014-07-15 11:05:27.003+0000: 19610: debug : virNetMessageEncodePayload:358 : Encode length as 36
>2014-07-15 11:05:27.003+0000: 19610: debug : virNetServerClientSendMessageLocked:1122 : msg=0x1179280 proc=66 len=36 offset=0
>2014-07-15 11:05:27.003+0000: 19610: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x11791a0 len=36 prog=536903814 vers=1 proc=66 type=1 status=0 serial=0
>2014-07-15 11:05:27.003+0000: 19610: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1174370 tx=0x1179280
>2014-07-15 11:05:27.003+0000: 19610: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-07-15 11:05:27.003+0000: 19610: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=3
>2014-07-15 11:05:27.003+0000: 19610: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-07-15 11:05:27.003+0000: 19610: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11767c0
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.003+0000: 19610: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11791a0
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=5 d=0
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=-1
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.003+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=9 events=2
>2014-07-15 11:05:27.003+0000: 19609: debug : virNetMessageFree:73 : msg=0x1179280 nfds=0 cb=(nil)
>2014-07-15 11:05:27.004+0000: 19609: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1174370 tx=(nil)
>2014-07-15 11:05:27.004+0000: 19609: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=-1
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.004+0000: 19609: debug : virNetMessageDecodeLength:152 : Got length, now need 32 total (28 more)
>2014-07-15 11:05:27.004+0000: 19609: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1174370 tx=(nil)
>2014-07-15 11:05:27.004+0000: 19609: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.004+0000: 19609: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x11791a0 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=1
>2014-07-15 11:05:27.004+0000: 19609: debug : virKeepAliveCheckMessage:384 : ka=0x11737f0, client=0x11791a0, msg=0x1174370
>2014-07-15 11:05:27.004+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x11791a0
>2014-07-15 11:05:27.004+0000: 19609: debug : virNetServerDispatchNewMessage:218 : server=0x116d1b0 client=0x11791a0 message=0x1174370
>2014-07-15 11:05:27.004+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x11767c0
>2014-07-15 11:05:27.004+0000: 19609: debug : virNetMessageNew:45 : msg=0x1173e30 tracked=1
>2014-07-15 11:05:27.004+0000: 19609: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1173e30 tx=(nil)
>2014-07-15 11:05:27.004+0000: 19611: debug : virNetServerHandleJob:189 : server=0x116d1b0 client=0x11791a0 message=0x1174370 prog=0x11767c0
>2014-07-15 11:05:27.004+0000: 19609: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.004+0000: 19611: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=1 proc=60
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-07-15 11:05:27.004+0000: 19611: debug : remoteDispatchSupportsFeatureHelper:13350 : server=0x116d1b0 client=0x11791a0 msg=0x1174370 rerr=0x7f66590fbb80 args=0x7f664c0c5490 ret=0x7f664c080b40
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.004+0000: 19611: debug : virKeepAliveStart:289 : RPC_KEEPALIVE_START: ka=0x11737f0 client=0x11791a0 interval=0 count=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=-1
>2014-07-15 11:05:27.004+0000: 19611: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-07-15 11:05:27.004+0000: 19611: debug : virEventPollAddTimeout:243 : EVENT_POLL_ADD_TIMEOUT: timer=2 frequency=5000 cb=0x351774a8e0 opaque=0x11737f0 ff=0x41f1b0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.004+0000: 19611: debug : virObjectRef:168 : OBJECT_REF: obj=0x11737f0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.004+0000: 19611: debug : virNetMessageEncodePayload:358 : Encode length as 32
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.004+0000: 19611: debug : virNetServerClientSendMessageLocked:1122 : msg=0x1174370 proc=60 len=32 offset=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-07-15 11:05:27.004+0000: 19611: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x11791a0 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=1
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.004+0000: 19611: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1173e30 tx=0x1174370
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.004+0000: 19611: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.004+0000: 19611: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=3
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.004+0000: 19611: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 1606113376
>2014-07-15 11:05:27.004+0000: 19611: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11767c0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.004+0000: 19611: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11791a0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=5 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1405422332004
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 1405422332004 due in 5000 ms
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=5000
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=9 events=2
>2014-07-15 11:05:27.004+0000: 19609: debug : virNetMessageFree:73 : msg=0x1174370 nfds=0 cb=(nil)
>2014-07-15 11:05:27.004+0000: 19609: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1173e30 tx=(nil)
>2014-07-15 11:05:27.004+0000: 19609: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.004+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1405422332004
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 1405422332004 due in 4999 ms
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4999
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.005+0000: 19609: debug : virNetMessageDecodeLength:152 : Got length, now need 40 total (36 more)
>2014-07-15 11:05:27.005+0000: 19609: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1173e30 tx=(nil)
>2014-07-15 11:05:27.005+0000: 19609: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.005+0000: 19609: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x11791a0 len=40 prog=536903814 vers=1 proc=1 type=0 status=0 serial=2
>2014-07-15 11:05:27.005+0000: 19609: debug : virKeepAliveCheckMessage:384 : ka=0x11737f0, client=0x11791a0, msg=0x1173e30
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=2 frequency=5000
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.005+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x11791a0
>2014-07-15 11:05:27.005+0000: 19609: debug : virNetServerDispatchNewMessage:218 : server=0x116d1b0 client=0x11791a0 message=0x1173e30
>2014-07-15 11:05:27.005+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x11767c0
>2014-07-15 11:05:27.005+0000: 19609: debug : virNetMessageNew:45 : msg=0x11738f0 tracked=1
>2014-07-15 11:05:27.005+0000: 19609: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x11738f0 tx=(nil)
>2014-07-15 11:05:27.005+0000: 19609: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.005+0000: 19612: debug : virNetServerHandleJob:189 : server=0x116d1b0 client=0x11791a0 message=0x1173e30 prog=0x11767c0
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.005+0000: 19612: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=2 proc=1
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.005+0000: 19612: debug : remoteDispatchOpenHelper:11127 : server=0x116d1b0 client=0x11791a0 msg=0x1173e30 rerr=0x7f66586fab80 args=0x7f66480008c0 ret=0x7f66480008e0
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.005+0000: 19612: debug : remoteDispatchOpen:766 : priv=0x1179630 conn=(nil)
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.005+0000: 19612: debug : virConnectOpen:1331 : name=
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.005+0000: 19612: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f66480009c0 classname=virConnect
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.005+0000: 19612: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f6648000930 classname=virConnectCloseCallbackData
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.005+0000: 19612: debug : virConnectGetConfigFile:953 : Loading config file '/etc/libvirt/libvirt.conf'
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-15 11:05:27.005+0000: 19612: debug : virFileClose:72 : Closed fd 19
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1405422332005
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 1405422332005 due in 5000 ms
>2014-07-15 11:05:27.005+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=5000
>2014-07-15 11:05:27.005+0000: 19612: debug : do_open:1131 : no name, allowing driver auto-select
>2014-07-15 11:05:27.005+0000: 19612: debug : do_open:1174 : trying driver 0 (Test) ...
>2014-07-15 11:05:27.005+0000: 19612: debug : do_open:1180 : driver 0 Test returned DECLINED
>2014-07-15 11:05:27.005+0000: 19612: debug : do_open:1174 : trying driver 1 (ESX) ...
>2014-07-15 11:05:27.005+0000: 19612: debug : do_open:1180 : driver 1 ESX returned DECLINED
>2014-07-15 11:05:27.005+0000: 19612: debug : do_open:1174 : trying driver 2 (remote) ...
>2014-07-15 11:05:27.005+0000: 19612: debug : do_open:1180 : driver 2 remote returned DECLINED
>2014-07-15 11:05:27.005+0000: 19612: debug : do_open:1174 : trying driver 3 (QEMU) ...
>2014-07-15 11:05:27.005+0000: 19612: debug : do_open:1180 : driver 3 QEMU returned SUCCESS
>2014-07-15 11:05:27.005+0000: 19612: debug : do_open:1202 : network driver 0 Test returned DECLINED
>2014-07-15 11:05:27.005+0000: 19612: debug : do_open:1202 : network driver 1 ESX returned DECLINED
>2014-07-15 11:05:27.005+0000: 19612: debug : do_open:1202 : network driver 2 remote returned DECLINED
>2014-07-15 11:05:27.005+0000: 19612: debug : do_open:1202 : network driver 3 Network returned SUCCESS
>2014-07-15 11:05:27.005+0000: 19612: debug : do_open:1217 : interface driver 0 Test returned DECLINED
>2014-07-15 11:05:27.005+0000: 19612: debug : do_open:1217 : interface driver 1 ESX returned DECLINED
>2014-07-15 11:05:27.005+0000: 19612: debug : do_open:1217 : interface driver 2 remote returned DECLINED
>2014-07-15 11:05:27.020+0000: 19612: debug : do_open:1217 : interface driver 3 Interface returned SUCCESS
>2014-07-15 11:05:27.020+0000: 19612: debug : do_open:1233 : storage driver 0 Test returned DECLINED
>2014-07-15 11:05:27.020+0000: 19612: debug : do_open:1233 : storage driver 1 ESX returned DECLINED
>2014-07-15 11:05:27.020+0000: 19612: debug : do_open:1233 : storage driver 2 remote returned DECLINED
>2014-07-15 11:05:27.020+0000: 19612: debug : do_open:1233 : storage driver 3 storage returned SUCCESS
>2014-07-15 11:05:27.020+0000: 19612: debug : do_open:1249 : node driver 0 Test returned DECLINED
>2014-07-15 11:05:27.020+0000: 19612: debug : do_open:1249 : node driver 1 ESX returned DECLINED
>2014-07-15 11:05:27.020+0000: 19612: debug : do_open:1249 : node driver 2 remote returned DECLINED
>2014-07-15 11:05:27.020+0000: 19612: debug : do_open:1249 : node driver 3 udevDeviceMonitor returned SUCCESS
>2014-07-15 11:05:27.020+0000: 19612: debug : do_open:1265 : secret driver 0 Test returned DECLINED
>2014-07-15 11:05:27.020+0000: 19612: debug : do_open:1265 : secret driver 1 ESX returned DECLINED
>2014-07-15 11:05:27.020+0000: 19612: debug : do_open:1265 : secret driver 2 remote returned DECLINED
>2014-07-15 11:05:27.020+0000: 19612: debug : do_open:1265 : secret driver 3 secret returned SUCCESS
>2014-07-15 11:05:27.020+0000: 19612: debug : do_open:1281 : nwfilter driver 0 Test returned DECLINED
>2014-07-15 11:05:27.020+0000: 19612: debug : do_open:1281 : nwfilter driver 1 ESX returned DECLINED
>2014-07-15 11:05:27.020+0000: 19612: debug : do_open:1281 : nwfilter driver 2 remote returned DECLINED
>2014-07-15 11:05:27.020+0000: 19612: debug : do_open:1281 : nwfilter driver 3 nwfilter returned SUCCESS
>2014-07-15 11:05:27.020+0000: 19612: debug : virNetMessageEncodePayload:358 : Encode length as 28
>2014-07-15 11:05:27.020+0000: 19612: debug : virNetServerClientSendMessageLocked:1122 : msg=0x1173e30 proc=1 len=28 offset=0
>2014-07-15 11:05:27.020+0000: 19612: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x11791a0 len=28 prog=536903814 vers=1 proc=1 type=1 status=0 serial=2
>2014-07-15 11:05:27.020+0000: 19612: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x11738f0 tx=0x1173e30
>2014-07-15 11:05:27.020+0000: 19612: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-07-15 11:05:27.020+0000: 19612: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=3
>2014-07-15 11:05:27.020+0000: 19612: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-07-15 11:05:27.020+0000: 19612: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11767c0
>2014-07-15 11:05:27.020+0000: 19612: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11791a0
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=5 d=0
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1405422332005
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 1405422332005 due in 4985 ms
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4985
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=9 events=2
>2014-07-15 11:05:27.020+0000: 19609: debug : virNetMessageFree:73 : msg=0x1173e30 nfds=0 cb=(nil)
>2014-07-15 11:05:27.020+0000: 19609: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x11738f0 tx=(nil)
>2014-07-15 11:05:27.020+0000: 19609: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1405422332005
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 1405422332005 due in 4985 ms
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4985
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.020+0000: 19609: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2014-07-15 11:05:27.020+0000: 19609: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x11738f0 tx=(nil)
>2014-07-15 11:05:27.020+0000: 19609: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.020+0000: 19609: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x11791a0 len=28 prog=536903814 vers=1 proc=110 type=0 status=0 serial=3
>2014-07-15 11:05:27.020+0000: 19609: debug : virKeepAliveCheckMessage:384 : ka=0x11737f0, client=0x11791a0, msg=0x11738f0
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=2 frequency=5000
>2014-07-15 11:05:27.020+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.020+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x11791a0
>2014-07-15 11:05:27.020+0000: 19609: debug : virNetServerDispatchNewMessage:218 : server=0x116d1b0 client=0x11791a0 message=0x11738f0
>2014-07-15 11:05:27.020+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x11767c0
>2014-07-15 11:05:27.020+0000: 19609: debug : virNetMessageNew:45 : msg=0x1172e20 tracked=1
>2014-07-15 11:05:27.020+0000: 19609: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1172e20 tx=(nil)
>2014-07-15 11:05:27.020+0000: 19609: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.021+0000: 19613: debug : virNetServerHandleJob:189 : server=0x116d1b0 client=0x11791a0 message=0x11738f0 prog=0x11767c0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.021+0000: 19613: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=110
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.021+0000: 19613: debug : remoteDispatchGetURIHelper:7297 : server=0x116d1b0 client=0x11791a0 msg=0x11738f0 rerr=0x7f6657cf9b80 args=0x7f663c0008c0 ret=0x7f663c0008e0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.021+0000: 19613: debug : virConnectGetURI:1700 : conn=0x7f66480009c0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.021+0000: 19613: debug : virNetMessageEncodePayload:358 : Encode length as 48
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.021+0000: 19613: debug : virNetServerClientSendMessageLocked:1122 : msg=0x11738f0 proc=110 len=48 offset=0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.021+0000: 19613: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x11791a0 len=48 prog=536903814 vers=1 proc=110 type=1 status=0 serial=3
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-15 11:05:27.021+0000: 19613: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1172e20 tx=0x11738f0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1405422332020
>2014-07-15 11:05:27.021+0000: 19613: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 1405422332020 due in 4999 ms
>2014-07-15 11:05:27.021+0000: 19613: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=3
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4999
>2014-07-15 11:05:27.021+0000: 19613: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-07-15 11:05:27.021+0000: 19613: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11767c0
>2014-07-15 11:05:27.021+0000: 19613: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11791a0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=5 d=0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1405422332020
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 1405422332020 due in 4999 ms
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4999
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=9 events=2
>2014-07-15 11:05:27.021+0000: 19609: debug : virNetMessageFree:73 : msg=0x11738f0 nfds=0 cb=(nil)
>2014-07-15 11:05:27.021+0000: 19609: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1172e20 tx=(nil)
>2014-07-15 11:05:27.021+0000: 19609: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1405422332020
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 1405422332020 due in 4999 ms
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4999
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.021+0000: 19609: debug : virNetMessageDecodeLength:152 : Got length, now need 40 total (36 more)
>2014-07-15 11:05:27.021+0000: 19609: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1172e20 tx=(nil)
>2014-07-15 11:05:27.021+0000: 19609: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.021+0000: 19609: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x11791a0 len=40 prog=536903814 vers=1 proc=23 type=0 status=0 serial=4
>2014-07-15 11:05:27.021+0000: 19609: debug : virKeepAliveCheckMessage:384 : ka=0x11737f0, client=0x11791a0, msg=0x1172e20
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=2 frequency=5000
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.021+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x11791a0
>2014-07-15 11:05:27.021+0000: 19609: debug : virNetServerDispatchNewMessage:218 : server=0x116d1b0 client=0x11791a0 message=0x1172e20
>2014-07-15 11:05:27.021+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x11767c0
>2014-07-15 11:05:27.021+0000: 19609: debug : virNetMessageNew:45 : msg=0x1173d80 tracked=1
>2014-07-15 11:05:27.021+0000: 19609: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1173d80 tx=(nil)
>2014-07-15 11:05:27.021+0000: 19609: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-15 11:05:27.021+0000: 19614: debug : virNetServerHandleJob:189 : server=0x116d1b0 client=0x11791a0 message=0x1172e20 prog=0x11767c0
>2014-07-15 11:05:27.021+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.022+0000: 19614: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=23
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.022+0000: 19614: debug : remoteDispatchDomainLookupByNameHelper:3084 : server=0x116d1b0 client=0x11791a0 msg=0x1172e20 rerr=0x7f66572f8b80 args=0x7f66400008c0 ret=0x7f66400008e0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.022+0000: 19614: debug : virDomainLookupByName:2117 : conn=0x7f66480009c0, name=rhel6
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.022+0000: 19614: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f6640000bc0 classname=virDomain
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.022+0000: 19614: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f66480009c0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.022+0000: 19614: debug : virDomainFree:2281 : dom=0x7f6640000bc0, (VM: name=rhel6, uuid=bb1991ca-2e71-4136-91aa-c1fbfd73245f)
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.022+0000: 19614: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f6640000bc0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.022+0000: 19614: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f6640000bc0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.022+0000: 19614: debug : virDomainDispose:264 : release domain 0x7f6640000bc0 rhel6 bb1991ca-2e71-4136-91aa-c1fbfd73245f
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.022+0000: 19614: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f66480009c0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-15 11:05:27.022+0000: 19614: debug : virNetMessageEncodePayload:358 : Encode length as 60
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1405422332021
>2014-07-15 11:05:27.022+0000: 19614: debug : virNetServerClientSendMessageLocked:1122 : msg=0x1172e20 proc=23 len=60 offset=0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 1405422332021 due in 4999 ms
>2014-07-15 11:05:27.022+0000: 19614: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x11791a0 len=60 prog=536903814 vers=1 proc=23 type=1 status=0 serial=4
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4999
>2014-07-15 11:05:27.022+0000: 19614: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1173d80 tx=0x1172e20
>2014-07-15 11:05:27.022+0000: 19614: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-07-15 11:05:27.022+0000: 19614: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=3
>2014-07-15 11:05:27.022+0000: 19614: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-07-15 11:05:27.022+0000: 19614: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11767c0
>2014-07-15 11:05:27.022+0000: 19614: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11791a0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=5 d=0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1405422332021
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 1405422332021 due in 4999 ms
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4999
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=9 events=2
>2014-07-15 11:05:27.022+0000: 19609: debug : virNetMessageFree:73 : msg=0x1172e20 nfds=0 cb=(nil)
>2014-07-15 11:05:27.022+0000: 19609: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1173d80 tx=(nil)
>2014-07-15 11:05:27.022+0000: 19609: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1405422332021
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 1405422332021 due in 4999 ms
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4999
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.022+0000: 19609: debug : virNetMessageDecodeLength:152 : Got length, now need 108 total (104 more)
>2014-07-15 11:05:27.022+0000: 19609: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1173d80 tx=(nil)
>2014-07-15 11:05:27.022+0000: 19609: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.022+0000: 19609: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x11791a0 len=108 prog=536903814 vers=1 proc=266 type=0 status=0 serial=5
>2014-07-15 11:05:27.022+0000: 19609: debug : virKeepAliveCheckMessage:384 : ka=0x11737f0, client=0x11791a0, msg=0x1173d80
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=2 frequency=5000
>2014-07-15 11:05:27.022+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.022+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x11791a0
>2014-07-15 11:05:27.022+0000: 19609: debug : virNetServerDispatchNewMessage:218 : server=0x116d1b0 client=0x11791a0 message=0x1173d80
>2014-07-15 11:05:27.022+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x11767c0
>2014-07-15 11:05:27.022+0000: 19609: debug : virNetMessageNew:45 : msg=0x1173050 tracked=1
>2014-07-15 11:05:27.023+0000: 19609: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1173050 tx=(nil)
>2014-07-15 11:05:27.023+0000: 19609: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-15 11:05:27.023+0000: 19610: debug : virNetServerHandleJob:189 : server=0x116d1b0 client=0x11791a0 message=0x1173d80 prog=0x11767c0
>2014-07-15 11:05:27.023+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.023+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.023+0000: 19610: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=5 proc=266
>2014-07-15 11:05:27.023+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.023+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.023+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.023+0000: 19610: debug : remoteDispatchDomainBlockRebaseHelper:799 : server=0x116d1b0 client=0x11791a0 msg=0x1173d80 rerr=0x7f6659afcb80 args=0x7f6644000bf0 ret=0x7f66440024d0
>2014-07-15 11:05:27.023+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.023+0000: 19610: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f66440027c0 classname=virDomain
>2014-07-15 11:05:27.023+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.023+0000: 19610: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f66480009c0
>2014-07-15 11:05:27.023+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.023+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.023+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.023+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.023+0000: 19610: debug : virDomainBlockRebase:19464 : dom=0x7f66440027c0, (VM: name=rhel6, uuid=bb1991ca-2e71-4136-91aa-c1fbfd73245f), disk=vda, base=/home/shyu/copy.img, bandwidth=0, flags=8
>2014-07-15 11:05:27.023+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.023+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.023+0000: 19610: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f664c01e880
>2014-07-15 11:05:27.023+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.023+0000: 19610: debug : qemuDomainObjBeginJobInternal:847 : Starting job: modify (async=none)
>2014-07-15 11:05:27.023+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.023+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2014-07-15 11:05:27.023+0000: 19610: debug : virCgroupNew:617 : New group /libvirt/qemu/rhel6
>2014-07-15 11:05:27.023+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-15 11:05:27.023+0000: 19609: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1405422332022
>2014-07-15 11:05:27.023+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 1405422332022 due in 4999 ms
>2014-07-15 11:05:27.023+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4999
>2014-07-15 11:05:27.023+0000: 19610: debug : virCgroupDetect:273 : Detected mount/mapping 0:cpu at /cgroup/cpu in 
>2014-07-15 11:05:27.023+0000: 19610: debug : virCgroupDetect:273 : Detected mount/mapping 1:cpuacct at /cgroup/cpuacct in 
>2014-07-15 11:05:27.023+0000: 19610: debug : virCgroupDetect:273 : Detected mount/mapping 2:cpuset at /cgroup/cpuset in 
>2014-07-15 11:05:27.023+0000: 19610: debug : virCgroupDetect:273 : Detected mount/mapping 3:memory at /cgroup/memory in 
>2014-07-15 11:05:27.023+0000: 19610: debug : virCgroupDetect:273 : Detected mount/mapping 4:devices at /cgroup/devices in 
>2014-07-15 11:05:27.023+0000: 19610: debug : virCgroupDetect:273 : Detected mount/mapping 5:freezer at /cgroup/freezer in 
>2014-07-15 11:05:27.023+0000: 19610: debug : virCgroupDetect:273 : Detected mount/mapping 6:blkio at /cgroup/blkio in 
>2014-07-15 11:05:27.023+0000: 19610: debug : virCgroupMakeGroup:535 : Make group /libvirt/qemu/rhel6
>2014-07-15 11:05:27.023+0000: 19610: debug : virCgroupMakeGroup:560 : Make controller /cgroup/cpu/libvirt/qemu/rhel6/
>2014-07-15 11:05:27.023+0000: 19610: debug : virCgroupMakeGroup:560 : Make controller /cgroup/cpuacct/libvirt/qemu/rhel6/
>2014-07-15 11:05:27.023+0000: 19610: debug : virCgroupMakeGroup:560 : Make controller /cgroup/cpuset/libvirt/qemu/rhel6/
>2014-07-15 11:05:27.023+0000: 19610: debug : virCgroupMakeGroup:560 : Make controller /cgroup/memory/libvirt/qemu/rhel6/
>2014-07-15 11:05:27.023+0000: 19610: debug : virCgroupMakeGroup:560 : Make controller /cgroup/devices/libvirt/qemu/rhel6/
>2014-07-15 11:05:27.023+0000: 19610: debug : virCgroupMakeGroup:560 : Make controller /cgroup/freezer/libvirt/qemu/rhel6/
>2014-07-15 11:05:27.023+0000: 19610: debug : virCgroupMakeGroup:560 : Make controller /cgroup/blkio/libvirt/qemu/rhel6/
>2014-07-15 11:05:27.023+0000: 19610: debug : virStorageFileGetMetadata:1006 : path=/var/lib/libvirt/images/kvm-rhel6.5-x86_64-qcow2.img format=12 uid=107 gid=107 probe=0
>2014-07-15 11:05:27.023+0000: 19610: debug : virStorageFileGetMetadataRecurse:938 : path=/var/lib/libvirt/images/kvm-rhel6.5-x86_64-qcow2.img format=12 uid=107 gid=107 probe=0
>2014-07-15 11:05:27.023+0000: 19610: debug : virStorageFileGetMetadataInternal:686 : path=/var/lib/libvirt/images/kvm-rhel6.5-x86_64-qcow2.img, fd=22, format=12
>2014-07-15 11:05:27.023+0000: 19610: debug : virFileClose:72 : Closed fd 22
>2014-07-15 11:05:27.026+0000: 19610: debug : virStorageFileIsSharedFSType:1177 : Check if path /home/shyu/copy.img with FS magic 4283649346 is shared
>2014-07-15 11:05:27.029+0000: 19610: debug : virFileClose:72 : Closed fd 22
>2014-07-15 11:05:27.029+0000: 19610: debug : virDomainLockDiskAttach:254 : plugin=0x7f664c00a7f0 dom=0x7f664c01e880 disk=0x7f664c0810b0
>2014-07-15 11:05:27.029+0000: 19610: debug : virDomainLockManagerNew:128 : plugin=0x7f664c00a7f0 dom=0x7f664c01e880 withResources=0
>2014-07-15 11:05:27.029+0000: 19610: debug : virLockManagerPluginGetDriver:283 : plugin=0x7f664c00a7f0
>2014-07-15 11:05:27.029+0000: 19610: debug : virLockManagerNew:307 : driver=0x3517a3f4a0 type=0 nparams=5 params=0x7f6659afc730 flags=0
>2014-07-15 11:05:27.029+0000: 19610: debug : virLockManagerLogParams:107 :   key=uuid type=uuid value=bb1991ca-2e71-4136-91aa-c1fbfd73245f
>2014-07-15 11:05:27.029+0000: 19610: debug : virLockManagerLogParams:100 :   key=name type=string value=rhel6
>2014-07-15 11:05:27.029+0000: 19610: debug : virLockManagerLogParams:88 :   key=id type=uint value=2
>2014-07-15 11:05:27.029+0000: 19610: debug : virLockManagerLogParams:88 :   key=pid type=uint value=18348
>2014-07-15 11:05:27.029+0000: 19610: debug : virLockManagerLogParams:103 :   key=uri type=cstring value=qemu:///system
>2014-07-15 11:05:27.029+0000: 19610: debug : virDomainLockManagerAddDisk:86 : Add disk /home/shyu/copy.img
>2014-07-15 11:05:27.029+0000: 19610: debug : virLockManagerAddResource:336 : lock=0x7f6644002a20 type=0 name=/home/shyu/copy.img nparams=0 params=(nil) flags=0
>2014-07-15 11:05:27.029+0000: 19610: debug : virLockManagerAcquire:354 : lock=0x7f6644002a20 state='(null)' flags=0 action=0 fd=(nil)
>2014-07-15 11:05:27.029+0000: 19610: debug : virLockManagerFree:391 : lock=0x7f6644002a20
>2014-07-15 11:05:27.029+0000: 19610: debug : qemuSetupDiskPathAllow:71 : Process path /home/shyu/copy.img for disk
>2014-07-15 11:05:27.029+0000: 19610: debug : virSecurityDACParseImageIds:150 : DAC imagelabel for domain 'rhel6' wasn't found
>2014-07-15 11:05:27.029+0000: 19610: info : virSecurityDACSetOwnership:247 : Setting DAC user and group on '/home/shyu/copy.img' to '107:107'
>2014-07-15 11:05:27.029+0000: 19610: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f6644000d20
>2014-07-15 11:05:27.029+0000: 19610: debug : qemuMonitorDriveMirror:2869 : mon=0x7f6644000d20, device=drive-virtio-disk0, file=/home/shyu/copy.img, format=qcow2, bandwidth=0, flags=0
>2014-07-15 11:05:27.030+0000: 19610: debug : virJSONValueToString:1099 : object=0x7f6644002a20
>2014-07-15 11:05:27.030+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002a20 type=0 gen=0x7f6644003140
>2014-07-15 11:05:27.030+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002a40 type=2 gen=0x7f6644003140
>2014-07-15 11:05:27.030+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002860 type=0 gen=0x7f6644003140
>2014-07-15 11:05:27.030+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002880 type=2 gen=0x7f6644003140
>2014-07-15 11:05:27.030+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002de0 type=2 gen=0x7f6644003140
>2014-07-15 11:05:27.030+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002b00 type=3 gen=0x7f6644003140
>2014-07-15 11:05:27.030+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002b80 type=2 gen=0x7f6644003140
>2014-07-15 11:05:27.030+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002c30 type=2 gen=0x7f6644003140
>2014-07-15 11:05:27.030+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002f60 type=2 gen=0x7f6644003140
>2014-07-15 11:05:27.030+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644003050 type=2 gen=0x7f6644003140
>2014-07-15 11:05:27.030+0000: 19610: debug : virJSONValueToString:1133 : result={"execute":"drive-mirror","arguments":{"device":"drive-virtio-disk0","target":"/home/shyu/copy.img","speed":0,"sync":"full","mode":"absolute-paths","format":"qcow2"},"id":"libvirt-7"}
>2014-07-15 11:05:27.030+0000: 19610: debug : qemuMonitorJSONCommandWithFd:269 : Send command '{"execute":"drive-mirror","arguments":{"device":"drive-virtio-disk0","target":"/home/shyu/copy.img","speed":0,"sync":"full","mode":"absolute-paths","format":"qcow2"},"id":"libvirt-7"}' for write with FD -1
>2014-07-15 11:05:27.030+0000: 19610: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=15
>2014-07-15 11:05:27.030+0000: 19610: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-07-15 11:05:27.030+0000: 19610: debug : qemuMonitorSend:911 : QEMU_MONITOR_SEND_MSG: mon=0x7f6644000d20 msg={"execute":"drive-mirror","arguments":{"device":"drive-virtio-disk0","target":"/home/shyu/copy.img","speed":0,"sync":"full","mode":"absolute-paths","format":"qcow2"},"id":"libvirt-7"}
> fd=-1
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=29 d=0
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1405422332022
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 1405422332022 due in 4992 ms
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4992
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=2
>2014-07-15 11:05:27.030+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f6644000d20
>2014-07-15 11:05:27.030+0000: 19609: debug : qemuMonitorIOWrite:463 : QEMU_MONITOR_IO_WRITE: mon=0x7f6644000d20 buf={"execute":"drive-mirror","arguments":{"device":"drive-virtio-disk0","target":"/home/shyu/copy.img","speed":0,"sync":"full","mode":"absolute-paths","format":"qcow2"},"id":"libvirt-7"}
> len=185 ret=185 errno=11
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=13
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.030+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f6644000d20
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1405422332022
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 1405422332022 due in 4992 ms
>2014-07-15 11:05:27.030+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4992
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=1
>2014-07-15 11:05:27.032+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f6644000d20
>2014-07-15 11:05:27.032+0000: 19609: debug : qemuMonitorIOProcess:355 : QEMU_MONITOR_IO_PROCESS: mon=0x7f6644000d20 buf={"id": "libvirt-7", "error": {"class": "CommandNotFound", "desc": "The command drive-mirror has not been found", "data": {"name": "drive-mirror"}}}
> len=149
>2014-07-15 11:05:27.032+0000: 19609: debug : qemuMonitorJSONIOProcessLine:156 : Line [{"id": "libvirt-7", "error": {"class": "CommandNotFound", "desc": "The command drive-mirror has not been found", "data": {"name": "drive-mirror"}}}]
>2014-07-15 11:05:27.032+0000: 19609: debug : virJSONValueFromString:975 : string={"id": "libvirt-7", "error": {"class": "CommandNotFound", "desc": "The command drive-mirror has not been found", "data": {"name": "drive-mirror"}}}
>2014-07-15 11:05:27.032+0000: 19609: debug : virJSONParserHandleStartMap:852 : parser=0x7fff27fbea20
>2014-07-15 11:05:27.032+0000: 19609: debug : virJSONParserHandleMapKey:833 : parser=0x7fff27fbea20 key=0x11796c2
>2014-07-15 11:05:27.032+0000: 19609: debug : virJSONParserHandleString:813 : parser=0x7fff27fbea20 str=0x11796c8
>2014-07-15 11:05:27.032+0000: 19609: debug : virJSONParserHandleMapKey:833 : parser=0x7fff27fbea20 key=0x11796d5
>2014-07-15 11:05:27.032+0000: 19609: debug : virJSONParserHandleStartMap:852 : parser=0x7fff27fbea20
>2014-07-15 11:05:27.032+0000: 19609: debug : virJSONParserHandleMapKey:833 : parser=0x7fff27fbea20 key=0x11796df
>2014-07-15 11:05:27.032+0000: 19609: debug : virJSONParserHandleString:813 : parser=0x7fff27fbea20 str=0x11796e8
>2014-07-15 11:05:27.032+0000: 19609: debug : virJSONParserHandleMapKey:833 : parser=0x7fff27fbea20 key=0x11796fb
>2014-07-15 11:05:27.032+0000: 19609: debug : virJSONParserHandleString:813 : parser=0x7fff27fbea20 str=0x1179703
>2014-07-15 11:05:27.032+0000: 19609: debug : virJSONParserHandleMapKey:833 : parser=0x7fff27fbea20 key=0x1179732
>2014-07-15 11:05:27.032+0000: 19609: debug : virJSONParserHandleStartMap:852 : parser=0x7fff27fbea20
>2014-07-15 11:05:27.032+0000: 19609: debug : virJSONParserHandleMapKey:833 : parser=0x7fff27fbea20 key=0x117973b
>2014-07-15 11:05:27.032+0000: 19609: debug : virJSONParserHandleString:813 : parser=0x7fff27fbea20 str=0x1179743
>2014-07-15 11:05:27.032+0000: 19609: debug : virJSONParserHandleEndMap:881 : parser=0x7fff27fbea20
>2014-07-15 11:05:27.032+0000: 19609: debug : virJSONParserHandleEndMap:881 : parser=0x7fff27fbea20
>2014-07-15 11:05:27.032+0000: 19609: debug : virJSONParserHandleEndMap:881 : parser=0x7fff27fbea20
>2014-07-15 11:05:27.032+0000: 19609: debug : virJSONValueFromString:1019 : result=0x114ade0
>2014-07-15 11:05:27.032+0000: 19609: debug : qemuMonitorJSONIOProcessLine:176 : QEMU_MONITOR_RECV_REPLY: mon=0x7f6644000d20 reply={"id": "libvirt-7", "error": {"class": "CommandNotFound", "desc": "The command drive-mirror has not been found", "data": {"name": "drive-mirror"}}}
>2014-07-15 11:05:27.032+0000: 19609: debug : qemuMonitorJSONIOProcess:227 : Total used 149 bytes out of 149 available in buffer
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=13
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.032+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f6644000d20
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.032+0000: 19610: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=13
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.032+0000: 19610: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 1606113376
>2014-07-15 11:05:27.032+0000: 19610: debug : qemuMonitorJSONCommandWithFd:274 : Receive command reply ret=0 rxObject=0x114ade0
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19610: debug : qemuMonitorJSONDriveMirror:3473 : block-job-complete command not found, trying RHEL version
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19610: debug : virJSONValueToString:1099 : object=0x7f6644002a20
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002a20 type=0 gen=0x7f6644003140
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644003050 type=2 gen=0x7f6644003140
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002860 type=0 gen=0x7f6644003140
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.032+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002f60 type=2 gen=0x7f6644003140
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002ee0 type=2 gen=0x7f6644003140
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-15 11:05:27.032+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002bc0 type=3 gen=0x7f6644003140
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1405422332022
>2014-07-15 11:05:27.032+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002b20 type=4 gen=0x7f6644003140
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 1405422332022 due in 4990 ms
>2014-07-15 11:05:27.032+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002de0 type=2 gen=0x7f6644003140
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4990
>2014-07-15 11:05:27.032+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002880 type=2 gen=0x7f6644003140
>2014-07-15 11:05:27.032+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002820 type=2 gen=0x7f6644003140
>2014-07-15 11:05:27.032+0000: 19610: debug : virJSONValueToString:1133 : result={"execute":"__com.redhat_drive-mirror","arguments":{"device":"drive-virtio-disk0","target":"/home/shyu/copy.img","speed":0,"full":true,"mode":"absolute-paths","format":"qcow2"},"id":"libvirt-8"}
>2014-07-15 11:05:27.032+0000: 19610: debug : qemuMonitorJSONCommandWithFd:269 : Send command '{"execute":"__com.redhat_drive-mirror","arguments":{"device":"drive-virtio-disk0","target":"/home/shyu/copy.img","speed":0,"full":true,"mode":"absolute-paths","format":"qcow2"},"id":"libvirt-8"}' for write with FD -1
>2014-07-15 11:05:27.032+0000: 19610: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=15
>2014-07-15 11:05:27.032+0000: 19610: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-07-15 11:05:27.032+0000: 19610: debug : qemuMonitorSend:911 : QEMU_MONITOR_SEND_MSG: mon=0x7f6644000d20 msg={"execute":"__com.redhat_drive-mirror","arguments":{"device":"drive-virtio-disk0","target":"/home/shyu/copy.img","speed":0,"full":true,"mode":"absolute-paths","format":"qcow2"},"id":"libvirt-8"}
> fd=-1
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=29 d=0
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1405422332022
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 1405422332022 due in 4990 ms
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4990
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=2
>2014-07-15 11:05:27.032+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f6644000d20
>2014-07-15 11:05:27.032+0000: 19609: debug : qemuMonitorIOWrite:463 : QEMU_MONITOR_IO_WRITE: mon=0x7f6644000d20 buf={"execute":"__com.redhat_drive-mirror","arguments":{"device":"drive-virtio-disk0","target":"/home/shyu/copy.img","speed":0,"full":true,"mode":"absolute-paths","format":"qcow2"},"id":"libvirt-8"}
> len=196 ret=196 errno=11
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=13
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.032+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f6644000d20
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2014-07-15 11:05:27.032+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-15 11:05:27.033+0000: 19609: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1405422332022
>2014-07-15 11:05:27.033+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 1405422332022 due in 4989 ms
>2014-07-15 11:05:27.033+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4989
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=1
>2014-07-15 11:05:27.060+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f6644000d20
>2014-07-15 11:05:27.060+0000: 19609: debug : qemuMonitorIOProcess:355 : QEMU_MONITOR_IO_PROCESS: mon=0x7f6644000d20 buf={"id": "libvirt-8", "error": {"class": "OpenFileFailed", "desc": "Could not open '/home/shyu/copy.img': Invalid argument", "data": {"filename": "/home/shyu/copy.img", "__com.redhat_error_message": "Invalid argument"}}}
> len=220
>2014-07-15 11:05:27.060+0000: 19609: debug : qemuMonitorJSONIOProcessLine:156 : Line [{"id": "libvirt-8", "error": {"class": "OpenFileFailed", "desc": "Could not open '/home/shyu/copy.img': Invalid argument", "data": {"filename": "/home/shyu/copy.img", "__com.redhat_error_message": "Invalid argument"}}}]
>2014-07-15 11:05:27.060+0000: 19609: debug : virJSONValueFromString:975 : string={"id": "libvirt-8", "error": {"class": "OpenFileFailed", "desc": "Could not open '/home/shyu/copy.img': Invalid argument", "data": {"filename": "/home/shyu/copy.img", "__com.redhat_error_message": "Invalid argument"}}}
>2014-07-15 11:05:27.060+0000: 19609: debug : virJSONParserHandleStartMap:852 : parser=0x7fff27fbea20
>2014-07-15 11:05:27.060+0000: 19609: debug : virJSONParserHandleMapKey:833 : parser=0x7fff27fbea20 key=0x1172772
>2014-07-15 11:05:27.060+0000: 19609: debug : virJSONParserHandleString:813 : parser=0x7fff27fbea20 str=0x1172778
>2014-07-15 11:05:27.060+0000: 19609: debug : virJSONParserHandleMapKey:833 : parser=0x7fff27fbea20 key=0x1172785
>2014-07-15 11:05:27.060+0000: 19609: debug : virJSONParserHandleStartMap:852 : parser=0x7fff27fbea20
>2014-07-15 11:05:27.060+0000: 19609: debug : virJSONParserHandleMapKey:833 : parser=0x7fff27fbea20 key=0x117278f
>2014-07-15 11:05:27.060+0000: 19609: debug : virJSONParserHandleString:813 : parser=0x7fff27fbea20 str=0x1172798
>2014-07-15 11:05:27.060+0000: 19609: debug : virJSONParserHandleMapKey:833 : parser=0x7fff27fbea20 key=0x11727aa
>2014-07-15 11:05:27.060+0000: 19609: debug : virJSONParserHandleString:813 : parser=0x7fff27fbea20 str=0x11727b2
>2014-07-15 11:05:27.060+0000: 19609: debug : virJSONParserHandleMapKey:833 : parser=0x7fff27fbea20 key=0x11727ec
>2014-07-15 11:05:27.060+0000: 19609: debug : virJSONParserHandleStartMap:852 : parser=0x7fff27fbea20
>2014-07-15 11:05:27.060+0000: 19609: debug : virJSONParserHandleMapKey:833 : parser=0x7fff27fbea20 key=0x11727f5
>2014-07-15 11:05:27.060+0000: 19609: debug : virJSONParserHandleString:813 : parser=0x7fff27fbea20 str=0x1172801
>2014-07-15 11:05:27.060+0000: 19609: debug : virJSONParserHandleMapKey:833 : parser=0x7fff27fbea20 key=0x1172818
>2014-07-15 11:05:27.060+0000: 19609: debug : virJSONParserHandleString:813 : parser=0x7fff27fbea20 str=0x1172836
>2014-07-15 11:05:27.060+0000: 19609: debug : virJSONParserHandleEndMap:881 : parser=0x7fff27fbea20
>2014-07-15 11:05:27.060+0000: 19609: debug : virJSONParserHandleEndMap:881 : parser=0x7fff27fbea20
>2014-07-15 11:05:27.060+0000: 19609: debug : virJSONParserHandleEndMap:881 : parser=0x7fff27fbea20
>2014-07-15 11:05:27.060+0000: 19609: debug : virJSONValueFromString:1019 : result=0x1178490
>2014-07-15 11:05:27.060+0000: 19609: debug : qemuMonitorJSONIOProcessLine:176 : QEMU_MONITOR_RECV_REPLY: mon=0x7f6644000d20 reply={"id": "libvirt-8", "error": {"class": "OpenFileFailed", "desc": "Could not open '/home/shyu/copy.img': Invalid argument", "data": {"filename": "/home/shyu/copy.img", "__com.redhat_error_message": "Invalid argument"}}}
>2014-07-15 11:05:27.060+0000: 19609: debug : qemuMonitorJSONIOProcess:227 : Total used 220 bytes out of 220 available in buffer
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=13
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.060+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f6644000d20
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.060+0000: 19610: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=13
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.060+0000: 19610: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 1606113376
>2014-07-15 11:05:27.060+0000: 19610: debug : qemuMonitorJSONCommandWithFd:274 : Receive command reply ret=0 rxObject=0x1178490
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.060+0000: 19610: debug : virJSONValueToString:1099 : object=0x7f6644002a20
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.060+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002a20 type=0 gen=0x7f66440030d0
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.060+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644003050 type=2 gen=0x7f66440030d0
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.060+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002860 type=0 gen=0x7f66440030d0
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.060+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002f60 type=2 gen=0x7f66440030d0
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.060+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002ee0 type=2 gen=0x7f66440030d0
>2014-07-15 11:05:27.060+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.060+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002bc0 type=3 gen=0x7f66440030d0
>2014-07-15 11:05:27.061+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.061+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002b20 type=4 gen=0x7f66440030d0
>2014-07-15 11:05:27.061+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2014-07-15 11:05:27.061+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002de0 type=2 gen=0x7f66440030d0
>2014-07-15 11:05:27.061+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-15 11:05:27.061+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002880 type=2 gen=0x7f66440030d0
>2014-07-15 11:05:27.061+0000: 19609: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1405422332022
>2014-07-15 11:05:27.061+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x7f6644002820 type=2 gen=0x7f66440030d0
>2014-07-15 11:05:27.061+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 1405422332022 due in 4961 ms
>2014-07-15 11:05:27.061+0000: 19610: debug : virJSONValueToString:1133 : result={"execute":"__com.redhat_drive-mirror","arguments":{"device":"drive-virtio-disk0","target":"/home/shyu/copy.img","speed":0,"full":true,"mode":"absolute-paths","format":"qcow2"},"id":"libvirt-8"}
>2014-07-15 11:05:27.061+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4961
>2014-07-15 11:05:27.061+0000: 19610: debug : virJSONValueToString:1099 : object=0x1178490
>2014-07-15 11:05:27.061+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x1178490 type=0 gen=0x7f6644003490
>2014-07-15 11:05:27.061+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x114a3c0 type=2 gen=0x7f6644003490
>2014-07-15 11:05:27.061+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x114a8f0 type=0 gen=0x7f6644003490
>2014-07-15 11:05:27.061+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x1179180 type=2 gen=0x7f6644003490
>2014-07-15 11:05:27.061+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x11500f0 type=2 gen=0x7f6644003490
>2014-07-15 11:05:27.061+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x1149010 type=0 gen=0x7f6644003490
>2014-07-15 11:05:27.061+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x11792d0 type=2 gen=0x7f6644003490
>2014-07-15 11:05:27.061+0000: 19610: debug : virJSONValueToStringOne:1030 : object=0x114aa10 type=2 gen=0x7f6644003490
>2014-07-15 11:05:27.061+0000: 19610: debug : virJSONValueToString:1133 : result={"id":"libvirt-8","error":{"class":"OpenFileFailed","desc":"Could not open '/home/shyu/copy.img': Invalid argument","data":{"filename":"/home/shyu/copy.img","__com.redhat_error_message":"Invalid argument"}}}
>2014-07-15 11:05:27.061+0000: 19610: debug : qemuMonitorJSONCheckError:348 : unable to execute QEMU command {"execute":"__com.redhat_drive-mirror","arguments":{"device":"drive-virtio-disk0","target":"/home/shyu/copy.img","speed":0,"full":true,"mode":"absolute-paths","format":"qcow2"},"id":"libvirt-8"}: {"id":"libvirt-8","error":{"class":"OpenFileFailed","desc":"Could not open '/home/shyu/copy.img': Invalid argument","data":{"filename":"/home/shyu/copy.img","__com.redhat_error_message":"Invalid argument"}}}
>2014-07-15 11:05:27.061+0000: 19610: error : qemuMonitorJSONCheckError:359 : internal error unable to execute QEMU command '__com.redhat_drive-mirror': Could not open '/home/shyu/copy.img': Invalid argument
>2014-07-15 11:05:27.061+0000: 19610: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f6644000d20
>2014-07-15 11:05:27.061+0000: 19610: info : virSecurityDACRestoreSecurityFileLabel:291 : Restoring DAC user and group on '/home/shyu/copy.img'
>2014-07-15 11:05:27.061+0000: 19610: info : virSecurityDACSetOwnership:247 : Setting DAC user and group on '/home/shyu/copy.img' to '0:0'
>2014-07-15 11:05:27.061+0000: 19610: debug : qemuTeardownDiskPathDeny:112 : Process path /home/shyu/copy.img for disk
>2014-07-15 11:05:27.061+0000: 19610: debug : virDomainLockDiskDetach:282 : plugin=0x7f664c00a7f0 dom=0x7f664c01e880 disk=0x7f664c0810b0
>2014-07-15 11:05:27.061+0000: 19610: debug : virDomainLockManagerNew:128 : plugin=0x7f664c00a7f0 dom=0x7f664c01e880 withResources=0
>2014-07-15 11:05:27.061+0000: 19610: debug : virLockManagerPluginGetDriver:283 : plugin=0x7f664c00a7f0
>2014-07-15 11:05:27.061+0000: 19610: debug : virLockManagerNew:307 : driver=0x3517a3f4a0 type=0 nparams=5 params=0x7f6659afc740 flags=0
>2014-07-15 11:05:27.061+0000: 19610: debug : virLockManagerLogParams:107 :   key=uuid type=uuid value=bb1991ca-2e71-4136-91aa-c1fbfd73245f
>2014-07-15 11:05:27.061+0000: 19610: debug : virLockManagerLogParams:100 :   key=name type=string value=rhel6
>2014-07-15 11:05:27.061+0000: 19610: debug : virLockManagerLogParams:88 :   key=id type=uint value=2
>2014-07-15 11:05:27.061+0000: 19610: debug : virLockManagerLogParams:88 :   key=pid type=uint value=18348
>2014-07-15 11:05:27.061+0000: 19610: debug : virLockManagerLogParams:103 :   key=uri type=cstring value=(null)
>2014-07-15 11:05:27.061+0000: 19610: debug : virDomainLockManagerAddDisk:86 : Add disk /home/shyu/copy.img
>2014-07-15 11:05:27.061+0000: 19610: debug : virLockManagerAddResource:336 : lock=0x7f6644002f10 type=0 name=/home/shyu/copy.img nparams=0 params=(nil) flags=0
>2014-07-15 11:05:27.061+0000: 19610: debug : virLockManagerRelease:369 : lock=0x7f6644002f10 state=(nil) flags=0
>2014-07-15 11:05:27.061+0000: 19610: debug : virLockManagerFree:391 : lock=0x7f6644002f10
>2014-07-15 11:05:27.062+0000: 19610: debug : qemuDomainObjEndJob:992 : Stopping job: modify (async=none)
>2014-07-15 11:05:27.062+0000: 19610: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f664c01e880
>2014-07-15 11:05:27.062+0000: 19610: debug : virDomainFree:2281 : dom=0x7f66440027c0, (VM: name=rhel6, uuid=bb1991ca-2e71-4136-91aa-c1fbfd73245f)
>2014-07-15 11:05:27.062+0000: 19610: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f66440027c0
>2014-07-15 11:05:27.062+0000: 19610: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f66440027c0
>2014-07-15 11:05:27.062+0000: 19610: debug : virDomainDispose:264 : release domain 0x7f66440027c0 rhel6 bb1991ca-2e71-4136-91aa-c1fbfd73245f
>2014-07-15 11:05:27.062+0000: 19610: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f66480009c0
>2014-07-15 11:05:27.062+0000: 19610: debug : virNetServerProgramSendError:150 : prog=536903814 ver=1 proc=266 type=1 serial=5 msg=0x1173d80 rerr=0x7f6659afcb80
>2014-07-15 11:05:27.062+0000: 19610: debug : virNetMessageEncodePayload:358 : Encode length as 352
>2014-07-15 11:05:27.062+0000: 19610: debug : virNetServerClientSendMessageLocked:1122 : msg=0x1173d80 proc=266 len=352 offset=0
>2014-07-15 11:05:27.062+0000: 19610: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x11791a0 len=352 prog=536903814 vers=1 proc=266 type=1 status=1 serial=5
>2014-07-15 11:05:27.062+0000: 19610: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1173050 tx=0x1173d80
>2014-07-15 11:05:27.062+0000: 19610: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-07-15 11:05:27.062+0000: 19610: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=3
>2014-07-15 11:05:27.062+0000: 19610: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-07-15 11:05:27.062+0000: 19610: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11767c0
>2014-07-15 11:05:27.062+0000: 19610: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11791a0
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=5 d=0
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1405422332022
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 1405422332022 due in 4960 ms
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4960
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=9 events=2
>2014-07-15 11:05:27.062+0000: 19609: debug : virNetMessageFree:73 : msg=0x1173d80 nfds=0 cb=(nil)
>2014-07-15 11:05:27.062+0000: 19609: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1173050 tx=(nil)
>2014-07-15 11:05:27.062+0000: 19609: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.062+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1405422332022
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 1405422332022 due in 4959 ms
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4959
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.063+0000: 19609: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2014-07-15 11:05:27.063+0000: 19609: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1173050 tx=(nil)
>2014-07-15 11:05:27.063+0000: 19609: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.063+0000: 19609: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x11791a0 len=28 prog=536903814 vers=1 proc=2 type=0 status=0 serial=6
>2014-07-15 11:05:27.063+0000: 19609: debug : virKeepAliveCheckMessage:384 : ka=0x11737f0, client=0x11791a0, msg=0x1173050
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=2 frequency=5000
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.063+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x11791a0
>2014-07-15 11:05:27.063+0000: 19609: debug : virNetServerDispatchNewMessage:218 : server=0x116d1b0 client=0x11791a0 message=0x1173050
>2014-07-15 11:05:27.063+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x11767c0
>2014-07-15 11:05:27.063+0000: 19609: debug : virNetMessageNew:45 : msg=0x1173960 tracked=1
>2014-07-15 11:05:27.063+0000: 19609: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1173960 tx=(nil)
>2014-07-15 11:05:27.063+0000: 19611: debug : virNetServerHandleJob:189 : server=0x116d1b0 client=0x11791a0 message=0x1173050 prog=0x11767c0
>2014-07-15 11:05:27.063+0000: 19609: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-15 11:05:27.063+0000: 19611: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=6 proc=2
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=1
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.063+0000: 19611: debug : remoteDispatchCloseHelper:124 : server=0x116d1b0 client=0x11791a0 msg=0x1173050 rerr=0x7f66590fbb80 args=0x7f664c0c5040 ret=0x7f664c0c4d30
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.063+0000: 19611: debug : virNetMessageEncodePayload:358 : Encode length as 28
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.063+0000: 19611: debug : virNetServerClientSendMessageLocked:1122 : msg=0x1173050 proc=2 len=28 offset=0
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.063+0000: 19611: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x11791a0 len=28 prog=536903814 vers=1 proc=2 type=1 status=0 serial=6
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.063+0000: 19611: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1173960 tx=0x1173050
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.063+0000: 19611: debug : virNetServerClientCalculateHandleMode:183 : mode=2
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.063+0000: 19611: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=2
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1405422332063
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 1405422332063 due in 5000 ms
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=5000
>2014-07-15 11:05:27.063+0000: 19611: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-07-15 11:05:27.063+0000: 19611: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11767c0
>2014-07-15 11:05:27.063+0000: 19611: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11791a0
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=4 d=0
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1405422332063
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 1405422332063 due in 5000 ms
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=5000
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=9 events=2
>2014-07-15 11:05:27.063+0000: 19609: debug : virNetMessageFree:73 : msg=0x1173050 nfds=0 cb=(nil)
>2014-07-15 11:05:27.063+0000: 19609: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1173960 tx=(nil)
>2014-07-15 11:05:27.063+0000: 19609: debug : virNetServerClientCalculateHandleMode:183 : mode=0
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=0
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.063+0000: 19609: debug : virNetServerClientClose:623 : client=0x11791a0
>2014-07-15 11:05:27.063+0000: 19609: debug : virKeepAliveStop:320 : RPC_KEEPALIVE_STOP: ka=0x11737f0 client=0x11791a0
>2014-07-15 11:05:27.063+0000: 19609: debug : virEventPollRemoveTimeout:288 : EVENT_POLL_REMOVE_TIMEOUT: timer=2
>2014-07-15 11:05:27.064+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 1606113376
>2014-07-15 11:05:27.064+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x11791a0
>2014-07-15 11:05:27.064+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11737f0
>2014-07-15 11:05:27.064+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11791a0
>2014-07-15 11:05:27.064+0000: 19609: debug : virObjectRef:168 : OBJECT_REF: obj=0x11791a0
>2014-07-15 11:05:27.064+0000: 19609: debug : daemonRemoveAllClientStreams:480 : stream=(nil)
>2014-07-15 11:05:27.064+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11791a0
>2014-07-15 11:05:27.064+0000: 19609: debug : virEventPollRemoveHandle:175 : EVENT_POLL_REMOVE_HANDLE: watch=9
>2014-07-15 11:05:27.064+0000: 19609: debug : virEventPollRemoveHandle:188 : mark delete 8 18
>2014-07-15 11:05:27.064+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 1606113376
>2014-07-15 11:05:27.064+0000: 19609: debug : virNetMessageFree:73 : msg=0x1173960 nfds=0 cb=(nil)
>2014-07-15 11:05:27.064+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1178f60
>2014-07-15 11:05:27.064+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11791a0
>2014-07-15 11:05:27.064+0000: 19609: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-15 11:05:27.064+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-15 11:05:27.064+0000: 19609: debug : virEventPollCleanupTimeouts:514 : EVENT_POLL_PURGE_TIMEOUT: timer=2
>2014-07-15 11:05:27.064+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11737f0
>2014-07-15 11:05:27.064+0000: 19609: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x11737f0
>2014-07-15 11:05:27.064+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11791a0
>2014-07-15 11:05:27.064+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-07-15 11:05:27.064+0000: 19609: debug : virEventPollCleanupHandles:562 : EVENT_POLL_PURGE_HANDLE: watch=9
>2014-07-15 11:05:27.064+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11791a0
>2014-07-15 11:05:27.064+0000: 19609: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x11791a0
>2014-07-15 11:05:27.064+0000: 19609: debug : virConnectClose:1449 : conn=0x7f66480009c0
>2014-07-15 11:05:27.064+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f66480009c0
>2014-07-15 11:05:27.064+0000: 19609: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f66480009c0
>2014-07-15 11:05:27.064+0000: 19609: debug : qemuDriverCloseCallbackRunAll:785 : conn=0x7f66480009c0
>2014-07-15 11:05:27.064+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f6648000930
>2014-07-15 11:05:27.064+0000: 19609: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f6648000930
>2014-07-15 11:05:27.064+0000: 19609: debug : virEventPollRemoveTimeout:288 : EVENT_POLL_REMOVE_TIMEOUT: timer=1
>2014-07-15 11:05:27.064+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1606113376
>2014-07-15 11:05:27.064+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1178f60
>2014-07-15 11:05:27.064+0000: 19609: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x1178f60
>2014-07-15 11:05:27.064+0000: 19609: debug : virNetSocketDispose:889 : sock=0x1178f60 fd=18
>2014-07-15 11:05:27.064+0000: 19609: debug : virEventPollRemoveHandle:175 : EVENT_POLL_REMOVE_HANDLE: watch=9
>2014-07-15 11:05:27.064+0000: 19609: debug : virFileClose:72 : Closed fd 18
>2014-07-15 11:05:27.064+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-15 11:05:27.064+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-15 11:05:27.064+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-15 11:05:27.064+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-15 11:05:27.064+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-15 11:05:27.064+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=20 e=1 d=0
>2014-07-15 11:05:27.064+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-15 11:05:27.064+0000: 19609: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=23 e=25 d=0
>2014-07-15 11:05:27.064+0000: 19609: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2014-07-15 11:05:27.064+0000: 19609: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2014-07-15 11:05:27.064+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=-1
>2014-07-15 11:05:27.130+0000: 19609: debug : virEventPollRunOnce:617 : Poll got error event 4
>2014-07-15 11:05:27.130+0000: 19609: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=-1
>2014-07-15 11:05:27.130+0000: 19609: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-15 11:05:27.130+0000: 19609: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2014-07-15 11:05:27.130+0000: 19609: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2014-07-15 11:05:27.130+0000: 19609: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-15 11:05:27.130+0000: 19609: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-15 11:05:27.130+0000: 19609: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=2 events=1
>2014-07-15 11:05:27.130+0000: 19609: debug : virNetServerQuit:788 : Quit requested 0x116d1b0
>2014-07-15 11:05:27.130+0000: 19609: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-15 11:05:27.130+0000: 19609: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-15 11:05:27.130+0000: 19609: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-15 11:05:27.130+0000: 19609: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-15 11:05:27.130+0000: 19609: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-15 11:05:27.130+0000: 19609: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-15 11:05:27.130+0000: 19609: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-07-15 11:05:27.130+0000: 19609: debug : virEventPollCleanupTimeouts:514 : EVENT_POLL_PURGE_TIMEOUT: timer=1
>2014-07-15 11:05:27.130+0000: 19609: debug : virEventPollCleanupTimeouts:537 : Found 0 out of 10 timeout slots used, releasing 10
>2014-07-15 11:05:27.130+0000: 19609: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-15 11:05:27.131+0000: 19609: debug : virHookCheck:128 : No hook script /etc/libvirt/hooks/daemon
>2014-07-15 11:05:27.131+0000: 19609: debug : virHookCheck:128 : No hook script /etc/libvirt/hooks/qemu
>2014-07-15 11:05:27.131+0000: 19609: debug : virHookCheck:128 : No hook script /etc/libvirt/hooks/lxc
>2014-07-15 11:05:27.131+0000: 19609: debug : virHookCheck:128 : No hook script /etc/libvirt/hooks/network
>2014-07-15 11:05:27.131+0000: 19609: info : virNetlinkEventServiceStopAll:420 : stopping all netlink event services
>2014-07-15 11:05:27.131+0000: 19609: debug : virEventPollRemoveHandle:175 : EVENT_POLL_REMOVE_HANDLE: watch=5
>2014-07-15 11:05:27.131+0000: 19609: debug : virEventPollRemoveHandle:188 : mark delete 4 6
>2014-07-15 11:05:27.131+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 1606113376
>2014-07-15 11:05:27.131+0000: 19609: debug : virEventPollRemoveHandle:175 : EVENT_POLL_REMOVE_HANDLE: watch=6
>2014-07-15 11:05:27.131+0000: 19609: debug : virEventPollRemoveHandle:188 : mark delete 5 20
>2014-07-15 11:05:27.131+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 1606113376
>2014-07-15 11:05:27.131+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11767c0
>2014-07-15 11:05:27.131+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x116e590
>2014-07-15 11:05:27.131+0000: 19609: debug : virFileClose:72 : Closed fd 12
>2014-07-15 11:05:27.131+0000: 19609: debug : virFileClose:72 : Closed fd 13
>2014-07-15 11:05:27.131+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x116d1b0
>2014-07-15 11:05:27.131+0000: 19609: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x116d1b0
>2014-07-15 11:05:27.131+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=3 events=0
>2014-07-15 11:05:27.131+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 1606113376
>2014-07-15 11:05:27.131+0000: 19609: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=4 events=0
>2014-07-15 11:05:27.131+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 1606113376
>2014-07-15 11:05:27.132+0000: 19609: debug : virFileClose:72 : Closed fd 9
>2014-07-15 11:05:27.132+0000: 19609: debug : virFileClose:72 : Closed fd 10
>2014-07-15 11:05:27.132+0000: 19609: debug : virEventPollRemoveHandle:175 : EVENT_POLL_REMOVE_HANDLE: watch=2
>2014-07-15 11:05:27.132+0000: 19609: debug : virEventPollRemoveHandle:188 : mark delete 1 9
>2014-07-15 11:05:27.132+0000: 19609: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 1606113376
>2014-07-15 11:05:27.132+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x116a7c0
>2014-07-15 11:05:27.132+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x116d100
>2014-07-15 11:05:27.132+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11767c0
>2014-07-15 11:05:27.132+0000: 19609: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x11767c0
>2014-07-15 11:05:27.132+0000: 19609: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x116e590
>2014-07-15 11:05:27.132+0000: 19609: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x116e590
>2014-07-15 11:05:27.132+0000: 19609: debug : virFileClose:72 : Closed fd 5

Actions: View

Attachments on bug 924853: 918142 | 918143 | 918284 | 918285