Project

General

Profile

Feature #2773 ยป snapshot-stuck.log

Andrey Korolyov, 07/11/2012 04:35 AM

 
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollRunOnce:629 : Poll got 0 event(s)
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollDispatchTimeouts:414 : Dispatch 2
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollDispatchTimeouts:437 : EVENT_POLL_DISPATCH_TIMEOUT: timer=10
2012-07-11 10:59:46.352+0000: 8981: debug : virKeepAliveTimerInternal:135 : RPC_KEEPALIVE_TIMEOUT: ka=0x7fefd000f220 client=0x7fefd000eed0 countToDeath=5 idle=5
2012-07-11 10:59:46.352+0000: 8981: debug : virNetMessageNew:48 : msg=0x1b89260 tracked=0
2012-07-11 10:59:46.352+0000: 8981: debug : virNetMessageEncodePayloadEmpty:451 : Encode length as 28
2012-07-11 10:59:46.352+0000: 8981: debug : virKeepAliveMessage:103 : Sending keepalive request to client 0x7fefd000eed0
2012-07-11 10:59:46.352+0000: 8981: debug : virKeepAliveMessage:106 : RPC_KEEPALIVE_SEND: ka=0x7fefd000f220 client=0x7fefd000eed0 prog=1801807216 vers=1 proc=1
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollUpdateTimeout:258 : EVENT_POLL_UPDATE_TIMEOUT: timer=10 frequency=5000
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollInterruptLocked:701 : Skip interrupt, 1 -500684832
2012-07-11 10:59:46.352+0000: 8981: debug : virNetServerClientSendMessageLocked:1122 : msg=0x1b89260 proc=1 len=28 offset=0
2012-07-11 10:59:46.352+0000: 8981: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7fefd000eed0 len=28 prog=1801807216 vers=1 proc=1 type=2 status=0 serial=0
2012-07-11 10:59:46.352+0000: 8981: debug : virNetServerClientCalculateHandleMode:138 : tls=(nil) hs=-1, rx=0x1af5370 tx=0x1b89260
2012-07-11 10:59:46.352+0000: 8981: debug : virNetServerClientCalculateHandleMode:168 : mode=3
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollUpdateHandle:150 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=3
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollInterruptLocked:701 : Skip interrupt, 1 -500684832
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollCleanupTimeouts:505 : Cleanup 2
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollCleanupHandles:553 : Cleanup 10
2012-07-11 10:59:46.352+0000: 8981: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollCleanupTimeouts:505 : Cleanup 2
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollCleanupHandles:553 : Cleanup 10
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=0 w=1, f=7 e=1 d=0
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=1 w=2, f=9 e=1 d=0
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=2 w=3, f=11 e=1 d=0
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=3 w=4, f=12 e=1 d=0
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=4 w=5, f=13 e=1 d=0
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=5 w=6, f=6 e=1 d=0
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=6 w=7, f=15 e=1 d=0
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=7 w=8, f=16 e=1 d=0
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=8 w=10, f=20 e=25 d=0
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=9 w=15, f=17 e=5 d=0
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollCalculateTimeout:324 : Calculate expiry of 2 timers
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollCalculateTimeout:330 : Got a timeout scheduled for 1342004391352
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollCalculateTimeout:350 : Timeout at 1342004391352 due in 5000 ms
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollRunOnce:618 : EVENT_POLL_RUN: nhandles=10 timeout=5000
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollRunOnce:629 : Poll got 1 event(s)
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollDispatchTimeouts:414 : Dispatch 2
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollDispatchHandles:459 : Dispatch 10
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollDispatchHandles:473 : i=0 w=1
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollDispatchHandles:473 : i=1 w=2
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollDispatchHandles:473 : i=2 w=3
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollDispatchHandles:473 : i=3 w=4
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollDispatchHandles:473 : i=4 w=5
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollDispatchHandles:473 : i=5 w=6
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollDispatchHandles:473 : i=6 w=7
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollDispatchHandles:473 : i=7 w=8
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollDispatchHandles:473 : i=8 w=10
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollDispatchHandles:473 : i=9 w=15
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollDispatchHandles:487 : EVENT_POLL_DISPATCH_HANDLE: watch=15 events=2
2012-07-11 10:59:46.352+0000: 8981: debug : virNetMessageFree:76 : msg=0x1b89260 nfds=0 cb=(nil)
2012-07-11 10:59:46.352+0000: 8981: debug : virNetServerClientCalculateHandleMode:138 : tls=(nil) hs=-1, rx=0x1af5370 tx=(nil)
2012-07-11 10:59:46.352+0000: 8981: debug : virNetServerClientCalculateHandleMode:168 : mode=1
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollUpdateHandle:150 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=1
2012-07-11 10:59:46.352+0000: 8981: debug : virEventPollInterruptLocked:701 : Skip interrupt, 1 -500684832
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollCleanupTimeouts:505 : Cleanup 2
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollCleanupHandles:553 : Cleanup 10
2012-07-11 10:59:46.353+0000: 8981: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollCleanupTimeouts:505 : Cleanup 2
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollCleanupHandles:553 : Cleanup 10
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=0 w=1, f=7 e=1 d=0
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=1 w=2, f=9 e=1 d=0
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=2 w=3, f=11 e=1 d=0
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=3 w=4, f=12 e=1 d=0
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=4 w=5, f=13 e=1 d=0
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=5 w=6, f=6 e=1 d=0
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=6 w=7, f=15 e=1 d=0
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=7 w=8, f=16 e=1 d=0
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=8 w=10, f=20 e=25 d=0
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=9 w=15, f=17 e=1 d=0
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollCalculateTimeout:324 : Calculate expiry of 2 timers
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollCalculateTimeout:330 : Got a timeout scheduled for 1342004391352
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollCalculateTimeout:350 : Timeout at 1342004391352 due in 4999 ms
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollRunOnce:618 : EVENT_POLL_RUN: nhandles=10 timeout=4999
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollRunOnce:629 : Poll got 1 event(s)
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollDispatchTimeouts:414 : Dispatch 2
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollDispatchHandles:459 : Dispatch 10
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollDispatchHandles:473 : i=0 w=1
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollDispatchHandles:473 : i=1 w=2
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollDispatchHandles:473 : i=2 w=3
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollDispatchHandles:473 : i=3 w=4
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollDispatchHandles:473 : i=4 w=5
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollDispatchHandles:473 : i=5 w=6
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollDispatchHandles:473 : i=6 w=7
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollDispatchHandles:473 : i=7 w=8
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollDispatchHandles:473 : i=8 w=10
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollDispatchHandles:473 : i=9 w=15
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollDispatchHandles:487 : EVENT_POLL_DISPATCH_HANDLE: watch=15 events=1
2012-07-11 10:59:46.353+0000: 8981: debug : virNetMessageDecodeLength:155 : Got length, now need 28 total (24 more)
2012-07-11 10:59:46.353+0000: 8981: debug : virNetServerClientCalculateHandleMode:138 : tls=(nil) hs=-1, rx=0x1af5370 tx=(nil)
2012-07-11 10:59:46.353+0000: 8981: debug : virNetServerClientCalculateHandleMode:168 : mode=1
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollUpdateHandle:150 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=1
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollInterruptLocked:701 : Skip interrupt, 1 -500684832
2012-07-11 10:59:46.353+0000: 8981: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x7fefd000eed0 len=28 prog=1801807216 vers=1 proc=2 type=2 status=0 serial=0
2012-07-11 10:59:46.353+0000: 8981: debug : virKeepAliveCheckMessage:402 : ka=0x7fefd000f220, client=0x7fefd000eed0, msg=0x1af5370
2012-07-11 10:59:46.353+0000: 8981: debug : virKeepAliveCheckMessage:419 : RPC_KEEPALIVE_RECEIVED: ka=0x7fefd000f220 client=0x7fefd000eed0 prog=1801807216 vers=1 proc=2
2012-07-11 10:59:46.353+0000: 8981: debug : virKeepAliveCheckMessage:428 : Got keepalive response from client 0x7fefd000eed0
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollUpdateTimeout:258 : EVENT_POLL_UPDATE_TIMEOUT: timer=10 frequency=5000
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollInterruptLocked:701 : Skip interrupt, 1 -500684832
2012-07-11 10:59:46.353+0000: 8981: debug : virNetMessageFree:76 : msg=0x1af5370 nfds=0 cb=(nil)
2012-07-11 10:59:46.353+0000: 8981: debug : virNetMessageNew:48 : msg=0x1af5370 tracked=1
2012-07-11 10:59:46.353+0000: 8981: debug : virNetServerClientCalculateHandleMode:138 : tls=(nil) hs=-1, rx=0x1af5370 tx=(nil)
2012-07-11 10:59:46.353+0000: 8981: debug : virNetServerClientCalculateHandleMode:168 : mode=1
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollUpdateHandle:150 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=1
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollInterruptLocked:701 : Skip interrupt, 1 -500684832
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollCleanupTimeouts:505 : Cleanup 2
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollCleanupHandles:553 : Cleanup 10
2012-07-11 10:59:46.353+0000: 8981: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollCleanupTimeouts:505 : Cleanup 2
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollCleanupHandles:553 : Cleanup 10
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=0 w=1, f=7 e=1 d=0
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=1 w=2, f=9 e=1 d=0
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=2 w=3, f=11 e=1 d=0
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=3 w=4, f=12 e=1 d=0
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=4 w=5, f=13 e=1 d=0
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=5 w=6, f=6 e=1 d=0
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=6 w=7, f=15 e=1 d=0
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=7 w=8, f=16 e=1 d=0
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=8 w=10, f=20 e=25 d=0
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=9 w=15, f=17 e=1 d=0
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollCalculateTimeout:324 : Calculate expiry of 2 timers
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollCalculateTimeout:330 : Got a timeout scheduled for 1342004391353
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollCalculateTimeout:350 : Timeout at 1342004391353 due in 5000 ms
2012-07-11 10:59:46.353+0000: 8981: debug : virEventPollRunOnce:618 : EVENT_POLL_RUN: nhandles=10 timeout=5000


