Attachment 636989 Details for Bug 872420 – libirtd crash log

libirtd crash log

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

(hide)

Description:

Filename:

MIME Type:

Creator: zhpeng

Created: 2012-11-02 08:49:30 UTC

Size: 64.12 KB

patch

obsolete

>Caught Segmentation violation dumping internal log buffer:
>
>
>    ====== start of log =====
>
>s)
>2012-11-02 08:42:28.223+00006628: debug : virEventPollDispatchTimeouts:410 : Dispatch 8
>2012-11-02 08:42:28.223+00006628: debug : virEventPollDispatchHandles:455 : Dispatch 15
>2012-11-02 08:42:28.223+00006628: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-02 08:42:28.223+00006628: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-02 08:42:28.223+00006628: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-02 08:42:28.223+00006628: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-02 08:42:28.223+00006628: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-02 08:42:28.223+00006628: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-02 08:42:28.223+00006628: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-02 08:42:28.223+00006628: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-02 08:42:28.223+00006628: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-02 08:42:28.223+00006628: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2012-11-02 08:42:28.223+00006628: debug : virEventPollDispatchHandles:469 : i=10 w=12
>2012-11-02 08:42:28.223+00006628: debug : virEventPollDispatchHandles:469 : i=11 w=13
>2012-11-02 08:42:28.223+00006628: debug : virEventPollDispatchHandles:469 : i=12 w=14
>2012-11-02 08:42:28.223+00006628: debug : virEventPollDispatchHandles:469 : i=13 w=15
>2012-11-02 08:42:28.223+00006628: debug : virEventPollDispatchHandles:469 : i=14 w=16
>2012-11-02 08:42:28.223+00006628: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=16 events=2
>2012-11-02 08:42:28.223+00006628: debug : virNetMessageFree:73 : msg=0x216c580 nfds=0 cb=(nil)
>2012-11-02 08:42:28.223+00006628: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x216e870 tx=(nil)
>2012-11-02 08:42:28.223+00006628: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-02 08:42:28.223+00006628: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=1
>2012-11-02 08:42:28.223+00006628: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2126735264
>2012-11-02 08:42:28.223+00006628: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2012-11-02 08:42:28.223+00006628: debug : virEventPollCleanupHandles:549 : Cleanup 15
>2012-11-02 08:42:28.223+00006628: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-02 08:42:28.223+00006628: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2012-11-02 08:42:28.223+00006628: debug : virEventPollCleanupHandles:549 : Cleanup 15
>2012-11-02 08:42:28.223+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=25 e=25 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=12, f=24 e=25 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=13, f=20 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=14, f=23 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=13 w=15, f=28 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=14 w=16, f=31 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 8 timers
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845752513
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845750649
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845750425
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845753220
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCalculateTimeout:346 : Timeout at 1351845750425 due in 2201 ms
>2012-11-02 08:42:28.224+00006628: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=15 timeout=2201
>2012-11-02 08:42:28.224+00006628: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchTimeouts:410 : Dispatch 8
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:455 : Dispatch 15
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=10 w=12
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=11 w=13
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=12 w=14
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=13 w=15
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=14 w=16
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=16 events=1
>2012-11-02 08:42:28.224+00006628: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2012-11-02 08:42:28.224+00006628: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x216e870 tx=(nil)
>2012-11-02 08:42:28.224+00006628: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-02 08:42:28.224+00006628: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=1
>2012-11-02 08:42:28.224+00006628: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2126735264
>2012-11-02 08:42:28.224+00006628: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x2173720 len=28 prog=536903814 vers=1 proc=110 type=0 status=0 serial=3
>2012-11-02 08:42:28.224+00006628: debug : virKeepAliveCheckMessage:384 : ka=0x2173f80, client=0x2173720, msg=0x216e870
>2012-11-02 08:42:28.224+00006628: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=10 frequency=5000
>2012-11-02 08:42:28.224+00006628: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2126735264
>2012-11-02 08:42:28.224+00006628: debug : virObjectRef:168 : OBJECT_REF: obj=0x2173720
>2012-11-02 08:42:28.224+00006628: debug : virNetServerDispatchNewMessage:218 : server=0x2168d20 client=0x2173720 message=0x216e870
>2012-11-02 08:42:28.224+00006628: debug : virObjectRef:168 : OBJECT_REF: obj=0x2174060
>2012-11-02 08:42:28.224+00006628: debug : virNetMessageNew:45 : msg=0x216ce30 tracked=1
>2012-11-02 08:42:28.224+00006628: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x216ce30 tx=(nil)
>2012-11-02 08:42:28.224+00006630: debug : virNetServerHandleJob:189 : server=0x2168d20 client=0x2173720 message=0x216e870 prog=0x2174060
>2012-11-02 08:42:28.224+00006628: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-02 08:42:28.224+00006630: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=110
>2012-11-02 08:42:28.224+00006628: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=1
>2012-11-02 08:42:28.224+00006628: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2126735264
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCleanupHandles:549 : Cleanup 15
>2012-11-02 08:42:28.224+00006630: debug : remoteDispatchGetURIHelper:7297 : server=0x2168d20 client=0x2173720 msg=0x216e870 rerr=0x7f367ab35b80 args=0x7f366800cd40 ret=0x7f366800ccd0
>2012-11-02 08:42:28.224+00006628: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2012-11-02 08:42:28.224+00006630: debug : virConnectGetURI:1700 : conn=0x7f36700e15f0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCleanupHandles:549 : Cleanup 15
>2012-11-02 08:42:28.224+00006630: debug : virNetMessageEncodePayload:358 : Encode length as 48
>2012-11-02 08:42:28.224+00006630: debug : virNetServerClientSendMessageLocked:1116 : msg=0x216e870 proc=110 len=48 offset=0
>2012-11-02 08:42:28.224+00006630: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x2173720 len=48 prog=536903814 vers=1 proc=110 type=1 status=0 serial=3
>2012-11-02 08:42:28.224+00006630: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x216ce30 tx=0x216e870
>2012-11-02 08:42:28.224+00006630: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2012-11-02 08:42:28.224+00006630: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=3
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=25 e=25 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=12, f=24 e=25 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=13, f=20 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=14, f=23 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=13 w=15, f=28 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=14 w=16, f=31 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 8 timers
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845752513
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845750649
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845750425
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845753224
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCalculateTimeout:346 : Timeout at 1351845750425 due in 2201 ms
>2012-11-02 08:42:28.224+00006628: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=15 timeout=2201
>2012-11-02 08:42:28.224+00006630: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-11-02 08:42:28.224+00006630: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x2174060
>2012-11-02 08:42:28.224+00006630: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x2173720
>2012-11-02 08:42:28.224+00006628: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchTimeouts:410 : Dispatch 8
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:455 : Dispatch 15
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=10 w=12
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=11 w=13
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=12 w=14
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=13 w=15
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=14 w=16
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCleanupHandles:549 : Cleanup 15
>2012-11-02 08:42:28.224+00006628: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCleanupHandles:549 : Cleanup 15
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=25 e=25 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=12, f=24 e=25 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=13, f=20 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=14, f=23 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=13 w=15, f=28 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=14 w=16, f=31 e=5 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 8 timers
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845752513
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845750649
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845750425
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 13512012-11-02 08:42:28.479+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845750425
>2012-11-02 08:42:28.479+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845753225
>2012-11-02 08:42:28.479+00006628: debug : virEventPollCalculateTimeout:346 : Timeout at 1351845750425 due in 1946 ms
>: debug : virEventPollDispatchTimeouts:410 : Dispatch 8
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:455 : Dispatch 15
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=10 w=12
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=11 w=13
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=12 w=14
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=13 w=15
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=14 w=16
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=16 events=2
>2012-11-02 08:42:28.224+00006628: debug : virNetMessageFree:73 : msg=0x216e870 nfds=0 cb=(nil)
>2012-11-02 08:42:28.224+00006628: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x216ce30 tx=(nil)
>2012-11-02 08:42:28.224+00006628: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-02 08:42:28.224+00006628: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=1
>2012-11-02 08:42:28.224+00006628: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2126735264
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCleanupHandles:549 : Cleanup 15
>2012-11-02 08:42:28.224+00006628: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCleanupHandles:549 : Cleanup 15
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=25 e=25 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=12, f=24 e=25 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=13, f=20 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=14, f=23 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=13 w=15, f=28 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=14 w=16, f=31 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 8 timers
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845752513
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845750649
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845750425
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845753224
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCalculateTimeout:346 : Timeout at 1351845750425 due in 2201 ms
>2012-11-02 08:42:28.224+00006628: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=15 timeout=2201
>2012-11-02 08:42:28.224+00006628: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchTimeouts:410 : Dispatch 8
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:455 : Dispatch 15
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=10 w=12
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=11 w=13
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=12 w=14
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=13 w=15
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:469 : i=14 w=16
>2012-11-02 08:42:28.224+00006628: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=16 events=1
>2012-11-02 08:42:28.224+00006628: debug : virNetMessageDecodeLength:152 : Got length, now need 36 total (32 more)
>2012-11-02 08:42:28.224+00006628: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x216ce30 tx=(nil)
>2012-11-02 08:42:28.224+00006628: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-02 08:42:28.224+00006628: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=1
>2012-11-02 08:42:28.224+00006628: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2126735264
>2012-11-02 08:42:28.224+00006628: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x2173720 len=36 prog=536903814 vers=1 proc=23 type=0 status=0 serial=4
>2012-11-02 08:42:28.224+00006628: debug : virKeepAliveCheckMessage:384 : ka=0x2173f80, client=0x2173720, msg=0x216ce30
>2012-11-02 08:42:28.224+00006628: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=10 frequency=5000
>2012-11-02 08:42:28.224+00006628: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2126735264
>2012-11-02 08:42:28.224+00006628: debug : virObjectRef:168 : OBJECT_REF: obj=0x2173720
>2012-11-02 08:42:28.224+00006628: debug : virNetServerDispatchNewMessage:218 : server=0x2168d20 client=0x2173720 message=0x216ce30
>2012-11-02 08:42:28.224+00006628: debug : virObjectRef:168 : OBJECT_REF: obj=0x2174060
>2012-11-02 08:42:28.224+00006628: debug : virNetMessageNew:45 : msg=0x216c600 tracked=1
>2012-11-02 08:42:28.224+00006628: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x216c600 tx=(nil)
>2012-11-02 08:42:28.224+00006628: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-02 08:42:28.224+00006635: debug : virNetServerHandleJob:189 : server=0x2168d20 client=0x2173720 message=0x216ce30 prog=0x2174060
>2012-11-02 08:42:28.224+00006628: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=1
>2012-11-02 08:42:28.224+00006628: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2126735264
>2012-11-02 08:42:28.224+00006635: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=23
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCleanupHandles:549 : Cleanup 15
>2012-11-02 08:42:28.224+00006635: debug : remoteDispatchDomainLookupByNameHelper:3084 : server=0x2168d20 client=0x2173720 msg=0x216ce30 rerr=0x7f3677930b80 args=0x7f364c000c30 ret=0x7f364c000c50
>2012-11-02 08:42:28.224+00006628: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-02 08:42:28.224+00006635: debug : virDomainLookupByName:2117 : conn=0x7f36700e15f0, name=aaa
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2012-11-02 08:42:28.224+00006628: debug : virEventPollCleanupHandles:549 : Cleanup 15
>2012-11-02 08:42:28.224+00006635: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f364c000bf0 classname=virDomain
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-02 08:42:28.224+00006635: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f36700e15f0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-02 08:42:28.224+00006635: debug : virDomainFree:2281 : dom=0x7f364c000bf0, (VM: name=aaa, uuid=746973c4-2050-eb8a-4f11-670c2097d35a)
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-02 08:42:28.224+00006635: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f364c000bf0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-02 08:42:28.224+00006635: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f364c000bf0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-02 08:42:28.224+00006635: debug : virDomainDispose:225 : release domain 0x7f364c000bf0 aaa 746973c4-2050-eb8a-4f11-670c2097d35a
>2012-11-02 08:42:28.224+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=25 e=25 d=0
>2012-11-02 08:42:28.224+00006635: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f36700e15f0
>2012-11-02 08:42:28.225+00006635: debug : virNetMessageEncodePayload:358 : Encode length as 56
>2012-11-02 08:42:28.225+00006635: debug : virNetServerClientSendMessageLocked:1116 : msg=0x216ce30 proc=23 len=56 offset=0
>2012-11-02 08:42:28.225+00006635: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x2173720 len=56 prog=536903814 vers=1 proc=23 type=1 status=0 serial=4
>2012-11-02 08:42:28.225+00006635: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x216c600 tx=0x216ce30
>2012-11-02 08:42:28.225+00006635: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2012-11-02 08:42:28.225+00006635: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=3
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=12, f=24 e=25 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=13, f=20 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=14, f=23 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=13 w=15, f=28 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=14 w=16, f=31 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 8 timers
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845752513
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845750649
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845750425
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845753224
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:346 : Timeout at 1351845750425 due in 2200 ms
>2012-11-02 08:42:28.225+00006628: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=15 timeout=2200
>2012-11-02 08:42:28.225+00006635: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-11-02 08:42:28.225+00006635: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x2174060
>2012-11-02 08:42:28.225+00006635: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x2173720
>2012-11-02 08:42:28.225+00006628: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchTimeouts:410 : Dispatch 8
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:455 : Dispatch 15
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=10 w=12
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=11 w=13
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=12 w=14
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=13 w=15
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=14 w=16
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCleanupHandles:549 : Cleanup 15
>2012-11-02 08:42:28.225+00006628: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCleanupHandles:549 : Cleanup 15
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=25 e=25 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=12, f=24 e=25 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=13, f=20 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=14, f=23 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=13 w=15, f=28 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=14 w=16, f=31 e=5 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 8 timers
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845752513
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845750649
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845750425
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845753224
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:346 : Timeout at 1351845750425 due in 2200 ms
>2012-11-02 08:42:28.225+00006628: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=15 timeout=2200
>2012-11-02 08:42:28.225+00006628: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchTimeouts:410 : Dispatch 8
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:455 : Dispatch 15
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=10 w=12
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=11 w=13
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=12 w=14
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=13 w=15
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=14 w=16
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=16 events=2
>2012-11-02 08:42:28.225+00006628: debug : virNetMessageFree:73 : msg=0x216ce30 nfds=0 cb=(nil)
>2012-11-02 08:42:28.225+00006628: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x216c600 tx=(nil)
>2012-11-02 08:42:28.225+00006628: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-02 08:42:28.225+00006628: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=1
>2012-11-02 08:42:28.225+00006628: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2126735264
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCleanupHandles:549 : Cleanup 15
>2012-11-02 08:42:28.225+00006628: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCleanupHandles:549 : Cleanup 15
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=25 e=25 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=12, f=24 e=25 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=13, f=20 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=14, f=23 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=13 w=15, f=28 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=14 w=16, f=31 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 8 timers
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845752513
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845750649
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845750425
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845753224
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:346 : Timeout at 1351845750425 due in 2200 ms
>2012-11-02 08:42:28.225+00006628: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=15 timeout=2200
>2012-11-02 08:42:28.225+00006628: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchTimeouts:410 : Dispatch 8
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:455 : Dispatch 15
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=10 w=12
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=11 w=13
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=12 w=14
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=13 w=15
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=14 w=16
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=16 events=1
>2012-11-02 08:42:28.225+00006628: debug : virNetMessageDecodeLength:152 : Got length, now need 56 total (52 more)
>2012-11-02 08:42:28.225+00006628: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x216c600 tx=(nil)
>2012-11-02 08:42:28.225+00006628: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-02 08:42:28.225+00006628: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=1
>2012-11-02 08:42:28.225+00006628: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2126735264
>2012-11-02 08:42:28.225+00006628: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x2173720 len=56 prog=536903814 vers=1 proc=12 type=0 status=0 serial=5
>2012-11-02 08:42:28.225+00006628: debug : virKeepAliveCheckMessage:384 : ka=0x2173f80, client=0x2173720, msg=0x216c600
>2012-11-02 08:42:28.225+00006628: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=10 frequency=5000
>2012-11-02 08:42:28.225+00006628: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2126735264
>2012-11-02 08:42:28.225+00006628: debug : virObjectRef:168 : OBJECT_REF: obj=0x2173720
>2012-11-02 08:42:28.225+00006628: debug : virNetServerDispatchNewMessage:218 : server=0x2168d20 client=0x2173720 message=0x216c600
>2012-11-02 08:42:28.225+00006628: debug : virObjectRef:168 : OBJECT_REF: obj=0x2174060
>2012-11-02 08:42:28.225+00006628: debug : virNetMessageNew:45 : msg=0x216c510 tracked=1
>2012-11-02 08:42:28.225+00006629: debug : virNetServerHandleJob:189 : server=0x2168d20 client=0x2173720 message=0x216c600 prog=0x2174060
>2012-11-02 08:42:28.225+00006628: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x216c510 tx=(nil)
>2012-11-02 08:42:28.225+00006628: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-02 08:42:28.225+00006629: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=5 proc=12
>2012-11-02 08:42:28.225+00006628: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=16 events=1
>2012-11-02 08:42:28.225+00006628: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2126735264
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCleanupHandles:549 : Cleanup 15
>2012-11-02 08:42:28.225+00006629: debug : remoteDispatchDomainDestroyHelper:1254 : server=0x2168d20 client=0x2173720 msg=0x216c600 rerr=0x7f367b536b80 args=0x7f3670208480 ret=0x7f3670208420
>2012-11-02 08:42:28.225+00006628: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2012-11-02 08:42:28.225+00006629: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f3670208440 classname=virDomain
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCleanupHandles:549 : Cleanup 15
>2012-11-02 08:42:28.225+00006629: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f36700e15f0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-02 08:42:28.225+00006629: debug : virDomainDestroy:2172 : dom=0x7f3670208440, (VM: name=aaa, uuid=746973c4-2050-eb8a-4f11-670c2097d35a)
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-02 08:42:28.225+00006629: debug : qemuProcessKill:3955 : vm=aaa pid=6718 flags=1
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=25 e=25 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=12, f=24 e=25 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=13, f=20 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=14, f=23 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=13 w=15, f=28 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=14 w=16, f=31 e=1 d=0
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 8 timers
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845752513
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845750649
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845750425
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845753225
>2012-11-02 08:42:28.225+00006628: debug : virEventPollCalculateTimeout:346 : Timeout at 1351845750425 due in 2200 ms
>2012-11-02 08:42:28.225+00006628: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=15 timeout=2200
>2012-11-02 08:42:28.225+00006628: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchTimeouts:410 : Dispatch 8
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:455 : Dispatch 15
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2012-11-02 08:42:28.225+00006628: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=1
>2012-11-02 08:42:28.225+00006628: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f3668004ee0
>2012-11-02 08:42:28.230+00006628: debug : qemuMonitorIOProcess:353 : QEMU_MONITOR_IO_PROCESS: mon=0x7f3668004ee0 buf={"timestamp": {"seconds": 1351845748, "microseconds": 225804}, "event": "SHUTDOWN"}
> len=85
>2012-11-02 08:42:28.230+00006628: debug : qemuMonitorJSONIOProcessLine:150 : Line [{"timestamp": {"seconds": 1351845748, "microseconds": 225804}, "event": "SHUTDOWN"}]
>2012-11-02 08:42:28.230+00006628: debug : virJSONValueFromString:975 : string={"timestamp": {"seconds": 1351845748, "microseconds": 225804}, "event": "SHUTDOWN"}
>2012-11-02 08:42:28.230+00006628: debug : virJSONParserHandleStartMap:852 : parser=0x7fff67a57790
>2012-11-02 08:42:28.230+00006628: debug : virJSONParserHandleMapKey:833 : parser=0x7fff67a57790 key=0x216c9e2
>2012-11-02 08:42:28.230+00006628: debug : virJSONParserHandleStartMap:852 : parser=0x7fff67a57790
>2012-11-02 08:42:28.230+00006628: debug : virJSONParserHandleMapKey:833 : parser=0x7fff67a57790 key=0x216c9f0
>2012-11-02 08:42:28.230+00006628: debug : virJSONParserHandleNumber:792 : parser=0x7fff67a57790 str=(null)
>2012-11-02 08:42:28.230+00006628: debug : virJSONParserHandleMapKey:833 : parser=0x7fff67a57790 key=0x216ca07
>2012-11-02 08:42:28.230+00006628: debug : virJSONParserHandleNumber:792 : parser=0x7fff67a57790 str=(null)
>2012-11-02 08:42:28.230+00006628: debug : virJSONParserHandleEndMap:881 : parser=0x7fff67a57790
>2012-11-02 08:42:28.230+00006628: debug : virJSONParserHandleMapKey:833 : parser=0x7fff67a57790 key=0x216ca20
>2012-11-02 08:42:28.230+00006628: debug : virJSONParserHandleString:813 : parser=0x7fff67a57790 str=0x216ca29
>2012-11-02 08:42:28.230+00006628: debug : virJSONParserHandleEndMap:881 : parser=0x7fff67a57790
>2012-11-02 08:42:28.230+00006628: debug : virJSONValueFromString:1019 : result=0x2144a00
>2012-11-02 08:42:28.230+00006628: debug : qemuMonitorJSONIOProcessLine:165 : QEMU_MONITOR_RECV_EVENT: mon=0x7f3668004ee0 event={"timestamp": {"seconds": 1351845748, "microseconds": 225804}, "event": "SHUTDOWN"}
>2012-11-02 08:42:28.230+00006628: debug : qemuMonitorJSONIOProcessEvent:122 : mon=0x7f3668004ee0 obj=0x2144a00
>2012-11-02 08:42:28.230+00006628: debug : qemuMonitorJSONIOProcessEvent:136 : handle SHUTDOWN handler=0x496a20 data=(nil)
>2012-11-02 08:42:28.230+00006628: debug : qemuMonitorEmitShutdown:987 : mon=0x7f3668004ee0
>2012-11-02 08:42:28.230+00006628: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f3668004ee0
>2012-11-02 08:42:28.230+00006628: debug : qemuProcessHandleShutdown:637 : vm=0x7f36700ca5e0
>2012-11-02 08:42:28.425+00006629: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f36700ca5e0
>2012-11-02 08:42:28.425+00006629: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f36700ca5e0
>2012-11-02 08:42:28.425+00006629: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f36700ca5e0
>2012-11-02 08:42:28.425+00006628: debug : qemuProcessHandleShutdown:654 : Transitioned guest aaa to shutdown state
>2012-11-02 08:42:28.478+00006628: debug : virFileClose:72 : Closed fd 34
>2012-11-02 08:42:28.478+00006628: debug : qemuAgentNotifyEvent:1263 : mon=0x7f3668004ff0 event=1
>2012-11-02 08:42:28.478+00006628: warning : qemuAgentNotifyEvent:1274 : Received unexpected event 1
>2012-11-02 08:42:28.478+00006628: debug : qemuProcessKill:3955 : vm=aaa pid=6718 flags=2
>2012-11-02 08:42:28.478+00006628: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f3668004ee0
>2012-11-02 08:42:28.478+00006628: debug : qemuMonitorJSONIOProcess:221 : Total used 85 bytes out of 85 available in buffer
>2012-11-02 08:42:28.478+00006628: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=13
>2012-11-02 08:42:28.478+00006628: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2126735264
>2012-11-02 08:42:28.478+00006628: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f3668004ee0
>2012-11-02 08:42:28.478+00006628: debug : virEventPollDispatchHandles:469 : i=10 w=12
>2012-11-02 08:42:28.478+00006628: debug : virEventPollDispatchHandles:469 : i=11 w=13
>2012-11-02 08:42:28.478+00006628: debug : virEventPollDispatchHandles:469 : i=12 w=14
>2012-11-02 08:42:28.478+00006628: debug : virEventPollDispatchHandles:469 : i=13 w=15
>2012-11-02 08:42:28.478+00006628: debug : virEventPollDispatchHandles:469 : i=14 w=16
>2012-11-02 08:42:28.478+00006628: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2012-11-02 08:42:28.478+00006628: debug : virEventPollCleanupHandles:549 : Cleanup 15
>2012-11-02 08:42:28.478+00006628: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-02 08:42:28.478+00006628: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2012-11-02 08:42:28.478+00006628: debug : virEventPollCleanupHandles:549 : Cleanup 15
>2012-11-02 08:42:28.478+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-02 08:42:28.478+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-02 08:42:28.478+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-02 08:42:28.478+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-02 08:42:28.478+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-02 08:42:28.478+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-02 08:42:28.478+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-02 08:42:28.478+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-02 08:42:28.478+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-02 08:42:28.478+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=25 e=25 d=0
>2012-11-02 08:42:28.478+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=12, f=24 e=25 d=0
>2012-11-02 08:42:28.478+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=13, f=20 e=1 d=0
>2012-11-02 08:42:28.478+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=14, f=23 e=1 d=0
>2012-11-02 08:42:28.478+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=13 w=15, f=28 e=1 d=0
>2012-11-02 08:42:28.478+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=14 w=16, f=31 e=1 d=0
>2012-11-02 08:42:28.478+00006628: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 8 timers
>2012-11-02 08:42:28.478+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845752513
>2012-11-02 08:42:28.478+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845750649
>2012-11-02 08:42:28.478+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845750425
>2012-11-02 08:42:28.478+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845753225
>2012-11-02 08:42:28.478+00006628: debug : virEventPollCalculateTimeout:346 : Timeout at 1351845750425 due in 1947 ms
>2012-11-02 08:42:28.478+00006628: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=15 timeout=1947
>2012-11-02 08:42:28.478+00006628: debug : virEventPollRunOnce:625 : Poll got 4 event(s)
>2012-11-02 08:42:28.478+00006628: debug : virEventPollDispatchTimeouts:410 : Dispatch 8
>2012-11-02 08:42:28.478+00006628: debug : virEventPollDispatchHandles:455 : Dispatch 15
>2012-11-02 08:42:28.478+00006628: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-02 08:42:28.478+00006628: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-02 08:42:28.478+00006628: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-02 08:42:28.478+00006628: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-02 08:42:28.478+00006628: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-02 08:42:28.478+00006628: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-02 08:42:28.478+00006628: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-02 08:42:28.478+00006628: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-02 08:42:28.478+00006628: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=1
>2012-11-02 08:42:28.478+00006628: debug : virNetlinkEventCallback:346 : dispatching to max 0 clients, called from event watch 8
>2012-11-02 08:42:28.478+00006628: debug : virNetlinkEventCallback:359 : event not handled.
>2012-11-02 08:42:28.478+00006628: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-02 08:42:28.478+00006628: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=9 events=1
>2012-11-02 08:42:28.478+00006628: debug : udevEventHandleCallback:1467 : udev action: 'remove'
>2012-11-02 08:42:28.478+00006628: debug : udevRemoveOneDevice:1218 : Failed to find device to remove that has udev name '/sys/devices/virtual/net/vnet0/queues/tx-0'
>2012-11-02 08:42:28.478+00006628: debug : virEventPollDispatchHandles:469 : i=9 w=11
>2012-11-02 08:42:28.478+00006628: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=9
>2012-11-02 08:42:28.478+00006628: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f3668004ee0
>2012-11-02 08:42:28.478+00006628: error : qemuMonitorIO:613 : internal error End of file from monitor
>2012-11-02 08:42:28.478+00006628: debug : qemuMonitorIO:646 : Error on monitor internal error End of file from monitor
>2012-11-02 08:42:28.478+00006628: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=12
>2012-11-02 08:42:28.478+00006628: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2126735264
>2012-11-02 08:42:28.478+00006628: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f3668004ee0
>2012-11-02 08:42:28.478+00006628: debug : qemuMonitorIO:669 : Triggering EOF callback
>2012-11-02 08:42:28.478+00006628: debug : qemuProcessHandleMonitorEOF:282 : Received EOF on 0x7f36700ca5e0 'aaa'
>2012-11-02 08:42:28.478+00006628: debug : qemuProcessHandleMonitorEOF:290 : Domain is being destroyed, EOF is expected
>2012-11-02 08:42:28.478+00006628: debug : virEventPollDispatchHandles:469 : i=10 w=12
>2012-11-02 08:42:28.478+00006628: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=9
>2012-11-02 08:42:28.478+00006628: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f3668004ff0
>2012-11-02 08:42:28.478+00006628: error : qemuAgentIO:642 : internal error End of file from monitor
>2012-11-02 08:42:28.478+00006628: debug : qemuAgentIO:675 : Error on monitor internal error End of file from monitor
>2012-11-02 08:42:28.478+00006628: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=12
>2012-11-02 08:42:28.478+00006628: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2126735264
>2012-11-02 08:42:28.478+00006628: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f3668004ff0
>2012-11-02 08:42:28.478+00006628: debug : qemuAgentIO:698 : Triggering EOF callback
>2012-11-02 08:42:28.478+00006628: debug : qemuProcessHandleAgentEOF:125 : Received EOF from agent on 0x7f36700ca5e0 'aaa'
>2012-11-02 08:42:28.479+00006628: debug : qemuAgentClose:814 : mon=0x7f3668004ff0
>2012-11-02 08:42:28.479+00006628: debug : virEventPollRemoveHandle:175 : EVENT_POLL_REMOVE_HANDLE: watch=12
>2012-11-02 08:42:28.479+00006628: debug : virEventPollRemoveHandle:188 : mark delete 10 24
>2012-11-02 08:42:28.479+00006628: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2126735264
>2012-11-02 08:42:28.479+00006628: debug : virFileClose:72 : Closed fd 24
>2012-11-02 08:42:28.479+00006628: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f3668004ff0
>2012-11-02 08:42:28.479+00006628: debug : virEventPollDispatchHandles:469 : i=11 w=13
>2012-11-02 08:42:28.479+00006628: debug : virEventPollDispatchHandles:469 : i=12 w=14
>2012-11-02 08:42:28.479+00006628: debug : virEventPollDispatchHandles:469 : i=13 w=15
>2012-11-02 08:42:28.479+00006628: debug : virEventPollDispatchHandles:469 : i=14 w=16
>2012-11-02 08:42:28.479+00006628: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2012-11-02 08:42:28.479+00006631: debug : qemuAgentSend:900 : Send command resulted in error internal error End of file from monitor
>2012-11-02 08:42:28.479+00006628: debug : virEventPollCleanupHandles:549 : Cleanup 15
>2012-11-02 08:42:28.479+00006628: debug : virEventPollCleanupHandles:562 : EVENT_POLL_PURGE_HANDLE: watch=12
>2012-11-02 08:42:28.479+00006628: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f3668004ff0
>2012-11-02 08:42:28.479+00006631: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=12
>2012-11-02 08:42:28.479+00006628: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-02 08:42:28.479+00006628: debug : virEventPollCleanupTimeouts:501 : Cleanup 8
>2012-11-02 08:42:28.479+00006631: debug : qemuAgentCommand:1023 : Receive command reply ret=-1 rxObject=(nil)
>2012-11-02 08:42:28.479+00006628: debug : virEventPollCleanupHandles:549 : Cleanup 14
>2012-11-02 08:42:28.479+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-02 08:42:28.479+00006631: debug : qemuDomainObjEndJob:933 : Stopping job: modify (async=none)
>2012-11-02 08:42:28.479+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-02 08:42:28.479+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-02 08:42:28.479+00006631: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f36700ca5e0
>2012-11-02 08:42:28.479+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-02 08:42:28.479+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=14 e=1 d=0
>2012-11-02 08:42:28.479+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2012-11-02 08:42:28.479+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=6 e=1 d=0
>2012-11-02 08:42:28.479+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=16 e=1 d=0
>2012-11-02 08:42:28.479+00006631: debug : virDomainFree:2281 : dom=0x7f36600009f0, (VM: name=aaa, uuid=746973c4-2050-eb8a-4f11-670c2097d35a)
>2012-11-02 08:42:28.479+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=18 e=1 d=0
>2012-11-02 08:42:28.479+00006631: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f36600009f0
>2012-11-02 08:42:28.479+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=25 e=24 d=0
>2012-11-02 08:42:28.479+00006631: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f36600009f0
>2012-11-02 08:42:28.479+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=13, f=20 e=1 d=0
>2012-11-02 08:42:28.479+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=14, f=23 e=1 d=0
>2012-11-02 08:42:28.479+00006631: debug : virDomainDispose:225 : release domain 0x7f36600009f0 aaa 746973c4-2050-eb8a-4f11-670c2097d35a
>2012-11-02 08:42:28.479+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=15, f=28 e=1 d=0
>2012-11-02 08:42:28.479+00006631: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f365800e200
>2012-11-02 08:42:28.479+00006628: debug : virEventPollMakePollFDs:378 : Prepare n=13 w=16, f=31 e=1 d=0
>2012-11-02 08:42:28.479+00006628: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 8 timers
>2012-11-02 08:42:28.479+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845752513
>2012-11-02 08:42:28.479+00006628: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1351845750649
>2012-11-02 08:42:28.479+00006631: debug : virNetServerProgramSendError:150 : prog=536903814 ver=1 proc=261 type=1 serial=5 msg=0x216e520 rerr=0x7f367a134b80
>2012-11-02 08:42:28.479+00006633: debug : qemuDomainObjBeginJobInternal:804 : Starting job: modify (async=none)
>2012-11-02 08:42:28.479+00006631: debug : virNetMessageEncodePayload:358 : Encode length as 168
>2012-11-02 08:42:28.479+00006631: debug : virNetServerClientSendMessageLocked:1116 : msg=0x216e520 proc=261 len=168 offset=0
>2012-11-02 08:42:28.479+00006631: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x216b4a0 len=168 prog=536903814 vers=1 proc=261 type=1 status=1 serial=5
>2012-11-02 08:42:28.479+00006631: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x216e260 tx=0x216e520
>2012-11-02 08:42:28.479+00006631: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2012-11-02 08:42:28.479+00006631: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=3
>
>
>     ====== end of log =====
>

Actions: View

Attachments on bug 872420: 636969 | 636989 | 641268 | 649590