Attachment 602074 Details for Bug 845460 – libvirtd log

libvirtd log

libvirtd.log (text/plain), 64.12 KB, created by zhpeng on 2012-08-03 07:11:03 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: zhpeng

Created: 2012-08-03 07:11:03 UTC

Size: 64.12 KB

patch

obsolete

>Caught Segmentation violation dumping internal log buffer:
>
>
>    ====== start of log =====
>
>785: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-03 07:00:45.714+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977247008
>2012-08-03 07:00:45.714+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977250714
>2012-08-03 07:00:45.714+000025785: debug : virEventPollCalculateTimeout:346 : Timeout at 1343977247008 due in 1294 ms
>2012-08-03 07:00:45.714+000025785: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=1294
>2012-08-03 07:00:45.714+000025785: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=2
>2012-08-03 07:00:45.714+000025785: debug : daemonStreamEvent:140 : st=0x7f17c8000d40 events=2 EOF=0 closed=0
>2012-08-03 07:00:45.714+000025785: debug : daemonStreamHandleWrite:646 : client=0xa7bd30, stream=0x7f17c80008c0
>2012-08-03 07:00:45.714+000025785: debug : daemonStreamHandleWriteData:521 : client=0xa7bd30, stream=0x7f17c80008c0, proc=201, serial=9, len=29, offset=28
>2012-08-03 07:00:45.714+000025785: debug : virStreamSend:14787 : stream=0x7f17c8000d40, data=0xa7b93c, nbytes=1
>2012-08-03 07:00:45.714+000025785: debug : virNetMessageClear:56 : msg=0xa7bee0 nfds=0
>2012-08-03 07:00:45.714+000025785: debug : virNetServerClientSendMessageLocked:1120 : msg=0xa7bee0 proc=0 len=0 offset=0
>2012-08-03 07:00:45.714+000025785: debug : virNetServerClientSendMessageLocked:1128 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0xa7bd30 len=0 prog=0 vers=0 proc=0 type=1 status=0 serial=0
>2012-08-03 07:00:45.714+000025785: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xa74de0 tx=0xa7bee0
>2012-08-03 07:00:45.714+000025785: debug : virNetServerClientCalculateHandleMode:166 : mode=3
>2012-08-03 07:00:45.714+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=3
>2012-08-03 07:00:45.714+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:45.714+000025785: debug : virStreamEventUpdateCallback:15177 : stream=0x7f17c8000d40, events=1
>2012-08-03 07:00:45.714+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2012-08-03 07:00:45.714+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:45.714+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:45.714+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:45.714+000025785: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-03 07:00:45.714+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:45.714+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:45.714+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-03 07:00:45.714+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-03 07:00:45.714+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-03 07:00:45.714+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-03 07:00:45.714+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-08-03 07:00:45.714+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-08-03 07:00:45.714+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=20 e=25 d=0
>2012-08-03 07:00:45.714+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=18 e=1 d=0
>2012-08-03 07:00:45.714+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=24 e=25 d=0
>2012-08-03 07:00:45.714+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=5 d=0
>2012-08-03 07:00:45.714+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=26 e=1 d=0
>2012-08-03 07:00:45.714+000025785: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-03 07:00:45.714+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977247008
>2012-08-03 07:00:45.714+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977250714
>2012-08-03 07:00:45.714+000025785: debug : virEventPollCalculateTimeout:346 : Timeout at 1343977247008 due in 1294 ms
>2012-08-03 07:00:45.714+000025785: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=1294
>2012-08-03 07:00:45.714+000025785: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=10 events=2
>2012-08-03 07:00:45.714+000025785: debug : virNetMessageFree:73 : msg=0xa7bee0 nfds=0 cb=(nil)
>2012-08-03 07:00:45.714+000025785: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xa74de0 tx=(nil)
>2012-08-03 07:00:45.714+000025785: debug : virNetServerClientCalculateHandleMode:166 : mode=1
>2012-08-03 07:00:45.714+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=1
>2012-08-03 07:00:45.714+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:45.714+000025785: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-08-03 07:00:45.714+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:45.714+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:45.714+000025785: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-03 07:00:45.714+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:45.714+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:45.714+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-03 07:00:45.714+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-03 07:00:45.714+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-03 07:00:45.714+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-03 07:00:45.714+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-08-03 07:00:45.714+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-08-03 07:00:45.714+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=20 e=25 d=0
>2012-08-03 07:00:45.714+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=18 e=1 d=0
>2012-08-03 07:00:45.714+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=24 e=25 d=0
>2012-08-03 07:00:45.714+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=1 d=0
>2012-08-03 07:00:45.714+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=26 e=1 d=0
>2012-08-03 07:00:45.714+000025785: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-03 07:00:45.714+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977247008
>2012-08-03 07:00:45.714+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977250714
>2012-08-03 07:00:45.714+000025785: debug : virEventPollCalculateTimeout:346 : Timeout at 1343977247008 due in 1294 ms
>2012-08-03 07:00:45.714+000025785: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=1294
>2012-08-03 07:00:45.719+000025785: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-03 07:00:45.719+000025785: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-03 07:00:45.719+000025785: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-08-03 07:00:45.719+000025785: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-03 07:00:45.719+000025785: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-03 07:00:45.719+000025785: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-03 07:00:45.719+000025785: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-03 07:00:45.719+000025785: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-03 07:00:45.719+000025785: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-03 07:00:45.719+000025785: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-03 07:00:45.719+000025785: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-03 07:00:45.719+000025785: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-03 07:00:45.719+000025785: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-03 07:00:45.719+000025785: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-08-03 07:00:45.719+000025785: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=1
>2012-08-03 07:00:45.719+000025785: debug : daemonStreamEvent:140 : st=0x7f17c8000d40 events=1 EOF=0 closed=0
>2012-08-03 07:00:45.719+000025785: debug : daemonStreamHandleRead:718 : client=0xa7bd30, stream=0x7f17c80008c0 tx=1 closed=0
>2012-08-03 07:00:45.721+000025785: debug : virStreamRecv:14884 : stream=0x7f17c8000d40, data=0xa7f0e0, nbytes=4194280
>2012-08-03 07:00:45.721+000025785: debug : virNetMessageNew:45 : msg=0xa7da20 tracked=0
>2012-08-03 07:00:45.721+000025785: debug : virNetServerProgramSendStreamData:485 : client=0xa7bd30 msg=0xa7da20 data=0xa7f0e0 len=132
>2012-08-03 07:00:45.721+000025785: debug : virNetMessageEncodePayloadRaw:423 : Encode length as 160
>2012-08-03 07:00:45.721+000025785: debug : virNetServerProgramSendStreamData:513 : Total 160
>2012-08-03 07:00:45.721+000025785: debug : virNetServerClientSendMessageLocked:1120 : msg=0xa7da20 proc=201 len=160 offset=0
>2012-08-03 07:00:45.721+000025785: debug : virNetServerClientSendMessageLocked:1128 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0xa7bd30 len=160 prog=536903814 vers=1 proc=201 type=3 status=2 serial=9
>2012-08-03 07:00:45.721+000025785: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xa74de0 tx=0xa7da20
>2012-08-03 07:00:45.721+000025785: debug : virNetServerClientCalculateHandleMode:166 : mode=3
>2012-08-03 07:00:45.721+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=3
>2012-08-03 07:00:45.721+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:45.721+000025785: debug : virStreamEventUpdateCallback:15177 : stream=0x7f17c8000d40, events=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:45.721+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:45.721+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:45.721+000025785: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-03 07:00:45.721+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:45.721+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:45.721+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=20 e=25 d=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=18 e=1 d=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=24 e=25 d=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=5 d=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=26 e=0 d=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-03 07:00:45.721+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977247008
>2012-08-03 07:00:45.721+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977250714
>2012-08-03 07:00:45.721+000025785: debug : virEventPollCalculateTimeout:346 : Timeout at 1343977247008 due in 1287 ms
>2012-08-03 07:00:45.721+000025785: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=1287
>2012-08-03 07:00:45.721+000025785: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-03 07:00:45.721+000025785: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-03 07:00:45.721+000025785: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2012-08-03 07:00:45.721+000025785: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-03 07:00:45.721+000025785: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-03 07:00:45.721+000025785: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-03 07:00:45.721+000025785: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-03 07:00:45.721+000025785: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-03 07:00:45.721+000025785: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-03 07:00:45.721+000025785: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-03 07:00:45.721+000025785: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-03 07:00:45.721+000025785: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-03 07:00:45.721+000025785: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-03 07:00:45.721+000025785: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=10 events=2
>2012-08-03 07:00:45.721+000025785: debug : virNetMessageFree:73 : msg=0xa7da20 nfds=0 cb=0x43f0c0
>2012-08-03 07:00:45.721+000025785: debug : daemonStreamMessageFinished:98 : stream=0x7f17c80008c0 proc=201 serial=9
>2012-08-03 07:00:45.721+000025785: debug : virStreamEventUpdateCallback:15177 : stream=0x7f17c8000d40, events=1
>2012-08-03 07:00:45.721+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2012-08-03 07:00:45.721+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:45.721+000025785: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xa74de0 tx=(nil)
>2012-08-03 07:00:45.721+000025785: debug : virNetServerClientCalculateHandleMode:166 : mode=1
>2012-08-03 07:00:45.721+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=1
>2012-08-03 07:00:45.721+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:45.721+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:45.721+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:45.721+000025785: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-03 07:00:45.721+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:45.721+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:45.721+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=20 e=25 d=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=18 e=1 d=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=24 e=25 d=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=1 d=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=26 e=1 d=0
>2012-08-03 07:00:45.721+000025785: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-03 07:00:45.721+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977247008
>2012-08-03 07:00:45.721+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977250714
>2012-08-03 07:00:45.721+000025785: debug : virEventPollCalculateTimeout:346 : Timeout at 1343977247008 due in 1287 ms
>2012-08-03 07:00:45.721+000025785: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=1287
>2012-08-03 07:00:47.010+000025785: debug : virEventPollRunOnce:625 : Poll got 0 event(s)
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchTimeouts:433 : EVENT_POLL_DISPATCH_TIMEOUT: timer=2
>2012-08-03 07:00:47.010+000025785: debug : virKeepAliveTimerInternal:132 : RPC_KEEPALIVE_TIMEOUT: ka=0xa7b4f0 client=0xa7b420 countToDeath=5 idle=5
>2012-08-03 07:00:47.010+000025785: debug : virNetMessageNew:45 : msg=0xa7d940 tracked=0
>2012-08-03 07:00:47.010+000025785: debug : virNetMessageEncodePayloadEmpty:448 : Encode length as 28
>2012-08-03 07:00:47.010+000025785: debug : virKeepAliveMessage:100 : Sending keepalive request to client 0xa7b420
>2012-08-03 07:00:47.010+000025785: debug : virKeepAliveMessage:103 : RPC_KEEPALIVE_SEND: ka=0xa7b4f0 client=0xa7b420 prog=1801807216 vers=1 proc=1
>2012-08-03 07:00:47.010+000025785: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=2 frequency=5000
>2012-08-03 07:00:47.010+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:47.010+000025785: debug : virNetServerClientSendMessageLocked:1120 : msg=0xa7d940 proc=1 len=28 offset=0
>2012-08-03 07:00:47.010+000025785: debug : virNetServerClientSendMessageLocked:1128 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0xa7b420 len=28 prog=1801807216 vers=1 proc=1 type=2 status=0 serial=0
>2012-08-03 07:00:47.010+000025785: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xa7aa10 tx=0xa7d940
>2012-08-03 07:00:47.010+000025785: debug : virNetServerClientCalculateHandleMode:166 : mode=3
>2012-08-03 07:00:47.010+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=3
>2012-08-03 07:00:47.010+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:47.010+000025785: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=20 e=25 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=18 e=5 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=24 e=25 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=26 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977252010
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977250714
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCalculateTimeout:346 : Timeout at 1343977250714 due in 3704 ms
>2012-08-03 07:00:47.010+000025785: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=3704
>2012-08-03 07:00:47.010+000025785: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=2
>2012-08-03 07:00:47.010+000025785: debug : virNetMessageFree:73 : msg=0xa7d940 nfds=0 cb=(nil)
>2012-08-03 07:00:47.010+000025785: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xa7aa10 tx=(nil)
>2012-08-03 07:00:47.010+000025785: debug : virNetServerClientCalculateHandleMode:166 : mode=1
>2012-08-03 07:00:47.010+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
>2012-08-03 07:00:47.010+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:47.010+000025785: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=20 e=25 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=18 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=24 e=25 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=26 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977252010
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977250714
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCalculateTimeout:346 : Timeout at 1343977250714 due in 3704 ms
>2012-08-03 07:00:47.010+000025785: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=3704
>2012-08-03 07:00:47.010+000025785: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=1
>2012-08-03 07:00:47.010+000025785: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2012-08-03 07:00:47.010+000025785: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xa7aa10 tx=(nil)
>2012-08-03 07:00:47.010+000025785: debug : virNetServerClientCalculateHandleMode:166 : mode=1
>2012-08-03 07:00:47.010+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
>2012-08-03 07:00:47.010+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:47.010+000025785: debug : virNetServerClientDispatchRead:876 : RPC_SERVER_CLIENT_MSG_RX: client=0xa7b420 len=28 prog=1801807216 vers=1 proc=2 type=2 status=0 serial=0
>2012-08-03 07:00:47.010+000025785: debug : virKeepAliveCheckMessage:399 : ka=0xa7b4f0, client=0xa7b420, msg=0xa7aa10
>2012-08-03 07:00:47.010+000025785: debug : virKeepAliveCheckMessage:416 : RPC_KEEPALIVE_RECEIVED: ka=0xa7b4f0 client=0xa7b420 prog=1801807216 vers=1 proc=2
>2012-08-03 07:00:47.010+000025785: debug : virKeepAliveCheckMessage:425 : Got keepalive response from client 0xa7b420
>2012-08-03 07:00:47.010+000025785: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=2 frequency=5000
>2012-08-03 07:00:47.010+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:47.010+000025785: debug : virNetMessageFree:73 : msg=0xa7aa10 nfds=0 cb=(nil)
>2012-08-03 07:00:47.010+000025785: debug : virNetMessageNew:45 : msg=0xa7aa10 tracked=1
>2012-08-03 07:00:47.010+000025785: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xa7aa10 tx=(nil)
>2012-08-03 07:00:47.010+000025785: debug : virNetServerClientCalculateHandleMode:166 : mode=1
>2012-08-03 07:00:47.010+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
>2012-08-03 07:00:47.010+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-03 07:00:47.010+000025785: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:47.010+000025785: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=20 e=25 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=18 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=24 e=25 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=26 e=1 d=0
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977252010
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977250714
>2012-08-03 07:00:47.010+000025785: debug : virEventPollCalculateTimeout:346 : Timeout at 1343977250714 due in 3704 ms
>2012-08-03 07:00:47.010+000025785: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=3704
>2012-08-03 07:00:50.091+000025785: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=10 events=1
>2012-08-03 07:00:50.091+000025785: debug : virNetMessageDecodeLength:152 : Got length, now need 29 total (25 more)
>2012-08-03 07:00:50.091+000025785: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xa74de0 tx=(nil)
>2012-08-03 07:00:50.091+000025785: debug : virNetServerClientCalculateHandleMode:166 : mode=1
>2012-08-03 07:00:50.091+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=1
>2012-08-03 07:00:50.091+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:50.091+000025785: debug : virNetServerClientDispatchRead:876 : RPC_SERVER_CLIENT_MSG_RX: client=0xa7bd30 len=29 prog=536903814 vers=1 proc=201 type=3 status=2 serial=9
>2012-08-03 07:00:50.091+000025785: debug : virKeepAliveCheckMessage:399 : ka=0xa7da90, client=0xa7bd30, msg=0xa74de0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=4 frequency=5000
>2012-08-03 07:00:50.091+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:50.091+000025785: debug : daemonStreamFilter:296 : Incoming client=0xa7bd30, rx=(nil), serial=201, proc=9, status=2
>2012-08-03 07:00:50.091+000025785: debug : virStreamEventUpdateCallback:15177 : stream=0x7f17c8000d40, events=3
>2012-08-03 07:00:50.091+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=3
>2012-08-03 07:00:50.091+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:50.091+000025785: debug : virNetMessageNew:45 : msg=0xa7c6e0 tracked=1
>2012-08-03 07:00:50.091+000025785: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xa7c6e0 tx=(nil)
>2012-08-03 07:00:50.091+000025785: debug : virNetServerClientCalculateHandleMode:166 : mode=1
>2012-08-03 07:00:50.091+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=1
>2012-08-03 07:00:50.091+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:50.091+000025785: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=20 e=25 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=18 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=24 e=25 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=26 e=5 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977252010
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977255091
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCalculateTimeout:346 : Timeout at 1343977252010 due in 1919 ms
>2012-08-03 07:00:50.091+000025785: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=1919
>2012-08-03 07:00:50.091+000025785: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=2
>2012-08-03 07:00:50.091+000025785: debug : daemonStreamEvent:140 : st=0x7f17c8000d40 events=2 EOF=0 closed=0
>2012-08-03 07:00:50.091+000025785: debug : daemonStreamHandleWrite:646 : client=0xa7bd30, stream=0x7f17c80008c0
>2012-08-03 07:00:50.091+000025785: debug : daemonStreamHandleWriteData:521 : client=0xa7bd30, stream=0x7f17c80008c0, proc=201, serial=9, len=29, offset=28
>2012-08-03 07:00:50.091+000025785: debug : virStreamSend:14787 : stream=0x7f17c8000d40, data=0xa7b1ec, nbytes=1
>2012-08-03 07:00:50.091+000025785: debug : virNetMessageClear:56 : msg=0xa74de0 nfds=0
>2012-08-03 07:00:50.091+000025785: debug : virNetServerClientSendMessageLocked:1120 : msg=0xa74de0 proc=0 len=0 offset=0
>2012-08-03 07:00:50.091+000025785: debug : virNetServerClientSendMessageLocked:1128 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0xa7bd30 len=0 prog=0 vers=0 proc=0 type=1 status=0 serial=0
>2012-08-03 07:00:50.091+000025785: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xa7c6e0 tx=0xa74de0
>2012-08-03 07:00:50.091+000025785: debug : virNetServerClientCalculateHandleMode:166 : mode=3
>2012-08-03 07:00:50.091+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=3
>2012-08-03 07:00:50.091+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:50.091+000025785: debug : virStreamEventUpdateCallback:15177 : stream=0x7f17c8000d40, events=1
>2012-08-03 07:00:50.091+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2012-08-03 07:00:50.091+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:50.091+000025785: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=20 e=25 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=18 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=24 e=25 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=5 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=26 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977252010
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977255091
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCalculateTimeout:346 : Timeout at 1343977252010 due in 1919 ms
>2012-08-03 07:00:50.091+000025785: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=1919
>2012-08-03 07:00:50.091+000025785: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=10 events=2
>2012-08-03 07:00:50.091+000025785: debug : virNetMessageFree:73 : msg=0xa74de0 nfds=0 cb=(nil)
>2012-08-03 07:00:50.091+000025785: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xa7c6e0 tx=(nil)
>2012-08-03 07:00:50.091+000025785: debug : virNetServerClientCalculateHandleMode:166 : mode=1
>2012-08-03 07:00:50.091+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=1
>2012-08-03 07:00:50.091+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:50.091+000025785: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:50.091+000025785: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=20 e=25 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=18 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=24 e=25 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=26 e=1 d=0
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977252010
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977255091
>2012-08-03 07:00:50.091+000025785: debug : virEventPollCalculateTimeout:346 : Timeout at 1343977252010 due in 1919 ms
>2012-08-03 07:00:50.091+000025785: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=1919
>2012-08-03 07:00:51.106+000025785: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-03 07:00:51.106+000025785: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-03 07:00:51.106+000025785: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-08-03 07:00:51.106+000025785: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-03 07:00:51.106+000025785: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-03 07:00:51.106+000025785: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-03 07:00:51.106+000025785: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-03 07:00:51.106+000025785: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-03 07:00:51.106+000025785: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-03 07:00:51.106+000025785: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-03 07:00:51.106+000025785: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-03 07:00:51.106+000025785: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-03 07:00:51.106+000025785: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-03 07:00:51.106+000025785: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-08-03 07:00:51.106+000025785: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=1
>2012-08-03 07:00:51.106+000025785: debug : daemonStreamEvent:140 : st=0x7f17c8000d40 events=1 EOF=0 closed=0
>2012-08-03 07:00:51.106+000025785: debug : daemonStreamHandleRead:718 : client=0xa7bd30, stream=0x7f17c80008c0 tx=1 closed=0
>2012-08-03 07:00:51.108+000025785: debug : virStreamRecv:14884 : stream=0x7f17c8000d40, data=0xa7f0e0, nbytes=4194280
>2012-08-03 07:00:51.108+000025785: debug : virNetMessageNew:45 : msg=0xa7b290 tracked=0
>2012-08-03 07:00:51.108+000025785: debug : virNetServerProgramSendStreamData:485 : client=0xa7bd30 msg=0xa7b290 data=0xa7f0e0 len=132
>2012-08-03 07:00:51.108+000025785: debug : virNetMessageEncodePayloadRaw:423 : Encode length as 160
>2012-08-03 07:00:51.108+000025785: debug : virNetServerProgramSendStreamData:513 : Total 160
>2012-08-03 07:00:51.108+000025785: debug : virNetServerClientSendMessageLocked:1120 : msg=0xa7b290 proc=201 len=160 offset=0
>2012-08-03 07:00:51.108+000025785: debug : virNetServerClientSendMessageLocked:1128 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0xa7bd30 len=160 prog=536903814 vers=1 proc=201 type=3 status=2 serial=9
>2012-08-03 07:00:51.108+000025785: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xa7c6e0 tx=0xa7b290
>2012-08-03 07:00:51.108+000025785: debug : virNetServerClientCalculateHandleMode:166 : mode=3
>2012-08-03 07:00:51.108+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=3
>2012-08-03 07:00:51.108+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:51.108+000025785: debug : virStreamEventUpdateCallback:15177 : stream=0x7f17c8000d40, events=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:51.108+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:51.108+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:51.108+000025785: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-03 07:00:51.108+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:51.108+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:51.108+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=20 e=25 d=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=18 e=1 d=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=24 e=25 d=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=5 d=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=26 e=0 d=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-03 07:00:51.108+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977252010
>2012-08-03 07:00:51.108+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977255091
>2012-08-03 07:00:51.108+000025785: debug : virEventPollCalculateTimeout:346 : Timeout at 1343977252010 due in 902 ms
>2012-08-03 07:00:51.108+000025785: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=902
>2012-08-03 07:00:51.108+000025785: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-03 07:00:51.108+000025785: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-03 07:00:51.108+000025785: debug : virEventPollDispatchHandles:455 : Dispatch 10
>2012-08-03 07:00:51.108+000025785: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-03 07:00:51.108+000025785: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-03 07:00:51.108+000025785: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-03 07:00:51.108+000025785: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-03 07:00:51.108+000025785: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-03 07:00:51.108+000025785: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-03 07:00:51.108+000025785: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-03 07:00:51.108+000025785: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-03 07:00:51.108+000025785: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-03 07:00:51.108+000025785: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-03 07:00:51.108+000025785: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=10 events=2
>2012-08-03 07:00:51.108+000025785: debug : virNetMessageFree:73 : msg=0xa7b290 nfds=0 cb=0x43f0c0
>2012-08-03 07:00:51.108+000025785: debug : daemonStreamMessageFinished:98 : stream=0x7f17c80008c0 proc=201 serial=9
>2012-08-03 07:00:51.108+000025785: debug : virStreamEventUpdateCallback:15177 : stream=0x7f17c8000d40, events=1
>2012-08-03 07:00:51.108+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2012-08-03 07:00:51.108+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:51.108+000025785: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xa7c6e0 tx=(nil)
>2012-08-03 07:00:51.108+000025785: debug : virNetServerClientCalculateHandleMode:166 : mode=1
>2012-08-03 07:00:51.108+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=1
>2012-08-03 07:00:51.108+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:51.108+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:51.108+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:51.108+000025785: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-03 07:00:51.108+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:51.108+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:51.108+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=20 e=25 d=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=18 e=1 d=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=24 e=25 d=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=1 d=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=26 e=1 d=0
>2012-08-03 07:00:51.108+000025785: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-03 07:00:51.108+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977252010
>2012-08-03 07:00:51.108+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977255091
>2012-08-03 07:00:51.108+000025785: debug : virEventPollCalculateTimeout:346 : Timeout at 1343977252010 due in 902 ms
>2012-08-03 07:00:51.108+000025785: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=902
>2012-08-03 07:00:51.247+000025785: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=10 events=1
>2012-08-03 07:00:51.247+000025785: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2012-08-03 07:00:51.247+000025785: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xa7c6e0 tx=(nil)
>2012-08-03 07:00:51.247+000025785: debug : virNetServerClientCalculateHandleMode:166 : mode=1
>2012-08-03 07:00:51.247+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=1
>2012-08-03 07:00:51.247+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:51.247+000025785: debug : virNetServerClientDispatchRead:876 : RPC_SERVER_CLIENT_MSG_RX: client=0xa7bd30 len=28 prog=536903814 vers=1 proc=201 type=3 status=1 serial=9
>2012-08-03 07:00:51.247+000025785: debug : virKeepAliveCheckMessage:399 : ka=0xa7da90, client=0xa7bd30, msg=0xa7c6e0
>2012-08-03 07:00:51.247+000025785: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=4 frequency=5000
>2012-08-03 07:00:51.247+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:51.247+000025785: debug : daemonStreamFilter:296 : Incoming client=0xa7bd30, rx=(nil), serial=201, proc=9, status=1
>2012-08-03 07:00:51.247+000025785: debug : virStreamEventUpdateCallback:15177 : stream=0x7f17c8000d40, events=3
>2012-08-03 07:00:51.247+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=3
>2012-08-03 07:00:51.247+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:51.247+000025785: debug : virNetMessageNew:45 : msg=0xa7be00 tracked=1
>2012-08-03 07:00:51.247+000025785: debug : virNetServerClientCalculateHandleMode:135 : tls=(nil) hs=-1, rx=0xa7be00 tx=(nil)
>2012-08-03 07:00:51.247+000025785: debug : virNetServerClientCalculateHandleMode:166 : mode=1
>2012-08-03 07:00:51.247+000025785: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=1
>2012-08-03 07:00:51.247+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-08-03 07:00:51.247+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:51.247+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:51.247+000025785: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-08-03 07:00:51.247+000025785: debug : virEventPollCleanupTimeouts:501 : Cleanup 4
>2012-08-03 07:00:51.247+000025785: debug : virEventPollCleanupHandles:549 : Cleanup 11
>2012-08-03 07:00:51.247+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-08-03 07:00:51.247+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-08-03 07:00:51.247+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-08-03 07:00:51.247+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-08-03 07:00:51.247+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-08-03 07:00:51.247+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-08-03 07:00:51.247+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=20 e=25 d=0
>2012-08-03 07:00:51.247+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=18 e=1 d=0
>2012-08-03 07:00:51.247+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=24 e=25 d=0
>2012-08-03 07:00:51.247+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=22 e=1 d=0
>2012-08-03 07:00:51.247+000025785: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=26 e=5 d=0
>2012-08-03 07:00:51.247+000025785: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 4 timers
>2012-08-03 07:00:51.247+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977252010
>2012-08-03 07:00:51.247+000025785: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1343977256247
>2012-08-03 07:00:51.247+000025785: debug : virEventPollCalculateTimeout:346 : Timeout at 1343977252010 due in 763 ms
>2012-08-03 07:00:51.247+000025785: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=11 timeout=763
>2012-08-03 07:00:51.247+000025785: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchTimeouts:410 : Dispatch 4
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:455 : Dispatch 11
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-08-03 07:00:51.247+000025785: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=2
>2012-08-03 07:00:51.247+000025785: debug : daemonStreamEvent:140 : st=0x7f17c8000d40 events=2 EOF=0 closed=0
>2012-08-03 07:00:51.247+000025785: debug : daemonStreamHandleWrite:646 : client=0xa7bd30, stream=0x7f17c80008c0
>2012-08-03 07:00:51.247+000025785: debug : daemonStreamHandleAbort:607 : client=0xa7bd30, stream=0x7f17c80008c0, proc=201, serial=9
>2012-08-03 07:00:51.247+000025785: debug : virStreamEventRemoveCallback:15213 : stream=0x7f17c8000d40
>2012-08-03 07:00:51.247+000025785: debug : virEventPollRemoveHandle:175 : EVENT_POLL_REMOVE_HANDLE: watch=11
>2012-08-03 07:00:51.247+000025785: debug : virEventPollRemoveHandle:188 : mark delete 10 26
>2012-08-03 07:00:51.247+000025785: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -228235168
>2012-08-03 07:00:51.247+000025785: debug : virStreamAbort:15297 : stream=0x7f17c8000d40
>2012-08-03 07:00:51.247+000025785: debug : virFDStreamCloseInt:247 : st=0x7f17c8000d40
>2012-08-03 07:00:51.247+000025785: debug : virFileClose:72 : Closed fd 26
>
>
>     ====== end of log =====
>

Actions: View

Attachments on bug 845460: 602074