<snip>......</snip>


2012-07-11 11:01:46.524+0000: 8981: debug : virEventPollInterruptLocked:701 : Skip interrupt, 1 -500684832
2012-07-11 11:01:46.524+0000: 8981: debug : virEventPollCleanupTimeouts:505 : Cleanup 2
2012-07-11 11:01:46.524+0000: 8981: debug : virEventPollCleanupHandles:553 : Cleanup 10
2012-07-11 11:01:46.524+0000: 8981: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-07-11 11:01:46.524+0000: 8981: debug : virEventPollCleanupTimeouts:505 : Cleanup 2
2012-07-11 11:01:46.524+0000: 8981: debug : virEventPollCleanupHandles:553 : Cleanup 10
2012-07-11 11:01:46.524+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=0 w=1, f=7 e=1 d=0
2012-07-11 11:01:46.524+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=1 w=2, f=9 e=1 d=0
2012-07-11 11:01:46.524+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=2 w=3, f=11 e=1 d=0
2012-07-11 11:01:46.524+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=3 w=4, f=12 e=1 d=0
2012-07-11 11:01:46.524+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=4 w=5, f=13 e=1 d=0
2012-07-11 11:01:46.524+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=5 w=6, f=6 e=1 d=0
2012-07-11 11:01:46.524+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=6 w=7, f=15 e=1 d=0
2012-07-11 11:01:46.524+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=7 w=8, f=16 e=1 d=0
2012-07-11 11:01:46.524+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=8 w=10, f=20 e=25 d=0
2012-07-11 11:01:46.524+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=9 w=15, f=17 e=1 d=0
2012-07-11 11:01:46.524+0000: 8981: debug : virEventPollCalculateTimeout:324 : Calculate expiry of 2 timers
2012-07-11 11:01:46.524+0000: 8981: debug : virEventPollCalculateTimeout:330 : Got a timeout scheduled for 1342004511524
2012-07-11 11:01:46.524+0000: 8981: debug : virEventPollCalculateTimeout:350 : Timeout at 1342004511524 due in 5000 ms
2012-07-11 11:01:46.524+0000: 8981: debug : virEventPollRunOnce:618 : EVENT_POLL_RUN: nhandles=10 timeout=5000
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollRunOnce:629 : Poll got 1 event(s)
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollDispatchTimeouts:414 : Dispatch 2
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollDispatchHandles:459 : Dispatch 10
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollDispatchHandles:473 : i=0 w=1
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollDispatchHandles:473 : i=1 w=2
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollDispatchHandles:473 : i=2 w=3
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollDispatchHandles:473 : i=3 w=4
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollDispatchHandles:473 : i=4 w=5
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollDispatchHandles:473 : i=5 w=6
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollDispatchHandles:473 : i=6 w=7
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollDispatchHandles:473 : i=7 w=8
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollDispatchHandles:473 : i=8 w=10
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollDispatchHandles:473 : i=9 w=15
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollDispatchHandles:487 : EVENT_POLL_DISPATCH_HANDLE: watch=15 events=1
2012-07-11 11:01:46.525+0000: 8981: debug : virNetMessageDecodeLength:155 : Got length, now need 28 total (24 more)
2012-07-11 11:01:46.525+0000: 8981: debug : virNetServerClientCalculateHandleMode:138 : tls=(nil) hs=-1, rx=0x1af5370 tx=(nil)
2012-07-11 11:01:46.525+0000: 8981: debug : virNetServerClientCalculateHandleMode:168 : mode=1
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollUpdateHandle:150 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=1
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollInterruptLocked:701 : Skip interrupt, 1 -500684832
2012-07-11 11:01:46.525+0000: 8981: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x7fefd000eed0 len=28 prog=1801807216 vers=1 proc=2 type=2
status=0 serial=0
2012-07-11 11:01:46.525+0000: 8981: debug : virKeepAliveCheckMessage:402 : ka=0x7fefd000f220, client=0x7fefd000eed0, msg=0x1af5370
2012-07-11 11:01:46.525+0000: 8981: debug : virKeepAliveCheckMessage:419 : RPC_KEEPALIVE_RECEIVED: ka=0x7fefd000f220 client=0x7fefd000eed0 prog=1801807216 vers=1 proc=2
2012-07-11 11:01:46.525+0000: 8981: debug : virKeepAliveCheckMessage:428 : Got keepalive response from client 0x7fefd000eed0
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollUpdateTimeout:258 : EVENT_POLL_UPDATE_TIMEOUT: timer=10 frequency=5000
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollInterruptLocked:701 : Skip interrupt, 1 -500684832
2012-07-11 11:01:46.525+0000: 8981: debug : virNetMessageFree:76 : msg=0x1af5370 nfds=0 cb=(nil)
2012-07-11 11:01:46.525+0000: 8981: debug : virNetMessageNew:48 : msg=0x1af5370 tracked=1
2012-07-11 11:01:46.525+0000: 8981: debug : virNetServerClientCalculateHandleMode:138 : tls=(nil) hs=-1, rx=0x1af5370 tx=(nil)
2012-07-11 11:01:46.525+0000: 8981: debug : virNetServerClientCalculateHandleMode:168 : mode=1
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollUpdateHandle:150 : EVENT_POLL_UPDATE_HANDLE: watch=15 events=1
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollInterruptLocked:701 : Skip interrupt, 1 -500684832
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollCleanupTimeouts:505 : Cleanup 2
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollCleanupHandles:553 : Cleanup 10
2012-07-11 11:01:46.525+0000: 8981: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollCleanupTimeouts:505 : Cleanup 2
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollCleanupHandles:553 : Cleanup 10
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=0 w=1, f=7 e=1 d=0
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=1 w=2, f=9 e=1 d=0
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=2 w=3, f=11 e=1 d=0
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=3 w=4, f=12 e=1 d=0
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=4 w=5, f=13 e=1 d=0
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=5 w=6, f=6 e=1 d=0
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=6 w=7, f=15 e=1 d=0
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=7 w=8, f=16 e=1 d=0
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=8 w=10, f=20 e=25 d=0
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollMakePollFDs:382 : Prepare n=9 w=15, f=17 e=1 d=0
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollCalculateTimeout:324 : Calculate expiry of 2 timers
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollCalculateTimeout:330 : Got a timeout scheduled for 1342004511525
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollCalculateTimeout:350 : Timeout at 1342004511525 due in 5000 ms
2012-07-11 11:01:46.525+0000: 8981: debug : virEventPollRunOnce:618 : EVENT_POLL_RUN: nhandles=10 timeout=5000
2012-07-11 11:01:47.600+0000: 8981: debug : virEventPollRunOnce:629 : Poll got 1 event(s)
2012-07-11 11:01:47.600+0000: 8981: debug : virEventPollDispatchTimeouts:414 : Dispatch 2
2012-07-11 11:01:47.600+0000: 8981: debug : virEventPollDispatchHandles:459 : Dispatch 10
2012-07-11 11:01:47.600+0000: 8981: debug : virEventPollDispatchHandles:473 : i=0 w=1
2012-07-11 11:01:47.600+0000: 8981: debug : virEventPollDispatchHandles:473 : i=1 w=2
2012-07-11 11:01:47.600+0000: 8981: debug : virEventPollDispatchHandles:473 : i=2 w=3
2012-07-11 11:01:47.600+0000: 8981: debug : virEventPollDispatchHandles:473 : i=3 w=4
2012-07-11 11:01:47.600+0000: 8981: debug : virEventPollDispatchHandles:473 : i=4 w=5
2012-07-11 11:01:47.600+0000: 8981: debug : virEventPollDispatchHandles:473 : i=5 w=6
2012-07-11 11:01:47.600+0000: 8981: debug : virEventPollDispatchHandles:473 : i=6 w=7
2012-07-11 11:01:47.600+0000: 8981: debug : virEventPollDispatchHandles:473 : i=7 w=8
2012-07-11 11:01:47.600+0000: 8981: debug : virEventPollDispatchHandles:473 : i=8 w=10
2012-07-11 11:01:47.600+0000: 8981: debug : virEventPollDispatchHandles:487 : EVENT_POLL_DISPATCH_HANDLE: watch=10 events=1
2012-07-11 11:01:47.600+0000: 8981: debug : qemuMonitorRef:243 : QEMU_MONITOR_REF: mon=0x7fefd0004470 refs=4
2012-07-11 11:01:47.600+0000: 8981: debug : qemuMonitorIOProcess:369 : QEMU_MONITOR_IO_PROCESS: mon=0x7fefd0004470 buf={"return": "Error -22 while loading VM state\r\n", "id": "libvirt-9"}
len=71


    (1-1/1)