Attachment 667100 Details for Bug 889407 – libvirtd crash log

libvirtd crash log

crash.log (text/plain), 64.06 KB, created by weizhang on 2012-12-21 05:05:52 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: weizhang

Created: 2012-12-21 05:05:52 UTC

Size: 64.06 KB

patch

obsolete

>    ====== start of log =====
>
>:02:31.701+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.701+000028194: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=29, f=19 e=5 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-12-21 05:02:31.701+000028194: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=-1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=7 w=29
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=29 events=2
>2012-12-21 05:02:31.701+000028194: debug : virNetMessageFree:73 : msg=0x11255a0 nfds=0 cb=(nil)
>2012-12-21 05:02:31.701+000028194: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1124460 tx=(nil)
>2012-12-21 05:02:31.701+000028194: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=29 events=1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -795682720
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.701+000028194: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=29, f=19 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-12-21 05:02:31.701+000028194: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=-1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=7 w=29
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=29 events=1
>2012-12-21 05:02:31.701+000028194: debug : virNetMessageDecodeLength:152 : Got length, now need 32 total (28 more)
>2012-12-21 05:02:31.701+000028194: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1124460 tx=(nil)
>2012-12-21 05:02:31.701+000028194: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=29 events=1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -795682720
>2012-12-21 05:02:31.701+000028194: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x11254c0 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=1
>2012-12-21 05:02:31.701+000028194: debug : virKeepAliveCheckMessage:384 : ka=0x1124720, client=0x11254c0, msg=0x1124460
>2012-12-21 05:02:31.701+000028194: debug : virObjectRef:168 : OBJECT_REF: obj=0x11254c0
>2012-12-21 05:02:31.701+000028194: debug : virNetServerDispatchNewMessage:218 : server=0x111ccb0 client=0x11254c0 message=0x1124460
>2012-12-21 05:02:31.701+000028194: debug : virObjectRef:168 : OBJECT_REF: obj=0x1128040
>2012-12-21 05:02:31.701+000028194: debug : virNetMessageNew:45 : msg=0x11258e0 tracked=1
>2012-12-21 05:02:31.701+000028194: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x11258e0 tx=(nil)
>2012-12-21 05:02:31.701+000028194: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-12-21 05:02:31.701+000028196: debug : virNetServerHandleJob:189 : server=0x111ccb0 client=0x11254c0 message=0x1124460 prog=0x1128040
>2012-12-21 05:02:31.701+000028194: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=29 events=1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -795682720
>2012-12-21 05:02:31.701+000028196: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=1 proc=60
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.701+000028196: debug : remoteDispatchSupportsFeatureHelper:13350 : server=0x111ccb0 client=0x11254c0 msg=0x1124460 rerr=0x7f55ca099b80 args=0x7f55b4000910 ret=0x7f55b4000930
>2012-12-21 05:02:31.701+000028194: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2012-12-21 05:02:31.701+000028196: debug : virKeepAliveStart:289 : RPC_KEEPALIVE_START: ka=0x1124720 client=0x11254c0 interval=0 count=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=29, f=19 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-12-21 05:02:31.701+000028194: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=-1
>2012-12-21 05:02:31.701+000028196: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-12-21 05:02:31.701+000028196: debug : virEventPollAddTimeout:243 : EVENT_POLL_ADD_TIMEOUT: timer=39 frequency=5000 cb=0x7f55d0a93690 opaque=0x1124720 ff=0x41f5b0
>2012-12-21 05:02:31.701+000028196: debug : virObjectRef:168 : OBJECT_REF: obj=0x1124720
>2012-12-21 05:02:31.701+000028196: debug : virNetMessageEncodePayload:358 : Encode length as 32
>2012-12-21 05:02:31.701+000028196: debug : virNetServerClientSendMessageLocked:1116 : msg=0x1124460 proc=60 len=32 offset=0
>2012-12-21 05:02:31.701+000028196: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x11254c0 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=1
>2012-12-21 05:02:31.701+000028196: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x11258e0 tx=0x1124460
>2012-12-21 05:02:31.701+000028196: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2012-12-21 05:02:31.701+000028196: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=29 events=3
>2012-12-21 05:02:31.701+000028196: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-12-21 05:02:31.701+000028196: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1128040
>2012-12-21 05:02:31.701+000028196: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11254c0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=7 w=29
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.701+000028194: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=29, f=19 e=5 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1356066156701
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCalculateTimeout:346 : Timeout at 1356066156701 due in 5000 ms
>2012-12-21 05:02:31.701+000028194: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2012-12-21 05:02:31.701+000028194: debug : virEventPollRunOnce:625 : Poll got 2 event(s)
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=7 w=29
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=29 events=2
>2012-12-21 05:02:31.701+000028194: debug : virNetMessageFree:73 : msg=0x1124460 nfds=0 cb=(nil)
>2012-12-21 05:02:31.701+000028194: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x11258e0 tx=(nil)
>2012-12-21 05:02:31.701+000028194: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=29 events=1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -795682720
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.701+000028194: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=29, f=19 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1356066156701
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCalculateTimeout:346 : Timeout at 1356066156701 due in 5000 ms
>2012-12-21 05:02:31.701+000028194: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2012-12-21 05:02:31.701+000028194: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:469 : i=7 w=29
>2012-12-21 05:02:31.701+000028194: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=29 events=1
>2012-12-21 05:02:31.701+000028194: debug : virNetMessageDecodeLength:152 : Got length, now need 40 total (36 more)
>2012-12-21 05:02:31.701+000028194: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x11258e0 tx=(nil)
>2012-12-21 05:02:31.701+000028194: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=29 events=1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -795682720
>2012-12-21 05:02:31.701+000028194: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x11254c0 len=40 prog=536903814 vers=1 proc=1 type=0 status=0 serial=2
>2012-12-21 05:02:31.701+000028194: debug : virKeepAliveCheckMessage:384 : ka=0x1124720, client=0x11254c0, msg=0x11258e0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=39 frequency=5000
>2012-12-21 05:02:31.701+000028194: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -795682720
>2012-12-21 05:02:31.701+000028194: debug : virObjectRef:168 : OBJECT_REF: obj=0x11254c0
>2012-12-21 05:02:31.701+000028194: debug : virNetServerDispatchNewMessage:218 : server=0x111ccb0 client=0x11254c0 message=0x11258e0
>2012-12-21 05:02:31.701+000028194: debug : virObjectRef:168 : OBJECT_REF: obj=0x1128040
>2012-12-21 05:02:31.701+000028194: debug : virNetMessageNew:45 : msg=0x1124120 tracked=1
>2012-12-21 05:02:31.701+000028194: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1124120 tx=(nil)
>2012-12-21 05:02:31.701+000028194: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=29 events=1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -795682720
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.701+000028194: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.701+000028197: debug : virNetServerHandleJob:189 : server=0x111ccb0 client=0x11254c0 message=0x11258e0 prog=0x1128040
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-12-21 05:02:31.701+000028197: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=2 proc=1
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-12-21 05:02:31.701+000028197: debug : remoteDispatchOpenHelper:11127 : server=0x111ccb0 client=0x11254c0 msg=0x11258e0 rerr=0x7f55c9698b80 args=0x7f55b8006ec0 ret=0x7f55b8006e50
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-12-21 05:02:31.701+000028197: debug : remoteDispatchOpen:731 : priv=0x11244d0 conn=(nil)
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-12-21 05:02:31.701+000028197: debug : virConnectOpen:1331 : name=
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-12-21 05:02:31.701+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=29, f=19 e=1 d=0
>2012-12-21 05:02:31.701+000028197: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f55b8007ed0 classname=virConnect
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1356066156701
>2012-12-21 05:02:31.701+000028194: debug : virEventPollCalculateTimeout:346 : Timeout at 1356066156701 due in 5000 ms
>2012-12-21 05:02:31.701+000028194: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2012-12-21 05:02:31.701+000028197: debug : virConnectGetConfigFile:953 : Loading config file '/etc/libvirt/libvirt.conf'
>2012-12-21 05:02:31.702+000028197: debug : virFileClose:72 : Closed fd 20
>2012-12-21 05:02:31.702+000028197: debug : do_open:1131 : no name, allowing driver auto-select
>2012-12-21 05:02:31.702+000028197: debug : do_open:1174 : trying driver 0 (Test) ...
>2012-12-21 05:02:31.702+000028197: debug : do_open:1180 : driver 0 Test returned DECLINED
>2012-12-21 05:02:31.702+000028197: debug : do_open:1174 : trying driver 1 (ESX) ...
>2012-12-21 05:02:31.702+000028197: debug : do_open:1180 : driver 1 ESX returned DECLINED
>2012-12-21 05:02:31.702+000028197: debug : do_open:1174 : trying driver 2 (remote) ...
>2012-12-21 05:02:31.702+000028197: debug : do_open:1180 : driver 2 remote returned DECLINED
>2012-12-21 05:02:31.702+000028197: debug : do_open:1174 : trying driver 3 (QEMU) ...
>2012-12-21 05:02:31.702+000028197: debug : do_open:1180 : driver 3 QEMU returned SUCCESS
>2012-12-21 05:02:31.702+000028197: debug : do_open:1202 : network driver 0 Test returned DECLINED
>2012-12-21 05:02:31.702+000028197: debug : do_open:1202 : network driver 1 ESX returned DECLINED
>2012-12-21 05:02:31.702+000028197: debug : do_open:1202 : network driver 2 remote returned DECLINED
>2012-12-21 05:02:31.702+000028197: debug : do_open:1202 : network driver 3 Network returned SUCCESS
>2012-12-21 05:02:31.702+000028197: debug : do_open:1217 : interface driver 0 Test returned DECLINED
>2012-12-21 05:02:31.702+000028197: debug : do_open:1217 : interface driver 1 ESX returned DECLINED
>2012-12-21 05:02:31.702+000028197: debug : do_open:1217 : interface driver 2 remote returned DECLINED
>2012-12-21 05:02:31.706+000028197: debug : do_open:1217 : interface driver 3 Interface returned SUCCESS
>2012-12-21 05:02:31.706+000028197: debug : do_open:1233 : storage driver 0 Test returned DECLINED
>2012-12-21 05:02:31.706+000028197: debug : do_open:1233 : storage driver 1 ESX returned DECLINED
>2012-12-21 05:02:31.706+000028197: debug : do_open:1233 : storage driver 2 remote returned DECLINED
>2012-12-21 05:02:31.706+000028197: debug : do_open:1233 : storage driver 3 storage returned SUCCESS
>2012-12-21 05:02:31.706+000028197: debug : do_open:1249 : node driver 0 Test returned DECLINED
>2012-12-21 05:02:31.706+000028197: debug : do_open:1249 : node driver 1 ESX returned DECLINED
>2012-12-21 05:02:31.706+000028197: debug : do_open:1249 : node driver 2 remote returned DECLINED
>2012-12-21 05:02:31.706+000028197: debug : do_open:1249 : node driver 3 udevDeviceMonitor returned SUCCESS
>2012-12-21 05:02:31.706+000028197: debug : do_open:1265 : secret driver 0 Test returned DECLINED
>2012-12-21 05:02:31.706+000028197: debug : do_open:1265 : secret driver 1 ESX returned DECLINED
>2012-12-21 05:02:31.706+000028197: debug : do_open:1265 : secret driver 2 remote returned DECLINED
>2012-12-21 05:02:31.706+000028197: debug : do_open:1265 : secret driver 3 secret returned SUCCESS
>2012-12-21 05:02:31.706+000028197: debug : do_open:1281 : nwfilter driver 0 Test returned DECLINED
>2012-12-21 05:02:31.706+000028197: debug : do_open:1281 : nwfilter driver 1 ESX returned DECLINED
>2012-12-21 05:02:31.706+000028197: debug : do_open:1281 : nwfilter driver 2 remote returned DECLINED
>2012-12-21 05:02:31.706+000028197: debug : do_open:1281 : nwfilter driver 3 nwfilter returned SUCCESS
>2012-12-21 05:02:31.706+000028197: debug : virNetMessageEncodePayload:358 : Encode length as 28
>2012-12-21 05:02:31.706+000028197: debug : virNetServerClientSendMessageLocked:1116 : msg=0x11258e0 proc=1 len=28 offset=0
>2012-12-21 05:02:31.706+000028197: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x11254c0 len=28 prog=536903814 vers=1 proc=1 type=1 status=0 serial=2
>2012-12-21 05:02:31.706+000028197: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1124120 tx=0x11258e0
>2012-12-21 05:02:31.706+000028197: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2012-12-21 05:02:31.706+000028197: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=29 events=3
>2012-12-21 05:02:31.706+000028197: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-12-21 05:02:31.706+000028197: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1128040
>2012-12-21 05:02:31.706+000028197: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11254c0
>2012-12-21 05:02:31.706+000028194: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-12-21 05:02:31.706+000028194: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-12-21 05:02:31.706+000028194: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2012-12-21 05:02:31.706+000028194: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-12-21 05:02:31.706+000028194: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-12-21 05:02:31.706+000028194: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-12-21 05:02:31.706+000028194: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-12-21 05:02:31.706+000028194: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-12-21 05:02:31.706+000028194: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-12-21 05:02:31.706+000028194: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-12-21 05:02:31.706+000028194: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-12-21 05:02:31.706+000028194: debug : virEventPollDispatchHandles:469 : i=7 w=29
>2012-12-21 05:02:31.706+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.706+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.706+000028194: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-12-21 05:02:31.706+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.706+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.706+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-12-21 05:02:31.706+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-12-21 05:02:31.706+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-12-21 05:02:31.706+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-12-21 05:02:31.706+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-12-21 05:02:31.706+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-12-21 05:02:31.706+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-12-21 05:02:31.706+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=29, f=19 e=5 d=0
>2012-12-21 05:02:31.706+000028194: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-12-21 05:02:31.706+000028194: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1356066156701
>2012-12-21 05:02:31.706+000028194: debug : virEventPollCalculateTimeout:346 : Timeout at 1356066156701 due in 4995 ms
>2012-12-21 05:02:31.706+000028194: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4995
>2012-12-21 05:02:31.706+000028194: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-12-21 05:02:31.706+000028194: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-12-21 05:02:31.706+000028194: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2012-12-21 05:02:31.706+000028194: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-12-21 05:02:31.706+000028194: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-12-21 05:02:31.706+000028194: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=7 w=29
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=29 events=2
>2012-12-21 05:02:31.707+000028194: debug : virNetMessageFree:73 : msg=0x11258e0 nfds=0 cb=(nil)
>2012-12-21 05:02:31.707+000028194: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1124120 tx=(nil)
>2012-12-21 05:02:31.707+000028194: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-12-21 05:02:31.707+000028194: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=29 events=1
>2012-12-21 05:02:31.707+000028194: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -795682720
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.707+000028194: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=29, f=19 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1356066156701
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCalculateTimeout:346 : Timeout at 1356066156701 due in 4994 ms
>2012-12-21 05:02:31.707+000028194: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4994
>2012-12-21 05:02:31.707+000028194: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=7 w=29
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=29 events=1
>2012-12-21 05:02:31.707+000028194: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2012-12-21 05:02:31.707+000028194: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1124120 tx=(nil)
>2012-12-21 05:02:31.707+000028194: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-12-21 05:02:31.707+000028194: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=29 events=1
>2012-12-21 05:02:31.707+000028194: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -795682720
>2012-12-21 05:02:31.707+000028194: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x11254c0 len=28 prog=536903814 vers=1 proc=110 type=0 status=0 serial=3
>2012-12-21 05:02:31.707+000028194: debug : virKeepAliveCheckMessage:384 : ka=0x1124720, client=0x11254c0, msg=0x1124120
>2012-12-21 05:02:31.707+000028194: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=39 frequency=5000
>2012-12-21 05:02:31.707+000028194: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -795682720
>2012-12-21 05:02:31.707+000028194: debug : virObjectRef:168 : OBJECT_REF: obj=0x11254c0
>2012-12-21 05:02:31.707+000028194: debug : virNetServerDispatchNewMessage:218 : server=0x111ccb0 client=0x11254c0 message=0x1124120
>2012-12-21 05:02:31.707+000028194: debug : virObjectRef:168 : OBJECT_REF: obj=0x1128040
>2012-12-21 05:02:31.707+000028194: debug : virNetMessageNew:45 : msg=0x1124c30 tracked=1
>2012-12-21 05:02:31.707+000028194: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1124c30 tx=(nil)
>2012-12-21 05:02:31.707+000028194: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-12-21 05:02:31.707+000028198: debug : virNetServerHandleJob:189 : server=0x111ccb0 client=0x11254c0 message=0x1124120 prog=0x1128040
>2012-12-21 05:02:31.707+000028194: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=29 events=1
>2012-12-21 05:02:31.707+000028198: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=110
>2012-12-21 05:02:31.707+000028194: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -795682720
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.707+000028194: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-12-21 05:02:31.707+000028198: debug : remoteDispatchGetURIHelper:7297 : server=0x111ccb0 client=0x11254c0 msg=0x1124120 rerr=0x7f55c8c97b80 args=0x7f55ac005100 ret=0x7f55ac004be0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.707+000028198: debug : virConnectGetURI:1700 : conn=0x7f55b8007ed0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-12-21 05:02:31.707+000028198: debug : virNetMessageEncodePayload:358 : Encode length as 48
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-12-21 05:02:31.707+000028198: debug : virNetServerClientSendMessageLocked:1116 : msg=0x1124120 proc=110 len=48 offset=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-12-21 05:02:31.707+000028198: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x11254c0 len=48 prog=536903814 vers=1 proc=110 type=1 status=0 serial=3
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-12-21 05:02:31.707+000028198: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1124c30 tx=0x1124120
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-12-21 05:02:31.707+000028198: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-12-21 05:02:31.707+000028198: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=29 events=3
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=29, f=19 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1356066156707
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCalculateTimeout:346 : Timeout at 1356066156707 due in 5000 ms
>2012-12-21 05:02:31.707+000028194: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2012-12-21 05:02:31.707+000028198: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-12-21 05:02:31.707+000028198: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1128040
>2012-12-21 05:02:31.707+000028198: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11254c0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=7 w=29
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.707+000028194: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=29, f=19 e=5 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1356066156707
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCalculateTimeout:346 : Timeout at 1356066156707 due in 5000 ms
>2012-12-21 05:02:31.707+000028194: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2012-12-21 05:02:31.707+000028194: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=7 w=29
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=29 events=2
>2012-12-21 05:02:31.707+000028194: debug : virNetMessageFree:73 : msg=0x1124120 nfds=0 cb=(nil)
>2012-12-21 05:02:31.707+000028194: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1124c30 tx=(nil)
>2012-12-21 05:02:31.707+000028194: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-12-21 05:02:31.707+000028194: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=29 events=1
>2012-12-21 05:02:31.707+000028194: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -795682720
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.707+000028194: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=29, f=19 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1356066156707
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCalculateTimeout:346 : Timeout at 1356066156707 due in 5000 ms
>2012-12-21 05:02:31.707+000028194: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2012-12-21 05:02:31.707+000028194: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=7 w=29
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=29 events=1
>2012-12-21 05:02:31.707+000028194: debug : virNetMessageDecodeLength:152 : Got length, now need 36 total (32 more)
>2012-12-21 05:02:31.707+000028194: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1124c30 tx=(nil)
>2012-12-21 05:02:31.707+000028194: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-12-21 05:02:31.707+000028194: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=29 events=1
>2012-12-21 05:02:31.707+000028194: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -795682720
>2012-12-21 05:02:31.707+000028194: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x11254c0 len=36 prog=536903814 vers=1 proc=23 type=0 status=0 serial=4
>2012-12-21 05:02:31.707+000028194: debug : virKeepAliveCheckMessage:384 : ka=0x1124720, client=0x11254c0, msg=0x1124c30
>2012-12-21 05:02:31.707+000028194: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=39 frequency=5000
>2012-12-21 05:02:31.707+000028194: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -795682720
>2012-12-21 05:02:31.707+000028194: debug : virObjectRef:168 : OBJECT_REF: obj=0x11254c0
>2012-12-21 05:02:31.707+000028194: debug : virNetServerDispatchNewMessage:218 : server=0x111ccb0 client=0x11254c0 message=0x1124c30
>2012-12-21 05:02:31.707+000028194: debug : virObjectRef:168 : OBJECT_REF: obj=0x1128040
>2012-12-21 05:02:31.707+000028194: debug : virNetMessageNew:45 : msg=0x1126f80 tracked=1
>2012-12-21 05:02:31.707+000028194: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1126f80 tx=(nil)
>2012-12-21 05:02:31.707+000028194: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-12-21 05:02:31.707+000028194: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=29 events=1
>2012-12-21 05:02:31.707+000028194: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -795682720
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.707+000028194: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=29, f=19 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1356066156707
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCalculateTimeout:346 : Timeout at 1356066156707 due in 5000 ms
>2012-12-21 05:02:31.707+000028194: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2012-12-21 05:02:31.707+000028199: debug : virNetServerHandleJob:189 : server=0x111ccb0 client=0x11254c0 message=0x1124c30 prog=0x1128040
>2012-12-21 05:02:31.707+000028199: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=23
>2012-12-21 05:02:31.707+000028199: debug : remoteDispatchDomainLookupByNameHelper:3084 : server=0x111ccb0 client=0x11254c0 msg=0x1124c30 rerr=0x7f55c8296b80 args=0x7f55a8001f00 ret=0x7f55a8001f60
>2012-12-21 05:02:31.707+000028199: debug : virDomainLookupByName:2117 : conn=0x7f55b8007ed0, name=tt
>2012-12-21 05:02:31.707+000028199: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f55a8001f20 classname=virDomain
>2012-12-21 05:02:31.707+000028199: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f55b8007ed0
>2012-12-21 05:02:31.707+000028199: debug : virDomainFree:2281 : dom=0x7f55a8001f20, (VM: name=tt, uuid=6dc11780-3129-c602-5931-d1c5f99fd512)
>2012-12-21 05:02:31.707+000028199: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f55a8001f20
>2012-12-21 05:02:31.707+000028199: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f55a8001f20
>2012-12-21 05:02:31.707+000028199: debug : virDomainDispose:225 : release domain 0x7f55a8001f20 tt 6dc11780-3129-c602-5931-d1c5f99fd512
>2012-12-21 05:02:31.707+000028199: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f55b8007ed0
>2012-12-21 05:02:31.707+000028199: debug : virNetMessageEncodePayload:358 : Encode length as 56
>2012-12-21 05:02:31.707+000028199: debug : virNetServerClientSendMessageLocked:1116 : msg=0x1124c30 proc=23 len=56 offset=0
>2012-12-21 05:02:31.707+000028199: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x11254c0 len=56 prog=536903814 vers=1 proc=23 type=1 status=0 serial=4
>2012-12-21 05:02:31.707+000028199: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1126f80 tx=0x1124c30
>2012-12-21 05:02:31.707+000028199: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2012-12-21 05:02:31.707+000028199: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=29 events=3
>2012-12-21 05:02:31.707+000028199: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-12-21 05:02:31.707+000028199: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1128040
>2012-12-21 05:02:31.707+000028199: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x11254c0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=7 w=29
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.707+000028194: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=29, f=19 e=5 d=0
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1356066156707
>2012-12-21 05:02:31.707+000028194: debug : virEventPollCalculateTimeout:346 : Timeout at 1356066156707 due in 5000 ms
>2012-12-21 05:02:31.707+000028194: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2012-12-21 05:02:31.707+000028194: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:469 : i=7 w=29
>2012-12-21 05:02:31.707+000028194: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=29 events=2
>2012-12-21 05:02:31.708+000028194: debug : virNetMessageFree:73 : msg=0x1124c30 nfds=0 cb=(nil)
>2012-12-21 05:02:31.708+000028194: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1126f80 tx=(nil)
>2012-12-21 05:02:31.708+000028194: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-12-21 05:02:31.708+000028194: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=29 events=1
>2012-12-21 05:02:31.708+000028194: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -795682720
>2012-12-21 05:02:31.708+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.708+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.708+000028194: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-12-21 05:02:31.708+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.708+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.708+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-12-21 05:02:31.708+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-12-21 05:02:31.708+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-12-21 05:02:31.708+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-12-21 05:02:31.708+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-12-21 05:02:31.708+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-12-21 05:02:31.708+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-12-21 05:02:31.708+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=29, f=19 e=1 d=0
>2012-12-21 05:02:31.708+000028194: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-12-21 05:02:31.708+000028194: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1356066156707
>2012-12-21 05:02:31.708+000028194: debug : virEventPollCalculateTimeout:346 : Timeout at 1356066156707 due in 4999 ms
>2012-12-21 05:02:31.708+000028194: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4999
>2012-12-21 05:02:31.708+000028194: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-12-21 05:02:31.708+000028194: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-12-21 05:02:31.708+000028194: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2012-12-21 05:02:31.708+000028194: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-12-21 05:02:31.708+000028194: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-12-21 05:02:31.708+000028194: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-12-21 05:02:31.708+000028194: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-12-21 05:02:31.708+000028194: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-12-21 05:02:31.708+000028194: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-12-21 05:02:31.708+000028194: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-12-21 05:02:31.708+000028194: debug : virEventPollDispatchHandles:469 : i=7 w=29
>2012-12-21 05:02:31.708+000028194: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=29 events=1
>2012-12-21 05:02:31.708+000028194: debug : virNetMessageDecodeLength:152 : Got length, now need 400 total (396 more)
>2012-12-21 05:02:31.708+000028194: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1126f80 tx=(nil)
>2012-12-21 05:02:31.708+000028194: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-12-21 05:02:31.708+000028194: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=29 events=1
>2012-12-21 05:02:31.708+000028194: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -795682720
>2012-12-21 05:02:31.708+000028194: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x11254c0 len=400 prog=536903814 vers=1 proc=185 type=0 status=0 serial=5
>2012-12-21 05:02:31.708+000028194: debug : virKeepAliveCheckMessage:384 : ka=0x1124720, client=0x11254c0, msg=0x1126f80
>2012-12-21 05:02:31.708+000028194: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=39 frequency=5000
>2012-12-21 05:02:31.708+000028194: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -795682720
>2012-12-21 05:02:31.708+000028194: debug : virObjectRef:168 : OBJECT_REF: obj=0x11254c0
>2012-12-21 05:02:31.708+000028194: debug : virNetServerDispatchNewMessage:218 : server=0x111ccb0 client=0x11254c0 message=0x1126f80
>2012-12-21 05:02:31.708+000028194: debug : virObjectRef:168 : OBJECT_REF: obj=0x1128040
>2012-12-21 05:02:31.708+000028194: debug : virNetMessageNew:45 : msg=0x1125870 tracked=1
>2012-12-21 05:02:31.708+000028194: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1125870 tx=(nil)
>2012-12-21 05:02:31.708+000028194: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-12-21 05:02:31.708+000028194: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=29 events=1
>2012-12-21 05:02:31.708+000028194: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -795682720
>2012-12-21 05:02:31.708+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.708+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.708+000028194: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-12-21 05:02:31.708+000028194: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-12-21 05:02:31.708+000028194: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2012-12-21 05:02:31.708+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-12-21 05:02:31.708+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-12-21 05:02:31.708+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-12-21 05:02:31.708+000028195: debug : virNetServerHandleJob:189 : server=0x111ccb0 client=0x11254c0 message=0x1126f80 prog=0x1128040
>2012-12-21 05:02:31.708+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-12-21 05:02:31.708+000028195: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=5 proc=185
>2012-12-21 05:02:31.708+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-12-21 05:02:31.708+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-12-21 05:02:31.708+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-12-21 05:02:31.708+000028194: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=29, f=19 e=1 d=0
>2012-12-21 05:02:31.708+000028195: debug : remoteDispatchDomainSnapshotCreateXMLHelper:5869 : server=0x111ccb0 client=0x11254c0 msg=0x1126f80 rerr=0x7f55caa9ab80 args=0x7f55bc0d6c40 ret=0x7f55bc0d6c80
>2012-12-21 05:02:31.708+000028194: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-12-21 05:02:31.708+000028195: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f55bc0d8bd0 classname=virDomain
>2012-12-21 05:02:31.708+000028194: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1356066156708
>2012-12-21 05:02:31.708+000028195: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f55b8007ed0
>2012-12-21 05:02:31.708+000028194: debug : virEventPollCalculateTimeout:346 : Timeout at 1356066156708 due in 5000 ms
>2012-12-21 05:02:31.708+000028194: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2012-12-21 05:02:31.708+000028195: debug : virDomainSnapshotCreateXML:17820 : dom=0x7f55bc0d8bd0, (VM: name=tt, uuid=6dc11780-3129-c602-5931-d1c5f99fd512), xmlDesc=<domainsnapshot>
>  <name>snapshot-tck1</name>
>  <state>shutoff</state>
>  <creationTime>1356059598</creationTime>
>  <memory snapshot='no'/>
>  <disks>
>    <disk name='vda' snapshot='external'>
>      <driver type='qcow2'/>
>      <source file='/var/lib/libvirt/images/images/tt.snapshot-tck1'/>
>    </disk>
>  </disks>
></domainsnapshot>
>
>, flags=1
>2012-12-21 05:02:31.708+000028195: debug : qemuProcessAutoDestroyActive:4537 : vm=tt
>2012-12-21 05:02:31.708+000028195: debug : qemuDriverCloseCallbackGet:688 : vm=tt, uuid=6dc11780-3129-c602-5931-d1c5f99fd512, conn=(nil)
>2012-12-21 05:02:31.708+000028195: debug : qemuDriverCloseCallbackGet:694 : cb=(nil)
>2012-12-21 05:02:31.708+000028195: warning : virDomainSnapshotDefParseString:277 : parsing older snapshot that lacks domain
>2012-12-21 05:02:31.708+000028195: error : virDomainSnapshotAlignDisks:455 : unsupported configuration: disk 'vda' must use snapshot mode 'internal'
>
>
>     ====== end of log =====
>

Actions: View

Attachments on bug 889407: 667100