Attachment 939926 Details for Bug 1145028 – RHEL6 sendkey debug log

RHEL6 sendkey debug log

sendkey.text (text/plain), 179.13 KB, created by Luyao Huang on 2014-09-22 08:55:25 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Luyao Huang

Created: 2014-09-22 08:55:25 UTC

Size: 179.13 KB

patch

obsolete

>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=3 events=1
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=3 events=1
>2014-09-22 08:00:22.320+0000: 29478: debug : virNetSocketNew:147 : localAddr=0x7fff34341df0 remoteAddr=0x7fff34341d60 fd=18 errfd=-1 pid=0
>2014-09-22 08:00:22.320+0000: 29478: debug : virObjectNew:110 : OBJECT_NEW: obj=0x1235ab0 classname=virNetSocket
>2014-09-22 08:00:22.320+0000: 29478: debug : virNetSocketNew:204 : RPC_SOCKET_NEW: sock=0x1235ab0 fd=18 errfd=-1 pid=0 localAddr=127.0.0.1;0, remoteAddr=127.0.0.1;0
>2014-09-22 08:00:22.320+0000: 29478: debug : virNetServerClientNew:403 : sock=0x1235ab0 auth=2 tls=(nil)
>2014-09-22 08:00:22.320+0000: 29478: debug : virObjectNew:110 : OBJECT_NEW: obj=0x1235cf0 classname=virNetServerClient
>2014-09-22 08:00:22.320+0000: 29478: debug : virObjectNew:110 : OBJECT_NEW: obj=0x1235cf0 classname=virNetServerClient
>2014-09-22 08:00:22.320+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1235ab0
>2014-09-22 08:00:22.320+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1235ab0
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollAddTimeout:243 : EVENT_POLL_ADD_TIMEOUT: timer=3 frequency=-1 cb=0x7f00a219cb40 opaque=0x1235cf0 ff=(nil)
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollAddTimeout:243 : EVENT_POLL_ADD_TIMEOUT: timer=3 frequency=-1 cb=0x7f00a219cb40 opaque=0x1235cf0 ff=(nil)
>2014-09-22 08:00:22.320+0000: 29478: debug : virNetMessageNew:45 : msg=0x1235dd0 tracked=1
>2014-09-22 08:00:22.320+0000: 29478: debug : virNetServerClientNewInternal:382 : RPC_SERVER_CLIENT_NEW: client=0x1235cf0 sock=0x1235ab0
>2014-09-22 08:00:22.320+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1235dd0 tx=(nil)
>2014-09-22 08:00:22.320+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.320+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.320+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1235cf0
>2014-09-22 08:00:22.320+0000: 29478: debug : virNetServerClientRegisterEvent:199 : Registering client event callback 1
>2014-09-22 08:00:22.320+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1235ab0
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollAddHandle:136 : EVENT_POLL_ADD_HANDLE: watch=11 fd=18 events=1 cb=0x7f00a219ec20 opaque=0x1235ab0 ff=0x7f00a219f010
>2014-09-22 08:00:22.320+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1235cf0
>2014-09-22 08:00:22.320+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1235cf0
>2014-09-22 08:00:22.320+0000: 29478: debug : virKeepAliveNew:212 : client=0x1235cf0, interval=5, count=5
>2014-09-22 08:00:22.320+0000: 29478: debug : virKeepAliveNew:212 : client=0x1235cf0, interval=5, count=5
>2014-09-22 08:00:22.320+0000: 29478: debug : virObjectNew:110 : OBJECT_NEW: obj=0x1236320 classname=virKeepAlive
>2014-09-22 08:00:22.320+0000: 29478: debug : virObjectNew:110 : OBJECT_NEW: obj=0x1236320 classname=virKeepAlive
>2014-09-22 08:00:22.320+0000: 29478: debug : virKeepAliveNew:236 : RPC_KEEPALIVE_NEW: ka=0x1236320 client=0x1235cf0
>2014-09-22 08:00:22.320+0000: 29478: debug : virKeepAliveNew:236 : RPC_KEEPALIVE_NEW: ka=0x1236320 client=0x1235cf0
>2014-09-22 08:00:22.320+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1235cf0
>2014-09-22 08:00:22.320+0000: 29478: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1235cf0
>2014-09-22 08:00:22.320+0000: 29478: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1235cf0
>2014-09-22 08:00:22.320+0000: 29478: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1235ab0
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollCleanupTimeouts:514 : EVENT_POLL_PURGE_TIMEOUT: timer=1
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=-1
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=-1
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.320+0000: 29478: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2014-09-22 08:00:22.320+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1235dd0 tx=(nil)
>2014-09-22 08:00:22.320+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.320+0000: 29478: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2014-09-22 08:00:22.320+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1235dd0 tx=(nil)
>2014-09-22 08:00:22.320+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.320+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.320+0000: 29478: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x1235cf0 len=28 prog=536903814 vers=1 proc=66 type=0 status=0 serial=0
>2014-09-22 08:00:22.321+0000: 29478: debug : virKeepAliveCheckMessage:384 : ka=0x1236320, client=0x1235cf0, msg=0x1235dd0
>2014-09-22 08:00:22.321+0000: 29478: debug : virKeepAliveCheckMessage:384 : ka=0x1236320, client=0x1235cf0, msg=0x1235dd0
>2014-09-22 08:00:22.321+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1235cf0
>2014-09-22 08:00:22.321+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1235cf0
>2014-09-22 08:00:22.321+0000: 29478: debug : virNetServerDispatchNewMessage:218 : server=0x12311b0 client=0x1235cf0 message=0x1235dd0
>2014-09-22 08:00:22.321+0000: 29478: debug : virNetServerDispatchNewMessage:218 : server=0x12311b0 client=0x1235cf0 message=0x1235dd0
>2014-09-22 08:00:22.321+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1239b30
>2014-09-22 08:00:22.321+0000: 29478: debug : virNetMessageNew:45 : msg=0x1236f80 tracked=1
>2014-09-22 08:00:22.321+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1236f80 tx=(nil)
>2014-09-22 08:00:22.321+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.321+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.321+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.321+0000: 29482: debug : virNetServerHandleJob:189 : server=0x12311b0 client=0x1235cf0 message=0x1235dd0 prog=0x1239b30
>2014-09-22 08:00:22.321+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.321+0000: 29482: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=0 proc=66
>2014-09-22 08:00:22.321+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-09-22 08:00:22.321+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.321+0000: 29482: debug : remoteDispatchAuthListHelper:18 : server=0x12311b0 client=0x1235cf0 msg=0x1235dd0 rerr=0x7f009a3a3b80 args=0x7f0084000cc0 ret=0x7f0084000ce0
>2014-09-22 08:00:22.321+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.321+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-09-22 08:00:22.321+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.321+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.321+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.321+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.321+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.321+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.321+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.321+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.321+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.321+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.321+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.321+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.321+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2014-09-22 08:00:22.321+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2014-09-22 08:00:22.321+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2014-09-22 08:00:22.321+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=-1
>8: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=-1
>2014-09-22 08:00:22.321+0000: 29482: debug : virFileClose:72 : Closed fd 19
>2014-09-22 08:00:22.321+0000: 29482: info : remoteDispatchAuthList:2432 : Bypass polkit auth for privileged client pid:29753,uid:0
>2014-09-22 08:00:22.322+0000: 29482: debug : virNetMessageEncodePayload:377 : Encode length as 36
>2014-09-22 08:00:22.322+0000: 29482: debug : virNetServerClientSendMessageLocked:1122 : msg=0x1235dd0 proc=66 len=36 offset=0
>2014-09-22 08:00:22.322+0000: 29482: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1235cf0 len=36 prog=536903814 vers=1 proc=66 type=1 status=0 serial=0
>2014-09-22 08:00:22.322+0000: 29482: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1236f80 tx=0x1235dd0
>2014-09-22 08:00:22.322+0000: 29482: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-09-22 08:00:22.322+0000: 29482: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-09-22 08:00:22.322+0000: 29482: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=3
>2014-09-22 08:00:22.322+0000: 29482: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-09-22 08:00:22.322+0000: 29482: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1239b30
>2014-09-22 08:00:22.322+0000: 29482: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1235cf0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=5 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=-1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=2
>2014-09-22 08:00:22.322+0000: 29478: debug : virNetMessageFree:73 : msg=0x1235dd0 nfds=0 cb=(nil)
>2014-09-22 08:00:22.322+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1236f80 tx=(nil)
>2014-09-22 08:00:22.322+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=-1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=2
>2014-09-22 08:00:22.322+0000: 29478: debug : virNetMessageFree:73 : msg=0x1235dd0 nfds=0 cb=(nil)
>2014-09-22 08:00:22.322+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1236f80 tx=(nil)
>2014-09-22 08:00:22.322+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=-1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.322+0000: 29478: debug : virNetMessageDecodeLength:152 : Got length, now need 32 total (28 more)
>2014-09-22 08:00:22.322+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1236f80 tx=(nil)
>2014-09-22 08:00:22.322+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.322+0000: 29478: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x1235cf0 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=1
>2014-09-22 08:00:22.323+0000: 29478: debug : virKeepAliveCheckMessage:384 : ka=0x1236320, client=0x1235cf0, msg=0x1236f80
>2014-09-22 08:00:22.322+0000: 29478: debug : virNetMessageDecodeLength:152 : Got length, now need 32 total (28 more)
>2014-09-22 08:00:22.322+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1236f80 tx=(nil)
>2014-09-22 08:00:22.322+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.322+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.322+0000: 29478: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x1235cf0 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=1
>2014-09-22 08:00:22.323+0000: 29478: debug : virKeepAliveCheckMessage:384 : ka=0x1236320, client=0x1235cf0, msg=0x1236f80
>2014-09-22 08:00:22.323+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1235cf0
>2014-09-22 08:00:22.323+0000: 29478: debug : virNetServerDispatchNewMessage:218 : server=0x12311b0 client=0x1235cf0 message=0x1236f80
>2014-09-22 08:00:22.323+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1235cf0
>2014-09-22 08:00:22.323+0000: 29478: debug : virNetServerDispatchNewMessage:218 : server=0x12311b0 client=0x1235cf0 message=0x1236f80
>2014-09-22 08:00:22.323+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1239b30
>2014-09-22 08:00:22.323+0000: 29478: debug : virNetMessageNew:45 : msg=0x12354e0 tracked=1
>2014-09-22 08:00:22.323+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x12354e0 tx=(nil)
>2014-09-22 08:00:22.323+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x12354e0 tx=(nil)
>2014-09-22 08:00:22.323+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.323+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.323+0000: 29483: debug : virNetServerHandleJob:189 : server=0x12311b0 client=0x1235cf0 message=0x1236f80 prog=0x1239b30
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.323+0000: 29483: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=1 proc=60
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.323+0000: 29483: debug : remoteDispatchSupportsFeatureHelper:13350 : server=0x12311b0 client=0x1235cf0 msg=0x1236f80 rerr=0x7f00999a2b80 args=0x7f00780008c0 ret=0x7f00780008e0
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.323+0000: 29483: debug : virKeepAliveStart:289 : RPC_KEEPALIVE_START: ka=0x1236320 client=0x1235cf0 interval=0 count=0
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=-1
>2014-09-22 08:00:22.323+0000: 29483: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-09-22 08:00:22.323+0000: 29483: debug : virEventPollAddTimeout:243 : EVENT_POLL_ADD_TIMEOUT: timer=4 frequency=5000 cb=0x7f00a21a87a0 opaque=0x1236320 ff=0x41f248
>2014-09-22 08:00:22.323+0000: 29483: debug : virObjectRef:168 : OBJECT_REF: obj=0x1236320
>2014-09-22 08:00:22.323+0000: 29483: debug : virObjectRef:168 : OBJECT_REF: obj=0x1236320
>2014-09-22 08:00:22.323+0000: 29483: debug : virNetMessageEncodePayload:377 : Encode length as 32
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.323+0000: 29483: debug : virNetServerClientSendMessageLocked:1122 : msg=0x1236f80 proc=60 len=32 offset=0
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.323+0000: 29483: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1235cf0 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=1
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.323+0000: 29483: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x12354e0 tx=0x1236f80
>2014-09-22 08:00:22.323+0000: 29483: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x12354e0 tx=0x1236f80
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.323+0000: 29483: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-09-22 08:00:22.323+0000: 29483: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.323+0000: 29483: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=3
>2014-09-22 08:00:22.323+0000: 29483: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=3
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.323+0000: 29483: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 -1576822688
>2014-09-22 08:00:22.323+0000: 29483: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1239b30
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.323+0000: 29483: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1235cf0
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.323+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=5 d=0
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827323
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827323 due in 4999 ms
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4999
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4999
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=2
>2014-09-22 08:00:22.324+0000: 29478: debug : virNetMessageFree:73 : msg=0x1236f80 nfds=0 cb=(nil)
>2014-09-22 08:00:22.324+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x12354e0 tx=(nil)
>2014-09-22 08:00:22.324+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.324+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>ollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827323
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827323 due in 4999 ms
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4999
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.324+0000: 29478: debug : virNetMessageDecodeLength:152 : Got length, now need 40 total (36 more)
>2014-09-22 08:00:22.324+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x12354e0 tx=(nil)
>2014-09-22 08:00:22.324+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x12354e0 tx=(nil)
>2014-09-22 08:00:22.324+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.324+0000: 29478: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x1235cf0 len=40 prog=536903814 vers=1 proc=1 type=0 status=0 serial=2
>2014-09-22 08:00:22.324+0000: 29478: debug : virKeepAliveCheckMessage:384 : ka=0x1236320, client=0x1235cf0, msg=0x12354e0
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=4 frequency=5000
>2014-09-22 08:00:22.324+0000: 29478: debug : virKeepAliveCheckMessage:384 : ka=0x1236320, client=0x1235cf0, msg=0x12354e0
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=4 frequency=5000
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.324+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1235cf0
>2014-09-22 08:00:22.324+0000: 29478: debug : virNetServerDispatchNewMessage:218 : server=0x12311b0 client=0x1235cf0 message=0x12354e0
>:218 : server=0x12311b0 client=0x1235cf0 message=0x12354e0
>2014-09-22 08:00:22.324+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1239b30
>2014-09-22 08:00:22.324+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1239b30
>2014-09-22 08:00:22.324+0000: 29478: debug : virNetMessageNew:45 : msg=0x1234c50 tracked=1
>2014-09-22 08:00:22.324+0000: 29479: debug : virNetServerHandleJob:189 : server=0x12311b0 client=0x1235cf0 message=0x12354e0 prog=0x1239b30
>2014-09-22 08:00:22.324+0000: 29479: debug : virNetServerHandleJob:189 : server=0x12311b0 client=0x1235cf0 message=0x12354e0 prog=0x1239b30
>2014-09-22 08:00:22.324+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1234c50 tx=(nil)
>2014-09-22 08:00:22.324+0000: 29479: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=2 proc=1
>2014-09-22 08:00:22.324+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.324+0000: 29479: debug : remoteDispatchOpenHelper:11127 : server=0x12311b0 client=0x1235cf0 msg=0x12354e0 rerr=0x7f009c1a6b80 args=0x7f0088002950 ret=0x7f0088002110
>2014-09-22 08:00:22.324+0000: 29479: debug : remoteDispatchOpen:766 : priv=0x1235f90 conn=(nil)
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.324+0000: 29479: debug : virConnectOpen:1331 : name=
>2014-09-22 08:00:22.324+0000: 29479: debug : virConnectOpen:1331 : name=
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.324+0000: 29479: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f00880029b0 classname=virConnect
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.324+0000: 29479: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f0088002ae0 classname=virConnectCloseCallbackData
>2014-09-22 08:00:22.324+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.325+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.325+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.325+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.325+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.325+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.325+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.325+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.325+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.325+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.325+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.325+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.325+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.325+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827324
>2014-09-22 08:00:22.325+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827324 due in 4999 ms
>2014-09-22 08:00:22.325+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4999
>2014-09-22 08:00:22.325+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4999
>2014-09-22 08:00:22.325+0000: 29479: debug : virConnectGetConfigFile:953 : Loading config file '/etc/libvirt/libvirt.conf'
>2014-09-22 08:00:22.325+0000: 29479: debug : virFileClose:72 : Closed fd 19
>2014-09-22 08:00:22.325+0000: 29479: debug : do_open:1131 : no name, allowing driver auto-select
>2014-09-22 08:00:22.325+0000: 29479: debug : do_open:1174 : trying driver 0 (Test) ...
>2014-09-22 08:00:22.325+0000: 29479: debug : do_open:1174 : trying driver 0 (Test) ...
>2014-09-22 08:00:22.325+0000: 29479: debug : do_open:1180 : driver 0 Test returned DECLINED
>2014-09-22 08:00:22.325+0000: 29479: debug : do_open:1174 : trying driver 1 (ESX) ...
> 1 (ESX) ...
>2014-09-22 08:00:22.325+0000: 29479: debug : do_open:1180 : driver 1 ESX returned DECLINED
>2014-09-22 08:00:22.325+0000: 29479: debug : do_open:1180 : driver 1 ESX returned DECLINED
>2014-09-22 08:00:22.325+0000: 29479: debug : do_open:1174 : trying driver 2 (remote) ...
>2014-09-22 08:00:22.325+0000: 29479: debug : do_open:1180 : driver 2 remote returned DECLINED
>2014-09-22 08:00:22.325+0000: 29479: debug : do_open:1174 : trying driver 3 (QEMU) ...
>2014-09-22 08:00:22.325+0000: 29479: debug : do_open:1180 : driver 3 QEMU returned SUCCESS
>2014-09-22 08:00:22.325+0000: 29479: debug : do_open:1202 : network driver 0 Test returned DECLINED
>2014-09-22 08:00:22.325+0000: 29479: debug : do_open:1202 : network driver 0 Test returned DECLINED
>2014-09-22 08:00:22.325+0000: 29479: debug : do_open:1202 : network driver 1 ESX returned DECLINED
>2014-09-22 08:00:22.325+0000: 29479: debug : do_open:1202 : network driver 1 ESX returned DECLINED
>2014-09-22 08:00:22.325+0000: 29479: debug : do_open:1202 : network driver 2 remote returned DECLINED
>2014-09-22 08:00:22.325+0000: 29479: debug : do_open:1202 : network driver 3 Network returned SUCCESS
>2014-09-22 08:00:22.325+0000: 29479: debug : do_open:1217 : interface driver 0 Test returned DECLINED
>2014-09-22 08:00:22.325+0000: 29479: debug : do_open:1217 : interface driver 1 ESX returned DECLINED
>2014-09-22 08:00:22.325+0000: 29479: debug : do_open:1217 : interface driver 1 ESX returned DECLINED
>2014-09-22 08:00:22.325+0000: 29479: debug : do_open:1217 : interface driver 2 remote returned DECLINED
>2014-09-22 08:00:22.325+0000: 29479: debug : do_open:1217 : interface driver 2 remote returned DECLINED
>2014-09-22 08:00:22.338+0000: 29479: debug : do_open:1217 : interface driver 3 Interface returned SUCCESS
>2014-09-22 08:00:22.339+0000: 29479: debug : do_open:1233 : storage driver 0 Test returned DECLINED
>2014-09-22 08:00:22.339+0000: 29479: debug : do_open:1233 : storage driver 1 ESX returned DECLINED
>2014-09-22 08:00:22.339+0000: 29479: debug : do_open:1233 : storage driver 1 ESX returned DECLINED
>2014-09-22 08:00:22.339+0000: 29479: debug : do_open:1233 : storage driver 2 remote returned DECLINED
>2014-09-22 08:00:22.339+0000: 29479: debug : do_open:1233 : storage driver 3 storage returned SUCCESS
>2014-09-22 08:00:22.339+0000: 29479: debug : do_open:1249 : node driver 0 Test returned DECLINED
>2014-09-22 08:00:22.339+0000: 29479: debug : do_open:1249 : node driver 1 ESX returned DECLINED
>2014-09-22 08:00:22.339+0000: 29479: debug : do_open:1249 : node driver 2 remote returned DECLINED
>2014-09-22 08:00:22.339+0000: 29479: debug : do_open:1249 : node driver 3 udevDeviceMonitor returned SUCCESS
>2014-09-22 08:00:22.339+0000: 29479: debug : do_open:1265 : secret driver 0 Test returned DECLINED
>2014-09-22 08:00:22.339+0000: 29479: debug : do_open:1265 : secret driver 0 Test returned DECLINED
>2014-09-22 08:00:22.339+0000: 29479: debug : do_open:1265 : secret driver 1 ESX returned DECLINED
>2014-09-22 08:00:22.339+0000: 29479: debug : do_open:1265 : secret driver 1 ESX returned DECLINED
>2014-09-22 08:00:22.339+0000: 29479: debug : do_open:1265 : secret driver 2 remote returned DECLINED
>2014-09-22 08:00:22.339+0000: 29479: debug : do_open:1265 : secret driver 3 secret returned SUCCESS
>2014-09-22 08:00:22.339+0000: 29479: debug : do_open:1281 : nwfilter driver 0 Test returned DECLINED
>2014-09-22 08:00:22.339+0000: 29479: debug : do_open:1281 : nwfilter driver 1 ESX returned DECLINED
>2014-09-22 08:00:22.339+0000: 29479: debug : do_open:1281 : nwfilter driver 2 remote returned DECLINED
>2014-09-22 08:00:22.339+0000: 29479: debug : do_open:1281 : nwfilter driver 3 nwfilter returned SUCCESS
>2014-09-22 08:00:22.339+0000: 29479: debug : do_open:1281 : nwfilter driver 3 nwfilter returned SUCCESS
>2014-09-22 08:00:22.339+0000: 29479: debug : virNetMessageEncodePayload:377 : Encode length as 28
>2014-09-22 08:00:22.339+0000: 29479: debug : virNetServerClientSendMessageLocked:1122 : msg=0x12354e0 proc=1 len=28 offset=0
>2014-09-22 08:00:22.339+0000: 29479: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1235cf0 len=28 prog=536903814 vers=1 proc=1 type=1 status=0 serial=2
>2014-09-22 08:00:22.339+0000: 29479: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1234c50 tx=0x12354e0
>2014-09-22 08:00:22.339+0000: 29479: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-09-22 08:00:22.339+0000: 29479: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-09-22 08:00:22.339+0000: 29479: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=3
>2014-09-22 08:00:22.339+0000: 29479: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-09-22 08:00:22.339+0000: 29479: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1239b30
>2014-09-22 08:00:22.339+0000: 29479: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1235cf0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=5 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827324
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827324 due in 4985 ms
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4985
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=2
>2014-09-22 08:00:22.339+0000: 29478: debug : virNetMessageFree:73 : msg=0x12354e0 nfds=0 cb=(nil)
>2014-09-22 08:00:22.339+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1234c50 tx=(nil)
>2014-09-22 08:00:22.339+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=5 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827324
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827324 due in 4985 ms
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4985
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=2
>2014-09-22 08:00:22.339+0000: 29478: debug : virNetMessageFree:73 : msg=0x12354e0 nfds=0 cb=(nil)
>2014-09-22 08:00:22.339+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1234c50 tx=(nil)
>2014-09-22 08:00:22.339+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.339+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827324
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827324 due in 4984 ms
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4984
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827324 due in 4984 ms
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4984
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.340+0000: 29478: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>9478: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2014-09-22 08:00:22.340+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1234c50 tx=(nil)
>2014-09-22 08:00:22.340+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1234c50 tx=(nil)
>2014-09-22 08:00:22.340+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.340+0000: 29478: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x1235cf0 len=28 prog=536903814 vers=1 proc=110 type=0 status=0 serial=3
>2014-09-22 08:00:22.340+0000: 29478: debug : virKeepAliveCheckMessage:384 : ka=0x1236320, client=0x1235cf0, msg=0x1234c50
>2014-09-22 08:00:22.340+0000: 29478: debug : virKeepAliveCheckMessage:384 : ka=0x1236320, client=0x1235cf0, msg=0x1234c50
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=4 frequency=5000
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.340+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1235cf0
>2014-09-22 08:00:22.340+0000: 29478: debug : virNetServerDispatchNewMessage:218 : server=0x12311b0 client=0x1235cf0 message=0x1234c50
>2014-09-22 08:00:22.340+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1239b30
>2014-09-22 08:00:22.340+0000: 29478: debug : virNetMessageNew:45 : msg=0x1234dd0 tracked=1
>2014-09-22 08:00:22.340+0000: 29480: debug : virNetServerHandleJob:189 : server=0x12311b0 client=0x1235cf0 message=0x1234c50 prog=0x1239b30
>2014-09-22 08:00:22.340+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1234dd0 tx=(nil)
>2014-09-22 08:00:22.340+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.340+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.340+0000: 29480: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=110
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.340+0000: 29480: debug : remoteDispatchGetURIHelper:7297 : server=0x12311b0 client=0x1235cf0 msg=0x1234c50 rerr=0x7f009b7a5b80 args=0x7f00800020d0 ret=0x7f0080001990
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.340+0000: 29480: debug : virConnectGetURI:1700 : conn=0x7f00880029b0
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.340+0000: 29480: debug : virNetMessageEncodePayload:377 : Encode length as 48
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.340+0000: 29480: debug : virNetServerClientSendMessageLocked:1122 : msg=0x1234c50 proc=110 len=48 offset=0
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.340+0000: 29480: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1235cf0 len=48 prog=536903814 vers=1 proc=110 type=1 status=0 serial=3
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.340+0000: 29480: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1234dd0 tx=0x1234c50
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827340
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827340
>2014-09-22 08:00:22.340+0000: 29480: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827340 due in 5000 ms
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=5000
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=5000
>2014-09-22 08:00:22.340+0000: 29480: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=3
>2014-09-22 08:00:22.340+0000: 29480: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=3
>2014-09-22 08:00:22.340+0000: 29480: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-09-22 08:00:22.340+0000: 29480: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1239b30
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.340+0000: 29480: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1235cf0
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.340+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>21 e=25 d=0
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=5 d=0
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=5 d=0
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827340
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827340 due in 4999 ms
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4999
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=2
>2014-09-22 08:00:22.341+0000: 29478: debug : virNetMessageFree:73 : msg=0x1234c50 nfds=0 cb=(nil)
>2014-09-22 08:00:22.341+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1234dd0 tx=(nil)
>2014-09-22 08:00:22.341+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.341+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827340
>eout:326 : Got a timeout scheduled for 1411372827340
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827340 due in 4999 ms
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827340 due in 4999 ms
>2014-09-22 08:00:22.341+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4999
>2014-09-22 08:00:22.342+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.342+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.342+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.342+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.342+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.342+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.342+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.342+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.342+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.342+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.342+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.342+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.342+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.342+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.342+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.342+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.342+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.343+0000: 29478: debug : virNetMessageDecodeLength:152 : Got length, now need 36 total (32 more)
>2014-09-22 08:00:22.343+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1234dd0 tx=(nil)
>2014-09-22 08:00:22.343+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1234dd0 tx=(nil)
>2014-09-22 08:00:22.343+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.343+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.343+0000: 29478: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x1235cf0 len=36 prog=536903814 vers=1 proc=23 type=0 status=0 serial=4
>2014-09-22 08:00:22.343+0000: 29478: debug : virKeepAliveCheckMessage:384 : ka=0x1236320, client=0x1235cf0, msg=0x1234dd0
>2014-09-22 08:00:22.343+0000: 29478: debug : virKeepAliveCheckMessage:384 : ka=0x1236320, client=0x1235cf0, msg=0x1234dd0
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=4 frequency=5000
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=4 frequency=5000
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.343+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1235cf0
>2014-09-22 08:00:22.343+0000: 29478: debug : virNetServerDispatchNewMessage:218 : server=0x12311b0 client=0x1235cf0 message=0x1234dd0
>2014-09-22 08:00:22.343+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1239b30
>2014-09-22 08:00:22.343+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1239b30
>2014-09-22 08:00:22.343+0000: 29478: debug : virNetMessageNew:45 : msg=0x1235390 tracked=1
>2014-09-22 08:00:22.343+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1235390 tx=(nil)
>2014-09-22 08:00:22.343+0000: 29481: debug : virNetServerHandleJob:189 : server=0x12311b0 client=0x1235cf0 message=0x1234dd0 prog=0x1239b30
>2014-09-22 08:00:22.343+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.343+0000: 29481: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=23
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.343+0000: 29481: debug : remoteDispatchDomainLookupByNameHelper:3084 : server=0x12311b0 client=0x12014-09-22 08:00:22.343+0000: 29481: debug : remoteDispatchDomainLookupByNameHelper:3084 : server=0x12311b0 client=0x1235cf0 msg=0x1234dd0 rerr=0x7f009ada4b80 args=0x7f00900a5e30 ret=0x7f00900a5ef0
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.343+0000: 29481: debug : virDomainLookupByName:2117 : conn=0x7f00880029b0, name=win7
>2014-09-22 08:00:22.343+0000: 29481: debug : virDomainLookupByName:2117 : conn=0x7f00880029b0, name=win7
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.343+0000: 29481: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f00900a5eb0 classname=virDomain
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.343+0000: 29481: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f00880029b0
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.343+0000: 29481: debug : virDomainFree:2281 : dom=0x7f00900a5eb0, (VM: name=win7, uuid=61a3467e-b4d0-58c6-e134-1ddd473d8c60)
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.343+0000: 29481: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f00900a5eb0
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.343+0000: 29481: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f00900a5eb0
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.343+0000: 29481: debug : virDomainDispose:264 : release domain 0x7f00900a5eb0 win7 61a3467e-b4d0-58c6-e134-1ddd473d8c60
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.343+0000: 29481: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f00880029b0
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.343+0000: 29481: debug : virNetMessageEncodePayload:377 : Encode length as 56
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827343
>2014-09-22 08:00:22.343+0000: 29481: debug : virNetServerClientSendMessageLocked:1122 : msg=0x1234dd0 proc=23 len=56 offset=0
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827343 due in 5000 ms
>2014-09-22 08:00:22.343+0000: 29481: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1235cf0 len=56 prog=536903814 vers=1 proc=23 type=1 status=0 serial=4
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=5000
>2014-09-22 08:00:22.343+0000: 29481: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1235390 tx=0x1234dd0
>2014-09-22 08:00:22.343+0000: 29481: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-09-22 08:00:22.343+0000: 29481: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=3
>2014-09-22 08:00:22.343+0000: 29481: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-09-22 08:00:22.343+0000: 29481: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1239b30
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.343+0000: 29481: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1235cf0
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.343+0000: 29481: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f00880029b0
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.343+0000: 29481: debug : virDomainFree:2281 : dom=0x7f00900a5eb0, (VM: name=win7, uuid=61a3467e-b4d0-58c6-e134-1ddd473d8c60)
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.343+0000: 29481: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f00900a5eb0
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.343+0000: 29481: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f00900a5eb0
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.343+0000: 29481: debug : virDomainDispose:264 : release domain 0x7f00900a5eb0 win7 61a3467e-b4d0-58c6-e134-1ddd473d8c60
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.343+0000: 29481: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f00880029b0
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.343+0000: 29481: debug : virNetMessageEncodePayload:377 : Encode length as 56
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827343
>2014-09-22 08:00:22.343+0000: 29481: debug : virNetServerClientSendMessageLocked:1122 : msg=0x1234dd0 proc=23 len=56 offset=0
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827343 due in 5000 ms
>2014-09-22 08:00:22.343+0000: 29481: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1235cf0 len=56 prog=536903814 vers=1 proc=23 type=1 status=0 serial=4
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=5000
>2014-09-22 08:00:22.343+0000: 29481: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1235390 tx=0x1234dd0
>2014-09-22 08:00:22.343+0000: 29481: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-09-22 08:00:22.343+0000: 29481: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=3
>2014-09-22 08:00:22.343+0000: 29481: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-09-22 08:00:22.343+0000: 29481: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1239b30
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.343+0000: 29481: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1235cf0
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.343+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=5 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=5 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827343
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827343 due in 4999 ms
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4999
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>lDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=2
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=2
>2014-09-22 08:00:22.344+0000: 29478: debug : virNetMessageFree:73 : msg=0x1234dd0 nfds=0 cb=(nil)
>2014-09-22 08:00:22.344+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1235390 tx=(nil)
>2014-09-22 08:00:22.344+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.344+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827343
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827343 due in 4999 ms
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4999
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.344+0000: 29478: debug : virNetMessageDecodeLength:152 : Got length, now need 84 total (80 more)
>2014-09-22 08:00:22.342014-09-22 08:00:22.344+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1235390 tx=(nil)
>2014-09-22 08:00:22.344+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.344+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.344+0000: 29478: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x1235cf0 len=84 prog=536903814 vers=1 proc=226 type=0 status=0 serial=5
>2014-09-22 08:00:22.344+0000: 29478: debug : virKeepAliveCheckMessage:384 : ka=0x1236320, client=0x1235cf0, msg=0x1235390
>2014-09-22 08:00:22.344+0000: 29478: debug : virKeepAliveCheckMessage:384 : ka=0x1236320, client=0x1235cf0, msg=0x1235390
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=4 frequency=5000
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=4 frequency=5000
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.344+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1235cf0
>2014-09-22 08:00:22.344+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1235cf0
>2014-09-22 08:00:22.344+0000: 29478: debug : virNetServerDispatchNewMessage:218 : server=0x12311b0 client=0x1235cf0 message=0x1235390
>2014-09-22 08:00:22.344+0000: 29478: debug : virNetServerDispatchNewMessage:218 : server=0x12311b0 client=0x1235cf0 message=0x1235390
>2014-09-22 08:00:22.344+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1239b30
>2014-09-22 08:00:22.344+0000: 29478: debug : virNetMessageNew:45 : msg=0x1236170 tracked=1
>2014-09-22 08:00:22.344+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1236170 tx=(nil)
>2014-09-22 08:00:22.344+0000: 29482: debug : virNetServerHandleJob:189 : server=0x12311b0 client=0x1235cf0 message=0x1235390 prog=0x1239b30
>2014-09-22 08:00:22.344+0000: 29482: debug : virNetServerHandleJob:189 : server=0x12311b0 client=0x1235cf0 message=0x1235390 prog=0x1239b30
>2014-09-22 08:00:22.344+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.344+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.344+0000: 29482: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=5 proc=226
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.344+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827344
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827344 due in 4999 ms
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4999
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827344
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827344 due in 4999 ms
>2014-09-22 08:00:22.345+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4999
>2014-09-22 08:00:22.353+0000: 29482: debug : remoteDispatchDomainSendKeyHelper:4898 : server=0x12311b0 client=0x1235cf0 msg=0x1235390 rerr=0x7f009a3a3b80 args=0x7f0084000b30 ret=0x7f0084000be0
>2014-09-22 08:00:22.353+0000: 29482: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f0084000c50 classname=virDomain
>2014-09-22 08:00:22.353+0000: 29482: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f00880029b0
>2014-09-22 08:00:22.353+0000: 29482: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f00880029b0
>2014-09-22 08:00:22.353+0000: 29482: debug : virDomainSendKey:8625 : dom=0x7f0084000c50, (VM: name=win7, uuid=61a3467e-b4d0-58c6-e134-1ddd473d8c60), codeset=0, holdtime=0, nkeycodes=3, flags=0
>2014-09-22 08:00:22.353+0000: 29482: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f009000ce30
>2014-09-22 08:00:22.353+0000: 29482: debug : qemuDomainObjBeginJobInternal:847 : Starting job: modify (async=none)
>2014-09-22 08:00:22.353+0000: 29482: debug : qemuDomainObjBeginJobInternal:847 : Starting job: modify (async=none)
>2014-09-22 08:00:22.353+0000: 29482: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f0080000d20
>2014-09-22 08:00:22.353+0000: 29482: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f0080000d20
>2014-09-22 08:00:22.353+0000: 29482: debug : qemuMonitorSendKey:2995 : mon=0x7f0080000d20, holdtime=0, nkeycodes=3
>2014-09-22 08:00:22.353+0000: 29482: debug : virJSONValueToString:1099 : object=0x7f0084000cb0
>2014-09-22 08:00:22.354+0000: 29482: debug : virJSONValueToStringOne:1030 : object=0x7f0084000cb0 type=0 gen=0x7f00840011d0
>2014-09-22 08:00:22.354+0000: 29482: debug : virJSONValueToStringOne:1030 : object=0x7f0084000cd0 type=2 gen=0x7f00840011d0
>2014-09-22 08:00:22.354+0000: 29482: debug : virJSONValueToStringOne:1030 : object=0x7f0084000940 type=0 gen=0x7f00840011d0
>2014-09-22 08:00:22.354+0000: 29482: debug : virJSONValueToStringOne:1030 : object=0x7f0084000940 type=0 gen=0x7f00840011d0
>2014-09-22 08:00:22.354+0000: 29482: debug : virJSONValueToStringOne:1030 : object=0x7f0084000960 type=2 gen=0x7f00840011d0
>2014-09-22 08:00:22.354+0000: 29482: debug : virJSONValueToStringOne:1030 : object=0x7f0084000960 type=2 gen=0x7f00840011d0
>2014-09-22 08:00:22.354+0000: 29482: debug : virJSONValueToStringOne:1030 : object=0x7f0084000aa0 type=2 gen=0x7f00840011d0
>2014-09-22 08:00:22.354+0000: 29482: debug : virJSONValueToStringOne:1030 : object=0x7f0084000aa0 type=2 gen=0x7f00840011d0
>2014-09-22 08:00:22.354+0000: 29482: debug : virJSONValueToString:1133 : result={"execute":"human-monitor-command","arguments":{"command-line":"sendkey 0x9D-0x46-0x46"},"id":"libvirt-7"}
>2014-09-22 08:00:22.354+0000: 29482: debug : virJSONValueToString:1133 : result={"execute":"human-monitor-command","arguments":{"command-line":"sendkey 0x9D-0x46-0x46"},"id":"libvirt-7"}
>2014-09-22 08:00:22.354+0000: 29482: debug : qemuMonitorJSONCommandWithFd:269 : Send command '{"execute":"human-monitor-command","arguments":{"command-line":"sendkey 0x9D-0x46-0x46"},"id":"libvirt-7"}' for write with FD -1
>2014-09-22 08:00:22.354+0000: 29482: debug : qemuMonitorJSONCommandWithFd:269 : Send command '{"execute":"human-monitor-command","arguments":{"command-line":"sendkey 0x9D-0x46-0x46"},"id":"libvirt-7"}' for write with FD -1
>2014-09-22 08:00:22.354+0000: 29482: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=15
>2014-09-22 08:00:22.354+0000: 29482: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=15
>2014-09-22 08:00:22.354+0000: 29482: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-09-22 08:00:22.354+0000: 29482: debug : qemuMonitorSend:911 : QEMU_MONITOR_SEND_MSG: mon=0x7f0080000d20 msg={"execute":"human-monitor-command","arguments":{"command-line":"sendkey 0x9D-0x46-0x46"},"id":"libvirt-7"}
> fd=-1
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>nning default event implementation
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=29 d=0
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827344
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827344 due in 4990 ms
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4990
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=29 d=0
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827344
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827344 due in 4990 ms
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4990
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=2
>2014-09-22 08:00:22.354+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f0080000d20
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.354+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=2
>2014-09-22 08:00:22.354+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f0080000d20
>2014-09-22 08:00:22.360+0000: 29478: debug : qemuMonitorIOWrite:463 : QEMU_MONITOR_IO_WRITE: mon=0x7f0080000d20 buf={"execute":"human-monitor-command","arguments":{"command-line":"sendkey 0x9D-0x46-0x46"},"id":"libvirt-7"}
> len=108 ret=108 errno=11
>2014-09-22 08:00:22.360+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=13
>2014-09-22 08:00:22.360+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.360+0000: 29478: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f0080000d20
>2014-09-22 08:00:22.360+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.360+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.360+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.360+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.360+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.360+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.360+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.360+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.360+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.360+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.360+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.360+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.360+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.360+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.360+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.360+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.360+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.360+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.360+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827344
>2014-09-22 08:00:22.360+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827344 due in 4984 ms
>2014-09-22 08:00:22.360+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4984
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=1
>2014-09-22 08:00:22.361+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f0080000d20
>2014-09-22 08:00:22.361+0000: 29478: debug : qemuMonitorIOProcess:355 : QEMU_MONITOR_IO_PROCESS: mon=0x7f0080000d20 buf={"return": "", "id": "libvirt-7"}
> len=35
>2014-09-22 08:00:22.361+0000: 29478: debug : qemuMonitorJSONIOProcessLine:156 : Line [{"return": "", "id": "libvirt-7"}]
>2014-09-22 08:00:22.361+0000: 29478: debug : virJSONValueFromString:975 : string={"return": "", "id": "libvirt-7"}
>2014-09-22 08:00:22.361+0000: 29478: debug : virJSONParserHandleStartMap:852 : parser=0x7fff34341ce0
>2014-09-22 08:00:22.361+0000: 29478: debug : virJSONParserHandleMapKey:833 : parser=0x7fff34341ce0 key=0x1235ea2
>2014-09-22 08:00:22.361+0000: 29478: debug : virJSONParserHandleString:813 : parser=0x7fff34341ce0 str=0x1235eac
>2014-09-22 08:00:22.361+0000: 29478: debug : virJSONParserHandleMapKey:833 : parser=0x7fff34341ce0 key=0x1235eb0
>2014-09-22 08:00:22.361+0000: 29478: debug : virJSONParserHandleString:813 : parser=0x7fff34341ce0 str=0x1235eb6
>2014-09-22 08:00:22.361+0000: 29478: debug : virJSONParserHandleEndMap:881 : parser=0x7fff34341ce0
>2014-09-22 08:00:22.361+0000: 29478: debug : virJSONValueFromString:1019 : result=0x1215240
>2014-09-22 08:00:22.361+0000: 29478: debug : qemuMonitorJSONIOProcessLine:176 : QEMU_MONITOR_RECV_REPLY: mon=0x7f0080000d20 reply={"return": "", "id": "libvirt-7"}
>2014-09-22 08:00:22.361+0000: 29478: debug : qemuMonitorJSONIOProcess:227 : Total used 35 bytes out of 35 available in buffer
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=13
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.361+0000: 29478: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f0080000d20
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.361+0000: 29482: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=13
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.361+0000: 29482: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 -1576822688
>2014-09-22 08:00:22.361+0000: 29482: debug : qemuMonitorJSONCommandWithFd:274 : Receive command reply ret=0 rxObject=0x1215240
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.361+0000: 29482: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f0080000d20
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.361+0000: 29482: debug : qemuDomainObjEndJob:992 : Stopping job: modify (async=none)
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.361+0000: 29482: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f009000ce30
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.361+0000: 29482: debug : virDomainFree:2281 : dom=0x7f0084000c50, (VM: name=win7, uuid=61a3467e-b4d0-58c6-e134-1ddd473d8c60)
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.361+0000: 29482: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f0084000c50
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.361+0000: 29482: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f0084000c50
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.361+0000: 29482: debug : virDomainDispose:264 : release domain 0x7f0084000c50 win7 61a3467e-b4d0-58c6-e134-1ddd473d8c60
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.361+0000: 29482: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f00880029b0
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=1
>2014-09-22 08:00:22.361+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f0080000d20
>2014-09-22 08:00:22.361+0000: 29478: debug : qemuMonitorIOProcess:355 : QEMU_MONITOR_IO_PROCESS: mon=0x7f0080000d20 buf={"return": "", "id": "libvirt-7"}
> len=35
>2014-09-22 08:00:22.361+0000: 29478: debug : qemuMonitorJSONIOProcessLine:156 : Line [{"return": "", "id": "libvirt-7"}]
>2014-09-22 08:00:22.361+0000: 29478: debug : virJSONValueFromString:975 : string={"return": "", "id": "libvirt-7"}
>2014-09-22 08:00:22.361+0000: 29478: debug : virJSONParserHandleStartMap:852 : parser=0x7fff34341ce0
>2014-09-22 08:00:22.361+0000: 29478: debug : virJSONParserHandleMapKey:833 : parser=0x7fff34341ce0 key=0x1235ea2
>2014-09-22 08:00:22.361+0000: 29478: debug : virJSONParserHandleString:813 : parser=0x7fff34341ce0 str=0x1235eac
>2014-09-22 08:00:22.361+0000: 29478: debug : virJSONParserHandleMapKey:833 : parser=0x7fff34341ce0 key=0x1235eb0
>2014-09-22 08:00:22.361+0000: 29478: debug : virJSONParserHandleString:813 : parser=0x7fff34341ce0 str=0x1235eb6
>2014-09-22 08:00:22.361+0000: 29478: debug : virJSONParserHandleEndMap:881 : parser=0x7fff34341ce0
>2014-09-22 08:00:22.361+0000: 29478: debug : virJSONValueFromString:1019 : result=0x1215240
>2014-09-22 08:00:22.361+0000: 29478: debug : qemuMonitorJSONIOProcessLine:176 : QEMU_MONITOR_RECV_REPLY: mon=0x7f0080000d20 reply={"return": "", "id": "libvirt-7"}
>2014-09-22 08:00:22.361+0000: 29478: debug : qemuMonitorJSONIOProcess:227 : Total used 35 bytes out of 35 available in buffer
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=13
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.361+0000: 29478: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f0080000d20
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.361+0000: 29482: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=13
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.361+0000: 29482: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 -1576822688
>2014-09-22 08:00:22.361+0000: 29482: debug : qemuMonitorJSONCommandWithFd:274 : Receive command reply ret=0 rxObject=0x1215240
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.361+0000: 29482: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f0080000d20
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.361+0000: 29482: debug : qemuDomainObjEndJob:992 : Stopping job: modify (async=none)
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.361+0000: 29482: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f009000ce30
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.361+0000: 29482: debug : virDomainFree:2281 : dom=0x7f0084000c50, (VM: name=win7, uuid=61a3467e-b4d0-58c6-e134-1ddd473d8c60)
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.361+0000: 29482: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f0084000c50
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.361+0000: 29482: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f0084000c50
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.361+0000: 29482: debug : virDomainDispose:264 : release domain 0x7f0084000c50 win7 61a3467e-b4d0-58c6-e134-1ddd473d8c60
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.361+0000: 29482: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f00880029b0
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827344
>2014-09-22 08:00:22.361+0000: 29482: debug : virNetMessageEncodePayload:377 : Encode length as 28
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827344 due in 4983 ms
>2014-09-22 08:00:22.361+0000: 29482: debug : virNetServerClientSendMessageLocked:1122 : msg=0x1235390 proc=226 len=28 offset=0
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4983
>2014-09-22 08:00:22.361+0000: 29482: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1235cf0 len=28 prog=536903814 vers=1 proc=226 type=1 status=0 serial=5
>2014-09-22 08:00:22.361+0000: 29482: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1236170 tx=0x1235390
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827344
>2014-09-22 08:00:22.361+0000: 29482: debug : virNetMessageEncodePayload:377 : Encode length as 28
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827344 due in 4983 ms
>2014-09-22 08:00:22.361+0000: 29482: debug : virNetServerClientSendMessageLocked:1122 : msg=0x1235390 proc=226 len=28 offset=0
>2014-09-22 08:00:22.361+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4983
>2014-09-22 08:00:22.361+0000: 29482: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1235cf0 len=28 prog=536903814 vers=1 proc=226 type=1 status=0 serial=5
>2014-09-22 08:00:22.361+0000: 29482: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1236170 tx=0x1235390
>2014-09-22 08:00:22.361+0000: 29482: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-09-22 08:00:22.361+0000: 29482: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=3
>2014-09-22 08:00:22.361+0000: 29482: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-09-22 08:00:22.361+0000: 29482: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=3
>2014-09-22 08:00:22.361+0000: 29482: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-09-22 08:00:22.362+0000: 29482: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1239b30
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.362+0000: 29482: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1235cf0
>2014-09-22 08:00:22.362+0000: 29482: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1235cf0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=5 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827344
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827344 due in 4982 ms
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4982
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=2
>2014-09-22 08:00:22.362+0000: 29478: debug : virNetMessageFree:73 : msg=0x1235390 nfds=0 cb=(nil)
>2014-09-22 08:00:22.362+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1236170 tx=(nil)
>2014-09-22 08:00:22.362+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.362+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827344
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827344
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827344 due in 4982 ms
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827344 due in 4982 ms
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4982
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.362+0000: 29478: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2014-09-22 08:00:22.362+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1236170 tx=(nil)
>2014-09-22 08:00:22.362+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1236170 tx=(nil)
>2014-09-22 08:00:22.362+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.362+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.362+0000: 29478: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x1235cf0 len=28 prog=536903814 vers=1 proc=2 type=0 status=0 serial=6
>2014-09-22 08:00:22.362+0000: 29478: debug : virKeepAliveCheckMessage:384 : ka=0x1236320, client=0x1235cf0, msg=0x1236170
>2014-09-22 08:00:22.362+0000: 29478: debug : virKeepAliveCheckMessage:384 : ka=0x1236320, client=0x1235cf0, msg=0x1236170
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=4 frequency=5000
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=4 frequency=5000
>2014-09-22 08:00:22.362+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.362+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1235cf0
>2014-09-22 08:00:22.362+0000: 29478: debug : virNetServerDispatchNewMessage:218 : server=0x12311b0 client=0x1235cf0 message=0x1236170
>2014-09-22 08:00:22.362+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1239b30
>2014-09-22 08:00:22.363+0000: 29478: debug : virNetMessageNew:45 : msg=0x1234e40 tracked=1
>2014-09-22 08:00:22.363+0000: 29483: debug : virNetServerHandleJob:189 : server=0x12311b0 client=0x1235cf0 message=0x1236170 prog=0x1239b30
>2014-09-22 08:00:22.363+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1234e40 tx=(nil)
>2014-09-22 08:00:22.363+0000: 29483: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=6 proc=2
>2014-09-22 08:00:22.363+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.363+0000: 29483: debug : remoteDispatchCloseHelper:124 : server=0x12311b0 client=0x1235cf0 msg=0x1236170 rerr=0x7f00999a2b80 args=0x7f00780008c0 ret=0x7f00780008e0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.363+0000: 29483: debug : virNetMessageEncodePayload:377 : Encode length as 28
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.363+0000: 29483: debug : virNetServerClientSendMessageLocked:1122 : msg=0x1236170 proc=2 len=28 offset=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29483: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1235cf0 len=28 prog=536903814 vers=1 proc=2 type=1 status=0 serial=6
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29483: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1234e40 tx=0x1236170
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29483: debug : virNetServerClientCalculateHandleMode:183 : mode=2
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29483: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=2
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827362
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827362 due in 4999 ms
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4999
>2014-09-22 08:00:22.363+0000: 29483: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-09-22 08:00:22.363+0000: 29483: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1239b30
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.363+0000: 29483: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1235cf0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.363+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.363+0000: 29483: debug : remoteDispatchCloseHelper:124 : server=0x12311b0 client=0x1235cf0 msg=0x1236170 rerr=0x7f00999a2b80 args=0x7f00780008c0 ret=0x7f00780008e0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.363+0000: 29483: debug : virNetMessageEncodePayload:377 : Encode length as 28
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.363+0000: 29483: debug : virNetServerClientSendMessageLocked:1122 : msg=0x1236170 proc=2 len=28 offset=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29483: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1235cf0 len=28 prog=536903814 vers=1 proc=2 type=1 status=0 serial=6
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29483: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1234e40 tx=0x1236170
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29483: debug : virNetServerClientCalculateHandleMode:183 : mode=2
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29483: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=2
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827362
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827362 due in 4999 ms
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4999
>2014-09-22 08:00:22.363+0000: 29483: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-09-22 08:00:22.363+0000: 29483: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1239b30
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.363+0000: 29483: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1235cf0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=18 e=4 d=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1411372827362
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 1411372827362 due in 4999 ms
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4999
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=2
>2014-09-22 08:00:22.363+0000: 29478: debug : virNetMessageFree:73 : msg=0x1236170 nfds=0 cb=(nil)
>2014-09-22 08:00:22.363+0000: 29478: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1234e40 tx=(nil)
>2014-09-22 08:00:22.363+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virNetServerClientCalculateHandleMode:183 : mode=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.363+0000: 29478: debug : virNetServerClientClose:623 : client=0x1235cf0
>2014-09-22 08:00:22.363+0000: 29478: debug : virKeepAliveStop:320 : RPC_KEEPALIVE_STOP: ka=0x1236320 client=0x1235cf0
>2014-09-22 08:00:22.363+0000: 29478: debug : virEventPollRemoveTimeout:288 : EVENT_POLL_REMOVE_TIMEOUT: timer=4
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 -1576822688
>2014-09-22 08:00:22.364+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1235cf0
>2014-09-22 08:00:22.364+0000: 29478: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1236320
>2014-09-22 08:00:22.364+0000: 29478: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1235cf0
>1235cf0
>2014-09-22 08:00:22.364+0000: 29478: debug : virObjectRef:168 : OBJECT_REF: obj=0x1235cf0
>2014-09-22 08:00:22.364+0000: 29478: debug : daemonRemoveAllClientStreams:480 : stream=(nil)
>2014-09-22 08:00:22.364+0000: 29478: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1235cf0
>2014-09-22 08:00:22.364+0000: 29478: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1235cf0
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollRemoveHandle:175 : EVENT_POLL_REMOVE_HANDLE: watch=11
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollRemoveHandle:188 : mark delete 9 18
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 -1576822688
>2014-09-22 08:00:22.364+0000: 29478: debug : virNetMessageFree:73 : msg=0x1234e40 nfds=0 cb=(nil)
>2014-09-22 08:00:22.364+0000: 29478: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1235ab0
>2014-09-22 08:00:22.364+0000: 29478: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1235cf0
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollCleanupTimeouts:514 : EVENT_POLL_PURGE_TIMEOUT: timer=4
>2014-09-22 08:00:22.364+0000: 29478: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1236320
>2014-09-22 08:00:22.364+0000: 29478: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x1236320
>2014-09-22 08:00:22.364+0000: 29478: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1235cf0
>2014-09-22 08:00:22.364+0000: 29478: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1235cf0
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollCleanupHandles:549 : Cleanup 10
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollCleanupHandles:562 : EVENT_POLL_PURGE_HANDLE: watch=11
>2014-09-22 08:00:22.364+0000: 29478: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1235cf0
>2014-09-22 08:00:22.364+0000: 29478: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x1235cf0
>2014-09-22 08:00:22.364+0000: 29478: debug : virConnectClose:1449 : conn=0x7f00880029b0
>2014-09-22 08:00:22.364+0000: 29478: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f00880029b0
>2014-09-22 08:00:22.364+0000: 29478: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f00880029b0
>2014-09-22 08:00:22.364+0000: 29478: debug : qemuDriverCloseCallbackRunAll:785 : conn=0x7f00880029b0
>2014-09-22 08:00:22.364+0000: 29478: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f0088002ae0
>2014-09-22 08:00:22.364+0000: 29478: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f0088002ae0
>2014-09-22 08:00:22.364+0000: 29478: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f0088002ae0
>2014-09-22 08:00:22.364+0000: 29478: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f0088002ae0
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollRemoveTimeout:288 : EVENT_POLL_REMOVE_TIMEOUT: timer=3
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollRemoveTimeout:288 : EVENT_POLL_REMOVE_TIMEOUT: timer=3
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -1576822688
>2014-09-22 08:00:22.364+0000: 29478: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1235ab0
>2014-09-22 08:00:22.364+0000: 29478: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x1235ab0
>2014-09-22 08:00:22.364+0000: 29478: debug : virNetSocketDispose:889 : sock=0x1235ab0 fd=18
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollRemoveHandle:175 : EVENT_POLL_REMOVE_HANDLE: watch=11
>2014-09-22 08:00:22.364+0000: 29478: debug : virFileClose:72 : Closed fd 18
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=20 e=25 d=0
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=25 d=0
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2014-09-22 08:00:22.364+0000: 29478: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=-1

Actions: View

Attachments on bug 1145028: 939925 | 939926